-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Sat 2025-01-18 18:18:36 CET. -- Jan 18 18:17:00 volumio-rpi4 volumio[14093]: info: ------------------------------------------- Jan 18 18:17:00 volumio-rpi4 volumio[14093]: info: ----- Volumio3 ---- Jan 18 18:17:00 volumio-rpi4 volumio[14093]: info: ------------------------------------------- Jan 18 18:17:00 volumio-rpi4 volumio[14093]: info: ----- System startup ---- Jan 18 18:17:00 volumio-rpi4 volumio[14093]: info: ------------------------------------------- Jan 18 18:17:00 volumio-rpi4 volumio[14093]: info: MYVOLUMIO Environment detected Jan 18 18:17:00 volumio-rpi4 systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Jan 18 18:17:00 volumio-rpi4 systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1247. Jan 18 18:17:00 volumio-rpi4 systemd[1]: Stopped FusionDsp Daemon. Jan 18 18:17:01 volumio-rpi4 volumio[14093]: info: Plugin folders cleanup Jan 18 18:17:01 volumio-rpi4 volumio[14093]: info: Scanning into folder /volumio/app/plugins/ Jan 18 18:17:01 volumio-rpi4 volumio[14093]: info: Scanning category audio_interface Jan 18 18:17:01 volumio-rpi4 volumio[14093]: info: Scanning category miscellanea Jan 18 18:17:01 volumio-rpi4 volumio[14093]: info: Scanning category music_service Jan 18 18:17:01 volumio-rpi4 volumio[14093]: info: Scanning category plugins.json Jan 18 18:17:01 volumio-rpi4 volumio[14093]: info: Scanning category system_controller Jan 18 18:17:01 volumio-rpi4 volumio[14093]: info: Scanning category user_interface Jan 18 18:17:01 volumio-rpi4 volumio[14093]: info: Scanning into folder /data/plugins/ Jan 18 18:17:01 volumio-rpi4 volumio[14093]: info: Scanning category audio_interface Jan 18 18:17:01 volumio-rpi4 volumio[14093]: info: Scanning category music_service Jan 18 18:17:01 volumio-rpi4 volumio[14093]: info: Scanning category system_controller Jan 18 18:17:01 volumio-rpi4 volumio[14093]: info: Scanning category user_interface Jan 18 18:17:01 volumio-rpi4 volumio[14093]: info: Plugin folders cleanup completed Jan 18 18:17:01 volumio-rpi4 volumio[14093]: info: ------------------------------------------- Jan 18 18:17:01 volumio-rpi4 volumio[14093]: info: ----- Core plugins startup ---- Jan 18 18:17:01 volumio-rpi4 volumio[14093]: info: ------------------------------------------- Jan 18 18:17:01 volumio-rpi4 volumio[14093]: info: Loading plugins from folder /volumio/app/plugins/ Jan 18 18:17:01 volumio-rpi4 volumio[14093]: info: Adding plugin upnp to MyMusic Plugins Jan 18 18:17:01 volumio-rpi4 volumio[14093]: info: Adding plugin airplay_emulation to MyMusic Plugins Jan 18 18:17:01 volumio-rpi4 volumio[14093]: info: Adding plugin upnp_browser to MyMusic Plugins Jan 18 18:17:01 volumio-rpi4 volumio[14093]: info: Loading plugins from folder /data/plugins/ Jan 18 18:17:01 volumio-rpi4 volumio[14093]: info: Loading plugin "system"... Jan 18 18:17:01 volumio-rpi4 systemd[1]: Started FusionDsp Daemon. Jan 18 18:17:01 volumio-rpi4 volumio[14093]: info: Loading plugin "appearance"... Jan 18 18:17:01 volumio-rpi4 systemd[14120]: fusiondsp.service: Failed to execute command: No such file or directory Jan 18 18:17:01 volumio-rpi4 systemd[14120]: fusiondsp.service: Failed at step EXEC spawning /data/plugins/audio_interface/fusiondsp/startcgui.sh: No such file or directory Jan 18 18:17:01 volumio-rpi4 systemd[1]: fusiondsp.service: Main process exited, code=exited, status=203/EXEC Jan 18 18:17:01 volumio-rpi4 systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jan 18 18:17:01 volumio-rpi4 volumio[14093]: info: Loading plugin "network"... Jan 18 18:17:01 volumio-rpi4 volumio[14093]: info: Refreshing Cached IP Addresses Jan 18 18:17:01 volumio-rpi4 sudo[14126]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 18 18:17:01 volumio-rpi4 sudo[14126]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 18:17:01 volumio-rpi4 sudo[14126]: pam_unix(sudo:session): session closed for user root Jan 18 18:17:01 volumio-rpi4 sudo[14128]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 18 18:17:01 volumio-rpi4 sudo[14128]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 18:17:01 volumio-rpi4 sudo[14128]: pam_unix(sudo:session): session closed for user root Jan 18 18:17:01 volumio-rpi4 volumio[14093]: info: Loading plugin "services"... Jan 18 18:17:01 volumio-rpi4 volumio[14093]: info: Loading plugin "alsa_controller"... Jan 18 18:17:01 volumio-rpi4 sudo[14137]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jan 18 18:17:01 volumio-rpi4 sudo[14137]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 18:17:01 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 18 18:17:01 volumio-rpi4 volumio[14093]: info: Loading plugin "wizard"... Jan 18 18:17:01 volumio-rpi4 volumio[14093]: info: Loading plugin "networkfs"... Jan 18 18:17:01 volumio-rpi4 volumio[14093]: info: Cannot mount NAS musique at system boot, trial number 1 ,retrying in 5 seconds Jan 18 18:17:01 volumio-rpi4 volumio[14093]: info: Starting Udev Watcher for removable devices Jan 18 18:17:01 volumio-rpi4 volumio[14093]: info: Ignoring mount for partition: boot Jan 18 18:17:01 volumio-rpi4 volumio[14093]: info: Ignoring mount for partition: volumio Jan 18 18:17:01 volumio-rpi4 volumio[14093]: info: Ignoring mount for partition: volumio_data Jan 18 18:17:01 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 18 18:17:01 volumio-rpi4 volumio[14093]: info: Loading plugin "volumio_command_line_client"... Jan 18 18:17:01 volumio-rpi4 volumio[14093]: info: Loading plugin "upnp"... Jan 18 18:17:01 volumio-rpi4 volumio[14093]: info: [1737220621842] Starting Upmpd Daemon Jan 18 18:17:01 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 18 18:17:01 volumio-rpi4 volumio[14093]: info: Loading plugin "my_music"... Jan 18 18:17:01 volumio-rpi4 volumio[14093]: info: Loading plugin "mpd"... Jan 18 18:17:02 volumio-rpi4 volumio[14093]: info: Loading plugin "upnp_browser"... Jan 18 18:17:02 volumio-rpi4 volumio[14093]: info: Loading plugin "alarm-clock"... Jan 18 18:17:02 volumio-rpi4 volumio[14093]: info: Loading plugin "airplay_emulation"... Jan 18 18:17:02 volumio-rpi4 volumio[14093]: info: Starting Shairport Sync Jan 18 18:17:02 volumio-rpi4 volumio[14093]: info: Loading plugin "last_100"... Jan 18 18:17:02 volumio-rpi4 volumio[14093]: info: Loading plugin "webradio"... Jan 18 18:17:02 volumio-rpi4 volumio[14093]: info: Loading plugin "i2s_dacs"... Jan 18 18:17:02 volumio-rpi4 volumio[14093]: info: I2S DAC not set, start Auto-detection Jan 18 18:17:02 volumio-rpi4 volumio[14093]: info: Loading plugin "volumiodiscovery"... Jan 18 18:17:02 volumio-rpi4 volumio[14093]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 18 18:17:02 volumio-rpi4 volumio[14093]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 18 18:17:02 volumio-rpi4 volumio[14093]: *** WARNING *** For more information see Jan 18 18:17:02 volumio-rpi4 node[14093]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 18 18:17:02 volumio-rpi4 volumio[14093]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 18 18:17:02 volumio-rpi4 volumio[14093]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 18 18:17:02 volumio-rpi4 volumio[14093]: *** WARNING *** For more information see Jan 18 18:17:02 volumio-rpi4 node[14093]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 18 18:17:02 volumio-rpi4 node[14093]: *** WARNING *** For more information see Jan 18 18:17:02 volumio-rpi4 node[14093]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 18 18:17:02 volumio-rpi4 node[14093]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 18 18:17:02 volumio-rpi4 node[14093]: *** WARNING *** For more information see Jan 18 18:17:02 volumio-rpi4 volumio[14093]: info: Applying required configuration parameters for plugin volumiodiscovery Jan 18 18:17:02 volumio-rpi4 volumio[14093]: info: Discovery: Started advertising with name: Volumio-rpi4 Jan 18 18:17:02 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 18 18:17:02 volumio-rpi4 volumio[14093]: info: Plugin calmradio is not enabled Jan 18 18:17:02 volumio-rpi4 volumio[14093]: info: Plugin spop is not enabled Jan 18 18:17:02 volumio-rpi4 volumio[14093]: info: Plugin ytmusic is not enabled Jan 18 18:17:02 volumio-rpi4 volumio[14093]: info: Plugin SleepWakePlugin is not enabled Jan 18 18:17:02 volumio-rpi4 volumio[14093]: info: Plugin now_playing is not enabled Jan 18 18:17:02 volumio-rpi4 volumio[14093]: info: Loading plugin "outputs"... Jan 18 18:17:02 volumio-rpi4 volumio[14093]: info: Loading plugin "albumart"... Jan 18 18:17:02 volumio-rpi4 volumio[14093]: info: Plugin example_plugin is not enabled Jan 18 18:17:02 volumio-rpi4 volumio[14093]: info: Loading plugin "inputs"... Jan 18 18:17:02 volumio-rpi4 volumio[14093]: info: Loading plugin "updater_comm"... Jan 18 18:17:02 volumio-rpi4 volumio[14093]: info: Plugin mpdemulation is not enabled Jan 18 18:17:02 volumio-rpi4 volumio[14093]: info: Loading plugin "rest_api"... Jan 18 18:17:02 volumio-rpi4 volumio[14093]: info: Loading plugin "websocket"... Jan 18 18:17:02 volumio-rpi4 volumio[14093]: info: Starting Socket.io Server version 2.3.0 Jan 18 18:17:02 volumio-rpi4 volumio[14093]: info: Plugin Bluetoothout is not enabled Jan 18 18:17:02 volumio-rpi4 volumio[14093]: info: Loading plugin "fusiondsp"... Jan 18 18:17:02 volumio-rpi4 volumio[14093]: Forking 3 albumart workers Jan 18 18:17:02 volumio-rpi4 volumio[14093]: info: Applying required configuration parameters for plugin fusiondsp Jan 18 18:17:03 volumio-rpi4 volumio[14093]: info: Plugin mpdoutput is not enabled Jan 18 18:17:03 volumio-rpi4 volumio[14093]: info: Plugin podcast is not enabled Jan 18 18:17:03 volumio-rpi4 volumio[14093]: info: Plugin peppymeterbasic is not enabled Jan 18 18:17:03 volumio-rpi4 volumio[14093]: info: Plugin peppyspectrum is not enabled Jan 18 18:17:03 volumio-rpi4 volumio[14093]: info: Loading plugin "touch_display"... Jan 18 18:17:03 volumio-rpi4 systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Jan 18 18:17:03 volumio-rpi4 systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1248. Jan 18 18:17:03 volumio-rpi4 systemd[1]: Stopped FusionDsp Daemon. Jan 18 18:17:03 volumio-rpi4 volumio[14093]: info: Applying required configuration parameters for plugin touch_display Jan 18 18:17:03 volumio-rpi4 systemd[1]: Started FusionDsp Daemon. Jan 18 18:17:03 volumio-rpi4 systemd[14184]: fusiondsp.service: Failed to execute command: No such file or directory Jan 18 18:17:03 volumio-rpi4 systemd[14184]: fusiondsp.service: Failed at step EXEC spawning /data/plugins/audio_interface/fusiondsp/startcgui.sh: No such file or directory Jan 18 18:17:03 volumio-rpi4 systemd[1]: fusiondsp.service: Main process exited, code=exited, status=203/EXEC Jan 18 18:17:03 volumio-rpi4 systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jan 18 18:17:03 volumio-rpi4 volumio[14093]: info: Loading i18n strings for locale en Jan 18 18:17:03 volumio-rpi4 volumio[14093]: Updating browse sources language Jan 18 18:17:03 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 18 18:17:03 volumio-rpi4 volumio[14093]: Starting albumart workers Jan 18 18:17:03 volumio-rpi4 volumio[14093]: Starting albumart workers Jan 18 18:17:03 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 18 18:17:03 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::initPlayerControls Jan 18 18:17:03 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 18 18:17:03 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 18 18:17:03 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 18 18:17:03 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 18 18:17:03 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 18 18:17:03 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 18 18:17:03 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 18 18:17:03 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 18 18:17:03 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 18 18:17:03 volumio-rpi4 volumio[14093]: Express server listening on port 3000 Jan 18 18:17:03 volumio-rpi4 volumio[14093]: [Metrics] WebUI: 3s 305.85ms Jan 18 18:17:03 volumio-rpi4 volumio[14093]: info: CoreStateMachine::resetVolumioState Jan 18 18:17:03 volumio-rpi4 volumio[14093]: info: CoreStateMachine::getcurrentVolume Jan 18 18:17:03 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::volumioRetrievevolume Jan 18 18:17:03 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 18 18:17:03 volumio-rpi4 volumio[14093]: Starting albumart workers Jan 18 18:17:03 volumio-rpi4 volumio[14093]: info: Volumio Network Manager: Network status updated: 1 Jan 18 18:17:03 volumio-rpi4 volumio[14093]: verbose: New Socket.io Connection to 192.168.1.93 from 192.168.1.50 UA: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:134.0) Gecko/20100101 Firefox/134.0 Engine version: 3 Transport: polling Total Clients: 1 Jan 18 18:17:03 volumio-rpi4 volumio[14093]: verbose: New Socket.io Connection to 192.168.1.93:3000 from 192.168.1.77 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Jan 18 18:17:03 volumio-rpi4 volumio[14093]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Jan 18 18:17:03 volumio-rpi4 volumio[14093]: verbose: New Socket.io Connection to 192.168.1.93 from 192.168.1.50 UA: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:134.0) Gecko/20100101 Firefox/134.0 Engine version: 3 Transport: polling Total Clients: 4 Jan 18 18:17:03 volumio-rpi4 volumio[14093]: verbose: New Socket.io Connection to 192.168.1.93 from 192.168.1.50 UA: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:134.0) Gecko/20100101 Firefox/134.0 Engine version: 3 Transport: polling Total Clients: 5 Jan 18 18:17:03 volumio-rpi4 volumio[14093]: verbose: New Socket.io Connection to 192.168.1.93 from 192.168.1.50 UA: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:134.0) Gecko/20100101 Firefox/134.0 Engine version: 3 Transport: polling Total Clients: 6 Jan 18 18:17:03 volumio-rpi4 volumio[14093]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Jan 18 18:17:03 volumio-rpi4 volumio[14093]: verbose: New Socket.io Connection to 192.168.1.93 from 192.168.1.50 UA: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:134.0) Gecko/20100101 Firefox/134.0 Engine version: 3 Transport: polling Total Clients: 8 Jan 18 18:17:03 volumio-rpi4 volumio[14093]: verbose: New Socket.io Connection to 192.168.1.93:3000 from 192.168.1.38 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Jan 18 18:17:03 volumio-rpi4 volumio[14093]: verbose: New Socket.io Connection to 192.168.1.93:3000 from 192.168.1.89 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Jan 18 18:17:03 volumio-rpi4 volumio[14093]: verbose: New Socket.io Connection to 192.168.1.93:3000 from 192.168.1.23 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Jan 18 18:17:03 volumio-rpi4 volumio[14093]: verbose: New Socket.io Connection to 192.168.1.93:3000 from 192.168.1.77 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 12 Jan 18 18:17:03 volumio-rpi4 volumio[14093]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 13 Jan 18 18:17:03 volumio-rpi4 volumio[14093]: info: Reloading queue from file Jan 18 18:17:03 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Jan 18 18:17:03 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Jan 18 18:17:03 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Jan 18 18:17:03 volumio-rpi4 volumio[14093]: info: VolumeController:: Volume=29 Mute =false Jan 18 18:17:03 volumio-rpi4 volumio[14093]: info: CoreStateMachine::pushState Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: CorePlayQueue::getTrack 0 Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::volumioPushState Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: CoreStateMachine::updateTrackBlock Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: CorePlayQueue::getTrackBlock Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::volumioRetrievevolume Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: CoreStateMachine::setRepeat null single undefined Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: CoreStateMachine::pushState Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: CorePlayQueue::getTrack 0 Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::volumioPushState Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: CoreStateMachine::setRandom true Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: CoreStateMachine::pushState Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: CorePlayQueue::getTrack 0 Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::volumioPushState Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: Setting Device type: Raspberry PI Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: USB Boot Capable - System SBC Revision found in cpuinfo: b03111 Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: Discovery: adding 5532dd92-2afe-4b04-8e5b-187fa7b041fd Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: Discovery: Found device Volumio-dell-salon Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: Discovery: Connecting to remote: 192.168.1.89 Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::volumioGetState Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: CorePlayQueue::getTrack 0 Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::volumioGetState Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: CorePlayQueue::getTrack 0 Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::volumioGetState Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: CorePlayQueue::getTrack 0 Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: Discovery: adding c8d9e3d6-fa93-49b0-9dc2-577f68689abf Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: Discovery: Found device Integro-office Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: Discovery: Connecting to remote: 192.168.1.38 Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::volumioGetState Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: CorePlayQueue::getTrack 0 Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: VolumeController:: Volume=29 Mute =false Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: CoreStateMachine::pushState Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: CorePlayQueue::getTrack 0 Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::volumioPushState Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: Discovery: adding b1a570d3-0924-4981-8fd1-68bca66a4193 Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: Discovery: Found device bookworm-rpi5 Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: Discovery: Connecting to remote: 192.168.1.23 Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: Discovery: Connected to remote: 192.168.1.38 Jan 18 18:17:04 volumio-rpi4 volumio[14093]: verbose: New Socket.io Connection to 192.168.1.93 from 192.168.1.50 UA: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:134.0) Gecko/20100101 Firefox/134.0 Engine version: 3 Transport: polling Total Clients: 14 Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: Discovery: Connected to remote: 192.168.1.89 Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: Completed loading Core Plugins Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: Preparing to generate the ALSA configuration file Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: Reading ALSA contributions from plugins. Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: Discovery: adding cb9136bd-5e70-4c85-86fa-eff4cd2bf0ca Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: Discovery: Found device Volumio-rpi4 Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::volumioGetState Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: CorePlayQueue::getTrack 0 Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: Discovery: adding 89a86619-18ae-4300-a9bd-067abcfbd7d0 Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: Discovery: Found device Volumio-primo Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: Discovery: Connecting to remote: 192.168.1.77 Jan 18 18:17:04 volumio-rpi4 volumio-remote-updater[589]: [2025-01-18 18:17:04] [connect] Successful connection Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: Discovery: Connected to remote: 192.168.1.23 Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: Received Get System Info Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: Discovery: Getting this device information Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::volumioGetState Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: CorePlayQueue::getTrack 0 Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::volumioGetState Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: CorePlayQueue::getTrack 0 Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::volumioGetVisibleSources Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::volumioGetState Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: CorePlayQueue::getTrack 0 Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: Received Get System Info Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: Discovery: Getting this device information Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::volumioGetState Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: CorePlayQueue::getTrack 0 Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::volumioGetState Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: CorePlayQueue::getTrack 0 Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: Listing playlists Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: Discovery: this is already registered, cb9136bd-5e70-4c85-86fa-eff4cd2bf0ca Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: Discovery: Found device Volumio-rpi4 Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::volumioGetState Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: CorePlayQueue::getTrack 0 Jan 18 18:17:04 volumio-rpi4 volumio-remote-updater[589]: [2025-01-18 18:17:04] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1737220624 101 Jan 18 18:17:04 volumio-rpi4 volumio[14093]: 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: 15 Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: Discovery: Connected to remote: 192.168.1.77 Jan 18 18:17:04 volumio-rpi4 volumio[14093]: verbose: New Socket.io Connection to 192.168.1.93 from 192.168.1.50 UA: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:134.0) Gecko/20100101 Firefox/134.0 Engine version: 3 Transport: polling Total Clients: 16 Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: Asound.conf file unchanged, so no further update is needed Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: Output device has changed, restarting MPD Jan 18 18:17:04 volumio-rpi4 sudo[14227]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 18 18:17:04 volumio-rpi4 sudo[14227]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: Output device has changed, restarting Shairport Sync Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 18 18:17:04 volumio-rpi4 sudo[14227]: pam_unix(sudo:session): session closed for user root Jan 18 18:17:04 volumio-rpi4 sudo[14230]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 18 18:17:04 volumio-rpi4 sudo[14230]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 18:17:04 volumio-rpi4 systemd[1]: Stopping Music Player Daemon... Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: ___________ START PLUGINS ___________ Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: ControllerMpd::onStart: Initializing MPD Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: Creating MPD Configuration file Jan 18 18:17:04 volumio-rpi4 systemd[1]: mpd.service: Succeeded. Jan 18 18:17:04 volumio-rpi4 systemd[1]: Stopped Music Player Daemon. Jan 18 18:17:04 volumio-rpi4 sudo[14237]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 18 18:17:04 volumio-rpi4 sudo[14237]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: [1737220624600] CoreMusicLibrary::Adding element Media Servers Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 18 18:17:04 volumio-rpi4 sudo[14237]: pam_unix(sudo:session): session closed for user root Jan 18 18:17:04 volumio-rpi4 sudo[14240]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 18 18:17:04 volumio-rpi4 sudo[14240]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 18:17:04 volumio-rpi4 systemd[1]: Starting Music Player Daemon... Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 18 18:17:04 volumio-rpi4 systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jan 18 18:17:04 volumio-rpi4 systemd[1]: mpd.service: Succeeded. Jan 18 18:17:04 volumio-rpi4 systemd[1]: Stopped Music Player Daemon. Jan 18 18:17:04 volumio-rpi4 systemd[1]: Starting Music Player Daemon... Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: [1737220624703] CoreMusicLibrary::Adding element Last_100 Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: [1737220624705] CoreMusicLibrary::Adding element Webradio Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: Initializing BBC Radios Jan 18 18:17:04 volumio-rpi4 sudo[14247]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 18 18:17:04 volumio-rpi4 sudo[14247]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 18:17:04 volumio-rpi4 sudo[14247]: pam_unix(sudo:session): session closed for user root Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: Loading i18n strings for locale en Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: FusionDsp - mixtype--------------------- Hardware Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: Loading i18n strings for locale en Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: Volumio Calling Home Jan 18 18:17:04 volumio-rpi4 volumio[14093]: info: Preparing to generate the ALSA configuration file Jan 18 18:17:04 volumio-rpi4 sudo[14266]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Jan 18 18:17:04 volumio-rpi4 sudo[14266]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 18:17:04 volumio-rpi4 sudo[14269]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Jan 18 18:17:04 volumio-rpi4 sudo[14269]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 18:17:04 volumio-rpi4 sudo[14272]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Jan 18 18:17:04 volumio-rpi4 sudo[14272]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 18:17:04 volumio-rpi4 sudo[14266]: pam_unix(sudo:session): session closed for user root Jan 18 18:17:04 volumio-rpi4 systemd[1]: Reloading. Jan 18 18:17:05 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 18 18:17:05 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 18 18:17:05 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 18 18:17:05 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 18 18:17:05 volumio-rpi4 volumio[14093]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jan 18 18:17:05 volumio-rpi4 volumio[14093]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jan 18 18:17:05 volumio-rpi4 volumio[14093]: info: Reading ALSA contributions from plugins. Jan 18 18:17:05 volumio-rpi4 volumio[14093]: info: touch_display: Backlight interface detected. Jan 18 18:17:05 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::volumioGetState Jan 18 18:17:05 volumio-rpi4 volumio[14093]: info: CorePlayQueue::getTrack 0 Jan 18 18:17:05 volumio-rpi4 volumio[14093]: info: touch_display: systemctl stop getty@tty1.service succeeded. Jan 18 18:17:05 volumio-rpi4 volumio[14093]: info: MPD Permissions set Jan 18 18:17:05 volumio-rpi4 volumio[14093]: info: MPD Permissions set Jan 18 18:17:05 volumio-rpi4 volumio[14093]: info: Received Get System Info Jan 18 18:17:05 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 18 18:17:05 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 18 18:17:05 volumio-rpi4 volumio[14093]: info: Discovery: Getting this device information Jan 18 18:17:05 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::volumioGetState Jan 18 18:17:05 volumio-rpi4 volumio[14093]: info: CorePlayQueue::getTrack 0 Jan 18 18:17:05 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 18 18:17:05 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::volumioGetState Jan 18 18:17:05 volumio-rpi4 volumio[14093]: info: CorePlayQueue::getTrack 0 Jan 18 18:17:05 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 18 18:17:05 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::volumioGetVisibleSources Jan 18 18:17:05 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 18 18:17:05 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::volumioGetState Jan 18 18:17:05 volumio-rpi4 volumio[14093]: info: CorePlayQueue::getTrack 0 Jan 18 18:17:05 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::volumioGetState Jan 18 18:17:05 volumio-rpi4 volumio[14093]: info: CorePlayQueue::getTrack 0 Jan 18 18:17:05 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jan 18 18:17:05 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 18 18:17:05 volumio-rpi4 volumio[14093]: info: Received Get System Info Jan 18 18:17:05 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 18 18:17:05 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 18 18:17:05 volumio-rpi4 volumio[14093]: info: Discovery: Getting this device information Jan 18 18:17:05 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::volumioGetState Jan 18 18:17:05 volumio-rpi4 volumio[14093]: info: CorePlayQueue::getTrack 0 Jan 18 18:17:05 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 18 18:17:05 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::volumioGetState Jan 18 18:17:05 volumio-rpi4 volumio[14093]: info: CorePlayQueue::getTrack 0 Jan 18 18:17:05 volumio-rpi4 volumio[14093]: info: Listing playlists Jan 18 18:17:05 volumio-rpi4 volumio[14093]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 17 Jan 18 18:17:05 volumio-rpi4 volumio[14093]: info: Volumio called home Jan 18 18:17:05 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 18 18:17:05 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 18 18:17:05 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 18 18:17:05 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 18 18:17:05 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 18 18:17:05 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 18 18:17:05 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 18 18:17:05 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 18 18:17:05 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 18 18:17:05 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 18 18:17:05 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 18 18:17:05 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 18 18:17:05 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 18 18:17:05 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 18 18:17:05 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 18 18:17:05 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 18 18:17:05 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 18 18:17:05 volumio-rpi4 systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Jan 18 18:17:05 volumio-rpi4 sudo[14137]: pam_unix(sudo:session): session closed for user root Jan 18 18:17:05 volumio-rpi4 systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Jan 18 18:17:05 volumio-rpi4 systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1249. Jan 18 18:17:05 volumio-rpi4 sudo[14269]: pam_unix(sudo:session): session closed for user root Jan 18 18:17:05 volumio-rpi4 systemd[1]: Stopped FusionDsp Daemon. Jan 18 18:17:05 volumio-rpi4 systemd[1]: Started FusionDsp Daemon. Jan 18 18:17:05 volumio-rpi4 systemd[1]: Reloading. Jan 18 18:17:05 volumio-rpi4 systemd[14296]: fusiondsp.service: Failed to execute command: No such file or directory Jan 18 18:17:05 volumio-rpi4 systemd[14296]: fusiondsp.service: Failed at step EXEC spawning /data/plugins/audio_interface/fusiondsp/startcgui.sh: No such file or directory Jan 18 18:17:05 volumio-rpi4 mpd[14261]: decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 18 18:17:05 volumio-rpi4 mpd[14261]: output: No 'audio_output' defined in config file Jan 18 18:17:05 volumio-rpi4 mpd[14261]: output: Successfully detected a sndio audio device Jan 18 18:17:05 volumio-rpi4 mpd[14261]: zeroconf: No global port, disabling zeroconf Jan 18 18:17:05 volumio-rpi4 systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Jan 18 18:17:06 volumio-rpi4 volumio[14093]: info: touch_display: systemctl disable getty@tty1.service succeeded. Jan 18 18:17:06 volumio-rpi4 volumio[14093]: info: touch_display: Raspberry Pi Foundation touch screen detected. Jan 18 18:17:06 volumio-rpi4 sudo[14313]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Jan 18 18:17:06 volumio-rpi4 sudo[14313]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 18:17:06 volumio-rpi4 volumio[14093]: info: Starting Shairport Sync Jan 18 18:17:06 volumio-rpi4 sudo[14313]: pam_unix(sudo:session): session closed for user root Jan 18 18:17:06 volumio-rpi4 sudo[14316]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+w /sys/class/backlight/rpi_backlight/brightness Jan 18 18:17:06 volumio-rpi4 sudo[14316]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 18:17:06 volumio-rpi4 sudo[14316]: pam_unix(sudo:session): session closed for user root Jan 18 18:17:06 volumio-rpi4 volumio[14093]: info: Starting Shairport Sync Jan 18 18:17:06 volumio-rpi4 sudo[14319]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 18 18:17:06 volumio-rpi4 sudo[14319]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 18:17:06 volumio-rpi4 volumio[14093]: info: Starting Shairport Sync Jan 18 18:17:06 volumio-rpi4 sudo[14321]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 18 18:17:06 volumio-rpi4 sudo[14321]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 18:17:06 volumio-rpi4 sudo[14325]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 18 18:17:06 volumio-rpi4 sudo[14325]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 18:17:06 volumio-rpi4 systemd[1]: Started Music Player Daemon. Jan 18 18:17:06 volumio-rpi4 sudo[14272]: pam_unix(sudo:session): session closed for user root Jan 18 18:17:06 volumio-rpi4 systemd[1]: fusiondsp.service: Main process exited, code=exited, status=203/EXEC Jan 18 18:17:06 volumio-rpi4 systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jan 18 18:17:06 volumio-rpi4 sudo[14230]: pam_unix(sudo:session): session closed for user root Jan 18 18:17:06 volumio-rpi4 sudo[14240]: pam_unix(sudo:session): session closed for user root Jan 18 18:17:06 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::volumioGetState Jan 18 18:17:06 volumio-rpi4 volumio[14093]: info: CorePlayQueue::getTrack 0 Jan 18 18:17:06 volumio-rpi4 volumio[14093]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Jan 18 18:17:06 volumio-rpi4 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 18 18:17:06 volumio-rpi4 systemd[1]: shairport-sync.service: Succeeded. Jan 18 18:17:06 volumio-rpi4 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 18 18:17:06 volumio-rpi4 volumio[14093]: info: touch_display: systemctl daemon-reload succeeded. Jan 18 18:17:06 volumio-rpi4 volumio[14093]: info: touch_display: File permissions for backlight brightness control set. Jan 18 18:17:06 volumio-rpi4 volumio[14093]: info: Asound.conf file unchanged, so no further update is needed Jan 18 18:17:06 volumio-rpi4 volumio[14093]: info: Output device has changed, restarting MPD Jan 18 18:17:06 volumio-rpi4 sudo[14331]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Jan 18 18:17:06 volumio-rpi4 sudo[14331]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 18:17:06 volumio-rpi4 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 18 18:17:06 volumio-rpi4 sudo[14334]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 18 18:17:06 volumio-rpi4 sudo[14334]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 18:17:06 volumio-rpi4 sudo[14325]: pam_unix(sudo:session): session closed for user root Jan 18 18:17:06 volumio-rpi4 sudo[14321]: pam_unix(sudo:session): session closed for user root Jan 18 18:17:06 volumio-rpi4 sudo[14319]: pam_unix(sudo:session): session closed for user root Jan 18 18:17:06 volumio-rpi4 sudo[14334]: pam_unix(sudo:session): session closed for user root Jan 18 18:17:06 volumio-rpi4 volumio[14093]: info: Output device has changed, restarting Shairport Sync Jan 18 18:17:06 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 18 18:17:06 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 18 18:17:06 volumio-rpi4 sudo[14337]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 18 18:17:06 volumio-rpi4 sudo[14337]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 18:17:06 volumio-rpi4 sudo[14331]: pam_unix(sudo:session): session closed for user root Jan 18 18:17:06 volumio-rpi4 systemd[1]: Stopping Music Player Daemon... Jan 18 18:17:06 volumio-rpi4 systemd[1]: mpd.service: Succeeded. Jan 18 18:17:06 volumio-rpi4 systemd[1]: Stopped Music Player Daemon. Jan 18 18:17:06 volumio-rpi4 systemd[1]: Starting Music Player Daemon... Jan 18 18:17:06 volumio-rpi4 volumio[14093]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 18 18:17:06 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 18 18:17:06 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 18 18:17:06 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 18 18:17:06 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 18 18:17:06 volumio-rpi4 volumio[14093]: info: MPD running with PID14261 Jan 18 18:17:06 volumio-rpi4 volumio[14093]: ,establishing connection Jan 18 18:17:06 volumio-rpi4 sudo[14344]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 18 18:17:06 volumio-rpi4 sudo[14344]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 18:17:06 volumio-rpi4 volumio[14093]: error: MPD error: Error: write EPIPE Jan 18 18:17:06 volumio-rpi4 volumio[14093]: error: write EPIPE {"errno":-32,"code":"EPIPE","syscall":"write"} Jan 18 18:17:06 volumio-rpi4 sudo[14344]: pam_unix(sudo:session): session closed for user root Jan 18 18:17:06 volumio-rpi4 volumio[14093]: error: MPD error: The expression evaluated to a falsy value: Jan 18 18:17:06 volumio-rpi4 volumio[14093]: assert.ok(self.idling) Jan 18 18:17:06 volumio-rpi4 volumio[14093]: error: The expression evaluated to a falsy value: Jan 18 18:17:06 volumio-rpi4 volumio[14093]: assert.ok(self.idling) Jan 18 18:17:06 volumio-rpi4 volumio[14093]: error: MPD error: Error: write EPIPE Jan 18 18:17:06 volumio-rpi4 volumio[14093]: error: write EPIPE {"errno":-32,"code":"EPIPE","syscall":"write"} Jan 18 18:17:06 volumio-rpi4 volumio[14093]: error: MPD error: The expression evaluated to a falsy value: Jan 18 18:17:06 volumio-rpi4 volumio[14093]: assert.ok(self.idling) Jan 18 18:17:06 volumio-rpi4 volumio[14093]: error: The expression evaluated to a falsy value: Jan 18 18:17:06 volumio-rpi4 volumio[14093]: assert.ok(self.idling) Jan 18 18:17:06 volumio-rpi4 volumio[14093]: info: MPD Permissions set Jan 18 18:17:06 volumio-rpi4 volumio[14093]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Jan 18 18:17:06 volumio-rpi4 volumio[14093]: info: touch_display: Volumio Kiosk started. Jan 18 18:17:06 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::volumioGetState Jan 18 18:17:06 volumio-rpi4 volumio[14093]: info: CorePlayQueue::getTrack 0 Jan 18 18:17:06 volumio-rpi4 volumio[14093]: info: Completed starting Core Plugins Jan 18 18:17:06 volumio-rpi4 volumio[14093]: info: ------------------------------------------- Jan 18 18:17:06 volumio-rpi4 volumio[14093]: info: ----- MyVolumio plugins startup ---- Jan 18 18:17:06 volumio-rpi4 volumio[14093]: info: ------------------------------------------- Jan 18 18:17:06 volumio-rpi4 volumio[14093]: info: [MyVolumio PluginManager] Fetching plans data.... Jan 18 18:17:06 volumio-rpi4 volumio[14093]: info: Shairport-Sync Started Jan 18 18:17:06 volumio-rpi4 volumio[14093]: Error adding Membership: Error: addMembership EINVAL Jan 18 18:17:06 volumio-rpi4 volumio[14093]: info: Shairport-Sync Started Jan 18 18:17:06 volumio-rpi4 volumio[14093]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Jan 18 18:17:06 volumio-rpi4 volumio[14093]: 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 Jan 18 18:17:06 volumio-rpi4 volumio[14093]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Jan 18 18:17:06 volumio-rpi4 volumio[14093]: info: camilladsp spawned new process with pid 14358, instance 1, run: true Jan 18 18:17:06 volumio-rpi4 volumio[14093]: info: camilladsp service started and running in background, instance 1 Jan 18 18:17:06 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 18 18:17:06 volumio-rpi4 volumio[14093]: info: FusionDsp - AAAAAAAAAAAAAA-> 22050 44100 48000 88200 96000 176400 192000 <-AAAAAAAAAAAAA Jan 18 18:17:06 volumio-rpi4 volumio[14093]: info: FusionDsp loaded Jan 18 18:17:06 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 18 18:17:06 volumio-rpi4 sudo[14368]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Jan 18 18:17:06 volumio-rpi4 sudo[14368]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 18:17:06 volumio-rpi4 volumio[14093]: info: FusionDsp - Reporting Fusion DSP Enabled Jan 18 18:17:06 volumio-rpi4 volumio[14093]: info: Adding Signal Path Element [object Object] Jan 18 18:17:06 volumio-rpi4 volumio[14093]: info: Adding fusiondspeq DSP Signal Path Element Jan 18 18:17:06 volumio-rpi4 volumio[14093]: info: FusionDsp - ---- installed callbackRead Jan 18 18:17:06 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::volumioGetState Jan 18 18:17:06 volumio-rpi4 volumio[14093]: info: CorePlayQueue::getTrack 0 Jan 18 18:17:06 volumio-rpi4 volumio[14093]: info: touch_display: X display number found: 0 Jan 18 18:17:06 volumio-rpi4 volumio[14093]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jan 18 18:17:06 volumio-rpi4 volumio[14093]: info: Cannot mount NAS musique at system boot, trial number 2 ,retrying in 5 seconds Jan 18 18:17:06 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 18 18:17:06 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 18 18:17:06 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 18 18:17:06 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 18 18:17:06 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 18 18:17:06 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 18 18:17:06 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 18 18:17:06 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 18 18:17:07 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 18 18:17:07 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 18 18:17:07 volumio-rpi4 volumio[14093]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 18 Jan 18 18:17:07 volumio-rpi4 mpd[14348]: decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 18 18:17:07 volumio-rpi4 mpd[14348]: output: No 'audio_output' defined in config file Jan 18 18:17:07 volumio-rpi4 mpd[14348]: output: Successfully detected a sndio audio device Jan 18 18:17:07 volumio-rpi4 mpd[14348]: zeroconf: No global port, disabling zeroconf Jan 18 18:17:07 volumio-rpi4 systemd[1]: Started Music Player Daemon. Jan 18 18:17:07 volumio-rpi4 sudo[14337]: pam_unix(sudo:session): session closed for user root Jan 18 18:17:07 volumio-rpi4 volumio[14093]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Jan 18 18:17:07 volumio-rpi4 volumio[14093]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jan 18 18:17:07 volumio-rpi4 volumio[14093]: error: updateQueue error: null Jan 18 18:17:07 volumio-rpi4 volumio[14093]: info: touch_display: X display number found: 0 Jan 18 18:17:07 volumio-rpi4 volumio[14093]: info: Starting Shairport Sync Jan 18 18:17:07 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::volumioGetState Jan 18 18:17:07 volumio-rpi4 volumio[14093]: info: CorePlayQueue::getTrack 0 Jan 18 18:17:07 volumio-rpi4 sudo[14382]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 18 18:17:07 volumio-rpi4 sudo[14382]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 18:17:07 volumio-rpi4 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 18 18:17:07 volumio-rpi4 systemd[1]: shairport-sync.service: Succeeded. Jan 18 18:17:07 volumio-rpi4 volumio[14093]: error: updateQueue error: null Jan 18 18:17:07 volumio-rpi4 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 18 18:17:07 volumio-rpi4 volumio[14093]: info: touch_display: Setting screensaver timeout to 120 seconds. Jan 18 18:17:07 volumio-rpi4 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 18 18:17:07 volumio-rpi4 sudo[14382]: pam_unix(sudo:session): session closed for user root Jan 18 18:17:07 volumio-rpi4 volumio[14093]: info: Shairport-Sync Started Jan 18 18:17:07 volumio-rpi4 volumio[14093]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 19 Jan 18 18:17:08 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 18 18:17:08 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::volumioGetVisibleSources Jan 18 18:17:08 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 18 18:17:08 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::volumioGetState Jan 18 18:17:08 volumio-rpi4 volumio[14093]: info: CorePlayQueue::getTrack 0 Jan 18 18:17:08 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jan 18 18:17:08 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 18 18:17:08 volumio-rpi4 volumio[14093]: info: Received Get System Info Jan 18 18:17:08 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 18 18:17:08 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 18 18:17:08 volumio-rpi4 volumio[14093]: info: Discovery: Getting this device information Jan 18 18:17:08 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::volumioGetState Jan 18 18:17:08 volumio-rpi4 volumio[14093]: info: CorePlayQueue::getTrack 0 Jan 18 18:17:08 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 18 18:17:08 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::volumioGetState Jan 18 18:17:08 volumio-rpi4 volumio[14093]: info: CorePlayQueue::getTrack 0 Jan 18 18:17:08 volumio-rpi4 volumio[14093]: info: Listing playlists Jan 18 18:17:08 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 18 18:17:08 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 18 18:17:08 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 18 18:17:08 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 18 18:17:08 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 18 18:17:08 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 18 18:17:08 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 18 18:17:08 volumio-rpi4 systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Jan 18 18:17:08 volumio-rpi4 systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1250. Jan 18 18:17:08 volumio-rpi4 systemd[1]: Stopped FusionDsp Daemon. Jan 18 18:17:08 volumio-rpi4 systemd[14389]: fusiondsp.service: Failed to execute command: No such file or directory Jan 18 18:17:08 volumio-rpi4 systemd[14389]: fusiondsp.service: Failed at step EXEC spawning /data/plugins/audio_interface/fusiondsp/startcgui.sh: No such file or directory Jan 18 18:17:08 volumio-rpi4 systemd[1]: Started FusionDsp Daemon. Jan 18 18:17:08 volumio-rpi4 systemd[1]: fusiondsp.service: Main process exited, code=exited, status=203/EXEC Jan 18 18:17:08 volumio-rpi4 systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jan 18 18:17:08 volumio-rpi4 sudo[14368]: pam_unix(sudo:session): session closed for user root Jan 18 18:17:10 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 18 18:17:10 volumio-rpi4 systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Jan 18 18:17:10 volumio-rpi4 systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1251. Jan 18 18:17:10 volumio-rpi4 systemd[1]: Stopped FusionDsp Daemon. Jan 18 18:17:10 volumio-rpi4 systemd[14394]: fusiondsp.service: Failed to execute command: No such file or directory Jan 18 18:17:10 volumio-rpi4 systemd[1]: Started FusionDsp Daemon. Jan 18 18:17:10 volumio-rpi4 systemd[14394]: fusiondsp.service: Failed at step EXEC spawning /data/plugins/audio_interface/fusiondsp/startcgui.sh: No such file or directory Jan 18 18:17:10 volumio-rpi4 systemd[1]: fusiondsp.service: Main process exited, code=exited, status=203/EXEC Jan 18 18:17:10 volumio-rpi4 systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jan 18 18:17:11 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 18 18:17:11 volumio-rpi4 mpd[14348]: exception: No database Jan 18 18:17:11 volumio-rpi4 mpd[14348]: exception: No database Jan 18 18:17:11 volumio-rpi4 volumio[14093]: error: Failed LSINFO: Error: [50@0] {lsinfo} No database Jan 18 18:17:11 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 18 18:17:11 volumio-rpi4 sudo[14410]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 18 18:17:11 volumio-rpi4 sudo[14410]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 18:17:11 volumio-rpi4 sudo[14410]: pam_unix(sudo:session): session closed for user root Jan 18 18:17:11 volumio-rpi4 sudo[14413]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 18 18:17:11 volumio-rpi4 sudo[14413]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 18:17:11 volumio-rpi4 sudo[14413]: pam_unix(sudo:session): session closed for user root Jan 18 18:17:11 volumio-rpi4 sudo[14416]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jan 18 18:17:11 volumio-rpi4 sudo[14416]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 18:17:11 volumio-rpi4 sudo[14416]: pam_unix(sudo:session): session closed for user root Jan 18 18:17:11 volumio-rpi4 volumio[14093]: info: Upmpdcli Daemon Started Jan 18 18:17:11 volumio-rpi4 volumio[14093]: info: Cannot mount NAS musique at system boot, trial number 3 ,retrying in 5 seconds Jan 18 18:17:12 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 18 18:17:12 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 18 18:17:12 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 18 18:17:12 volumio-rpi4 systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Jan 18 18:17:12 volumio-rpi4 systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1252. Jan 18 18:17:12 volumio-rpi4 systemd[1]: Stopped FusionDsp Daemon. Jan 18 18:17:13 volumio-rpi4 systemd[1]: Started FusionDsp Daemon. Jan 18 18:17:13 volumio-rpi4 systemd[14418]: fusiondsp.service: Failed to execute command: No such file or directory Jan 18 18:17:13 volumio-rpi4 systemd[14418]: fusiondsp.service: Failed at step EXEC spawning /data/plugins/audio_interface/fusiondsp/startcgui.sh: No such file or directory Jan 18 18:17:13 volumio-rpi4 systemd[1]: fusiondsp.service: Main process exited, code=exited, status=203/EXEC Jan 18 18:17:13 volumio-rpi4 systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jan 18 18:17:13 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 18 18:17:13 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 18 18:17:14 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 18 18:17:14 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 18 18:17:15 volumio-rpi4 systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Jan 18 18:17:15 volumio-rpi4 systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1253. Jan 18 18:17:15 volumio-rpi4 systemd[1]: Stopped FusionDsp Daemon. Jan 18 18:17:15 volumio-rpi4 systemd[1]: Started FusionDsp Daemon. Jan 18 18:17:15 volumio-rpi4 systemd[14460]: fusiondsp.service: Failed to execute command: No such file or directory Jan 18 18:17:15 volumio-rpi4 systemd[14460]: fusiondsp.service: Failed at step EXEC spawning /data/plugins/audio_interface/fusiondsp/startcgui.sh: No such file or directory Jan 18 18:17:15 volumio-rpi4 systemd[1]: fusiondsp.service: Main process exited, code=exited, status=203/EXEC Jan 18 18:17:15 volumio-rpi4 systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jan 18 18:17:15 volumio-rpi4 volumio[14093]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jan 18 18:17:15 volumio-rpi4 volumio[14093]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jan 18 18:17:15 volumio-rpi4 volumio[14093]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jan 18 18:17:15 volumio-rpi4 volumio[14093]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jan 18 18:17:15 volumio-rpi4 volumio[14093]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jan 18 18:17:15 volumio-rpi4 volumio[14093]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jan 18 18:17:15 volumio-rpi4 volumio[14093]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jan 18 18:17:15 volumio-rpi4 volumio[14093]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jan 18 18:17:15 volumio-rpi4 volumio[14093]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jan 18 18:17:15 volumio-rpi4 volumio[14093]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jan 18 18:17:15 volumio-rpi4 volumio[14093]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jan 18 18:17:15 volumio-rpi4 volumio[14093]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jan 18 18:17:15 volumio-rpi4 volumio[14093]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jan 18 18:17:15 volumio-rpi4 volumio[14093]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jan 18 18:17:15 volumio-rpi4 volumio[14093]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jan 18 18:17:15 volumio-rpi4 volumio[14093]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jan 18 18:17:15 volumio-rpi4 volumio[14093]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jan 18 18:17:15 volumio-rpi4 volumio[14093]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jan 18 18:17:15 volumio-rpi4 volumio[14093]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jan 18 18:17:15 volumio-rpi4 volumio[14093]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jan 18 18:17:15 volumio-rpi4 volumio[14093]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jan 18 18:17:15 volumio-rpi4 volumio[14093]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jan 18 18:17:15 volumio-rpi4 volumio[14093]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jan 18 18:17:15 volumio-rpi4 volumio[14093]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jan 18 18:17:15 volumio-rpi4 volumio[14093]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jan 18 18:17:15 volumio-rpi4 volumio[14093]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jan 18 18:17:15 volumio-rpi4 volumio[14093]: info: Adding plugin bluetooth to MyMusic Plugins Jan 18 18:17:15 volumio-rpi4 volumio[14093]: info: Adding plugin multiroom to MyMusic Plugins Jan 18 18:17:15 volumio-rpi4 volumio[14093]: info: Adding plugin metavolumio to MyMusic Plugins Jan 18 18:17:15 volumio-rpi4 volumio[14093]: info: Adding plugin cd_controller to MyMusic Plugins Jan 18 18:17:15 volumio-rpi4 volumio[14093]: info: Adding plugin smart_inputs to MyMusic Plugins Jan 18 18:17:15 volumio-rpi4 volumio[14093]: info: Adding plugin tidalconnect to MyMusic Plugins Jan 18 18:17:15 volumio-rpi4 volumio[14093]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jan 18 18:17:15 volumio-rpi4 volumio[14093]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jan 18 18:17:15 volumio-rpi4 volumio[14093]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jan 18 18:17:15 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 18 18:17:15 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 18 18:17:15 volumio-rpi4 volumio[14093]: info: Starting MyVolumio Remote Streaming Endpoints Jan 18 18:17:15 volumio-rpi4 volumio[14093]: info: MyVolumio login type: Token Jan 18 18:17:15 volumio-rpi4 volumio[14093]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jan 18 18:17:15 volumio-rpi4 volumio[14093]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jan 18 18:17:16 volumio-rpi4 volumio[14093]: info: Starting Streaming Service Transparent Proxy Jan 18 18:17:16 volumio-rpi4 volumio[14093]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jan 18 18:17:16 volumio-rpi4 volumio[14093]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jan 18 18:17:16 volumio-rpi4 volumio[14093]: info: Streaming services startup Jan 18 18:17:16 volumio-rpi4 volumio[14093]: info: Starting Streaming Daemon Jan 18 18:17:16 volumio-rpi4 sudo[14468]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jan 18 18:17:16 volumio-rpi4 sudo[14468]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 18:17:16 volumio-rpi4 sudo[14468]: pam_unix(sudo:session): session closed for user root Jan 18 18:17:16 volumio-rpi4 volumio[14093]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jan 18 18:17:16 volumio-rpi4 volumio[14093]: error: Cannot start Volumio Streaming Daemon Jan 18 18:17:16 volumio-rpi4 volumio[14093]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jan 18 18:17:16 volumio-rpi4 volumio[14093]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jan 18 18:17:16 volumio-rpi4 volumio[14093]: info: Cannot mount NAS musique at system boot, trial number 4 ,retrying in 5 seconds Jan 18 18:17:17 volumio-rpi4 volumio[14093]: STREAMING PROXY: Starting server on port 3245 Jan 18 18:17:17 volumio-rpi4 volumio[14093]: Node JS runtime: 14 Jan 18 18:17:17 volumio-rpi4 volumio[14093]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jan 18 18:17:17 volumio-rpi4 systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Jan 18 18:17:17 volumio-rpi4 systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1254. Jan 18 18:17:17 volumio-rpi4 systemd[1]: Stopped FusionDsp Daemon. Jan 18 18:17:17 volumio-rpi4 systemd[1]: Started FusionDsp Daemon. Jan 18 18:17:17 volumio-rpi4 systemd[14475]: fusiondsp.service: Failed to execute command: No such file or directory Jan 18 18:17:17 volumio-rpi4 systemd[14475]: fusiondsp.service: Failed at step EXEC spawning /data/plugins/audio_interface/fusiondsp/startcgui.sh: No such file or directory Jan 18 18:17:17 volumio-rpi4 systemd[1]: fusiondsp.service: Main process exited, code=exited, status=203/EXEC Jan 18 18:17:17 volumio-rpi4 systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jan 18 18:17:19 volumio-rpi4 systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Jan 18 18:17:19 volumio-rpi4 systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1255. Jan 18 18:17:19 volumio-rpi4 systemd[1]: Stopped FusionDsp Daemon. Jan 18 18:17:19 volumio-rpi4 systemd[1]: Started FusionDsp Daemon. Jan 18 18:17:19 volumio-rpi4 systemd[14481]: fusiondsp.service: Failed to execute command: No such file or directory Jan 18 18:17:19 volumio-rpi4 systemd[14481]: fusiondsp.service: Failed at step EXEC spawning /data/plugins/audio_interface/fusiondsp/startcgui.sh: No such file or directory Jan 18 18:17:19 volumio-rpi4 systemd[1]: fusiondsp.service: Main process exited, code=exited, status=203/EXEC Jan 18 18:17:19 volumio-rpi4 systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jan 18 18:17:20 volumio-rpi4 volumio[14093]: info: MyVolumio token set successfully Jan 18 18:17:20 volumio-rpi4 volumio[14093]: info: MYVOLUMIO: Adding device Jan 18 18:17:20 volumio-rpi4 volumio[14093]: info: MYVOLUMIO: Evaluating Server Jan 18 18:17:21 volumio-rpi4 volumio[14093]: info: MyVolumio Plan changed: superstar Jan 18 18:17:21 volumio-rpi4 volumio[14093]: info: [MyVolumio PluginManager] Subscribed plan changed to superstar Jan 18 18:17:21 volumio-rpi4 volumio[14093]: info: Adding audio output: Jan 18 18:17:21 volumio-rpi4 volumio[14093]: info: MYVOLUMIO: Adding device Jan 18 18:17:21 volumio-rpi4 volumio[14093]: info: MYVOLUMIO: Evaluating Server Jan 18 18:17:21 volumio-rpi4 volumio[14093]: info: Remote config written successfully Jan 18 18:17:21 volumio-rpi4 volumio[14093]: info: Starting Tunnel 1 Jan 18 18:17:21 volumio-rpi4 volumio[14093]: info: Starting Tunnel Connection Checker Jan 18 18:17:21 volumio-rpi4 volumio[14093]: info: Completed starting MyVolumio Plugin Jan 18 18:17:21 volumio-rpi4 volumio[14093]: info: MYVolumio Device enabled Jan 18 18:17:21 volumio-rpi4 volumio[14093]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Jan 18 18:17:21 volumio-rpi4 volumio[14093]: info: MyVolumio status changed Jan 18 18:17:21 volumio-rpi4 volumio[14093]: info: Streaming services startup Jan 18 18:17:21 volumio-rpi4 volumio[14093]: info: Starting Streaming Daemon Jan 18 18:17:21 volumio-rpi4 sudo[14513]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jan 18 18:17:21 volumio-rpi4 volumio[14093]: info: Setting Geolocation for MyVolumio to eu9 Jan 18 18:17:21 volumio-rpi4 sudo[14513]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 18:17:21 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 18 18:17:21 volumio-rpi4 sudo[14513]: pam_unix(sudo:session): session closed for user root Jan 18 18:17:21 volumio-rpi4 volumio[14093]: error: Cannot start Volumio Streaming Daemon Jan 18 18:17:21 volumio-rpi4 volumio[14093]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jan 18 18:17:21 volumio-rpi4 volumio[14093]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jan 18 18:17:21 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Jan 18 18:17:21 volumio-rpi4 volumio[14093]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Jan 18 18:17:21 volumio-rpi4 volumio[14093]: info: [1737220641816] Starting BluetoothController Jan 18 18:17:21 volumio-rpi4 volumio[14093]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Jan 18 18:17:21 volumio-rpi4 systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Jan 18 18:17:21 volumio-rpi4 systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1256. Jan 18 18:17:21 volumio-rpi4 systemd[1]: Stopped FusionDsp Daemon. Jan 18 18:17:22 volumio-rpi4 systemd[1]: Started FusionDsp Daemon. Jan 18 18:17:22 volumio-rpi4 systemd[14530]: fusiondsp.service: Failed to execute command: No such file or directory Jan 18 18:17:22 volumio-rpi4 systemd[14530]: fusiondsp.service: Failed at step EXEC spawning /data/plugins/audio_interface/fusiondsp/startcgui.sh: No such file or directory Jan 18 18:17:22 volumio-rpi4 systemd[1]: fusiondsp.service: Main process exited, code=exited, status=203/EXEC Jan 18 18:17:22 volumio-rpi4 systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jan 18 18:17:22 volumio-rpi4 volumio[14093]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Jan 18 18:17:22 volumio-rpi4 sudo[14532]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Jan 18 18:17:22 volumio-rpi4 sudo[14532]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 18:17:22 volumio-rpi4 sudo[14532]: pam_unix(sudo:session): session closed for user root Jan 18 18:17:22 volumio-rpi4 volumio[14093]: /bin/mkdir: cannot create directory ‘/tmp/hls’: File exists Jan 18 18:17:22 volumio-rpi4 volumio[14093]: info: MRS: MultiRoom plugin initialized Jan 18 18:17:22 volumio-rpi4 volumio[14093]: info: MRS: STOPPING SNAPCLIENT Jan 18 18:17:22 volumio-rpi4 volumio[14093]: info: MRS: Snap server stop Jan 18 18:17:22 volumio-rpi4 sudo[14548]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Jan 18 18:17:22 volumio-rpi4 sudo[14548]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 18:17:22 volumio-rpi4 volumio[14093]: info: MRS: STOPPING volumioStreaming Jan 18 18:17:22 volumio-rpi4 sudo[14551]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Jan 18 18:17:22 volumio-rpi4 sudo[14551]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 18:17:22 volumio-rpi4 sudo[14548]: pam_unix(sudo:session): session closed for user root Jan 18 18:17:22 volumio-rpi4 sudo[14554]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Jan 18 18:17:22 volumio-rpi4 sudo[14554]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 18:17:22 volumio-rpi4 sudo[14551]: pam_unix(sudo:session): session closed for user root Jan 18 18:17:22 volumio-rpi4 volumio[14093]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Jan 18 18:17:22 volumio-rpi4 sudo[14557]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Jan 18 18:17:22 volumio-rpi4 sudo[14557]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 18:17:22 volumio-rpi4 sudo[14557]: pam_unix(sudo:session): session closed for user root Jan 18 18:17:22 volumio-rpi4 volumio[14093]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Jan 18 18:17:22 volumio-rpi4 sudo[14554]: pam_unix(sudo:session): session closed for user root Jan 18 18:17:22 volumio-rpi4 volumio[14093]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Jan 18 18:17:22 volumio-rpi4 volumio[14093]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Jan 18 18:17:22 volumio-rpi4 volumio[14093]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Jan 18 18:17:22 volumio-rpi4 volumio[14093]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Jan 18 18:17:22 volumio-rpi4 volumio[14093]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Jan 18 18:17:22 volumio-rpi4 volumio[14093]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Jan 18 18:17:22 volumio-rpi4 volumio[14093]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Jan 18 18:17:22 volumio-rpi4 volumio[14093]: info: Preparing to generate the ALSA configuration file Jan 18 18:17:22 volumio-rpi4 volumio[14093]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Jan 18 18:17:22 volumio-rpi4 volumio[14093]: info: Updating MyVolumio device info Jan 18 18:17:22 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 18 18:17:22 volumio-rpi4 volumio[14093]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jan 18 18:17:22 volumio-rpi4 volumio[14093]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jan 18 18:17:22 volumio-rpi4 volumio[14093]: info: Reading ALSA contributions from plugins. Jan 18 18:17:22 volumio-rpi4 volumio[14093]: info: MRS: Removed streaming files Jan 18 18:17:22 volumio-rpi4 volumio[14093]: info: MRS: volumioStreaming STOPPED Jan 18 18:17:22 volumio-rpi4 volumio[14093]: info: MRS: SNAPSERVER STOPPED Jan 18 18:17:22 volumio-rpi4 volumio[14093]: info: MRS: SNAPCLIENT STOPPED Jan 18 18:17:23 volumio-rpi4 volumio[14093]: info: Setting Geolocation for MyVolumio to eu8 Jan 18 18:17:23 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 18 18:17:23 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 18 18:17:23 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 18 18:17:23 volumio-rpi4 volumio[14093]: info: Asound.conf file unchanged, so no further update is needed Jan 18 18:17:23 volumio-rpi4 volumio[14093]: info: Output device has changed, restarting MPD Jan 18 18:17:23 volumio-rpi4 sudo[14560]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 18 18:17:23 volumio-rpi4 sudo[14560]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 18:17:23 volumio-rpi4 sudo[14560]: pam_unix(sudo:session): session closed for user root Jan 18 18:17:23 volumio-rpi4 volumio[14093]: info: Output device has changed, restarting Shairport Sync Jan 18 18:17:23 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 18 18:17:23 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 18 18:17:23 volumio-rpi4 sudo[14563]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 18 18:17:23 volumio-rpi4 sudo[14563]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 18:17:23 volumio-rpi4 systemd[1]: Stopping Music Player Daemon... Jan 18 18:17:23 volumio-rpi4 volumio[14093]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 18 18:17:23 volumio-rpi4 volumio[14093]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jan 18 18:17:23 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 18 18:17:23 volumio-rpi4 systemd[1]: mpd.service: Succeeded. Jan 18 18:17:23 volumio-rpi4 systemd[1]: Stopped Music Player Daemon. Jan 18 18:17:23 volumio-rpi4 volumio[14093]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Jan 18 18:17:23 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 18 18:17:23 volumio-rpi4 volumio[14093]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Jan 18 18:17:23 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Jan 18 18:17:23 volumio-rpi4 volumio[14093]: info: MRS: Pushing multiroomSync output for this device Jan 18 18:17:23 volumio-rpi4 volumio[14093]: info: MRS: Pushing multiroomSync output Jan 18 18:17:23 volumio-rpi4 sudo[14572]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name Volumio-rpi4 Jan 18 18:17:23 volumio-rpi4 sudo[14572]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 18:17:23 volumio-rpi4 volumio[14093]: info: Adding audio output: Jan 18 18:17:23 volumio-rpi4 systemd[1]: Starting Music Player Daemon... Jan 18 18:17:23 volumio-rpi4 sudo[14572]: pam_unix(sudo:session): session closed for user root Jan 18 18:17:23 volumio-rpi4 volumio[14093]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jan 18 18:17:23 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 18 18:17:23 volumio-rpi4 volumio[14093]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Jan 18 18:17:23 volumio-rpi4 volumio[14093]: info: Adding METAVOLUMIO REST API Endpoints Jan 18 18:17:23 volumio-rpi4 volumio[14093]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Jan 18 18:17:23 volumio-rpi4 volumio[14093]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Jan 18 18:17:23 volumio-rpi4 volumio[14093]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Jan 18 18:17:23 volumio-rpi4 volumio[14093]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Jan 18 18:17:23 volumio-rpi4 volumio[14093]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Jan 18 18:17:23 volumio-rpi4 volumio[14093]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Jan 18 18:17:23 volumio-rpi4 volumio[14093]: info: [MyVolumio PluginManager] Plugin cd_controller disabled by user. Not starting Jan 18 18:17:23 volumio-rpi4 volumio[14093]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Jan 18 18:17:23 volumio-rpi4 volumio[14093]: info: [MyVolumio PluginManager] Plugin smart_inputs disabled by user. Not starting Jan 18 18:17:23 volumio-rpi4 volumio[14093]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Jan 18 18:17:23 volumio-rpi4 volumio[14093]: error: Hi Res Audio Failed Login: Missing Login Data Jan 18 18:17:23 volumio-rpi4 volumio[14093]: info: Adding HIGHRESAUDIO REST API Endpoints Jan 18 18:17:23 volumio-rpi4 volumio[14093]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Jan 18 18:17:23 volumio-rpi4 volumio[14093]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Jan 18 18:17:23 volumio-rpi4 volumio[14093]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Jan 18 18:17:23 volumio-rpi4 volumio[14093]: info: Refreshing QOBUZ token Jan 18 18:17:23 volumio-rpi4 volumio[14093]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Jan 18 18:17:23 volumio-rpi4 sudo[14568]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 18 18:17:23 volumio-rpi4 sudo[14568]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 18:17:23 volumio-rpi4 sudo[14578]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jan 18 18:17:23 volumio-rpi4 sudo[14568]: pam_unix(sudo:session): session closed for user root Jan 18 18:17:23 volumio-rpi4 sudo[14578]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 18:17:23 volumio-rpi4 volumio[14093]: info: Stopping AccessToken refresher cron Jan 18 18:17:23 volumio-rpi4 systemd[1]: Stopping Volumio Tidal Connect Service... Jan 18 18:17:23 volumio-rpi4 systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Jan 18 18:17:23 volumio-rpi4 systemd[1]: vtcs.service: Succeeded. Jan 18 18:17:23 volumio-rpi4 systemd[1]: Stopped Volumio Tidal Connect Service. Jan 18 18:17:23 volumio-rpi4 sudo[14578]: pam_unix(sudo:session): session closed for user root Jan 18 18:17:23 volumio-rpi4 volumio[14093]: info: AccessToken refresher cron started Jan 18 18:17:23 volumio-rpi4 volumio[14093]: info: Adding TIDAL REST API Endpoints Jan 18 18:17:23 volumio-rpi4 volumio[14093]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Jan 18 18:17:23 volumio-rpi4 volumio[14093]: info: MRS: Adding multiroomSync output Jan 18 18:17:23 volumio-rpi4 volumio[14093]: info: Adding audio output: Jan 18 18:17:23 volumio-rpi4 volumio[14093]: info: MRS: Pushing multiroomSync output Jan 18 18:17:23 volumio-rpi4 volumio[14093]: info: MRS: Adding multiroomSync output Jan 18 18:17:23 volumio-rpi4 volumio[14093]: info: Adding audio output: Jan 18 18:17:23 volumio-rpi4 volumio[14093]: info: MRS: Pushing multiroomSync output Jan 18 18:17:23 volumio-rpi4 volumio[14093]: info: Bluetooth name changed to Volumio-rpi4 Jan 18 18:17:23 volumio-rpi4 volumio[14093]: info: MPD Permissions set Jan 18 18:17:23 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 18 18:17:23 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 18 18:17:23 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 18 18:17:23 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 18 18:17:23 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 18 18:17:23 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 18 18:17:23 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 18 18:17:23 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 18 18:17:23 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 18 18:17:23 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 18 18:17:23 volumio-rpi4 volumio[14093]: info: Starting Shairport Sync Jan 18 18:17:23 volumio-rpi4 sudo[14589]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 18 18:17:23 volumio-rpi4 sudo[14589]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 18:17:23 volumio-rpi4 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 18 18:17:23 volumio-rpi4 systemd[1]: shairport-sync.service: Succeeded. Jan 18 18:17:23 volumio-rpi4 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 18 18:17:23 volumio-rpi4 volumio[14093]: info: Successfully Added MyVolumio device Jan 18 18:17:23 volumio-rpi4 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 18 18:17:23 volumio-rpi4 sudo[14589]: pam_unix(sudo:session): session closed for user root Jan 18 18:17:23 volumio-rpi4 volumio[14093]: info: Shairport-Sync Started Jan 18 18:17:23 volumio-rpi4 volumio[14093]: info: Successfully Updated MyVolumio device Jan 18 18:17:23 volumio-rpi4 volumio[14093]: info: Successfully Added MyVolumio device Jan 18 18:17:23 volumio-rpi4 mpd[14581]: Jan 18 18:17 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 18 18:17:24 volumio-rpi4 volumio[14093]: info: Updating MyVolumio device info Jan 18 18:17:24 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 18 18:17:24 volumio-rpi4 systemd[1]: Started Music Player Daemon. Jan 18 18:17:24 volumio-rpi4 sudo[14563]: pam_unix(sudo:session): session closed for user root Jan 18 18:17:24 volumio-rpi4 volumio[14093]: error: updateQueue error: null Jan 18 18:17:24 volumio-rpi4 systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Jan 18 18:17:24 volumio-rpi4 systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1257. Jan 18 18:17:24 volumio-rpi4 systemd[1]: Stopped FusionDsp Daemon. Jan 18 18:17:24 volumio-rpi4 systemd[1]: Started FusionDsp Daemon. Jan 18 18:17:24 volumio-rpi4 systemd[14596]: fusiondsp.service: Failed to execute command: No such file or directory Jan 18 18:17:24 volumio-rpi4 systemd[14596]: fusiondsp.service: Failed at step EXEC spawning /data/plugins/audio_interface/fusiondsp/startcgui.sh: No such file or directory Jan 18 18:17:24 volumio-rpi4 systemd[1]: fusiondsp.service: Main process exited, code=exited, status=203/EXEC Jan 18 18:17:24 volumio-rpi4 systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jan 18 18:17:24 volumio-rpi4 volumio[14093]: info: Access Token successfully retrieved Jan 18 18:17:24 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 18 18:17:24 volumio-rpi4 volumio[14093]: info: [1737220644349] CoreMusicLibrary::Adding element QOBUZ Jan 18 18:17:24 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 18 18:17:24 volumio-rpi4 volumio[14093]: Cannot find translation for source QOBUZ Jan 18 18:17:24 volumio-rpi4 volumio[14093]: info: Stopping AccessToken refresher cron for QOBUZ Jan 18 18:17:24 volumio-rpi4 volumio[14093]: info: AccessToken refresher cron started for QOBUZ Jan 18 18:17:24 volumio-rpi4 volumio[14093]: info: Adding QOBUZ REST API Endpoints Jan 18 18:17:24 volumio-rpi4 volumio[14093]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Jan 18 18:17:25 volumio-rpi4 volumio[14093]: info: Successfully Updated MyVolumio device Jan 18 18:17:26 volumio-rpi4 sudo[14598]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Jan 18 18:17:26 volumio-rpi4 sudo[14598]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 18:17:26 volumio-rpi4 sudo[14598]: pam_unix(sudo:session): session closed for user root Jan 18 18:17:26 volumio-rpi4 volumio[14093]: info: Volumio BT Module successfully started Jan 18 18:17:26 volumio-rpi4 volumio[14093]: info: TidalConnect service stoped! Jan 18 18:17:26 volumio-rpi4 sudo[14605]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Jan 18 18:17:26 volumio-rpi4 sudo[14605]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 18:17:26 volumio-rpi4 systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Jan 18 18:17:26 volumio-rpi4 systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1258. Jan 18 18:17:26 volumio-rpi4 systemd[1]: Stopped FusionDsp Daemon. Jan 18 18:17:26 volumio-rpi4 volumio[14093]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jan 18 18:17:26 volumio-rpi4 volumio[14093]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Jan 18 18:17:26 volumio-rpi4 sudo[14617]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jan 18 18:17:26 volumio-rpi4 sudo[14617]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 18:17:26 volumio-rpi4 systemd[1]: Started FusionDsp Daemon. Jan 18 18:17:26 volumio-rpi4 systemd[14611]: fusiondsp.service: Failed to execute command: No such file or directory Jan 18 18:17:26 volumio-rpi4 systemd[14611]: fusiondsp.service: Failed at step EXEC spawning /data/plugins/audio_interface/fusiondsp/startcgui.sh: No such file or directory Jan 18 18:17:26 volumio-rpi4 systemd[1]: fusiondsp.service: Main process exited, code=exited, status=203/EXEC Jan 18 18:17:26 volumio-rpi4 systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jan 18 18:17:26 volumio-rpi4 autossh[13637]: received signal to exit (15) Jan 18 18:17:26 volumio-rpi4 systemd[1]: Stopping MyVolumio SSH Tunnel... Jan 18 18:17:26 volumio-rpi4 systemd[1]: sshtunnel.service: Main process exited, code=killed, status=15/TERM Jan 18 18:17:26 volumio-rpi4 systemd[1]: sshtunnel.service: Succeeded. Jan 18 18:17:26 volumio-rpi4 systemd[1]: Stopped MyVolumio SSH Tunnel. Jan 18 18:17:26 volumio-rpi4 volumio[14093]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 18 18:17:26 volumio-rpi4 systemd[1]: Started Volumio Tidal Connect Service. Jan 18 18:17:26 volumio-rpi4 volumio[14093]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jan 18 18:17:26 volumio-rpi4 sudo[14617]: pam_unix(sudo:session): session closed for user root Jan 18 18:17:26 volumio-rpi4 systemd[1]: Started MyVolumio SSH Tunnel. Jan 18 18:17:26 volumio-rpi4 sudo[14605]: pam_unix(sudo:session): session closed for user root Jan 18 18:17:26 volumio-rpi4 volumio[14093]: info: Remote SSH Started Jan 18 18:17:26 volumio-rpi4 autossh[14623]: port set to 0, monitoring disabled Jan 18 18:17:26 volumio-rpi4 autossh[14623]: starting ssh (count 1) Jan 18 18:17:26 volumio-rpi4 autossh[14623]: ssh child pid is 14626 Jan 18 18:17:26 volumio-rpi4 volumio[14093]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 20 Jan 18 18:17:26 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::volumioGetState Jan 18 18:17:26 volumio-rpi4 volumio[14093]: info: CorePlayQueue::getTrack 0 Jan 18 18:17:26 volumio-rpi4 volumio[14093]: info: Executing endpoint tc_getconfig Jan 18 18:17:26 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Jan 18 18:17:26 volumio-rpi4 vtcs[14619]: STARTING TidalConnect services, version: 1.4.0.34 Jan 18 18:17:26 volumio-rpi4 vtcs[14619]: STARTED TidalConnect services. Jan 18 18:17:26 volumio-rpi4 volumio[14093]: info: Executing endpoint tc_connect Jan 18 18:17:26 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Jan 18 18:17:26 volumio-rpi4 volumio[14093]: info: Connecting to TidalConnect Jan 18 18:17:26 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::servicePushState Jan 18 18:17:26 volumio-rpi4 volumio[14093]: info: CoreStateMachine::pushState Jan 18 18:17:26 volumio-rpi4 volumio[14093]: info: CorePlayQueue::getTrack 0 Jan 18 18:17:26 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::volumioPushState Jan 18 18:17:26 volumio-rpi4 volumio[14093]: info: MRS: Pushing multiroomSync output update for this device Jan 18 18:17:26 volumio-rpi4 volumio[14093]: info: MRS: Pushing multiroomSync output Jan 18 18:17:26 volumio-rpi4 volumio[14093]: info: CorePlayQueue::getTrack 0 Jan 18 18:17:26 volumio-rpi4 volumio[14093]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Jan 18 18:17:26 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::servicePushState Jan 18 18:17:26 volumio-rpi4 volumio[14093]: info: CoreStateMachine::pushState Jan 18 18:17:26 volumio-rpi4 volumio[14093]: info: CorePlayQueue::getTrack 0 Jan 18 18:17:26 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::volumioPushState Jan 18 18:17:26 volumio-rpi4 volumio[14093]: info: MRS: Pushing multiroomSync output update for this device Jan 18 18:17:26 volumio-rpi4 volumio[14093]: info: MRS: Pushing multiroomSync output Jan 18 18:17:26 volumio-rpi4 volumio[14093]: info: CorePlayQueue::getTrack 0 Jan 18 18:17:26 volumio-rpi4 volumio[14093]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Jan 18 18:17:28 volumio-rpi4 volumio[14093]: info: MRS: Getting audio outputs on start Jan 18 18:17:28 volumio-rpi4 volumio[14093]: info: MRS: Requesting all other devices output Jan 18 18:17:28 volumio-rpi4 systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Jan 18 18:17:28 volumio-rpi4 systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1259. Jan 18 18:17:28 volumio-rpi4 systemd[1]: Stopped FusionDsp Daemon. Jan 18 18:17:28 volumio-rpi4 systemd[1]: Started FusionDsp Daemon. Jan 18 18:17:28 volumio-rpi4 systemd[14643]: fusiondsp.service: Failed to execute command: No such file or directory Jan 18 18:17:28 volumio-rpi4 systemd[14643]: fusiondsp.service: Failed at step EXEC spawning /data/plugins/audio_interface/fusiondsp/startcgui.sh: No such file or directory Jan 18 18:17:28 volumio-rpi4 systemd[1]: fusiondsp.service: Main process exited, code=exited, status=203/EXEC Jan 18 18:17:28 volumio-rpi4 systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jan 18 18:17:29 volumio-rpi4 volumio[14093]: info: TidalConnect service started! Jan 18 18:17:29 volumio-rpi4 volumio[14093]: [Metrics] CommandRouter: 28s 935.92ms Jan 18 18:17:29 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::volumiosetStartupVolume Jan 18 18:17:29 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 18 18:17:29 volumio-rpi4 volumio[14093]: info: VolumeController:: Setting startup Volume 25 Jan 18 18:17:29 volumio-rpi4 volumio[14093]: info: VolumeController::SetAlsaVolume25 Jan 18 18:17:29 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 18 18:17:29 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::Close All Modals sent Jan 18 18:17:29 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::Close All Modals sent Jan 18 18:17:29 volumio-rpi4 volumio[14093]: info: CoreStateMachine::pushState Jan 18 18:17:29 volumio-rpi4 volumio[14093]: info: CorePlayQueue::getTrack 0 Jan 18 18:17:29 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 18 18:17:29 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::volumioPushState Jan 18 18:17:29 volumio-rpi4 volumio[14093]: info: MRS: Pushing multiroomSync output update for this device Jan 18 18:17:29 volumio-rpi4 volumio[14093]: info: MRS: Pushing multiroomSync output Jan 18 18:17:29 volumio-rpi4 volumio[14093]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Jan 18 18:17:29 volumio-rpi4 volumio[14093]: info: FusionDsp - ---- read samplerate from file: 44100 Jan 18 18:17:29 volumio-rpi4 volumio[14093]: info: camilladsp stopping service pid 14358... Jan 18 18:17:29 volumio-rpi4 kernel: bcm2835_audio bcm2835_audio: failed to close VCHI service connection (status=-11) Jan 18 18:17:29 volumio-rpi4 volumio[14093]: info: camilladsp service terminated, instance 1 Jan 18 18:17:29 volumio-rpi4 volumio[14093]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jan 18 18:17:29 volumio-rpi4 volumio[14093]: info: camilladsp service started and running in background, instance 1 Jan 18 18:17:29 volumio-rpi4 volumio[14093]: info: camilladsp spawned new process with pid 14650, instance 1, run: true Jan 18 18:17:30 volumio-rpi4 volumiossh-tunnel[14620]: Warning: Permanently added '[eu8.myvolumio.org]:2222,[178.62.246.67]:2222' (RSA) to the list of known hosts. Jan 18 18:17:30 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jan 18 18:17:30 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 18 18:17:30 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jan 18 18:17:30 volumio-rpi4 systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Jan 18 18:17:30 volumio-rpi4 systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1260. Jan 18 18:17:30 volumio-rpi4 systemd[1]: Stopped FusionDsp Daemon. Jan 18 18:17:30 volumio-rpi4 systemd[1]: Started FusionDsp Daemon. Jan 18 18:17:30 volumio-rpi4 systemd[14662]: fusiondsp.service: Failed to execute command: No such file or directory Jan 18 18:17:30 volumio-rpi4 systemd[14662]: fusiondsp.service: Failed at step EXEC spawning /data/plugins/audio_interface/fusiondsp/startcgui.sh: No such file or directory Jan 18 18:17:30 volumio-rpi4 systemd[1]: fusiondsp.service: Main process exited, code=exited, status=203/EXEC Jan 18 18:17:30 volumio-rpi4 systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jan 18 18:17:30 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 18 18:17:30 volumio-rpi4 volumio[14093]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 21 Jan 18 18:17:30 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::volumioGetState Jan 18 18:17:30 volumio-rpi4 volumio[14093]: info: CorePlayQueue::getTrack 0 Jan 18 18:17:32 volumio-rpi4 systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Jan 18 18:17:32 volumio-rpi4 systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1261. Jan 18 18:17:32 volumio-rpi4 systemd[1]: Stopped FusionDsp Daemon. Jan 18 18:17:33 volumio-rpi4 systemd[1]: Started FusionDsp Daemon. Jan 18 18:17:33 volumio-rpi4 systemd[14677]: fusiondsp.service: Failed to execute command: No such file or directory Jan 18 18:17:33 volumio-rpi4 systemd[14677]: fusiondsp.service: Failed at step EXEC spawning /data/plugins/audio_interface/fusiondsp/startcgui.sh: No such file or directory Jan 18 18:17:33 volumio-rpi4 systemd[1]: fusiondsp.service: Main process exited, code=exited, status=203/EXEC Jan 18 18:17:33 volumio-rpi4 systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jan 18 18:17:34 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 18 18:17:34 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 18 18:17:34 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 18 18:17:34 volumio-rpi4 kernel: bcm2835_audio bcm2835_audio: failed to close VCHI service connection (status=-11) Jan 18 18:17:34 volumio-rpi4 volumio[14093]: info: camilladsp spawned new process with pid 14680, instance 1, run: true Jan 18 18:17:35 volumio-rpi4 volumio-remote-updater[589]: Test mode enabled Jan 18 18:17:35 volumio-rpi4 volumio-remote-updater[589]: No alpha test mode Jan 18 18:17:35 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jan 18 18:17:35 volumio-rpi4 volumio[14093]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jan 18 18:17:35 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jan 18 18:17:35 volumio-rpi4 systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Jan 18 18:17:35 volumio-rpi4 systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1262. Jan 18 18:17:35 volumio-rpi4 systemd[1]: Stopped FusionDsp Daemon. Jan 18 18:17:35 volumio-rpi4 systemd[1]: Started FusionDsp Daemon. Jan 18 18:17:35 volumio-rpi4 systemd[14688]: fusiondsp.service: Failed to execute command: No such file or directory Jan 18 18:17:35 volumio-rpi4 systemd[14688]: fusiondsp.service: Failed at step EXEC spawning /data/plugins/audio_interface/fusiondsp/startcgui.sh: No such file or directory Jan 18 18:17:35 volumio-rpi4 systemd[1]: fusiondsp.service: Main process exited, code=exited, status=203/EXEC Jan 18 18:17:35 volumio-rpi4 systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jan 18 18:17:35 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 18 18:17:35 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 18 18:17:35 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jan 18 18:17:35 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jan 18 18:17:35 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jan 18 18:17:35 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::volumioGetBrowseSources Jan 18 18:17:35 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 18 18:17:36 volumio-rpi4 volumio[14093]: info: BOOT COMPLETED Jan 18 18:17:37 volumio-rpi4 systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Jan 18 18:17:37 volumio-rpi4 systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1263. Jan 18 18:17:37 volumio-rpi4 systemd[1]: Stopped FusionDsp Daemon. Jan 18 18:17:37 volumio-rpi4 systemd[1]: Started FusionDsp Daemon. Jan 18 18:17:37 volumio-rpi4 systemd[14689]: fusiondsp.service: Failed to execute command: No such file or directory Jan 18 18:17:37 volumio-rpi4 systemd[14689]: fusiondsp.service: Failed at step EXEC spawning /data/plugins/audio_interface/fusiondsp/startcgui.sh: No such file or directory Jan 18 18:17:37 volumio-rpi4 systemd[1]: fusiondsp.service: Main process exited, code=exited, status=203/EXEC Jan 18 18:17:37 volumio-rpi4 systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jan 18 18:17:39 volumio-rpi4 systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Jan 18 18:17:39 volumio-rpi4 systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1264. Jan 18 18:17:39 volumio-rpi4 systemd[1]: Stopped FusionDsp Daemon. Jan 18 18:17:39 volumio-rpi4 systemd[1]: Started FusionDsp Daemon. Jan 18 18:17:39 volumio-rpi4 systemd[14690]: fusiondsp.service: Failed to execute command: No such file or directory Jan 18 18:17:39 volumio-rpi4 systemd[14690]: fusiondsp.service: Failed at step EXEC spawning /data/plugins/audio_interface/fusiondsp/startcgui.sh: No such file or directory Jan 18 18:17:39 volumio-rpi4 systemd[1]: fusiondsp.service: Main process exited, code=exited, status=203/EXEC Jan 18 18:17:39 volumio-rpi4 systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jan 18 18:17:40 volumio-rpi4 volumio[14093]: info: VolumeController::SetAlsaVolume38 Jan 18 18:17:40 volumio-rpi4 volumio[14093]: info: CoreStateMachine::pushState Jan 18 18:17:40 volumio-rpi4 volumio[14093]: info: CorePlayQueue::getTrack 0 Jan 18 18:17:40 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 18 18:17:40 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::volumioPushState Jan 18 18:17:40 volumio-rpi4 volumio[14093]: info: MRS: Pushing multiroomSync output update for this device Jan 18 18:17:40 volumio-rpi4 volumio[14093]: info: MRS: Pushing multiroomSync output Jan 18 18:17:40 volumio-rpi4 volumio[14093]: info: Jan 18 18:17:40 volumio-rpi4 volumio[14093]: ---------------------------- MPD announces state update: mixer Jan 18 18:17:40 volumio-rpi4 volumio[14093]: info: ControllerMpd::getState Jan 18 18:17:40 volumio-rpi4 volumio[14093]: verbose: ControllerMpd::sendMpdCommand status Jan 18 18:17:40 volumio-rpi4 volumio[14093]: info: sendMpdCommand status took 5 milliseconds Jan 18 18:17:40 volumio-rpi4 volumio[14093]: verbose: ControllerMpd::parseState Jan 18 18:17:40 volumio-rpi4 volumio[14093]: info: ControllerMpd::pushState Jan 18 18:17:40 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::servicePushState Jan 18 18:17:40 volumio-rpi4 volumio[14093]: info: CoreStateMachine::pushState Jan 18 18:17:40 volumio-rpi4 volumio[14093]: info: CorePlayQueue::getTrack 0 Jan 18 18:17:40 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::volumioPushState Jan 18 18:17:40 volumio-rpi4 volumio[14093]: info: MRS: Pushing multiroomSync output update for this device Jan 18 18:17:40 volumio-rpi4 volumio[14093]: info: MRS: Pushing multiroomSync output Jan 18 18:17:40 volumio-rpi4 volumio[14093]: info: CorePlayQueue::getTrack 0 Jan 18 18:17:40 volumio-rpi4 volumio[14093]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 18 18:17:40 volumio-rpi4 volumio[14093]: verbose: CURRENT POSITION 0 Jan 18 18:17:40 volumio-rpi4 volumio[14093]: info: CoreStateMachine::syncState stateService stop Jan 18 18:17:40 volumio-rpi4 volumio[14093]: info: CoreStateMachine::syncState currentStatus stop Jan 18 18:17:40 volumio-rpi4 volumio[14093]: info: CoreStateMachine::pushState Jan 18 18:17:40 volumio-rpi4 volumio[14093]: info: CorePlayQueue::getTrack 0 Jan 18 18:17:40 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::volumioPushState Jan 18 18:17:40 volumio-rpi4 volumio[14093]: info: MRS: Pushing multiroomSync output update for this device Jan 18 18:17:40 volumio-rpi4 volumio[14093]: info: MRS: Pushing multiroomSync output Jan 18 18:17:40 volumio-rpi4 volumio[14093]: info: No code Jan 18 18:17:40 volumio-rpi4 volumio[14093]: info: CoreStateMachine::pushState Jan 18 18:17:40 volumio-rpi4 volumio[14093]: info: CorePlayQueue::getTrack 0 Jan 18 18:17:40 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::volumioPushState Jan 18 18:17:40 volumio-rpi4 volumio[14093]: info: MRS: Pushing multiroomSync output update for this device Jan 18 18:17:40 volumio-rpi4 volumio[14093]: info: MRS: Pushing multiroomSync output Jan 18 18:17:40 volumio-rpi4 volumio[14093]: info: ------------------------------ 92ms Jan 18 18:17:41 volumio-rpi4 systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Jan 18 18:17:41 volumio-rpi4 systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1265. Jan 18 18:17:41 volumio-rpi4 systemd[1]: Stopped FusionDsp Daemon. Jan 18 18:17:42 volumio-rpi4 systemd[1]: Started FusionDsp Daemon. Jan 18 18:17:42 volumio-rpi4 systemd[14710]: fusiondsp.service: Failed to execute command: No such file or directory Jan 18 18:17:42 volumio-rpi4 systemd[14710]: fusiondsp.service: Failed at step EXEC spawning /data/plugins/audio_interface/fusiondsp/startcgui.sh: No such file or directory Jan 18 18:17:42 volumio-rpi4 systemd[1]: fusiondsp.service: Main process exited, code=exited, status=203/EXEC Jan 18 18:17:42 volumio-rpi4 systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jan 18 18:17:43 volumio-rpi4 volumio[14093]: info: VolumeController::SetAlsaVolume40 Jan 18 18:17:43 volumio-rpi4 volumio[14093]: info: CoreStateMachine::pushState Jan 18 18:17:43 volumio-rpi4 volumio[14093]: info: CorePlayQueue::getTrack 0 Jan 18 18:17:43 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 18 18:17:43 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::volumioPushState Jan 18 18:17:43 volumio-rpi4 volumio[14093]: info: MRS: Pushing multiroomSync output update for this device Jan 18 18:17:43 volumio-rpi4 volumio[14093]: info: MRS: Pushing multiroomSync output Jan 18 18:17:43 volumio-rpi4 volumio[14093]: info: Jan 18 18:17:43 volumio-rpi4 volumio[14093]: ---------------------------- MPD announces state update: mixer Jan 18 18:17:43 volumio-rpi4 volumio[14093]: info: ControllerMpd::getState Jan 18 18:17:43 volumio-rpi4 volumio[14093]: verbose: ControllerMpd::sendMpdCommand status Jan 18 18:17:43 volumio-rpi4 volumio[14093]: info: sendMpdCommand status took 3 milliseconds Jan 18 18:17:43 volumio-rpi4 volumio[14093]: verbose: ControllerMpd::parseState Jan 18 18:17:43 volumio-rpi4 volumio[14093]: info: ControllerMpd::pushState Jan 18 18:17:43 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::servicePushState Jan 18 18:17:43 volumio-rpi4 volumio[14093]: info: CoreStateMachine::pushState Jan 18 18:17:43 volumio-rpi4 volumio[14093]: info: CorePlayQueue::getTrack 0 Jan 18 18:17:43 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::volumioPushState Jan 18 18:17:43 volumio-rpi4 volumio[14093]: info: MRS: Pushing multiroomSync output update for this device Jan 18 18:17:43 volumio-rpi4 volumio[14093]: info: MRS: Pushing multiroomSync output Jan 18 18:17:43 volumio-rpi4 volumio[14093]: info: CorePlayQueue::getTrack 0 Jan 18 18:17:43 volumio-rpi4 volumio[14093]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 18 18:17:43 volumio-rpi4 volumio[14093]: verbose: CURRENT POSITION 0 Jan 18 18:17:43 volumio-rpi4 volumio[14093]: info: CoreStateMachine::syncState stateService stop Jan 18 18:17:43 volumio-rpi4 volumio[14093]: info: CoreStateMachine::syncState currentStatus stop Jan 18 18:17:43 volumio-rpi4 volumio[14093]: info: CoreStateMachine::pushState Jan 18 18:17:43 volumio-rpi4 volumio[14093]: info: CorePlayQueue::getTrack 0 Jan 18 18:17:43 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::volumioPushState Jan 18 18:17:43 volumio-rpi4 volumio[14093]: info: MRS: Pushing multiroomSync output update for this device Jan 18 18:17:43 volumio-rpi4 volumio[14093]: info: MRS: Pushing multiroomSync output Jan 18 18:17:43 volumio-rpi4 volumio[14093]: info: No code Jan 18 18:17:43 volumio-rpi4 volumio[14093]: info: CoreStateMachine::pushState Jan 18 18:17:43 volumio-rpi4 volumio[14093]: info: CorePlayQueue::getTrack 0 Jan 18 18:17:43 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::volumioPushState Jan 18 18:17:43 volumio-rpi4 volumio[14093]: info: MRS: Pushing multiroomSync output update for this device Jan 18 18:17:43 volumio-rpi4 volumio[14093]: info: MRS: Pushing multiroomSync output Jan 18 18:17:43 volumio-rpi4 volumio[14093]: info: ------------------------------ 79ms Jan 18 18:17:43 volumio-rpi4 volumio[14093]: info: VolumeController::SetAlsaVolume46 Jan 18 18:17:43 volumio-rpi4 volumio[14093]: info: CoreStateMachine::pushState Jan 18 18:17:43 volumio-rpi4 volumio[14093]: info: CorePlayQueue::getTrack 0 Jan 18 18:17:43 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 18 18:17:43 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::volumioPushState Jan 18 18:17:43 volumio-rpi4 volumio[14093]: info: MRS: Pushing multiroomSync output update for this device Jan 18 18:17:43 volumio-rpi4 volumio[14093]: info: MRS: Pushing multiroomSync output Jan 18 18:17:43 volumio-rpi4 volumio[14093]: info: Jan 18 18:17:43 volumio-rpi4 volumio[14093]: ---------------------------- MPD announces state update: mixer Jan 18 18:17:43 volumio-rpi4 volumio[14093]: info: ControllerMpd::getState Jan 18 18:17:43 volumio-rpi4 volumio[14093]: verbose: ControllerMpd::sendMpdCommand status Jan 18 18:17:43 volumio-rpi4 volumio[14093]: info: sendMpdCommand status took 6 milliseconds Jan 18 18:17:43 volumio-rpi4 volumio[14093]: verbose: ControllerMpd::parseState Jan 18 18:17:43 volumio-rpi4 volumio[14093]: info: ControllerMpd::pushState Jan 18 18:17:43 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::servicePushState Jan 18 18:17:43 volumio-rpi4 volumio[14093]: info: CoreStateMachine::pushState Jan 18 18:17:43 volumio-rpi4 volumio[14093]: info: CorePlayQueue::getTrack 0 Jan 18 18:17:43 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::volumioPushState Jan 18 18:17:43 volumio-rpi4 volumio[14093]: info: MRS: Pushing multiroomSync output update for this device Jan 18 18:17:43 volumio-rpi4 volumio[14093]: info: MRS: Pushing multiroomSync output Jan 18 18:17:43 volumio-rpi4 volumio[14093]: info: CorePlayQueue::getTrack 0 Jan 18 18:17:43 volumio-rpi4 volumio[14093]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 18 18:17:43 volumio-rpi4 volumio[14093]: verbose: CURRENT POSITION 0 Jan 18 18:17:43 volumio-rpi4 volumio[14093]: info: CoreStateMachine::syncState stateService stop Jan 18 18:17:43 volumio-rpi4 volumio[14093]: info: CoreStateMachine::syncState currentStatus stop Jan 18 18:17:43 volumio-rpi4 volumio[14093]: info: CoreStateMachine::pushState Jan 18 18:17:43 volumio-rpi4 volumio[14093]: info: CorePlayQueue::getTrack 0 Jan 18 18:17:43 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::volumioPushState Jan 18 18:17:43 volumio-rpi4 volumio[14093]: info: MRS: Pushing multiroomSync output update for this device Jan 18 18:17:43 volumio-rpi4 volumio[14093]: info: MRS: Pushing multiroomSync output Jan 18 18:17:43 volumio-rpi4 volumio[14093]: info: No code Jan 18 18:17:43 volumio-rpi4 volumio[14093]: info: CoreStateMachine::pushState Jan 18 18:17:43 volumio-rpi4 volumio[14093]: info: CorePlayQueue::getTrack 0 Jan 18 18:17:43 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::volumioPushState Jan 18 18:17:43 volumio-rpi4 volumio[14093]: info: MRS: Pushing multiroomSync output update for this device Jan 18 18:17:43 volumio-rpi4 volumio[14093]: info: MRS: Pushing multiroomSync output Jan 18 18:17:43 volumio-rpi4 volumio[14093]: info: ------------------------------ 75ms Jan 18 18:17:44 volumio-rpi4 systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Jan 18 18:17:44 volumio-rpi4 systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1266. Jan 18 18:17:44 volumio-rpi4 systemd[1]: Stopped FusionDsp Daemon. Jan 18 18:17:44 volumio-rpi4 systemd[1]: Started FusionDsp Daemon. Jan 18 18:17:44 volumio-rpi4 systemd[14713]: fusiondsp.service: Failed to execute command: No such file or directory Jan 18 18:17:44 volumio-rpi4 systemd[14713]: fusiondsp.service: Failed at step EXEC spawning /data/plugins/audio_interface/fusiondsp/startcgui.sh: No such file or directory Jan 18 18:17:44 volumio-rpi4 systemd[1]: fusiondsp.service: Main process exited, code=exited, status=203/EXEC Jan 18 18:17:44 volumio-rpi4 systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jan 18 18:17:45 volumio-rpi4 volumio[14093]: info: VolumeController::SetAlsaVolume23 Jan 18 18:17:45 volumio-rpi4 volumio[14093]: info: CoreStateMachine::pushState Jan 18 18:17:45 volumio-rpi4 volumio[14093]: info: CorePlayQueue::getTrack 0 Jan 18 18:17:45 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 18 18:17:45 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::volumioPushState Jan 18 18:17:45 volumio-rpi4 volumio[14093]: info: MRS: Pushing multiroomSync output update for this device Jan 18 18:17:45 volumio-rpi4 volumio[14093]: info: MRS: Pushing multiroomSync output Jan 18 18:17:45 volumio-rpi4 volumio[14093]: info: Jan 18 18:17:45 volumio-rpi4 volumio[14093]: ---------------------------- MPD announces state update: mixer Jan 18 18:17:45 volumio-rpi4 volumio[14093]: info: ControllerMpd::getState Jan 18 18:17:45 volumio-rpi4 volumio[14093]: verbose: ControllerMpd::sendMpdCommand status Jan 18 18:17:45 volumio-rpi4 volumio[14093]: info: sendMpdCommand status took 10 milliseconds Jan 18 18:17:45 volumio-rpi4 volumio[14093]: verbose: ControllerMpd::parseState Jan 18 18:17:45 volumio-rpi4 volumio[14093]: info: ControllerMpd::pushState Jan 18 18:17:45 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::servicePushState Jan 18 18:17:45 volumio-rpi4 volumio[14093]: info: CoreStateMachine::pushState Jan 18 18:17:45 volumio-rpi4 volumio[14093]: info: CorePlayQueue::getTrack 0 Jan 18 18:17:45 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::volumioPushState Jan 18 18:17:45 volumio-rpi4 volumio[14093]: info: MRS: Pushing multiroomSync output update for this device Jan 18 18:17:45 volumio-rpi4 volumio[14093]: info: MRS: Pushing multiroomSync output Jan 18 18:17:45 volumio-rpi4 volumio[14093]: info: CorePlayQueue::getTrack 0 Jan 18 18:17:45 volumio-rpi4 volumio[14093]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 18 18:17:45 volumio-rpi4 volumio[14093]: verbose: CURRENT POSITION 0 Jan 18 18:17:45 volumio-rpi4 volumio[14093]: info: CoreStateMachine::syncState stateService stop Jan 18 18:17:45 volumio-rpi4 volumio[14093]: info: CoreStateMachine::syncState currentStatus stop Jan 18 18:17:45 volumio-rpi4 volumio[14093]: info: CoreStateMachine::pushState Jan 18 18:17:45 volumio-rpi4 volumio[14093]: info: CorePlayQueue::getTrack 0 Jan 18 18:17:45 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::volumioPushState Jan 18 18:17:45 volumio-rpi4 volumio[14093]: info: MRS: Pushing multiroomSync output update for this device Jan 18 18:17:45 volumio-rpi4 volumio[14093]: info: MRS: Pushing multiroomSync output Jan 18 18:17:45 volumio-rpi4 volumio[14093]: info: No code Jan 18 18:17:45 volumio-rpi4 volumio[14093]: info: CoreStateMachine::pushState Jan 18 18:17:45 volumio-rpi4 volumio[14093]: info: CorePlayQueue::getTrack 0 Jan 18 18:17:45 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::volumioPushState Jan 18 18:17:45 volumio-rpi4 volumio[14093]: info: MRS: Pushing multiroomSync output update for this device Jan 18 18:17:45 volumio-rpi4 volumio[14093]: info: MRS: Pushing multiroomSync output Jan 18 18:17:45 volumio-rpi4 volumio[14093]: info: ------------------------------ 77ms Jan 18 18:17:46 volumio-rpi4 systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Jan 18 18:17:46 volumio-rpi4 systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1267. Jan 18 18:17:46 volumio-rpi4 systemd[1]: Stopped FusionDsp Daemon. Jan 18 18:17:46 volumio-rpi4 systemd[1]: Started FusionDsp Daemon. Jan 18 18:17:46 volumio-rpi4 systemd[14716]: fusiondsp.service: Failed to execute command: No such file or directory Jan 18 18:17:46 volumio-rpi4 systemd[14716]: fusiondsp.service: Failed at step EXEC spawning /data/plugins/audio_interface/fusiondsp/startcgui.sh: No such file or directory Jan 18 18:17:46 volumio-rpi4 systemd[1]: fusiondsp.service: Main process exited, code=exited, status=203/EXEC Jan 18 18:17:46 volumio-rpi4 systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jan 18 18:17:48 volumio-rpi4 systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Jan 18 18:17:48 volumio-rpi4 systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1268. Jan 18 18:17:48 volumio-rpi4 systemd[1]: Stopped FusionDsp Daemon. Jan 18 18:17:48 volumio-rpi4 systemd[1]: Started FusionDsp Daemon. Jan 18 18:17:48 volumio-rpi4 systemd[14717]: fusiondsp.service: Failed to execute command: No such file or directory Jan 18 18:17:48 volumio-rpi4 systemd[14717]: fusiondsp.service: Failed at step EXEC spawning /data/plugins/audio_interface/fusiondsp/startcgui.sh: No such file or directory Jan 18 18:17:48 volumio-rpi4 systemd[1]: fusiondsp.service: Main process exited, code=exited, status=203/EXEC Jan 18 18:17:48 volumio-rpi4 systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jan 18 18:17:50 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::volumioGetState Jan 18 18:17:50 volumio-rpi4 volumio[14093]: info: CorePlayQueue::getTrack 0 Jan 18 18:17:50 volumio-rpi4 systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Jan 18 18:17:50 volumio-rpi4 systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1269. Jan 18 18:17:50 volumio-rpi4 systemd[1]: Stopped FusionDsp Daemon. Jan 18 18:17:51 volumio-rpi4 systemd[1]: Started FusionDsp Daemon. Jan 18 18:17:51 volumio-rpi4 systemd[14721]: fusiondsp.service: Failed to execute command: No such file or directory Jan 18 18:17:51 volumio-rpi4 systemd[14721]: fusiondsp.service: Failed at step EXEC spawning /data/plugins/audio_interface/fusiondsp/startcgui.sh: No such file or directory Jan 18 18:17:51 volumio-rpi4 systemd[1]: fusiondsp.service: Main process exited, code=exited, status=203/EXEC Jan 18 18:17:51 volumio-rpi4 systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jan 18 18:17:52 volumio-rpi4 volumio[14093]: info: Executing endpoint metavolumio Jan 18 18:17:52 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 18 18:17:52 volumio-rpi4 volumio[14093]: info: Executing endpoint metavolumio Jan 18 18:17:52 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 18 18:17:52 volumio-rpi4 volumio[14093]: info: Executing endpoint metavolumio Jan 18 18:17:52 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 18 18:17:53 volumio-rpi4 systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Jan 18 18:17:53 volumio-rpi4 systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1270. Jan 18 18:17:53 volumio-rpi4 systemd[1]: Stopped FusionDsp Daemon. Jan 18 18:17:53 volumio-rpi4 systemd[1]: Started FusionDsp Daemon. Jan 18 18:17:53 volumio-rpi4 systemd[14736]: fusiondsp.service: Failed to execute command: No such file or directory Jan 18 18:17:53 volumio-rpi4 systemd[14736]: fusiondsp.service: Failed at step EXEC spawning /data/plugins/audio_interface/fusiondsp/startcgui.sh: No such file or directory Jan 18 18:17:53 volumio-rpi4 systemd[1]: fusiondsp.service: Main process exited, code=exited, status=203/EXEC Jan 18 18:17:53 volumio-rpi4 systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jan 18 18:17:54 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 18 18:17:54 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jan 18 18:17:54 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 18 18:17:54 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 18 18:17:55 volumio-rpi4 systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Jan 18 18:17:55 volumio-rpi4 systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1271. Jan 18 18:17:55 volumio-rpi4 systemd[1]: Stopped FusionDsp Daemon. Jan 18 18:17:55 volumio-rpi4 systemd[1]: Started FusionDsp Daemon. Jan 18 18:17:55 volumio-rpi4 systemd[14737]: fusiondsp.service: Failed to execute command: No such file or directory Jan 18 18:17:55 volumio-rpi4 systemd[14737]: fusiondsp.service: Failed at step EXEC spawning /data/plugins/audio_interface/fusiondsp/startcgui.sh: No such file or directory Jan 18 18:17:55 volumio-rpi4 systemd[1]: fusiondsp.service: Main process exited, code=exited, status=203/EXEC Jan 18 18:17:55 volumio-rpi4 systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jan 18 18:17:57 volumio-rpi4 systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Jan 18 18:17:57 volumio-rpi4 systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1272. Jan 18 18:17:57 volumio-rpi4 systemd[1]: Stopped FusionDsp Daemon. Jan 18 18:17:57 volumio-rpi4 systemd[1]: Started FusionDsp Daemon. Jan 18 18:17:57 volumio-rpi4 systemd[14738]: fusiondsp.service: Failed to execute command: No such file or directory Jan 18 18:17:57 volumio-rpi4 systemd[14738]: fusiondsp.service: Failed at step EXEC spawning /data/plugins/audio_interface/fusiondsp/startcgui.sh: No such file or directory Jan 18 18:17:57 volumio-rpi4 systemd[1]: fusiondsp.service: Main process exited, code=exited, status=203/EXEC Jan 18 18:17:57 volumio-rpi4 systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jan 18 18:17:58 volumio-rpi4 volumio[14093]: info: FusionDsp - Stopping FusionDsp service Jan 18 18:17:58 volumio-rpi4 volumio[14093]: info: camilladsp stopping service pid 14680... Jan 18 18:17:58 volumio-rpi4 kernel: bcm2835_audio bcm2835_audio: failed to close VCHI service connection (status=-11) Jan 18 18:17:58 volumio-rpi4 volumio[14093]: info: camilladsp service terminated, instance 1 Jan 18 18:17:58 volumio-rpi4 volumio[14093]: info: Disabling plugin fusiondsp Jan 18 18:17:58 volumio-rpi4 volumio[14093]: info: Preparing to generate the ALSA configuration file Jan 18 18:17:58 volumio-rpi4 sudo[14742]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop fusiondsp.service Jan 18 18:17:58 volumio-rpi4 sudo[14742]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 18:17:58 volumio-rpi4 volumio[14093]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jan 18 18:17:58 volumio-rpi4 volumio[14093]: info: Reading ALSA contributions from plugins. Jan 18 18:17:58 volumio-rpi4 systemd[1]: Stopped FusionDsp Daemon. Jan 18 18:17:58 volumio-rpi4 volumio[14093]: info: Asound.conf file written Jan 18 18:17:58 volumio-rpi4 sudo[14742]: pam_unix(sudo:session): session closed for user root Jan 18 18:17:58 volumio-rpi4 sudo[14745]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jan 18 18:17:58 volumio-rpi4 sudo[14745]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 18:17:58 volumio-rpi4 sudo[14745]: pam_unix(sudo:session): session closed for user root Jan 18 18:17:58 volumio-rpi4 volumio[14093]: info: Output device has changed, restarting MPD Jan 18 18:17:58 volumio-rpi4 sudo[14750]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 18 18:17:58 volumio-rpi4 sudo[14750]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 18:17:58 volumio-rpi4 sudo[14750]: pam_unix(sudo:session): session closed for user root Jan 18 18:17:58 volumio-rpi4 volumio[14093]: info: Output device has changed, restarting Shairport Sync Jan 18 18:17:58 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 18 18:17:58 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 18 18:17:58 volumio-rpi4 sudo[14753]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 18 18:17:58 volumio-rpi4 sudo[14753]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 18:17:58 volumio-rpi4 systemd[1]: Stopping Music Player Daemon... Jan 18 18:17:58 volumio-rpi4 volumio[14093]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 18 18:17:58 volumio-rpi4 volumio[14093]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jan 18 18:17:58 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 18 18:17:58 volumio-rpi4 systemd[1]: mpd.service: Succeeded. Jan 18 18:17:58 volumio-rpi4 systemd[1]: Stopped Music Player Daemon. Jan 18 18:17:58 volumio-rpi4 vtcs[14619]: [2025-01-18 18:17:58.406] [tisoc] [warning] [SessionManagerImpl.cpp:243] Illegal State: IDLE Jan 18 18:17:58 volumio-rpi4 vtcs[14619]: [2025-01-18 18:17:58.407] [tisoc] [error] [SpkconServer.cpp:381] recv error. client fd=8 errorno=104 error=Connection reset by peer Jan 18 18:17:58 volumio-rpi4 vtcs[14619]: [2025-01-18 18:17:58.407] [tisoc] [error] [SpkconServer.cpp:376] recv error. socket disconnected Jan 18 18:17:58 volumio-rpi4 systemd[1]: Starting Music Player Daemon... Jan 18 18:17:58 volumio-rpi4 sudo[14762]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jan 18 18:17:58 volumio-rpi4 volumio[14093]: info: Done. Jan 18 18:17:58 volumio-rpi4 sudo[14762]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 18:17:58 volumio-rpi4 systemd[1]: Stopping Volumio Tidal Connect Service... Jan 18 18:17:58 volumio-rpi4 systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Jan 18 18:17:58 volumio-rpi4 volumio[14093]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 18 18:17:58 volumio-rpi4 volumio[14093]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jan 18 18:17:58 volumio-rpi4 volumio[14093]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 18 18:17:58 volumio-rpi4 systemd[1]: vtcs.service: Succeeded. Jan 18 18:17:58 volumio-rpi4 volumio[14093]: info: MPD Permissions set Jan 18 18:17:58 volumio-rpi4 volumio[14093]: info: FusionDsp - Reporting Fusion DSP Disabled Jan 18 18:17:58 volumio-rpi4 systemd[1]: Stopped Volumio Tidal Connect Service. Jan 18 18:17:58 volumio-rpi4 sudo[14765]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jan 18 18:17:58 volumio-rpi4 volumio[14093]: info: Removing fusiondspeq DSP Signal Path Element Jan 18 18:17:58 volumio-rpi4 sudo[14765]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 18:17:58 volumio-rpi4 volumio[14093]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 18 18:17:58 volumio-rpi4 sudo[14762]: pam_unix(sudo:session): session closed for user root Jan 18 18:17:58 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 18 18:17:58 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 18 18:17:58 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 18 18:17:58 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 18 18:17:58 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 18 18:17:58 volumio-rpi4 sudo[14765]: pam_unix(sudo:session): session closed for user root Jan 18 18:17:58 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 18 18:17:58 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 18 18:17:58 volumio-rpi4 sudo[14758]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 18 18:17:58 volumio-rpi4 sudo[14758]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 18:17:58 volumio-rpi4 volumio[14093]: info: Starting Shairport Sync Jan 18 18:17:58 volumio-rpi4 sudo[14758]: pam_unix(sudo:session): session closed for user root Jan 18 18:17:58 volumio-rpi4 sudo[14776]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 18 18:17:58 volumio-rpi4 sudo[14776]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 18:17:58 volumio-rpi4 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 18 18:17:58 volumio-rpi4 volumio[14093]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 18 18:17:58 volumio-rpi4 systemd[1]: shairport-sync.service: Succeeded. Jan 18 18:17:58 volumio-rpi4 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 18 18:17:58 volumio-rpi4 volumio[14093]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jan 18 18:17:58 volumio-rpi4 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 18 18:17:58 volumio-rpi4 sudo[14776]: pam_unix(sudo:session): session closed for user root Jan 18 18:17:58 volumio-rpi4 volumio[14093]: info: Shairport-Sync Started Jan 18 18:17:59 volumio-rpi4 mpd[14774]: Jan 18 18:17 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 18 18:17:59 volumio-rpi4 systemd[1]: Started Music Player Daemon. Jan 18 18:17:59 volumio-rpi4 sudo[14753]: pam_unix(sudo:session): session closed for user root Jan 18 18:17:59 volumio-rpi4 volumio[14093]: error: updateQueue error: null Jan 18 18:17:59 volumio-rpi4 volumio[14093]: error: Failed request for metavolumio API Jan 18 18:18:01 volumio-rpi4 volumio[14093]: info: TidalConnect service stoped! Jan 18 18:18:01 volumio-rpi4 volumio[14093]: info: TidalConnect service stoped! Jan 18 18:18:01 volumio-rpi4 volumio[14093]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jan 18 18:18:01 volumio-rpi4 volumio[14093]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jan 18 18:18:01 volumio-rpi4 sudo[14795]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jan 18 18:18:01 volumio-rpi4 sudo[14795]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 18:18:01 volumio-rpi4 systemd[1]: Started Volumio Tidal Connect Service. Jan 18 18:18:01 volumio-rpi4 sudo[14795]: pam_unix(sudo:session): session closed for user root Jan 18 18:18:01 volumio-rpi4 volumio[14093]: info: Executing endpoint tc_getconfig Jan 18 18:18:01 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Jan 18 18:18:01 volumio-rpi4 vtcs[14797]: STARTING TidalConnect services, version: 1.4.0.34 Jan 18 18:18:01 volumio-rpi4 vtcs[14797]: STARTED TidalConnect services. Jan 18 18:18:01 volumio-rpi4 volumio[14093]: info: Executing endpoint tc_connect Jan 18 18:18:01 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Jan 18 18:18:01 volumio-rpi4 volumio[14093]: info: Connecting to TidalConnect Jan 18 18:18:01 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::servicePushState Jan 18 18:18:01 volumio-rpi4 volumio[14093]: info: CoreStateMachine::pushState Jan 18 18:18:01 volumio-rpi4 volumio[14093]: info: CorePlayQueue::getTrack 0 Jan 18 18:18:01 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::volumioPushState Jan 18 18:18:01 volumio-rpi4 volumio[14093]: info: MRS: Pushing multiroomSync output update for this device Jan 18 18:18:01 volumio-rpi4 volumio[14093]: info: MRS: Pushing multiroomSync output Jan 18 18:18:01 volumio-rpi4 volumio[14093]: info: CorePlayQueue::getTrack 0 Jan 18 18:18:01 volumio-rpi4 volumio[14093]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Jan 18 18:18:01 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::servicePushState Jan 18 18:18:01 volumio-rpi4 volumio[14093]: info: CoreStateMachine::pushState Jan 18 18:18:01 volumio-rpi4 volumio[14093]: info: CorePlayQueue::getTrack 0 Jan 18 18:18:01 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::volumioPushState Jan 18 18:18:01 volumio-rpi4 volumio[14093]: info: MRS: Pushing multiroomSync output update for this device Jan 18 18:18:01 volumio-rpi4 volumio[14093]: info: MRS: Pushing multiroomSync output Jan 18 18:18:01 volumio-rpi4 volumio[14093]: info: CorePlayQueue::getTrack 0 Jan 18 18:18:01 volumio-rpi4 volumio[14093]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Jan 18 18:18:04 volumio-rpi4 volumio[14093]: info: Enabling plugin fusiondsp Jan 18 18:18:04 volumio-rpi4 volumio[14093]: info: Loading plugin "fusiondsp"... Jan 18 18:18:04 volumio-rpi4 volumio[14093]: info: Applying required configuration parameters for plugin fusiondsp Jan 18 18:18:04 volumio-rpi4 volumio[14093]: info: Preparing to generate the ALSA configuration file Jan 18 18:18:04 volumio-rpi4 volumio[14093]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jan 18 18:18:04 volumio-rpi4 volumio[14093]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jan 18 18:18:04 volumio-rpi4 volumio[14093]: info: Reading ALSA contributions from plugins. Jan 18 18:18:04 volumio-rpi4 volumio[14093]: info: Asound.conf file written Jan 18 18:18:04 volumio-rpi4 sudo[14832]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jan 18 18:18:04 volumio-rpi4 sudo[14832]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 18:18:04 volumio-rpi4 sudo[14832]: pam_unix(sudo:session): session closed for user root Jan 18 18:18:04 volumio-rpi4 volumio[14093]: info: Output device has changed, restarting MPD Jan 18 18:18:04 volumio-rpi4 sudo[14837]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 18 18:18:04 volumio-rpi4 sudo[14837]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 18:18:04 volumio-rpi4 sudo[14837]: pam_unix(sudo:session): session closed for user root Jan 18 18:18:04 volumio-rpi4 volumio[14093]: info: Output device has changed, restarting Shairport Sync Jan 18 18:18:04 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 18 18:18:04 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 18 18:18:04 volumio-rpi4 sudo[14840]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 18 18:18:04 volumio-rpi4 sudo[14840]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 18:18:04 volumio-rpi4 systemd[1]: Stopping Music Player Daemon... Jan 18 18:18:04 volumio-rpi4 volumio[14093]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 18 18:18:04 volumio-rpi4 volumio[14093]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jan 18 18:18:04 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 18 18:18:04 volumio-rpi4 systemd[1]: mpd.service: Succeeded. Jan 18 18:18:04 volumio-rpi4 systemd[1]: Stopped Music Player Daemon. Jan 18 18:18:04 volumio-rpi4 vtcs[14797]: [2025-01-18 18:18:04.554] [tisoc] [error] [SpkconServer.cpp:376] recv error. socket disconnected Jan 18 18:18:04 volumio-rpi4 volumio[14093]: info: PLUGIN START: fusiondsp Jan 18 18:18:04 volumio-rpi4 volumio[14093]: info: Loading i18n strings for locale en Jan 18 18:18:04 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jan 18 18:18:04 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 18 18:18:04 volumio-rpi4 volumio[14093]: info: FusionDsp - mixtype--------------------- Hardware Jan 18 18:18:04 volumio-rpi4 volumio[14093]: info: Preparing to generate the ALSA configuration file Jan 18 18:18:04 volumio-rpi4 volumio[14093]: info: Done. Jan 18 18:18:04 volumio-rpi4 sudo[14849]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jan 18 18:18:04 volumio-rpi4 vtcs[14797]: [2025-01-18 18:18:04.554] [tisoc] [warning] [SessionManagerImpl.cpp:243] Illegal State: IDLE Jan 18 18:18:04 volumio-rpi4 sudo[14849]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 18:18:04 volumio-rpi4 systemd[1]: Starting Music Player Daemon... Jan 18 18:18:04 volumio-rpi4 volumio[14093]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jan 18 18:18:04 volumio-rpi4 volumio[14093]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jan 18 18:18:04 volumio-rpi4 volumio[14093]: info: Reading ALSA contributions from plugins. Jan 18 18:18:04 volumio-rpi4 systemd[1]: Stopping Volumio Tidal Connect Service... Jan 18 18:18:04 volumio-rpi4 volumio[14093]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 18 18:18:04 volumio-rpi4 systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Jan 18 18:18:04 volumio-rpi4 systemd[1]: vtcs.service: Succeeded. Jan 18 18:18:04 volumio-rpi4 systemd[1]: Stopped Volumio Tidal Connect Service. Jan 18 18:18:04 volumio-rpi4 volumio[14093]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jan 18 18:18:04 volumio-rpi4 volumio[14093]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 18 18:18:04 volumio-rpi4 sudo[14852]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jan 18 18:18:04 volumio-rpi4 sudo[14852]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 18:18:04 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 18 18:18:04 volumio-rpi4 sudo[14849]: pam_unix(sudo:session): session closed for user root Jan 18 18:18:04 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 18 18:18:04 volumio-rpi4 volumio[14093]: info: MPD Permissions set Jan 18 18:18:04 volumio-rpi4 volumio[14093]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 18 18:18:04 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 18 18:18:04 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 18 18:18:04 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 18 18:18:04 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 18 18:18:04 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 18 18:18:04 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 18 18:18:04 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 18 18:18:04 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 18 18:18:04 volumio-rpi4 sudo[14852]: pam_unix(sudo:session): session closed for user root Jan 18 18:18:04 volumio-rpi4 sudo[14846]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 18 18:18:04 volumio-rpi4 sudo[14846]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 18:18:04 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 18 18:18:04 volumio-rpi4 sudo[14846]: pam_unix(sudo:session): session closed for user root Jan 18 18:18:04 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 18 18:18:05 volumio-rpi4 volumio[14093]: info: TidalConnect service started! Jan 18 18:18:05 volumio-rpi4 volumio[14093]: info: Starting Shairport Sync Jan 18 18:18:05 volumio-rpi4 volumio[14093]: info: Asound.conf file unchanged, so no further update is needed Jan 18 18:18:05 volumio-rpi4 volumio[14093]: info: Output device has changed, restarting MPD Jan 18 18:18:05 volumio-rpi4 sudo[14863]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 18 18:18:05 volumio-rpi4 sudo[14863]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 18:18:05 volumio-rpi4 sudo[14866]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 18 18:18:05 volumio-rpi4 sudo[14866]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 18:18:05 volumio-rpi4 volumio[14093]: info: Output device has changed, restarting Shairport Sync Jan 18 18:18:05 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 18 18:18:05 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 18 18:18:05 volumio-rpi4 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 18 18:18:05 volumio-rpi4 systemd[1]: shairport-sync.service: Succeeded. Jan 18 18:18:05 volumio-rpi4 sudo[14866]: pam_unix(sudo:session): session closed for user root Jan 18 18:18:05 volumio-rpi4 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 18 18:18:05 volumio-rpi4 sudo[14869]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 18 18:18:05 volumio-rpi4 sudo[14869]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 18:18:05 volumio-rpi4 volumio[14093]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 18 18:18:05 volumio-rpi4 volumio[14093]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jan 18 18:18:05 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 18 18:18:05 volumio-rpi4 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 18 18:18:05 volumio-rpi4 sudo[14863]: pam_unix(sudo:session): session closed for user root Jan 18 18:18:05 volumio-rpi4 systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jan 18 18:18:05 volumio-rpi4 systemd[1]: mpd.service: Succeeded. Jan 18 18:18:05 volumio-rpi4 systemd[1]: Stopped Music Player Daemon. Jan 18 18:18:05 volumio-rpi4 systemd[1]: Starting Music Player Daemon... Jan 18 18:18:05 volumio-rpi4 sudo[14881]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jan 18 18:18:05 volumio-rpi4 sudo[14881]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 18:18:05 volumio-rpi4 sudo[14881]: pam_unix(sudo:session): session closed for user root Jan 18 18:18:05 volumio-rpi4 volumio[14093]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 18 18:18:05 volumio-rpi4 volumio[14093]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jan 18 18:18:05 volumio-rpi4 sudo[14884]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jan 18 18:18:05 volumio-rpi4 volumio[14093]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jan 18 18:18:05 volumio-rpi4 volumio[14093]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 18 18:18:05 volumio-rpi4 sudo[14884]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 18:18:05 volumio-rpi4 volumio[14093]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jan 18 18:18:05 volumio-rpi4 volumio[14093]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 18 18:18:05 volumio-rpi4 volumio[14093]: info: MPD Permissions set Jan 18 18:18:05 volumio-rpi4 volumio[14093]: info: Shairport-Sync Started Jan 18 18:18:05 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 18 18:18:05 volumio-rpi4 sudo[14878]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 18 18:18:05 volumio-rpi4 sudo[14878]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 18:18:05 volumio-rpi4 sudo[14884]: pam_unix(sudo:session): session closed for user root Jan 18 18:18:05 volumio-rpi4 sudo[14878]: pam_unix(sudo:session): session closed for user root Jan 18 18:18:05 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 18 18:18:05 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 18 18:18:05 volumio-rpi4 volumio[14093]: info: Starting Shairport Sync Jan 18 18:18:05 volumio-rpi4 sudo[14895]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 18 18:18:05 volumio-rpi4 sudo[14895]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 18:18:05 volumio-rpi4 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 18 18:18:05 volumio-rpi4 systemd[1]: shairport-sync.service: Succeeded. Jan 18 18:18:05 volumio-rpi4 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 18 18:18:05 volumio-rpi4 volumio[14093]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 18 18:18:05 volumio-rpi4 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 18 18:18:05 volumio-rpi4 sudo[14895]: pam_unix(sudo:session): session closed for user root Jan 18 18:18:05 volumio-rpi4 volumio[14093]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jan 18 18:18:05 volumio-rpi4 volumio[14093]: info: Shairport-Sync Started Jan 18 18:18:06 volumio-rpi4 mpd[14890]: Jan 18 18:18 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 18 18:18:06 volumio-rpi4 systemd[1]: Started Music Player Daemon. Jan 18 18:18:06 volumio-rpi4 sudo[14840]: pam_unix(sudo:session): session closed for user root Jan 18 18:18:06 volumio-rpi4 sudo[14869]: pam_unix(sudo:session): session closed for user root Jan 18 18:18:06 volumio-rpi4 volumio[14093]: error: MPD error: The expression evaluated to a falsy value: Jan 18 18:18:06 volumio-rpi4 volumio[14093]: assert.ok(self.idling) Jan 18 18:18:06 volumio-rpi4 volumio[14093]: error: The expression evaluated to a falsy value: Jan 18 18:18:06 volumio-rpi4 volumio[14093]: assert.ok(self.idling) Jan 18 18:18:06 volumio-rpi4 volumio[14093]: error: updateQueue error: null Jan 18 18:18:06 volumio-rpi4 volumio[14093]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Jan 18 18:18:06 volumio-rpi4 volumio[14093]: 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 Jan 18 18:18:06 volumio-rpi4 volumio[14093]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Jan 18 18:18:06 volumio-rpi4 volumio[14093]: info: camilladsp spawned new process with pid 14908, instance 2, run: true Jan 18 18:18:06 volumio-rpi4 volumio[14093]: info: camilladsp service started and running in background, instance 2 Jan 18 18:18:06 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 18 18:18:06 volumio-rpi4 volumio[14093]: info: FusionDsp - AAAAAAAAAAAAAA-> 22050 44100 48000 88200 96000 176400 192000 <-AAAAAAAAAAAAA Jan 18 18:18:06 volumio-rpi4 volumio[14093]: info: FusionDsp loaded Jan 18 18:18:06 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 18 18:18:06 volumio-rpi4 sudo[14918]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Jan 18 18:18:06 volumio-rpi4 sudo[14918]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 18:18:06 volumio-rpi4 volumio[14093]: info: FusionDsp - Reporting Fusion DSP Enabled Jan 18 18:18:06 volumio-rpi4 volumio[14093]: info: Adding Signal Path Element [object Object] Jan 18 18:18:06 volumio-rpi4 volumio[14093]: info: Adding fusiondspeq DSP Signal Path Element Jan 18 18:18:06 volumio-rpi4 volumio[14093]: info: FusionDsp - ---- installed callbackRead Jan 18 18:18:06 volumio-rpi4 volumio[14093]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Jan 18 18:18:06 volumio-rpi4 volumio[14093]: info: FusionDsp - ---- read samplerate from file: 44100 Jan 18 18:18:06 volumio-rpi4 volumio[14093]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jan 18 18:18:06 volumio-rpi4 volumio[14093]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Jan 18 18:18:06 volumio-rpi4 volumio[14093]: info: FusionDsp - ---- read samplerate from file: 44100 Jan 18 18:18:06 volumio-rpi4 volumio[14093]: info: camilladsp stopping service pid 14908... Jan 18 18:18:06 volumio-rpi4 kernel: bcm2835_audio bcm2835_audio: failed to close VCHI service connection (status=-11) Jan 18 18:18:06 volumio-rpi4 volumio[14093]: info: camilladsp service terminated, instance 2 Jan 18 18:18:06 volumio-rpi4 volumio[14093]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jan 18 18:18:06 volumio-rpi4 volumio[14093]: info: camilladsp service started and running in background, instance 2 Jan 18 18:18:06 volumio-rpi4 volumio[14093]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jan 18 18:18:06 volumio-rpi4 volumio[14093]: error: FusionDsp - WebSocket error: [object Object] Jan 18 18:18:06 volumio-rpi4 volumio[14093]: error: FusionDsp - WebSocket error: [object Object] Jan 18 18:18:06 volumio-rpi4 volumio[14093]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 14 Jan 18 18:18:06 volumio-rpi4 volumio[14093]: info: camilladsp spawned new process with pid 14923, instance 2, run: true Jan 18 18:18:06 volumio-rpi4 systemd[14921]: fusiondsp.service: Failed to execute command: No such file or directory Jan 18 18:18:06 volumio-rpi4 systemd[14921]: fusiondsp.service: Failed at step EXEC spawning /data/plugins/audio_interface/fusiondsp/startcgui.sh: No such file or directory Jan 18 18:18:06 volumio-rpi4 systemd[1]: Started FusionDsp Daemon. Jan 18 18:18:06 volumio-rpi4 systemd[1]: fusiondsp.service: Main process exited, code=exited, status=203/EXEC Jan 18 18:18:06 volumio-rpi4 sudo[14918]: pam_unix(sudo:session): session closed for user root Jan 18 18:18:06 volumio-rpi4 systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jan 18 18:18:07 volumio-rpi4 volumio[14093]: info: TidalConnect service stoped! Jan 18 18:18:07 volumio-rpi4 volumio[14093]: info: TidalConnect service stoped! Jan 18 18:18:07 volumio-rpi4 volumio[14093]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jan 18 18:18:07 volumio-rpi4 volumio[14093]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jan 18 18:18:07 volumio-rpi4 sudo[14939]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jan 18 18:18:07 volumio-rpi4 sudo[14939]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 18:18:07 volumio-rpi4 systemd[1]: Started Volumio Tidal Connect Service. Jan 18 18:18:07 volumio-rpi4 sudo[14939]: pam_unix(sudo:session): session closed for user root Jan 18 18:18:08 volumio-rpi4 volumio[14093]: info: Executing endpoint tc_getconfig Jan 18 18:18:08 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Jan 18 18:18:08 volumio-rpi4 vtcs[14941]: STARTING TidalConnect services, version: 1.4.0.34 Jan 18 18:18:08 volumio-rpi4 vtcs[14941]: STARTED TidalConnect services. Jan 18 18:18:08 volumio-rpi4 volumio[14093]: info: Executing endpoint tc_connect Jan 18 18:18:08 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Jan 18 18:18:08 volumio-rpi4 volumio[14093]: info: Connecting to TidalConnect Jan 18 18:18:08 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::servicePushState Jan 18 18:18:08 volumio-rpi4 volumio[14093]: info: CoreStateMachine::pushState Jan 18 18:18:08 volumio-rpi4 volumio[14093]: info: CorePlayQueue::getTrack 0 Jan 18 18:18:08 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::volumioPushState Jan 18 18:18:08 volumio-rpi4 volumio[14093]: info: MRS: Pushing multiroomSync output update for this device Jan 18 18:18:08 volumio-rpi4 volumio[14093]: info: MRS: Pushing multiroomSync output Jan 18 18:18:08 volumio-rpi4 volumio[14093]: info: CorePlayQueue::getTrack 0 Jan 18 18:18:08 volumio-rpi4 volumio[14093]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Jan 18 18:18:08 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::servicePushState Jan 18 18:18:08 volumio-rpi4 volumio[14093]: info: CoreStateMachine::pushState Jan 18 18:18:08 volumio-rpi4 volumio[14093]: info: CorePlayQueue::getTrack 0 Jan 18 18:18:08 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::volumioPushState Jan 18 18:18:08 volumio-rpi4 volumio[14093]: info: MRS: Pushing multiroomSync output update for this device Jan 18 18:18:08 volumio-rpi4 volumio[14093]: info: MRS: Pushing multiroomSync output Jan 18 18:18:08 volumio-rpi4 volumio[14093]: info: CorePlayQueue::getTrack 0 Jan 18 18:18:08 volumio-rpi4 volumio[14093]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Jan 18 18:18:08 volumio-rpi4 volumio[14093]: info: TidalConnect service stoped! Jan 18 18:18:08 volumio-rpi4 volumio[14093]: info: TidalConnect service stoped! Jan 18 18:18:08 volumio-rpi4 volumio[14093]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jan 18 18:18:08 volumio-rpi4 volumio[14093]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jan 18 18:18:08 volumio-rpi4 sudo[14969]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jan 18 18:18:08 volumio-rpi4 sudo[14969]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 18:18:08 volumio-rpi4 sudo[14969]: pam_unix(sudo:session): session closed for user root Jan 18 18:18:08 volumio-rpi4 systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Jan 18 18:18:08 volumio-rpi4 systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1. Jan 18 18:18:08 volumio-rpi4 systemd[1]: Stopped FusionDsp Daemon. Jan 18 18:18:09 volumio-rpi4 systemd[1]: Started FusionDsp Daemon. Jan 18 18:18:09 volumio-rpi4 systemd[14972]: fusiondsp.service: Failed to execute command: No such file or directory Jan 18 18:18:09 volumio-rpi4 systemd[14972]: fusiondsp.service: Failed at step EXEC spawning /data/plugins/audio_interface/fusiondsp/startcgui.sh: No such file or directory Jan 18 18:18:09 volumio-rpi4 systemd[1]: fusiondsp.service: Main process exited, code=exited, status=203/EXEC Jan 18 18:18:09 volumio-rpi4 systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jan 18 18:18:10 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 18 18:18:10 volumio-rpi4 volumio[14093]: info: TidalConnect service started! Jan 18 18:18:11 volumio-rpi4 systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Jan 18 18:18:11 volumio-rpi4 systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 2. Jan 18 18:18:11 volumio-rpi4 systemd[1]: Stopped FusionDsp Daemon. Jan 18 18:18:11 volumio-rpi4 systemd[1]: Started FusionDsp Daemon. Jan 18 18:18:11 volumio-rpi4 systemd[14976]: fusiondsp.service: Failed to execute command: No such file or directory Jan 18 18:18:11 volumio-rpi4 systemd[14976]: fusiondsp.service: Failed at step EXEC spawning /data/plugins/audio_interface/fusiondsp/startcgui.sh: No such file or directory Jan 18 18:18:11 volumio-rpi4 systemd[1]: fusiondsp.service: Main process exited, code=exited, status=203/EXEC Jan 18 18:18:11 volumio-rpi4 systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jan 18 18:18:11 volumio-rpi4 volumio[14093]: info: TidalConnect service started! Jan 18 18:18:13 volumio-rpi4 systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Jan 18 18:18:13 volumio-rpi4 systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 3. Jan 18 18:18:13 volumio-rpi4 systemd[1]: Stopped FusionDsp Daemon. Jan 18 18:18:13 volumio-rpi4 systemd[1]: Started FusionDsp Daemon. Jan 18 18:18:13 volumio-rpi4 systemd[14992]: fusiondsp.service: Failed to execute command: No such file or directory Jan 18 18:18:13 volumio-rpi4 systemd[14992]: fusiondsp.service: Failed at step EXEC spawning /data/plugins/audio_interface/fusiondsp/startcgui.sh: No such file or directory Jan 18 18:18:13 volumio-rpi4 systemd[1]: fusiondsp.service: Main process exited, code=exited, status=203/EXEC Jan 18 18:18:13 volumio-rpi4 systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jan 18 18:18:15 volumio-rpi4 systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Jan 18 18:18:15 volumio-rpi4 systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 4. Jan 18 18:18:15 volumio-rpi4 systemd[1]: Stopped FusionDsp Daemon. Jan 18 18:18:15 volumio-rpi4 systemd[1]: Started FusionDsp Daemon. Jan 18 18:18:15 volumio-rpi4 systemd[15034]: fusiondsp.service: Failed to execute command: No such file or directory Jan 18 18:18:15 volumio-rpi4 systemd[15034]: fusiondsp.service: Failed at step EXEC spawning /data/plugins/audio_interface/fusiondsp/startcgui.sh: No such file or directory Jan 18 18:18:15 volumio-rpi4 systemd[1]: fusiondsp.service: Main process exited, code=exited, status=203/EXEC Jan 18 18:18:15 volumio-rpi4 systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jan 18 18:18:17 volumio-rpi4 systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Jan 18 18:18:17 volumio-rpi4 systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 5. Jan 18 18:18:17 volumio-rpi4 systemd[1]: Stopped FusionDsp Daemon. Jan 18 18:18:18 volumio-rpi4 systemd[1]: Started FusionDsp Daemon. Jan 18 18:18:18 volumio-rpi4 systemd[15035]: fusiondsp.service: Failed to execute command: No such file or directory Jan 18 18:18:18 volumio-rpi4 systemd[15035]: fusiondsp.service: Failed at step EXEC spawning /data/plugins/audio_interface/fusiondsp/startcgui.sh: No such file or directory Jan 18 18:18:18 volumio-rpi4 systemd[1]: fusiondsp.service: Main process exited, code=exited, status=203/EXEC Jan 18 18:18:18 volumio-rpi4 systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jan 18 18:18:19 volumio-rpi4 volumio[14093]: info: CALLMETHOD: audio_interface fusiondsp choosedsp [object Object] Jan 18 18:18:19 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , choosedsp Jan 18 18:18:19 volumio-rpi4 volumio[14093]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jan 18 18:18:19 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 18 18:18:19 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::Close All Modals sent Jan 18 18:18:19 volumio-rpi4 volumio[14093]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jan 18 18:18:20 volumio-rpi4 systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Jan 18 18:18:20 volumio-rpi4 systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 6. Jan 18 18:18:20 volumio-rpi4 systemd[1]: Stopped FusionDsp Daemon. Jan 18 18:18:20 volumio-rpi4 systemd[1]: Started FusionDsp Daemon. Jan 18 18:18:20 volumio-rpi4 systemd[15041]: fusiondsp.service: Failed to execute command: No such file or directory Jan 18 18:18:20 volumio-rpi4 systemd[15041]: fusiondsp.service: Failed at step EXEC spawning /data/plugins/audio_interface/fusiondsp/startcgui.sh: No such file or directory Jan 18 18:18:20 volumio-rpi4 systemd[1]: fusiondsp.service: Main process exited, code=exited, status=203/EXEC Jan 18 18:18:20 volumio-rpi4 systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jan 18 18:18:22 volumio-rpi4 systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Jan 18 18:18:22 volumio-rpi4 systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 7. Jan 18 18:18:22 volumio-rpi4 systemd[1]: Stopped FusionDsp Daemon. Jan 18 18:18:22 volumio-rpi4 systemd[1]: Started FusionDsp Daemon. Jan 18 18:18:22 volumio-rpi4 systemd[15056]: fusiondsp.service: Failed to execute command: No such file or directory Jan 18 18:18:22 volumio-rpi4 systemd[15056]: fusiondsp.service: Failed at step EXEC spawning /data/plugins/audio_interface/fusiondsp/startcgui.sh: No such file or directory Jan 18 18:18:22 volumio-rpi4 systemd[1]: fusiondsp.service: Main process exited, code=exited, status=203/EXEC Jan 18 18:18:22 volumio-rpi4 systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jan 18 18:18:24 volumio-rpi4 volumio[14093]: info: CALLMETHOD: audio_interface fusiondsp moresettings Jan 18 18:18:24 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , moresettings Jan 18 18:18:24 volumio-rpi4 systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Jan 18 18:18:24 volumio-rpi4 systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 8. Jan 18 18:18:24 volumio-rpi4 systemd[1]: Stopped FusionDsp Daemon. Jan 18 18:18:24 volumio-rpi4 volumio[14093]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jan 18 18:18:24 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 18 18:18:24 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::Close All Modals sent Jan 18 18:18:24 volumio-rpi4 systemd[1]: Started FusionDsp Daemon. Jan 18 18:18:24 volumio-rpi4 systemd[15057]: fusiondsp.service: Failed to execute command: No such file or directory Jan 18 18:18:24 volumio-rpi4 systemd[15057]: fusiondsp.service: Failed at step EXEC spawning /data/plugins/audio_interface/fusiondsp/startcgui.sh: No such file or directory Jan 18 18:18:24 volumio-rpi4 systemd[1]: fusiondsp.service: Main process exited, code=exited, status=203/EXEC Jan 18 18:18:24 volumio-rpi4 systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jan 18 18:18:25 volumio-rpi4 volumio[14093]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jan 18 18:18:26 volumio-rpi4 systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Jan 18 18:18:26 volumio-rpi4 systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 9. Jan 18 18:18:26 volumio-rpi4 systemd[1]: Stopped FusionDsp Daemon. Jan 18 18:18:27 volumio-rpi4 systemd[1]: Started FusionDsp Daemon. Jan 18 18:18:27 volumio-rpi4 systemd[15059]: fusiondsp.service: Failed to execute command: No such file or directory Jan 18 18:18:27 volumio-rpi4 systemd[15059]: fusiondsp.service: Failed at step EXEC spawning /data/plugins/audio_interface/fusiondsp/startcgui.sh: No such file or directory Jan 18 18:18:27 volumio-rpi4 systemd[1]: fusiondsp.service: Main process exited, code=exited, status=203/EXEC Jan 18 18:18:27 volumio-rpi4 systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jan 18 18:18:29 volumio-rpi4 systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Jan 18 18:18:29 volumio-rpi4 systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 10. Jan 18 18:18:29 volumio-rpi4 systemd[1]: Stopped FusionDsp Daemon. Jan 18 18:18:29 volumio-rpi4 systemd[1]: Started FusionDsp Daemon. Jan 18 18:18:29 volumio-rpi4 systemd[15060]: fusiondsp.service: Failed to execute command: No such file or directory Jan 18 18:18:29 volumio-rpi4 systemd[15060]: fusiondsp.service: Failed at step EXEC spawning /data/plugins/audio_interface/fusiondsp/startcgui.sh: No such file or directory Jan 18 18:18:29 volumio-rpi4 systemd[1]: fusiondsp.service: Main process exited, code=exited, status=203/EXEC Jan 18 18:18:29 volumio-rpi4 systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jan 18 18:18:31 volumio-rpi4 systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Jan 18 18:18:31 volumio-rpi4 systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 11. Jan 18 18:18:31 volumio-rpi4 systemd[1]: Stopped FusionDsp Daemon. Jan 18 18:18:31 volumio-rpi4 systemd[1]: Started FusionDsp Daemon. Jan 18 18:18:31 volumio-rpi4 systemd[15064]: fusiondsp.service: Failed to execute command: No such file or directory Jan 18 18:18:31 volumio-rpi4 systemd[15064]: fusiondsp.service: Failed at step EXEC spawning /data/plugins/audio_interface/fusiondsp/startcgui.sh: No such file or directory Jan 18 18:18:31 volumio-rpi4 systemd[1]: fusiondsp.service: Main process exited, code=exited, status=203/EXEC Jan 18 18:18:31 volumio-rpi4 systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jan 18 18:18:33 volumio-rpi4 systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Jan 18 18:18:33 volumio-rpi4 systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 12. Jan 18 18:18:33 volumio-rpi4 systemd[1]: Stopped FusionDsp Daemon. Jan 18 18:18:33 volumio-rpi4 systemd[1]: Started FusionDsp Daemon. Jan 18 18:18:33 volumio-rpi4 systemd[15079]: fusiondsp.service: Failed to execute command: No such file or directory Jan 18 18:18:33 volumio-rpi4 systemd[15079]: fusiondsp.service: Failed at step EXEC spawning /data/plugins/audio_interface/fusiondsp/startcgui.sh: No such file or directory Jan 18 18:18:33 volumio-rpi4 systemd[1]: fusiondsp.service: Main process exited, code=exited, status=203/EXEC Jan 18 18:18:33 volumio-rpi4 systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jan 18 18:18:35 volumio-rpi4 volumio[14093]: info: CALLMETHOD: audio_interface fusiondsp saveparameq [object Object] Jan 18 18:18:35 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , saveparameq Jan 18 18:18:35 volumio-rpi4 volumio[14093]: info: FusionDsp - value distance L------- 0 R 0 Jan 18 18:18:35 volumio-rpi4 volumio[14093]: info: FusionDsp - no delay needed Jan 18 18:18:35 volumio-rpi4 volumio[14093]: info: VolumeController::SetAlsaVolume+ Jan 18 18:18:35 volumio-rpi4 volumio[14093]: info: VolumeController::SetAlsaVolume- Jan 18 18:18:35 volumio-rpi4 volumio[14093]: info: CoreStateMachine::pushState Jan 18 18:18:35 volumio-rpi4 volumio[14093]: info: CorePlayQueue::getTrack 0 Jan 18 18:18:35 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::volumioPushState Jan 18 18:18:35 volumio-rpi4 volumio[14093]: info: MRS: Pushing multiroomSync output update for this device Jan 18 18:18:35 volumio-rpi4 volumio[14093]: info: MRS: Pushing multiroomSync output Jan 18 18:18:35 volumio-rpi4 volumio[14093]: info: CoreStateMachine::pushState Jan 18 18:18:35 volumio-rpi4 volumio[14093]: info: CorePlayQueue::getTrack 0 Jan 18 18:18:35 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::volumioPushState Jan 18 18:18:35 volumio-rpi4 volumio[14093]: info: MRS: Pushing multiroomSync output update for this device Jan 18 18:18:35 volumio-rpi4 volumio[14093]: info: MRS: Pushing multiroomSync output Jan 18 18:18:35 volumio-rpi4 volumio[14093]: info: Jan 18 18:18:35 volumio-rpi4 volumio[14093]: ---------------------------- MPD announces state update: mixer Jan 18 18:18:35 volumio-rpi4 volumio[14093]: info: ControllerMpd::getState Jan 18 18:18:35 volumio-rpi4 volumio[14093]: verbose: ControllerMpd::sendMpdCommand status Jan 18 18:18:35 volumio-rpi4 volumio[14093]: info: Jan 18 18:18:35 volumio-rpi4 volumio[14093]: ---------------------------- MPD announces state update: mixer Jan 18 18:18:35 volumio-rpi4 volumio[14093]: info: ControllerMpd::getState Jan 18 18:18:35 volumio-rpi4 volumio[14093]: verbose: ControllerMpd::sendMpdCommand status Jan 18 18:18:35 volumio-rpi4 volumio[14093]: info: Jan 18 18:18:35 volumio-rpi4 volumio[14093]: ---------------------------- MPD announces state update: mixer Jan 18 18:18:35 volumio-rpi4 volumio[14093]: info: ControllerMpd::getState Jan 18 18:18:35 volumio-rpi4 volumio[14093]: verbose: ControllerMpd::sendMpdCommand status Jan 18 18:18:35 volumio-rpi4 volumio[14093]: info: sendMpdCommand status took 10 milliseconds Jan 18 18:18:35 volumio-rpi4 volumio[14093]: info: sendMpdCommand status took 8 milliseconds Jan 18 18:18:35 volumio-rpi4 volumio[14093]: verbose: ControllerMpd::parseState Jan 18 18:18:35 volumio-rpi4 volumio[14093]: verbose: ControllerMpd::parseState Jan 18 18:18:35 volumio-rpi4 volumio[14093]: info: ControllerMpd::pushState Jan 18 18:18:35 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::servicePushState Jan 18 18:18:35 volumio-rpi4 volumio[14093]: info: CoreStateMachine::pushState Jan 18 18:18:35 volumio-rpi4 volumio[14093]: info: CorePlayQueue::getTrack 0 Jan 18 18:18:35 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::volumioPushState Jan 18 18:18:35 volumio-rpi4 volumio[14093]: info: MRS: Pushing multiroomSync output update for this device Jan 18 18:18:35 volumio-rpi4 volumio[14093]: info: MRS: Pushing multiroomSync output Jan 18 18:18:35 volumio-rpi4 volumio[14093]: info: CorePlayQueue::getTrack 0 Jan 18 18:18:35 volumio-rpi4 volumio[14093]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 18 18:18:35 volumio-rpi4 volumio[14093]: verbose: CURRENT POSITION 0 Jan 18 18:18:35 volumio-rpi4 volumio[14093]: info: CoreStateMachine::syncState stateService stop Jan 18 18:18:35 volumio-rpi4 volumio[14093]: info: CoreStateMachine::syncState currentStatus stop Jan 18 18:18:35 volumio-rpi4 volumio[14093]: info: CoreStateMachine::pushState Jan 18 18:18:35 volumio-rpi4 volumio[14093]: info: CorePlayQueue::getTrack 0 Jan 18 18:18:35 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::volumioPushState Jan 18 18:18:35 volumio-rpi4 volumio[14093]: info: MRS: Pushing multiroomSync output update for this device Jan 18 18:18:35 volumio-rpi4 volumio[14093]: info: MRS: Pushing multiroomSync output Jan 18 18:18:35 volumio-rpi4 volumio[14093]: info: No code Jan 18 18:18:35 volumio-rpi4 volumio[14093]: info: CoreStateMachine::pushState Jan 18 18:18:35 volumio-rpi4 volumio[14093]: info: CorePlayQueue::getTrack 0 Jan 18 18:18:35 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::volumioPushState Jan 18 18:18:35 volumio-rpi4 volumio[14093]: info: MRS: Pushing multiroomSync output update for this device Jan 18 18:18:35 volumio-rpi4 volumio[14093]: info: MRS: Pushing multiroomSync output Jan 18 18:18:35 volumio-rpi4 volumio[14093]: info: ControllerMpd::pushState Jan 18 18:18:35 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::servicePushState Jan 18 18:18:35 volumio-rpi4 volumio[14093]: info: CoreStateMachine::pushState Jan 18 18:18:35 volumio-rpi4 volumio[14093]: info: CorePlayQueue::getTrack 0 Jan 18 18:18:35 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::volumioPushState Jan 18 18:18:35 volumio-rpi4 volumio[14093]: info: MRS: Pushing multiroomSync output update for this device Jan 18 18:18:35 volumio-rpi4 volumio[14093]: info: MRS: Pushing multiroomSync output Jan 18 18:18:35 volumio-rpi4 volumio[14093]: info: CorePlayQueue::getTrack 0 Jan 18 18:18:35 volumio-rpi4 volumio[14093]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 18 18:18:35 volumio-rpi4 volumio[14093]: verbose: CURRENT POSITION 0 Jan 18 18:18:35 volumio-rpi4 volumio[14093]: info: CoreStateMachine::syncState stateService stop Jan 18 18:18:35 volumio-rpi4 volumio[14093]: info: CoreStateMachine::syncState currentStatus stop Jan 18 18:18:35 volumio-rpi4 volumio[14093]: info: CoreStateMachine::pushState Jan 18 18:18:35 volumio-rpi4 volumio[14093]: info: CorePlayQueue::getTrack 0 Jan 18 18:18:35 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::volumioPushState Jan 18 18:18:35 volumio-rpi4 volumio[14093]: info: MRS: Pushing multiroomSync output update for this device Jan 18 18:18:35 volumio-rpi4 volumio[14093]: info: MRS: Pushing multiroomSync output Jan 18 18:18:35 volumio-rpi4 volumio[14093]: info: No code Jan 18 18:18:35 volumio-rpi4 volumio[14093]: info: CoreStateMachine::pushState Jan 18 18:18:35 volumio-rpi4 volumio[14093]: info: CorePlayQueue::getTrack 0 Jan 18 18:18:35 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::volumioPushState Jan 18 18:18:35 volumio-rpi4 volumio[14093]: info: MRS: Pushing multiroomSync output update for this device Jan 18 18:18:35 volumio-rpi4 volumio[14093]: info: MRS: Pushing multiroomSync output Jan 18 18:18:35 volumio-rpi4 volumio[14093]: info: ------------------------------ 137ms Jan 18 18:18:35 volumio-rpi4 volumio[14093]: info: ------------------------------ 135ms Jan 18 18:18:35 volumio-rpi4 volumio[14093]: info: Jan 18 18:18:35 volumio-rpi4 volumio[14093]: ---------------------------- MPD announces state update: mixer Jan 18 18:18:35 volumio-rpi4 volumio[14093]: info: ControllerMpd::getState Jan 18 18:18:35 volumio-rpi4 volumio[14093]: verbose: ControllerMpd::sendMpdCommand status Jan 18 18:18:35 volumio-rpi4 volumio[14093]: info: sendMpdCommand status took 131 milliseconds Jan 18 18:18:35 volumio-rpi4 volumio[14093]: verbose: ControllerMpd::parseState Jan 18 18:18:35 volumio-rpi4 volumio[14093]: info: ControllerMpd::pushState Jan 18 18:18:35 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::servicePushState Jan 18 18:18:35 volumio-rpi4 volumio[14093]: info: CoreStateMachine::pushState Jan 18 18:18:35 volumio-rpi4 volumio[14093]: info: CorePlayQueue::getTrack 0 Jan 18 18:18:35 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::volumioPushState Jan 18 18:18:35 volumio-rpi4 volumio[14093]: info: MRS: Pushing multiroomSync output update for this device Jan 18 18:18:35 volumio-rpi4 volumio[14093]: info: MRS: Pushing multiroomSync output Jan 18 18:18:35 volumio-rpi4 volumio[14093]: info: CorePlayQueue::getTrack 0 Jan 18 18:18:35 volumio-rpi4 volumio[14093]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 18 18:18:35 volumio-rpi4 volumio[14093]: verbose: CURRENT POSITION 0 Jan 18 18:18:35 volumio-rpi4 volumio[14093]: info: CoreStateMachine::syncState stateService stop Jan 18 18:18:35 volumio-rpi4 volumio[14093]: info: CoreStateMachine::syncState currentStatus stop Jan 18 18:18:35 volumio-rpi4 volumio[14093]: info: CoreStateMachine::pushState Jan 18 18:18:35 volumio-rpi4 volumio[14093]: info: CorePlayQueue::getTrack 0 Jan 18 18:18:35 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::volumioPushState Jan 18 18:18:35 volumio-rpi4 volumio[14093]: info: MRS: Pushing multiroomSync output update for this device Jan 18 18:18:35 volumio-rpi4 volumio[14093]: info: MRS: Pushing multiroomSync output Jan 18 18:18:35 volumio-rpi4 volumio[14093]: info: No code Jan 18 18:18:35 volumio-rpi4 volumio[14093]: info: CoreStateMachine::pushState Jan 18 18:18:35 volumio-rpi4 volumio[14093]: info: CorePlayQueue::getTrack 0 Jan 18 18:18:35 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::volumioPushState Jan 18 18:18:35 volumio-rpi4 volumio[14093]: info: MRS: Pushing multiroomSync output update for this device Jan 18 18:18:35 volumio-rpi4 volumio[14093]: info: MRS: Pushing multiroomSync output Jan 18 18:18:35 volumio-rpi4 volumio[14093]: info: ------------------------------ 229ms Jan 18 18:18:35 volumio-rpi4 systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Jan 18 18:18:35 volumio-rpi4 systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 13. Jan 18 18:18:36 volumio-rpi4 volumio[14093]: info: sendMpdCommand status took 171 milliseconds Jan 18 18:18:36 volumio-rpi4 volumio[14093]: verbose: ControllerMpd::parseState Jan 18 18:18:36 volumio-rpi4 volumio[14093]: info: ControllerMpd::pushState Jan 18 18:18:36 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::servicePushState Jan 18 18:18:36 volumio-rpi4 volumio[14093]: info: CoreStateMachine::pushState Jan 18 18:18:36 volumio-rpi4 volumio[14093]: info: CorePlayQueue::getTrack 0 Jan 18 18:18:36 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::volumioPushState Jan 18 18:18:35 volumio-rpi4 systemd[1]: Stopped FusionDsp Daemon. Jan 18 18:18:36 volumio-rpi4 systemd[15082]: fusiondsp.service: Failed to execute command: No such file or directory Jan 18 18:18:36 volumio-rpi4 systemd[15082]: fusiondsp.service: Failed at step EXEC spawning /data/plugins/audio_interface/fusiondsp/startcgui.sh: No such file or directory Jan 18 18:18:36 volumio-rpi4 systemd[1]: Started FusionDsp Daemon. Jan 18 18:18:36 volumio-rpi4 systemd[1]: fusiondsp.service: Main process exited, code=exited, status=203/EXEC Jan 18 18:18:36 volumio-rpi4 systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jan 18 18:18:36 volumio-rpi4 volumio[14093]: info: MRS: Pushing multiroomSync output update for this device Jan 18 18:18:36 volumio-rpi4 volumio[14093]: info: MRS: Pushing multiroomSync output Jan 18 18:18:36 volumio-rpi4 volumio[14093]: info: CorePlayQueue::getTrack 0 Jan 18 18:18:36 volumio-rpi4 volumio[14093]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 18 18:18:36 volumio-rpi4 volumio[14093]: verbose: CURRENT POSITION 0 Jan 18 18:18:36 volumio-rpi4 volumio[14093]: info: CoreStateMachine::syncState stateService stop Jan 18 18:18:36 volumio-rpi4 volumio[14093]: info: CoreStateMachine::syncState currentStatus stop Jan 18 18:18:36 volumio-rpi4 volumio[14093]: info: CoreStateMachine::pushState Jan 18 18:18:36 volumio-rpi4 volumio[14093]: info: CorePlayQueue::getTrack 0 Jan 18 18:18:36 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::volumioPushState Jan 18 18:18:36 volumio-rpi4 volumio[14093]: info: MRS: Pushing multiroomSync output update for this device Jan 18 18:18:36 volumio-rpi4 volumio[14093]: info: MRS: Pushing multiroomSync output Jan 18 18:18:36 volumio-rpi4 volumio[14093]: info: No code Jan 18 18:18:36 volumio-rpi4 volumio[14093]: info: CoreStateMachine::pushState Jan 18 18:18:36 volumio-rpi4 volumio[14093]: info: CorePlayQueue::getTrack 0 Jan 18 18:18:36 volumio-rpi4 volumio[14093]: info: CoreCommandRouter::volumioPushState Jan 18 18:18:36 volumio-rpi4 volumio[14093]: info: MRS: Pushing multiroomSync output update for this device Jan 18 18:18:36 volumio-rpi4 volumio[14093]: info: MRS: Pushing multiroomSync output Jan 18 18:18:36 volumio-rpi4 volumio[14093]: info: ------------------------------ 246ms Jan 18 18:18:36 volumio-rpi4 volumio[14093]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jan 18 18:18:36 volumio-rpi4 volumio[14093]: info: FusionDsp - Loudness is ON true Jan 18 18:18:36 volumio-rpi4 volumio[14093]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jan 18 18:18:36 volumio-rpi4 volumio[14093]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 18 18:18:36 volumio-rpi4 volumio[14093]: TypeError: self.commandRouter.getcurrentVolume is not a function Jan 18 18:18:36 volumio-rpi4 volumio[14093]: at FusionDsp.sendvolumelevel (/data/plugins/audio_interface/fusiondsp/index.js:4986:33) Jan 18 18:18:36 volumio-rpi4 volumio[14093]: at Timeout._onTimeout (/data/plugins/audio_interface/fusiondsp/index.js:4068:14) Jan 18 18:18:36 volumio-rpi4 volumio[14093]: at listOnTimeout (internal/timers.js:554:17) Jan 18 18:18:36 volumio-rpi4 volumio[14093]: at processTimers (internal/timers.js:497:7) Jan 18 18:18:36 volumio-rpi4 volumio[14093]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 18 18:18:36 volumio-rpi4 sudo[15093]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-01-18 18:17 Jan 18 18:18:36 volumio-rpi4 sudo[15093]: 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="cc0042a1eab1cb7982711a1347e242f8947b54b6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="d316d397153fbb98c246d670c12f98a120b995a2" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 11 Jan 2025 10:58:50 AM CET" VOLUMIO_VERSION="3.788" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e6f1869148b01453dc48d3e2d79d1421"