-- Logs begin at Thu 2019-02-14 12:12:00 EET, end at Mon 2024-10-07 18:14:12 EEST. -- Oct 07 18:13:26 elutuba volumio[1911]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Oct 07 18:13:26 elutuba volumio[1911]: 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 Oct 07 18:13:26 elutuba volumio[1911]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Oct 07 18:13:26 elutuba volumio[1911]: info: camilladsp spawned new process with pid undefined, instance 1, run: true Oct 07 18:13:26 elutuba volumio[1911]: info: camilladsp service started and running in background, instance 1 Oct 07 18:13:26 elutuba volumio[1911]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:26 elutuba volumio[1911]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Oct 07 18:13:26 elutuba volumio[1911]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Oct 07 18:13:26 elutuba volumio[1911]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Oct 07 18:13:26 elutuba volumio[1911]: info: FusionDsp loaded Oct 07 18:13:26 elutuba volumio[1911]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 07 18:13:26 elutuba sudo[2107]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Oct 07 18:13:26 elutuba sudo[2107]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:13:26 elutuba volumio[1911]: info: FusionDsp - Reporting Fusion DSP Enabled Oct 07 18:13:26 elutuba volumio[1911]: info: Adding Signal Path Element [object Object] Oct 07 18:13:26 elutuba volumio[1911]: info: Adding fusiondspeq DSP Signal Path Element Oct 07 18:13:26 elutuba volumio[1911]: info: FusionDsp - ---- installed callbackRead Oct 07 18:13:26 elutuba volumio[1911]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 07 18:13:26 elutuba sudo[2107]: pam_unix(sudo:session): session closed for user root Oct 07 18:13:26 elutuba volumio[1911]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Oct 07 18:13:26 elutuba volumio[1911]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Oct 07 18:13:26 elutuba volumio[1911]: at onErrorNT (internal/child_process.js:465:16) Oct 07 18:13:26 elutuba volumio[1911]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Oct 07 18:13:26 elutuba volumio[1911]: errno: -2, Oct 07 18:13:26 elutuba volumio[1911]: code: 'ENOENT', Oct 07 18:13:26 elutuba volumio[1911]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Oct 07 18:13:26 elutuba volumio[1911]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Oct 07 18:13:26 elutuba volumio[1911]: spawnargs: [ Oct 07 18:13:26 elutuba volumio[1911]: '-p', Oct 07 18:13:26 elutuba volumio[1911]: 9876, Oct 07 18:13:26 elutuba volumio[1911]: '-o', Oct 07 18:13:26 elutuba volumio[1911]: '/tmp/camilladsp.log', Oct 07 18:13:26 elutuba volumio[1911]: '-l', Oct 07 18:13:26 elutuba volumio[1911]: 'warn', Oct 07 18:13:26 elutuba volumio[1911]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Oct 07 18:13:26 elutuba volumio[1911]: ] Oct 07 18:13:26 elutuba volumio[1911]: } Oct 07 18:13:26 elutuba volumio[1911]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 07 18:13:26 elutuba mpd[2089]: Oct 07 18:13 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 07 18:13:26 elutuba systemd[1]: Started Music Player Daemon. Oct 07 18:13:26 elutuba sudo[1995]: pam_unix(sudo:session): session closed for user root Oct 07 18:13:26 elutuba sudo[2074]: pam_unix(sudo:session): session closed for user root Oct 07 18:13:26 elutuba sudo[2006]: pam_unix(sudo:session): session closed for user root Oct 07 18:13:26 elutuba sudo[2120]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-07 18:12 Oct 07 18:13:26 elutuba sudo[2120]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:13:26 elutuba sudo[2120]: pam_unix(sudo:session): session closed for user root Oct 07 18:13:27 elutuba volumio-remote-updater[629]: [2024-10-07 18:13:27] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Oct 07 18:13:27 elutuba volumio-remote-updater[629]: [2024-10-07 18:13:27] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Oct 07 18:13:27 elutuba systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Oct 07 18:13:27 elutuba systemd[1]: volumio.service: Failed with result 'exit-code'. Oct 07 18:13:27 elutuba systemd[1]: Started dynamicswap service. Oct 07 18:13:27 elutuba systemd[1]: dynamicswap.service: Succeeded. Oct 07 18:13:27 elutuba systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Oct 07 18:13:27 elutuba systemd[1]: volumio.service: Scheduled restart job, restart counter is at 5. Oct 07 18:13:27 elutuba systemd[1]: Started dynamicswap service. Oct 07 18:13:27 elutuba systemd[1]: Stopped Volumio Backend Module. Oct 07 18:13:27 elutuba systemd[1]: Started Volumio Backend Module. Oct 07 18:13:27 elutuba systemd[1]: dynamicswap.service: Succeeded. Oct 07 18:13:29 elutuba volumio[2134]: info: ------------------------------------------- Oct 07 18:13:29 elutuba volumio[2134]: info: ----- Volumio3 ---- Oct 07 18:13:29 elutuba volumio[2134]: info: ------------------------------------------- Oct 07 18:13:29 elutuba volumio[2134]: info: ----- System startup ---- Oct 07 18:13:29 elutuba volumio[2134]: info: ------------------------------------------- Oct 07 18:13:30 elutuba volumio[2134]: info: MYVOLUMIO Environment detected Oct 07 18:13:30 elutuba volumio[2134]: info: Plugin folders cleanup Oct 07 18:13:30 elutuba volumio[2134]: info: Scanning into folder /volumio/app/plugins/ Oct 07 18:13:30 elutuba volumio[2134]: info: Scanning category audio_interface Oct 07 18:13:30 elutuba volumio[2134]: info: Scanning category miscellanea Oct 07 18:13:30 elutuba volumio[2134]: info: Scanning category music_service Oct 07 18:13:30 elutuba volumio[2134]: info: Scanning category plugins.json Oct 07 18:13:30 elutuba volumio[2134]: info: Scanning category system_controller Oct 07 18:13:30 elutuba volumio[2134]: info: Scanning category user_interface Oct 07 18:13:30 elutuba volumio[2134]: info: Scanning into folder /data/plugins/ Oct 07 18:13:30 elutuba volumio[2134]: info: Scanning category audio_interface Oct 07 18:13:30 elutuba volumio[2134]: info: Plugin folders cleanup completed Oct 07 18:13:30 elutuba volumio[2134]: info: ------------------------------------------- Oct 07 18:13:30 elutuba volumio[2134]: info: ----- Core plugins startup ---- Oct 07 18:13:30 elutuba volumio[2134]: info: ------------------------------------------- Oct 07 18:13:30 elutuba volumio[2134]: info: Loading plugins from folder /volumio/app/plugins/ Oct 07 18:13:30 elutuba volumio[2134]: info: Adding plugin upnp to MyMusic Plugins Oct 07 18:13:30 elutuba volumio[2134]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 07 18:13:30 elutuba volumio[2134]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 07 18:13:30 elutuba volumio[2134]: info: Loading plugins from folder /data/plugins/ Oct 07 18:13:30 elutuba volumio[2134]: info: Loading plugin "system"... Oct 07 18:13:30 elutuba volumio[2134]: info: Loading plugin "appearance"... Oct 07 18:13:31 elutuba volumio[2134]: info: Loading plugin "network"... Oct 07 18:13:31 elutuba volumio[2134]: info: Refreshing Cached IP Addresses Oct 07 18:13:31 elutuba sudo[2161]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 07 18:13:31 elutuba sudo[2161]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:13:31 elutuba volumio[2134]: info: Loading plugin "services"... Oct 07 18:13:31 elutuba sudo[2161]: pam_unix(sudo:session): session closed for user root Oct 07 18:13:31 elutuba sudo[2163]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 07 18:13:31 elutuba sudo[2163]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:13:31 elutuba volumio[2134]: info: Loading plugin "alsa_controller"... Oct 07 18:13:31 elutuba sudo[2163]: pam_unix(sudo:session): session closed for user root Oct 07 18:13:31 elutuba sudo[2166]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 07 18:13:31 elutuba sudo[2166]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:13:31 elutuba volumio[2134]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 07 18:13:31 elutuba volumio[2134]: info: Loading plugin "wizard"... Oct 07 18:13:31 elutuba volumio[2134]: info: Loading plugin "networkfs"... Oct 07 18:13:31 elutuba volumio[2134]: info: Starting Udev Watcher for removable devices Oct 07 18:13:31 elutuba volumio[2134]: info: Ignoring mount for partition: boot Oct 07 18:13:31 elutuba volumio[2134]: info: Ignoring mount for partition: volumio Oct 07 18:13:31 elutuba volumio[2134]: info: Ignoring mount for partition: volumio_data Oct 07 18:13:31 elutuba volumio[2134]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 07 18:13:31 elutuba volumio[2134]: info: Loading plugin "volumio_command_line_client"... Oct 07 18:13:31 elutuba volumio[2134]: info: Loading plugin "upnp"... Oct 07 18:13:31 elutuba volumio[2134]: info: [1728314011543] Starting Upmpd Daemon Oct 07 18:13:31 elutuba volumio[2134]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 07 18:13:31 elutuba volumio[2134]: info: Loading plugin "my_music"... Oct 07 18:13:31 elutuba volumio[2134]: info: Loading plugin "mpd"... Oct 07 18:13:31 elutuba volumio[2134]: info: Loading plugin "upnp_browser"... Oct 07 18:13:32 elutuba volumio-remote-updater[629]: [2024-10-07 18:13:32] [connect] Successful connection Oct 07 18:13:32 elutuba volumio[2134]: info: Loading plugin "alarm-clock"... Oct 07 18:13:32 elutuba sudo[2166]: pam_unix(sudo:session): session closed for user root Oct 07 18:13:32 elutuba volumio[2134]: info: Loading plugin "airplay_emulation"... Oct 07 18:13:32 elutuba volumio[2134]: info: Starting Shairport Sync Oct 07 18:13:32 elutuba volumio[2134]: info: Loading plugin "last_100"... Oct 07 18:13:32 elutuba volumio[2134]: info: Loading plugin "webradio"... Oct 07 18:13:32 elutuba volumio[2134]: info: Loading plugin "i2s_dacs"... Oct 07 18:13:32 elutuba volumio[2134]: info: I2S DAC not set, start Auto-detection Oct 07 18:13:32 elutuba volumio[2134]: info: Loading plugin "volumiodiscovery"... Oct 07 18:13:32 elutuba volumio[2134]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 07 18:13:32 elutuba volumio[2134]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 07 18:13:32 elutuba volumio[2134]: *** WARNING *** For more information see Oct 07 18:13:32 elutuba node[2134]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 07 18:13:32 elutuba volumio[2134]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 07 18:13:32 elutuba volumio[2134]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 07 18:13:32 elutuba volumio[2134]: *** WARNING *** For more information see Oct 07 18:13:32 elutuba node[2134]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 07 18:13:32 elutuba node[2134]: *** WARNING *** For more information see Oct 07 18:13:32 elutuba node[2134]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 07 18:13:32 elutuba node[2134]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 07 18:13:32 elutuba node[2134]: *** WARNING *** For more information see Oct 07 18:13:32 elutuba volumio[2134]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 07 18:13:32 elutuba volumio[2134]: info: Discovery: Started advertising with name: Elutuba Oct 07 18:13:32 elutuba volumio[2134]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 07 18:13:32 elutuba volumio[2134]: info: Loading plugin "outputs"... Oct 07 18:13:32 elutuba volumio[2134]: info: Loading plugin "albumart"... Oct 07 18:13:32 elutuba volumio[2134]: info: Plugin example_plugin is not enabled Oct 07 18:13:32 elutuba volumio[2134]: info: Loading plugin "inputs"... Oct 07 18:13:32 elutuba volumio[2134]: info: Loading plugin "updater_comm"... Oct 07 18:13:32 elutuba volumio[2134]: info: Plugin mpdemulation is not enabled Oct 07 18:13:32 elutuba volumio[2134]: info: Loading plugin "rest_api"... Oct 07 18:13:32 elutuba volumio[2134]: info: Loading plugin "websocket"... Oct 07 18:13:32 elutuba volumio[2134]: info: Starting Socket.io Server version 2.3.0 Oct 07 18:13:32 elutuba volumio[2134]: info: Loading plugin "fusiondsp"... Oct 07 18:13:33 elutuba volumio[2134]: Forking 3 albumart workers Oct 07 18:13:33 elutuba volumio[2134]: info: Applying required configuration parameters for plugin fusiondsp Oct 07 18:13:33 elutuba volumio[2134]: info: Loading i18n strings for locale en Oct 07 18:13:33 elutuba volumio[2134]: Updating browse sources language Oct 07 18:13:33 elutuba volumio[2134]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 07 18:13:33 elutuba volumio[2134]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 07 18:13:33 elutuba volumio[2134]: info: CoreCommandRouter::initPlayerControls Oct 07 18:13:33 elutuba volumio[2134]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:33 elutuba volumio[2134]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:33 elutuba volumio[2134]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:33 elutuba volumio[2134]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:33 elutuba volumio[2134]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:33 elutuba volumio[2134]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:33 elutuba volumio[2134]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:33 elutuba volumio[2134]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:33 elutuba volumio[2134]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 07 18:13:33 elutuba volumio[2134]: Express server listening on port 3000 Oct 07 18:13:33 elutuba volumio[2134]: [Metrics] WebUI: 5s 179.89ms Oct 07 18:13:33 elutuba volumio[2134]: info: CoreStateMachine::resetVolumioState Oct 07 18:13:33 elutuba volumio[2134]: info: CoreStateMachine::getcurrentVolume Oct 07 18:13:33 elutuba volumio[2134]: info: CoreCommandRouter::volumioRetrievevolume Oct 07 18:13:33 elutuba volumio[2134]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 07 18:13:33 elutuba volumio[2134]: info: Cannot read play queue from file Oct 07 18:13:34 elutuba volumio[2134]: verbose: New Socket.io Connection to 192.168.175.140 from 192.168.175.185 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/128.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Oct 07 18:13:34 elutuba volumio-remote-updater[629]: [2024-10-07 18:13:34] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1728314012 101 Oct 07 18:13:34 elutuba volumio[2134]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 2 Oct 07 18:13:34 elutuba volumio[2134]: info: CoreCommandRouter::volumioGetState Oct 07 18:13:34 elutuba volumio[2134]: info: CorePlayQueue::getTrack 0 Oct 07 18:13:34 elutuba volumio[2134]: Starting albumart workers Oct 07 18:13:34 elutuba volumio[2134]: Starting albumart workers Oct 07 18:13:34 elutuba volumio[2134]: Starting albumart workers Oct 07 18:13:34 elutuba volumio[2134]: info: VolumeController:: Volume=0 Mute =false Oct 07 18:13:34 elutuba volumio[2134]: info: CoreStateMachine::pushState Oct 07 18:13:34 elutuba volumio[2134]: info: CorePlayQueue::getTrack 0 Oct 07 18:13:34 elutuba volumio[2134]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 07 18:13:34 elutuba volumio[2134]: info: CoreCommandRouter::volumioPushState Oct 07 18:13:34 elutuba volumio[2134]: info: CoreStateMachine::updateTrackBlock Oct 07 18:13:34 elutuba volumio[2134]: info: CorePlayQueue::getTrackBlock Oct 07 18:13:34 elutuba volumio[2134]: info: CoreCommandRouter::volumioRetrievevolume Oct 07 18:13:34 elutuba volumio[2134]: info: CoreStateMachine::setRepeat null single undefined Oct 07 18:13:34 elutuba volumio[2134]: info: CoreStateMachine::pushState Oct 07 18:13:34 elutuba volumio[2134]: info: CorePlayQueue::getTrack 0 Oct 07 18:13:34 elutuba volumio[2134]: info: CoreCommandRouter::volumioPushState Oct 07 18:13:34 elutuba volumio[2134]: info: CoreStateMachine::setRandom null Oct 07 18:13:34 elutuba volumio[2134]: info: CoreStateMachine::pushState Oct 07 18:13:34 elutuba volumio[2134]: info: CorePlayQueue::getTrack 0 Oct 07 18:13:34 elutuba volumio[2134]: info: CoreCommandRouter::volumioPushState Oct 07 18:13:34 elutuba volumio[2134]: info: Setting Device type: Raspberry PI Oct 07 18:13:34 elutuba volumio[2134]: info: Discovery: adding 320db26b-9bed-4586-9e4d-ee23fdd56bae Oct 07 18:13:34 elutuba volumio[2134]: info: Discovery: Found device Elutuba Oct 07 18:13:34 elutuba volumio[2134]: info: CoreCommandRouter::volumioGetState Oct 07 18:13:34 elutuba volumio[2134]: info: CorePlayQueue::getTrack 0 Oct 07 18:13:34 elutuba volumio[2134]: info: VolumeController:: Volume=0 Mute =false Oct 07 18:13:34 elutuba volumio[2134]: info: CoreStateMachine::pushState Oct 07 18:13:34 elutuba volumio[2134]: info: CorePlayQueue::getTrack 0 Oct 07 18:13:34 elutuba volumio[2134]: info: CoreCommandRouter::volumioPushState Oct 07 18:13:34 elutuba volumio[2134]: info: Completed loading Core Plugins Oct 07 18:13:34 elutuba volumio[2134]: info: Preparing to generate the ALSA configuration file Oct 07 18:13:34 elutuba volumio[2134]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Oct 07 18:13:34 elutuba volumio[2134]: info: Reading ALSA contributions from plugins. Oct 07 18:13:34 elutuba sudo[2216]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 07 18:13:34 elutuba volumio[2134]: info: Asound.conf file unchanged, so no further update is needed Oct 07 18:13:34 elutuba sudo[2216]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:13:34 elutuba volumio[2134]: info: Output device has changed, restarting MPD Oct 07 18:13:34 elutuba sudo[2216]: pam_unix(sudo:session): session closed for user root Oct 07 18:13:34 elutuba sudo[2218]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 07 18:13:34 elutuba volumio[2134]: info: Output device has changed, restarting Shairport Sync Oct 07 18:13:34 elutuba sudo[2218]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:13:34 elutuba volumio[2134]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:34 elutuba volumio[2134]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 07 18:13:34 elutuba sudo[2221]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 07 18:13:34 elutuba sudo[2218]: pam_unix(sudo:session): session closed for user root Oct 07 18:13:34 elutuba sudo[2221]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:13:34 elutuba sudo[2221]: pam_unix(sudo:session): session closed for user root Oct 07 18:13:34 elutuba sudo[2225]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 07 18:13:34 elutuba sudo[2225]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:13:34 elutuba volumio[2134]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 07 18:13:34 elutuba volumio[2134]: info: ___________ START PLUGINS ___________ Oct 07 18:13:34 elutuba systemd[1]: Stopping Music Player Daemon... Oct 07 18:13:35 elutuba volumio[2134]: info: ControllerMpd::onStart: Initializing MPD Oct 07 18:13:35 elutuba volumio[2134]: info: Creating MPD Configuration file Oct 07 18:13:35 elutuba systemd[1]: mpd.service: Succeeded. Oct 07 18:13:35 elutuba systemd[1]: Stopped Music Player Daemon. Oct 07 18:13:35 elutuba volumio[2134]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 07 18:13:35 elutuba volumio[2134]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 07 18:13:35 elutuba volumio[2134]: info: [1728314015073] CoreMusicLibrary::Adding element Media Servers Oct 07 18:13:35 elutuba volumio[2134]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 07 18:13:35 elutuba sudo[2233]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 07 18:13:35 elutuba sudo[2233]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:13:35 elutuba systemd[1]: Starting Music Player Daemon... Oct 07 18:13:35 elutuba sudo[2233]: pam_unix(sudo:session): session closed for user root Oct 07 18:13:35 elutuba sudo[2234]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 07 18:13:35 elutuba sudo[2234]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:13:35 elutuba systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Oct 07 18:13:35 elutuba systemd[1]: mpd.service: Succeeded. Oct 07 18:13:35 elutuba systemd[1]: Stopped Music Player Daemon. Oct 07 18:13:35 elutuba systemd[1]: Starting Music Player Daemon... Oct 07 18:13:35 elutuba volumio[2134]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:35 elutuba volumio[2134]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 07 18:13:35 elutuba volumio[2134]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 07 18:13:35 elutuba volumio[2134]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:35 elutuba volumio[2134]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 07 18:13:35 elutuba volumio[2134]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 07 18:13:35 elutuba volumio[2134]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 07 18:13:35 elutuba volumio[2134]: info: [1728314015348] CoreMusicLibrary::Adding element Last_100 Oct 07 18:13:35 elutuba volumio[2134]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 07 18:13:35 elutuba volumio[2134]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 07 18:13:35 elutuba volumio[2134]: info: [1728314015356] CoreMusicLibrary::Adding element Webradio Oct 07 18:13:35 elutuba sudo[2237]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 07 18:13:35 elutuba sudo[2237]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:13:35 elutuba volumio[2134]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 07 18:13:35 elutuba volumio[2134]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 07 18:13:35 elutuba sudo[2237]: pam_unix(sudo:session): session closed for user root Oct 07 18:13:35 elutuba volumio[2134]: info: Initializing BBC Radios Oct 07 18:13:35 elutuba volumio[2134]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 07 18:13:35 elutuba volumio[2134]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 07 18:13:35 elutuba volumio[2134]: info: Loading i18n strings for locale en Oct 07 18:13:35 elutuba volumio[2134]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Oct 07 18:13:35 elutuba volumio[2134]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:35 elutuba volumio[2134]: info: FusionDsp - mixtype--------------------- Hardware Oct 07 18:13:35 elutuba volumio[2134]: info: Volumio Calling Home Oct 07 18:13:35 elutuba volumio[2134]: info: Preparing to generate the ALSA configuration file Oct 07 18:13:35 elutuba volumio[2134]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Oct 07 18:13:35 elutuba volumio[2134]: info: Reading ALSA contributions from plugins. Oct 07 18:13:35 elutuba volumio[2134]: info: MPD Permissions set Oct 07 18:13:35 elutuba volumio[2134]: info: MPD Permissions set Oct 07 18:13:35 elutuba volumio[2134]: info: Volumio called home Oct 07 18:13:35 elutuba volumio[2134]: verbose: New Socket.io Connection to 192.168.175.140 from 192.168.175.185 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/128.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Oct 07 18:13:35 elutuba volumio[2134]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Oct 07 18:13:35 elutuba volumio[2134]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:35 elutuba volumio[2134]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:35 elutuba volumio[2134]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:35 elutuba volumio[2134]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:35 elutuba volumio[2134]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:35 elutuba volumio[2134]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:35 elutuba volumio[2134]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:35 elutuba volumio[2134]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:35 elutuba volumio[2134]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 07 18:13:35 elutuba volumio[2134]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:35 elutuba volumio[2134]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:35 elutuba volumio[2134]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:35 elutuba volumio[2134]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:35 elutuba volumio[2134]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:35 elutuba volumio[2134]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:35 elutuba volumio[2134]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:35 elutuba volumio[2134]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:35 elutuba volumio[2134]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:35 elutuba volumio[2134]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 07 18:13:35 elutuba volumio[2134]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:36 elutuba volumio[2134]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 07 18:13:36 elutuba volumio[2134]: info: CoreCommandRouter::volumioGetVisibleSources Oct 07 18:13:36 elutuba volumio[2134]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 07 18:13:36 elutuba volumio[2134]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 07 18:13:36 elutuba volumio[2134]: info: CoreCommandRouter::volumioGetState Oct 07 18:13:36 elutuba volumio[2134]: info: CorePlayQueue::getTrack 0 Oct 07 18:13:36 elutuba volumio[2134]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 07 18:13:36 elutuba volumio[2134]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 07 18:13:36 elutuba volumio[2134]: info: Received Get System Info Oct 07 18:13:36 elutuba volumio[2134]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 07 18:13:36 elutuba volumio[2134]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 07 18:13:36 elutuba volumio[2134]: info: Discovery: Getting this device information Oct 07 18:13:36 elutuba volumio[2134]: info: CoreCommandRouter::volumioGetState Oct 07 18:13:36 elutuba volumio[2134]: info: CorePlayQueue::getTrack 0 Oct 07 18:13:36 elutuba volumio[2134]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 07 18:13:36 elutuba volumio[2134]: info: CoreCommandRouter::volumioGetState Oct 07 18:13:36 elutuba volumio[2134]: info: CorePlayQueue::getTrack 0 Oct 07 18:13:36 elutuba volumio[2134]: info: Listing playlists Oct 07 18:13:36 elutuba volumio[2134]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 07 18:13:36 elutuba volumio[2134]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 07 18:13:36 elutuba volumio[2134]: info: Starting Shairport Sync Oct 07 18:13:36 elutuba volumio[2134]: info: Starting Shairport Sync Oct 07 18:13:36 elutuba volumio[2134]: info: Starting Shairport Sync Oct 07 18:13:36 elutuba sudo[2292]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 07 18:13:36 elutuba sudo[2292]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:13:36 elutuba sudo[2294]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 07 18:13:36 elutuba sudo[2294]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:13:36 elutuba systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 07 18:13:36 elutuba systemd[1]: shairport-sync.service: Succeeded. Oct 07 18:13:36 elutuba systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 07 18:13:36 elutuba volumio[2134]: info: CoreCommandRouter::volumioGetState Oct 07 18:13:36 elutuba volumio[2134]: info: CorePlayQueue::getTrack 0 Oct 07 18:13:36 elutuba sudo[2298]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 07 18:13:36 elutuba sudo[2298]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:13:36 elutuba systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 07 18:13:36 elutuba sudo[2292]: pam_unix(sudo:session): session closed for user root Oct 07 18:13:36 elutuba sudo[2294]: pam_unix(sudo:session): session closed for user root Oct 07 18:13:36 elutuba systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 07 18:13:36 elutuba systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Oct 07 18:13:36 elutuba systemd[1]: shairport-sync.service: Succeeded. Oct 07 18:13:36 elutuba systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 07 18:13:36 elutuba systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 07 18:13:36 elutuba sudo[2298]: pam_unix(sudo:session): session closed for user root Oct 07 18:13:36 elutuba volumio[2134]: info: Asound.conf file unchanged, so no further update is needed Oct 07 18:13:36 elutuba volumio[2134]: info: Output device has changed, restarting MPD Oct 07 18:13:36 elutuba volumio[2134]: info: Output device has changed, restarting Shairport Sync Oct 07 18:13:36 elutuba volumio[2134]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:36 elutuba volumio[2134]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 07 18:13:36 elutuba sudo[2305]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 07 18:13:36 elutuba sudo[2305]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:13:36 elutuba sudo[2305]: pam_unix(sudo:session): session closed for user root Oct 07 18:13:36 elutuba sudo[2307]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 07 18:13:36 elutuba sudo[2307]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:13:36 elutuba systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Oct 07 18:13:36 elutuba systemd[1]: mpd.service: Succeeded. Oct 07 18:13:36 elutuba systemd[1]: Stopped Music Player Daemon. Oct 07 18:13:36 elutuba volumio[2134]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 07 18:13:36 elutuba volumio[2134]: info: Shairport-Sync Started Oct 07 18:13:36 elutuba volumio[2134]: Error adding Membership: Error: addMembership EINVAL Oct 07 18:13:36 elutuba volumio[2134]: info: Shairport-Sync Started Oct 07 18:13:36 elutuba volumio[2134]: info: Shairport-Sync Started Oct 07 18:13:36 elutuba volumio[2134]: info: MPD Permissions set Oct 07 18:13:36 elutuba volumio[2134]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:36 elutuba volumio[2134]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:36 elutuba volumio[2134]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:36 elutuba volumio[2134]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:36 elutuba volumio[2134]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:36 elutuba volumio[2134]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:36 elutuba volumio[2134]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:36 elutuba volumio[2134]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:36 elutuba systemd[1]: Starting Music Player Daemon... Oct 07 18:13:36 elutuba volumio[2134]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 07 18:13:36 elutuba volumio[2134]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:36 elutuba volumio[2134]: info: Starting Shairport Sync Oct 07 18:13:36 elutuba sudo[2319]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 07 18:13:36 elutuba sudo[2319]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:13:36 elutuba systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 07 18:13:36 elutuba systemd[1]: shairport-sync.service: Succeeded. Oct 07 18:13:36 elutuba systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 07 18:13:36 elutuba volumio[2134]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 07 18:13:36 elutuba volumio[2134]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 07 18:13:36 elutuba volumio[2134]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 07 18:13:36 elutuba volumio[2134]: info: Received Get System Info Oct 07 18:13:36 elutuba volumio[2134]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 07 18:13:36 elutuba volumio[2134]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 07 18:13:36 elutuba volumio[2134]: info: Discovery: Getting this device information Oct 07 18:13:36 elutuba volumio[2134]: info: CoreCommandRouter::volumioGetState Oct 07 18:13:36 elutuba volumio[2134]: info: CorePlayQueue::getTrack 0 Oct 07 18:13:36 elutuba volumio[2134]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 07 18:13:36 elutuba sudo[2313]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 07 18:13:36 elutuba systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 07 18:13:36 elutuba sudo[2313]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:13:36 elutuba sudo[2313]: pam_unix(sudo:session): session closed for user root Oct 07 18:13:36 elutuba sudo[2319]: pam_unix(sudo:session): session closed for user root Oct 07 18:13:37 elutuba volumio[2134]: info: Shairport-Sync Started Oct 07 18:13:37 elutuba volumio[2134]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Oct 07 18:13:37 elutuba volumio[2134]: 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 Oct 07 18:13:37 elutuba volumio[2134]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Oct 07 18:13:37 elutuba volumio[2134]: info: camilladsp spawned new process with pid undefined, instance 1, run: true Oct 07 18:13:37 elutuba volumio[2134]: info: camilladsp service started and running in background, instance 1 Oct 07 18:13:37 elutuba volumio[2134]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:37 elutuba volumio[2134]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Oct 07 18:13:37 elutuba volumio[2134]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Oct 07 18:13:37 elutuba volumio[2134]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Oct 07 18:13:37 elutuba volumio[2134]: info: FusionDsp loaded Oct 07 18:13:37 elutuba volumio[2134]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 07 18:13:37 elutuba sudo[2337]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Oct 07 18:13:37 elutuba sudo[2337]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:13:37 elutuba volumio[2134]: info: FusionDsp - Reporting Fusion DSP Enabled Oct 07 18:13:37 elutuba volumio[2134]: info: Adding Signal Path Element [object Object] Oct 07 18:13:37 elutuba volumio[2134]: info: Adding fusiondspeq DSP Signal Path Element Oct 07 18:13:37 elutuba volumio[2134]: info: FusionDsp - ---- installed callbackRead Oct 07 18:13:37 elutuba volumio[2134]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 07 18:13:37 elutuba sudo[2337]: pam_unix(sudo:session): session closed for user root Oct 07 18:13:37 elutuba volumio[2134]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Oct 07 18:13:37 elutuba volumio[2134]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Oct 07 18:13:37 elutuba volumio[2134]: at onErrorNT (internal/child_process.js:465:16) Oct 07 18:13:37 elutuba volumio[2134]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Oct 07 18:13:37 elutuba volumio[2134]: at runNextTicks (internal/process/task_queues.js:62:3) Oct 07 18:13:37 elutuba volumio[2134]: at listOnTimeout (internal/timers.js:523:9) Oct 07 18:13:37 elutuba volumio[2134]: at processTimers (internal/timers.js:497:7) { Oct 07 18:13:37 elutuba volumio[2134]: errno: -2, Oct 07 18:13:37 elutuba volumio[2134]: code: 'ENOENT', Oct 07 18:13:37 elutuba volumio[2134]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Oct 07 18:13:37 elutuba volumio[2134]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Oct 07 18:13:37 elutuba volumio[2134]: spawnargs: [ Oct 07 18:13:37 elutuba volumio[2134]: '-p', Oct 07 18:13:37 elutuba volumio[2134]: 9876, Oct 07 18:13:37 elutuba volumio[2134]: '-o', Oct 07 18:13:37 elutuba volumio[2134]: '/tmp/camilladsp.log', Oct 07 18:13:37 elutuba volumio[2134]: '-l', Oct 07 18:13:37 elutuba volumio[2134]: 'warn', Oct 07 18:13:37 elutuba volumio[2134]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Oct 07 18:13:37 elutuba volumio[2134]: ] Oct 07 18:13:37 elutuba volumio[2134]: } Oct 07 18:13:37 elutuba volumio[2134]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 07 18:13:38 elutuba sudo[2348]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-07 18:12 Oct 07 18:13:38 elutuba sudo[2348]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:13:38 elutuba mpd[2323]: Oct 07 18:13 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 07 18:13:38 elutuba systemd[1]: Started Music Player Daemon. Oct 07 18:13:38 elutuba sudo[2234]: pam_unix(sudo:session): session closed for user root Oct 07 18:13:38 elutuba sudo[2225]: pam_unix(sudo:session): session closed for user root Oct 07 18:13:38 elutuba sudo[2307]: pam_unix(sudo:session): session closed for user root Oct 07 18:13:38 elutuba sudo[2348]: pam_unix(sudo:session): session closed for user root Oct 07 18:13:38 elutuba volumio-remote-updater[629]: [2024-10-07 18:13:38] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Oct 07 18:13:38 elutuba volumio-remote-updater[629]: [2024-10-07 18:13:38] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Oct 07 18:13:38 elutuba systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Oct 07 18:13:38 elutuba systemd[1]: volumio.service: Failed with result 'exit-code'. Oct 07 18:13:38 elutuba systemd[1]: Started dynamicswap service. Oct 07 18:13:38 elutuba systemd[1]: dynamicswap.service: Succeeded. Oct 07 18:13:38 elutuba systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Oct 07 18:13:38 elutuba systemd[1]: volumio.service: Scheduled restart job, restart counter is at 6. Oct 07 18:13:38 elutuba systemd[1]: Started dynamicswap service. Oct 07 18:13:38 elutuba systemd[1]: Stopped Volumio Backend Module. Oct 07 18:13:38 elutuba systemd[1]: Started Volumio Backend Module. Oct 07 18:13:38 elutuba systemd[1]: dynamicswap.service: Succeeded. Oct 07 18:13:40 elutuba volumio[2361]: info: ------------------------------------------- Oct 07 18:13:40 elutuba volumio[2361]: info: ----- Volumio3 ---- Oct 07 18:13:40 elutuba volumio[2361]: info: ------------------------------------------- Oct 07 18:13:40 elutuba volumio[2361]: info: ----- System startup ---- Oct 07 18:13:40 elutuba volumio[2361]: info: ------------------------------------------- Oct 07 18:13:41 elutuba volumio[2361]: info: MYVOLUMIO Environment detected Oct 07 18:13:41 elutuba volumio[2361]: info: Plugin folders cleanup Oct 07 18:13:41 elutuba volumio[2361]: info: Scanning into folder /volumio/app/plugins/ Oct 07 18:13:41 elutuba volumio[2361]: info: Scanning category audio_interface Oct 07 18:13:41 elutuba volumio[2361]: info: Scanning category miscellanea Oct 07 18:13:41 elutuba volumio[2361]: info: Scanning category music_service Oct 07 18:13:41 elutuba volumio[2361]: info: Scanning category plugins.json Oct 07 18:13:41 elutuba volumio[2361]: info: Scanning category system_controller Oct 07 18:13:41 elutuba volumio[2361]: info: Scanning category user_interface Oct 07 18:13:41 elutuba volumio[2361]: info: Scanning into folder /data/plugins/ Oct 07 18:13:41 elutuba volumio[2361]: info: Scanning category audio_interface Oct 07 18:13:41 elutuba volumio[2361]: info: Plugin folders cleanup completed Oct 07 18:13:41 elutuba volumio[2361]: info: ------------------------------------------- Oct 07 18:13:41 elutuba volumio[2361]: info: ----- Core plugins startup ---- Oct 07 18:13:41 elutuba volumio[2361]: info: ------------------------------------------- Oct 07 18:13:41 elutuba volumio[2361]: info: Loading plugins from folder /volumio/app/plugins/ Oct 07 18:13:41 elutuba volumio[2361]: info: Adding plugin upnp to MyMusic Plugins Oct 07 18:13:41 elutuba volumio[2361]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 07 18:13:41 elutuba volumio[2361]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 07 18:13:41 elutuba volumio[2361]: info: Loading plugins from folder /data/plugins/ Oct 07 18:13:41 elutuba volumio[2361]: info: Loading plugin "system"... Oct 07 18:13:41 elutuba volumio[2361]: info: Loading plugin "appearance"... Oct 07 18:13:42 elutuba volumio[2361]: info: Loading plugin "network"... Oct 07 18:13:42 elutuba volumio[2361]: info: Refreshing Cached IP Addresses Oct 07 18:13:42 elutuba sudo[2387]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 07 18:13:42 elutuba sudo[2387]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:13:42 elutuba volumio[2361]: info: Loading plugin "services"... Oct 07 18:13:42 elutuba sudo[2387]: pam_unix(sudo:session): session closed for user root Oct 07 18:13:42 elutuba sudo[2389]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 07 18:13:42 elutuba sudo[2389]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:13:42 elutuba volumio[2361]: info: Loading plugin "alsa_controller"... Oct 07 18:13:42 elutuba sudo[2389]: pam_unix(sudo:session): session closed for user root Oct 07 18:13:42 elutuba sudo[2392]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 07 18:13:42 elutuba sudo[2392]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:13:42 elutuba volumio[2361]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 07 18:13:42 elutuba volumio[2361]: info: Loading plugin "wizard"... Oct 07 18:13:42 elutuba volumio[2361]: info: Loading plugin "networkfs"... Oct 07 18:13:42 elutuba volumio[2361]: info: Starting Udev Watcher for removable devices Oct 07 18:13:42 elutuba volumio[2361]: info: Ignoring mount for partition: boot Oct 07 18:13:42 elutuba volumio[2361]: info: Ignoring mount for partition: volumio Oct 07 18:13:42 elutuba volumio[2361]: info: Ignoring mount for partition: volumio_data Oct 07 18:13:42 elutuba volumio[2361]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 07 18:13:42 elutuba volumio[2361]: info: Loading plugin "volumio_command_line_client"... Oct 07 18:13:42 elutuba volumio[2361]: info: Loading plugin "upnp"... Oct 07 18:13:42 elutuba volumio[2361]: info: [1728314022988] Starting Upmpd Daemon Oct 07 18:13:42 elutuba volumio[2361]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 07 18:13:42 elutuba volumio[2361]: info: Loading plugin "my_music"... Oct 07 18:13:42 elutuba volumio[2361]: info: Loading plugin "mpd"... Oct 07 18:13:43 elutuba volumio[2361]: info: Loading plugin "upnp_browser"... Oct 07 18:13:43 elutuba volumio-remote-updater[629]: [2024-10-07 18:13:43] [connect] Successful connection Oct 07 18:13:43 elutuba volumio[2361]: info: Loading plugin "alarm-clock"... Oct 07 18:13:43 elutuba sudo[2392]: pam_unix(sudo:session): session closed for user root Oct 07 18:13:43 elutuba volumio[2361]: info: Loading plugin "airplay_emulation"... Oct 07 18:13:43 elutuba volumio[2361]: info: Starting Shairport Sync Oct 07 18:13:43 elutuba volumio[2361]: info: Loading plugin "last_100"... Oct 07 18:13:43 elutuba volumio[2361]: info: Loading plugin "webradio"... Oct 07 18:13:44 elutuba volumio[2361]: info: Loading plugin "i2s_dacs"... Oct 07 18:13:44 elutuba volumio[2361]: info: I2S DAC not set, start Auto-detection Oct 07 18:13:44 elutuba volumio[2361]: info: Loading plugin "volumiodiscovery"... Oct 07 18:13:44 elutuba volumio[2361]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 07 18:13:44 elutuba volumio[2361]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 07 18:13:44 elutuba node[2361]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 07 18:13:44 elutuba volumio[2361]: *** WARNING *** For more information see Oct 07 18:13:44 elutuba volumio[2361]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 07 18:13:44 elutuba volumio[2361]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 07 18:13:44 elutuba volumio[2361]: *** WARNING *** For more information see Oct 07 18:13:44 elutuba node[2361]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 07 18:13:44 elutuba node[2361]: *** WARNING *** For more information see Oct 07 18:13:44 elutuba node[2361]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 07 18:13:44 elutuba node[2361]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 07 18:13:44 elutuba node[2361]: *** WARNING *** For more information see Oct 07 18:13:44 elutuba volumio[2361]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 07 18:13:44 elutuba volumio[2361]: info: Discovery: Started advertising with name: Elutuba Oct 07 18:13:44 elutuba volumio[2361]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 07 18:13:44 elutuba volumio[2361]: info: Loading plugin "outputs"... Oct 07 18:13:44 elutuba volumio[2361]: info: Loading plugin "albumart"... Oct 07 18:13:44 elutuba volumio[2361]: info: Plugin example_plugin is not enabled Oct 07 18:13:44 elutuba volumio[2361]: info: Loading plugin "inputs"... Oct 07 18:13:44 elutuba volumio[2361]: info: Loading plugin "updater_comm"... Oct 07 18:13:44 elutuba volumio[2361]: info: Plugin mpdemulation is not enabled Oct 07 18:13:44 elutuba volumio[2361]: info: Loading plugin "rest_api"... Oct 07 18:13:44 elutuba volumio[2361]: info: Loading plugin "websocket"... Oct 07 18:13:44 elutuba volumio[2361]: info: Starting Socket.io Server version 2.3.0 Oct 07 18:13:44 elutuba volumio[2361]: info: Loading plugin "fusiondsp"... Oct 07 18:13:44 elutuba volumio[2361]: Forking 3 albumart workers Oct 07 18:13:44 elutuba volumio[2361]: info: Applying required configuration parameters for plugin fusiondsp Oct 07 18:13:44 elutuba volumio[2361]: info: Loading i18n strings for locale en Oct 07 18:13:45 elutuba volumio[2361]: Updating browse sources language Oct 07 18:13:45 elutuba volumio[2361]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 07 18:13:45 elutuba volumio[2361]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 07 18:13:45 elutuba volumio[2361]: info: CoreCommandRouter::initPlayerControls Oct 07 18:13:45 elutuba volumio[2361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:45 elutuba volumio[2361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:45 elutuba volumio[2361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:45 elutuba volumio[2361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:45 elutuba volumio[2361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:45 elutuba volumio[2361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:45 elutuba volumio[2361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:45 elutuba volumio[2361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:45 elutuba volumio[2361]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 07 18:13:45 elutuba volumio[2361]: Express server listening on port 3000 Oct 07 18:13:45 elutuba volumio[2361]: [Metrics] WebUI: 5s 172.75ms Oct 07 18:13:45 elutuba volumio[2361]: info: CoreStateMachine::resetVolumioState Oct 07 18:13:45 elutuba volumio[2361]: info: CoreStateMachine::getcurrentVolume Oct 07 18:13:45 elutuba volumio[2361]: info: CoreCommandRouter::volumioRetrievevolume Oct 07 18:13:45 elutuba volumio[2361]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 07 18:13:45 elutuba volumio[2361]: info: Cannot read play queue from file Oct 07 18:13:45 elutuba volumio[2361]: verbose: New Socket.io Connection to 192.168.175.140 from 192.168.175.185 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/128.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Oct 07 18:13:45 elutuba volumio[2361]: verbose: New Socket.io Connection to 192.168.175.140 from 192.168.175.185 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/128.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Oct 07 18:13:45 elutuba volumio-remote-updater[629]: [2024-10-07 18:13:45] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1728314023 101 Oct 07 18:13:45 elutuba volumio[2361]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 3 Oct 07 18:13:45 elutuba volumio[2361]: info: CoreStateMachine::setRepeat null single undefined Oct 07 18:13:45 elutuba volumio[2361]: info: CoreStateMachine::pushState Oct 07 18:13:45 elutuba volumio[2361]: info: CorePlayQueue::getTrack 0 Oct 07 18:13:45 elutuba volumio[2361]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 07 18:13:45 elutuba volumio[2361]: info: CoreCommandRouter::volumioPushState Oct 07 18:13:45 elutuba volumio[2361]: info: CoreStateMachine::setRandom null Oct 07 18:13:45 elutuba volumio[2361]: info: CoreStateMachine::pushState Oct 07 18:13:45 elutuba volumio[2361]: info: CorePlayQueue::getTrack 0 Oct 07 18:13:45 elutuba volumio[2361]: info: CoreCommandRouter::volumioPushState Oct 07 18:13:45 elutuba volumio[2361]: Starting albumart workers Oct 07 18:13:45 elutuba volumio[2361]: info: Setting Device type: Raspberry PI Oct 07 18:13:45 elutuba volumio[2361]: Starting albumart workers Oct 07 18:13:45 elutuba volumio[2361]: info: VolumeController:: Volume=0 Mute =false Oct 07 18:13:45 elutuba volumio[2361]: info: CoreStateMachine::pushState Oct 07 18:13:45 elutuba volumio[2361]: info: CorePlayQueue::getTrack 0 Oct 07 18:13:45 elutuba volumio[2361]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 07 18:13:45 elutuba volumio[2361]: info: CoreCommandRouter::volumioPushState Oct 07 18:13:45 elutuba volumio[2361]: info: CoreStateMachine::updateTrackBlock Oct 07 18:13:45 elutuba volumio[2361]: info: CorePlayQueue::getTrackBlock Oct 07 18:13:45 elutuba volumio[2361]: Starting albumart workers Oct 07 18:13:45 elutuba volumio[2361]: info: CoreCommandRouter::volumioRetrievevolume Oct 07 18:13:45 elutuba volumio[2361]: info: Completed loading Core Plugins Oct 07 18:13:45 elutuba volumio[2361]: info: Preparing to generate the ALSA configuration file Oct 07 18:13:45 elutuba volumio[2361]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Oct 07 18:13:45 elutuba volumio[2361]: info: Reading ALSA contributions from plugins. Oct 07 18:13:45 elutuba volumio[2361]: info: Asound.conf file unchanged, so no further update is needed Oct 07 18:13:45 elutuba volumio[2361]: info: Output device has changed, restarting MPD Oct 07 18:13:46 elutuba sudo[2442]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 07 18:13:46 elutuba sudo[2442]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:13:46 elutuba volumio[2361]: info: Output device has changed, restarting Shairport Sync Oct 07 18:13:46 elutuba volumio[2361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:46 elutuba volumio[2361]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 07 18:13:46 elutuba sudo[2442]: pam_unix(sudo:session): session closed for user root Oct 07 18:13:46 elutuba sudo[2444]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 07 18:13:46 elutuba sudo[2444]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:13:46 elutuba systemd[1]: Stopping Music Player Daemon... Oct 07 18:13:46 elutuba systemd[1]: mpd.service: Succeeded. Oct 07 18:13:46 elutuba systemd[1]: Stopped Music Player Daemon. Oct 07 18:13:46 elutuba volumio[2361]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 07 18:13:46 elutuba systemd[1]: Starting Music Player Daemon... Oct 07 18:13:46 elutuba volumio[2361]: info: ___________ START PLUGINS ___________ Oct 07 18:13:46 elutuba volumio[2361]: info: ControllerMpd::onStart: Initializing MPD Oct 07 18:13:46 elutuba volumio[2361]: info: Creating MPD Configuration file Oct 07 18:13:46 elutuba sudo[2452]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 07 18:13:46 elutuba sudo[2452]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:13:46 elutuba sudo[2452]: pam_unix(sudo:session): session closed for user root Oct 07 18:13:46 elutuba volumio[2361]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 07 18:13:46 elutuba volumio[2361]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 07 18:13:46 elutuba volumio[2361]: info: [1728314026370] CoreMusicLibrary::Adding element Media Servers Oct 07 18:13:46 elutuba volumio[2361]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 07 18:13:46 elutuba sudo[2450]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 07 18:13:46 elutuba sudo[2450]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:13:46 elutuba sudo[2450]: pam_unix(sudo:session): session closed for user root Oct 07 18:13:46 elutuba sudo[2455]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 07 18:13:46 elutuba sudo[2455]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:13:46 elutuba volumio[2361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:46 elutuba volumio[2361]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 07 18:13:46 elutuba systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Oct 07 18:13:46 elutuba systemd[1]: mpd.service: Succeeded. Oct 07 18:13:46 elutuba systemd[1]: Stopped Music Player Daemon. Oct 07 18:13:46 elutuba systemd[1]: Starting Music Player Daemon... Oct 07 18:13:46 elutuba volumio[2361]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 07 18:13:46 elutuba volumio[2361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:46 elutuba volumio[2361]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 07 18:13:46 elutuba sudo[2462]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 07 18:13:46 elutuba sudo[2462]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:13:46 elutuba sudo[2462]: pam_unix(sudo:session): session closed for user root Oct 07 18:13:46 elutuba volumio[2361]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 07 18:13:46 elutuba volumio[2361]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 07 18:13:46 elutuba volumio[2361]: info: [1728314026789] CoreMusicLibrary::Adding element Last_100 Oct 07 18:13:46 elutuba volumio[2361]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 07 18:13:46 elutuba volumio[2361]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 07 18:13:46 elutuba volumio[2361]: info: [1728314026798] CoreMusicLibrary::Adding element Webradio Oct 07 18:13:46 elutuba volumio[2361]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 07 18:13:46 elutuba volumio[2361]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 07 18:13:46 elutuba volumio[2361]: info: Initializing BBC Radios Oct 07 18:13:46 elutuba volumio[2361]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 07 18:13:46 elutuba volumio[2361]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 07 18:13:46 elutuba volumio[2361]: info: Loading i18n strings for locale en Oct 07 18:13:46 elutuba volumio[2361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Oct 07 18:13:46 elutuba volumio[2361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:46 elutuba volumio[2361]: info: FusionDsp - mixtype--------------------- Hardware Oct 07 18:13:46 elutuba volumio[2361]: info: Volumio Calling Home Oct 07 18:13:47 elutuba volumio[2361]: info: Preparing to generate the ALSA configuration file Oct 07 18:13:47 elutuba volumio[2361]: info: Discovery: adding 320db26b-9bed-4586-9e4d-ee23fdd56bae Oct 07 18:13:47 elutuba volumio[2361]: info: Discovery: Found device Elutuba Oct 07 18:13:47 elutuba volumio[2361]: info: CoreCommandRouter::volumioGetState Oct 07 18:13:47 elutuba volumio[2361]: info: CorePlayQueue::getTrack 0 Oct 07 18:13:47 elutuba volumio[2361]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Oct 07 18:13:47 elutuba volumio[2361]: info: Reading ALSA contributions from plugins. Oct 07 18:13:47 elutuba volumio[2361]: info: MPD Permissions set Oct 07 18:13:47 elutuba volumio[2361]: info: MPD Permissions set Oct 07 18:13:47 elutuba volumio[2361]: info: VolumeController:: Volume=0 Mute =false Oct 07 18:13:47 elutuba volumio[2361]: info: CoreStateMachine::pushState Oct 07 18:13:47 elutuba volumio[2361]: info: CorePlayQueue::getTrack 0 Oct 07 18:13:47 elutuba volumio[2361]: info: CoreCommandRouter::volumioPushState Oct 07 18:13:47 elutuba volumio[2361]: info: Volumio called home Oct 07 18:13:47 elutuba volumio[2361]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Oct 07 18:13:47 elutuba volumio[2361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:47 elutuba volumio[2361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:47 elutuba volumio[2361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:47 elutuba volumio[2361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:47 elutuba volumio[2361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:47 elutuba volumio[2361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:47 elutuba volumio[2361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:47 elutuba volumio[2361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:47 elutuba volumio[2361]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 07 18:13:47 elutuba volumio[2361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:47 elutuba volumio[2361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:47 elutuba volumio[2361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:47 elutuba volumio[2361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:47 elutuba volumio[2361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:47 elutuba volumio[2361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:47 elutuba volumio[2361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:47 elutuba volumio[2361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:47 elutuba volumio[2361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:47 elutuba volumio[2361]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 07 18:13:47 elutuba volumio[2361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:47 elutuba volumio[2361]: info: Starting Shairport Sync Oct 07 18:13:47 elutuba volumio[2361]: info: Starting Shairport Sync Oct 07 18:13:47 elutuba volumio[2361]: info: Starting Shairport Sync Oct 07 18:13:47 elutuba sudo[2514]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 07 18:13:47 elutuba sudo[2514]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:13:47 elutuba sudo[2516]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 07 18:13:47 elutuba sudo[2516]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:13:47 elutuba systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 07 18:13:47 elutuba systemd[1]: shairport-sync.service: Succeeded. Oct 07 18:13:47 elutuba volumio[2361]: info: CoreCommandRouter::volumioGetState Oct 07 18:13:47 elutuba systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 07 18:13:47 elutuba volumio[2361]: info: CorePlayQueue::getTrack 0 Oct 07 18:13:47 elutuba sudo[2519]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 07 18:13:47 elutuba sudo[2519]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:13:47 elutuba volumio[2361]: info: Asound.conf file unchanged, so no further update is needed Oct 07 18:13:47 elutuba volumio[2361]: info: Output device has changed, restarting MPD Oct 07 18:13:47 elutuba systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 07 18:13:47 elutuba sudo[2514]: pam_unix(sudo:session): session closed for user root Oct 07 18:13:47 elutuba sudo[2524]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 07 18:13:47 elutuba sudo[2524]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:13:47 elutuba sudo[2524]: pam_unix(sudo:session): session closed for user root Oct 07 18:13:47 elutuba systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 07 18:13:47 elutuba systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Oct 07 18:13:47 elutuba systemd[1]: shairport-sync.service: Succeeded. Oct 07 18:13:47 elutuba systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 07 18:13:47 elutuba volumio[2361]: info: Output device has changed, restarting Shairport Sync Oct 07 18:13:47 elutuba volumio[2361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:47 elutuba volumio[2361]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 07 18:13:47 elutuba systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 07 18:13:47 elutuba sudo[2519]: pam_unix(sudo:session): session closed for user root Oct 07 18:13:47 elutuba sudo[2516]: pam_unix(sudo:session): session closed for user root Oct 07 18:13:47 elutuba sudo[2527]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 07 18:13:47 elutuba sudo[2527]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:13:47 elutuba systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Oct 07 18:13:47 elutuba systemd[1]: mpd.service: Succeeded. Oct 07 18:13:47 elutuba systemd[1]: Stopped Music Player Daemon. Oct 07 18:13:47 elutuba volumio[2361]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 07 18:13:47 elutuba volumio[2361]: info: MPD Permissions set Oct 07 18:13:47 elutuba volumio[2361]: info: Shairport-Sync Started Oct 07 18:13:47 elutuba systemd[1]: Starting Music Player Daemon... Oct 07 18:13:47 elutuba volumio[2361]: Error adding Membership: Error: addMembership EINVAL Oct 07 18:13:47 elutuba volumio[2361]: info: Shairport-Sync Started Oct 07 18:13:47 elutuba volumio[2361]: info: Shairport-Sync Started Oct 07 18:13:47 elutuba volumio[2361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:47 elutuba volumio[2361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:47 elutuba volumio[2361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:47 elutuba volumio[2361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:47 elutuba volumio[2361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:47 elutuba volumio[2361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:47 elutuba volumio[2361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:47 elutuba volumio[2361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:47 elutuba volumio[2361]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 07 18:13:47 elutuba volumio[2361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:48 elutuba volumio[2361]: info: Starting Shairport Sync Oct 07 18:13:48 elutuba volumio[2361]: verbose: New Socket.io Connection to 192.168.175.140 from 192.168.175.185 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/128.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Oct 07 18:13:48 elutuba sudo[2541]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 07 18:13:48 elutuba sudo[2541]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:13:48 elutuba volumio[2361]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 07 18:13:48 elutuba systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 07 18:13:48 elutuba systemd[1]: shairport-sync.service: Succeeded. Oct 07 18:13:48 elutuba systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 07 18:13:48 elutuba sudo[2535]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 07 18:13:48 elutuba sudo[2535]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:13:48 elutuba volumio[2361]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 07 18:13:48 elutuba volumio[2361]: info: CoreCommandRouter::volumioGetVisibleSources Oct 07 18:13:48 elutuba volumio[2361]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 07 18:13:48 elutuba volumio[2361]: info: CoreCommandRouter::volumioGetState Oct 07 18:13:48 elutuba volumio[2361]: info: CorePlayQueue::getTrack 0 Oct 07 18:13:48 elutuba volumio[2361]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 07 18:13:48 elutuba volumio[2361]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 07 18:13:48 elutuba volumio[2361]: info: Received Get System Info Oct 07 18:13:48 elutuba volumio[2361]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 07 18:13:48 elutuba volumio[2361]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 07 18:13:48 elutuba volumio[2361]: info: Discovery: Getting this device information Oct 07 18:13:48 elutuba volumio[2361]: info: CoreCommandRouter::volumioGetState Oct 07 18:13:48 elutuba volumio[2361]: info: CorePlayQueue::getTrack 0 Oct 07 18:13:48 elutuba volumio[2361]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 07 18:13:48 elutuba volumio[2361]: info: CoreCommandRouter::volumioGetState Oct 07 18:13:48 elutuba volumio[2361]: info: CorePlayQueue::getTrack 0 Oct 07 18:13:48 elutuba volumio[2361]: info: Listing playlists Oct 07 18:13:48 elutuba sudo[2535]: pam_unix(sudo:session): session closed for user root Oct 07 18:13:48 elutuba systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 07 18:13:48 elutuba volumio[2361]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 07 18:13:48 elutuba volumio[2361]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 07 18:13:48 elutuba sudo[2541]: pam_unix(sudo:session): session closed for user root Oct 07 18:13:48 elutuba volumio[2361]: info: Shairport-Sync Started Oct 07 18:13:49 elutuba volumio[2361]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Oct 07 18:13:49 elutuba volumio[2361]: 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 Oct 07 18:13:49 elutuba volumio[2361]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Oct 07 18:13:49 elutuba volumio[2361]: info: camilladsp spawned new process with pid undefined, instance 1, run: true Oct 07 18:13:49 elutuba volumio[2361]: info: camilladsp service started and running in background, instance 1 Oct 07 18:13:49 elutuba volumio[2361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:49 elutuba volumio[2361]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Oct 07 18:13:49 elutuba volumio[2361]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Oct 07 18:13:49 elutuba volumio[2361]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Oct 07 18:13:49 elutuba volumio[2361]: info: FusionDsp loaded Oct 07 18:13:49 elutuba volumio[2361]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 07 18:13:49 elutuba sudo[2559]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Oct 07 18:13:49 elutuba sudo[2559]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:13:49 elutuba volumio[2361]: info: FusionDsp - Reporting Fusion DSP Enabled Oct 07 18:13:49 elutuba volumio[2361]: info: Adding Signal Path Element [object Object] Oct 07 18:13:49 elutuba volumio[2361]: info: Adding fusiondspeq DSP Signal Path Element Oct 07 18:13:49 elutuba volumio[2361]: info: FusionDsp - ---- installed callbackRead Oct 07 18:13:49 elutuba volumio[2361]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 07 18:13:49 elutuba sudo[2559]: pam_unix(sudo:session): session closed for user root Oct 07 18:13:49 elutuba volumio[2361]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Oct 07 18:13:49 elutuba volumio[2361]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Oct 07 18:13:49 elutuba volumio[2361]: at onErrorNT (internal/child_process.js:465:16) Oct 07 18:13:49 elutuba volumio[2361]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Oct 07 18:13:49 elutuba volumio[2361]: errno: -2, Oct 07 18:13:49 elutuba volumio[2361]: code: 'ENOENT', Oct 07 18:13:49 elutuba volumio[2361]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Oct 07 18:13:49 elutuba volumio[2361]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Oct 07 18:13:49 elutuba volumio[2361]: spawnargs: [ Oct 07 18:13:49 elutuba volumio[2361]: '-p', Oct 07 18:13:49 elutuba volumio[2361]: 9876, Oct 07 18:13:49 elutuba volumio[2361]: '-o', Oct 07 18:13:49 elutuba volumio[2361]: '/tmp/camilladsp.log', Oct 07 18:13:49 elutuba volumio[2361]: '-l', Oct 07 18:13:49 elutuba volumio[2361]: 'warn', Oct 07 18:13:49 elutuba volumio[2361]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Oct 07 18:13:49 elutuba volumio[2361]: ] Oct 07 18:13:49 elutuba volumio[2361]: } Oct 07 18:13:49 elutuba volumio[2361]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 07 18:13:49 elutuba sudo[2570]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-07 18:12 Oct 07 18:13:49 elutuba sudo[2570]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:13:49 elutuba mpd[2545]: Oct 07 18:13 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 07 18:13:49 elutuba systemd[1]: Started Music Player Daemon. Oct 07 18:13:49 elutuba sudo[2444]: pam_unix(sudo:session): session closed for user root Oct 07 18:13:49 elutuba sudo[2527]: pam_unix(sudo:session): session closed for user root Oct 07 18:13:49 elutuba sudo[2455]: pam_unix(sudo:session): session closed for user root Oct 07 18:13:49 elutuba sudo[2570]: pam_unix(sudo:session): session closed for user root Oct 07 18:13:49 elutuba volumio-remote-updater[629]: [2024-10-07 18:13:49] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Oct 07 18:13:49 elutuba volumio-remote-updater[629]: [2024-10-07 18:13:49] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Oct 07 18:13:49 elutuba systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Oct 07 18:13:49 elutuba systemd[1]: volumio.service: Failed with result 'exit-code'. Oct 07 18:13:50 elutuba systemd[1]: Started dynamicswap service. Oct 07 18:13:50 elutuba systemd[1]: dynamicswap.service: Succeeded. Oct 07 18:13:50 elutuba systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Oct 07 18:13:50 elutuba systemd[1]: volumio.service: Scheduled restart job, restart counter is at 7. Oct 07 18:13:50 elutuba systemd[1]: Started dynamicswap service. Oct 07 18:13:50 elutuba systemd[1]: Stopped Volumio Backend Module. Oct 07 18:13:50 elutuba systemd[1]: Started Volumio Backend Module. Oct 07 18:13:50 elutuba systemd[1]: dynamicswap.service: Succeeded. Oct 07 18:13:52 elutuba volumio[2583]: info: ------------------------------------------- Oct 07 18:13:52 elutuba volumio[2583]: info: ----- Volumio3 ---- Oct 07 18:13:52 elutuba volumio[2583]: info: ------------------------------------------- Oct 07 18:13:52 elutuba volumio[2583]: info: ----- System startup ---- Oct 07 18:13:52 elutuba volumio[2583]: info: ------------------------------------------- Oct 07 18:13:53 elutuba volumio[2583]: info: MYVOLUMIO Environment detected Oct 07 18:13:53 elutuba volumio[2583]: info: Plugin folders cleanup Oct 07 18:13:53 elutuba volumio[2583]: info: Scanning into folder /volumio/app/plugins/ Oct 07 18:13:53 elutuba volumio[2583]: info: Scanning category audio_interface Oct 07 18:13:53 elutuba volumio[2583]: info: Scanning category miscellanea Oct 07 18:13:53 elutuba volumio[2583]: info: Scanning category music_service Oct 07 18:13:53 elutuba volumio[2583]: info: Scanning category plugins.json Oct 07 18:13:53 elutuba volumio[2583]: info: Scanning category system_controller Oct 07 18:13:53 elutuba volumio[2583]: info: Scanning category user_interface Oct 07 18:13:53 elutuba volumio[2583]: info: Scanning into folder /data/plugins/ Oct 07 18:13:53 elutuba volumio[2583]: info: Scanning category audio_interface Oct 07 18:13:53 elutuba volumio[2583]: info: Plugin folders cleanup completed Oct 07 18:13:53 elutuba volumio[2583]: info: ------------------------------------------- Oct 07 18:13:53 elutuba volumio[2583]: info: ----- Core plugins startup ---- Oct 07 18:13:53 elutuba volumio[2583]: info: ------------------------------------------- Oct 07 18:13:53 elutuba volumio[2583]: info: Loading plugins from folder /volumio/app/plugins/ Oct 07 18:13:53 elutuba volumio[2583]: info: Adding plugin upnp to MyMusic Plugins Oct 07 18:13:53 elutuba volumio[2583]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 07 18:13:53 elutuba volumio[2583]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 07 18:13:53 elutuba volumio[2583]: info: Loading plugins from folder /data/plugins/ Oct 07 18:13:53 elutuba volumio[2583]: info: Loading plugin "system"... Oct 07 18:13:53 elutuba volumio[2583]: info: Loading plugin "appearance"... Oct 07 18:13:54 elutuba volumio[2583]: info: Loading plugin "network"... Oct 07 18:13:54 elutuba volumio[2583]: info: Refreshing Cached IP Addresses Oct 07 18:13:54 elutuba sudo[2609]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 07 18:13:54 elutuba sudo[2609]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:13:54 elutuba sudo[2609]: pam_unix(sudo:session): session closed for user root Oct 07 18:13:54 elutuba sudo[2611]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 07 18:13:54 elutuba volumio[2583]: info: Loading plugin "services"... Oct 07 18:13:54 elutuba sudo[2611]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:13:54 elutuba volumio[2583]: info: Loading plugin "alsa_controller"... Oct 07 18:13:54 elutuba sudo[2611]: pam_unix(sudo:session): session closed for user root Oct 07 18:13:54 elutuba sudo[2614]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 07 18:13:54 elutuba sudo[2614]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:13:54 elutuba volumio[2583]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 07 18:13:54 elutuba volumio[2583]: info: Loading plugin "wizard"... Oct 07 18:13:54 elutuba volumio[2583]: info: Loading plugin "networkfs"... Oct 07 18:13:54 elutuba volumio[2583]: info: Starting Udev Watcher for removable devices Oct 07 18:13:54 elutuba volumio[2583]: info: Ignoring mount for partition: boot Oct 07 18:13:54 elutuba volumio[2583]: info: Ignoring mount for partition: volumio Oct 07 18:13:54 elutuba volumio[2583]: info: Ignoring mount for partition: volumio_data Oct 07 18:13:54 elutuba volumio[2583]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 07 18:13:54 elutuba volumio[2583]: info: Loading plugin "volumio_command_line_client"... Oct 07 18:13:54 elutuba volumio[2583]: info: Loading plugin "upnp"... Oct 07 18:13:54 elutuba volumio[2583]: info: [1728314034537] Starting Upmpd Daemon Oct 07 18:13:54 elutuba volumio[2583]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 07 18:13:54 elutuba volumio[2583]: info: Loading plugin "my_music"... Oct 07 18:13:54 elutuba volumio[2583]: info: Loading plugin "mpd"... Oct 07 18:13:54 elutuba volumio[2583]: info: Loading plugin "upnp_browser"... Oct 07 18:13:54 elutuba volumio-remote-updater[629]: [2024-10-07 18:13:54] [connect] Successful connection Oct 07 18:13:55 elutuba volumio[2583]: info: Loading plugin "alarm-clock"... Oct 07 18:13:55 elutuba sudo[2614]: pam_unix(sudo:session): session closed for user root Oct 07 18:13:55 elutuba volumio[2583]: info: Loading plugin "airplay_emulation"... Oct 07 18:13:55 elutuba volumio[2583]: info: Starting Shairport Sync Oct 07 18:13:55 elutuba volumio[2583]: info: Loading plugin "last_100"... Oct 07 18:13:55 elutuba volumio[2583]: info: Loading plugin "webradio"... Oct 07 18:13:55 elutuba volumio[2583]: info: Loading plugin "i2s_dacs"... Oct 07 18:13:55 elutuba volumio[2583]: info: I2S DAC not set, start Auto-detection Oct 07 18:13:55 elutuba volumio[2583]: info: Loading plugin "volumiodiscovery"... Oct 07 18:13:55 elutuba volumio[2583]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 07 18:13:55 elutuba volumio[2583]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 07 18:13:55 elutuba volumio[2583]: *** WARNING *** For more information see Oct 07 18:13:55 elutuba volumio[2583]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 07 18:13:55 elutuba node[2583]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 07 18:13:55 elutuba volumio[2583]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 07 18:13:55 elutuba volumio[2583]: *** WARNING *** For more information see Oct 07 18:13:55 elutuba node[2583]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 07 18:13:55 elutuba node[2583]: *** WARNING *** For more information see Oct 07 18:13:55 elutuba node[2583]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 07 18:13:55 elutuba node[2583]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 07 18:13:55 elutuba node[2583]: *** WARNING *** For more information see Oct 07 18:13:55 elutuba volumio[2583]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 07 18:13:55 elutuba volumio[2583]: info: Discovery: Started advertising with name: Elutuba Oct 07 18:13:55 elutuba volumio[2583]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 07 18:13:55 elutuba volumio[2583]: info: Loading plugin "outputs"... Oct 07 18:13:55 elutuba volumio[2583]: info: Loading plugin "albumart"... Oct 07 18:13:55 elutuba volumio[2583]: info: Plugin example_plugin is not enabled Oct 07 18:13:55 elutuba volumio[2583]: info: Loading plugin "inputs"... Oct 07 18:13:55 elutuba volumio[2583]: info: Loading plugin "updater_comm"... Oct 07 18:13:55 elutuba volumio[2583]: info: Plugin mpdemulation is not enabled Oct 07 18:13:55 elutuba volumio[2583]: info: Loading plugin "rest_api"... Oct 07 18:13:55 elutuba volumio[2583]: info: Loading plugin "websocket"... Oct 07 18:13:56 elutuba volumio[2583]: info: Starting Socket.io Server version 2.3.0 Oct 07 18:13:56 elutuba volumio[2583]: info: Loading plugin "fusiondsp"... Oct 07 18:13:56 elutuba volumio[2583]: Forking 3 albumart workers Oct 07 18:13:56 elutuba volumio[2583]: info: Applying required configuration parameters for plugin fusiondsp Oct 07 18:13:56 elutuba volumio[2583]: info: Loading i18n strings for locale en Oct 07 18:13:56 elutuba volumio[2583]: Updating browse sources language Oct 07 18:13:56 elutuba volumio[2583]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 07 18:13:56 elutuba volumio[2583]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 07 18:13:56 elutuba volumio[2583]: info: CoreCommandRouter::initPlayerControls Oct 07 18:13:56 elutuba volumio[2583]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:56 elutuba volumio[2583]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:56 elutuba volumio[2583]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:56 elutuba volumio[2583]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:56 elutuba volumio[2583]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:56 elutuba volumio[2583]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:56 elutuba volumio[2583]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:56 elutuba volumio[2583]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:56 elutuba volumio[2583]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 07 18:13:56 elutuba volumio[2583]: Express server listening on port 3000 Oct 07 18:13:56 elutuba volumio[2583]: [Metrics] WebUI: 5s 225.88ms Oct 07 18:13:56 elutuba volumio[2583]: info: CoreStateMachine::resetVolumioState Oct 07 18:13:56 elutuba volumio[2583]: info: CoreStateMachine::getcurrentVolume Oct 07 18:13:56 elutuba volumio[2583]: info: CoreCommandRouter::volumioRetrievevolume Oct 07 18:13:56 elutuba volumio[2583]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 07 18:13:57 elutuba volumio[2583]: info: Cannot read play queue from file Oct 07 18:13:57 elutuba volumio[2583]: verbose: New Socket.io Connection to 192.168.175.140 from 192.168.175.185 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/128.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Oct 07 18:13:57 elutuba volumio[2583]: verbose: New Socket.io Connection to 192.168.175.140 from 192.168.175.185 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/128.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Oct 07 18:13:57 elutuba volumio-remote-updater[629]: [2024-10-07 18:13:57] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1728314034 101 Oct 07 18:13:57 elutuba volumio[2583]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 3 Oct 07 18:13:57 elutuba volumio[2583]: Starting albumart workers Oct 07 18:13:57 elutuba volumio[2583]: info: CoreStateMachine::setRepeat null single undefined Oct 07 18:13:57 elutuba volumio[2583]: info: CoreStateMachine::pushState Oct 07 18:13:57 elutuba volumio[2583]: info: CorePlayQueue::getTrack 0 Oct 07 18:13:57 elutuba volumio[2583]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 07 18:13:57 elutuba volumio[2583]: info: CoreCommandRouter::volumioPushState Oct 07 18:13:57 elutuba volumio[2583]: Starting albumart workers Oct 07 18:13:57 elutuba volumio[2583]: info: CoreStateMachine::setRandom null Oct 07 18:13:57 elutuba volumio[2583]: info: CoreStateMachine::pushState Oct 07 18:13:57 elutuba volumio[2583]: info: CorePlayQueue::getTrack 0 Oct 07 18:13:57 elutuba volumio[2583]: info: CoreCommandRouter::volumioPushState Oct 07 18:13:57 elutuba volumio[2583]: info: Setting Device type: Raspberry PI Oct 07 18:13:57 elutuba volumio[2583]: info: VolumeController:: Volume=0 Mute =false Oct 07 18:13:57 elutuba volumio[2583]: info: CoreStateMachine::pushState Oct 07 18:13:57 elutuba volumio[2583]: info: CorePlayQueue::getTrack 0 Oct 07 18:13:57 elutuba volumio[2583]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 07 18:13:57 elutuba volumio[2583]: info: CoreCommandRouter::volumioPushState Oct 07 18:13:57 elutuba volumio[2583]: info: CoreStateMachine::updateTrackBlock Oct 07 18:13:57 elutuba volumio[2583]: info: CorePlayQueue::getTrackBlock Oct 07 18:13:57 elutuba volumio[2583]: info: CoreCommandRouter::volumioRetrievevolume Oct 07 18:13:57 elutuba volumio[2583]: info: Completed loading Core Plugins Oct 07 18:13:57 elutuba volumio[2583]: info: Preparing to generate the ALSA configuration file Oct 07 18:13:57 elutuba volumio[2583]: Starting albumart workers Oct 07 18:13:57 elutuba volumio[2583]: info: Discovery: adding 320db26b-9bed-4586-9e4d-ee23fdd56bae Oct 07 18:13:57 elutuba volumio[2583]: info: Discovery: Found device Elutuba Oct 07 18:13:57 elutuba volumio[2583]: info: CoreCommandRouter::volumioGetState Oct 07 18:13:57 elutuba volumio[2583]: info: CorePlayQueue::getTrack 0 Oct 07 18:13:57 elutuba volumio[2583]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Oct 07 18:13:57 elutuba volumio[2583]: info: Reading ALSA contributions from plugins. Oct 07 18:13:57 elutuba volumio[2583]: info: Asound.conf file unchanged, so no further update is needed Oct 07 18:13:57 elutuba volumio[2583]: info: Output device has changed, restarting MPD Oct 07 18:13:57 elutuba volumio[2583]: info: Output device has changed, restarting Shairport Sync Oct 07 18:13:57 elutuba volumio[2583]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:57 elutuba volumio[2583]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 07 18:13:57 elutuba sudo[2666]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 07 18:13:57 elutuba sudo[2666]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:13:57 elutuba sudo[2666]: pam_unix(sudo:session): session closed for user root Oct 07 18:13:57 elutuba sudo[2668]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 07 18:13:57 elutuba sudo[2668]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:13:57 elutuba volumio[2583]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 07 18:13:57 elutuba volumio[2583]: info: ___________ START PLUGINS ___________ Oct 07 18:13:57 elutuba systemd[1]: Stopping Music Player Daemon... Oct 07 18:13:57 elutuba volumio[2583]: info: ControllerMpd::onStart: Initializing MPD Oct 07 18:13:57 elutuba systemd[1]: mpd.service: Succeeded. Oct 07 18:13:57 elutuba volumio[2583]: info: Creating MPD Configuration file Oct 07 18:13:57 elutuba systemd[1]: Stopped Music Player Daemon. Oct 07 18:13:57 elutuba volumio[2583]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 07 18:13:57 elutuba volumio[2583]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 07 18:13:57 elutuba volumio[2583]: info: [1728314037893] CoreMusicLibrary::Adding element Media Servers Oct 07 18:13:57 elutuba volumio[2583]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 07 18:13:57 elutuba systemd[1]: Starting Music Player Daemon... Oct 07 18:13:57 elutuba sudo[2676]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 07 18:13:57 elutuba sudo[2676]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:13:57 elutuba volumio[2583]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:57 elutuba volumio[2583]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 07 18:13:57 elutuba sudo[2676]: pam_unix(sudo:session): session closed for user root Oct 07 18:13:57 elutuba sudo[2677]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 07 18:13:57 elutuba sudo[2677]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:13:58 elutuba systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Oct 07 18:13:58 elutuba systemd[1]: mpd.service: Succeeded. Oct 07 18:13:58 elutuba systemd[1]: Stopped Music Player Daemon. Oct 07 18:13:58 elutuba systemd[1]: Starting Music Player Daemon... Oct 07 18:13:58 elutuba volumio[2583]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 07 18:13:58 elutuba volumio[2583]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:58 elutuba volumio[2583]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 07 18:13:58 elutuba sudo[2683]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 07 18:13:58 elutuba sudo[2683]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:13:58 elutuba sudo[2683]: pam_unix(sudo:session): session closed for user root Oct 07 18:13:58 elutuba volumio[2583]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 07 18:13:58 elutuba volumio[2583]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 07 18:13:58 elutuba volumio[2583]: info: [1728314038220] CoreMusicLibrary::Adding element Last_100 Oct 07 18:13:58 elutuba volumio[2583]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 07 18:13:58 elutuba volumio[2583]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 07 18:13:58 elutuba volumio[2583]: info: [1728314038242] CoreMusicLibrary::Adding element Webradio Oct 07 18:13:58 elutuba volumio[2583]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 07 18:13:58 elutuba volumio[2583]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 07 18:13:58 elutuba volumio[2583]: info: Initializing BBC Radios Oct 07 18:13:58 elutuba volumio[2583]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 07 18:13:58 elutuba volumio[2583]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 07 18:13:58 elutuba volumio[2583]: info: Loading i18n strings for locale en Oct 07 18:13:58 elutuba volumio[2583]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Oct 07 18:13:58 elutuba volumio[2583]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:58 elutuba volumio[2583]: info: FusionDsp - mixtype--------------------- Hardware Oct 07 18:13:58 elutuba volumio[2583]: info: Volumio Calling Home Oct 07 18:13:58 elutuba volumio[2583]: info: Preparing to generate the ALSA configuration file Oct 07 18:13:58 elutuba volumio[2583]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Oct 07 18:13:58 elutuba volumio[2583]: info: Reading ALSA contributions from plugins. Oct 07 18:13:58 elutuba volumio[2583]: info: MPD Permissions set Oct 07 18:13:58 elutuba volumio[2583]: info: MPD Permissions set Oct 07 18:13:58 elutuba volumio[2583]: info: VolumeController:: Volume=0 Mute =false Oct 07 18:13:58 elutuba volumio[2583]: info: CoreStateMachine::pushState Oct 07 18:13:58 elutuba volumio[2583]: info: CorePlayQueue::getTrack 0 Oct 07 18:13:58 elutuba volumio[2583]: info: CoreCommandRouter::volumioPushState Oct 07 18:13:58 elutuba volumio[2583]: info: Volumio called home Oct 07 18:13:58 elutuba volumio[2583]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Oct 07 18:13:58 elutuba volumio[2583]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:58 elutuba volumio[2583]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:58 elutuba volumio[2583]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:58 elutuba volumio[2583]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:58 elutuba volumio[2583]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:58 elutuba volumio[2583]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:58 elutuba volumio[2583]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:58 elutuba volumio[2583]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:58 elutuba volumio[2583]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 07 18:13:58 elutuba volumio[2583]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:58 elutuba volumio[2583]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:58 elutuba volumio[2583]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:58 elutuba volumio[2583]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:58 elutuba volumio[2583]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:58 elutuba volumio[2583]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:58 elutuba volumio[2583]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:58 elutuba volumio[2583]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:58 elutuba volumio[2583]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:58 elutuba volumio[2583]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 07 18:13:58 elutuba volumio[2583]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:59 elutuba volumio[2583]: info: Starting Shairport Sync Oct 07 18:13:59 elutuba volumio[2583]: info: Starting Shairport Sync Oct 07 18:13:59 elutuba volumio[2583]: info: Starting Shairport Sync Oct 07 18:13:59 elutuba sudo[2735]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 07 18:13:59 elutuba sudo[2735]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:13:59 elutuba sudo[2737]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 07 18:13:59 elutuba sudo[2737]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:13:59 elutuba volumio[2583]: info: CoreCommandRouter::volumioGetState Oct 07 18:13:59 elutuba volumio[2583]: info: CorePlayQueue::getTrack 0 Oct 07 18:13:59 elutuba systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 07 18:13:59 elutuba systemd[1]: shairport-sync.service: Succeeded. Oct 07 18:13:59 elutuba systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 07 18:13:59 elutuba sudo[2740]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 07 18:13:59 elutuba sudo[2740]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:13:59 elutuba volumio[2583]: info: Asound.conf file unchanged, so no further update is needed Oct 07 18:13:59 elutuba volumio[2583]: info: Output device has changed, restarting MPD Oct 07 18:13:59 elutuba systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 07 18:13:59 elutuba volumio[2583]: info: Output device has changed, restarting Shairport Sync Oct 07 18:13:59 elutuba sudo[2745]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 07 18:13:59 elutuba volumio[2583]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:59 elutuba volumio[2583]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 07 18:13:59 elutuba sudo[2745]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:13:59 elutuba sudo[2745]: pam_unix(sudo:session): session closed for user root Oct 07 18:13:59 elutuba sudo[2747]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 07 18:13:59 elutuba sudo[2747]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:13:59 elutuba sudo[2735]: pam_unix(sudo:session): session closed for user root Oct 07 18:13:59 elutuba systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 07 18:13:59 elutuba systemd[1]: shairport-sync.service: Succeeded. Oct 07 18:13:59 elutuba systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 07 18:13:59 elutuba volumio[2583]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 07 18:13:59 elutuba systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 07 18:13:59 elutuba sudo[2737]: pam_unix(sudo:session): session closed for user root Oct 07 18:13:59 elutuba sudo[2740]: pam_unix(sudo:session): session closed for user root Oct 07 18:13:59 elutuba systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Oct 07 18:13:59 elutuba systemd[1]: mpd.service: Succeeded. Oct 07 18:13:59 elutuba systemd[1]: Stopped Music Player Daemon. Oct 07 18:13:59 elutuba systemd[1]: Starting Music Player Daemon... Oct 07 18:13:59 elutuba volumio[2583]: verbose: New Socket.io Connection to 192.168.175.140 from 192.168.175.185 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/128.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Oct 07 18:13:59 elutuba volumio[2583]: info: MPD Permissions set Oct 07 18:13:59 elutuba volumio[2583]: info: Shairport-Sync Started Oct 07 18:13:59 elutuba volumio[2583]: Error adding Membership: Error: addMembership EINVAL Oct 07 18:13:59 elutuba volumio[2583]: info: Shairport-Sync Started Oct 07 18:13:59 elutuba volumio[2583]: info: Shairport-Sync Started Oct 07 18:13:59 elutuba volumio[2583]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 07 18:13:59 elutuba sudo[2757]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 07 18:13:59 elutuba sudo[2757]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:13:59 elutuba sudo[2757]: pam_unix(sudo:session): session closed for user root Oct 07 18:13:59 elutuba volumio[2583]: info: CoreCommandRouter::volumioGetState Oct 07 18:13:59 elutuba volumio[2583]: info: CorePlayQueue::getTrack 0 Oct 07 18:13:59 elutuba volumio[2583]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Oct 07 18:13:59 elutuba volumio[2583]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Oct 07 18:13:59 elutuba volumio[2583]: info: Received Get System Version Oct 07 18:13:59 elutuba volumio[2583]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 07 18:13:59 elutuba volumio[2583]: info: Received Get System Info Oct 07 18:13:59 elutuba volumio[2583]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 07 18:13:59 elutuba volumio[2583]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 07 18:13:59 elutuba volumio[2583]: info: Discovery: Getting this device information Oct 07 18:13:59 elutuba volumio[2583]: info: CoreCommandRouter::volumioGetState Oct 07 18:13:59 elutuba volumio[2583]: info: CorePlayQueue::getTrack 0 Oct 07 18:13:59 elutuba volumio[2583]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 07 18:13:59 elutuba volumio[2583]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 07 18:13:59 elutuba volumio[2583]: info: CoreCommandRouter::volumioGetVisibleSources Oct 07 18:13:59 elutuba volumio[2583]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 07 18:13:59 elutuba volumio[2583]: info: CoreCommandRouter::volumioGetState Oct 07 18:13:59 elutuba volumio[2583]: info: CorePlayQueue::getTrack 0 Oct 07 18:13:59 elutuba volumio[2583]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 07 18:13:59 elutuba volumio[2583]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 07 18:13:59 elutuba volumio[2583]: info: Received Get System Info Oct 07 18:13:59 elutuba volumio[2583]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 07 18:13:59 elutuba volumio[2583]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 07 18:13:59 elutuba volumio[2583]: info: Discovery: Getting this device information Oct 07 18:13:59 elutuba volumio[2583]: info: CoreCommandRouter::volumioGetState Oct 07 18:13:59 elutuba volumio[2583]: info: CorePlayQueue::getTrack 0 Oct 07 18:13:59 elutuba volumio[2583]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 07 18:13:59 elutuba volumio[2583]: info: CoreCommandRouter::volumioGetState Oct 07 18:13:59 elutuba volumio[2583]: info: CorePlayQueue::getTrack 0 Oct 07 18:13:59 elutuba volumio[2583]: info: Listing playlists Oct 07 18:13:59 elutuba volumio[2583]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:59 elutuba volumio[2583]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:59 elutuba volumio[2583]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:59 elutuba volumio[2583]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:59 elutuba volumio[2583]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:59 elutuba volumio[2583]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:59 elutuba volumio[2583]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:59 elutuba volumio[2583]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:59 elutuba volumio[2583]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 07 18:13:59 elutuba volumio[2583]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:13:59 elutuba volumio[2583]: info: Listing playlists Oct 07 18:13:59 elutuba volumio[2583]: info: Listing playlists Oct 07 18:13:59 elutuba volumio[2583]: info: Starting Shairport Sync Oct 07 18:13:59 elutuba volumio[2583]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 07 18:13:59 elutuba sudo[2766]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 07 18:13:59 elutuba sudo[2766]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:13:59 elutuba systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 07 18:13:59 elutuba systemd[1]: shairport-sync.service: Succeeded. Oct 07 18:13:59 elutuba systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 07 18:13:59 elutuba systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 07 18:13:59 elutuba sudo[2766]: pam_unix(sudo:session): session closed for user root Oct 07 18:14:00 elutuba volumio[2583]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 07 18:14:00 elutuba volumio[2583]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 07 18:14:00 elutuba volumio[2583]: info: Shairport-Sync Started Oct 07 18:14:00 elutuba volumio[2583]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 07 18:14:00 elutuba volumio[2583]: TypeError: Cannot read property 'off' of undefined Oct 07 18:14:00 elutuba volumio[2583]: at FusionDsp.onStop (/data/plugins/audio_interface/fusiondsp/index.js:107:15) Oct 07 18:14:00 elutuba volumio[2583]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Oct 07 18:14:00 elutuba volumio[2583]: at PluginManager.disableAndStopPlugin (/volumio/app/pluginmanager.js:1883:8) Oct 07 18:14:00 elutuba volumio[2583]: at CoreCommandRouter.disableAndStopPlugin (/volumio/app/index.js:1537:29) Oct 07 18:14:00 elutuba volumio[2583]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1387:47) Oct 07 18:14:00 elutuba volumio[2583]: at Socket.emit (events.js:315:20) Oct 07 18:14:00 elutuba volumio[2583]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Oct 07 18:14:00 elutuba volumio[2583]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Oct 07 18:14:00 elutuba volumio[2583]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 07 18:14:00 elutuba sudo[2782]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-07 18:13 Oct 07 18:14:00 elutuba sudo[2782]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:14:01 elutuba sudo[2782]: pam_unix(sudo:session): session closed for user root Oct 07 18:14:01 elutuba mpd[2760]: Oct 07 18:14 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 07 18:14:01 elutuba systemd[1]: Started Music Player Daemon. Oct 07 18:14:01 elutuba sudo[2668]: pam_unix(sudo:session): session closed for user root Oct 07 18:14:01 elutuba sudo[2747]: pam_unix(sudo:session): session closed for user root Oct 07 18:14:01 elutuba sudo[2677]: pam_unix(sudo:session): session closed for user root Oct 07 18:14:01 elutuba volumio-remote-updater[629]: [2024-10-07 18:14:01] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Oct 07 18:14:01 elutuba volumio-remote-updater[629]: [2024-10-07 18:14:01] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Oct 07 18:14:01 elutuba systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Oct 07 18:14:01 elutuba systemd[1]: volumio.service: Failed with result 'exit-code'. Oct 07 18:14:01 elutuba systemd[1]: Started dynamicswap service. Oct 07 18:14:01 elutuba systemd[1]: dynamicswap.service: Succeeded. Oct 07 18:14:01 elutuba systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Oct 07 18:14:01 elutuba systemd[1]: volumio.service: Scheduled restart job, restart counter is at 8. Oct 07 18:14:01 elutuba systemd[1]: Stopped Volumio Backend Module. Oct 07 18:14:01 elutuba systemd[1]: Started Volumio Backend Module. Oct 07 18:14:01 elutuba systemd[1]: Started dynamicswap service. Oct 07 18:14:01 elutuba systemd[1]: dynamicswap.service: Succeeded. Oct 07 18:14:03 elutuba volumio[2797]: info: ------------------------------------------- Oct 07 18:14:03 elutuba volumio[2797]: info: ----- Volumio3 ---- Oct 07 18:14:03 elutuba volumio[2797]: info: ------------------------------------------- Oct 07 18:14:03 elutuba volumio[2797]: info: ----- System startup ---- Oct 07 18:14:03 elutuba volumio[2797]: info: ------------------------------------------- Oct 07 18:14:04 elutuba volumio[2797]: info: MYVOLUMIO Environment detected Oct 07 18:14:04 elutuba volumio[2797]: info: Plugin folders cleanup Oct 07 18:14:04 elutuba volumio[2797]: info: Scanning into folder /volumio/app/plugins/ Oct 07 18:14:04 elutuba volumio[2797]: info: Scanning category audio_interface Oct 07 18:14:04 elutuba volumio[2797]: info: Scanning category miscellanea Oct 07 18:14:04 elutuba volumio[2797]: info: Scanning category music_service Oct 07 18:14:04 elutuba volumio[2797]: info: Scanning category plugins.json Oct 07 18:14:04 elutuba volumio[2797]: info: Scanning category system_controller Oct 07 18:14:04 elutuba volumio[2797]: info: Scanning category user_interface Oct 07 18:14:04 elutuba volumio[2797]: info: Scanning into folder /data/plugins/ Oct 07 18:14:04 elutuba volumio[2797]: info: Scanning category audio_interface Oct 07 18:14:04 elutuba volumio[2797]: info: Plugin folders cleanup completed Oct 07 18:14:04 elutuba volumio[2797]: info: ------------------------------------------- Oct 07 18:14:04 elutuba volumio[2797]: info: ----- Core plugins startup ---- Oct 07 18:14:04 elutuba volumio[2797]: info: ------------------------------------------- Oct 07 18:14:04 elutuba volumio[2797]: info: Loading plugins from folder /volumio/app/plugins/ Oct 07 18:14:04 elutuba volumio[2797]: info: Adding plugin upnp to MyMusic Plugins Oct 07 18:14:04 elutuba volumio[2797]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 07 18:14:04 elutuba volumio[2797]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 07 18:14:04 elutuba volumio[2797]: info: Loading plugins from folder /data/plugins/ Oct 07 18:14:04 elutuba volumio[2797]: info: Loading plugin "system"... Oct 07 18:14:04 elutuba volumio[2797]: info: Loading plugin "appearance"... Oct 07 18:14:05 elutuba volumio[2797]: info: Loading plugin "network"... Oct 07 18:14:05 elutuba volumio[2797]: info: Refreshing Cached IP Addresses Oct 07 18:14:05 elutuba sudo[2824]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 07 18:14:05 elutuba sudo[2824]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:14:05 elutuba sudo[2824]: pam_unix(sudo:session): session closed for user root Oct 07 18:14:05 elutuba volumio[2797]: info: Loading plugin "services"... Oct 07 18:14:05 elutuba sudo[2826]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 07 18:14:05 elutuba sudo[2826]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:14:05 elutuba volumio[2797]: info: Loading plugin "alsa_controller"... Oct 07 18:14:05 elutuba sudo[2826]: pam_unix(sudo:session): session closed for user root Oct 07 18:14:05 elutuba sudo[2829]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 07 18:14:05 elutuba sudo[2829]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:14:05 elutuba volumio[2797]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 07 18:14:05 elutuba volumio[2797]: info: Loading plugin "wizard"... Oct 07 18:14:05 elutuba volumio[2797]: info: Loading plugin "networkfs"... Oct 07 18:14:06 elutuba volumio[2797]: info: Starting Udev Watcher for removable devices Oct 07 18:14:06 elutuba volumio[2797]: info: Ignoring mount for partition: boot Oct 07 18:14:06 elutuba volumio[2797]: info: Ignoring mount for partition: volumio Oct 07 18:14:06 elutuba volumio[2797]: info: Ignoring mount for partition: volumio_data Oct 07 18:14:06 elutuba volumio[2797]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 07 18:14:06 elutuba volumio[2797]: info: Loading plugin "volumio_command_line_client"... Oct 07 18:14:06 elutuba volumio[2797]: info: Loading plugin "upnp"... Oct 07 18:14:06 elutuba volumio[2797]: info: [1728314046055] Starting Upmpd Daemon Oct 07 18:14:06 elutuba volumio[2797]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 07 18:14:06 elutuba volumio[2797]: info: Loading plugin "my_music"... Oct 07 18:14:06 elutuba volumio[2797]: info: Loading plugin "mpd"... Oct 07 18:14:06 elutuba volumio[2797]: info: Loading plugin "upnp_browser"... Oct 07 18:14:06 elutuba volumio-remote-updater[629]: [2024-10-07 18:14:06] [connect] Successful connection Oct 07 18:14:06 elutuba volumio[2797]: info: Loading plugin "alarm-clock"... Oct 07 18:14:06 elutuba sudo[2829]: pam_unix(sudo:session): session closed for user root Oct 07 18:14:06 elutuba volumio[2797]: info: Loading plugin "airplay_emulation"... Oct 07 18:14:06 elutuba volumio[2797]: info: Starting Shairport Sync Oct 07 18:14:06 elutuba volumio[2797]: info: Loading plugin "last_100"... Oct 07 18:14:06 elutuba volumio[2797]: info: Loading plugin "webradio"... Oct 07 18:14:07 elutuba volumio[2797]: info: Loading plugin "i2s_dacs"... Oct 07 18:14:07 elutuba volumio[2797]: info: I2S DAC not set, start Auto-detection Oct 07 18:14:07 elutuba volumio[2797]: info: Loading plugin "volumiodiscovery"... Oct 07 18:14:07 elutuba volumio[2797]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 07 18:14:07 elutuba volumio[2797]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 07 18:14:07 elutuba volumio[2797]: *** WARNING *** For more information see Oct 07 18:14:07 elutuba node[2797]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 07 18:14:07 elutuba volumio[2797]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 07 18:14:07 elutuba volumio[2797]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 07 18:14:07 elutuba volumio[2797]: *** WARNING *** For more information see Oct 07 18:14:07 elutuba node[2797]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 07 18:14:07 elutuba node[2797]: *** WARNING *** For more information see Oct 07 18:14:07 elutuba node[2797]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 07 18:14:07 elutuba node[2797]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 07 18:14:07 elutuba node[2797]: *** WARNING *** For more information see Oct 07 18:14:07 elutuba volumio[2797]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 07 18:14:07 elutuba volumio[2797]: info: Discovery: Started advertising with name: Elutuba Oct 07 18:14:07 elutuba volumio[2797]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 07 18:14:07 elutuba volumio[2797]: info: Loading plugin "outputs"... Oct 07 18:14:07 elutuba volumio[2797]: info: Loading plugin "albumart"... Oct 07 18:14:07 elutuba volumio[2797]: info: Plugin example_plugin is not enabled Oct 07 18:14:07 elutuba volumio[2797]: info: Loading plugin "inputs"... Oct 07 18:14:07 elutuba volumio[2797]: info: Loading plugin "updater_comm"... Oct 07 18:14:07 elutuba volumio[2797]: info: Plugin mpdemulation is not enabled Oct 07 18:14:07 elutuba volumio[2797]: info: Loading plugin "rest_api"... Oct 07 18:14:07 elutuba volumio[2797]: info: Loading plugin "websocket"... Oct 07 18:14:07 elutuba volumio[2797]: info: Starting Socket.io Server version 2.3.0 Oct 07 18:14:07 elutuba volumio[2797]: info: Loading plugin "fusiondsp"... Oct 07 18:14:07 elutuba volumio[2797]: Forking 3 albumart workers Oct 07 18:14:07 elutuba volumio[2797]: info: Applying required configuration parameters for plugin fusiondsp Oct 07 18:14:08 elutuba volumio[2797]: info: Loading i18n strings for locale en Oct 07 18:14:08 elutuba volumio[2797]: Updating browse sources language Oct 07 18:14:08 elutuba volumio[2797]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 07 18:14:08 elutuba volumio[2797]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 07 18:14:08 elutuba volumio[2797]: info: CoreCommandRouter::initPlayerControls Oct 07 18:14:08 elutuba volumio[2797]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:14:08 elutuba volumio[2797]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:14:08 elutuba volumio[2797]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:14:08 elutuba volumio[2797]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:14:08 elutuba volumio[2797]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:14:08 elutuba volumio[2797]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:14:08 elutuba volumio[2797]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:14:08 elutuba volumio[2797]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:14:08 elutuba volumio[2797]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 07 18:14:08 elutuba volumio[2797]: Express server listening on port 3000 Oct 07 18:14:08 elutuba volumio[2797]: [Metrics] WebUI: 5s 192.53ms Oct 07 18:14:08 elutuba volumio[2797]: info: CoreStateMachine::resetVolumioState Oct 07 18:14:08 elutuba volumio[2797]: info: CoreStateMachine::getcurrentVolume Oct 07 18:14:08 elutuba volumio[2797]: info: CoreCommandRouter::volumioRetrievevolume Oct 07 18:14:08 elutuba volumio[2797]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 07 18:14:08 elutuba volumio[2797]: info: Cannot read play queue from file Oct 07 18:14:08 elutuba volumio[2797]: verbose: New Socket.io Connection to 192.168.175.140 from 192.168.175.185 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/128.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Oct 07 18:14:08 elutuba volumio[2797]: verbose: New Socket.io Connection to 192.168.175.140 from 192.168.175.185 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/128.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Oct 07 18:14:08 elutuba volumio-remote-updater[629]: [2024-10-07 18:14:08] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1728314046 101 Oct 07 18:14:08 elutuba volumio[2797]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 3 Oct 07 18:14:08 elutuba volumio[2797]: info: CoreStateMachine::setRepeat null single undefined Oct 07 18:14:08 elutuba volumio[2797]: info: CoreStateMachine::pushState Oct 07 18:14:08 elutuba volumio[2797]: info: CorePlayQueue::getTrack 0 Oct 07 18:14:08 elutuba volumio[2797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 07 18:14:08 elutuba volumio[2797]: info: CoreCommandRouter::volumioPushState Oct 07 18:14:08 elutuba volumio[2797]: info: CoreStateMachine::setRandom null Oct 07 18:14:08 elutuba volumio[2797]: info: CoreStateMachine::pushState Oct 07 18:14:08 elutuba volumio[2797]: info: CorePlayQueue::getTrack 0 Oct 07 18:14:08 elutuba volumio[2797]: info: CoreCommandRouter::volumioPushState Oct 07 18:14:08 elutuba volumio[2797]: info: Setting Device type: Raspberry PI Oct 07 18:14:08 elutuba volumio[2797]: info: VolumeController:: Volume=0 Mute =false Oct 07 18:14:08 elutuba volumio[2797]: info: CoreStateMachine::pushState Oct 07 18:14:08 elutuba volumio[2797]: info: CorePlayQueue::getTrack 0 Oct 07 18:14:08 elutuba volumio[2797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 07 18:14:08 elutuba volumio[2797]: info: CoreCommandRouter::volumioPushState Oct 07 18:14:08 elutuba volumio[2797]: info: CoreStateMachine::updateTrackBlock Oct 07 18:14:08 elutuba volumio[2797]: info: CorePlayQueue::getTrackBlock Oct 07 18:14:08 elutuba volumio[2797]: info: CoreCommandRouter::volumioRetrievevolume Oct 07 18:14:08 elutuba volumio[2797]: Starting albumart workers Oct 07 18:14:08 elutuba volumio[2797]: info: Discovery: adding 320db26b-9bed-4586-9e4d-ee23fdd56bae Oct 07 18:14:08 elutuba volumio[2797]: info: Discovery: Found device Elutuba Oct 07 18:14:08 elutuba volumio[2797]: info: CoreCommandRouter::volumioGetState Oct 07 18:14:08 elutuba volumio[2797]: info: CorePlayQueue::getTrack 0 Oct 07 18:14:08 elutuba volumio[2797]: info: Completed loading Core Plugins Oct 07 18:14:08 elutuba volumio[2797]: info: Preparing to generate the ALSA configuration file Oct 07 18:14:09 elutuba volumio[2797]: Starting albumart workers Oct 07 18:14:09 elutuba volumio[2797]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Oct 07 18:14:09 elutuba volumio[2797]: info: Reading ALSA contributions from plugins. Oct 07 18:14:09 elutuba volumio[2797]: Starting albumart workers Oct 07 18:14:09 elutuba volumio[2797]: info: Asound.conf file unchanged, so no further update is needed Oct 07 18:14:09 elutuba volumio[2797]: info: Output device has changed, restarting MPD Oct 07 18:14:09 elutuba volumio[2797]: info: Output device has changed, restarting Shairport Sync Oct 07 18:14:09 elutuba volumio[2797]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:14:09 elutuba volumio[2797]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 07 18:14:09 elutuba sudo[2879]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 07 18:14:09 elutuba sudo[2879]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:14:09 elutuba sudo[2881]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 07 18:14:09 elutuba sudo[2881]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:14:09 elutuba sudo[2879]: pam_unix(sudo:session): session closed for user root Oct 07 18:14:09 elutuba systemd[1]: Stopping Music Player Daemon... Oct 07 18:14:09 elutuba volumio[2797]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 07 18:14:09 elutuba volumio[2797]: info: ___________ START PLUGINS ___________ Oct 07 18:14:09 elutuba volumio[2797]: info: ControllerMpd::onStart: Initializing MPD Oct 07 18:14:09 elutuba volumio[2797]: info: Creating MPD Configuration file Oct 07 18:14:09 elutuba systemd[1]: mpd.service: Succeeded. Oct 07 18:14:09 elutuba systemd[1]: Stopped Music Player Daemon. Oct 07 18:14:09 elutuba sudo[2889]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 07 18:14:09 elutuba sudo[2889]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:14:09 elutuba volumio[2797]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 07 18:14:09 elutuba volumio[2797]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 07 18:14:09 elutuba volumio[2797]: info: [1728314049346] CoreMusicLibrary::Adding element Media Servers Oct 07 18:14:09 elutuba volumio[2797]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 07 18:14:09 elutuba sudo[2889]: pam_unix(sudo:session): session closed for user root Oct 07 18:14:09 elutuba systemd[1]: Starting Music Player Daemon... Oct 07 18:14:09 elutuba sudo[2892]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 07 18:14:09 elutuba sudo[2892]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:14:09 elutuba systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Oct 07 18:14:09 elutuba systemd[1]: mpd.service: Succeeded. Oct 07 18:14:09 elutuba systemd[1]: Stopped Music Player Daemon. Oct 07 18:14:09 elutuba volumio[2797]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:14:09 elutuba volumio[2797]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 07 18:14:09 elutuba systemd[1]: Starting Music Player Daemon... Oct 07 18:14:09 elutuba volumio[2797]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 07 18:14:09 elutuba volumio[2797]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:14:09 elutuba volumio[2797]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 07 18:14:09 elutuba sudo[2894]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 07 18:14:09 elutuba sudo[2894]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:14:09 elutuba sudo[2894]: pam_unix(sudo:session): session closed for user root Oct 07 18:14:09 elutuba volumio[2797]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 07 18:14:09 elutuba volumio[2797]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 07 18:14:09 elutuba volumio[2797]: info: [1728314049696] CoreMusicLibrary::Adding element Last_100 Oct 07 18:14:09 elutuba volumio[2797]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 07 18:14:09 elutuba volumio[2797]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 07 18:14:09 elutuba volumio[2797]: info: [1728314049702] CoreMusicLibrary::Adding element Webradio Oct 07 18:14:09 elutuba volumio[2797]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 07 18:14:09 elutuba volumio[2797]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 07 18:14:09 elutuba volumio[2797]: info: Initializing BBC Radios Oct 07 18:14:09 elutuba volumio[2797]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 07 18:14:09 elutuba volumio[2797]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 07 18:14:09 elutuba volumio[2797]: info: Loading i18n strings for locale en Oct 07 18:14:09 elutuba volumio[2797]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Oct 07 18:14:09 elutuba volumio[2797]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:14:09 elutuba volumio[2797]: info: FusionDsp - mixtype--------------------- Hardware Oct 07 18:14:09 elutuba volumio[2797]: info: Volumio Calling Home Oct 07 18:14:10 elutuba volumio[2797]: info: Preparing to generate the ALSA configuration file Oct 07 18:14:10 elutuba volumio[2797]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Oct 07 18:14:10 elutuba volumio[2797]: info: Reading ALSA contributions from plugins. Oct 07 18:14:10 elutuba volumio[2797]: verbose: New Socket.io Connection to 192.168.175.140 from 192.168.175.185 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/128.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Oct 07 18:14:10 elutuba volumio[2797]: info: MPD Permissions set Oct 07 18:14:10 elutuba volumio[2797]: info: MPD Permissions set Oct 07 18:14:10 elutuba volumio[2797]: info: VolumeController:: Volume=0 Mute =false Oct 07 18:14:10 elutuba volumio[2797]: info: CoreStateMachine::pushState Oct 07 18:14:10 elutuba volumio[2797]: info: CorePlayQueue::getTrack 0 Oct 07 18:14:10 elutuba volumio[2797]: info: CoreCommandRouter::volumioPushState Oct 07 18:14:10 elutuba volumio[2797]: info: Volumio called home Oct 07 18:14:10 elutuba volumio[2797]: info: CoreCommandRouter::volumioGetState Oct 07 18:14:10 elutuba volumio[2797]: info: CorePlayQueue::getTrack 0 Oct 07 18:14:10 elutuba volumio[2797]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Oct 07 18:14:10 elutuba volumio[2797]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:14:10 elutuba volumio[2797]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:14:10 elutuba volumio[2797]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:14:10 elutuba volumio[2797]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:14:10 elutuba volumio[2797]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:14:10 elutuba volumio[2797]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:14:10 elutuba volumio[2797]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:14:10 elutuba volumio[2797]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:14:10 elutuba volumio[2797]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 07 18:14:10 elutuba volumio[2797]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:14:10 elutuba volumio[2797]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:14:10 elutuba volumio[2797]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:14:10 elutuba volumio[2797]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:14:10 elutuba volumio[2797]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:14:10 elutuba volumio[2797]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:14:10 elutuba volumio[2797]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:14:10 elutuba volumio[2797]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:14:10 elutuba volumio[2797]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:14:10 elutuba volumio[2797]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 07 18:14:10 elutuba volumio[2797]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:14:10 elutuba volumio[2797]: info: Starting Shairport Sync Oct 07 18:14:10 elutuba volumio[2797]: info: Starting Shairport Sync Oct 07 18:14:10 elutuba volumio[2797]: info: Starting Shairport Sync Oct 07 18:14:10 elutuba sudo[2949]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 07 18:14:10 elutuba sudo[2949]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:14:10 elutuba sudo[2951]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 07 18:14:10 elutuba sudo[2951]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:14:10 elutuba volumio[2797]: info: CoreCommandRouter::volumioGetState Oct 07 18:14:10 elutuba volumio[2797]: info: CorePlayQueue::getTrack 0 Oct 07 18:14:10 elutuba sudo[2955]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 07 18:14:10 elutuba sudo[2955]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:14:10 elutuba systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 07 18:14:10 elutuba systemd[1]: shairport-sync.service: Succeeded. Oct 07 18:14:10 elutuba systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 07 18:14:10 elutuba volumio[2797]: info: Asound.conf file unchanged, so no further update is needed Oct 07 18:14:10 elutuba volumio[2797]: info: Output device has changed, restarting MPD Oct 07 18:14:10 elutuba volumio[2797]: info: Output device has changed, restarting Shairport Sync Oct 07 18:14:10 elutuba volumio[2797]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:14:10 elutuba volumio[2797]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 07 18:14:10 elutuba sudo[2959]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 07 18:14:10 elutuba sudo[2959]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:14:10 elutuba systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 07 18:14:10 elutuba sudo[2959]: pam_unix(sudo:session): session closed for user root Oct 07 18:14:10 elutuba sudo[2951]: pam_unix(sudo:session): session closed for user root Oct 07 18:14:10 elutuba sudo[2949]: pam_unix(sudo:session): session closed for user root Oct 07 18:14:10 elutuba systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 07 18:14:10 elutuba systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Oct 07 18:14:10 elutuba systemd[1]: shairport-sync.service: Succeeded. Oct 07 18:14:10 elutuba systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 07 18:14:10 elutuba sudo[2961]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 07 18:14:10 elutuba sudo[2961]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:14:10 elutuba systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 07 18:14:10 elutuba sudo[2955]: pam_unix(sudo:session): session closed for user root Oct 07 18:14:10 elutuba volumio[2797]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 07 18:14:10 elutuba systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Oct 07 18:14:10 elutuba systemd[1]: mpd.service: Succeeded. Oct 07 18:14:10 elutuba systemd[1]: Stopped Music Player Daemon. Oct 07 18:14:10 elutuba systemd[1]: Starting Music Player Daemon... Oct 07 18:14:10 elutuba volumio[2797]: info: MPD Permissions set Oct 07 18:14:10 elutuba volumio[2797]: info: Shairport-Sync Started Oct 07 18:14:10 elutuba volumio[2797]: Error adding Membership: Error: addMembership EINVAL Oct 07 18:14:10 elutuba volumio[2797]: info: Shairport-Sync Started Oct 07 18:14:10 elutuba volumio[2797]: info: Shairport-Sync Started Oct 07 18:14:11 elutuba volumio[2797]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:14:11 elutuba volumio[2797]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:14:11 elutuba volumio[2797]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:14:11 elutuba volumio[2797]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:14:11 elutuba volumio[2797]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:14:11 elutuba volumio[2797]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:14:11 elutuba volumio[2797]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:14:11 elutuba volumio[2797]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:14:11 elutuba volumio[2797]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 07 18:14:11 elutuba volumio[2797]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:14:11 elutuba volumio[2797]: info: Starting Shairport Sync Oct 07 18:14:11 elutuba sudo[2976]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 07 18:14:11 elutuba sudo[2976]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:14:11 elutuba sudo[2969]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 07 18:14:11 elutuba sudo[2969]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:14:11 elutuba systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 07 18:14:11 elutuba sudo[2969]: pam_unix(sudo:session): session closed for user root Oct 07 18:14:11 elutuba systemd[1]: shairport-sync.service: Succeeded. Oct 07 18:14:11 elutuba systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 07 18:14:11 elutuba systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 07 18:14:11 elutuba sudo[2976]: pam_unix(sudo:session): session closed for user root Oct 07 18:14:11 elutuba volumio[2797]: info: Shairport-Sync Started Oct 07 18:14:12 elutuba volumio[2797]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Oct 07 18:14:12 elutuba volumio[2797]: 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 Oct 07 18:14:12 elutuba volumio[2797]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Oct 07 18:14:12 elutuba volumio[2797]: info: camilladsp spawned new process with pid undefined, instance 1, run: true Oct 07 18:14:12 elutuba volumio[2797]: info: camilladsp service started and running in background, instance 1 Oct 07 18:14:12 elutuba volumio[2797]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:14:12 elutuba volumio[2797]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Oct 07 18:14:12 elutuba volumio[2797]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Oct 07 18:14:12 elutuba volumio[2797]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Oct 07 18:14:12 elutuba volumio[2797]: info: FusionDsp loaded Oct 07 18:14:12 elutuba volumio[2797]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 07 18:14:12 elutuba sudo[2994]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Oct 07 18:14:12 elutuba sudo[2994]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:14:12 elutuba volumio[2797]: info: FusionDsp - Reporting Fusion DSP Enabled Oct 07 18:14:12 elutuba volumio[2797]: info: Adding Signal Path Element [object Object] Oct 07 18:14:12 elutuba volumio[2797]: info: Adding fusiondspeq DSP Signal Path Element Oct 07 18:14:12 elutuba volumio[2797]: info: FusionDsp - ---- installed callbackRead Oct 07 18:14:12 elutuba volumio[2797]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 07 18:14:12 elutuba sudo[2994]: pam_unix(sudo:session): session closed for user root Oct 07 18:14:12 elutuba volumio[2797]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Oct 07 18:14:12 elutuba volumio[2797]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Oct 07 18:14:12 elutuba volumio[2797]: at onErrorNT (internal/child_process.js:465:16) Oct 07 18:14:12 elutuba volumio[2797]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Oct 07 18:14:12 elutuba volumio[2797]: errno: -2, Oct 07 18:14:12 elutuba volumio[2797]: code: 'ENOENT', Oct 07 18:14:12 elutuba volumio[2797]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Oct 07 18:14:12 elutuba volumio[2797]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Oct 07 18:14:12 elutuba volumio[2797]: spawnargs: [ Oct 07 18:14:12 elutuba volumio[2797]: '-p', Oct 07 18:14:12 elutuba volumio[2797]: 9876, Oct 07 18:14:12 elutuba volumio[2797]: '-o', Oct 07 18:14:12 elutuba volumio[2797]: '/tmp/camilladsp.log', Oct 07 18:14:12 elutuba volumio[2797]: '-l', Oct 07 18:14:12 elutuba volumio[2797]: 'warn', Oct 07 18:14:12 elutuba volumio[2797]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Oct 07 18:14:12 elutuba volumio[2797]: ] Oct 07 18:14:12 elutuba volumio[2797]: } Oct 07 18:14:12 elutuba volumio[2797]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 07 18:14:12 elutuba mpd[2979]: Oct 07 18:14 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 07 18:14:12 elutuba systemd[1]: Started Music Player Daemon. Oct 07 18:14:12 elutuba sudo[2961]: pam_unix(sudo:session): session closed for user root Oct 07 18:14:12 elutuba sudo[2881]: pam_unix(sudo:session): session closed for user root Oct 07 18:14:12 elutuba sudo[2892]: pam_unix(sudo:session): session closed for user root Oct 07 18:14:12 elutuba sudo[3045]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-07 18:13 Oct 07 18:14:12 elutuba sudo[3045]: 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="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:01:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e0a6dc7e914e3fb93cbf2123f2f73eac"