-- Logs begin at Mon 2024-10-07 17:54:02 EEST, end at Mon 2024-10-07 18:10:01 EEST. -- Oct 07 18:09:00 elutuba sudo[15861]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 07 18:09:00 elutuba sudo[15861]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:09:00 elutuba volumio[15740]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:00 elutuba volumio[15740]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 07 18:09:00 elutuba systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Oct 07 18:09:00 elutuba systemd[1]: mpd.service: Succeeded. Oct 07 18:09:00 elutuba systemd[1]: Stopped Music Player Daemon. Oct 07 18:09:00 elutuba systemd[1]: Starting Music Player Daemon... Oct 07 18:09:00 elutuba volumio[15740]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 07 18:09:00 elutuba volumio[15740]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:00 elutuba volumio[15740]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 07 18:09:00 elutuba volumio[15740]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 07 18:09:00 elutuba volumio[15740]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 07 18:09:00 elutuba volumio[15740]: info: [1728313740154] CoreMusicLibrary::Adding element Last_100 Oct 07 18:09:00 elutuba volumio[15740]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 07 18:09:00 elutuba volumio[15740]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 07 18:09:00 elutuba volumio[15740]: info: [1728313740158] CoreMusicLibrary::Adding element Webradio Oct 07 18:09:00 elutuba volumio[15740]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 07 18:09:00 elutuba volumio[15740]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 07 18:09:00 elutuba volumio[15740]: info: Initializing BBC Radios Oct 07 18:09:00 elutuba sudo[15866]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 07 18:09:00 elutuba sudo[15866]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:09:00 elutuba sudo[15866]: pam_unix(sudo:session): session closed for user root Oct 07 18:09:00 elutuba volumio[15740]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 07 18:09:00 elutuba volumio[15740]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 07 18:09:00 elutuba volumio[15740]: info: Loading i18n strings for locale en Oct 07 18:09:00 elutuba volumio[15740]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Oct 07 18:09:00 elutuba volumio[15740]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:00 elutuba volumio[15740]: info: FusionDsp - mixtype--------------------- Hardware Oct 07 18:09:00 elutuba volumio[15740]: info: Volumio Calling Home Oct 07 18:09:00 elutuba volumio[15740]: info: Preparing to generate the ALSA configuration file Oct 07 18:09:00 elutuba volumio[15740]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Oct 07 18:09:00 elutuba volumio[15740]: info: Reading ALSA contributions from plugins. Oct 07 18:09:00 elutuba volumio[15740]: info: MPD Permissions set Oct 07 18:09:00 elutuba volumio[15740]: info: MPD Permissions set Oct 07 18:09:00 elutuba volumio[15740]: info: Volumio called home Oct 07 18:09:00 elutuba volumio[15740]: info: Listing playlists Oct 07 18:09:00 elutuba volumio[15740]: info: Listing playlists Oct 07 18:09:00 elutuba volumio[15740]: 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:09:00 elutuba volumio[15740]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:00 elutuba volumio[15740]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:00 elutuba volumio[15740]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:00 elutuba volumio[15740]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:00 elutuba volumio[15740]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:00 elutuba volumio[15740]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:00 elutuba volumio[15740]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:00 elutuba volumio[15740]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:00 elutuba volumio[15740]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 07 18:09:00 elutuba volumio[15740]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:00 elutuba volumio[15740]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:00 elutuba volumio[15740]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:00 elutuba volumio[15740]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:00 elutuba volumio[15740]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:00 elutuba volumio[15740]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:00 elutuba volumio[15740]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:00 elutuba volumio[15740]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:00 elutuba volumio[15740]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:00 elutuba volumio[15740]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 07 18:09:00 elutuba volumio[15740]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:00 elutuba volumio[15740]: info: Starting Shairport Sync Oct 07 18:09:00 elutuba volumio[15740]: info: Starting Shairport Sync Oct 07 18:09:00 elutuba sudo[15894]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 07 18:09:00 elutuba volumio[15740]: info: Starting Shairport Sync Oct 07 18:09:00 elutuba sudo[15894]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:09:00 elutuba sudo[15897]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 07 18:09:00 elutuba sudo[15897]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:09:00 elutuba volumio[15740]: info: CoreCommandRouter::volumioGetState Oct 07 18:09:00 elutuba volumio[15740]: info: CorePlayQueue::getTrack 0 Oct 07 18:09:01 elutuba systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 07 18:09:01 elutuba systemd[1]: shairport-sync.service: Succeeded. Oct 07 18:09:01 elutuba systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 07 18:09:01 elutuba sudo[15900]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 07 18:09:01 elutuba sudo[15900]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:09:01 elutuba volumio[15740]: info: Asound.conf file unchanged, so no further update is needed Oct 07 18:09:01 elutuba volumio[15740]: info: Output device has changed, restarting MPD Oct 07 18:09:01 elutuba systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 07 18:09:01 elutuba sudo[15897]: pam_unix(sudo:session): session closed for user root Oct 07 18:09:01 elutuba sudo[15894]: pam_unix(sudo:session): session closed for user root Oct 07 18:09:01 elutuba sudo[15904]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 07 18:09:01 elutuba systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 07 18:09:01 elutuba systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Oct 07 18:09:01 elutuba systemd[1]: shairport-sync.service: Succeeded. Oct 07 18:09:01 elutuba sudo[15904]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:09:01 elutuba systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 07 18:09:01 elutuba systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 07 18:09:01 elutuba sudo[15904]: pam_unix(sudo:session): session closed for user root Oct 07 18:09:01 elutuba sudo[15900]: pam_unix(sudo:session): session closed for user root Oct 07 18:09:01 elutuba volumio[15740]: info: Output device has changed, restarting Shairport Sync Oct 07 18:09:01 elutuba volumio[15740]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:01 elutuba volumio[15740]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 07 18:09:01 elutuba sudo[15908]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 07 18:09:01 elutuba sudo[15908]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:09:01 elutuba systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Oct 07 18:09:01 elutuba systemd[1]: mpd.service: Succeeded. Oct 07 18:09:01 elutuba systemd[1]: Stopped Music Player Daemon. Oct 07 18:09:01 elutuba volumio[15740]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 07 18:09:01 elutuba systemd[1]: Starting Music Player Daemon... Oct 07 18:09:01 elutuba volumio[15740]: info: MPD Permissions set Oct 07 18:09:01 elutuba volumio[15740]: info: Shairport-Sync Started Oct 07 18:09:01 elutuba volumio[15740]: Error adding Membership: Error: addMembership EINVAL Oct 07 18:09:01 elutuba volumio[15740]: info: Shairport-Sync Started Oct 07 18:09:01 elutuba volumio[15740]: info: Shairport-Sync Started Oct 07 18:09:01 elutuba volumio[15740]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:01 elutuba volumio[15740]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:01 elutuba volumio[15740]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:01 elutuba volumio[15740]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:01 elutuba volumio[15740]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:01 elutuba volumio[15740]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:01 elutuba volumio[15740]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:01 elutuba volumio[15740]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 07 18:09:01 elutuba volumio[15740]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:01 elutuba volumio[15740]: info: Starting Shairport Sync Oct 07 18:09:01 elutuba sudo[15921]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 07 18:09:01 elutuba sudo[15921]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:09:01 elutuba sudo[15915]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 07 18:09:01 elutuba sudo[15915]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:09:01 elutuba sudo[15915]: pam_unix(sudo:session): session closed for user root Oct 07 18:09:01 elutuba systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 07 18:09:01 elutuba systemd[1]: shairport-sync.service: Succeeded. Oct 07 18:09:01 elutuba systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 07 18:09:01 elutuba systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 07 18:09:01 elutuba sudo[15921]: pam_unix(sudo:session): session closed for user root Oct 07 18:09:01 elutuba volumio[15740]: info: Shairport-Sync Started Oct 07 18:09:02 elutuba volumio[15740]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Oct 07 18:09:02 elutuba volumio[15740]: 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:09:02 elutuba volumio[15740]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Oct 07 18:09:02 elutuba volumio[15740]: info: camilladsp spawned new process with pid undefined, instance 1, run: true Oct 07 18:09:02 elutuba volumio[15740]: info: camilladsp service started and running in background, instance 1 Oct 07 18:09:02 elutuba volumio[15740]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:02 elutuba volumio[15740]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Oct 07 18:09:02 elutuba volumio[15740]: 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:09:02 elutuba volumio[15740]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Oct 07 18:09:02 elutuba volumio[15740]: info: FusionDsp loaded Oct 07 18:09:02 elutuba volumio[15740]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 07 18:09:02 elutuba sudo[15939]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Oct 07 18:09:02 elutuba sudo[15939]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:09:02 elutuba volumio[15740]: info: FusionDsp - Reporting Fusion DSP Enabled Oct 07 18:09:02 elutuba volumio[15740]: info: Adding Signal Path Element [object Object] Oct 07 18:09:02 elutuba volumio[15740]: info: Adding fusiondspeq DSP Signal Path Element Oct 07 18:09:02 elutuba volumio[15740]: info: FusionDsp - ---- installed callbackRead Oct 07 18:09:02 elutuba volumio[15740]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 07 18:09:02 elutuba sudo[15939]: pam_unix(sudo:session): session closed for user root Oct 07 18:09:02 elutuba volumio[15740]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Oct 07 18:09:02 elutuba volumio[15740]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Oct 07 18:09:02 elutuba volumio[15740]: at onErrorNT (internal/child_process.js:465:16) Oct 07 18:09:02 elutuba volumio[15740]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Oct 07 18:09:02 elutuba volumio[15740]: errno: -2, Oct 07 18:09:02 elutuba volumio[15740]: code: 'ENOENT', Oct 07 18:09:02 elutuba volumio[15740]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Oct 07 18:09:02 elutuba volumio[15740]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Oct 07 18:09:02 elutuba volumio[15740]: spawnargs: [ Oct 07 18:09:02 elutuba volumio[15740]: '-p', Oct 07 18:09:02 elutuba volumio[15740]: 9876, Oct 07 18:09:02 elutuba volumio[15740]: '-o', Oct 07 18:09:02 elutuba volumio[15740]: '/tmp/camilladsp.log', Oct 07 18:09:02 elutuba volumio[15740]: '-l', Oct 07 18:09:02 elutuba volumio[15740]: 'warn', Oct 07 18:09:02 elutuba volumio[15740]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Oct 07 18:09:02 elutuba volumio[15740]: ] Oct 07 18:09:02 elutuba volumio[15740]: } Oct 07 18:09:02 elutuba volumio[15740]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 07 18:09:03 elutuba mpd[15924]: Oct 07 18:09 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 07 18:09:03 elutuba systemd[1]: Started Music Player Daemon. Oct 07 18:09:03 elutuba sudo[15908]: pam_unix(sudo:session): session closed for user root Oct 07 18:09:03 elutuba sudo[15861]: pam_unix(sudo:session): session closed for user root Oct 07 18:09:03 elutuba sudo[15850]: pam_unix(sudo:session): session closed for user root Oct 07 18:09:03 elutuba sudo[15950]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-07 18:08 Oct 07 18:09:03 elutuba sudo[15950]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:09:03 elutuba sudo[15950]: pam_unix(sudo:session): session closed for user root Oct 07 18:09:03 elutuba volumio-remote-updater[653]: [2024-10-07 18:09:03] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Oct 07 18:09:03 elutuba volumio-remote-updater[653]: [2024-10-07 18:09:03] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Oct 07 18:09:03 elutuba systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Oct 07 18:09:03 elutuba systemd[1]: volumio.service: Failed with result 'exit-code'. Oct 07 18:09:03 elutuba systemd[1]: Started dynamicswap service. Oct 07 18:09:03 elutuba systemd[1]: dynamicswap.service: Succeeded. Oct 07 18:09:03 elutuba systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Oct 07 18:09:03 elutuba systemd[1]: volumio.service: Scheduled restart job, restart counter is at 67. Oct 07 18:09:03 elutuba systemd[1]: Started dynamicswap service. Oct 07 18:09:03 elutuba systemd[1]: Stopped Volumio Backend Module. Oct 07 18:09:03 elutuba systemd[1]: Started Volumio Backend Module. Oct 07 18:09:03 elutuba systemd[1]: dynamicswap.service: Succeeded. Oct 07 18:09:05 elutuba volumio[15963]: info: ------------------------------------------- Oct 07 18:09:05 elutuba volumio[15963]: info: ----- Volumio3 ---- Oct 07 18:09:05 elutuba volumio[15963]: info: ------------------------------------------- Oct 07 18:09:05 elutuba volumio[15963]: info: ----- System startup ---- Oct 07 18:09:05 elutuba volumio[15963]: info: ------------------------------------------- Oct 07 18:09:06 elutuba volumio[15963]: info: MYVOLUMIO Environment detected Oct 07 18:09:06 elutuba volumio[15963]: info: Plugin folders cleanup Oct 07 18:09:06 elutuba volumio[15963]: info: Scanning into folder /volumio/app/plugins/ Oct 07 18:09:06 elutuba volumio[15963]: info: Scanning category audio_interface Oct 07 18:09:06 elutuba volumio[15963]: info: Scanning category miscellanea Oct 07 18:09:06 elutuba volumio[15963]: info: Scanning category music_service Oct 07 18:09:06 elutuba volumio[15963]: info: Scanning category plugins.json Oct 07 18:09:06 elutuba volumio[15963]: info: Scanning category system_controller Oct 07 18:09:06 elutuba volumio[15963]: info: Scanning category user_interface Oct 07 18:09:06 elutuba volumio[15963]: info: Scanning into folder /data/plugins/ Oct 07 18:09:06 elutuba volumio[15963]: info: Scanning category audio_interface Oct 07 18:09:06 elutuba volumio[15963]: info: Plugin folders cleanup completed Oct 07 18:09:06 elutuba volumio[15963]: info: ------------------------------------------- Oct 07 18:09:06 elutuba volumio[15963]: info: ----- Core plugins startup ---- Oct 07 18:09:06 elutuba volumio[15963]: info: ------------------------------------------- Oct 07 18:09:06 elutuba volumio[15963]: info: Loading plugins from folder /volumio/app/plugins/ Oct 07 18:09:06 elutuba volumio[15963]: info: Adding plugin upnp to MyMusic Plugins Oct 07 18:09:06 elutuba volumio[15963]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 07 18:09:06 elutuba volumio[15963]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 07 18:09:06 elutuba volumio[15963]: info: Loading plugins from folder /data/plugins/ Oct 07 18:09:06 elutuba volumio[15963]: info: Loading plugin "system"... Oct 07 18:09:06 elutuba volumio[15963]: info: Loading plugin "appearance"... Oct 07 18:09:07 elutuba volumio[15963]: info: Loading plugin "network"... Oct 07 18:09:07 elutuba volumio[15963]: info: Refreshing Cached IP Addresses Oct 07 18:09:07 elutuba sudo[15989]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 07 18:09:07 elutuba sudo[15989]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:09:07 elutuba sudo[15989]: pam_unix(sudo:session): session closed for user root Oct 07 18:09:07 elutuba sudo[15991]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 07 18:09:07 elutuba volumio[15963]: info: Loading plugin "services"... Oct 07 18:09:07 elutuba sudo[15991]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:09:07 elutuba volumio[15963]: info: Loading plugin "alsa_controller"... Oct 07 18:09:07 elutuba sudo[15991]: pam_unix(sudo:session): session closed for user root Oct 07 18:09:07 elutuba sudo[15994]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 07 18:09:07 elutuba sudo[15994]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:09:07 elutuba volumio[15963]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 07 18:09:07 elutuba volumio[15963]: info: Loading plugin "wizard"... Oct 07 18:09:07 elutuba volumio[15963]: info: Loading plugin "networkfs"... Oct 07 18:09:07 elutuba volumio[15963]: info: Starting Udev Watcher for removable devices Oct 07 18:09:07 elutuba volumio[15963]: info: Ignoring mount for partition: boot Oct 07 18:09:07 elutuba volumio[15963]: info: Ignoring mount for partition: volumio Oct 07 18:09:07 elutuba volumio[15963]: info: Ignoring mount for partition: volumio_data Oct 07 18:09:07 elutuba volumio[15963]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 07 18:09:07 elutuba volumio[15963]: info: Loading plugin "volumio_command_line_client"... Oct 07 18:09:07 elutuba volumio[15963]: info: Loading plugin "upnp"... Oct 07 18:09:07 elutuba volumio[15963]: info: [1728313747803] Starting Upmpd Daemon Oct 07 18:09:07 elutuba volumio[15963]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 07 18:09:07 elutuba volumio[15963]: info: Loading plugin "my_music"... Oct 07 18:09:07 elutuba volumio[15963]: info: Loading plugin "mpd"... Oct 07 18:09:08 elutuba volumio[15963]: info: Loading plugin "upnp_browser"... Oct 07 18:09:08 elutuba volumio-remote-updater[653]: [2024-10-07 18:09:08] [connect] Successful connection Oct 07 18:09:08 elutuba sudo[15994]: pam_unix(sudo:session): session closed for user root Oct 07 18:09:08 elutuba volumio[15963]: info: Loading plugin "alarm-clock"... Oct 07 18:09:08 elutuba volumio[15963]: info: Loading plugin "airplay_emulation"... Oct 07 18:09:08 elutuba volumio[15963]: info: Starting Shairport Sync Oct 07 18:09:08 elutuba volumio[15963]: info: Loading plugin "last_100"... Oct 07 18:09:08 elutuba volumio[15963]: info: Loading plugin "webradio"... Oct 07 18:09:08 elutuba volumio[15963]: info: Loading plugin "i2s_dacs"... Oct 07 18:09:08 elutuba volumio[15963]: info: I2S DAC not set, start Auto-detection Oct 07 18:09:08 elutuba volumio[15963]: info: Loading plugin "volumiodiscovery"... Oct 07 18:09:08 elutuba volumio[15963]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 07 18:09:08 elutuba node[15963]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 07 18:09:08 elutuba volumio[15963]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 07 18:09:08 elutuba node[15963]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 07 18:09:08 elutuba volumio[15963]: *** WARNING *** For more information see Oct 07 18:09:08 elutuba node[15963]: *** WARNING *** For more information see Oct 07 18:09:08 elutuba volumio[15963]: *** 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:09:08 elutuba node[15963]: *** 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:09:08 elutuba volumio[15963]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 07 18:09:08 elutuba node[15963]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 07 18:09:08 elutuba volumio[15963]: *** WARNING *** For more information see Oct 07 18:09:08 elutuba node[15963]: *** WARNING *** For more information see Oct 07 18:09:08 elutuba volumio[15963]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 07 18:09:08 elutuba volumio[15963]: info: Discovery: Started advertising with name: Elutuba Oct 07 18:09:08 elutuba volumio[15963]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 07 18:09:08 elutuba volumio[15963]: info: Loading plugin "outputs"... Oct 07 18:09:08 elutuba volumio[15963]: info: Loading plugin "albumart"... Oct 07 18:09:09 elutuba volumio[15963]: info: Plugin example_plugin is not enabled Oct 07 18:09:09 elutuba volumio[15963]: info: Loading plugin "inputs"... Oct 07 18:09:09 elutuba volumio[15963]: info: Loading plugin "updater_comm"... Oct 07 18:09:09 elutuba volumio[15963]: info: Plugin mpdemulation is not enabled Oct 07 18:09:09 elutuba volumio[15963]: info: Loading plugin "rest_api"... Oct 07 18:09:09 elutuba volumio[15963]: info: Loading plugin "websocket"... Oct 07 18:09:09 elutuba volumio[15963]: info: Starting Socket.io Server version 2.3.0 Oct 07 18:09:09 elutuba volumio[15963]: info: Loading plugin "fusiondsp"... Oct 07 18:09:09 elutuba volumio[15963]: Forking 3 albumart workers Oct 07 18:09:09 elutuba volumio[15963]: info: Applying required configuration parameters for plugin fusiondsp Oct 07 18:09:09 elutuba volumio[15963]: info: Loading i18n strings for locale en Oct 07 18:09:09 elutuba volumio[15963]: Updating browse sources language Oct 07 18:09:09 elutuba volumio[15963]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 07 18:09:10 elutuba volumio[15963]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 07 18:09:10 elutuba volumio[15963]: info: CoreCommandRouter::initPlayerControls Oct 07 18:09:10 elutuba volumio[15963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:10 elutuba volumio[15963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:10 elutuba volumio[15963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:10 elutuba volumio[15963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:10 elutuba volumio[15963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:10 elutuba volumio[15963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:10 elutuba volumio[15963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:10 elutuba volumio[15963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:10 elutuba volumio[15963]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 07 18:09:10 elutuba volumio[15963]: Express server listening on port 3000 Oct 07 18:09:10 elutuba volumio[15963]: [Metrics] WebUI: 5s 269.84ms Oct 07 18:09:10 elutuba volumio[15963]: info: CoreStateMachine::resetVolumioState Oct 07 18:09:10 elutuba volumio[15963]: info: CoreStateMachine::getcurrentVolume Oct 07 18:09:10 elutuba volumio[15963]: info: CoreCommandRouter::volumioRetrievevolume Oct 07 18:09:10 elutuba volumio[15963]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 07 18:09:10 elutuba volumio[15963]: info: Cannot read play queue from file Oct 07 18:09:10 elutuba volumio[15963]: 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:09:10 elutuba volumio[15963]: 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:09:10 elutuba volumio-remote-updater[653]: [2024-10-07 18:09:10] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1728313748 101 Oct 07 18:09:10 elutuba volumio[15963]: Starting albumart workers Oct 07 18:09:10 elutuba volumio[15963]: 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:09:10 elutuba volumio[15963]: 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:09:10 elutuba volumio[15963]: Starting albumart workers Oct 07 18:09:10 elutuba volumio[15963]: Starting albumart workers Oct 07 18:09:10 elutuba volumio[15963]: info: CoreCommandRouter::volumioGetState Oct 07 18:09:10 elutuba volumio[15963]: info: CorePlayQueue::getTrack 0 Oct 07 18:09:10 elutuba volumio[15963]: info: CoreStateMachine::setRepeat null single undefined Oct 07 18:09:10 elutuba volumio[15963]: info: CoreStateMachine::pushState Oct 07 18:09:10 elutuba volumio[15963]: info: CorePlayQueue::getTrack 0 Oct 07 18:09:10 elutuba volumio[15963]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 07 18:09:10 elutuba volumio[15963]: info: CoreCommandRouter::volumioPushState Oct 07 18:09:10 elutuba volumio[15963]: info: CoreStateMachine::setRandom null Oct 07 18:09:10 elutuba volumio[15963]: info: CoreStateMachine::pushState Oct 07 18:09:10 elutuba volumio[15963]: info: CorePlayQueue::getTrack 0 Oct 07 18:09:10 elutuba volumio[15963]: info: CoreCommandRouter::volumioPushState Oct 07 18:09:10 elutuba volumio[15963]: info: Setting Device type: Raspberry PI Oct 07 18:09:10 elutuba volumio[15963]: info: VolumeController:: Volume=0 Mute =false Oct 07 18:09:10 elutuba volumio[15963]: info: CoreStateMachine::pushState Oct 07 18:09:10 elutuba volumio[15963]: info: CorePlayQueue::getTrack 0 Oct 07 18:09:10 elutuba volumio[15963]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 07 18:09:10 elutuba volumio[15963]: info: CoreCommandRouter::volumioPushState Oct 07 18:09:10 elutuba volumio[15963]: info: CoreStateMachine::updateTrackBlock Oct 07 18:09:10 elutuba volumio[15963]: info: CorePlayQueue::getTrackBlock Oct 07 18:09:10 elutuba volumio[15963]: info: CoreCommandRouter::volumioRetrievevolume Oct 07 18:09:10 elutuba volumio[15963]: info: Completed loading Core Plugins Oct 07 18:09:11 elutuba volumio[15963]: info: Preparing to generate the ALSA configuration file Oct 07 18:09:11 elutuba volumio[15963]: info: Discovery: adding 320db26b-9bed-4586-9e4d-ee23fdd56bae Oct 07 18:09:11 elutuba volumio[15963]: info: Discovery: Found device Elutuba Oct 07 18:09:11 elutuba volumio[15963]: info: CoreCommandRouter::volumioGetState Oct 07 18:09:11 elutuba volumio[15963]: info: CorePlayQueue::getTrack 0 Oct 07 18:09:11 elutuba volumio[15963]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Oct 07 18:09:11 elutuba volumio[15963]: info: Reading ALSA contributions from plugins. Oct 07 18:09:11 elutuba volumio[15963]: info: Asound.conf file unchanged, so no further update is needed Oct 07 18:09:11 elutuba volumio[15963]: info: Output device has changed, restarting MPD Oct 07 18:09:11 elutuba volumio[15963]: info: Output device has changed, restarting Shairport Sync Oct 07 18:09:11 elutuba volumio[15963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:11 elutuba volumio[15963]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 07 18:09:11 elutuba sudo[16048]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 07 18:09:11 elutuba sudo[16048]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:09:11 elutuba sudo[16047]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 07 18:09:11 elutuba sudo[16047]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:09:11 elutuba sudo[16047]: pam_unix(sudo:session): session closed for user root Oct 07 18:09:11 elutuba volumio[15963]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 07 18:09:11 elutuba volumio[15963]: info: ___________ START PLUGINS ___________ Oct 07 18:09:11 elutuba volumio[15963]: info: ControllerMpd::onStart: Initializing MPD Oct 07 18:09:11 elutuba systemd[1]: Starting Cleanup of Temporary Directories... Oct 07 18:09:11 elutuba volumio[15963]: info: Creating MPD Configuration file Oct 07 18:09:11 elutuba systemd[1]: Stopping Music Player Daemon... Oct 07 18:09:11 elutuba systemd[1]: mpd.service: Succeeded. Oct 07 18:09:11 elutuba systemd[1]: Stopped Music Player Daemon. Oct 07 18:09:11 elutuba systemd[1]: Starting Music Player Daemon... Oct 07 18:09:11 elutuba systemd[1]: systemd-tmpfiles-clean.service: Succeeded. Oct 07 18:09:11 elutuba systemd[1]: Started Cleanup of Temporary Directories. Oct 07 18:09:11 elutuba volumio[15963]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 07 18:09:11 elutuba volumio[15963]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 07 18:09:11 elutuba volumio[15963]: info: [1728313751508] CoreMusicLibrary::Adding element Media Servers Oct 07 18:09:11 elutuba volumio[15963]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 07 18:09:11 elutuba sudo[16073]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 07 18:09:11 elutuba sudo[16073]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:09:11 elutuba sudo[16073]: pam_unix(sudo:session): session closed for user root Oct 07 18:09:11 elutuba volumio[15963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:11 elutuba volumio[15963]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 07 18:09:11 elutuba sudo[16075]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 07 18:09:11 elutuba sudo[16075]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:09:11 elutuba systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Oct 07 18:09:11 elutuba systemd[1]: mpd.service: Succeeded. Oct 07 18:09:11 elutuba systemd[1]: Stopped Music Player Daemon. Oct 07 18:09:11 elutuba volumio[15963]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 07 18:09:11 elutuba volumio[15963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:11 elutuba volumio[15963]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 07 18:09:11 elutuba systemd[1]: Starting Music Player Daemon... Oct 07 18:09:11 elutuba volumio[15963]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 07 18:09:11 elutuba volumio[15963]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 07 18:09:11 elutuba volumio[15963]: info: [1728313751702] CoreMusicLibrary::Adding element Last_100 Oct 07 18:09:11 elutuba volumio[15963]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 07 18:09:11 elutuba volumio[15963]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 07 18:09:11 elutuba volumio[15963]: info: [1728313751706] CoreMusicLibrary::Adding element Webradio Oct 07 18:09:11 elutuba volumio[15963]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 07 18:09:11 elutuba volumio[15963]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 07 18:09:11 elutuba volumio[15963]: info: Initializing BBC Radios Oct 07 18:09:11 elutuba volumio[15963]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 07 18:09:11 elutuba volumio[15963]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 07 18:09:11 elutuba volumio[15963]: info: Loading i18n strings for locale en Oct 07 18:09:11 elutuba volumio[15963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Oct 07 18:09:11 elutuba volumio[15963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:11 elutuba volumio[15963]: info: FusionDsp - mixtype--------------------- Hardware Oct 07 18:09:11 elutuba sudo[16089]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 07 18:09:11 elutuba sudo[16089]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:09:11 elutuba volumio[15963]: info: Volumio Calling Home Oct 07 18:09:11 elutuba sudo[16089]: pam_unix(sudo:session): session closed for user root Oct 07 18:09:11 elutuba volumio[15963]: info: Preparing to generate the ALSA configuration file Oct 07 18:09:12 elutuba volumio[15963]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Oct 07 18:09:12 elutuba volumio[15963]: info: Reading ALSA contributions from plugins. Oct 07 18:09:12 elutuba volumio[15963]: info: MPD Permissions set Oct 07 18:09:12 elutuba volumio[15963]: info: MPD Permissions set Oct 07 18:09:12 elutuba volumio[15963]: info: VolumeController:: Volume=0 Mute =false Oct 07 18:09:12 elutuba volumio[15963]: info: CoreStateMachine::pushState Oct 07 18:09:12 elutuba volumio[15963]: info: CorePlayQueue::getTrack 0 Oct 07 18:09:12 elutuba volumio[15963]: info: CoreCommandRouter::volumioPushState Oct 07 18:09:12 elutuba volumio[15963]: info: Volumio called home Oct 07 18:09:12 elutuba volumio[15963]: 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:09:12 elutuba volumio[15963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:12 elutuba volumio[15963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:12 elutuba volumio[15963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:12 elutuba volumio[15963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:12 elutuba volumio[15963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:12 elutuba volumio[15963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:12 elutuba volumio[15963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:12 elutuba volumio[15963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:12 elutuba volumio[15963]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 07 18:09:12 elutuba volumio[15963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:12 elutuba volumio[15963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:12 elutuba volumio[15963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:12 elutuba volumio[15963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:12 elutuba volumio[15963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:12 elutuba volumio[15963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:12 elutuba volumio[15963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:12 elutuba volumio[15963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:12 elutuba volumio[15963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:12 elutuba volumio[15963]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 07 18:09:12 elutuba volumio[15963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:12 elutuba volumio[15963]: info: Starting Shairport Sync Oct 07 18:09:12 elutuba volumio[15963]: info: Starting Shairport Sync Oct 07 18:09:12 elutuba volumio[15963]: info: Starting Shairport Sync Oct 07 18:09:12 elutuba sudo[16117]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 07 18:09:12 elutuba sudo[16117]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:09:12 elutuba sudo[16119]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 07 18:09:12 elutuba sudo[16119]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:09:12 elutuba volumio[15963]: info: CoreCommandRouter::volumioGetState Oct 07 18:09:12 elutuba volumio[15963]: info: CorePlayQueue::getTrack 0 Oct 07 18:09:12 elutuba sudo[16123]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 07 18:09:12 elutuba systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 07 18:09:12 elutuba sudo[16123]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:09:12 elutuba systemd[1]: shairport-sync.service: Succeeded. Oct 07 18:09:12 elutuba systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 07 18:09:12 elutuba volumio[15963]: info: Asound.conf file unchanged, so no further update is needed Oct 07 18:09:12 elutuba volumio[15963]: info: Output device has changed, restarting MPD Oct 07 18:09:12 elutuba volumio[15963]: info: Output device has changed, restarting Shairport Sync Oct 07 18:09:12 elutuba volumio[15963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:12 elutuba volumio[15963]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 07 18:09:12 elutuba sudo[16127]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 07 18:09:12 elutuba sudo[16127]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:09:12 elutuba sudo[16127]: pam_unix(sudo:session): session closed for user root Oct 07 18:09:12 elutuba sudo[16129]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 07 18:09:12 elutuba sudo[16129]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:09:12 elutuba systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 07 18:09:12 elutuba sudo[16117]: pam_unix(sudo:session): session closed for user root Oct 07 18:09:12 elutuba systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 07 18:09:12 elutuba sudo[16119]: pam_unix(sudo:session): session closed for user root Oct 07 18:09:12 elutuba systemd[1]: shairport-sync.service: Succeeded. Oct 07 18:09:12 elutuba systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 07 18:09:12 elutuba volumio[15963]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 07 18:09:12 elutuba systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 07 18:09:12 elutuba sudo[16123]: pam_unix(sudo:session): session closed for user root Oct 07 18:09:12 elutuba systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Oct 07 18:09:12 elutuba systemd[1]: mpd.service: Succeeded. Oct 07 18:09:12 elutuba systemd[1]: Stopped Music Player Daemon. Oct 07 18:09:12 elutuba systemd[1]: Starting Music Player Daemon... Oct 07 18:09:12 elutuba volumio[15963]: info: MPD Permissions set Oct 07 18:09:12 elutuba volumio[15963]: info: Shairport-Sync Started Oct 07 18:09:12 elutuba volumio[15963]: Error adding Membership: Error: addMembership EINVAL Oct 07 18:09:12 elutuba volumio[15963]: info: Shairport-Sync Started Oct 07 18:09:12 elutuba volumio[15963]: info: Shairport-Sync Started Oct 07 18:09:12 elutuba volumio[15963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:12 elutuba volumio[15963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:12 elutuba volumio[15963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:12 elutuba volumio[15963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:12 elutuba volumio[15963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:12 elutuba volumio[15963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:12 elutuba volumio[15963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:12 elutuba volumio[15963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:12 elutuba volumio[15963]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 07 18:09:12 elutuba volumio[15963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:12 elutuba volumio[15963]: info: Starting Shairport Sync Oct 07 18:09:12 elutuba sudo[16138]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 07 18:09:12 elutuba sudo[16138]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:09:12 elutuba sudo[16146]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 07 18:09:12 elutuba sudo[16146]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:09:12 elutuba sudo[16138]: pam_unix(sudo:session): session closed for user root Oct 07 18:09:12 elutuba systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 07 18:09:12 elutuba systemd[1]: shairport-sync.service: Succeeded. Oct 07 18:09:12 elutuba systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 07 18:09:12 elutuba systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 07 18:09:12 elutuba sudo[16146]: pam_unix(sudo:session): session closed for user root Oct 07 18:09:12 elutuba volumio[15963]: info: Shairport-Sync Started Oct 07 18:09:13 elutuba volumio[15963]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Oct 07 18:09:13 elutuba volumio[15963]: 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:09:13 elutuba volumio[15963]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Oct 07 18:09:13 elutuba volumio[15963]: info: camilladsp spawned new process with pid undefined, instance 1, run: true Oct 07 18:09:13 elutuba volumio[15963]: info: camilladsp service started and running in background, instance 1 Oct 07 18:09:13 elutuba volumio[15963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:13 elutuba volumio[15963]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Oct 07 18:09:13 elutuba volumio[15963]: 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:09:13 elutuba volumio[15963]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Oct 07 18:09:13 elutuba volumio[15963]: info: FusionDsp loaded Oct 07 18:09:13 elutuba volumio[15963]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 07 18:09:13 elutuba sudo[16164]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Oct 07 18:09:13 elutuba sudo[16164]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:09:13 elutuba volumio[15963]: info: FusionDsp - Reporting Fusion DSP Enabled Oct 07 18:09:13 elutuba volumio[15963]: info: Adding Signal Path Element [object Object] Oct 07 18:09:13 elutuba volumio[15963]: info: Adding fusiondspeq DSP Signal Path Element Oct 07 18:09:13 elutuba sudo[16164]: pam_unix(sudo:session): session closed for user root Oct 07 18:09:13 elutuba volumio[15963]: info: FusionDsp - ---- installed callbackRead Oct 07 18:09:13 elutuba volumio[15963]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 07 18:09:13 elutuba volumio[15963]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Oct 07 18:09:13 elutuba volumio[15963]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Oct 07 18:09:13 elutuba volumio[15963]: at onErrorNT (internal/child_process.js:465:16) Oct 07 18:09:13 elutuba volumio[15963]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Oct 07 18:09:13 elutuba volumio[15963]: errno: -2, Oct 07 18:09:13 elutuba volumio[15963]: code: 'ENOENT', Oct 07 18:09:13 elutuba volumio[15963]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Oct 07 18:09:13 elutuba volumio[15963]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Oct 07 18:09:13 elutuba volumio[15963]: spawnargs: [ Oct 07 18:09:13 elutuba volumio[15963]: '-p', Oct 07 18:09:13 elutuba volumio[15963]: 9876, Oct 07 18:09:13 elutuba volumio[15963]: '-o', Oct 07 18:09:13 elutuba volumio[15963]: '/tmp/camilladsp.log', Oct 07 18:09:13 elutuba volumio[15963]: '-l', Oct 07 18:09:13 elutuba volumio[15963]: 'warn', Oct 07 18:09:13 elutuba volumio[15963]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Oct 07 18:09:13 elutuba volumio[15963]: ] Oct 07 18:09:13 elutuba volumio[15963]: } Oct 07 18:09:13 elutuba volumio[15963]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 07 18:09:14 elutuba mpd[16149]: Oct 07 18:09 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 07 18:09:14 elutuba systemd[1]: Started Music Player Daemon. Oct 07 18:09:14 elutuba sudo[16075]: pam_unix(sudo:session): session closed for user root Oct 07 18:09:14 elutuba sudo[16129]: pam_unix(sudo:session): session closed for user root Oct 07 18:09:14 elutuba sudo[16048]: pam_unix(sudo:session): session closed for user root Oct 07 18:09:14 elutuba sudo[16177]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-07 18:08 Oct 07 18:09:14 elutuba sudo[16177]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:09:14 elutuba sudo[16177]: pam_unix(sudo:session): session closed for user root Oct 07 18:09:14 elutuba volumio-remote-updater[653]: [2024-10-07 18:09:14] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Oct 07 18:09:14 elutuba volumio-remote-updater[653]: [2024-10-07 18:09:14] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Oct 07 18:09:14 elutuba systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Oct 07 18:09:14 elutuba systemd[1]: volumio.service: Failed with result 'exit-code'. Oct 07 18:09:14 elutuba systemd[1]: Started dynamicswap service. Oct 07 18:09:14 elutuba systemd[1]: dynamicswap.service: Succeeded. Oct 07 18:09:15 elutuba systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Oct 07 18:09:15 elutuba systemd[1]: volumio.service: Scheduled restart job, restart counter is at 68. Oct 07 18:09:15 elutuba systemd[1]: Started dynamicswap service. Oct 07 18:09:15 elutuba systemd[1]: Stopped Volumio Backend Module. Oct 07 18:09:15 elutuba systemd[1]: Started Volumio Backend Module. Oct 07 18:09:15 elutuba systemd[1]: dynamicswap.service: Succeeded. Oct 07 18:09:17 elutuba volumio[16188]: info: ------------------------------------------- Oct 07 18:09:17 elutuba volumio[16188]: info: ----- Volumio3 ---- Oct 07 18:09:17 elutuba volumio[16188]: info: ------------------------------------------- Oct 07 18:09:17 elutuba volumio[16188]: info: ----- System startup ---- Oct 07 18:09:17 elutuba volumio[16188]: info: ------------------------------------------- Oct 07 18:09:17 elutuba volumio[16188]: info: MYVOLUMIO Environment detected Oct 07 18:09:17 elutuba volumio[16188]: info: Plugin folders cleanup Oct 07 18:09:17 elutuba volumio[16188]: info: Scanning into folder /volumio/app/plugins/ Oct 07 18:09:17 elutuba volumio[16188]: info: Scanning category audio_interface Oct 07 18:09:17 elutuba volumio[16188]: info: Scanning category miscellanea Oct 07 18:09:17 elutuba volumio[16188]: info: Scanning category music_service Oct 07 18:09:17 elutuba volumio[16188]: info: Scanning category plugins.json Oct 07 18:09:17 elutuba volumio[16188]: info: Scanning category system_controller Oct 07 18:09:17 elutuba volumio[16188]: info: Scanning category user_interface Oct 07 18:09:17 elutuba volumio[16188]: info: Scanning into folder /data/plugins/ Oct 07 18:09:17 elutuba volumio[16188]: info: Scanning category audio_interface Oct 07 18:09:17 elutuba volumio[16188]: info: Plugin folders cleanup completed Oct 07 18:09:17 elutuba volumio[16188]: info: ------------------------------------------- Oct 07 18:09:17 elutuba volumio[16188]: info: ----- Core plugins startup ---- Oct 07 18:09:17 elutuba volumio[16188]: info: ------------------------------------------- Oct 07 18:09:17 elutuba volumio[16188]: info: Loading plugins from folder /volumio/app/plugins/ Oct 07 18:09:17 elutuba volumio[16188]: info: Adding plugin upnp to MyMusic Plugins Oct 07 18:09:17 elutuba volumio[16188]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 07 18:09:17 elutuba volumio[16188]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 07 18:09:17 elutuba volumio[16188]: info: Loading plugins from folder /data/plugins/ Oct 07 18:09:17 elutuba volumio[16188]: info: Loading plugin "system"... Oct 07 18:09:17 elutuba volumio[16188]: info: Loading plugin "appearance"... Oct 07 18:09:19 elutuba volumio[16188]: info: Loading plugin "network"... Oct 07 18:09:19 elutuba volumio[16188]: info: Refreshing Cached IP Addresses Oct 07 18:09:19 elutuba sudo[16214]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 07 18:09:19 elutuba sudo[16214]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:09:19 elutuba sudo[16214]: pam_unix(sudo:session): session closed for user root Oct 07 18:09:19 elutuba volumio[16188]: info: Loading plugin "services"... Oct 07 18:09:19 elutuba sudo[16216]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 07 18:09:19 elutuba sudo[16216]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:09:19 elutuba volumio[16188]: info: Loading plugin "alsa_controller"... Oct 07 18:09:19 elutuba sudo[16216]: pam_unix(sudo:session): session closed for user root Oct 07 18:09:19 elutuba sudo[16219]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 07 18:09:19 elutuba sudo[16219]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:09:19 elutuba volumio[16188]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 07 18:09:19 elutuba volumio[16188]: info: Loading plugin "wizard"... Oct 07 18:09:19 elutuba volumio[16188]: info: Loading plugin "networkfs"... Oct 07 18:09:19 elutuba volumio[16188]: info: Starting Udev Watcher for removable devices Oct 07 18:09:19 elutuba volumio[16188]: info: Ignoring mount for partition: boot Oct 07 18:09:19 elutuba volumio[16188]: info: Ignoring mount for partition: volumio Oct 07 18:09:19 elutuba volumio[16188]: info: Ignoring mount for partition: volumio_data Oct 07 18:09:19 elutuba volumio[16188]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 07 18:09:19 elutuba volumio[16188]: info: Loading plugin "volumio_command_line_client"... Oct 07 18:09:19 elutuba volumio[16188]: info: Loading plugin "upnp"... Oct 07 18:09:19 elutuba volumio[16188]: info: [1728313759294] Starting Upmpd Daemon Oct 07 18:09:19 elutuba volumio[16188]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 07 18:09:19 elutuba volumio[16188]: info: Loading plugin "my_music"... Oct 07 18:09:19 elutuba volumio[16188]: info: Loading plugin "mpd"... Oct 07 18:09:19 elutuba volumio[16188]: info: Loading plugin "upnp_browser"... Oct 07 18:09:19 elutuba volumio-remote-updater[653]: [2024-10-07 18:09:19] [connect] Successful connection Oct 07 18:09:20 elutuba volumio[16188]: info: Loading plugin "alarm-clock"... Oct 07 18:09:20 elutuba sudo[16219]: pam_unix(sudo:session): session closed for user root Oct 07 18:09:20 elutuba volumio[16188]: info: Loading plugin "airplay_emulation"... Oct 07 18:09:20 elutuba volumio[16188]: info: Starting Shairport Sync Oct 07 18:09:20 elutuba volumio[16188]: info: Loading plugin "last_100"... Oct 07 18:09:20 elutuba volumio[16188]: info: Loading plugin "webradio"... Oct 07 18:09:20 elutuba volumio[16188]: info: Loading plugin "i2s_dacs"... Oct 07 18:09:20 elutuba volumio[16188]: info: I2S DAC not set, start Auto-detection Oct 07 18:09:20 elutuba volumio[16188]: info: Loading plugin "volumiodiscovery"... Oct 07 18:09:20 elutuba volumio[16188]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 07 18:09:20 elutuba volumio[16188]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 07 18:09:20 elutuba volumio[16188]: *** WARNING *** For more information see Oct 07 18:09:20 elutuba node[16188]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 07 18:09:20 elutuba volumio[16188]: *** 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:09:20 elutuba volumio[16188]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 07 18:09:20 elutuba volumio[16188]: *** WARNING *** For more information see Oct 07 18:09:20 elutuba node[16188]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 07 18:09:20 elutuba node[16188]: *** WARNING *** For more information see Oct 07 18:09:20 elutuba node[16188]: *** 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:09:20 elutuba node[16188]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 07 18:09:20 elutuba node[16188]: *** WARNING *** For more information see Oct 07 18:09:20 elutuba volumio[16188]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 07 18:09:20 elutuba volumio[16188]: info: Discovery: Started advertising with name: Elutuba Oct 07 18:09:20 elutuba volumio[16188]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 07 18:09:20 elutuba volumio[16188]: info: Loading plugin "outputs"... Oct 07 18:09:20 elutuba volumio[16188]: info: Loading plugin "albumart"... Oct 07 18:09:20 elutuba volumio[16188]: info: Plugin example_plugin is not enabled Oct 07 18:09:20 elutuba volumio[16188]: info: Loading plugin "inputs"... Oct 07 18:09:20 elutuba volumio[16188]: info: Loading plugin "updater_comm"... Oct 07 18:09:20 elutuba volumio[16188]: info: Plugin mpdemulation is not enabled Oct 07 18:09:20 elutuba volumio[16188]: info: Loading plugin "rest_api"... Oct 07 18:09:20 elutuba volumio[16188]: info: Loading plugin "websocket"... Oct 07 18:09:20 elutuba volumio[16188]: info: Starting Socket.io Server version 2.3.0 Oct 07 18:09:20 elutuba volumio[16188]: info: Loading plugin "fusiondsp"... Oct 07 18:09:20 elutuba volumio[16188]: Forking 3 albumart workers Oct 07 18:09:21 elutuba volumio[16188]: info: Applying required configuration parameters for plugin fusiondsp Oct 07 18:09:21 elutuba volumio[16188]: info: Loading i18n strings for locale en Oct 07 18:09:21 elutuba volumio[16188]: Updating browse sources language Oct 07 18:09:21 elutuba volumio[16188]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 07 18:09:21 elutuba volumio[16188]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 07 18:09:21 elutuba volumio[16188]: info: CoreCommandRouter::initPlayerControls Oct 07 18:09:21 elutuba volumio[16188]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:21 elutuba volumio[16188]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:21 elutuba volumio[16188]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:21 elutuba volumio[16188]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:21 elutuba volumio[16188]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:21 elutuba volumio[16188]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:21 elutuba volumio[16188]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:21 elutuba volumio[16188]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:21 elutuba volumio[16188]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 07 18:09:21 elutuba volumio[16188]: Express server listening on port 3000 Oct 07 18:09:21 elutuba volumio[16188]: [Metrics] WebUI: 5s 213.56ms Oct 07 18:09:21 elutuba volumio[16188]: info: CoreStateMachine::resetVolumioState Oct 07 18:09:21 elutuba volumio[16188]: info: CoreStateMachine::getcurrentVolume Oct 07 18:09:21 elutuba volumio[16188]: info: CoreCommandRouter::volumioRetrievevolume Oct 07 18:09:21 elutuba volumio[16188]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 07 18:09:21 elutuba volumio[16188]: info: Cannot read play queue from file Oct 07 18:09:21 elutuba volumio[16188]: 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:09:21 elutuba volumio[16188]: 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:09:21 elutuba volumio-remote-updater[653]: [2024-10-07 18:09:21] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1728313759 101 Oct 07 18:09:21 elutuba volumio[16188]: 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:09:22 elutuba volumio[16188]: info: CoreCommandRouter::volumioGetState Oct 07 18:09:22 elutuba volumio[16188]: info: CorePlayQueue::getTrack 0 Oct 07 18:09:22 elutuba volumio[16188]: Starting albumart workers Oct 07 18:09:22 elutuba volumio[16188]: Starting albumart workers Oct 07 18:09:22 elutuba volumio[16188]: Starting albumart workers Oct 07 18:09:22 elutuba volumio[16188]: info: VolumeController:: Volume=0 Mute =false Oct 07 18:09:22 elutuba volumio[16188]: info: CoreStateMachine::pushState Oct 07 18:09:22 elutuba volumio[16188]: info: CorePlayQueue::getTrack 0 Oct 07 18:09:22 elutuba volumio[16188]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 07 18:09:22 elutuba volumio[16188]: info: CoreCommandRouter::volumioPushState Oct 07 18:09:22 elutuba volumio[16188]: info: CoreStateMachine::updateTrackBlock Oct 07 18:09:22 elutuba volumio[16188]: info: CorePlayQueue::getTrackBlock Oct 07 18:09:22 elutuba volumio[16188]: info: CoreCommandRouter::volumioRetrievevolume Oct 07 18:09:22 elutuba volumio[16188]: info: CoreStateMachine::setRepeat null single undefined Oct 07 18:09:22 elutuba volumio[16188]: info: CoreStateMachine::pushState Oct 07 18:09:22 elutuba volumio[16188]: info: CorePlayQueue::getTrack 0 Oct 07 18:09:22 elutuba volumio[16188]: info: CoreCommandRouter::volumioPushState Oct 07 18:09:22 elutuba volumio[16188]: info: CoreStateMachine::setRandom null Oct 07 18:09:22 elutuba volumio[16188]: info: CoreStateMachine::pushState Oct 07 18:09:22 elutuba volumio[16188]: info: CorePlayQueue::getTrack 0 Oct 07 18:09:22 elutuba volumio[16188]: info: CoreCommandRouter::volumioPushState Oct 07 18:09:22 elutuba volumio[16188]: info: Setting Device type: Raspberry PI Oct 07 18:09:22 elutuba volumio[16188]: info: Listing playlists Oct 07 18:09:22 elutuba volumio[16188]: info: Listing playlists Oct 07 18:09:22 elutuba volumio[16188]: info: Completed loading Core Plugins Oct 07 18:09:22 elutuba volumio[16188]: info: Preparing to generate the ALSA configuration file Oct 07 18:09:22 elutuba volumio[16188]: info: Discovery: adding 320db26b-9bed-4586-9e4d-ee23fdd56bae Oct 07 18:09:22 elutuba volumio[16188]: info: Discovery: Found device Elutuba Oct 07 18:09:22 elutuba volumio[16188]: info: CoreCommandRouter::volumioGetState Oct 07 18:09:22 elutuba volumio[16188]: info: CorePlayQueue::getTrack 0 Oct 07 18:09:22 elutuba volumio[16188]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Oct 07 18:09:22 elutuba volumio[16188]: info: Reading ALSA contributions from plugins. Oct 07 18:09:22 elutuba volumio[16188]: info: VolumeController:: Volume=0 Mute =false Oct 07 18:09:22 elutuba volumio[16188]: info: CoreStateMachine::pushState Oct 07 18:09:22 elutuba volumio[16188]: info: CorePlayQueue::getTrack 0 Oct 07 18:09:22 elutuba volumio[16188]: info: CoreCommandRouter::volumioPushState Oct 07 18:09:22 elutuba volumio[16188]: info: Asound.conf file unchanged, so no further update is needed Oct 07 18:09:22 elutuba volumio[16188]: info: Output device has changed, restarting MPD Oct 07 18:09:22 elutuba sudo[16270]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 07 18:09:22 elutuba sudo[16270]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:09:22 elutuba volumio[16188]: info: Output device has changed, restarting Shairport Sync Oct 07 18:09:22 elutuba volumio[16188]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:22 elutuba volumio[16188]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 07 18:09:22 elutuba sudo[16270]: pam_unix(sudo:session): session closed for user root Oct 07 18:09:22 elutuba sudo[16272]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 07 18:09:22 elutuba sudo[16272]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:09:22 elutuba volumio[16188]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 07 18:09:22 elutuba volumio[16188]: info: ___________ START PLUGINS ___________ Oct 07 18:09:22 elutuba volumio[16188]: info: ControllerMpd::onStart: Initializing MPD Oct 07 18:09:22 elutuba volumio[16188]: info: Creating MPD Configuration file Oct 07 18:09:22 elutuba systemd[1]: Stopping Music Player Daemon... Oct 07 18:09:22 elutuba systemd[1]: mpd.service: Succeeded. Oct 07 18:09:22 elutuba systemd[1]: Stopped Music Player Daemon. Oct 07 18:09:22 elutuba sudo[16279]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 07 18:09:22 elutuba sudo[16279]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:09:22 elutuba volumio[16188]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 07 18:09:22 elutuba volumio[16188]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 07 18:09:22 elutuba volumio[16188]: info: [1728313762807] CoreMusicLibrary::Adding element Media Servers Oct 07 18:09:22 elutuba volumio[16188]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 07 18:09:22 elutuba systemd[1]: Starting Music Player Daemon... Oct 07 18:09:22 elutuba sudo[16279]: pam_unix(sudo:session): session closed for user root Oct 07 18:09:22 elutuba sudo[16282]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 07 18:09:22 elutuba sudo[16282]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:09:22 elutuba systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Oct 07 18:09:22 elutuba systemd[1]: mpd.service: Succeeded. Oct 07 18:09:22 elutuba systemd[1]: Stopped Music Player Daemon. Oct 07 18:09:22 elutuba systemd[1]: Starting Music Player Daemon... Oct 07 18:09:22 elutuba volumio[16188]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:22 elutuba volumio[16188]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 07 18:09:23 elutuba volumio[16188]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 07 18:09:23 elutuba volumio[16188]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:23 elutuba volumio[16188]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 07 18:09:23 elutuba sudo[16284]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 07 18:09:23 elutuba sudo[16284]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:09:23 elutuba sudo[16284]: pam_unix(sudo:session): session closed for user root Oct 07 18:09:23 elutuba volumio[16188]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 07 18:09:23 elutuba volumio[16188]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 07 18:09:23 elutuba volumio[16188]: info: [1728313763094] CoreMusicLibrary::Adding element Last_100 Oct 07 18:09:23 elutuba volumio[16188]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 07 18:09:23 elutuba volumio[16188]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 07 18:09:23 elutuba volumio[16188]: info: [1728313763100] CoreMusicLibrary::Adding element Webradio Oct 07 18:09:23 elutuba volumio[16188]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 07 18:09:23 elutuba volumio[16188]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 07 18:09:23 elutuba volumio[16188]: info: Initializing BBC Radios Oct 07 18:09:23 elutuba volumio[16188]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 07 18:09:23 elutuba volumio[16188]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 07 18:09:23 elutuba volumio[16188]: info: Loading i18n strings for locale en Oct 07 18:09:23 elutuba volumio[16188]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Oct 07 18:09:23 elutuba volumio[16188]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:23 elutuba volumio[16188]: info: FusionDsp - mixtype--------------------- Hardware Oct 07 18:09:23 elutuba volumio[16188]: info: Volumio Calling Home Oct 07 18:09:23 elutuba volumio[16188]: info: Preparing to generate the ALSA configuration file Oct 07 18:09:23 elutuba volumio[16188]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Oct 07 18:09:23 elutuba volumio[16188]: info: Reading ALSA contributions from plugins. Oct 07 18:09:23 elutuba volumio[16188]: info: MPD Permissions set Oct 07 18:09:23 elutuba volumio[16188]: info: MPD Permissions set Oct 07 18:09:23 elutuba volumio[16188]: info: Volumio called home Oct 07 18:09:23 elutuba volumio[16188]: 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:09:23 elutuba volumio[16188]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:23 elutuba volumio[16188]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:23 elutuba volumio[16188]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:23 elutuba volumio[16188]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:23 elutuba volumio[16188]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:23 elutuba volumio[16188]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:23 elutuba volumio[16188]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:23 elutuba volumio[16188]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:23 elutuba volumio[16188]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 07 18:09:23 elutuba volumio[16188]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:23 elutuba volumio[16188]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:23 elutuba volumio[16188]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:23 elutuba volumio[16188]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:23 elutuba volumio[16188]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:23 elutuba volumio[16188]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:23 elutuba volumio[16188]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:23 elutuba volumio[16188]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:23 elutuba volumio[16188]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:23 elutuba volumio[16188]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 07 18:09:23 elutuba volumio[16188]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:23 elutuba volumio[16188]: info: Starting Shairport Sync Oct 07 18:09:23 elutuba volumio[16188]: info: Starting Shairport Sync Oct 07 18:09:23 elutuba volumio[16188]: info: Starting Shairport Sync Oct 07 18:09:23 elutuba sudo[16339]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 07 18:09:23 elutuba sudo[16339]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:09:23 elutuba sudo[16341]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 07 18:09:23 elutuba sudo[16341]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:09:23 elutuba volumio[16188]: info: CoreCommandRouter::volumioGetState Oct 07 18:09:23 elutuba volumio[16188]: info: CorePlayQueue::getTrack 0 Oct 07 18:09:23 elutuba systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 07 18:09:23 elutuba sudo[16345]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 07 18:09:23 elutuba sudo[16345]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:09:23 elutuba systemd[1]: shairport-sync.service: Succeeded. Oct 07 18:09:23 elutuba systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 07 18:09:23 elutuba volumio[16188]: info: Asound.conf file unchanged, so no further update is needed Oct 07 18:09:23 elutuba volumio[16188]: info: Output device has changed, restarting MPD Oct 07 18:09:24 elutuba volumio[16188]: info: Output device has changed, restarting Shairport Sync Oct 07 18:09:24 elutuba volumio[16188]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:24 elutuba volumio[16188]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 07 18:09:24 elutuba sudo[16349]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 07 18:09:24 elutuba sudo[16349]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:09:24 elutuba systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 07 18:09:24 elutuba sudo[16341]: pam_unix(sudo:session): session closed for user root Oct 07 18:09:24 elutuba sudo[16339]: pam_unix(sudo:session): session closed for user root Oct 07 18:09:24 elutuba sudo[16351]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 07 18:09:24 elutuba sudo[16351]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:09:24 elutuba sudo[16349]: pam_unix(sudo:session): session closed for user root Oct 07 18:09:24 elutuba systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 07 18:09:24 elutuba systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Oct 07 18:09:24 elutuba systemd[1]: shairport-sync.service: Succeeded. Oct 07 18:09:24 elutuba systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 07 18:09:24 elutuba volumio[16188]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 07 18:09:24 elutuba systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 07 18:09:24 elutuba sudo[16345]: pam_unix(sudo:session): session closed for user root Oct 07 18:09:24 elutuba systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Oct 07 18:09:24 elutuba systemd[1]: mpd.service: Succeeded. Oct 07 18:09:24 elutuba systemd[1]: Stopped Music Player Daemon. Oct 07 18:09:24 elutuba systemd[1]: Starting Music Player Daemon... Oct 07 18:09:24 elutuba volumio[16188]: info: MPD Permissions set Oct 07 18:09:24 elutuba volumio[16188]: info: Shairport-Sync Started Oct 07 18:09:24 elutuba volumio[16188]: Error adding Membership: Error: addMembership EINVAL Oct 07 18:09:24 elutuba volumio[16188]: info: Shairport-Sync Started Oct 07 18:09:24 elutuba volumio[16188]: info: Shairport-Sync Started Oct 07 18:09:24 elutuba volumio[16188]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:24 elutuba volumio[16188]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:24 elutuba volumio[16188]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:24 elutuba volumio[16188]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:24 elutuba volumio[16188]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:24 elutuba volumio[16188]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:24 elutuba volumio[16188]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:24 elutuba volumio[16188]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:24 elutuba volumio[16188]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 07 18:09:24 elutuba volumio[16188]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:24 elutuba volumio[16188]: info: Starting Shairport Sync Oct 07 18:09:24 elutuba sudo[16366]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 07 18:09:24 elutuba sudo[16366]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:09:24 elutuba sudo[16358]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 07 18:09:24 elutuba sudo[16358]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:09:24 elutuba sudo[16358]: pam_unix(sudo:session): session closed for user root Oct 07 18:09:24 elutuba systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 07 18:09:24 elutuba systemd[1]: shairport-sync.service: Succeeded. Oct 07 18:09:24 elutuba systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 07 18:09:24 elutuba systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 07 18:09:24 elutuba sudo[16366]: pam_unix(sudo:session): session closed for user root Oct 07 18:09:24 elutuba volumio[16188]: info: Shairport-Sync Started Oct 07 18:09:25 elutuba volumio[16188]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Oct 07 18:09:25 elutuba volumio[16188]: 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:09:25 elutuba volumio[16188]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Oct 07 18:09:25 elutuba volumio[16188]: info: camilladsp spawned new process with pid undefined, instance 1, run: true Oct 07 18:09:25 elutuba volumio[16188]: info: camilladsp service started and running in background, instance 1 Oct 07 18:09:25 elutuba volumio[16188]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:25 elutuba volumio[16188]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Oct 07 18:09:25 elutuba volumio[16188]: 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:09:25 elutuba volumio[16188]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Oct 07 18:09:25 elutuba volumio[16188]: info: FusionDsp loaded Oct 07 18:09:25 elutuba volumio[16188]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 07 18:09:25 elutuba sudo[16384]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Oct 07 18:09:25 elutuba sudo[16384]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:09:25 elutuba volumio[16188]: info: FusionDsp - Reporting Fusion DSP Enabled Oct 07 18:09:25 elutuba volumio[16188]: info: Adding Signal Path Element [object Object] Oct 07 18:09:25 elutuba volumio[16188]: info: Adding fusiondspeq DSP Signal Path Element Oct 07 18:09:25 elutuba volumio[16188]: info: FusionDsp - ---- installed callbackRead Oct 07 18:09:25 elutuba volumio[16188]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 07 18:09:25 elutuba sudo[16384]: pam_unix(sudo:session): session closed for user root Oct 07 18:09:25 elutuba volumio[16188]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Oct 07 18:09:25 elutuba volumio[16188]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Oct 07 18:09:25 elutuba volumio[16188]: at onErrorNT (internal/child_process.js:465:16) Oct 07 18:09:25 elutuba volumio[16188]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Oct 07 18:09:25 elutuba volumio[16188]: errno: -2, Oct 07 18:09:25 elutuba volumio[16188]: code: 'ENOENT', Oct 07 18:09:25 elutuba volumio[16188]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Oct 07 18:09:25 elutuba volumio[16188]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Oct 07 18:09:25 elutuba volumio[16188]: spawnargs: [ Oct 07 18:09:25 elutuba volumio[16188]: '-p', Oct 07 18:09:25 elutuba volumio[16188]: 9876, Oct 07 18:09:25 elutuba volumio[16188]: '-o', Oct 07 18:09:25 elutuba volumio[16188]: '/tmp/camilladsp.log', Oct 07 18:09:25 elutuba volumio[16188]: '-l', Oct 07 18:09:25 elutuba volumio[16188]: 'warn', Oct 07 18:09:25 elutuba volumio[16188]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Oct 07 18:09:25 elutuba volumio[16188]: ] Oct 07 18:09:25 elutuba volumio[16188]: } Oct 07 18:09:25 elutuba volumio[16188]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 07 18:09:25 elutuba mpd[16369]: Oct 07 18:09 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 07 18:09:25 elutuba systemd[1]: Started Music Player Daemon. Oct 07 18:09:25 elutuba sudo[16272]: pam_unix(sudo:session): session closed for user root Oct 07 18:09:25 elutuba sudo[16282]: pam_unix(sudo:session): session closed for user root Oct 07 18:09:25 elutuba sudo[16351]: pam_unix(sudo:session): session closed for user root Oct 07 18:09:26 elutuba sudo[16397]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-07 18:08 Oct 07 18:09:26 elutuba sudo[16397]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:09:26 elutuba sudo[16397]: pam_unix(sudo:session): session closed for user root Oct 07 18:09:26 elutuba volumio-remote-updater[653]: [2024-10-07 18:09:26] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Oct 07 18:09:26 elutuba volumio-remote-updater[653]: [2024-10-07 18:09:26] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Oct 07 18:09:26 elutuba systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Oct 07 18:09:26 elutuba systemd[1]: volumio.service: Failed with result 'exit-code'. Oct 07 18:09:26 elutuba systemd[1]: Started dynamicswap service. Oct 07 18:09:26 elutuba systemd[1]: dynamicswap.service: Succeeded. Oct 07 18:09:26 elutuba systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Oct 07 18:09:26 elutuba systemd[1]: volumio.service: Scheduled restart job, restart counter is at 69. Oct 07 18:09:26 elutuba systemd[1]: Stopped Volumio Backend Module. Oct 07 18:09:26 elutuba systemd[1]: Started Volumio Backend Module. Oct 07 18:09:26 elutuba systemd[1]: Started dynamicswap service. Oct 07 18:09:26 elutuba systemd[1]: dynamicswap.service: Succeeded. Oct 07 18:09:28 elutuba volumio[16408]: info: ------------------------------------------- Oct 07 18:09:28 elutuba volumio[16408]: info: ----- Volumio3 ---- Oct 07 18:09:28 elutuba volumio[16408]: info: ------------------------------------------- Oct 07 18:09:28 elutuba volumio[16408]: info: ----- System startup ---- Oct 07 18:09:28 elutuba volumio[16408]: info: ------------------------------------------- Oct 07 18:09:29 elutuba volumio[16408]: info: MYVOLUMIO Environment detected Oct 07 18:09:29 elutuba volumio[16408]: info: Plugin folders cleanup Oct 07 18:09:29 elutuba volumio[16408]: info: Scanning into folder /volumio/app/plugins/ Oct 07 18:09:29 elutuba volumio[16408]: info: Scanning category audio_interface Oct 07 18:09:29 elutuba volumio[16408]: info: Scanning category miscellanea Oct 07 18:09:29 elutuba volumio[16408]: info: Scanning category music_service Oct 07 18:09:29 elutuba volumio[16408]: info: Scanning category plugins.json Oct 07 18:09:29 elutuba volumio[16408]: info: Scanning category system_controller Oct 07 18:09:29 elutuba volumio[16408]: info: Scanning category user_interface Oct 07 18:09:29 elutuba volumio[16408]: info: Scanning into folder /data/plugins/ Oct 07 18:09:29 elutuba volumio[16408]: info: Scanning category audio_interface Oct 07 18:09:29 elutuba volumio[16408]: info: Plugin folders cleanup completed Oct 07 18:09:29 elutuba volumio[16408]: info: ------------------------------------------- Oct 07 18:09:29 elutuba volumio[16408]: info: ----- Core plugins startup ---- Oct 07 18:09:29 elutuba volumio[16408]: info: ------------------------------------------- Oct 07 18:09:29 elutuba volumio[16408]: info: Loading plugins from folder /volumio/app/plugins/ Oct 07 18:09:29 elutuba volumio[16408]: info: Adding plugin upnp to MyMusic Plugins Oct 07 18:09:29 elutuba volumio[16408]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 07 18:09:29 elutuba volumio[16408]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 07 18:09:29 elutuba volumio[16408]: info: Loading plugins from folder /data/plugins/ Oct 07 18:09:29 elutuba volumio[16408]: info: Loading plugin "system"... Oct 07 18:09:29 elutuba volumio[16408]: info: Loading plugin "appearance"... Oct 07 18:09:30 elutuba volumio[16408]: info: Loading plugin "network"... Oct 07 18:09:30 elutuba volumio[16408]: info: Refreshing Cached IP Addresses Oct 07 18:09:30 elutuba sudo[16436]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 07 18:09:30 elutuba sudo[16436]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:09:30 elutuba sudo[16436]: pam_unix(sudo:session): session closed for user root Oct 07 18:09:30 elutuba sudo[16438]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 07 18:09:30 elutuba volumio[16408]: info: Loading plugin "services"... Oct 07 18:09:30 elutuba sudo[16438]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:09:30 elutuba volumio[16408]: info: Loading plugin "alsa_controller"... Oct 07 18:09:30 elutuba sudo[16438]: pam_unix(sudo:session): session closed for user root Oct 07 18:09:30 elutuba sudo[16441]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 07 18:09:30 elutuba sudo[16441]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:09:30 elutuba volumio[16408]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 07 18:09:30 elutuba volumio[16408]: info: Loading plugin "wizard"... Oct 07 18:09:30 elutuba volumio[16408]: info: Loading plugin "networkfs"... Oct 07 18:09:30 elutuba volumio[16408]: info: Starting Udev Watcher for removable devices Oct 07 18:09:30 elutuba volumio[16408]: info: Ignoring mount for partition: boot Oct 07 18:09:30 elutuba volumio[16408]: info: Ignoring mount for partition: volumio Oct 07 18:09:30 elutuba volumio[16408]: info: Ignoring mount for partition: volumio_data Oct 07 18:09:30 elutuba volumio[16408]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 07 18:09:30 elutuba volumio[16408]: info: Loading plugin "volumio_command_line_client"... Oct 07 18:09:30 elutuba volumio[16408]: info: Loading plugin "upnp"... Oct 07 18:09:30 elutuba volumio[16408]: info: [1728313770789] Starting Upmpd Daemon Oct 07 18:09:30 elutuba volumio[16408]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 07 18:09:30 elutuba volumio[16408]: info: Loading plugin "my_music"... Oct 07 18:09:30 elutuba volumio[16408]: info: Loading plugin "mpd"... Oct 07 18:09:31 elutuba volumio[16408]: info: Loading plugin "upnp_browser"... Oct 07 18:09:31 elutuba volumio-remote-updater[653]: [2024-10-07 18:09:31] [connect] Successful connection Oct 07 18:09:31 elutuba volumio[16408]: info: Loading plugin "alarm-clock"... Oct 07 18:09:31 elutuba sudo[16441]: pam_unix(sudo:session): session closed for user root Oct 07 18:09:31 elutuba volumio[16408]: info: Loading plugin "airplay_emulation"... Oct 07 18:09:31 elutuba volumio[16408]: info: Starting Shairport Sync Oct 07 18:09:31 elutuba volumio[16408]: info: Loading plugin "last_100"... Oct 07 18:09:31 elutuba volumio[16408]: info: Loading plugin "webradio"... Oct 07 18:09:31 elutuba volumio[16408]: info: Loading plugin "i2s_dacs"... Oct 07 18:09:31 elutuba volumio[16408]: info: I2S DAC not set, start Auto-detection Oct 07 18:09:31 elutuba volumio[16408]: info: Loading plugin "volumiodiscovery"... Oct 07 18:09:31 elutuba volumio[16408]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 07 18:09:31 elutuba volumio[16408]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 07 18:09:31 elutuba volumio[16408]: *** WARNING *** For more information see Oct 07 18:09:31 elutuba node[16408]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 07 18:09:31 elutuba volumio[16408]: *** 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:09:31 elutuba volumio[16408]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 07 18:09:31 elutuba volumio[16408]: *** WARNING *** For more information see Oct 07 18:09:31 elutuba node[16408]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 07 18:09:31 elutuba node[16408]: *** WARNING *** For more information see Oct 07 18:09:31 elutuba node[16408]: *** 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:09:31 elutuba node[16408]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 07 18:09:31 elutuba node[16408]: *** WARNING *** For more information see Oct 07 18:09:31 elutuba volumio[16408]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 07 18:09:31 elutuba volumio[16408]: info: Discovery: Started advertising with name: Elutuba Oct 07 18:09:31 elutuba volumio[16408]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 07 18:09:31 elutuba volumio[16408]: info: Loading plugin "outputs"... Oct 07 18:09:31 elutuba volumio[16408]: info: Loading plugin "albumart"... Oct 07 18:09:31 elutuba volumio[16408]: info: Plugin example_plugin is not enabled Oct 07 18:09:31 elutuba volumio[16408]: info: Loading plugin "inputs"... Oct 07 18:09:31 elutuba volumio[16408]: info: Loading plugin "updater_comm"... Oct 07 18:09:32 elutuba volumio[16408]: info: Plugin mpdemulation is not enabled Oct 07 18:09:32 elutuba volumio[16408]: info: Loading plugin "rest_api"... Oct 07 18:09:32 elutuba volumio[16408]: info: Loading plugin "websocket"... Oct 07 18:09:32 elutuba volumio[16408]: info: Starting Socket.io Server version 2.3.0 Oct 07 18:09:32 elutuba volumio[16408]: info: Loading plugin "fusiondsp"... Oct 07 18:09:32 elutuba volumio[16408]: Forking 3 albumart workers Oct 07 18:09:32 elutuba volumio[16408]: info: Applying required configuration parameters for plugin fusiondsp Oct 07 18:09:32 elutuba volumio[16408]: info: Loading i18n strings for locale en Oct 07 18:09:32 elutuba volumio[16408]: Updating browse sources language Oct 07 18:09:32 elutuba volumio[16408]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 07 18:09:33 elutuba volumio[16408]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 07 18:09:33 elutuba volumio[16408]: info: CoreCommandRouter::initPlayerControls Oct 07 18:09:33 elutuba volumio[16408]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:33 elutuba volumio[16408]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:33 elutuba volumio[16408]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:33 elutuba volumio[16408]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:33 elutuba volumio[16408]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:33 elutuba volumio[16408]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:33 elutuba volumio[16408]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:33 elutuba volumio[16408]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:33 elutuba volumio[16408]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 07 18:09:33 elutuba volumio[16408]: Express server listening on port 3000 Oct 07 18:09:33 elutuba volumio[16408]: [Metrics] WebUI: 5s 249.34ms Oct 07 18:09:33 elutuba volumio[16408]: info: CoreStateMachine::resetVolumioState Oct 07 18:09:33 elutuba volumio[16408]: info: CoreStateMachine::getcurrentVolume Oct 07 18:09:33 elutuba volumio[16408]: info: CoreCommandRouter::volumioRetrievevolume Oct 07 18:09:33 elutuba volumio[16408]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 07 18:09:33 elutuba volumio[16408]: info: Cannot read play queue from file Oct 07 18:09:33 elutuba volumio[16408]: 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:09:33 elutuba volumio[16408]: 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:09:33 elutuba volumio-remote-updater[653]: [2024-10-07 18:09:33] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1728313771 101 Oct 07 18:09:33 elutuba volumio[16408]: 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:09:33 elutuba volumio[16408]: Starting albumart workers Oct 07 18:09:33 elutuba volumio[16408]: Starting albumart workers Oct 07 18:09:33 elutuba volumio[16408]: Starting albumart workers Oct 07 18:09:33 elutuba volumio[16408]: info: CoreCommandRouter::volumioGetState Oct 07 18:09:33 elutuba volumio[16408]: info: CorePlayQueue::getTrack 0 Oct 07 18:09:33 elutuba volumio[16408]: info: VolumeController:: Volume=0 Mute =false Oct 07 18:09:33 elutuba volumio[16408]: info: CoreStateMachine::pushState Oct 07 18:09:33 elutuba volumio[16408]: info: CorePlayQueue::getTrack 0 Oct 07 18:09:33 elutuba volumio[16408]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 07 18:09:33 elutuba volumio[16408]: info: CoreCommandRouter::volumioPushState Oct 07 18:09:33 elutuba volumio[16408]: info: CoreStateMachine::updateTrackBlock Oct 07 18:09:33 elutuba volumio[16408]: info: CorePlayQueue::getTrackBlock Oct 07 18:09:33 elutuba volumio[16408]: info: CoreCommandRouter::volumioRetrievevolume Oct 07 18:09:33 elutuba volumio[16408]: info: CoreStateMachine::setRepeat null single undefined Oct 07 18:09:33 elutuba volumio[16408]: info: CoreStateMachine::pushState Oct 07 18:09:33 elutuba volumio[16408]: info: CorePlayQueue::getTrack 0 Oct 07 18:09:33 elutuba volumio[16408]: info: CoreCommandRouter::volumioPushState Oct 07 18:09:33 elutuba volumio[16408]: info: CoreStateMachine::setRandom null Oct 07 18:09:33 elutuba volumio[16408]: info: CoreStateMachine::pushState Oct 07 18:09:33 elutuba volumio[16408]: info: CorePlayQueue::getTrack 0 Oct 07 18:09:33 elutuba volumio[16408]: info: CoreCommandRouter::volumioPushState Oct 07 18:09:33 elutuba volumio[16408]: info: Setting Device type: Raspberry PI Oct 07 18:09:33 elutuba volumio[16408]: info: Completed loading Core Plugins Oct 07 18:09:33 elutuba volumio[16408]: info: Preparing to generate the ALSA configuration file Oct 07 18:09:34 elutuba volumio[16408]: info: Discovery: adding 320db26b-9bed-4586-9e4d-ee23fdd56bae Oct 07 18:09:34 elutuba volumio[16408]: info: Discovery: Found device Elutuba Oct 07 18:09:34 elutuba volumio[16408]: info: CoreCommandRouter::volumioGetState Oct 07 18:09:34 elutuba volumio[16408]: info: CorePlayQueue::getTrack 0 Oct 07 18:09:34 elutuba volumio[16408]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Oct 07 18:09:34 elutuba volumio[16408]: info: Reading ALSA contributions from plugins. Oct 07 18:09:34 elutuba volumio[16408]: info: VolumeController:: Volume=0 Mute =false Oct 07 18:09:34 elutuba volumio[16408]: info: CoreStateMachine::pushState Oct 07 18:09:34 elutuba volumio[16408]: info: CorePlayQueue::getTrack 0 Oct 07 18:09:34 elutuba volumio[16408]: info: CoreCommandRouter::volumioPushState Oct 07 18:09:34 elutuba volumio[16408]: info: Asound.conf file unchanged, so no further update is needed Oct 07 18:09:34 elutuba volumio[16408]: info: Output device has changed, restarting MPD Oct 07 18:09:34 elutuba volumio[16408]: info: Output device has changed, restarting Shairport Sync Oct 07 18:09:34 elutuba volumio[16408]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:34 elutuba volumio[16408]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 07 18:09:34 elutuba sudo[16492]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 07 18:09:34 elutuba sudo[16492]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:09:34 elutuba sudo[16493]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 07 18:09:34 elutuba sudo[16493]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:09:34 elutuba sudo[16492]: pam_unix(sudo:session): session closed for user root Oct 07 18:09:34 elutuba volumio[16408]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 07 18:09:34 elutuba volumio[16408]: info: ___________ START PLUGINS ___________ Oct 07 18:09:34 elutuba volumio[16408]: info: ControllerMpd::onStart: Initializing MPD Oct 07 18:09:34 elutuba volumio[16408]: info: Creating MPD Configuration file Oct 07 18:09:34 elutuba systemd[1]: Stopping Music Player Daemon... Oct 07 18:09:34 elutuba systemd[1]: mpd.service: Succeeded. Oct 07 18:09:34 elutuba systemd[1]: Stopped Music Player Daemon. Oct 07 18:09:34 elutuba volumio[16408]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 07 18:09:34 elutuba volumio[16408]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 07 18:09:34 elutuba volumio[16408]: info: [1728313774315] CoreMusicLibrary::Adding element Media Servers Oct 07 18:09:34 elutuba volumio[16408]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 07 18:09:34 elutuba sudo[16501]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 07 18:09:34 elutuba systemd[1]: Starting Music Player Daemon... Oct 07 18:09:34 elutuba sudo[16501]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:09:34 elutuba volumio[16408]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:34 elutuba volumio[16408]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 07 18:09:34 elutuba sudo[16503]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 07 18:09:34 elutuba sudo[16503]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:09:34 elutuba sudo[16501]: pam_unix(sudo:session): session closed for user root Oct 07 18:09:34 elutuba systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Oct 07 18:09:34 elutuba systemd[1]: mpd.service: Succeeded. Oct 07 18:09:34 elutuba systemd[1]: Stopped Music Player Daemon. Oct 07 18:09:34 elutuba systemd[1]: Starting Music Player Daemon... Oct 07 18:09:34 elutuba volumio[16408]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 07 18:09:34 elutuba volumio[16408]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:34 elutuba volumio[16408]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 07 18:09:34 elutuba volumio[16408]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 07 18:09:34 elutuba volumio[16408]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 07 18:09:34 elutuba volumio[16408]: info: [1728313774639] CoreMusicLibrary::Adding element Last_100 Oct 07 18:09:34 elutuba volumio[16408]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 07 18:09:34 elutuba volumio[16408]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 07 18:09:34 elutuba sudo[16521]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 07 18:09:34 elutuba volumio[16408]: info: [1728313774646] CoreMusicLibrary::Adding element Webradio Oct 07 18:09:34 elutuba volumio[16408]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 07 18:09:34 elutuba sudo[16521]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:09:34 elutuba volumio[16408]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 07 18:09:34 elutuba sudo[16521]: pam_unix(sudo:session): session closed for user root Oct 07 18:09:34 elutuba volumio[16408]: info: Initializing BBC Radios Oct 07 18:09:34 elutuba volumio[16408]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 07 18:09:34 elutuba volumio[16408]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 07 18:09:34 elutuba volumio[16408]: info: Loading i18n strings for locale en Oct 07 18:09:34 elutuba volumio[16408]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Oct 07 18:09:34 elutuba volumio[16408]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:34 elutuba volumio[16408]: info: FusionDsp - mixtype--------------------- Hardware Oct 07 18:09:34 elutuba volumio[16408]: info: Volumio Calling Home Oct 07 18:09:34 elutuba volumio[16408]: info: Preparing to generate the ALSA configuration file Oct 07 18:09:35 elutuba volumio[16408]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Oct 07 18:09:35 elutuba volumio[16408]: info: Reading ALSA contributions from plugins. Oct 07 18:09:35 elutuba volumio[16408]: info: MPD Permissions set Oct 07 18:09:35 elutuba volumio[16408]: info: MPD Permissions set Oct 07 18:09:35 elutuba volumio[16408]: 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:09:35 elutuba volumio[16408]: info: Volumio called home Oct 07 18:09:35 elutuba volumio[16408]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:35 elutuba volumio[16408]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:35 elutuba volumio[16408]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:35 elutuba volumio[16408]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:35 elutuba volumio[16408]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:35 elutuba volumio[16408]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:35 elutuba volumio[16408]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:35 elutuba volumio[16408]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:35 elutuba volumio[16408]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 07 18:09:35 elutuba volumio[16408]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:35 elutuba volumio[16408]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:35 elutuba volumio[16408]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:35 elutuba volumio[16408]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:35 elutuba volumio[16408]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:35 elutuba volumio[16408]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:35 elutuba volumio[16408]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:35 elutuba volumio[16408]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:35 elutuba volumio[16408]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:35 elutuba volumio[16408]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 07 18:09:35 elutuba volumio[16408]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:35 elutuba volumio[16408]: info: Starting Shairport Sync Oct 07 18:09:35 elutuba volumio[16408]: info: Starting Shairport Sync Oct 07 18:09:35 elutuba volumio[16408]: info: Starting Shairport Sync Oct 07 18:09:35 elutuba sudo[16561]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 07 18:09:35 elutuba sudo[16561]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:09:35 elutuba sudo[16563]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 07 18:09:35 elutuba sudo[16563]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:09:35 elutuba systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 07 18:09:35 elutuba systemd[1]: shairport-sync.service: Succeeded. Oct 07 18:09:35 elutuba systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 07 18:09:35 elutuba sudo[16567]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 07 18:09:35 elutuba sudo[16567]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:09:35 elutuba volumio[16408]: info: CoreCommandRouter::volumioGetState Oct 07 18:09:35 elutuba volumio[16408]: info: CorePlayQueue::getTrack 0 Oct 07 18:09:35 elutuba systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 07 18:09:35 elutuba sudo[16561]: pam_unix(sudo:session): session closed for user root Oct 07 18:09:35 elutuba systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 07 18:09:35 elutuba systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Oct 07 18:09:35 elutuba systemd[1]: shairport-sync.service: Succeeded. Oct 07 18:09:35 elutuba systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 07 18:09:35 elutuba systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 07 18:09:35 elutuba sudo[16567]: pam_unix(sudo:session): session closed for user root Oct 07 18:09:35 elutuba sudo[16563]: pam_unix(sudo:session): session closed for user root Oct 07 18:09:35 elutuba volumio[16408]: info: Shairport-Sync Started Oct 07 18:09:35 elutuba volumio[16408]: Error adding Membership: Error: addMembership EINVAL Oct 07 18:09:35 elutuba volumio[16408]: info: Asound.conf file unchanged, so no further update is needed Oct 07 18:09:35 elutuba volumio[16408]: info: Output device has changed, restarting MPD Oct 07 18:09:35 elutuba volumio[16408]: info: Output device has changed, restarting Shairport Sync Oct 07 18:09:35 elutuba volumio[16408]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:35 elutuba volumio[16408]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 07 18:09:35 elutuba sudo[16574]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 07 18:09:35 elutuba sudo[16574]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:09:35 elutuba sudo[16574]: pam_unix(sudo:session): session closed for user root Oct 07 18:09:35 elutuba sudo[16576]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 07 18:09:35 elutuba sudo[16576]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:09:35 elutuba systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Oct 07 18:09:35 elutuba systemd[1]: mpd.service: Succeeded. Oct 07 18:09:35 elutuba systemd[1]: Stopped Music Player Daemon. Oct 07 18:09:35 elutuba volumio[16408]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 07 18:09:35 elutuba volumio[16408]: info: Shairport-Sync Started Oct 07 18:09:35 elutuba volumio[16408]: info: Shairport-Sync Started Oct 07 18:09:35 elutuba systemd[1]: Starting Music Player Daemon... Oct 07 18:09:35 elutuba volumio[16408]: info: MPD Permissions set Oct 07 18:09:35 elutuba volumio[16408]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:35 elutuba volumio[16408]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:35 elutuba volumio[16408]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:35 elutuba volumio[16408]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:35 elutuba volumio[16408]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:35 elutuba volumio[16408]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:35 elutuba volumio[16408]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:35 elutuba volumio[16408]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:35 elutuba volumio[16408]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 07 18:09:35 elutuba volumio[16408]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:35 elutuba volumio[16408]: info: Starting Shairport Sync Oct 07 18:09:35 elutuba sudo[16588]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 07 18:09:35 elutuba sudo[16588]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:09:35 elutuba sudo[16591]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 07 18:09:35 elutuba sudo[16591]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:09:35 elutuba systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 07 18:09:35 elutuba sudo[16591]: pam_unix(sudo:session): session closed for user root Oct 07 18:09:35 elutuba systemd[1]: shairport-sync.service: Succeeded. Oct 07 18:09:35 elutuba systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 07 18:09:35 elutuba sudo[16582]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 07 18:09:35 elutuba sudo[16582]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:09:35 elutuba sudo[16582]: pam_unix(sudo:session): session closed for user root Oct 07 18:09:35 elutuba sudo[16594]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 07 18:09:35 elutuba sudo[16594]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:09:35 elutuba volumio[16408]: 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:09:35 elutuba sudo[16594]: pam_unix(sudo:session): session closed for user root Oct 07 18:09:35 elutuba systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 07 18:09:36 elutuba sudo[16588]: pam_unix(sudo:session): session closed for user root Oct 07 18:09:36 elutuba volumio[16408]: info: Shairport-Sync Started Oct 07 18:09:36 elutuba volumio[16408]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 07 18:09:36 elutuba volumio[16408]: info: CoreCommandRouter::volumioGetVisibleSources Oct 07 18:09:36 elutuba volumio[16408]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 07 18:09:36 elutuba volumio[16408]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 07 18:09:36 elutuba volumio[16408]: info: CoreCommandRouter::volumioGetState Oct 07 18:09:36 elutuba volumio[16408]: info: CorePlayQueue::getTrack 0 Oct 07 18:09:36 elutuba volumio[16408]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 07 18:09:36 elutuba volumio[16408]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 07 18:09:36 elutuba volumio[16408]: info: Received Get System Info Oct 07 18:09:36 elutuba volumio[16408]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 07 18:09:36 elutuba volumio[16408]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 07 18:09:36 elutuba volumio[16408]: info: Discovery: Getting this device information Oct 07 18:09:36 elutuba volumio[16408]: info: CoreCommandRouter::volumioGetState Oct 07 18:09:36 elutuba volumio[16408]: info: CorePlayQueue::getTrack 0 Oct 07 18:09:36 elutuba volumio[16408]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 07 18:09:36 elutuba volumio[16408]: info: CoreCommandRouter::volumioGetState Oct 07 18:09:36 elutuba volumio[16408]: info: CorePlayQueue::getTrack 0 Oct 07 18:09:36 elutuba volumio[16408]: info: Listing playlists Oct 07 18:09:36 elutuba volumio[16408]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 07 18:09:36 elutuba volumio[16408]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 07 18:09:36 elutuba volumio[16408]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 07 18:09:36 elutuba volumio[16408]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 07 18:09:37 elutuba volumio[16408]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Oct 07 18:09:37 elutuba volumio[16408]: 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:09:37 elutuba volumio[16408]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Oct 07 18:09:37 elutuba volumio[16408]: info: camilladsp spawned new process with pid undefined, instance 1, run: true Oct 07 18:09:37 elutuba volumio[16408]: info: camilladsp service started and running in background, instance 1 Oct 07 18:09:37 elutuba volumio[16408]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:37 elutuba volumio[16408]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Oct 07 18:09:37 elutuba volumio[16408]: 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:09:37 elutuba volumio[16408]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Oct 07 18:09:37 elutuba volumio[16408]: info: FusionDsp loaded Oct 07 18:09:37 elutuba volumio[16408]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 07 18:09:37 elutuba sudo[16651]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Oct 07 18:09:37 elutuba sudo[16651]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:09:37 elutuba volumio[16408]: info: FusionDsp - Reporting Fusion DSP Enabled Oct 07 18:09:37 elutuba volumio[16408]: info: Adding Signal Path Element [object Object] Oct 07 18:09:37 elutuba volumio[16408]: info: Adding fusiondspeq DSP Signal Path Element Oct 07 18:09:37 elutuba volumio[16408]: info: FusionDsp - ---- installed callbackRead Oct 07 18:09:37 elutuba volumio[16408]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 07 18:09:37 elutuba sudo[16651]: pam_unix(sudo:session): session closed for user root Oct 07 18:09:37 elutuba volumio[16408]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Oct 07 18:09:37 elutuba volumio[16408]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Oct 07 18:09:37 elutuba volumio[16408]: at onErrorNT (internal/child_process.js:465:16) Oct 07 18:09:37 elutuba volumio[16408]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Oct 07 18:09:37 elutuba volumio[16408]: at runNextTicks (internal/process/task_queues.js:62:3) Oct 07 18:09:37 elutuba volumio[16408]: at listOnTimeout (internal/timers.js:523:9) Oct 07 18:09:37 elutuba volumio[16408]: at processTimers (internal/timers.js:497:7) { Oct 07 18:09:37 elutuba volumio[16408]: errno: -2, Oct 07 18:09:37 elutuba volumio[16408]: code: 'ENOENT', Oct 07 18:09:37 elutuba volumio[16408]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Oct 07 18:09:37 elutuba volumio[16408]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Oct 07 18:09:37 elutuba volumio[16408]: spawnargs: [ Oct 07 18:09:37 elutuba volumio[16408]: '-p', Oct 07 18:09:37 elutuba volumio[16408]: 9876, Oct 07 18:09:37 elutuba volumio[16408]: '-o', Oct 07 18:09:37 elutuba volumio[16408]: '/tmp/camilladsp.log', Oct 07 18:09:37 elutuba volumio[16408]: '-l', Oct 07 18:09:37 elutuba volumio[16408]: 'warn', Oct 07 18:09:37 elutuba volumio[16408]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Oct 07 18:09:37 elutuba volumio[16408]: ] Oct 07 18:09:37 elutuba volumio[16408]: } Oct 07 18:09:37 elutuba volumio[16408]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 07 18:09:37 elutuba mpd[16598]: Oct 07 18:09 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 07 18:09:37 elutuba systemd[1]: Started Music Player Daemon. Oct 07 18:09:37 elutuba sudo[16576]: pam_unix(sudo:session): session closed for user root Oct 07 18:09:37 elutuba sudo[16493]: pam_unix(sudo:session): session closed for user root Oct 07 18:09:37 elutuba sudo[16503]: pam_unix(sudo:session): session closed for user root Oct 07 18:09:37 elutuba sudo[16664]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-07 18:08 Oct 07 18:09:37 elutuba sudo[16664]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:09:38 elutuba sudo[16664]: pam_unix(sudo:session): session closed for user root Oct 07 18:09:38 elutuba volumio-remote-updater[653]: [2024-10-07 18:09:38] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Oct 07 18:09:38 elutuba volumio-remote-updater[653]: [2024-10-07 18:09:38] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Oct 07 18:09:38 elutuba systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Oct 07 18:09:38 elutuba systemd[1]: volumio.service: Failed with result 'exit-code'. Oct 07 18:09:38 elutuba systemd[1]: Started dynamicswap service. Oct 07 18:09:38 elutuba systemd[1]: dynamicswap.service: Succeeded. Oct 07 18:09:38 elutuba systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Oct 07 18:09:38 elutuba systemd[1]: volumio.service: Scheduled restart job, restart counter is at 70. Oct 07 18:09:38 elutuba systemd[1]: Started dynamicswap service. Oct 07 18:09:38 elutuba systemd[1]: Stopped Volumio Backend Module. Oct 07 18:09:38 elutuba systemd[1]: Started Volumio Backend Module. Oct 07 18:09:38 elutuba systemd[1]: dynamicswap.service: Succeeded. Oct 07 18:09:40 elutuba volumio[16675]: info: ------------------------------------------- Oct 07 18:09:40 elutuba volumio[16675]: info: ----- Volumio3 ---- Oct 07 18:09:40 elutuba volumio[16675]: info: ------------------------------------------- Oct 07 18:09:40 elutuba volumio[16675]: info: ----- System startup ---- Oct 07 18:09:40 elutuba volumio[16675]: info: ------------------------------------------- Oct 07 18:09:41 elutuba volumio[16675]: info: MYVOLUMIO Environment detected Oct 07 18:09:41 elutuba volumio[16675]: info: Plugin folders cleanup Oct 07 18:09:41 elutuba volumio[16675]: info: Scanning into folder /volumio/app/plugins/ Oct 07 18:09:41 elutuba volumio[16675]: info: Scanning category audio_interface Oct 07 18:09:41 elutuba volumio[16675]: info: Scanning category miscellanea Oct 07 18:09:41 elutuba volumio[16675]: info: Scanning category music_service Oct 07 18:09:41 elutuba volumio[16675]: info: Scanning category plugins.json Oct 07 18:09:41 elutuba volumio[16675]: info: Scanning category system_controller Oct 07 18:09:41 elutuba volumio[16675]: info: Scanning category user_interface Oct 07 18:09:41 elutuba volumio[16675]: info: Scanning into folder /data/plugins/ Oct 07 18:09:41 elutuba volumio[16675]: info: Scanning category audio_interface Oct 07 18:09:41 elutuba volumio[16675]: info: Plugin folders cleanup completed Oct 07 18:09:41 elutuba volumio[16675]: info: ------------------------------------------- Oct 07 18:09:41 elutuba volumio[16675]: info: ----- Core plugins startup ---- Oct 07 18:09:41 elutuba volumio[16675]: info: ------------------------------------------- Oct 07 18:09:41 elutuba volumio[16675]: info: Loading plugins from folder /volumio/app/plugins/ Oct 07 18:09:41 elutuba volumio[16675]: info: Adding plugin upnp to MyMusic Plugins Oct 07 18:09:41 elutuba volumio[16675]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 07 18:09:41 elutuba volumio[16675]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 07 18:09:41 elutuba volumio[16675]: info: Loading plugins from folder /data/plugins/ Oct 07 18:09:41 elutuba volumio[16675]: info: Loading plugin "system"... Oct 07 18:09:41 elutuba volumio[16675]: info: Loading plugin "appearance"... Oct 07 18:09:42 elutuba volumio[16675]: info: Loading plugin "network"... Oct 07 18:09:42 elutuba volumio[16675]: info: Refreshing Cached IP Addresses Oct 07 18:09:42 elutuba sudo[16701]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 07 18:09:42 elutuba sudo[16701]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:09:42 elutuba sudo[16701]: pam_unix(sudo:session): session closed for user root Oct 07 18:09:42 elutuba sudo[16703]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 07 18:09:42 elutuba volumio[16675]: info: Loading plugin "services"... Oct 07 18:09:42 elutuba sudo[16703]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:09:42 elutuba volumio[16675]: info: Loading plugin "alsa_controller"... Oct 07 18:09:42 elutuba sudo[16703]: pam_unix(sudo:session): session closed for user root Oct 07 18:09:42 elutuba sudo[16706]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 07 18:09:42 elutuba sudo[16706]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:09:42 elutuba volumio[16675]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 07 18:09:42 elutuba volumio[16675]: info: Loading plugin "wizard"... Oct 07 18:09:42 elutuba volumio[16675]: info: Loading plugin "networkfs"... Oct 07 18:09:42 elutuba volumio[16675]: info: Starting Udev Watcher for removable devices Oct 07 18:09:42 elutuba volumio[16675]: info: Ignoring mount for partition: boot Oct 07 18:09:42 elutuba volumio[16675]: info: Ignoring mount for partition: volumio Oct 07 18:09:42 elutuba volumio[16675]: info: Ignoring mount for partition: volumio_data Oct 07 18:09:42 elutuba volumio[16675]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 07 18:09:42 elutuba volumio[16675]: info: Loading plugin "volumio_command_line_client"... Oct 07 18:09:42 elutuba volumio[16675]: info: Loading plugin "upnp"... Oct 07 18:09:42 elutuba volumio[16675]: info: [1728313782530] Starting Upmpd Daemon Oct 07 18:09:42 elutuba volumio[16675]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 07 18:09:42 elutuba volumio[16675]: info: Loading plugin "my_music"... Oct 07 18:09:42 elutuba volumio[16675]: info: Loading plugin "mpd"... Oct 07 18:09:42 elutuba volumio[16675]: info: Loading plugin "upnp_browser"... Oct 07 18:09:43 elutuba volumio-remote-updater[653]: [2024-10-07 18:09:43] [connect] Successful connection Oct 07 18:09:43 elutuba volumio[16675]: info: Loading plugin "alarm-clock"... Oct 07 18:09:43 elutuba sudo[16706]: pam_unix(sudo:session): session closed for user root Oct 07 18:09:43 elutuba volumio[16675]: info: Loading plugin "airplay_emulation"... Oct 07 18:09:43 elutuba volumio[16675]: info: Starting Shairport Sync Oct 07 18:09:43 elutuba volumio[16675]: info: Loading plugin "last_100"... Oct 07 18:09:43 elutuba volumio[16675]: info: Loading plugin "webradio"... Oct 07 18:09:43 elutuba volumio[16675]: info: Loading plugin "i2s_dacs"... Oct 07 18:09:43 elutuba volumio[16675]: info: I2S DAC not set, start Auto-detection Oct 07 18:09:43 elutuba volumio[16675]: info: Loading plugin "volumiodiscovery"... Oct 07 18:09:43 elutuba volumio[16675]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 07 18:09:43 elutuba volumio[16675]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 07 18:09:43 elutuba volumio[16675]: *** WARNING *** For more information see Oct 07 18:09:43 elutuba volumio[16675]: *** 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:09:43 elutuba node[16675]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 07 18:09:43 elutuba volumio[16675]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 07 18:09:43 elutuba volumio[16675]: *** WARNING *** For more information see Oct 07 18:09:43 elutuba node[16675]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 07 18:09:43 elutuba node[16675]: *** WARNING *** For more information see Oct 07 18:09:43 elutuba node[16675]: *** 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:09:43 elutuba node[16675]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 07 18:09:43 elutuba node[16675]: *** WARNING *** For more information see Oct 07 18:09:43 elutuba volumio[16675]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 07 18:09:43 elutuba volumio[16675]: info: Discovery: Started advertising with name: Elutuba Oct 07 18:09:43 elutuba volumio[16675]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 07 18:09:43 elutuba volumio[16675]: info: Loading plugin "outputs"... Oct 07 18:09:43 elutuba volumio[16675]: info: Loading plugin "albumart"... Oct 07 18:09:43 elutuba volumio[16675]: info: Plugin example_plugin is not enabled Oct 07 18:09:43 elutuba volumio[16675]: info: Loading plugin "inputs"... Oct 07 18:09:43 elutuba volumio[16675]: info: Loading plugin "updater_comm"... Oct 07 18:09:43 elutuba volumio[16675]: info: Plugin mpdemulation is not enabled Oct 07 18:09:43 elutuba volumio[16675]: info: Loading plugin "rest_api"... Oct 07 18:09:43 elutuba volumio[16675]: info: Loading plugin "websocket"... Oct 07 18:09:43 elutuba volumio[16675]: info: Starting Socket.io Server version 2.3.0 Oct 07 18:09:43 elutuba volumio[16675]: info: Loading plugin "fusiondsp"... Oct 07 18:09:44 elutuba volumio[16675]: Forking 3 albumart workers Oct 07 18:09:44 elutuba volumio[16675]: info: Applying required configuration parameters for plugin fusiondsp Oct 07 18:09:44 elutuba volumio[16675]: info: Loading i18n strings for locale en Oct 07 18:09:44 elutuba volumio[16675]: Updating browse sources language Oct 07 18:09:44 elutuba volumio[16675]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 07 18:09:44 elutuba volumio[16675]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 07 18:09:44 elutuba volumio[16675]: info: CoreCommandRouter::initPlayerControls Oct 07 18:09:44 elutuba volumio[16675]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:44 elutuba volumio[16675]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:44 elutuba volumio[16675]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:44 elutuba volumio[16675]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:44 elutuba volumio[16675]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:44 elutuba volumio[16675]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:44 elutuba volumio[16675]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:44 elutuba volumio[16675]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:44 elutuba volumio[16675]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 07 18:09:44 elutuba volumio[16675]: Express server listening on port 3000 Oct 07 18:09:44 elutuba volumio[16675]: [Metrics] WebUI: 5s 219.26ms Oct 07 18:09:44 elutuba volumio[16675]: info: CoreStateMachine::resetVolumioState Oct 07 18:09:44 elutuba volumio[16675]: info: CoreStateMachine::getcurrentVolume Oct 07 18:09:44 elutuba volumio[16675]: info: CoreCommandRouter::volumioRetrievevolume Oct 07 18:09:44 elutuba volumio[16675]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 07 18:09:45 elutuba volumio[16675]: info: Cannot read play queue from file Oct 07 18:09:45 elutuba volumio[16675]: 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:09:45 elutuba volumio[16675]: 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:09:45 elutuba volumio-remote-updater[653]: [2024-10-07 18:09:45] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1728313783 101 Oct 07 18:09:45 elutuba volumio[16675]: 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:09:45 elutuba volumio[16675]: 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:09:45 elutuba volumio[16675]: Starting albumart workers Oct 07 18:09:45 elutuba volumio[16675]: Starting albumart workers Oct 07 18:09:45 elutuba volumio[16675]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 07 18:09:45 elutuba volumio[16675]: info: CoreStateMachine::setRepeat null single undefined Oct 07 18:09:45 elutuba volumio[16675]: info: CoreStateMachine::pushState Oct 07 18:09:45 elutuba volumio[16675]: info: CorePlayQueue::getTrack 0 Oct 07 18:09:45 elutuba volumio[16675]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 07 18:09:45 elutuba volumio[16675]: info: CoreCommandRouter::volumioPushState Oct 07 18:09:45 elutuba volumio[16675]: info: CoreStateMachine::setRandom null Oct 07 18:09:45 elutuba volumio[16675]: info: CoreStateMachine::pushState Oct 07 18:09:45 elutuba volumio[16675]: info: CorePlayQueue::getTrack 0 Oct 07 18:09:45 elutuba volumio[16675]: info: CoreCommandRouter::volumioPushState Oct 07 18:09:45 elutuba volumio[16675]: info: Setting Device type: Raspberry PI Oct 07 18:09:45 elutuba volumio[16675]: Starting albumart workers Oct 07 18:09:45 elutuba volumio[16675]: info: CoreCommandRouter::volumioGetVisibleSources Oct 07 18:09:45 elutuba volumio[16675]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 07 18:09:45 elutuba volumio[16675]: info: CoreCommandRouter::volumioGetState Oct 07 18:09:45 elutuba volumio[16675]: info: CorePlayQueue::getTrack 0 Oct 07 18:09:45 elutuba volumio[16675]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 07 18:09:45 elutuba volumio[16675]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 07 18:09:45 elutuba volumio[16675]: info: Received Get System Info Oct 07 18:09:45 elutuba volumio[16675]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 07 18:09:45 elutuba volumio[16675]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 07 18:09:45 elutuba volumio[16675]: info: Discovery: Getting this device information Oct 07 18:09:45 elutuba volumio[16675]: info: CoreCommandRouter::volumioGetState Oct 07 18:09:45 elutuba volumio[16675]: info: CorePlayQueue::getTrack 0 Oct 07 18:09:45 elutuba volumio[16675]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 07 18:09:45 elutuba volumio[16675]: info: CoreCommandRouter::volumioGetState Oct 07 18:09:45 elutuba volumio[16675]: info: CorePlayQueue::getTrack 0 Oct 07 18:09:45 elutuba volumio[16675]: info: Listing playlists Oct 07 18:09:45 elutuba volumio[16675]: info: VolumeController:: Volume=0 Mute =false Oct 07 18:09:45 elutuba volumio[16675]: info: CoreStateMachine::pushState Oct 07 18:09:45 elutuba volumio[16675]: info: CorePlayQueue::getTrack 0 Oct 07 18:09:45 elutuba volumio[16675]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 07 18:09:45 elutuba volumio[16675]: info: CoreCommandRouter::volumioPushState Oct 07 18:09:45 elutuba volumio[16675]: info: CoreStateMachine::updateTrackBlock Oct 07 18:09:45 elutuba volumio[16675]: info: CorePlayQueue::getTrackBlock Oct 07 18:09:45 elutuba volumio[16675]: info: CoreCommandRouter::volumioRetrievevolume Oct 07 18:09:45 elutuba volumio[16675]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 07 18:09:45 elutuba volumio[16675]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 07 18:09:45 elutuba volumio[16675]: info: Discovery: adding 320db26b-9bed-4586-9e4d-ee23fdd56bae Oct 07 18:09:45 elutuba volumio[16675]: info: Discovery: Found device Elutuba Oct 07 18:09:45 elutuba volumio[16675]: info: CoreCommandRouter::volumioGetState Oct 07 18:09:45 elutuba volumio[16675]: info: CorePlayQueue::getTrack 0 Oct 07 18:09:45 elutuba volumio[16675]: info: VolumeController:: Volume=0 Mute =false Oct 07 18:09:45 elutuba volumio[16675]: info: CoreStateMachine::pushState Oct 07 18:09:45 elutuba volumio[16675]: info: CorePlayQueue::getTrack 0 Oct 07 18:09:45 elutuba volumio[16675]: info: CoreCommandRouter::volumioPushState Oct 07 18:09:45 elutuba volumio[16675]: info: Completed loading Core Plugins Oct 07 18:09:45 elutuba volumio[16675]: info: Preparing to generate the ALSA configuration file Oct 07 18:09:45 elutuba volumio[16675]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Oct 07 18:09:45 elutuba volumio[16675]: info: Reading ALSA contributions from plugins. Oct 07 18:09:46 elutuba volumio[16675]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 07 18:09:46 elutuba volumio[16675]: info: Asound.conf file unchanged, so no further update is needed Oct 07 18:09:46 elutuba volumio[16675]: info: Output device has changed, restarting MPD Oct 07 18:09:46 elutuba sudo[16780]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 07 18:09:46 elutuba sudo[16780]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:09:46 elutuba sudo[16780]: pam_unix(sudo:session): session closed for user root Oct 07 18:09:46 elutuba volumio[16675]: info: Output device has changed, restarting Shairport Sync Oct 07 18:09:46 elutuba volumio[16675]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:46 elutuba volumio[16675]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 07 18:09:46 elutuba sudo[16783]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 07 18:09:46 elutuba sudo[16783]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:09:46 elutuba systemd[1]: Stopping Music Player Daemon... Oct 07 18:09:46 elutuba volumio[16675]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 07 18:09:46 elutuba volumio[16675]: info: ___________ START PLUGINS ___________ Oct 07 18:09:46 elutuba volumio[16675]: info: ControllerMpd::onStart: Initializing MPD Oct 07 18:09:46 elutuba volumio[16675]: info: Creating MPD Configuration file Oct 07 18:09:46 elutuba systemd[1]: mpd.service: Succeeded. Oct 07 18:09:46 elutuba systemd[1]: Stopped Music Player Daemon. Oct 07 18:09:46 elutuba sudo[16790]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 07 18:09:46 elutuba sudo[16790]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:09:46 elutuba volumio[16675]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 07 18:09:46 elutuba volumio[16675]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 07 18:09:46 elutuba volumio[16675]: info: [1728313786892] CoreMusicLibrary::Adding element Media Servers Oct 07 18:09:46 elutuba volumio[16675]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 07 18:09:46 elutuba volumio[16675]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:46 elutuba volumio[16675]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 07 18:09:46 elutuba sudo[16790]: pam_unix(sudo:session): session closed for user root Oct 07 18:09:46 elutuba systemd[1]: Starting Music Player Daemon... Oct 07 18:09:46 elutuba sudo[16792]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 07 18:09:46 elutuba sudo[16792]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:09:46 elutuba systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Oct 07 18:09:46 elutuba systemd[1]: mpd.service: Succeeded. Oct 07 18:09:46 elutuba systemd[1]: Stopped Music Player Daemon. Oct 07 18:09:46 elutuba systemd[1]: Starting Music Player Daemon... Oct 07 18:09:46 elutuba volumio[16675]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 07 18:09:46 elutuba volumio[16675]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:46 elutuba volumio[16675]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 07 18:09:47 elutuba volumio[16675]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 07 18:09:47 elutuba volumio[16675]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 07 18:09:47 elutuba volumio[16675]: info: [1728313787043] CoreMusicLibrary::Adding element Last_100 Oct 07 18:09:47 elutuba volumio[16675]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 07 18:09:47 elutuba volumio[16675]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 07 18:09:47 elutuba volumio[16675]: info: [1728313787047] CoreMusicLibrary::Adding element Webradio Oct 07 18:09:47 elutuba volumio[16675]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 07 18:09:47 elutuba volumio[16675]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 07 18:09:47 elutuba volumio[16675]: info: Initializing BBC Radios Oct 07 18:09:47 elutuba sudo[16798]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 07 18:09:47 elutuba sudo[16798]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:09:47 elutuba sudo[16798]: pam_unix(sudo:session): session closed for user root Oct 07 18:09:47 elutuba volumio[16675]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 07 18:09:47 elutuba volumio[16675]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 07 18:09:47 elutuba volumio[16675]: info: Loading i18n strings for locale en Oct 07 18:09:47 elutuba volumio[16675]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Oct 07 18:09:47 elutuba volumio[16675]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:47 elutuba volumio[16675]: info: FusionDsp - mixtype--------------------- Hardware Oct 07 18:09:47 elutuba volumio[16675]: info: Volumio Calling Home Oct 07 18:09:47 elutuba volumio[16675]: info: Preparing to generate the ALSA configuration file Oct 07 18:09:47 elutuba volumio[16675]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Oct 07 18:09:47 elutuba volumio[16675]: info: Reading ALSA contributions from plugins. Oct 07 18:09:47 elutuba volumio[16675]: info: MPD Permissions set Oct 07 18:09:47 elutuba volumio[16675]: info: MPD Permissions set Oct 07 18:09:47 elutuba volumio[16675]: 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:09:47 elutuba volumio[16675]: info: Volumio called home Oct 07 18:09:47 elutuba volumio[16675]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:47 elutuba volumio[16675]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:47 elutuba volumio[16675]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:47 elutuba volumio[16675]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:47 elutuba volumio[16675]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:47 elutuba volumio[16675]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:47 elutuba volumio[16675]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:47 elutuba volumio[16675]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:47 elutuba volumio[16675]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 07 18:09:47 elutuba volumio[16675]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:47 elutuba volumio[16675]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:47 elutuba volumio[16675]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:47 elutuba volumio[16675]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:47 elutuba volumio[16675]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:47 elutuba volumio[16675]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:47 elutuba volumio[16675]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:47 elutuba volumio[16675]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:47 elutuba volumio[16675]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:47 elutuba volumio[16675]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 07 18:09:47 elutuba volumio[16675]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:47 elutuba volumio[16675]: info: Starting Shairport Sync Oct 07 18:09:47 elutuba volumio[16675]: info: Starting Shairport Sync Oct 07 18:09:47 elutuba volumio[16675]: info: Starting Shairport Sync Oct 07 18:09:47 elutuba sudo[16826]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 07 18:09:47 elutuba sudo[16826]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:09:47 elutuba sudo[16828]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 07 18:09:47 elutuba sudo[16828]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:09:47 elutuba volumio[16675]: info: CoreCommandRouter::volumioGetState Oct 07 18:09:47 elutuba volumio[16675]: info: CorePlayQueue::getTrack 0 Oct 07 18:09:47 elutuba sudo[16832]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 07 18:09:47 elutuba sudo[16832]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:09:47 elutuba systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 07 18:09:47 elutuba systemd[1]: shairport-sync.service: Succeeded. Oct 07 18:09:47 elutuba systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 07 18:09:47 elutuba volumio[16675]: info: Asound.conf file unchanged, so no further update is needed Oct 07 18:09:47 elutuba volumio[16675]: info: Output device has changed, restarting MPD Oct 07 18:09:47 elutuba volumio[16675]: info: Output device has changed, restarting Shairport Sync Oct 07 18:09:47 elutuba volumio[16675]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:47 elutuba sudo[16835]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 07 18:09:47 elutuba volumio[16675]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 07 18:09:47 elutuba sudo[16835]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:09:47 elutuba sudo[16835]: pam_unix(sudo:session): session closed for user root Oct 07 18:09:47 elutuba sudo[16838]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 07 18:09:47 elutuba sudo[16838]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:09:47 elutuba systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 07 18:09:47 elutuba sudo[16826]: pam_unix(sudo:session): session closed for user root Oct 07 18:09:47 elutuba volumio[16675]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 07 18:09:47 elutuba sudo[16828]: pam_unix(sudo:session): session closed for user root Oct 07 18:09:48 elutuba systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 07 18:09:48 elutuba systemd[1]: shairport-sync.service: Succeeded. Oct 07 18:09:48 elutuba systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 07 18:09:48 elutuba volumio[16675]: info: MPD Permissions set Oct 07 18:09:48 elutuba systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Oct 07 18:09:48 elutuba volumio[16675]: info: Shairport-Sync Started Oct 07 18:09:48 elutuba systemd[1]: mpd.service: Succeeded. Oct 07 18:09:48 elutuba systemd[1]: Stopped Music Player Daemon. Oct 07 18:09:48 elutuba volumio[16675]: Error adding Membership: Error: addMembership EINVAL Oct 07 18:09:48 elutuba volumio[16675]: info: Shairport-Sync Started Oct 07 18:09:48 elutuba volumio[16675]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:48 elutuba volumio[16675]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:48 elutuba volumio[16675]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:48 elutuba volumio[16675]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:48 elutuba volumio[16675]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:48 elutuba volumio[16675]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:48 elutuba volumio[16675]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:48 elutuba volumio[16675]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 07 18:09:48 elutuba volumio[16675]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:48 elutuba volumio[16675]: info: Starting Shairport Sync Oct 07 18:09:48 elutuba systemd[1]: Starting Music Player Daemon... Oct 07 18:09:48 elutuba systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 07 18:09:48 elutuba sudo[16832]: pam_unix(sudo:session): session closed for user root Oct 07 18:09:48 elutuba sudo[16856]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 07 18:09:48 elutuba sudo[16856]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:09:48 elutuba systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 07 18:09:48 elutuba systemd[1]: shairport-sync.service: Succeeded. Oct 07 18:09:48 elutuba systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 07 18:09:48 elutuba systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 07 18:09:48 elutuba sudo[16846]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 07 18:09:48 elutuba sudo[16846]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:09:48 elutuba volumio[16675]: info: Shairport-Sync Started Oct 07 18:09:48 elutuba sudo[16856]: pam_unix(sudo:session): session closed for user root Oct 07 18:09:48 elutuba sudo[16846]: pam_unix(sudo:session): session closed for user root Oct 07 18:09:48 elutuba volumio[16675]: info: Shairport-Sync Started Oct 07 18:09:48 elutuba volumio[16675]: info: Starting Uninstall of plugin audio_interface - fusiondsp Oct 07 18:09:48 elutuba volumio[16675]: info: Uninstalling plugin fusiondsp Oct 07 18:09:48 elutuba volumio[16675]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 07 18:09:48 elutuba volumio[16675]: TypeError: Cannot read property 'off' of undefined Oct 07 18:09:48 elutuba volumio[16675]: at FusionDsp.onStop (/data/plugins/audio_interface/fusiondsp/index.js:107:15) Oct 07 18:09:48 elutuba volumio[16675]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Oct 07 18:09:48 elutuba volumio[16675]: at PluginManager.unInstallPlugin (/volumio/app/pluginmanager.js:1449:10) Oct 07 18:09:48 elutuba volumio[16675]: at CoreCommandRouter.unInstallPlugin (/volumio/app/index.js:1465:22) Oct 07 18:09:48 elutuba volumio[16675]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1246:45) Oct 07 18:09:48 elutuba volumio[16675]: at Socket.emit (events.js:315:20) Oct 07 18:09:48 elutuba volumio[16675]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Oct 07 18:09:48 elutuba volumio[16675]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Oct 07 18:09:48 elutuba volumio[16675]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 07 18:09:49 elutuba sudo[16876]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-07 18:08 Oct 07 18:09:49 elutuba sudo[16876]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:09:49 elutuba sudo[16876]: pam_unix(sudo:session): session closed for user root Oct 07 18:09:49 elutuba mpd[16863]: Oct 07 18:09 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 07 18:09:49 elutuba systemd[1]: Started Music Player Daemon. Oct 07 18:09:49 elutuba sudo[16792]: pam_unix(sudo:session): session closed for user root Oct 07 18:09:49 elutuba sudo[16838]: pam_unix(sudo:session): session closed for user root Oct 07 18:09:49 elutuba sudo[16783]: pam_unix(sudo:session): session closed for user root Oct 07 18:09:50 elutuba volumio-remote-updater[653]: [2024-10-07 18:09:50] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Oct 07 18:09:50 elutuba volumio-remote-updater[653]: [2024-10-07 18:09:50] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Oct 07 18:09:50 elutuba systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Oct 07 18:09:50 elutuba systemd[1]: volumio.service: Failed with result 'exit-code'. Oct 07 18:09:50 elutuba systemd[1]: Started dynamicswap service. Oct 07 18:09:50 elutuba systemd[1]: dynamicswap.service: Succeeded. Oct 07 18:09:50 elutuba systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Oct 07 18:09:50 elutuba systemd[1]: volumio.service: Scheduled restart job, restart counter is at 71. Oct 07 18:09:50 elutuba systemd[1]: Started dynamicswap service. Oct 07 18:09:50 elutuba systemd[1]: Stopped Volumio Backend Module. Oct 07 18:09:50 elutuba systemd[1]: Started Volumio Backend Module. Oct 07 18:09:50 elutuba systemd[1]: dynamicswap.service: Succeeded. Oct 07 18:09:52 elutuba volumio[16892]: info: ------------------------------------------- Oct 07 18:09:52 elutuba volumio[16892]: info: ----- Volumio3 ---- Oct 07 18:09:52 elutuba volumio[16892]: info: ------------------------------------------- Oct 07 18:09:52 elutuba volumio[16892]: info: ----- System startup ---- Oct 07 18:09:52 elutuba volumio[16892]: info: ------------------------------------------- Oct 07 18:09:53 elutuba volumio[16892]: info: MYVOLUMIO Environment detected Oct 07 18:09:53 elutuba volumio[16892]: info: Plugin folders cleanup Oct 07 18:09:53 elutuba volumio[16892]: info: Scanning into folder /volumio/app/plugins/ Oct 07 18:09:53 elutuba volumio[16892]: info: Scanning category audio_interface Oct 07 18:09:53 elutuba volumio[16892]: info: Scanning category miscellanea Oct 07 18:09:53 elutuba volumio[16892]: info: Scanning category music_service Oct 07 18:09:53 elutuba volumio[16892]: info: Scanning category plugins.json Oct 07 18:09:53 elutuba volumio[16892]: info: Scanning category system_controller Oct 07 18:09:53 elutuba volumio[16892]: info: Scanning category user_interface Oct 07 18:09:53 elutuba volumio[16892]: info: Scanning into folder /data/plugins/ Oct 07 18:09:53 elutuba volumio[16892]: info: Scanning category audio_interface Oct 07 18:09:53 elutuba volumio[16892]: info: Plugin folders cleanup completed Oct 07 18:09:53 elutuba volumio[16892]: info: ------------------------------------------- Oct 07 18:09:53 elutuba volumio[16892]: info: ----- Core plugins startup ---- Oct 07 18:09:53 elutuba volumio[16892]: info: ------------------------------------------- Oct 07 18:09:53 elutuba volumio[16892]: info: Loading plugins from folder /volumio/app/plugins/ Oct 07 18:09:53 elutuba volumio[16892]: info: Adding plugin upnp to MyMusic Plugins Oct 07 18:09:53 elutuba volumio[16892]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 07 18:09:53 elutuba volumio[16892]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 07 18:09:53 elutuba volumio[16892]: info: Loading plugins from folder /data/plugins/ Oct 07 18:09:53 elutuba volumio[16892]: info: Loading plugin "system"... Oct 07 18:09:53 elutuba volumio[16892]: info: Loading plugin "appearance"... Oct 07 18:09:54 elutuba volumio[16892]: info: Loading plugin "network"... Oct 07 18:09:54 elutuba volumio[16892]: info: Refreshing Cached IP Addresses Oct 07 18:09:54 elutuba sudo[16918]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 07 18:09:54 elutuba sudo[16918]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:09:54 elutuba sudo[16918]: pam_unix(sudo:session): session closed for user root Oct 07 18:09:54 elutuba sudo[16920]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 07 18:09:54 elutuba volumio[16892]: info: Loading plugin "services"... Oct 07 18:09:54 elutuba sudo[16920]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:09:54 elutuba volumio[16892]: info: Loading plugin "alsa_controller"... Oct 07 18:09:54 elutuba sudo[16920]: pam_unix(sudo:session): session closed for user root Oct 07 18:09:54 elutuba sudo[16923]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 07 18:09:54 elutuba sudo[16923]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:09:54 elutuba volumio[16892]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 07 18:09:54 elutuba volumio[16892]: info: Loading plugin "wizard"... Oct 07 18:09:54 elutuba volumio[16892]: info: Loading plugin "networkfs"... Oct 07 18:09:54 elutuba volumio[16892]: info: Starting Udev Watcher for removable devices Oct 07 18:09:54 elutuba volumio[16892]: info: Ignoring mount for partition: boot Oct 07 18:09:54 elutuba volumio[16892]: info: Ignoring mount for partition: volumio Oct 07 18:09:54 elutuba volumio[16892]: info: Ignoring mount for partition: volumio_data Oct 07 18:09:54 elutuba volumio[16892]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 07 18:09:54 elutuba volumio[16892]: info: Loading plugin "volumio_command_line_client"... Oct 07 18:09:54 elutuba volumio[16892]: info: Loading plugin "upnp"... Oct 07 18:09:54 elutuba volumio[16892]: info: [1728313794564] Starting Upmpd Daemon Oct 07 18:09:54 elutuba volumio[16892]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 07 18:09:54 elutuba volumio[16892]: info: Loading plugin "my_music"... Oct 07 18:09:54 elutuba volumio[16892]: info: Loading plugin "mpd"... Oct 07 18:09:54 elutuba volumio[16892]: info: Loading plugin "upnp_browser"... Oct 07 18:09:55 elutuba volumio-remote-updater[653]: [2024-10-07 18:09:55] [connect] Successful connection Oct 07 18:09:55 elutuba volumio[16892]: info: Loading plugin "alarm-clock"... Oct 07 18:09:55 elutuba sudo[16923]: pam_unix(sudo:session): session closed for user root Oct 07 18:09:55 elutuba volumio[16892]: info: Loading plugin "airplay_emulation"... Oct 07 18:09:55 elutuba volumio[16892]: info: Starting Shairport Sync Oct 07 18:09:55 elutuba volumio[16892]: info: Loading plugin "last_100"... Oct 07 18:09:55 elutuba volumio[16892]: info: Loading plugin "webradio"... Oct 07 18:09:55 elutuba volumio[16892]: info: Loading plugin "i2s_dacs"... Oct 07 18:09:55 elutuba volumio[16892]: info: I2S DAC not set, start Auto-detection Oct 07 18:09:55 elutuba volumio[16892]: info: Loading plugin "volumiodiscovery"... Oct 07 18:09:55 elutuba volumio[16892]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 07 18:09:55 elutuba volumio[16892]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 07 18:09:55 elutuba volumio[16892]: *** WARNING *** For more information see Oct 07 18:09:55 elutuba node[16892]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 07 18:09:55 elutuba volumio[16892]: *** 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:09:55 elutuba volumio[16892]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 07 18:09:55 elutuba volumio[16892]: *** WARNING *** For more information see Oct 07 18:09:55 elutuba node[16892]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 07 18:09:55 elutuba node[16892]: *** WARNING *** For more information see Oct 07 18:09:55 elutuba node[16892]: *** 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:09:55 elutuba node[16892]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 07 18:09:55 elutuba node[16892]: *** WARNING *** For more information see Oct 07 18:09:55 elutuba volumio[16892]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 07 18:09:55 elutuba volumio[16892]: info: Discovery: Started advertising with name: Elutuba Oct 07 18:09:55 elutuba volumio[16892]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 07 18:09:55 elutuba volumio[16892]: info: Loading plugin "outputs"... Oct 07 18:09:55 elutuba volumio[16892]: info: Loading plugin "albumart"... Oct 07 18:09:55 elutuba volumio[16892]: info: Plugin example_plugin is not enabled Oct 07 18:09:55 elutuba volumio[16892]: info: Loading plugin "inputs"... Oct 07 18:09:55 elutuba volumio[16892]: info: Loading plugin "updater_comm"... Oct 07 18:09:55 elutuba volumio[16892]: info: Plugin mpdemulation is not enabled Oct 07 18:09:55 elutuba volumio[16892]: info: Loading plugin "rest_api"... Oct 07 18:09:56 elutuba volumio[16892]: info: Loading plugin "websocket"... Oct 07 18:09:56 elutuba volumio[16892]: info: Starting Socket.io Server version 2.3.0 Oct 07 18:09:56 elutuba volumio[16892]: info: Loading plugin "fusiondsp"... Oct 07 18:09:56 elutuba volumio[16892]: Forking 3 albumart workers Oct 07 18:09:56 elutuba volumio[16892]: info: Applying required configuration parameters for plugin fusiondsp Oct 07 18:09:56 elutuba volumio[16892]: info: Loading i18n strings for locale en Oct 07 18:09:56 elutuba volumio[16892]: Updating browse sources language Oct 07 18:09:56 elutuba volumio[16892]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 07 18:09:56 elutuba volumio[16892]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 07 18:09:56 elutuba volumio[16892]: info: CoreCommandRouter::initPlayerControls Oct 07 18:09:56 elutuba volumio[16892]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:56 elutuba volumio[16892]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:56 elutuba volumio[16892]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:56 elutuba volumio[16892]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:56 elutuba volumio[16892]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:56 elutuba volumio[16892]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:56 elutuba volumio[16892]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:56 elutuba volumio[16892]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:56 elutuba volumio[16892]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 07 18:09:56 elutuba volumio[16892]: Express server listening on port 3000 Oct 07 18:09:56 elutuba volumio[16892]: [Metrics] WebUI: 5s 276.31ms Oct 07 18:09:57 elutuba volumio[16892]: info: CoreStateMachine::resetVolumioState Oct 07 18:09:57 elutuba volumio[16892]: info: CoreStateMachine::getcurrentVolume Oct 07 18:09:57 elutuba volumio[16892]: info: CoreCommandRouter::volumioRetrievevolume Oct 07 18:09:57 elutuba volumio[16892]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 07 18:09:57 elutuba volumio[16892]: info: Cannot read play queue from file Oct 07 18:09:57 elutuba volumio[16892]: 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:09:57 elutuba volumio[16892]: 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:09:57 elutuba volumio[16892]: Starting albumart workers Oct 07 18:09:57 elutuba volumio-remote-updater[653]: [2024-10-07 18:09:57] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1728313795 101 Oct 07 18:09:57 elutuba volumio[16892]: 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:09:57 elutuba volumio[16892]: Starting albumart workers Oct 07 18:09:57 elutuba volumio[16892]: Starting albumart workers Oct 07 18:09:57 elutuba volumio[16892]: info: CoreStateMachine::setRepeat null single undefined Oct 07 18:09:57 elutuba volumio[16892]: info: CoreStateMachine::pushState Oct 07 18:09:57 elutuba volumio[16892]: info: CorePlayQueue::getTrack 0 Oct 07 18:09:57 elutuba volumio[16892]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 07 18:09:57 elutuba volumio[16892]: info: CoreCommandRouter::volumioPushState Oct 07 18:09:57 elutuba volumio[16892]: info: CoreStateMachine::setRandom null Oct 07 18:09:57 elutuba volumio[16892]: info: CoreStateMachine::pushState Oct 07 18:09:57 elutuba volumio[16892]: info: CorePlayQueue::getTrack 0 Oct 07 18:09:57 elutuba volumio[16892]: info: CoreCommandRouter::volumioPushState Oct 07 18:09:57 elutuba volumio[16892]: info: Setting Device type: Raspberry PI Oct 07 18:09:57 elutuba volumio[16892]: info: VolumeController:: Volume=0 Mute =false Oct 07 18:09:57 elutuba volumio[16892]: info: CoreStateMachine::pushState Oct 07 18:09:57 elutuba volumio[16892]: info: CorePlayQueue::getTrack 0 Oct 07 18:09:57 elutuba volumio[16892]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 07 18:09:57 elutuba volumio[16892]: info: CoreCommandRouter::volumioPushState Oct 07 18:09:57 elutuba volumio[16892]: info: CoreStateMachine::updateTrackBlock Oct 07 18:09:57 elutuba volumio[16892]: info: CorePlayQueue::getTrackBlock Oct 07 18:09:57 elutuba volumio[16892]: info: CoreCommandRouter::volumioRetrievevolume Oct 07 18:09:57 elutuba volumio[16892]: info: Completed loading Core Plugins Oct 07 18:09:57 elutuba volumio[16892]: info: Preparing to generate the ALSA configuration file Oct 07 18:09:57 elutuba volumio[16892]: info: Discovery: adding 320db26b-9bed-4586-9e4d-ee23fdd56bae Oct 07 18:09:57 elutuba volumio[16892]: info: Discovery: Found device Elutuba Oct 07 18:09:57 elutuba volumio[16892]: info: CoreCommandRouter::volumioGetState Oct 07 18:09:57 elutuba volumio[16892]: info: CorePlayQueue::getTrack 0 Oct 07 18:09:57 elutuba volumio[16892]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Oct 07 18:09:57 elutuba volumio[16892]: info: Reading ALSA contributions from plugins. Oct 07 18:09:57 elutuba volumio[16892]: info: Asound.conf file unchanged, so no further update is needed Oct 07 18:09:57 elutuba volumio[16892]: info: Output device has changed, restarting MPD Oct 07 18:09:57 elutuba volumio[16892]: info: Output device has changed, restarting Shairport Sync Oct 07 18:09:57 elutuba volumio[16892]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:57 elutuba volumio[16892]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 07 18:09:57 elutuba sudo[16974]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 07 18:09:57 elutuba sudo[16974]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:09:57 elutuba sudo[16974]: pam_unix(sudo:session): session closed for user root Oct 07 18:09:57 elutuba sudo[16975]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 07 18:09:57 elutuba sudo[16975]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:09:57 elutuba volumio[16892]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 07 18:09:57 elutuba volumio[16892]: info: ___________ START PLUGINS ___________ Oct 07 18:09:57 elutuba volumio[16892]: info: ControllerMpd::onStart: Initializing MPD Oct 07 18:09:57 elutuba volumio[16892]: info: Creating MPD Configuration file Oct 07 18:09:57 elutuba systemd[1]: Stopping Music Player Daemon... Oct 07 18:09:57 elutuba systemd[1]: mpd.service: Succeeded. Oct 07 18:09:57 elutuba sudo[16982]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 07 18:09:57 elutuba systemd[1]: Stopped Music Player Daemon. Oct 07 18:09:57 elutuba sudo[16982]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:09:57 elutuba volumio[16892]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 07 18:09:57 elutuba sudo[16982]: pam_unix(sudo:session): session closed for user root Oct 07 18:09:57 elutuba volumio[16892]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 07 18:09:57 elutuba volumio[16892]: info: [1728313797969] CoreMusicLibrary::Adding element Media Servers Oct 07 18:09:57 elutuba volumio[16892]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 07 18:09:57 elutuba sudo[16986]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 07 18:09:58 elutuba sudo[16986]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:09:58 elutuba systemd[1]: Starting Music Player Daemon... Oct 07 18:09:58 elutuba volumio[16892]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:58 elutuba volumio[16892]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 07 18:09:58 elutuba systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Oct 07 18:09:58 elutuba systemd[1]: mpd.service: Succeeded. Oct 07 18:09:58 elutuba systemd[1]: Stopped Music Player Daemon. Oct 07 18:09:58 elutuba systemd[1]: Starting Music Player Daemon... Oct 07 18:09:58 elutuba volumio[16892]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 07 18:09:58 elutuba volumio[16892]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:58 elutuba volumio[16892]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 07 18:09:58 elutuba volumio[16892]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 07 18:09:58 elutuba sudo[16988]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 07 18:09:58 elutuba volumio[16892]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 07 18:09:58 elutuba volumio[16892]: info: [1728313798218] CoreMusicLibrary::Adding element Last_100 Oct 07 18:09:58 elutuba volumio[16892]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 07 18:09:58 elutuba sudo[16988]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:09:58 elutuba volumio[16892]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 07 18:09:58 elutuba volumio[16892]: info: [1728313798225] CoreMusicLibrary::Adding element Webradio Oct 07 18:09:58 elutuba volumio[16892]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 07 18:09:58 elutuba sudo[16988]: pam_unix(sudo:session): session closed for user root Oct 07 18:09:58 elutuba volumio[16892]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 07 18:09:58 elutuba volumio[16892]: info: Initializing BBC Radios Oct 07 18:09:58 elutuba volumio[16892]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 07 18:09:58 elutuba volumio[16892]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 07 18:09:58 elutuba volumio[16892]: info: Loading i18n strings for locale en Oct 07 18:09:58 elutuba volumio[16892]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Oct 07 18:09:58 elutuba volumio[16892]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:58 elutuba volumio[16892]: info: FusionDsp - mixtype--------------------- Hardware Oct 07 18:09:58 elutuba volumio[16892]: info: Volumio Calling Home Oct 07 18:09:58 elutuba volumio[16892]: info: Preparing to generate the ALSA configuration file Oct 07 18:09:58 elutuba volumio[16892]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Oct 07 18:09:58 elutuba volumio[16892]: info: Reading ALSA contributions from plugins. Oct 07 18:09:58 elutuba volumio[16892]: info: MPD Permissions set Oct 07 18:09:58 elutuba volumio[16892]: info: MPD Permissions set Oct 07 18:09:58 elutuba volumio[16892]: info: VolumeController:: Volume=0 Mute =false Oct 07 18:09:58 elutuba volumio[16892]: info: CoreStateMachine::pushState Oct 07 18:09:58 elutuba volumio[16892]: info: CorePlayQueue::getTrack 0 Oct 07 18:09:58 elutuba volumio[16892]: info: CoreCommandRouter::volumioPushState Oct 07 18:09:58 elutuba volumio[16892]: 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:09:58 elutuba volumio[16892]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:58 elutuba volumio[16892]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:58 elutuba volumio[16892]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:58 elutuba volumio[16892]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:58 elutuba volumio[16892]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:58 elutuba volumio[16892]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:58 elutuba volumio[16892]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:58 elutuba volumio[16892]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:58 elutuba volumio[16892]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 07 18:09:58 elutuba volumio[16892]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:58 elutuba volumio[16892]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:58 elutuba volumio[16892]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:58 elutuba volumio[16892]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:58 elutuba volumio[16892]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:58 elutuba volumio[16892]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:58 elutuba volumio[16892]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:58 elutuba volumio[16892]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:58 elutuba volumio[16892]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:58 elutuba volumio[16892]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 07 18:09:58 elutuba volumio[16892]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:58 elutuba volumio[16892]: info: Volumio called home Oct 07 18:09:59 elutuba volumio[16892]: info: Starting Shairport Sync Oct 07 18:09:59 elutuba volumio[16892]: info: Starting Shairport Sync Oct 07 18:09:59 elutuba volumio[16892]: info: Starting Shairport Sync Oct 07 18:09:59 elutuba sudo[17043]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 07 18:09:59 elutuba sudo[17043]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:09:59 elutuba sudo[17045]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 07 18:09:59 elutuba sudo[17045]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:09:59 elutuba systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 07 18:09:59 elutuba systemd[1]: shairport-sync.service: Succeeded. Oct 07 18:09:59 elutuba systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 07 18:09:59 elutuba volumio[16892]: info: CoreCommandRouter::volumioGetState Oct 07 18:09:59 elutuba volumio[16892]: info: CorePlayQueue::getTrack 0 Oct 07 18:09:59 elutuba sudo[17049]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 07 18:09:59 elutuba sudo[17049]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:09:59 elutuba systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 07 18:09:59 elutuba sudo[17043]: pam_unix(sudo:session): session closed for user root Oct 07 18:09:59 elutuba volumio[16892]: info: Asound.conf file unchanged, so no further update is needed Oct 07 18:09:59 elutuba volumio[16892]: info: Output device has changed, restarting MPD Oct 07 18:09:59 elutuba systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 07 18:09:59 elutuba systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Oct 07 18:09:59 elutuba systemd[1]: shairport-sync.service: Succeeded. Oct 07 18:09:59 elutuba systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 07 18:09:59 elutuba systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 07 18:09:59 elutuba sudo[17045]: pam_unix(sudo:session): session closed for user root Oct 07 18:09:59 elutuba volumio[16892]: info: Output device has changed, restarting Shairport Sync Oct 07 18:09:59 elutuba volumio[16892]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:59 elutuba volumio[16892]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 07 18:09:59 elutuba sudo[17057]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 07 18:09:59 elutuba sudo[17057]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:09:59 elutuba sudo[17053]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 07 18:09:59 elutuba sudo[17049]: pam_unix(sudo:session): session closed for user root Oct 07 18:09:59 elutuba sudo[17053]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:09:59 elutuba sudo[17053]: pam_unix(sudo:session): session closed for user root Oct 07 18:09:59 elutuba volumio[16892]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 07 18:09:59 elutuba systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Oct 07 18:09:59 elutuba systemd[1]: mpd.service: Succeeded. Oct 07 18:09:59 elutuba systemd[1]: Stopped Music Player Daemon. Oct 07 18:09:59 elutuba volumio[16892]: info: MPD Permissions set Oct 07 18:09:59 elutuba volumio[16892]: info: Shairport-Sync Started Oct 07 18:09:59 elutuba volumio[16892]: Error adding Membership: Error: addMembership EINVAL Oct 07 18:09:59 elutuba volumio[16892]: info: Shairport-Sync Started Oct 07 18:09:59 elutuba volumio[16892]: info: Shairport-Sync Started Oct 07 18:09:59 elutuba systemd[1]: Starting Music Player Daemon... Oct 07 18:09:59 elutuba volumio[16892]: info: CoreCommandRouter::volumioGetState Oct 07 18:09:59 elutuba volumio[16892]: info: CorePlayQueue::getTrack 0 Oct 07 18:09:59 elutuba volumio[16892]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:59 elutuba volumio[16892]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:59 elutuba volumio[16892]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:59 elutuba volumio[16892]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:59 elutuba volumio[16892]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:59 elutuba sudo[17064]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 07 18:09:59 elutuba sudo[17064]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:09:59 elutuba volumio[16892]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:59 elutuba volumio[16892]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:59 elutuba volumio[16892]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:59 elutuba sudo[17064]: pam_unix(sudo:session): session closed for user root Oct 07 18:09:59 elutuba volumio[16892]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 07 18:09:59 elutuba volumio[16892]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:09:59 elutuba volumio[16892]: info: Starting Shairport Sync Oct 07 18:09:59 elutuba volumio[16892]: info: Listing playlists Oct 07 18:09:59 elutuba volumio[16892]: info: Listing playlists Oct 07 18:09:59 elutuba volumio[16892]: 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:09:59 elutuba sudo[17072]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 07 18:09:59 elutuba sudo[17072]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:09:59 elutuba volumio[16892]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 07 18:09:59 elutuba systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 07 18:09:59 elutuba systemd[1]: shairport-sync.service: Succeeded. Oct 07 18:09:59 elutuba systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 07 18:09:59 elutuba systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 07 18:09:59 elutuba volumio[16892]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Oct 07 18:09:59 elutuba volumio[16892]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Oct 07 18:09:59 elutuba volumio[16892]: info: Received Get System Version Oct 07 18:09:59 elutuba sudo[17072]: pam_unix(sudo:session): session closed for user root Oct 07 18:09:59 elutuba volumio[16892]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 07 18:09:59 elutuba volumio[16892]: info: Received Get System Info Oct 07 18:09:59 elutuba volumio[16892]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 07 18:09:59 elutuba volumio[16892]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 07 18:09:59 elutuba volumio[16892]: info: Discovery: Getting this device information Oct 07 18:09:59 elutuba volumio[16892]: info: CoreCommandRouter::volumioGetState Oct 07 18:09:59 elutuba volumio[16892]: info: CorePlayQueue::getTrack 0 Oct 07 18:09:59 elutuba volumio[16892]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 07 18:09:59 elutuba volumio[16892]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 07 18:09:59 elutuba volumio[16892]: info: CoreCommandRouter::volumioGetVisibleSources Oct 07 18:09:59 elutuba volumio[16892]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 07 18:09:59 elutuba volumio[16892]: info: CoreCommandRouter::volumioGetState Oct 07 18:09:59 elutuba volumio[16892]: info: CorePlayQueue::getTrack 0 Oct 07 18:09:59 elutuba volumio[16892]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 07 18:09:59 elutuba volumio[16892]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 07 18:09:59 elutuba volumio[16892]: info: Received Get System Info Oct 07 18:09:59 elutuba volumio[16892]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 07 18:09:59 elutuba volumio[16892]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 07 18:09:59 elutuba volumio[16892]: info: Discovery: Getting this device information Oct 07 18:09:59 elutuba volumio[16892]: info: CoreCommandRouter::volumioGetState Oct 07 18:09:59 elutuba volumio[16892]: info: CorePlayQueue::getTrack 0 Oct 07 18:09:59 elutuba volumio[16892]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 07 18:09:59 elutuba volumio[16892]: info: CoreCommandRouter::volumioGetState Oct 07 18:09:59 elutuba volumio[16892]: info: CorePlayQueue::getTrack 0 Oct 07 18:09:59 elutuba volumio[16892]: info: Listing playlists Oct 07 18:09:59 elutuba volumio[16892]: info: Shairport-Sync Started Oct 07 18:09:59 elutuba volumio[16892]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 07 18:10:00 elutuba volumio[16892]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 07 18:10:00 elutuba volumio[16892]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 07 18:10:00 elutuba volumio[16892]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Oct 07 18:10:00 elutuba volumio[16892]: 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:10:00 elutuba volumio[16892]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Oct 07 18:10:00 elutuba volumio[16892]: info: camilladsp spawned new process with pid undefined, instance 1, run: true Oct 07 18:10:00 elutuba volumio[16892]: info: camilladsp service started and running in background, instance 1 Oct 07 18:10:00 elutuba volumio[16892]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 18:10:00 elutuba volumio[16892]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Oct 07 18:10:00 elutuba volumio[16892]: 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:10:00 elutuba volumio[16892]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Oct 07 18:10:00 elutuba volumio[16892]: info: FusionDsp loaded Oct 07 18:10:00 elutuba volumio[16892]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 07 18:10:00 elutuba sudo[17089]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Oct 07 18:10:00 elutuba sudo[17089]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 18:10:00 elutuba volumio[16892]: info: FusionDsp - Reporting Fusion DSP Enabled Oct 07 18:10:00 elutuba volumio[16892]: info: Adding Signal Path Element [object Object] Oct 07 18:10:00 elutuba volumio[16892]: info: Adding fusiondspeq DSP Signal Path Element Oct 07 18:10:00 elutuba volumio[16892]: info: FusionDsp - ---- installed callbackRead Oct 07 18:10:00 elutuba volumio[16892]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 07 18:10:00 elutuba sudo[17089]: pam_unix(sudo:session): session closed for user root Oct 07 18:10:00 elutuba volumio[16892]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Oct 07 18:10:00 elutuba volumio[16892]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Oct 07 18:10:00 elutuba volumio[16892]: at onErrorNT (internal/child_process.js:465:16) Oct 07 18:10:00 elutuba volumio[16892]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Oct 07 18:10:00 elutuba volumio[16892]: at runNextTicks (internal/process/task_queues.js:62:3) Oct 07 18:10:00 elutuba volumio[16892]: at listOnTimeout (internal/timers.js:523:9) Oct 07 18:10:00 elutuba volumio[16892]: at processTimers (internal/timers.js:497:7) { Oct 07 18:10:00 elutuba volumio[16892]: errno: -2, Oct 07 18:10:00 elutuba volumio[16892]: code: 'ENOENT', Oct 07 18:10:00 elutuba volumio[16892]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Oct 07 18:10:00 elutuba volumio[16892]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Oct 07 18:10:00 elutuba volumio[16892]: spawnargs: [ Oct 07 18:10:00 elutuba volumio[16892]: '-p', Oct 07 18:10:00 elutuba volumio[16892]: 9876, Oct 07 18:10:00 elutuba volumio[16892]: '-o', Oct 07 18:10:00 elutuba volumio[16892]: '/tmp/camilladsp.log', Oct 07 18:10:00 elutuba volumio[16892]: '-l', Oct 07 18:10:00 elutuba volumio[16892]: 'warn', Oct 07 18:10:00 elutuba volumio[16892]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Oct 07 18:10:00 elutuba volumio[16892]: ] Oct 07 18:10:00 elutuba volumio[16892]: } Oct 07 18:10:00 elutuba volumio[16892]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 07 18:10:01 elutuba mpd[17067]: Oct 07 18:10 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 07 18:10:01 elutuba systemd[1]: Started Music Player Daemon. Oct 07 18:10:01 elutuba sudo[17057]: pam_unix(sudo:session): session closed for user root Oct 07 18:10:01 elutuba sudo[16986]: pam_unix(sudo:session): session closed for user root Oct 07 18:10:01 elutuba sudo[16975]: pam_unix(sudo:session): session closed for user root Oct 07 18:10:01 elutuba sudo[17102]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-07 18:09 Oct 07 18:10:01 elutuba sudo[17102]: 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"