-- Logs begin at Thu 2019-02-14 13:12:00 MSK, end at Thu 2024-10-24 02:06:21 MSK. -- Oct 24 02:05:00 volumio volumio[10286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:00 volumio volumio[10286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:00 volumio sudo[10467]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 24 02:05:00 volumio volumio[10286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:00 volumio volumio[10286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:00 volumio volumio[10286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:00 volumio volumio[10286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:00 volumio volumio[10286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:00 volumio sudo[10467]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 02:05:00 volumio sudo[10467]: pam_unix(sudo:session): session closed for user root Oct 24 02:05:00 volumio volumio[10286]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 24 02:05:00 volumio volumio[10286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:00 volumio volumio[10286]: info: Listing playlists Oct 24 02:05:00 volumio volumio[10286]: info: Listing playlists Oct 24 02:05:00 volumio volumio[10286]: info: Starting Shairport Sync Oct 24 02:05:00 volumio sudo[10476]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 24 02:05:00 volumio sudo[10476]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 02:05:00 volumio volumio[10286]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Oct 24 02:05:00 volumio volumio[10286]: 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 24 02:05:00 volumio volumio[10286]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Oct 24 02:05:00 volumio volumio[10286]: info: camilladsp spawned new process with pid undefined, instance 1, run: true Oct 24 02:05:00 volumio volumio[10286]: info: camilladsp service started and running in background, instance 1 Oct 24 02:05:00 volumio volumio[10286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:00 volumio volumio[10286]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Oct 24 02:05:00 volumio volumio[10286]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Oct 24 02:05:00 volumio volumio[10286]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Oct 24 02:05:00 volumio volumio[10286]: info: FusionDsp loaded Oct 24 02:05:00 volumio volumio[10286]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 24 02:05:00 volumio sudo[10488]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Oct 24 02:05:00 volumio sudo[10488]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 02:05:00 volumio volumio[10286]: info: FusionDsp - Reporting Fusion DSP Enabled Oct 24 02:05:00 volumio volumio[10286]: info: Adding Signal Path Element [object Object] Oct 24 02:05:00 volumio volumio[10286]: info: Adding fusiondspeq DSP Signal Path Element Oct 24 02:05:00 volumio volumio[10286]: info: FusionDsp - ---- installed callbackRead Oct 24 02:05:00 volumio volumio[10286]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 24 02:05:00 volumio sudo[10488]: pam_unix(sudo:session): session closed for user root Oct 24 02:05:01 volumio volumio[10286]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Oct 24 02:05:01 volumio volumio[10286]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Oct 24 02:05:01 volumio volumio[10286]: at onErrorNT (internal/child_process.js:465:16) Oct 24 02:05:01 volumio volumio[10286]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Oct 24 02:05:01 volumio volumio[10286]: errno: -2, Oct 24 02:05:01 volumio volumio[10286]: code: 'ENOENT', Oct 24 02:05:01 volumio volumio[10286]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Oct 24 02:05:01 volumio volumio[10286]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Oct 24 02:05:01 volumio volumio[10286]: spawnargs: [ Oct 24 02:05:01 volumio volumio[10286]: '-p', Oct 24 02:05:01 volumio volumio[10286]: 9876, Oct 24 02:05:01 volumio volumio[10286]: '-o', Oct 24 02:05:01 volumio volumio[10286]: '/tmp/camilladsp.log', Oct 24 02:05:01 volumio volumio[10286]: '-l', Oct 24 02:05:01 volumio volumio[10286]: 'warn', Oct 24 02:05:01 volumio volumio[10286]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Oct 24 02:05:01 volumio volumio[10286]: ] Oct 24 02:05:01 volumio volumio[10286]: } Oct 24 02:05:01 volumio volumio[10286]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 24 02:05:01 volumio mpd[10471]: Oct 24 02:05 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 24 02:05:01 volumio systemd[1]: Started Music Player Daemon. Oct 24 02:05:01 volumio sudo[10410]: pam_unix(sudo:session): session closed for user root Oct 24 02:05:01 volumio sudo[10462]: pam_unix(sudo:session): session closed for user root Oct 24 02:05:01 volumio sudo[10400]: pam_unix(sudo:session): session closed for user root Oct 24 02:05:01 volumio sudo[10501]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-24 02:04 Oct 24 02:05:01 volumio sudo[10501]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 02:05:01 volumio sudo[10501]: pam_unix(sudo:session): session closed for user root Oct 24 02:05:01 volumio volumio-remote-updater[658]: [2024-10-24 02:05:01] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Oct 24 02:05:01 volumio volumio-remote-updater[658]: [2024-10-24 02:05:01] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Oct 24 02:05:01 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Oct 24 02:05:01 volumio sudo[10451]: pam_unix(sudo:session): session closed for user root Oct 24 02:05:01 volumio sudo[10454]: pam_unix(sudo:session): session closed for user root Oct 24 02:05:01 volumio sudo[10476]: pam_unix(sudo:session): session closed for user root Oct 24 02:05:01 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Oct 24 02:05:01 volumio systemd[1]: Started dynamicswap service. Oct 24 02:05:02 volumio systemd[1]: dynamicswap.service: Succeeded. Oct 24 02:05:02 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Oct 24 02:05:02 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 20. Oct 24 02:05:02 volumio systemd[1]: Started dynamicswap service. Oct 24 02:05:02 volumio systemd[1]: Stopped Volumio Backend Module. Oct 24 02:05:02 volumio systemd[1]: Started Volumio Backend Module. Oct 24 02:05:02 volumio systemd[1]: dynamicswap.service: Succeeded. Oct 24 02:05:04 volumio volumio[10512]: info: ------------------------------------------- Oct 24 02:05:04 volumio volumio[10512]: info: ----- Volumio3 ---- Oct 24 02:05:04 volumio volumio[10512]: info: ------------------------------------------- Oct 24 02:05:04 volumio volumio[10512]: info: ----- System startup ---- Oct 24 02:05:04 volumio volumio[10512]: info: ------------------------------------------- Oct 24 02:05:04 volumio volumio[10512]: info: MYVOLUMIO Environment detected Oct 24 02:05:05 volumio volumio[10512]: info: Plugin folders cleanup Oct 24 02:05:05 volumio volumio[10512]: info: Scanning into folder /volumio/app/plugins/ Oct 24 02:05:05 volumio volumio[10512]: info: Scanning category audio_interface Oct 24 02:05:05 volumio volumio[10512]: info: Scanning category miscellanea Oct 24 02:05:05 volumio volumio[10512]: info: Scanning category music_service Oct 24 02:05:05 volumio volumio[10512]: info: Scanning category plugins.json Oct 24 02:05:05 volumio volumio[10512]: info: Scanning category system_controller Oct 24 02:05:05 volumio volumio[10512]: info: Scanning category user_interface Oct 24 02:05:05 volumio volumio[10512]: info: Scanning into folder /data/plugins/ Oct 24 02:05:05 volumio volumio[10512]: info: Scanning category audio_interface Oct 24 02:05:05 volumio volumio[10512]: info: Scanning category music_service Oct 24 02:05:05 volumio volumio[10512]: info: Scanning category system_controller Oct 24 02:05:05 volumio volumio[10512]: info: Plugin folders cleanup completed Oct 24 02:05:05 volumio volumio[10512]: info: ------------------------------------------- Oct 24 02:05:05 volumio volumio[10512]: info: ----- Core plugins startup ---- Oct 24 02:05:05 volumio volumio[10512]: info: ------------------------------------------- Oct 24 02:05:05 volumio volumio[10512]: info: Loading plugins from folder /volumio/app/plugins/ Oct 24 02:05:05 volumio volumio[10512]: info: Adding plugin upnp to MyMusic Plugins Oct 24 02:05:05 volumio volumio[10512]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 24 02:05:05 volumio volumio[10512]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 24 02:05:05 volumio volumio[10512]: info: Loading plugins from folder /data/plugins/ Oct 24 02:05:05 volumio volumio[10512]: info: Loading plugin "system"... Oct 24 02:05:05 volumio volumio[10512]: info: Loading plugin "appearance"... Oct 24 02:05:06 volumio volumio[10512]: info: Loading plugin "network"... Oct 24 02:05:06 volumio volumio[10512]: info: Refreshing Cached IP Addresses Oct 24 02:05:06 volumio sudo[10539]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 24 02:05:06 volumio sudo[10539]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 02:05:06 volumio sudo[10539]: pam_unix(sudo:session): session closed for user root Oct 24 02:05:06 volumio sudo[10541]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 24 02:05:06 volumio volumio[10512]: info: Loading plugin "services"... Oct 24 02:05:06 volumio sudo[10541]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 02:05:06 volumio volumio[10512]: info: Loading plugin "alsa_controller"... Oct 24 02:05:06 volumio sudo[10541]: pam_unix(sudo:session): session closed for user root Oct 24 02:05:06 volumio sudo[10544]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 24 02:05:06 volumio sudo[10544]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 02:05:06 volumio volumio[10512]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 24 02:05:06 volumio volumio[10512]: info: Loading plugin "wizard"... Oct 24 02:05:06 volumio volumio[10512]: info: Loading plugin "networkfs"... Oct 24 02:05:06 volumio volumio[10512]: info: Starting Udev Watcher for removable devices Oct 24 02:05:06 volumio volumio[10512]: info: Ignoring mount for partition: boot Oct 24 02:05:06 volumio volumio[10512]: info: Ignoring mount for partition: volumio Oct 24 02:05:06 volumio volumio[10512]: info: Ignoring mount for partition: volumio_data Oct 24 02:05:06 volumio volumio[10512]: info: Mounting Device 1303-1E3E Oct 24 02:05:06 volumio sudo[10555]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/1303-1E3E -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Oct 24 02:05:06 volumio sudo[10555]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 02:05:06 volumio sudo[10555]: pam_unix(sudo:session): session closed for user root Oct 24 02:05:06 volumio volumio[10512]: mount: /media/1303-1E3E: /dev/sda1 already mounted on /media/1303-1E3E. Oct 24 02:05:06 volumio volumio[10512]: error: Failed to mount 1303-1E3E: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sda1" "/mnt/USB/1303-1E3E" -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Oct 24 02:05:06 volumio volumio[10512]: mount: /media/1303-1E3E: /dev/sda1 already mounted on /media/1303-1E3E. Oct 24 02:05:06 volumio volumio[10512]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 24 02:05:06 volumio volumio[10512]: info: Loading plugin "volumio_command_line_client"... Oct 24 02:05:06 volumio volumio[10512]: info: Loading plugin "upnp"... Oct 24 02:05:06 volumio volumio[10512]: info: [1729724706490] Starting Upmpd Daemon Oct 24 02:05:06 volumio volumio[10512]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 24 02:05:06 volumio volumio[10512]: info: Loading plugin "my_music"... Oct 24 02:05:06 volumio volumio[10512]: info: Loading plugin "mpd"... Oct 24 02:05:06 volumio volumio[10512]: info: Loading plugin "upnp_browser"... Oct 24 02:05:06 volumio volumio-remote-updater[658]: [2024-10-24 02:05:06] [connect] Successful connection Oct 24 02:05:07 volumio sudo[10544]: pam_unix(sudo:session): session closed for user root Oct 24 02:05:07 volumio volumio[10512]: info: Loading plugin "alarm-clock"... Oct 24 02:05:07 volumio volumio[10512]: info: Loading plugin "airplay_emulation"... Oct 24 02:05:07 volumio volumio[10512]: info: Starting Shairport Sync Oct 24 02:05:07 volumio volumio[10512]: info: Loading plugin "last_100"... Oct 24 02:05:07 volumio volumio[10512]: info: Loading plugin "webradio"... Oct 24 02:05:07 volumio volumio[10512]: info: Loading plugin "i2s_dacs"... Oct 24 02:05:07 volumio volumio[10512]: info: I2S DAC not set, start Auto-detection Oct 24 02:05:07 volumio volumio[10512]: info: Loading plugin "volumiodiscovery"... Oct 24 02:05:07 volumio volumio[10512]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 24 02:05:07 volumio volumio[10512]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 24 02:05:07 volumio volumio[10512]: *** WARNING *** For more information see Oct 24 02:05:07 volumio node[10512]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 24 02:05:07 volumio volumio[10512]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 24 02:05:07 volumio volumio[10512]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 24 02:05:07 volumio volumio[10512]: *** WARNING *** For more information see Oct 24 02:05:07 volumio node[10512]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 24 02:05:07 volumio node[10512]: *** WARNING *** For more information see Oct 24 02:05:07 volumio node[10512]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 24 02:05:07 volumio node[10512]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 24 02:05:07 volumio node[10512]: *** WARNING *** For more information see Oct 24 02:05:07 volumio volumio[10512]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 24 02:05:07 volumio volumio[10512]: info: Discovery: Started advertising with name: Volumio Oct 24 02:05:07 volumio volumio[10512]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 24 02:05:07 volumio volumio[10512]: info: Loading plugin "calmradio"... Oct 24 02:05:08 volumio volumio[10512]: info: Loading plugin "autostart"... Oct 24 02:05:08 volumio volumio[10512]: info: Applying required configuration parameters for plugin autostart Oct 24 02:05:08 volumio volumio[10512]: info: AutoStart - onVolumioStart - read config.json Oct 24 02:05:08 volumio volumio[10512]: info: Loading plugin "outputs"... Oct 24 02:05:08 volumio volumio[10512]: info: Loading plugin "albumart"... Oct 24 02:05:08 volumio volumio[10512]: info: Plugin example_plugin is not enabled Oct 24 02:05:08 volumio volumio[10512]: info: Loading plugin "inputs"... Oct 24 02:05:08 volumio volumio[10512]: info: Loading plugin "updater_comm"... Oct 24 02:05:08 volumio volumio[10512]: info: Plugin mpdemulation is not enabled Oct 24 02:05:08 volumio volumio[10512]: info: Loading plugin "rest_api"... Oct 24 02:05:08 volumio volumio[10512]: info: Loading plugin "websocket"... Oct 24 02:05:08 volumio volumio[10512]: info: Starting Socket.io Server version 2.3.0 Oct 24 02:05:08 volumio volumio[10512]: info: Loading plugin "fusiondsp"... Oct 24 02:05:09 volumio volumio[10512]: Forking 3 albumart workers Oct 24 02:05:09 volumio volumio[10512]: info: Applying required configuration parameters for plugin fusiondsp Oct 24 02:05:09 volumio volumio[10512]: info: Loading plugin "80s80s"... Oct 24 02:05:09 volumio volumio[10512]: info: Applying required configuration parameters for plugin 80s80s Oct 24 02:05:09 volumio volumio[10512]: info: [1729724709681] [80s80s] API delay: 30 Oct 24 02:05:09 volumio volumio[10512]: info: Loading plugin "minidlna"... Oct 24 02:05:09 volumio volumio[10512]: info: Applying required configuration parameters for plugin minidlna Oct 24 02:05:09 volumio volumio[10512]: info: Plugin personal_radio is not enabled Oct 24 02:05:09 volumio volumio[10512]: info: Loading plugin "radio_paradise"... Oct 24 02:05:10 volumio volumio[10512]: Starting albumart workers Oct 24 02:05:10 volumio volumio[10512]: Starting albumart workers Oct 24 02:05:10 volumio volumio[10512]: info: Applying required configuration parameters for plugin radio_paradise Oct 24 02:05:10 volumio volumio[10512]: info: [1729724710327] [RadioParadise] API delay: 5 Oct 24 02:05:10 volumio volumio[10512]: info: Loading i18n strings for locale ru Oct 24 02:05:10 volumio volumio[10512]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Oct 24 02:05:10 volumio volumio[10512]: Updating browse sources language Oct 24 02:05:10 volumio volumio[10512]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 24 02:05:10 volumio volumio[10512]: Starting albumart workers Oct 24 02:05:10 volumio volumio[10512]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 24 02:05:10 volumio volumio[10512]: info: CoreCommandRouter::initPlayerControls Oct 24 02:05:10 volumio volumio[10512]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:10 volumio volumio[10512]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:10 volumio volumio[10512]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:10 volumio volumio[10512]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:10 volumio volumio[10512]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:10 volumio volumio[10512]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:10 volumio volumio[10512]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:10 volumio volumio[10512]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:10 volumio volumio[10512]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 24 02:05:10 volumio volumio[10512]: Express server listening on port 3000 Oct 24 02:05:10 volumio volumio[10512]: [Metrics] WebUI: 6s 998.42ms Oct 24 02:05:10 volumio volumio[10512]: info: CoreStateMachine::resetVolumioState Oct 24 02:05:10 volumio volumio[10512]: info: CoreStateMachine::getcurrentVolume Oct 24 02:05:10 volumio volumio[10512]: info: CoreCommandRouter::volumioRetrievevolume Oct 24 02:05:10 volumio volumio[10512]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 24 02:05:10 volumio volumio[10512]: verbose: New Socket.io Connection to 192.168.1.139 from 192.168.1.72 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Oct 24 02:05:10 volumio volumio[10512]: verbose: New Socket.io Connection to 192.168.1.139 from 192.168.1.72 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Oct 24 02:05:10 volumio volumio-remote-updater[658]: [2024-10-24 02:05:10] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1729724706 101 Oct 24 02:05:10 volumio volumio[10512]: 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 24 02:05:10 volumio volumio[10512]: verbose: New Socket.io Connection to 192.168.1.139 from 192.168.1.72 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Oct 24 02:05:11 volumio volumio[10512]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 24 02:05:11 volumio volumio[10512]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 24 02:05:11 volumio volumio[10512]: info: Discovery: Getting this device information Oct 24 02:05:11 volumio volumio[10512]: info: CoreCommandRouter::volumioGetState Oct 24 02:05:11 volumio volumio[10512]: info: CorePlayQueue::getTrack 0 Oct 24 02:05:11 volumio volumio[10512]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 24 02:05:11 volumio volumio[10512]: info: CoreCommandRouter::volumioGetState Oct 24 02:05:11 volumio volumio[10512]: info: CorePlayQueue::getTrack 0 Oct 24 02:05:11 volumio volumio[10512]: info: Reloading queue from file Oct 24 02:05:11 volumio volumio[10512]: info: CoreStateMachine::setRepeat false single undefined Oct 24 02:05:11 volumio volumio[10512]: info: CoreStateMachine::pushState Oct 24 02:05:11 volumio volumio[10512]: info: CorePlayQueue::getTrack 0 Oct 24 02:05:11 volumio volumio[10512]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 24 02:05:11 volumio volumio[10512]: info: CoreCommandRouter::volumioPushState Oct 24 02:05:11 volumio volumio[10512]: info: CoreStateMachine::setRandom null Oct 24 02:05:11 volumio volumio[10512]: info: CoreStateMachine::pushState Oct 24 02:05:11 volumio volumio[10512]: info: CorePlayQueue::getTrack 0 Oct 24 02:05:11 volumio volumio[10512]: info: CoreCommandRouter::volumioPushState Oct 24 02:05:11 volumio volumio[10512]: info: Setting Device type: Raspberry PI Oct 24 02:05:11 volumio volumio[10512]: info: VolumeController:: Volume=35 Mute =false Oct 24 02:05:11 volumio volumio[10512]: info: CoreStateMachine::pushState Oct 24 02:05:11 volumio volumio[10512]: info: CorePlayQueue::getTrack 0 Oct 24 02:05:11 volumio volumio[10512]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 24 02:05:11 volumio volumio[10512]: info: CoreCommandRouter::volumioPushState Oct 24 02:05:11 volumio volumio[10512]: info: CoreStateMachine::updateTrackBlock Oct 24 02:05:11 volumio volumio[10512]: info: CorePlayQueue::getTrackBlock Oct 24 02:05:11 volumio volumio[10512]: info: CoreCommandRouter::volumioRetrievevolume Oct 24 02:05:11 volumio volumio[10512]: verbose: New Socket.io Connection to 192.168.1.139:3000 from 192.168.1.118 UA: Dart/3.4 (dart:io) Engine version: 3 Transport: websocket Total Clients: 5 Oct 24 02:05:11 volumio volumio[10512]: info: CoreCommandRouter::volumioGetState Oct 24 02:05:11 volumio volumio[10512]: info: CorePlayQueue::getTrack 0 Oct 24 02:05:11 volumio volumio[10512]: info: Completed loading Core Plugins Oct 24 02:05:11 volumio volumio[10512]: info: Preparing to generate the ALSA configuration file Oct 24 02:05:11 volumio volumio[10512]: info: Discovery: adding 266418d9-d71f-4348-91b8-c2087d0064b7 Oct 24 02:05:11 volumio volumio[10512]: info: Discovery: Found device Volumio Oct 24 02:05:11 volumio volumio[10512]: info: CoreCommandRouter::volumioGetState Oct 24 02:05:11 volumio volumio[10512]: info: CorePlayQueue::getTrack 0 Oct 24 02:05:11 volumio volumio[10512]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 24 02:05:11 volumio volumio[10512]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 24 02:05:11 volumio volumio[10512]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Oct 24 02:05:11 volumio volumio[10512]: info: Reading ALSA contributions from plugins. Oct 24 02:05:11 volumio volumio[10512]: info: Asound.conf file unchanged, so no further update is needed Oct 24 02:05:11 volumio volumio[10512]: info: Output device has changed, restarting MPD Oct 24 02:05:11 volumio sudo[10624]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 24 02:05:11 volumio sudo[10624]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 02:05:11 volumio volumio[10512]: info: Output device has changed, restarting Shairport Sync Oct 24 02:05:11 volumio volumio[10512]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:11 volumio volumio[10512]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 24 02:05:11 volumio sudo[10624]: pam_unix(sudo:session): session closed for user root Oct 24 02:05:11 volumio sudo[10627]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 24 02:05:11 volumio sudo[10627]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 02:05:11 volumio systemd[1]: Stopping Music Player Daemon... Oct 24 02:05:11 volumio volumio[10512]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 24 02:05:11 volumio volumio[10512]: info: ___________ START PLUGINS ___________ Oct 24 02:05:11 volumio systemd[1]: mpd.service: Succeeded. Oct 24 02:05:11 volumio systemd[1]: Stopped Music Player Daemon. Oct 24 02:05:11 volumio volumio[10512]: info: ControllerMpd::onStart: Initializing MPD Oct 24 02:05:11 volumio volumio[10512]: info: Creating MPD Configuration file Oct 24 02:05:11 volumio sudo[10647]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 24 02:05:11 volumio sudo[10647]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 02:05:11 volumio volumio[10512]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 24 02:05:11 volumio systemd[1]: Starting Music Player Daemon... Oct 24 02:05:11 volumio volumio[10512]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 24 02:05:11 volumio sudo[10647]: pam_unix(sudo:session): session closed for user root Oct 24 02:05:11 volumio volumio[10512]: info: [1729724711583] CoreMusicLibrary::Adding element Медиа-серверы Oct 24 02:05:11 volumio volumio[10512]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 24 02:05:11 volumio sudo[10656]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 24 02:05:11 volumio sudo[10656]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 02:05:11 volumio volumio[10512]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:11 volumio volumio[10512]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 24 02:05:11 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Oct 24 02:05:11 volumio systemd[1]: mpd.service: Succeeded. Oct 24 02:05:11 volumio systemd[1]: Stopped Music Player Daemon. Oct 24 02:05:11 volumio systemd[1]: Starting Music Player Daemon... Oct 24 02:05:11 volumio volumio[10512]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 24 02:05:11 volumio volumio[10512]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:11 volumio volumio[10512]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 24 02:05:11 volumio volumio[10512]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 24 02:05:11 volumio volumio[10512]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 24 02:05:11 volumio volumio[10512]: info: [1729724711773] CoreMusicLibrary::Adding element Last_100 Oct 24 02:05:11 volumio volumio[10512]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 24 02:05:11 volumio volumio[10512]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 24 02:05:11 volumio volumio[10512]: info: [1729724711780] CoreMusicLibrary::Adding element Webradio Oct 24 02:05:11 volumio volumio[10512]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 24 02:05:11 volumio sudo[10671]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 24 02:05:11 volumio sudo[10671]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 02:05:11 volumio volumio[10512]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 24 02:05:11 volumio sudo[10671]: pam_unix(sudo:session): session closed for user root Oct 24 02:05:11 volumio volumio[10512]: info: Initializing BBC Radios Oct 24 02:05:11 volumio volumio[10512]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 24 02:05:11 volumio volumio[10512]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 24 02:05:11 volumio volumio[10512]: info: Adding Calm Radio to Browse Sources Oct 24 02:05:11 volumio volumio[10512]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 24 02:05:11 volumio volumio[10512]: info: [1729724711907] CoreMusicLibrary::Adding element Calm Radio Oct 24 02:05:11 volumio volumio[10512]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 24 02:05:11 volumio volumio[10512]: Cannot find translation for source Calm Radio Oct 24 02:05:11 volumio volumio[10512]: info: AutoStart - onStart Oct 24 02:05:11 volumio volumio[10512]: info: Loading i18n strings for locale ru Oct 24 02:05:11 volumio volumio[10512]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Oct 24 02:05:11 volumio volumio[10512]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Oct 24 02:05:11 volumio volumio[10512]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:11 volumio volumio[10512]: info: FusionDsp - mixtype--------------------- Hardware Oct 24 02:05:11 volumio volumio[10512]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 24 02:05:11 volumio volumio[10512]: info: [1729724711948] CoreMusicLibrary::Adding element 80s80s Radio Oct 24 02:05:11 volumio volumio[10512]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 24 02:05:11 volumio volumio[10512]: Cannot find translation for source Calm Radio Oct 24 02:05:11 volumio volumio[10512]: Cannot find translation for source 80s80s Radio Oct 24 02:05:11 volumio volumio[10512]: info: Loading i18n strings for locale ru Oct 24 02:05:11 volumio volumio[10512]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Oct 24 02:05:12 volumio volumio[10512]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 24 02:05:12 volumio volumio[10512]: info: [1729724712002] CoreMusicLibrary::Adding element Radio Paradise Oct 24 02:05:12 volumio volumio[10512]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 24 02:05:12 volumio volumio[10512]: Cannot find translation for source Calm Radio Oct 24 02:05:12 volumio volumio[10512]: Cannot find translation for source 80s80s Radio Oct 24 02:05:12 volumio volumio[10512]: Cannot find translation for source Radio Paradise Oct 24 02:05:12 volumio volumio[10512]: info: Volumio Calling Home Oct 24 02:05:12 volumio volumio[10512]: info: Preparing to generate the ALSA configuration file Oct 24 02:05:12 volumio volumio[10512]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Oct 24 02:05:12 volumio volumio[10512]: info: Reading ALSA contributions from plugins. Oct 24 02:05:12 volumio volumio[10512]: info: minidlna: Found miniDLNA version 1.2.1 Oct 24 02:05:12 volumio volumio[10512]: info: minidlna: Starting minidlna.service Oct 24 02:05:12 volumio volumio[10512]: info: MPD Permissions set Oct 24 02:05:12 volumio volumio[10512]: info: MPD Permissions set Oct 24 02:05:12 volumio volumio[10512]: info: VolumeController:: Volume=35 Mute =false Oct 24 02:05:12 volumio volumio[10512]: info: CoreStateMachine::pushState Oct 24 02:05:12 volumio volumio[10512]: info: CorePlayQueue::getTrack 0 Oct 24 02:05:12 volumio volumio[10512]: info: CoreCommandRouter::volumioPushState Oct 24 02:05:12 volumio sudo[10723]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start minidlna.service Oct 24 02:05:12 volumio sudo[10723]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 02:05:12 volumio volumio[10512]: info: Volumio called home Oct 24 02:05:12 volumio sudo[10723]: pam_unix(sudo:session): session closed for user root Oct 24 02:05:12 volumio volumio[10512]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Oct 24 02:05:12 volumio volumio[10512]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:12 volumio volumio[10512]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:12 volumio volumio[10512]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:12 volumio volumio[10512]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:12 volumio volumio[10512]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:12 volumio volumio[10512]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:12 volumio volumio[10512]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:12 volumio volumio[10512]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:12 volumio volumio[10512]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 24 02:05:12 volumio volumio[10512]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:12 volumio volumio[10512]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:12 volumio volumio[10512]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:12 volumio volumio[10512]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:12 volumio volumio[10512]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:12 volumio volumio[10512]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:12 volumio volumio[10512]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:12 volumio volumio[10512]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:12 volumio volumio[10512]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:12 volumio volumio[10512]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 24 02:05:12 volumio volumio[10512]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:12 volumio volumio[10512]: info: minidlna: systemctl start minidlna.service succeeded. Oct 24 02:05:12 volumio volumio[10512]: info: Starting Shairport Sync Oct 24 02:05:12 volumio volumio[10512]: info: Starting Shairport Sync Oct 24 02:05:12 volumio sudo[10734]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 24 02:05:12 volumio sudo[10734]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 02:05:12 volumio volumio[10512]: info: Starting Shairport Sync Oct 24 02:05:12 volumio sudo[10736]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 24 02:05:12 volumio sudo[10736]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 02:05:12 volumio sudo[10739]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 24 02:05:12 volumio sudo[10739]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 02:05:12 volumio volumio[10512]: info: CoreCommandRouter::volumioGetState Oct 24 02:05:12 volumio volumio[10512]: info: CorePlayQueue::getTrack 0 Oct 24 02:05:12 volumio volumio[10512]: info: Asound.conf file unchanged, so no further update is needed Oct 24 02:05:12 volumio volumio[10512]: info: Output device has changed, restarting MPD Oct 24 02:05:12 volumio sudo[10743]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 24 02:05:12 volumio sudo[10743]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 02:05:12 volumio volumio[10512]: info: Output device has changed, restarting Shairport Sync Oct 24 02:05:12 volumio volumio[10512]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:12 volumio volumio[10512]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 24 02:05:12 volumio sudo[10743]: pam_unix(sudo:session): session closed for user root Oct 24 02:05:12 volumio sudo[10745]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 24 02:05:12 volumio sudo[10745]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 02:05:12 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Oct 24 02:05:12 volumio systemd[1]: mpd.service: Succeeded. Oct 24 02:05:12 volumio systemd[1]: Stopped Music Player Daemon. Oct 24 02:05:13 volumio volumio[10512]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 24 02:05:13 volumio volumio[10512]: info: MPD Permissions set Oct 24 02:05:13 volumio volumio[10512]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:13 volumio volumio[10512]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:13 volumio volumio[10512]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:13 volumio volumio[10512]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:13 volumio volumio[10512]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:13 volumio volumio[10512]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:13 volumio volumio[10512]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:13 volumio volumio[10512]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:13 volumio volumio[10512]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 24 02:05:13 volumio volumio[10512]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:13 volumio systemd[1]: Starting Music Player Daemon... Oct 24 02:05:13 volumio volumio[10512]: info: Starting Shairport Sync Oct 24 02:05:13 volumio sudo[10758]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 24 02:05:13 volumio sudo[10758]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 02:05:13 volumio sudo[10751]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 24 02:05:13 volumio sudo[10751]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 02:05:13 volumio sudo[10751]: pam_unix(sudo:session): session closed for user root Oct 24 02:05:13 volumio volumio[10512]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 24 02:05:13 volumio volumio[10512]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 24 02:05:13 volumio volumio[10512]: info: Discovery: Getting this device information Oct 24 02:05:13 volumio volumio[10512]: info: CoreCommandRouter::volumioGetState Oct 24 02:05:13 volumio volumio[10512]: info: CorePlayQueue::getTrack 0 Oct 24 02:05:13 volumio volumio[10512]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 24 02:05:13 volumio volumio[10512]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Oct 24 02:05:13 volumio volumio[10512]: 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 24 02:05:13 volumio volumio[10512]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Oct 24 02:05:14 volumio volumio[10512]: info: camilladsp spawned new process with pid undefined, instance 1, run: true Oct 24 02:05:14 volumio volumio[10512]: info: camilladsp service started and running in background, instance 1 Oct 24 02:05:14 volumio volumio[10512]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:14 volumio volumio[10512]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Oct 24 02:05:14 volumio volumio[10512]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Oct 24 02:05:14 volumio volumio[10512]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Oct 24 02:05:14 volumio volumio[10512]: info: FusionDsp loaded Oct 24 02:05:14 volumio volumio[10512]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 24 02:05:14 volumio sudo[10772]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Oct 24 02:05:14 volumio sudo[10772]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 02:05:14 volumio volumio[10512]: info: FusionDsp - Reporting Fusion DSP Enabled Oct 24 02:05:14 volumio volumio[10512]: info: Adding Signal Path Element [object Object] Oct 24 02:05:14 volumio volumio[10512]: info: Adding fusiondspeq DSP Signal Path Element Oct 24 02:05:14 volumio sudo[10772]: pam_unix(sudo:session): session closed for user root Oct 24 02:05:14 volumio volumio[10512]: info: FusionDsp - ---- installed callbackRead Oct 24 02:05:14 volumio volumio[10512]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 24 02:05:14 volumio volumio[10512]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Oct 24 02:05:14 volumio volumio[10512]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Oct 24 02:05:14 volumio volumio[10512]: at onErrorNT (internal/child_process.js:465:16) Oct 24 02:05:14 volumio volumio[10512]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Oct 24 02:05:14 volumio volumio[10512]: errno: -2, Oct 24 02:05:14 volumio volumio[10512]: code: 'ENOENT', Oct 24 02:05:14 volumio volumio[10512]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Oct 24 02:05:14 volumio volumio[10512]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Oct 24 02:05:14 volumio volumio[10512]: spawnargs: [ Oct 24 02:05:14 volumio volumio[10512]: '-p', Oct 24 02:05:14 volumio volumio[10512]: 9876, Oct 24 02:05:14 volumio volumio[10512]: '-o', Oct 24 02:05:14 volumio volumio[10512]: '/tmp/camilladsp.log', Oct 24 02:05:14 volumio volumio[10512]: '-l', Oct 24 02:05:14 volumio volumio[10512]: 'warn', Oct 24 02:05:14 volumio volumio[10512]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Oct 24 02:05:14 volumio volumio[10512]: ] Oct 24 02:05:14 volumio volumio[10512]: } Oct 24 02:05:14 volumio volumio[10512]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 24 02:05:14 volumio mpd[10761]: Oct 24 02:05 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 24 02:05:14 volumio systemd[1]: Started Music Player Daemon. Oct 24 02:05:14 volumio sudo[10745]: pam_unix(sudo:session): session closed for user root Oct 24 02:05:14 volumio sudo[10627]: pam_unix(sudo:session): session closed for user root Oct 24 02:05:14 volumio sudo[10656]: pam_unix(sudo:session): session closed for user root Oct 24 02:05:14 volumio sudo[10785]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-24 02:04 Oct 24 02:05:14 volumio sudo[10785]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 02:05:14 volumio sudo[10785]: pam_unix(sudo:session): session closed for user root Oct 24 02:05:15 volumio volumio-remote-updater[658]: [2024-10-24 02:05:15] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Oct 24 02:05:15 volumio volumio-remote-updater[658]: [2024-10-24 02:05:15] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Oct 24 02:05:15 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Oct 24 02:05:15 volumio sudo[10736]: pam_unix(sudo:session): session closed for user root Oct 24 02:05:15 volumio sudo[10734]: pam_unix(sudo:session): session closed for user root Oct 24 02:05:15 volumio sudo[10758]: pam_unix(sudo:session): session closed for user root Oct 24 02:05:15 volumio sudo[10739]: pam_unix(sudo:session): session closed for user root Oct 24 02:05:15 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Oct 24 02:05:15 volumio systemd[1]: Started dynamicswap service. Oct 24 02:05:15 volumio systemd[1]: dynamicswap.service: Succeeded. Oct 24 02:05:15 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Oct 24 02:05:15 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 21. Oct 24 02:05:15 volumio systemd[1]: Stopped Volumio Backend Module. Oct 24 02:05:15 volumio systemd[1]: Started Volumio Backend Module. Oct 24 02:05:15 volumio systemd[1]: Started dynamicswap service. Oct 24 02:05:15 volumio systemd[1]: dynamicswap.service: Succeeded. Oct 24 02:05:17 volumio volumio[10795]: info: ------------------------------------------- Oct 24 02:05:17 volumio volumio[10795]: info: ----- Volumio3 ---- Oct 24 02:05:17 volumio volumio[10795]: info: ------------------------------------------- Oct 24 02:05:17 volumio volumio[10795]: info: ----- System startup ---- Oct 24 02:05:17 volumio volumio[10795]: info: ------------------------------------------- Oct 24 02:05:17 volumio volumio[10795]: info: MYVOLUMIO Environment detected Oct 24 02:05:18 volumio volumio[10795]: info: Plugin folders cleanup Oct 24 02:05:18 volumio volumio[10795]: info: Scanning into folder /volumio/app/plugins/ Oct 24 02:05:18 volumio volumio[10795]: info: Scanning category audio_interface Oct 24 02:05:18 volumio volumio[10795]: info: Scanning category miscellanea Oct 24 02:05:18 volumio volumio[10795]: info: Scanning category music_service Oct 24 02:05:18 volumio volumio[10795]: info: Scanning category plugins.json Oct 24 02:05:18 volumio volumio[10795]: info: Scanning category system_controller Oct 24 02:05:18 volumio volumio[10795]: info: Scanning category user_interface Oct 24 02:05:18 volumio volumio[10795]: info: Scanning into folder /data/plugins/ Oct 24 02:05:18 volumio volumio[10795]: info: Scanning category audio_interface Oct 24 02:05:18 volumio volumio[10795]: info: Scanning category music_service Oct 24 02:05:18 volumio volumio[10795]: info: Scanning category system_controller Oct 24 02:05:18 volumio volumio[10795]: info: Plugin folders cleanup completed Oct 24 02:05:18 volumio volumio[10795]: info: ------------------------------------------- Oct 24 02:05:18 volumio volumio[10795]: info: ----- Core plugins startup ---- Oct 24 02:05:18 volumio volumio[10795]: info: ------------------------------------------- Oct 24 02:05:18 volumio volumio[10795]: info: Loading plugins from folder /volumio/app/plugins/ Oct 24 02:05:18 volumio volumio[10795]: info: Adding plugin upnp to MyMusic Plugins Oct 24 02:05:18 volumio volumio[10795]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 24 02:05:18 volumio volumio[10795]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 24 02:05:18 volumio volumio[10795]: info: Loading plugins from folder /data/plugins/ Oct 24 02:05:18 volumio volumio[10795]: info: Loading plugin "system"... Oct 24 02:05:18 volumio volumio[10795]: info: Loading plugin "appearance"... Oct 24 02:05:19 volumio volumio[10795]: info: Loading plugin "network"... Oct 24 02:05:19 volumio volumio[10795]: info: Refreshing Cached IP Addresses Oct 24 02:05:19 volumio sudo[10822]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 24 02:05:19 volumio sudo[10822]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 02:05:19 volumio sudo[10822]: pam_unix(sudo:session): session closed for user root Oct 24 02:05:19 volumio volumio[10795]: info: Loading plugin "services"... Oct 24 02:05:19 volumio sudo[10824]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 24 02:05:19 volumio sudo[10824]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 02:05:19 volumio volumio[10795]: info: Loading plugin "alsa_controller"... Oct 24 02:05:19 volumio sudo[10824]: pam_unix(sudo:session): session closed for user root Oct 24 02:05:19 volumio sudo[10827]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 24 02:05:19 volumio sudo[10827]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 02:05:19 volumio volumio[10795]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 24 02:05:19 volumio volumio[10795]: info: Loading plugin "wizard"... Oct 24 02:05:19 volumio volumio[10795]: info: Loading plugin "networkfs"... Oct 24 02:05:19 volumio volumio[10795]: info: Starting Udev Watcher for removable devices Oct 24 02:05:19 volumio volumio[10795]: info: Ignoring mount for partition: boot Oct 24 02:05:19 volumio volumio[10795]: info: Ignoring mount for partition: volumio Oct 24 02:05:19 volumio volumio[10795]: info: Ignoring mount for partition: volumio_data Oct 24 02:05:19 volumio volumio[10795]: info: Mounting Device 1303-1E3E Oct 24 02:05:19 volumio sudo[10838]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/1303-1E3E -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Oct 24 02:05:19 volumio sudo[10838]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 02:05:19 volumio sudo[10838]: pam_unix(sudo:session): session closed for user root Oct 24 02:05:19 volumio volumio[10795]: mount: /media/1303-1E3E: /dev/sda1 already mounted on /media/1303-1E3E. Oct 24 02:05:19 volumio volumio[10795]: error: Failed to mount 1303-1E3E: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sda1" "/mnt/USB/1303-1E3E" -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Oct 24 02:05:19 volumio volumio[10795]: mount: /media/1303-1E3E: /dev/sda1 already mounted on /media/1303-1E3E. Oct 24 02:05:19 volumio volumio[10795]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 24 02:05:19 volumio volumio[10795]: info: Loading plugin "volumio_command_line_client"... Oct 24 02:05:19 volumio volumio[10795]: info: Loading plugin "upnp"... Oct 24 02:05:19 volumio volumio[10795]: info: [1729724719506] Starting Upmpd Daemon Oct 24 02:05:19 volumio volumio[10795]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 24 02:05:19 volumio volumio[10795]: info: Loading plugin "my_music"... Oct 24 02:05:19 volumio volumio[10795]: info: Loading plugin "mpd"... Oct 24 02:05:19 volumio volumio[10795]: info: Loading plugin "upnp_browser"... Oct 24 02:05:20 volumio volumio-remote-updater[658]: [2024-10-24 02:05:20] [connect] Successful connection Oct 24 02:05:20 volumio sudo[10827]: pam_unix(sudo:session): session closed for user root Oct 24 02:05:20 volumio volumio[10795]: info: Loading plugin "alarm-clock"... Oct 24 02:05:20 volumio volumio[10795]: info: Loading plugin "airplay_emulation"... Oct 24 02:05:20 volumio volumio[10795]: info: Starting Shairport Sync Oct 24 02:05:20 volumio volumio[10795]: info: Loading plugin "last_100"... Oct 24 02:05:20 volumio volumio[10795]: info: Loading plugin "webradio"... Oct 24 02:05:20 volumio volumio[10795]: info: Loading plugin "i2s_dacs"... Oct 24 02:05:20 volumio volumio[10795]: info: I2S DAC not set, start Auto-detection Oct 24 02:05:20 volumio volumio[10795]: info: Loading plugin "volumiodiscovery"... Oct 24 02:05:20 volumio volumio[10795]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 24 02:05:20 volumio volumio[10795]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 24 02:05:20 volumio volumio[10795]: *** WARNING *** For more information see Oct 24 02:05:20 volumio volumio[10795]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 24 02:05:20 volumio node[10795]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 24 02:05:20 volumio volumio[10795]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 24 02:05:20 volumio volumio[10795]: *** WARNING *** For more information see Oct 24 02:05:20 volumio node[10795]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 24 02:05:20 volumio node[10795]: *** WARNING *** For more information see Oct 24 02:05:20 volumio node[10795]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 24 02:05:20 volumio node[10795]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 24 02:05:20 volumio node[10795]: *** WARNING *** For more information see Oct 24 02:05:20 volumio volumio[10795]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 24 02:05:20 volumio volumio[10795]: info: Discovery: Started advertising with name: Volumio Oct 24 02:05:20 volumio volumio[10795]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 24 02:05:20 volumio volumio[10795]: info: Loading plugin "calmradio"... Oct 24 02:05:21 volumio volumio[10795]: info: Loading plugin "autostart"... Oct 24 02:05:21 volumio volumio[10795]: info: Applying required configuration parameters for plugin autostart Oct 24 02:05:21 volumio volumio[10795]: info: AutoStart - onVolumioStart - read config.json Oct 24 02:05:21 volumio volumio[10795]: info: Loading plugin "outputs"... Oct 24 02:05:21 volumio volumio[10795]: info: Loading plugin "albumart"... Oct 24 02:05:21 volumio volumio[10795]: info: Plugin example_plugin is not enabled Oct 24 02:05:21 volumio volumio[10795]: info: Loading plugin "inputs"... Oct 24 02:05:21 volumio volumio[10795]: info: Loading plugin "updater_comm"... Oct 24 02:05:22 volumio volumio[10795]: info: Plugin mpdemulation is not enabled Oct 24 02:05:22 volumio volumio[10795]: info: Loading plugin "rest_api"... Oct 24 02:05:22 volumio volumio[10795]: info: Loading plugin "websocket"... Oct 24 02:05:22 volumio volumio[10795]: info: Starting Socket.io Server version 2.3.0 Oct 24 02:05:22 volumio volumio[10795]: info: Loading plugin "fusiondsp"... Oct 24 02:05:22 volumio volumio[10795]: Forking 3 albumart workers Oct 24 02:05:22 volumio volumio[10795]: info: Applying required configuration parameters for plugin fusiondsp Oct 24 02:05:22 volumio volumio[10795]: info: Loading plugin "80s80s"... Oct 24 02:05:22 volumio volumio[10795]: info: Applying required configuration parameters for plugin 80s80s Oct 24 02:05:22 volumio volumio[10795]: info: [1729724722766] [80s80s] API delay: 30 Oct 24 02:05:22 volumio volumio[10795]: info: Loading plugin "minidlna"... Oct 24 02:05:22 volumio volumio[10795]: info: Applying required configuration parameters for plugin minidlna Oct 24 02:05:23 volumio volumio[10795]: info: Plugin personal_radio is not enabled Oct 24 02:05:23 volumio volumio[10795]: info: Loading plugin "radio_paradise"... Oct 24 02:05:23 volumio volumio[10795]: Starting albumart workers Oct 24 02:05:23 volumio volumio[10795]: info: Applying required configuration parameters for plugin radio_paradise Oct 24 02:05:23 volumio volumio[10795]: info: [1729724723390] [RadioParadise] API delay: 5 Oct 24 02:05:23 volumio volumio[10795]: Starting albumart workers Oct 24 02:05:23 volumio volumio[10795]: Starting albumart workers Oct 24 02:05:23 volumio volumio[10795]: info: Loading i18n strings for locale ru Oct 24 02:05:23 volumio volumio[10795]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Oct 24 02:05:23 volumio volumio[10795]: Updating browse sources language Oct 24 02:05:23 volumio volumio[10795]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 24 02:05:23 volumio volumio[10795]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 24 02:05:23 volumio volumio[10795]: info: CoreCommandRouter::initPlayerControls Oct 24 02:05:23 volumio volumio[10795]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:23 volumio volumio[10795]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:23 volumio volumio[10795]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:23 volumio volumio[10795]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:23 volumio volumio[10795]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:23 volumio volumio[10795]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:23 volumio volumio[10795]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:23 volumio volumio[10795]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:23 volumio volumio[10795]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 24 02:05:23 volumio volumio[10795]: Express server listening on port 3000 Oct 24 02:05:23 volumio volumio[10795]: [Metrics] WebUI: 7s 145.92ms Oct 24 02:05:23 volumio volumio[10795]: info: CoreStateMachine::resetVolumioState Oct 24 02:05:23 volumio volumio[10795]: info: CoreStateMachine::getcurrentVolume Oct 24 02:05:23 volumio volumio[10795]: info: CoreCommandRouter::volumioRetrievevolume Oct 24 02:05:23 volumio volumio[10795]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 24 02:05:25 volumio volumio[10795]: verbose: New Socket.io Connection to 192.168.1.139 from 192.168.1.72 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Oct 24 02:05:25 volumio volumio[10795]: verbose: New Socket.io Connection to 192.168.1.139 from 192.168.1.72 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Oct 24 02:05:25 volumio volumio[10795]: info: CoreCommandRouter::volumioGetState Oct 24 02:05:25 volumio volumio[10795]: info: CorePlayQueue::getTrack 0 Oct 24 02:05:25 volumio volumio[10795]: info: VolumeController:: Volume=35 Mute =false Oct 24 02:05:25 volumio volumio[10795]: info: CoreStateMachine::pushState Oct 24 02:05:25 volumio volumio[10795]: info: CorePlayQueue::getTrack 0 Oct 24 02:05:25 volumio volumio[10795]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 24 02:05:25 volumio volumio[10795]: info: CoreCommandRouter::volumioPushState Oct 24 02:05:25 volumio volumio[10795]: info: CoreStateMachine::updateTrackBlock Oct 24 02:05:25 volumio volumio[10795]: info: CorePlayQueue::getTrackBlock Oct 24 02:05:25 volumio volumio[10795]: info: CoreCommandRouter::volumioRetrievevolume Oct 24 02:05:25 volumio volumio[10795]: info: Reloading queue from file Oct 24 02:05:25 volumio volumio[10795]: verbose: New Socket.io Connection to 192.168.1.139 from 192.168.1.72 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Oct 24 02:05:25 volumio volumio[10795]: info: CoreStateMachine::setRepeat false single undefined Oct 24 02:05:25 volumio volumio[10795]: info: CoreStateMachine::pushState Oct 24 02:05:25 volumio volumio[10795]: info: CorePlayQueue::getTrack 0 Oct 24 02:05:25 volumio volumio[10795]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 24 02:05:25 volumio volumio[10795]: info: CoreCommandRouter::volumioPushState Oct 24 02:05:25 volumio volumio[10795]: info: CoreStateMachine::setRandom null Oct 24 02:05:25 volumio volumio[10795]: info: CoreStateMachine::pushState Oct 24 02:05:25 volumio volumio[10795]: info: CorePlayQueue::getTrack 0 Oct 24 02:05:25 volumio volumio[10795]: info: CoreCommandRouter::volumioPushState Oct 24 02:05:25 volumio volumio[10795]: info: Setting Device type: Raspberry PI Oct 24 02:05:25 volumio volumio[10795]: info: Listing playlists Oct 24 02:05:25 volumio volumio[10795]: info: Listing playlists Oct 24 02:05:25 volumio volumio[10795]: info: CoreCommandRouter::volumioGetState Oct 24 02:05:25 volumio volumio[10795]: info: CorePlayQueue::getTrack 0 Oct 24 02:05:25 volumio volumio[10795]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 24 02:05:25 volumio volumio[10795]: info: Listing playlists Oct 24 02:05:25 volumio volumio[10795]: info: VolumeController:: Volume=35 Mute =false Oct 24 02:05:25 volumio volumio[10795]: info: CoreStateMachine::pushState Oct 24 02:05:25 volumio volumio[10795]: info: CorePlayQueue::getTrack 0 Oct 24 02:05:25 volumio volumio[10795]: info: CoreCommandRouter::volumioPushState Oct 24 02:05:25 volumio volumio[10795]: info: Discovery: adding 266418d9-d71f-4348-91b8-c2087d0064b7 Oct 24 02:05:25 volumio volumio[10795]: info: Discovery: Found device Volumio Oct 24 02:05:25 volumio volumio[10795]: info: CoreCommandRouter::volumioGetState Oct 24 02:05:25 volumio volumio[10795]: info: CorePlayQueue::getTrack 0 Oct 24 02:05:25 volumio volumio[10795]: info: Completed loading Core Plugins Oct 24 02:05:25 volumio volumio[10795]: info: Preparing to generate the ALSA configuration file Oct 24 02:05:25 volumio volumio[10795]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Oct 24 02:05:25 volumio volumio[10795]: info: Reading ALSA contributions from plugins. Oct 24 02:05:25 volumio volumio[10795]: info: Asound.conf file unchanged, so no further update is needed Oct 24 02:05:25 volumio volumio[10795]: info: Output device has changed, restarting MPD Oct 24 02:05:25 volumio sudo[10908]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 24 02:05:25 volumio sudo[10908]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 02:05:25 volumio volumio[10795]: info: Output device has changed, restarting Shairport Sync Oct 24 02:05:25 volumio volumio[10795]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:25 volumio volumio[10795]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 24 02:05:25 volumio sudo[10908]: pam_unix(sudo:session): session closed for user root Oct 24 02:05:25 volumio sudo[10911]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 24 02:05:25 volumio sudo[10911]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 02:05:25 volumio systemd[1]: Stopping Music Player Daemon... Oct 24 02:05:25 volumio volumio[10795]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 24 02:05:25 volumio volumio[10795]: info: ___________ START PLUGINS ___________ Oct 24 02:05:25 volumio systemd[1]: mpd.service: Succeeded. Oct 24 02:05:25 volumio systemd[1]: Stopped Music Player Daemon. Oct 24 02:05:25 volumio volumio[10795]: info: ControllerMpd::onStart: Initializing MPD Oct 24 02:05:25 volumio volumio[10795]: info: Creating MPD Configuration file Oct 24 02:05:25 volumio sudo[10918]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 24 02:05:25 volumio sudo[10918]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 02:05:25 volumio sudo[10918]: pam_unix(sudo:session): session closed for user root Oct 24 02:05:26 volumio systemd[1]: Starting Music Player Daemon... Oct 24 02:05:26 volumio volumio[10795]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 24 02:05:26 volumio volumio[10795]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 24 02:05:26 volumio volumio[10795]: info: [1729724726016] CoreMusicLibrary::Adding element Медиа-серверы Oct 24 02:05:26 volumio volumio[10795]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 24 02:05:26 volumio sudo[10921]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 24 02:05:26 volumio sudo[10921]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 02:05:26 volumio volumio[10795]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:26 volumio volumio[10795]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 24 02:05:26 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Oct 24 02:05:26 volumio systemd[1]: mpd.service: Succeeded. Oct 24 02:05:26 volumio systemd[1]: Stopped Music Player Daemon. Oct 24 02:05:26 volumio systemd[1]: Starting Music Player Daemon... Oct 24 02:05:26 volumio volumio[10795]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 24 02:05:26 volumio volumio[10795]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:26 volumio volumio[10795]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 24 02:05:26 volumio volumio[10795]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 24 02:05:26 volumio volumio[10795]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 24 02:05:26 volumio volumio[10795]: info: [1729724726167] CoreMusicLibrary::Adding element Last_100 Oct 24 02:05:26 volumio volumio[10795]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 24 02:05:26 volumio volumio[10795]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 24 02:05:26 volumio volumio[10795]: info: [1729724726171] CoreMusicLibrary::Adding element Webradio Oct 24 02:05:26 volumio volumio[10795]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 24 02:05:26 volumio volumio[10795]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 24 02:05:26 volumio volumio[10795]: info: Initializing BBC Radios Oct 24 02:05:26 volumio sudo[10924]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 24 02:05:26 volumio sudo[10924]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 02:05:26 volumio sudo[10924]: pam_unix(sudo:session): session closed for user root Oct 24 02:05:26 volumio volumio[10795]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 24 02:05:26 volumio volumio[10795]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 24 02:05:26 volumio volumio[10795]: info: Adding Calm Radio to Browse Sources Oct 24 02:05:26 volumio volumio[10795]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 24 02:05:26 volumio volumio[10795]: info: [1729724726264] CoreMusicLibrary::Adding element Calm Radio Oct 24 02:05:26 volumio volumio[10795]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 24 02:05:26 volumio volumio[10795]: Cannot find translation for source Calm Radio Oct 24 02:05:26 volumio volumio[10795]: info: AutoStart - onStart Oct 24 02:05:26 volumio volumio[10795]: info: Loading i18n strings for locale ru Oct 24 02:05:26 volumio volumio[10795]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Oct 24 02:05:26 volumio volumio[10795]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Oct 24 02:05:26 volumio volumio[10795]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:26 volumio volumio[10795]: info: FusionDsp - mixtype--------------------- Hardware Oct 24 02:05:26 volumio volumio[10795]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 24 02:05:26 volumio volumio[10795]: info: [1729724726318] CoreMusicLibrary::Adding element 80s80s Radio Oct 24 02:05:26 volumio volumio[10795]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 24 02:05:26 volumio volumio[10795]: Cannot find translation for source Calm Radio Oct 24 02:05:26 volumio volumio[10795]: Cannot find translation for source 80s80s Radio Oct 24 02:05:26 volumio volumio[10795]: info: Loading i18n strings for locale ru Oct 24 02:05:26 volumio volumio[10795]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Oct 24 02:05:26 volumio volumio[10795]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 24 02:05:26 volumio volumio[10795]: info: [1729724726371] CoreMusicLibrary::Adding element Radio Paradise Oct 24 02:05:26 volumio volumio[10795]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 24 02:05:26 volumio volumio[10795]: Cannot find translation for source Calm Radio Oct 24 02:05:26 volumio volumio[10795]: Cannot find translation for source 80s80s Radio Oct 24 02:05:26 volumio volumio[10795]: Cannot find translation for source Radio Paradise Oct 24 02:05:26 volumio volumio[10795]: info: Volumio Calling Home Oct 24 02:05:26 volumio volumio[10795]: info: Preparing to generate the ALSA configuration file Oct 24 02:05:26 volumio volumio[10795]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Oct 24 02:05:26 volumio volumio[10795]: info: Reading ALSA contributions from plugins. Oct 24 02:05:26 volumio volumio[10795]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 24 02:05:26 volumio volumio[10795]: info: minidlna: Found miniDLNA version 1.2.1 Oct 24 02:05:26 volumio volumio[10795]: info: minidlna: Starting minidlna.service Oct 24 02:05:26 volumio volumio[10795]: info: MPD Permissions set Oct 24 02:05:26 volumio volumio[10795]: info: MPD Permissions set Oct 24 02:05:26 volumio sudo[10948]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start minidlna.service Oct 24 02:05:26 volumio sudo[10948]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 02:05:26 volumio volumio[10795]: info: Volumio called home Oct 24 02:05:26 volumio sudo[10948]: pam_unix(sudo:session): session closed for user root Oct 24 02:05:26 volumio volumio[10795]: 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 24 02:05:26 volumio volumio[10795]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:26 volumio volumio[10795]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:26 volumio volumio[10795]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:26 volumio volumio[10795]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:26 volumio volumio[10795]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:26 volumio volumio[10795]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:26 volumio volumio[10795]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:26 volumio volumio[10795]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:26 volumio volumio[10795]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 24 02:05:26 volumio volumio[10795]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:26 volumio volumio[10795]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:26 volumio volumio[10795]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:26 volumio volumio[10795]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:26 volumio volumio[10795]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:26 volumio volumio[10795]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:26 volumio volumio[10795]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:26 volumio volumio[10795]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:26 volumio volumio[10795]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:26 volumio volumio[10795]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 24 02:05:26 volumio volumio[10795]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:27 volumio volumio[10795]: info: minidlna: systemctl start minidlna.service succeeded. Oct 24 02:05:27 volumio volumio[10795]: info: Starting Shairport Sync Oct 24 02:05:27 volumio volumio[10795]: info: Starting Shairport Sync Oct 24 02:05:27 volumio sudo[10959]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 24 02:05:27 volumio sudo[10959]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 02:05:27 volumio volumio[10795]: info: Starting Shairport Sync Oct 24 02:05:27 volumio sudo[10961]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 24 02:05:27 volumio sudo[10961]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 02:05:27 volumio volumio[10795]: info: CoreCommandRouter::volumioGetState Oct 24 02:05:27 volumio volumio[10795]: info: CorePlayQueue::getTrack 0 Oct 24 02:05:27 volumio sudo[10965]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 24 02:05:27 volumio sudo[10965]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 02:05:27 volumio volumio[10795]: info: Asound.conf file unchanged, so no further update is needed Oct 24 02:05:27 volumio volumio[10795]: info: Output device has changed, restarting MPD Oct 24 02:05:27 volumio volumio[10795]: info: Output device has changed, restarting Shairport Sync Oct 24 02:05:27 volumio sudo[10968]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 24 02:05:27 volumio sudo[10968]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 02:05:27 volumio volumio[10795]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:27 volumio volumio[10795]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 24 02:05:27 volumio sudo[10968]: pam_unix(sudo:session): session closed for user root Oct 24 02:05:27 volumio sudo[10970]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 24 02:05:27 volumio sudo[10970]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 02:05:27 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Oct 24 02:05:27 volumio systemd[1]: mpd.service: Succeeded. Oct 24 02:05:27 volumio systemd[1]: Stopped Music Player Daemon. Oct 24 02:05:27 volumio volumio[10795]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 24 02:05:27 volumio volumio[10795]: info: MPD Permissions set Oct 24 02:05:27 volumio volumio[10795]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:27 volumio volumio[10795]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:27 volumio volumio[10795]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:27 volumio volumio[10795]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:27 volumio volumio[10795]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:27 volumio volumio[10795]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:27 volumio volumio[10795]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:27 volumio systemd[1]: Starting Music Player Daemon... Oct 24 02:05:27 volumio volumio[10795]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:27 volumio volumio[10795]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 24 02:05:27 volumio volumio[10795]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:27 volumio volumio[10795]: info: Starting Shairport Sync Oct 24 02:05:27 volumio sudo[10983]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 24 02:05:27 volumio sudo[10983]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 02:05:27 volumio sudo[10976]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 24 02:05:27 volumio sudo[10976]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 02:05:27 volumio sudo[10976]: pam_unix(sudo:session): session closed for user root Oct 24 02:05:28 volumio volumio[10795]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Oct 24 02:05:28 volumio volumio[10795]: 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 24 02:05:28 volumio volumio[10795]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Oct 24 02:05:28 volumio volumio[10795]: info: camilladsp spawned new process with pid undefined, instance 1, run: true Oct 24 02:05:28 volumio volumio[10795]: info: camilladsp service started and running in background, instance 1 Oct 24 02:05:28 volumio volumio[10795]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:28 volumio volumio[10795]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Oct 24 02:05:28 volumio volumio[10795]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Oct 24 02:05:28 volumio volumio[10795]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Oct 24 02:05:28 volumio volumio[10795]: info: FusionDsp loaded Oct 24 02:05:28 volumio volumio[10795]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 24 02:05:28 volumio sudo[10997]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Oct 24 02:05:28 volumio sudo[10997]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 02:05:28 volumio volumio[10795]: info: FusionDsp - Reporting Fusion DSP Enabled Oct 24 02:05:28 volumio volumio[10795]: info: Adding Signal Path Element [object Object] Oct 24 02:05:28 volumio volumio[10795]: info: Adding fusiondspeq DSP Signal Path Element Oct 24 02:05:28 volumio volumio[10795]: info: FusionDsp - ---- installed callbackRead Oct 24 02:05:28 volumio sudo[10997]: pam_unix(sudo:session): session closed for user root Oct 24 02:05:28 volumio volumio[10795]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 24 02:05:28 volumio volumio[10795]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Oct 24 02:05:28 volumio volumio[10795]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Oct 24 02:05:28 volumio volumio[10795]: at onErrorNT (internal/child_process.js:465:16) Oct 24 02:05:28 volumio volumio[10795]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Oct 24 02:05:28 volumio volumio[10795]: errno: -2, Oct 24 02:05:28 volumio volumio[10795]: code: 'ENOENT', Oct 24 02:05:28 volumio volumio[10795]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Oct 24 02:05:28 volumio volumio[10795]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Oct 24 02:05:28 volumio volumio[10795]: spawnargs: [ Oct 24 02:05:28 volumio volumio[10795]: '-p', Oct 24 02:05:28 volumio volumio[10795]: 9876, Oct 24 02:05:28 volumio volumio[10795]: '-o', Oct 24 02:05:28 volumio volumio[10795]: '/tmp/camilladsp.log', Oct 24 02:05:28 volumio volumio[10795]: '-l', Oct 24 02:05:28 volumio volumio[10795]: 'warn', Oct 24 02:05:28 volumio volumio[10795]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Oct 24 02:05:28 volumio volumio[10795]: ] Oct 24 02:05:28 volumio volumio[10795]: } Oct 24 02:05:28 volumio volumio[10795]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 24 02:05:29 volumio mpd[10986]: Oct 24 02:05 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 24 02:05:29 volumio systemd[1]: Started Music Player Daemon. Oct 24 02:05:29 volumio sudo[10970]: pam_unix(sudo:session): session closed for user root Oct 24 02:05:29 volumio sudo[10911]: pam_unix(sudo:session): session closed for user root Oct 24 02:05:29 volumio sudo[10921]: pam_unix(sudo:session): session closed for user root Oct 24 02:05:29 volumio sudo[11010]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-24 02:04 Oct 24 02:05:29 volumio sudo[11010]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 02:05:29 volumio sudo[11010]: pam_unix(sudo:session): session closed for user root Oct 24 02:05:29 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Oct 24 02:05:29 volumio sudo[10965]: pam_unix(sudo:session): session closed for user root Oct 24 02:05:29 volumio sudo[10959]: pam_unix(sudo:session): session closed for user root Oct 24 02:05:29 volumio sudo[10961]: pam_unix(sudo:session): session closed for user root Oct 24 02:05:29 volumio sudo[10983]: pam_unix(sudo:session): session closed for user root Oct 24 02:05:29 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Oct 24 02:05:29 volumio systemd[1]: Started dynamicswap service. Oct 24 02:05:29 volumio systemd[1]: dynamicswap.service: Succeeded. Oct 24 02:05:29 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Oct 24 02:05:29 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 22. Oct 24 02:05:29 volumio systemd[1]: Stopped Volumio Backend Module. Oct 24 02:05:29 volumio systemd[1]: Started Volumio Backend Module. Oct 24 02:05:29 volumio systemd[1]: Started dynamicswap service. Oct 24 02:05:29 volumio systemd[1]: dynamicswap.service: Succeeded. Oct 24 02:05:31 volumio volumio[11020]: info: ------------------------------------------- Oct 24 02:05:31 volumio volumio[11020]: info: ----- Volumio3 ---- Oct 24 02:05:31 volumio volumio[11020]: info: ------------------------------------------- Oct 24 02:05:31 volumio volumio[11020]: info: ----- System startup ---- Oct 24 02:05:31 volumio volumio[11020]: info: ------------------------------------------- Oct 24 02:05:32 volumio volumio[11020]: info: MYVOLUMIO Environment detected Oct 24 02:05:32 volumio volumio[11020]: info: Plugin folders cleanup Oct 24 02:05:32 volumio volumio[11020]: info: Scanning into folder /volumio/app/plugins/ Oct 24 02:05:32 volumio volumio[11020]: info: Scanning category audio_interface Oct 24 02:05:32 volumio volumio[11020]: info: Scanning category miscellanea Oct 24 02:05:32 volumio volumio[11020]: info: Scanning category music_service Oct 24 02:05:32 volumio volumio[11020]: info: Scanning category plugins.json Oct 24 02:05:32 volumio volumio[11020]: info: Scanning category system_controller Oct 24 02:05:32 volumio volumio[11020]: info: Scanning category user_interface Oct 24 02:05:32 volumio volumio[11020]: info: Scanning into folder /data/plugins/ Oct 24 02:05:32 volumio volumio[11020]: info: Scanning category audio_interface Oct 24 02:05:32 volumio volumio[11020]: info: Scanning category music_service Oct 24 02:05:32 volumio volumio[11020]: info: Scanning category system_controller Oct 24 02:05:32 volumio volumio[11020]: info: Plugin folders cleanup completed Oct 24 02:05:32 volumio volumio[11020]: info: ------------------------------------------- Oct 24 02:05:32 volumio volumio[11020]: info: ----- Core plugins startup ---- Oct 24 02:05:32 volumio volumio[11020]: info: ------------------------------------------- Oct 24 02:05:32 volumio volumio[11020]: info: Loading plugins from folder /volumio/app/plugins/ Oct 24 02:05:32 volumio volumio[11020]: info: Adding plugin upnp to MyMusic Plugins Oct 24 02:05:32 volumio volumio[11020]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 24 02:05:32 volumio volumio[11020]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 24 02:05:32 volumio volumio[11020]: info: Loading plugins from folder /data/plugins/ Oct 24 02:05:32 volumio volumio-remote-updater[658]: [2024-10-24 02:05:32] [connect] Successful connection Oct 24 02:05:32 volumio volumio[11020]: info: Loading plugin "system"... Oct 24 02:05:32 volumio volumio[11020]: info: Loading plugin "appearance"... Oct 24 02:05:33 volumio volumio[11020]: info: Loading plugin "network"... Oct 24 02:05:33 volumio volumio[11020]: info: Refreshing Cached IP Addresses Oct 24 02:05:33 volumio sudo[11047]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 24 02:05:33 volumio sudo[11047]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 02:05:33 volumio sudo[11047]: pam_unix(sudo:session): session closed for user root Oct 24 02:05:33 volumio sudo[11049]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 24 02:05:33 volumio sudo[11049]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 02:05:33 volumio volumio[11020]: info: Loading plugin "services"... Oct 24 02:05:33 volumio sudo[11049]: pam_unix(sudo:session): session closed for user root Oct 24 02:05:33 volumio volumio[11020]: info: Loading plugin "alsa_controller"... Oct 24 02:05:33 volumio sudo[11052]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 24 02:05:33 volumio sudo[11052]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 02:05:33 volumio volumio[11020]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 24 02:05:33 volumio volumio[11020]: info: Loading plugin "wizard"... Oct 24 02:05:33 volumio volumio[11020]: info: Loading plugin "networkfs"... Oct 24 02:05:33 volumio volumio[11020]: info: Starting Udev Watcher for removable devices Oct 24 02:05:33 volumio volumio[11020]: info: Ignoring mount for partition: boot Oct 24 02:05:33 volumio volumio[11020]: info: Ignoring mount for partition: volumio Oct 24 02:05:33 volumio volumio[11020]: info: Ignoring mount for partition: volumio_data Oct 24 02:05:33 volumio volumio[11020]: info: Mounting Device 1303-1E3E Oct 24 02:05:33 volumio sudo[11063]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/1303-1E3E -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Oct 24 02:05:33 volumio sudo[11063]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 02:05:33 volumio sudo[11063]: pam_unix(sudo:session): session closed for user root Oct 24 02:05:33 volumio volumio[11020]: mount: /media/1303-1E3E: /dev/sda1 already mounted on /media/1303-1E3E. Oct 24 02:05:33 volumio volumio[11020]: error: Failed to mount 1303-1E3E: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sda1" "/mnt/USB/1303-1E3E" -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Oct 24 02:05:33 volumio volumio[11020]: mount: /media/1303-1E3E: /dev/sda1 already mounted on /media/1303-1E3E. Oct 24 02:05:33 volumio volumio[11020]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 24 02:05:33 volumio volumio[11020]: info: Loading plugin "volumio_command_line_client"... Oct 24 02:05:33 volumio volumio[11020]: info: Loading plugin "upnp"... Oct 24 02:05:34 volumio volumio[11020]: info: [1729724734001] Starting Upmpd Daemon Oct 24 02:05:34 volumio volumio[11020]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 24 02:05:34 volumio volumio[11020]: info: Loading plugin "my_music"... Oct 24 02:05:34 volumio volumio[11020]: info: Loading plugin "mpd"... Oct 24 02:05:34 volumio volumio[11020]: info: Loading plugin "upnp_browser"... Oct 24 02:05:34 volumio sudo[11052]: pam_unix(sudo:session): session closed for user root Oct 24 02:05:34 volumio volumio[11020]: info: Loading plugin "alarm-clock"... Oct 24 02:05:34 volumio volumio[11020]: info: Loading plugin "airplay_emulation"... Oct 24 02:05:34 volumio volumio[11020]: info: Starting Shairport Sync Oct 24 02:05:34 volumio volumio[11020]: info: Loading plugin "last_100"... Oct 24 02:05:34 volumio volumio[11020]: info: Loading plugin "webradio"... Oct 24 02:05:35 volumio volumio[11020]: info: Loading plugin "i2s_dacs"... Oct 24 02:05:35 volumio volumio[11020]: info: I2S DAC not set, start Auto-detection Oct 24 02:05:35 volumio volumio[11020]: info: Loading plugin "volumiodiscovery"... Oct 24 02:05:35 volumio volumio[11020]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 24 02:05:35 volumio volumio[11020]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 24 02:05:35 volumio volumio[11020]: *** WARNING *** For more information see Oct 24 02:05:35 volumio node[11020]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 24 02:05:35 volumio volumio[11020]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 24 02:05:35 volumio volumio[11020]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 24 02:05:35 volumio volumio[11020]: *** WARNING *** For more information see Oct 24 02:05:35 volumio node[11020]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 24 02:05:35 volumio node[11020]: *** WARNING *** For more information see Oct 24 02:05:35 volumio node[11020]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 24 02:05:35 volumio node[11020]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 24 02:05:35 volumio node[11020]: *** WARNING *** For more information see Oct 24 02:05:35 volumio volumio[11020]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 24 02:05:35 volumio volumio[11020]: info: Discovery: Started advertising with name: Volumio Oct 24 02:05:35 volumio volumio[11020]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 24 02:05:35 volumio volumio[11020]: info: Loading plugin "calmradio"... Oct 24 02:05:36 volumio volumio[11020]: info: Loading plugin "autostart"... Oct 24 02:05:36 volumio volumio[11020]: info: Applying required configuration parameters for plugin autostart Oct 24 02:05:36 volumio volumio[11020]: info: AutoStart - onVolumioStart - read config.json Oct 24 02:05:36 volumio volumio[11020]: info: Loading plugin "outputs"... Oct 24 02:05:36 volumio volumio[11020]: info: Loading plugin "albumart"... Oct 24 02:05:36 volumio volumio[11020]: info: Plugin example_plugin is not enabled Oct 24 02:05:36 volumio volumio[11020]: info: Loading plugin "inputs"... Oct 24 02:05:36 volumio volumio[11020]: info: Loading plugin "updater_comm"... Oct 24 02:05:36 volumio volumio[11020]: info: Plugin mpdemulation is not enabled Oct 24 02:05:36 volumio volumio[11020]: info: Loading plugin "rest_api"... Oct 24 02:05:36 volumio volumio[11020]: info: Loading plugin "websocket"... Oct 24 02:05:36 volumio volumio[11020]: info: Starting Socket.io Server version 2.3.0 Oct 24 02:05:36 volumio volumio[11020]: info: Loading plugin "fusiondsp"... Oct 24 02:05:36 volumio volumio[11020]: Forking 3 albumart workers Oct 24 02:05:36 volumio volumio[11020]: info: Applying required configuration parameters for plugin fusiondsp Oct 24 02:05:36 volumio volumio[11020]: info: Loading plugin "80s80s"... Oct 24 02:05:37 volumio volumio[11020]: info: Applying required configuration parameters for plugin 80s80s Oct 24 02:05:37 volumio volumio[11020]: info: [1729724737319] [80s80s] API delay: 30 Oct 24 02:05:37 volumio volumio[11020]: info: Loading plugin "minidlna"... Oct 24 02:05:37 volumio volumio[11020]: info: Applying required configuration parameters for plugin minidlna Oct 24 02:05:37 volumio volumio[11020]: info: Plugin personal_radio is not enabled Oct 24 02:05:37 volumio volumio[11020]: info: Loading plugin "radio_paradise"... Oct 24 02:05:37 volumio volumio[11020]: Starting albumart workers Oct 24 02:05:37 volumio volumio[11020]: Starting albumart workers Oct 24 02:05:37 volumio volumio[11020]: Starting albumart workers Oct 24 02:05:37 volumio volumio[11020]: info: Applying required configuration parameters for plugin radio_paradise Oct 24 02:05:37 volumio volumio[11020]: info: [1729724737984] [RadioParadise] API delay: 5 Oct 24 02:05:38 volumio volumio[11020]: info: Loading i18n strings for locale ru Oct 24 02:05:38 volumio volumio[11020]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Oct 24 02:05:38 volumio volumio[11020]: Updating browse sources language Oct 24 02:05:38 volumio volumio[11020]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 24 02:05:38 volumio volumio[11020]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 24 02:05:38 volumio volumio[11020]: info: CoreCommandRouter::initPlayerControls Oct 24 02:05:38 volumio volumio[11020]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:38 volumio volumio[11020]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:38 volumio volumio[11020]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:38 volumio volumio[11020]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:38 volumio volumio[11020]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:38 volumio volumio[11020]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:38 volumio volumio[11020]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:38 volumio volumio[11020]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:38 volumio volumio[11020]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 24 02:05:38 volumio volumio[11020]: Express server listening on port 3000 Oct 24 02:05:38 volumio volumio[11020]: [Metrics] WebUI: 7s 185.36ms Oct 24 02:05:38 volumio volumio[11020]: info: CoreStateMachine::resetVolumioState Oct 24 02:05:38 volumio volumio[11020]: info: CoreStateMachine::getcurrentVolume Oct 24 02:05:38 volumio volumio[11020]: info: CoreCommandRouter::volumioRetrievevolume Oct 24 02:05:38 volumio volumio[11020]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 24 02:05:38 volumio volumio[11020]: verbose: New Socket.io Connection to 192.168.1.139 from 192.168.1.72 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Oct 24 02:05:38 volumio volumio[11020]: verbose: New Socket.io Connection to 192.168.1.139 from 192.168.1.72 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Oct 24 02:05:38 volumio volumio[11020]: verbose: New Socket.io Connection to 192.168.1.139 from 192.168.1.72 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Oct 24 02:05:38 volumio volumio[11020]: info: Reloading queue from file Oct 24 02:05:38 volumio volumio[11020]: info: CoreStateMachine::setRepeat false single undefined Oct 24 02:05:38 volumio volumio[11020]: info: CoreStateMachine::pushState Oct 24 02:05:38 volumio volumio[11020]: info: CorePlayQueue::getTrack 0 Oct 24 02:05:38 volumio volumio[11020]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 24 02:05:38 volumio volumio[11020]: info: CoreCommandRouter::volumioPushState Oct 24 02:05:38 volumio volumio[11020]: info: CoreStateMachine::setRandom null Oct 24 02:05:38 volumio volumio[11020]: info: CoreStateMachine::pushState Oct 24 02:05:38 volumio volumio[11020]: info: CorePlayQueue::getTrack 0 Oct 24 02:05:38 volumio volumio[11020]: info: CoreCommandRouter::volumioPushState Oct 24 02:05:38 volumio volumio[11020]: info: Setting Device type: Raspberry PI Oct 24 02:05:38 volumio volumio[11020]: info: VolumeController:: Volume=35 Mute =false Oct 24 02:05:38 volumio volumio[11020]: info: CoreStateMachine::pushState Oct 24 02:05:38 volumio volumio[11020]: info: CorePlayQueue::getTrack 0 Oct 24 02:05:38 volumio volumio[11020]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 24 02:05:38 volumio volumio[11020]: info: CoreCommandRouter::volumioPushState Oct 24 02:05:38 volumio volumio[11020]: info: CoreStateMachine::updateTrackBlock Oct 24 02:05:38 volumio volumio[11020]: info: CorePlayQueue::getTrackBlock Oct 24 02:05:38 volumio volumio[11020]: info: CoreCommandRouter::volumioRetrievevolume Oct 24 02:05:38 volumio volumio[11020]: info: Completed loading Core Plugins Oct 24 02:05:38 volumio volumio[11020]: info: Preparing to generate the ALSA configuration file Oct 24 02:05:38 volumio volumio[11020]: info: Discovery: adding 266418d9-d71f-4348-91b8-c2087d0064b7 Oct 24 02:05:38 volumio volumio[11020]: info: Discovery: Found device Volumio Oct 24 02:05:38 volumio volumio[11020]: info: CoreCommandRouter::volumioGetState Oct 24 02:05:38 volumio volumio[11020]: info: CorePlayQueue::getTrack 0 Oct 24 02:05:38 volumio volumio[11020]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Oct 24 02:05:38 volumio volumio[11020]: info: Reading ALSA contributions from plugins. Oct 24 02:05:38 volumio volumio[11020]: info: Asound.conf file unchanged, so no further update is needed Oct 24 02:05:38 volumio volumio[11020]: info: Output device has changed, restarting MPD Oct 24 02:05:38 volumio sudo[11132]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 24 02:05:38 volumio sudo[11132]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 02:05:38 volumio volumio[11020]: info: Output device has changed, restarting Shairport Sync Oct 24 02:05:38 volumio volumio[11020]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:38 volumio volumio[11020]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 24 02:05:38 volumio sudo[11132]: pam_unix(sudo:session): session closed for user root Oct 24 02:05:38 volumio sudo[11135]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 24 02:05:38 volumio sudo[11135]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 02:05:38 volumio systemd[1]: Stopping Music Player Daemon... Oct 24 02:05:38 volumio volumio[11020]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 24 02:05:38 volumio volumio[11020]: info: ___________ START PLUGINS ___________ Oct 24 02:05:39 volumio volumio[11020]: info: ControllerMpd::onStart: Initializing MPD Oct 24 02:05:39 volumio volumio[11020]: info: Creating MPD Configuration file Oct 24 02:05:39 volumio systemd[1]: mpd.service: Succeeded. Oct 24 02:05:39 volumio systemd[1]: Stopped Music Player Daemon. Oct 24 02:05:39 volumio sudo[11142]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 24 02:05:39 volumio sudo[11142]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 02:05:39 volumio systemd[1]: Starting Music Player Daemon... Oct 24 02:05:39 volumio volumio[11020]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 24 02:05:39 volumio volumio[11020]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 24 02:05:39 volumio volumio[11020]: info: [1729724739059] CoreMusicLibrary::Adding element Медиа-серверы Oct 24 02:05:39 volumio volumio[11020]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 24 02:05:39 volumio sudo[11142]: pam_unix(sudo:session): session closed for user root Oct 24 02:05:39 volumio sudo[11144]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 24 02:05:39 volumio sudo[11144]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 02:05:39 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Oct 24 02:05:39 volumio systemd[1]: mpd.service: Succeeded. Oct 24 02:05:39 volumio systemd[1]: Stopped Music Player Daemon. Oct 24 02:05:39 volumio volumio[11020]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:39 volumio volumio[11020]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 24 02:05:39 volumio systemd[1]: Starting Music Player Daemon... Oct 24 02:05:39 volumio volumio[11020]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 24 02:05:39 volumio volumio[11020]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:39 volumio volumio[11020]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 24 02:05:39 volumio volumio[11020]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 24 02:05:39 volumio volumio[11020]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 24 02:05:39 volumio volumio[11020]: info: [1729724739229] CoreMusicLibrary::Adding element Last_100 Oct 24 02:05:39 volumio volumio[11020]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 24 02:05:39 volumio volumio[11020]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 24 02:05:39 volumio volumio[11020]: info: [1729724739234] CoreMusicLibrary::Adding element Webradio Oct 24 02:05:39 volumio volumio[11020]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 24 02:05:39 volumio volumio[11020]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 24 02:05:39 volumio sudo[11147]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 24 02:05:39 volumio volumio[11020]: info: Initializing BBC Radios Oct 24 02:05:39 volumio sudo[11147]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 02:05:39 volumio sudo[11147]: pam_unix(sudo:session): session closed for user root Oct 24 02:05:39 volumio volumio[11020]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 24 02:05:39 volumio volumio[11020]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 24 02:05:39 volumio volumio[11020]: info: Adding Calm Radio to Browse Sources Oct 24 02:05:39 volumio volumio[11020]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 24 02:05:39 volumio volumio[11020]: info: [1729724739326] CoreMusicLibrary::Adding element Calm Radio Oct 24 02:05:39 volumio volumio[11020]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 24 02:05:39 volumio volumio[11020]: Cannot find translation for source Calm Radio Oct 24 02:05:39 volumio volumio[11020]: info: AutoStart - onStart Oct 24 02:05:39 volumio volumio[11020]: info: Loading i18n strings for locale ru Oct 24 02:05:39 volumio volumio[11020]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Oct 24 02:05:39 volumio volumio[11020]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Oct 24 02:05:39 volumio volumio[11020]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:39 volumio volumio[11020]: info: FusionDsp - mixtype--------------------- Hardware Oct 24 02:05:39 volumio volumio[11020]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 24 02:05:39 volumio volumio[11020]: info: [1729724739374] CoreMusicLibrary::Adding element 80s80s Radio Oct 24 02:05:39 volumio volumio[11020]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 24 02:05:39 volumio volumio[11020]: Cannot find translation for source Calm Radio Oct 24 02:05:39 volumio volumio[11020]: Cannot find translation for source 80s80s Radio Oct 24 02:05:39 volumio volumio[11020]: info: Loading i18n strings for locale ru Oct 24 02:05:39 volumio volumio[11020]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Oct 24 02:05:39 volumio volumio[11020]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 24 02:05:39 volumio volumio[11020]: info: [1729724739425] CoreMusicLibrary::Adding element Radio Paradise Oct 24 02:05:39 volumio volumio[11020]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 24 02:05:39 volumio volumio[11020]: Cannot find translation for source Calm Radio Oct 24 02:05:39 volumio volumio[11020]: Cannot find translation for source 80s80s Radio Oct 24 02:05:39 volumio volumio[11020]: Cannot find translation for source Radio Paradise Oct 24 02:05:39 volumio volumio[11020]: info: Volumio Calling Home Oct 24 02:05:39 volumio volumio[11020]: info: Preparing to generate the ALSA configuration file Oct 24 02:05:39 volumio volumio[11020]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Oct 24 02:05:39 volumio volumio[11020]: info: Reading ALSA contributions from plugins. Oct 24 02:05:39 volumio volumio[11020]: info: MPD Permissions set Oct 24 02:05:39 volumio volumio[11020]: info: MPD Permissions set Oct 24 02:05:39 volumio volumio[11020]: info: VolumeController:: Volume=35 Mute =false Oct 24 02:05:39 volumio volumio[11020]: info: CoreStateMachine::pushState Oct 24 02:05:39 volumio volumio[11020]: info: CorePlayQueue::getTrack 0 Oct 24 02:05:39 volumio volumio[11020]: info: CoreCommandRouter::volumioPushState Oct 24 02:05:39 volumio volumio[11020]: info: Volumio called home Oct 24 02:05:39 volumio volumio[11020]: info: minidlna: Found miniDLNA version 1.2.1 Oct 24 02:05:39 volumio volumio[11020]: info: minidlna: Starting minidlna.service Oct 24 02:05:39 volumio volumio[11020]: info: CoreCommandRouter::volumioGetState Oct 24 02:05:39 volumio volumio[11020]: info: CorePlayQueue::getTrack 0 Oct 24 02:05:39 volumio sudo[11172]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start minidlna.service Oct 24 02:05:39 volumio sudo[11172]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 02:05:39 volumio volumio[11020]: 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 24 02:05:39 volumio volumio[11020]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:39 volumio volumio[11020]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:39 volumio volumio[11020]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:39 volumio volumio[11020]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:39 volumio volumio[11020]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:39 volumio volumio[11020]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:39 volumio volumio[11020]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:39 volumio volumio[11020]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:39 volumio sudo[11172]: pam_unix(sudo:session): session closed for user root Oct 24 02:05:39 volumio volumio[11020]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 24 02:05:39 volumio volumio[11020]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:39 volumio volumio[11020]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:39 volumio volumio[11020]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:39 volumio volumio[11020]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:39 volumio volumio[11020]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:39 volumio volumio[11020]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:39 volumio volumio[11020]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:39 volumio volumio[11020]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:39 volumio volumio[11020]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:39 volumio volumio[11020]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 24 02:05:39 volumio volumio[11020]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:40 volumio volumio[11020]: info: minidlna: systemctl start minidlna.service succeeded. Oct 24 02:05:40 volumio volumio[11020]: info: Starting Shairport Sync Oct 24 02:05:40 volumio volumio[11020]: info: Starting Shairport Sync Oct 24 02:05:40 volumio sudo[11183]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 24 02:05:40 volumio volumio[11020]: info: Starting Shairport Sync Oct 24 02:05:40 volumio sudo[11183]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 02:05:40 volumio sudo[11185]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 24 02:05:40 volumio sudo[11185]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 02:05:40 volumio volumio[11020]: info: CoreCommandRouter::volumioGetState Oct 24 02:05:40 volumio volumio[11020]: info: CorePlayQueue::getTrack 0 Oct 24 02:05:40 volumio sudo[11189]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 24 02:05:40 volumio sudo[11189]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 02:05:40 volumio volumio[11020]: info: Asound.conf file unchanged, so no further update is needed Oct 24 02:05:40 volumio volumio[11020]: info: Output device has changed, restarting MPD Oct 24 02:05:40 volumio sudo[11192]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 24 02:05:40 volumio volumio[11020]: info: Output device has changed, restarting Shairport Sync Oct 24 02:05:40 volumio volumio[11020]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:40 volumio sudo[11192]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 02:05:40 volumio volumio[11020]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 24 02:05:40 volumio sudo[11192]: pam_unix(sudo:session): session closed for user root Oct 24 02:05:40 volumio sudo[11195]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 24 02:05:40 volumio sudo[11195]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 02:05:40 volumio volumio[11020]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 24 02:05:40 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Oct 24 02:05:40 volumio systemd[1]: mpd.service: Succeeded. Oct 24 02:05:40 volumio systemd[1]: Stopped Music Player Daemon. Oct 24 02:05:40 volumio volumio[11020]: info: MPD Permissions set Oct 24 02:05:40 volumio volumio[11020]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:40 volumio volumio[11020]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:40 volumio volumio[11020]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:40 volumio volumio[11020]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:40 volumio volumio[11020]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:40 volumio volumio[11020]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:40 volumio volumio[11020]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:40 volumio volumio[11020]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:40 volumio volumio[11020]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 24 02:05:40 volumio volumio[11020]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:40 volumio volumio[11020]: info: Starting Shairport Sync Oct 24 02:05:40 volumio systemd[1]: Starting Music Player Daemon... Oct 24 02:05:40 volumio sudo[11206]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 24 02:05:40 volumio sudo[11206]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 02:05:40 volumio sudo[11200]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 24 02:05:40 volumio sudo[11200]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 02:05:40 volumio sudo[11200]: pam_unix(sudo:session): session closed for user root Oct 24 02:05:41 volumio volumio[11020]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Oct 24 02:05:41 volumio volumio[11020]: 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 24 02:05:41 volumio volumio[11020]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Oct 24 02:05:41 volumio volumio[11020]: info: camilladsp spawned new process with pid undefined, instance 1, run: true Oct 24 02:05:41 volumio volumio[11020]: info: camilladsp service started and running in background, instance 1 Oct 24 02:05:41 volumio volumio[11020]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:41 volumio volumio[11020]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Oct 24 02:05:41 volumio volumio[11020]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Oct 24 02:05:41 volumio volumio[11020]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Oct 24 02:05:41 volumio volumio[11020]: info: FusionDsp loaded Oct 24 02:05:41 volumio volumio[11020]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 24 02:05:41 volumio sudo[11222]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Oct 24 02:05:41 volumio sudo[11222]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 02:05:41 volumio volumio[11020]: info: FusionDsp - Reporting Fusion DSP Enabled Oct 24 02:05:41 volumio volumio[11020]: info: Adding Signal Path Element [object Object] Oct 24 02:05:41 volumio volumio[11020]: info: Adding fusiondspeq DSP Signal Path Element Oct 24 02:05:41 volumio volumio[11020]: info: FusionDsp - ---- installed callbackRead Oct 24 02:05:41 volumio volumio[11020]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 24 02:05:41 volumio sudo[11222]: pam_unix(sudo:session): session closed for user root Oct 24 02:05:41 volumio volumio[11020]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Oct 24 02:05:41 volumio volumio[11020]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Oct 24 02:05:41 volumio volumio[11020]: at onErrorNT (internal/child_process.js:465:16) Oct 24 02:05:41 volumio volumio[11020]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Oct 24 02:05:41 volumio volumio[11020]: errno: -2, Oct 24 02:05:41 volumio volumio[11020]: code: 'ENOENT', Oct 24 02:05:41 volumio volumio[11020]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Oct 24 02:05:41 volumio volumio[11020]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Oct 24 02:05:41 volumio volumio[11020]: spawnargs: [ Oct 24 02:05:41 volumio volumio[11020]: '-p', Oct 24 02:05:41 volumio volumio[11020]: 9876, Oct 24 02:05:41 volumio volumio[11020]: '-o', Oct 24 02:05:41 volumio volumio[11020]: '/tmp/camilladsp.log', Oct 24 02:05:41 volumio volumio[11020]: '-l', Oct 24 02:05:41 volumio volumio[11020]: 'warn', Oct 24 02:05:41 volumio volumio[11020]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Oct 24 02:05:41 volumio volumio[11020]: ] Oct 24 02:05:41 volumio volumio[11020]: } Oct 24 02:05:41 volumio volumio[11020]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 24 02:05:42 volumio mpd[11211]: Oct 24 02:05 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 24 02:05:42 volumio systemd[1]: Started Music Player Daemon. Oct 24 02:05:42 volumio sudo[11195]: pam_unix(sudo:session): session closed for user root Oct 24 02:05:42 volumio sudo[11144]: pam_unix(sudo:session): session closed for user root Oct 24 02:05:42 volumio sudo[11135]: pam_unix(sudo:session): session closed for user root Oct 24 02:05:42 volumio sudo[11235]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-24 02:04 Oct 24 02:05:42 volumio sudo[11235]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 02:05:42 volumio sudo[11235]: pam_unix(sudo:session): session closed for user root Oct 24 02:05:42 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Oct 24 02:05:42 volumio sudo[11185]: pam_unix(sudo:session): session closed for user root Oct 24 02:05:42 volumio sudo[11206]: pam_unix(sudo:session): session closed for user root Oct 24 02:05:42 volumio sudo[11183]: pam_unix(sudo:session): session closed for user root Oct 24 02:05:42 volumio sudo[11189]: pam_unix(sudo:session): session closed for user root Oct 24 02:05:42 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Oct 24 02:05:42 volumio systemd[1]: Started dynamicswap service. Oct 24 02:05:42 volumio systemd[1]: dynamicswap.service: Succeeded. Oct 24 02:05:42 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Oct 24 02:05:42 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 23. Oct 24 02:05:42 volumio systemd[1]: Started dynamicswap service. Oct 24 02:05:42 volumio systemd[1]: Stopped Volumio Backend Module. Oct 24 02:05:42 volumio systemd[1]: Started Volumio Backend Module. Oct 24 02:05:42 volumio systemd[1]: dynamicswap.service: Succeeded. Oct 24 02:05:44 volumio volumio[11246]: info: ------------------------------------------- Oct 24 02:05:44 volumio volumio[11246]: info: ----- Volumio3 ---- Oct 24 02:05:44 volumio volumio[11246]: info: ------------------------------------------- Oct 24 02:05:44 volumio volumio[11246]: info: ----- System startup ---- Oct 24 02:05:44 volumio volumio[11246]: info: ------------------------------------------- Oct 24 02:05:45 volumio volumio[11246]: info: MYVOLUMIO Environment detected Oct 24 02:05:45 volumio volumio[11246]: info: Plugin folders cleanup Oct 24 02:05:45 volumio volumio[11246]: info: Scanning into folder /volumio/app/plugins/ Oct 24 02:05:45 volumio volumio[11246]: info: Scanning category audio_interface Oct 24 02:05:45 volumio volumio[11246]: info: Scanning category miscellanea Oct 24 02:05:45 volumio volumio[11246]: info: Scanning category music_service Oct 24 02:05:45 volumio volumio[11246]: info: Scanning category plugins.json Oct 24 02:05:45 volumio volumio[11246]: info: Scanning category system_controller Oct 24 02:05:45 volumio volumio[11246]: info: Scanning category user_interface Oct 24 02:05:45 volumio volumio[11246]: info: Scanning into folder /data/plugins/ Oct 24 02:05:45 volumio volumio[11246]: info: Scanning category audio_interface Oct 24 02:05:45 volumio volumio[11246]: info: Scanning category music_service Oct 24 02:05:45 volumio volumio[11246]: info: Scanning category system_controller Oct 24 02:05:45 volumio volumio[11246]: info: Plugin folders cleanup completed Oct 24 02:05:45 volumio volumio[11246]: info: ------------------------------------------- Oct 24 02:05:45 volumio volumio[11246]: info: ----- Core plugins startup ---- Oct 24 02:05:45 volumio volumio[11246]: info: ------------------------------------------- Oct 24 02:05:45 volumio volumio[11246]: info: Loading plugins from folder /volumio/app/plugins/ Oct 24 02:05:45 volumio volumio[11246]: info: Adding plugin upnp to MyMusic Plugins Oct 24 02:05:45 volumio volumio[11246]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 24 02:05:45 volumio volumio[11246]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 24 02:05:45 volumio volumio[11246]: info: Loading plugins from folder /data/plugins/ Oct 24 02:05:45 volumio volumio[11246]: info: Loading plugin "system"... Oct 24 02:05:45 volumio volumio[11246]: info: Loading plugin "appearance"... Oct 24 02:05:46 volumio volumio[11246]: info: Loading plugin "network"... Oct 24 02:05:46 volumio volumio[11246]: info: Refreshing Cached IP Addresses Oct 24 02:05:46 volumio sudo[11273]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 24 02:05:46 volumio sudo[11273]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 02:05:46 volumio sudo[11273]: pam_unix(sudo:session): session closed for user root Oct 24 02:05:46 volumio sudo[11275]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 24 02:05:46 volumio volumio[11246]: info: Loading plugin "services"... Oct 24 02:05:46 volumio sudo[11275]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 02:05:46 volumio volumio[11246]: info: Loading plugin "alsa_controller"... Oct 24 02:05:46 volumio sudo[11275]: pam_unix(sudo:session): session closed for user root Oct 24 02:05:46 volumio sudo[11278]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 24 02:05:46 volumio sudo[11278]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 02:05:46 volumio volumio[11246]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 24 02:05:46 volumio volumio[11246]: info: Loading plugin "wizard"... Oct 24 02:05:46 volumio volumio[11246]: info: Loading plugin "networkfs"... Oct 24 02:05:46 volumio volumio[11246]: info: Starting Udev Watcher for removable devices Oct 24 02:05:46 volumio volumio[11246]: info: Ignoring mount for partition: boot Oct 24 02:05:46 volumio volumio[11246]: info: Ignoring mount for partition: volumio Oct 24 02:05:46 volumio volumio[11246]: info: Ignoring mount for partition: volumio_data Oct 24 02:05:46 volumio volumio[11246]: info: Mounting Device 1303-1E3E Oct 24 02:05:46 volumio sudo[11289]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/1303-1E3E -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Oct 24 02:05:46 volumio sudo[11289]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 02:05:46 volumio sudo[11289]: pam_unix(sudo:session): session closed for user root Oct 24 02:05:46 volumio volumio[11246]: mount: /media/1303-1E3E: /dev/sda1 already mounted on /media/1303-1E3E. Oct 24 02:05:46 volumio volumio[11246]: error: Failed to mount 1303-1E3E: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sda1" "/mnt/USB/1303-1E3E" -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Oct 24 02:05:46 volumio volumio[11246]: mount: /media/1303-1E3E: /dev/sda1 already mounted on /media/1303-1E3E. Oct 24 02:05:46 volumio volumio[11246]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 24 02:05:46 volumio volumio[11246]: info: Loading plugin "volumio_command_line_client"... Oct 24 02:05:46 volumio volumio[11246]: info: Loading plugin "upnp"... Oct 24 02:05:46 volumio volumio[11246]: info: [1729724746953] Starting Upmpd Daemon Oct 24 02:05:46 volumio volumio[11246]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 24 02:05:46 volumio volumio[11246]: info: Loading plugin "my_music"... Oct 24 02:05:46 volumio volumio[11246]: info: Loading plugin "mpd"... Oct 24 02:05:47 volumio volumio[11246]: info: Loading plugin "upnp_browser"... Oct 24 02:05:47 volumio sudo[11278]: pam_unix(sudo:session): session closed for user root Oct 24 02:05:47 volumio volumio-remote-updater[658]: [2024-10-24 02:05:47] [connect] Successful connection Oct 24 02:05:47 volumio volumio[11246]: info: Loading plugin "alarm-clock"... Oct 24 02:05:47 volumio volumio[11246]: info: Loading plugin "airplay_emulation"... Oct 24 02:05:47 volumio volumio[11246]: info: Starting Shairport Sync Oct 24 02:05:47 volumio volumio[11246]: info: Loading plugin "last_100"... Oct 24 02:05:47 volumio volumio[11246]: info: Loading plugin "webradio"... Oct 24 02:05:47 volumio volumio[11246]: info: Loading plugin "i2s_dacs"... Oct 24 02:05:47 volumio volumio[11246]: info: I2S DAC not set, start Auto-detection Oct 24 02:05:47 volumio volumio[11246]: info: Loading plugin "volumiodiscovery"... Oct 24 02:05:48 volumio volumio[11246]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 24 02:05:48 volumio volumio[11246]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 24 02:05:48 volumio volumio[11246]: *** WARNING *** For more information see Oct 24 02:05:48 volumio node[11246]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 24 02:05:48 volumio volumio[11246]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 24 02:05:48 volumio volumio[11246]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 24 02:05:48 volumio volumio[11246]: *** WARNING *** For more information see Oct 24 02:05:48 volumio node[11246]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 24 02:05:48 volumio node[11246]: *** WARNING *** For more information see Oct 24 02:05:48 volumio node[11246]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 24 02:05:48 volumio node[11246]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 24 02:05:48 volumio node[11246]: *** WARNING *** For more information see Oct 24 02:05:48 volumio volumio[11246]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 24 02:05:48 volumio volumio[11246]: info: Discovery: Started advertising with name: Volumio Oct 24 02:05:48 volumio volumio[11246]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 24 02:05:48 volumio volumio[11246]: info: Loading plugin "calmradio"... Oct 24 02:05:49 volumio volumio[11246]: info: Loading plugin "autostart"... Oct 24 02:05:49 volumio volumio[11246]: info: Applying required configuration parameters for plugin autostart Oct 24 02:05:49 volumio volumio[11246]: info: AutoStart - onVolumioStart - read config.json Oct 24 02:05:49 volumio volumio[11246]: info: Loading plugin "outputs"... Oct 24 02:05:49 volumio volumio[11246]: info: Loading plugin "albumart"... Oct 24 02:05:49 volumio volumio[11246]: info: Plugin example_plugin is not enabled Oct 24 02:05:49 volumio volumio[11246]: info: Loading plugin "inputs"... Oct 24 02:05:49 volumio volumio[11246]: info: Loading plugin "updater_comm"... Oct 24 02:05:49 volumio volumio[11246]: info: Plugin mpdemulation is not enabled Oct 24 02:05:49 volumio volumio[11246]: info: Loading plugin "rest_api"... Oct 24 02:05:49 volumio volumio[11246]: info: Loading plugin "websocket"... Oct 24 02:05:49 volumio volumio[11246]: info: Starting Socket.io Server version 2.3.0 Oct 24 02:05:49 volumio volumio[11246]: info: Loading plugin "fusiondsp"... Oct 24 02:05:49 volumio volumio[11246]: Forking 3 albumart workers Oct 24 02:05:49 volumio volumio[11246]: info: Applying required configuration parameters for plugin fusiondsp Oct 24 02:05:49 volumio volumio[11246]: info: Loading plugin "80s80s"... Oct 24 02:05:50 volumio volumio[11246]: info: Applying required configuration parameters for plugin 80s80s Oct 24 02:05:50 volumio volumio[11246]: info: [1729724750213] [80s80s] API delay: 30 Oct 24 02:05:50 volumio volumio[11246]: info: Loading plugin "minidlna"... Oct 24 02:05:50 volumio volumio[11246]: info: Applying required configuration parameters for plugin minidlna Oct 24 02:05:50 volumio volumio[11246]: info: Plugin personal_radio is not enabled Oct 24 02:05:50 volumio volumio[11246]: info: Loading plugin "radio_paradise"... Oct 24 02:05:50 volumio volumio[11246]: Starting albumart workers Oct 24 02:05:50 volumio volumio[11246]: Starting albumart workers Oct 24 02:05:50 volumio volumio[11246]: Starting albumart workers Oct 24 02:05:50 volumio volumio[11246]: info: Applying required configuration parameters for plugin radio_paradise Oct 24 02:05:50 volumio volumio[11246]: info: [1729724750847] [RadioParadise] API delay: 5 Oct 24 02:05:50 volumio volumio[11246]: info: Loading i18n strings for locale ru Oct 24 02:05:50 volumio volumio[11246]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Oct 24 02:05:50 volumio volumio[11246]: Updating browse sources language Oct 24 02:05:50 volumio volumio[11246]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 24 02:05:51 volumio volumio[11246]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 24 02:05:51 volumio volumio[11246]: info: CoreCommandRouter::initPlayerControls Oct 24 02:05:51 volumio volumio[11246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:51 volumio volumio[11246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:51 volumio volumio[11246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:51 volumio volumio[11246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:51 volumio volumio[11246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:51 volumio volumio[11246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:51 volumio volumio[11246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:51 volumio volumio[11246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:51 volumio volumio[11246]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 24 02:05:51 volumio volumio[11246]: Express server listening on port 3000 Oct 24 02:05:51 volumio volumio[11246]: [Metrics] WebUI: 7s 69.97ms Oct 24 02:05:51 volumio volumio[11246]: info: CoreStateMachine::resetVolumioState Oct 24 02:05:51 volumio volumio[11246]: info: CoreStateMachine::getcurrentVolume Oct 24 02:05:51 volumio volumio[11246]: info: CoreCommandRouter::volumioRetrievevolume Oct 24 02:05:51 volumio volumio[11246]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 24 02:05:51 volumio volumio[11246]: verbose: New Socket.io Connection to 192.168.1.139 from 192.168.1.72 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Oct 24 02:05:51 volumio volumio[11246]: verbose: New Socket.io Connection to 192.168.1.139 from 192.168.1.72 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Oct 24 02:05:51 volumio volumio-remote-updater[658]: [2024-10-24 02:05:51] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1729724747 101 Oct 24 02:05:51 volumio volumio[11246]: 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 24 02:05:51 volumio volumio[11246]: info: CoreCommandRouter::volumioGetState Oct 24 02:05:51 volumio volumio[11246]: info: CorePlayQueue::getTrack 0 Oct 24 02:05:51 volumio volumio[11246]: info: Reloading queue from file Oct 24 02:05:51 volumio volumio[11246]: info: VolumeController:: Volume=35 Mute =false Oct 24 02:05:51 volumio volumio[11246]: info: CoreStateMachine::pushState Oct 24 02:05:51 volumio volumio[11246]: info: CorePlayQueue::getTrack 0 Oct 24 02:05:51 volumio volumio[11246]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 24 02:05:51 volumio volumio[11246]: info: CoreCommandRouter::volumioPushState Oct 24 02:05:51 volumio volumio[11246]: info: CoreStateMachine::updateTrackBlock Oct 24 02:05:51 volumio volumio[11246]: info: CorePlayQueue::getTrackBlock Oct 24 02:05:51 volumio volumio[11246]: info: CoreCommandRouter::volumioRetrievevolume Oct 24 02:05:51 volumio volumio[11246]: info: Setting Device type: Raspberry PI Oct 24 02:05:51 volumio volumio[11246]: info: CoreStateMachine::setRepeat false single undefined Oct 24 02:05:51 volumio volumio[11246]: info: CoreStateMachine::pushState Oct 24 02:05:51 volumio volumio[11246]: info: CorePlayQueue::getTrack 0 Oct 24 02:05:51 volumio volumio[11246]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 24 02:05:51 volumio volumio[11246]: info: CoreCommandRouter::volumioPushState Oct 24 02:05:51 volumio volumio[11246]: info: CoreStateMachine::setRandom null Oct 24 02:05:51 volumio volumio[11246]: info: CoreStateMachine::pushState Oct 24 02:05:51 volumio volumio[11246]: info: CorePlayQueue::getTrack 0 Oct 24 02:05:51 volumio volumio[11246]: info: CoreCommandRouter::volumioPushState Oct 24 02:05:51 volumio volumio[11246]: info: Listing playlists Oct 24 02:05:51 volumio volumio[11246]: info: Listing playlists Oct 24 02:05:51 volumio volumio[11246]: info: Completed loading Core Plugins Oct 24 02:05:51 volumio volumio[11246]: info: Preparing to generate the ALSA configuration file Oct 24 02:05:51 volumio volumio[11246]: info: Discovery: adding 266418d9-d71f-4348-91b8-c2087d0064b7 Oct 24 02:05:51 volumio volumio[11246]: info: Discovery: Found device Volumio Oct 24 02:05:51 volumio volumio[11246]: info: CoreCommandRouter::volumioGetState Oct 24 02:05:51 volumio volumio[11246]: info: CorePlayQueue::getTrack 0 Oct 24 02:05:51 volumio volumio[11246]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Oct 24 02:05:51 volumio volumio[11246]: info: Reading ALSA contributions from plugins. Oct 24 02:05:51 volumio volumio[11246]: info: VolumeController:: Volume=35 Mute =false Oct 24 02:05:51 volumio volumio[11246]: info: CoreStateMachine::pushState Oct 24 02:05:51 volumio volumio[11246]: info: CorePlayQueue::getTrack 0 Oct 24 02:05:51 volumio volumio[11246]: info: CoreCommandRouter::volumioPushState Oct 24 02:05:51 volumio volumio[11246]: info: Asound.conf file unchanged, so no further update is needed Oct 24 02:05:51 volumio volumio[11246]: info: Output device has changed, restarting MPD Oct 24 02:05:51 volumio sudo[11358]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 24 02:05:51 volumio sudo[11358]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 02:05:51 volumio volumio[11246]: info: Output device has changed, restarting Shairport Sync Oct 24 02:05:51 volumio volumio[11246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:51 volumio volumio[11246]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 24 02:05:51 volumio sudo[11358]: pam_unix(sudo:session): session closed for user root Oct 24 02:05:51 volumio sudo[11360]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 24 02:05:51 volumio sudo[11360]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 02:05:51 volumio systemd[1]: Stopping Music Player Daemon... Oct 24 02:05:51 volumio volumio[11246]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 24 02:05:51 volumio systemd[1]: mpd.service: Succeeded. Oct 24 02:05:51 volumio volumio[11246]: info: ___________ START PLUGINS ___________ Oct 24 02:05:51 volumio systemd[1]: Stopped Music Player Daemon. Oct 24 02:05:52 volumio volumio[11246]: info: ControllerMpd::onStart: Initializing MPD Oct 24 02:05:52 volumio volumio[11246]: info: Creating MPD Configuration file Oct 24 02:05:52 volumio sudo[11368]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 24 02:05:52 volumio sudo[11368]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 02:05:52 volumio volumio[11246]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 24 02:05:52 volumio volumio[11246]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 24 02:05:52 volumio volumio[11246]: info: [1729724752057] CoreMusicLibrary::Adding element Медиа-серверы Oct 24 02:05:52 volumio volumio[11246]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 24 02:05:52 volumio sudo[11368]: pam_unix(sudo:session): session closed for user root Oct 24 02:05:52 volumio systemd[1]: Starting Music Player Daemon... Oct 24 02:05:52 volumio sudo[11370]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 24 02:05:52 volumio sudo[11370]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 02:05:52 volumio volumio[11246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:52 volumio volumio[11246]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 24 02:05:52 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Oct 24 02:05:52 volumio systemd[1]: mpd.service: Succeeded. Oct 24 02:05:52 volumio systemd[1]: Stopped Music Player Daemon. Oct 24 02:05:52 volumio systemd[1]: Starting Music Player Daemon... Oct 24 02:05:52 volumio volumio[11246]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 24 02:05:52 volumio volumio[11246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:52 volumio volumio[11246]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 24 02:05:52 volumio volumio[11246]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 24 02:05:52 volumio volumio[11246]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 24 02:05:52 volumio volumio[11246]: info: [1729724752210] CoreMusicLibrary::Adding element Last_100 Oct 24 02:05:52 volumio volumio[11246]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 24 02:05:52 volumio volumio[11246]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 24 02:05:52 volumio volumio[11246]: info: [1729724752214] CoreMusicLibrary::Adding element Webradio Oct 24 02:05:52 volumio volumio[11246]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 24 02:05:52 volumio volumio[11246]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 24 02:05:52 volumio volumio[11246]: info: Initializing BBC Radios Oct 24 02:05:52 volumio sudo[11374]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 24 02:05:52 volumio sudo[11374]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 02:05:52 volumio sudo[11374]: pam_unix(sudo:session): session closed for user root Oct 24 02:05:52 volumio volumio[11246]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 24 02:05:52 volumio volumio[11246]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 24 02:05:52 volumio volumio[11246]: info: Adding Calm Radio to Browse Sources Oct 24 02:05:52 volumio volumio[11246]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 24 02:05:52 volumio volumio[11246]: info: [1729724752304] CoreMusicLibrary::Adding element Calm Radio Oct 24 02:05:52 volumio volumio[11246]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 24 02:05:52 volumio volumio[11246]: Cannot find translation for source Calm Radio Oct 24 02:05:52 volumio volumio[11246]: info: AutoStart - onStart Oct 24 02:05:52 volumio volumio[11246]: info: Loading i18n strings for locale ru Oct 24 02:05:52 volumio volumio[11246]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Oct 24 02:05:52 volumio volumio[11246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Oct 24 02:05:52 volumio volumio[11246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:52 volumio volumio[11246]: info: FusionDsp - mixtype--------------------- Hardware Oct 24 02:05:52 volumio volumio[11246]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 24 02:05:52 volumio volumio[11246]: info: [1729724752353] CoreMusicLibrary::Adding element 80s80s Radio Oct 24 02:05:52 volumio volumio[11246]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 24 02:05:52 volumio volumio[11246]: Cannot find translation for source Calm Radio Oct 24 02:05:52 volumio volumio[11246]: Cannot find translation for source 80s80s Radio Oct 24 02:05:52 volumio volumio[11246]: info: Loading i18n strings for locale ru Oct 24 02:05:52 volumio volumio[11246]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Oct 24 02:05:52 volumio volumio[11246]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 24 02:05:52 volumio volumio[11246]: info: [1729724752403] CoreMusicLibrary::Adding element Radio Paradise Oct 24 02:05:52 volumio volumio[11246]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 24 02:05:52 volumio volumio[11246]: Cannot find translation for source Calm Radio Oct 24 02:05:52 volumio volumio[11246]: Cannot find translation for source 80s80s Radio Oct 24 02:05:52 volumio volumio[11246]: Cannot find translation for source Radio Paradise Oct 24 02:05:52 volumio volumio[11246]: info: Volumio Calling Home Oct 24 02:05:52 volumio volumio[11246]: info: Preparing to generate the ALSA configuration file Oct 24 02:05:52 volumio volumio[11246]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Oct 24 02:05:52 volumio volumio[11246]: info: Reading ALSA contributions from plugins. Oct 24 02:05:52 volumio volumio[11246]: info: minidlna: Found miniDLNA version 1.2.1 Oct 24 02:05:52 volumio volumio[11246]: info: minidlna: Starting minidlna.service Oct 24 02:05:52 volumio volumio[11246]: info: MPD Permissions set Oct 24 02:05:52 volumio volumio[11246]: info: MPD Permissions set Oct 24 02:05:52 volumio volumio[11246]: info: Volumio called home Oct 24 02:05:52 volumio sudo[11398]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start minidlna.service Oct 24 02:05:52 volumio sudo[11398]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 02:05:52 volumio sudo[11398]: pam_unix(sudo:session): session closed for user root Oct 24 02:05:52 volumio volumio[11246]: 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 24 02:05:52 volumio volumio[11246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:52 volumio volumio[11246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:52 volumio volumio[11246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:52 volumio volumio[11246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:52 volumio volumio[11246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:52 volumio volumio[11246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:52 volumio volumio[11246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:52 volumio volumio[11246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:52 volumio volumio[11246]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 24 02:05:52 volumio volumio[11246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:52 volumio volumio[11246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:52 volumio volumio[11246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:52 volumio volumio[11246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:52 volumio volumio[11246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:52 volumio volumio[11246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:52 volumio volumio[11246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:52 volumio volumio[11246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:52 volumio volumio[11246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:52 volumio volumio[11246]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 24 02:05:52 volumio volumio[11246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:52 volumio volumio[11246]: info: minidlna: systemctl start minidlna.service succeeded. Oct 24 02:05:53 volumio volumio[11246]: info: Starting Shairport Sync Oct 24 02:05:53 volumio volumio[11246]: info: Starting Shairport Sync Oct 24 02:05:53 volumio sudo[11409]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 24 02:05:53 volumio volumio[11246]: info: Starting Shairport Sync Oct 24 02:05:53 volumio sudo[11409]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 02:05:53 volumio sudo[11411]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 24 02:05:53 volumio sudo[11411]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 02:05:53 volumio volumio[11246]: info: CoreCommandRouter::volumioGetState Oct 24 02:05:53 volumio volumio[11246]: info: CorePlayQueue::getTrack 0 Oct 24 02:05:53 volumio sudo[11415]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 24 02:05:53 volumio sudo[11415]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 02:05:53 volumio volumio[11246]: info: Asound.conf file unchanged, so no further update is needed Oct 24 02:05:53 volumio volumio[11246]: info: Output device has changed, restarting MPD Oct 24 02:05:53 volumio volumio[11246]: info: Output device has changed, restarting Shairport Sync Oct 24 02:05:53 volumio sudo[11418]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 24 02:05:53 volumio sudo[11418]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 02:05:53 volumio volumio[11246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:53 volumio volumio[11246]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 24 02:05:53 volumio sudo[11418]: pam_unix(sudo:session): session closed for user root Oct 24 02:05:53 volumio sudo[11420]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 24 02:05:53 volumio sudo[11420]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 02:05:53 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Oct 24 02:05:53 volumio systemd[1]: mpd.service: Succeeded. Oct 24 02:05:53 volumio systemd[1]: Stopped Music Player Daemon. Oct 24 02:05:53 volumio volumio[11246]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 24 02:05:53 volumio volumio[11246]: info: MPD Permissions set Oct 24 02:05:53 volumio volumio[11246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:53 volumio volumio[11246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:53 volumio volumio[11246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:53 volumio volumio[11246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:53 volumio volumio[11246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:53 volumio volumio[11246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:53 volumio volumio[11246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:53 volumio volumio[11246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:53 volumio systemd[1]: Starting Music Player Daemon... Oct 24 02:05:53 volumio volumio[11246]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 24 02:05:53 volumio volumio[11246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:53 volumio volumio[11246]: info: Starting Shairport Sync Oct 24 02:05:53 volumio sudo[11433]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 24 02:05:53 volumio sudo[11433]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 02:05:53 volumio sudo[11426]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 24 02:05:53 volumio sudo[11426]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 02:05:53 volumio sudo[11426]: pam_unix(sudo:session): session closed for user root Oct 24 02:05:53 volumio volumio[11246]: verbose: New Socket.io Connection to 192.168.1.139 from 192.168.1.72 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Oct 24 02:05:53 volumio volumio[11246]: info: CoreCommandRouter::Close All Modals sent Oct 24 02:05:53 volumio volumio[11246]: info: CoreCommandRouter::volumioGetState Oct 24 02:05:53 volumio volumio[11246]: info: CorePlayQueue::getTrack 0 Oct 24 02:05:53 volumio volumio[11246]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 24 02:05:53 volumio volumio[11246]: info: Listing playlists Oct 24 02:05:54 volumio volumio[11246]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Oct 24 02:05:54 volumio volumio[11246]: 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 24 02:05:54 volumio volumio[11246]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Oct 24 02:05:54 volumio volumio[11246]: info: camilladsp spawned new process with pid undefined, instance 1, run: true Oct 24 02:05:54 volumio volumio[11246]: info: camilladsp service started and running in background, instance 1 Oct 24 02:05:54 volumio volumio[11246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:05:54 volumio volumio[11246]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Oct 24 02:05:54 volumio volumio[11246]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Oct 24 02:05:54 volumio volumio[11246]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Oct 24 02:05:54 volumio volumio[11246]: info: FusionDsp loaded Oct 24 02:05:54 volumio volumio[11246]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 24 02:05:54 volumio sudo[11447]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Oct 24 02:05:54 volumio sudo[11447]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 02:05:54 volumio volumio[11246]: info: FusionDsp - Reporting Fusion DSP Enabled Oct 24 02:05:54 volumio volumio[11246]: info: Adding Signal Path Element [object Object] Oct 24 02:05:54 volumio volumio[11246]: info: Adding fusiondspeq DSP Signal Path Element Oct 24 02:05:54 volumio volumio[11246]: info: FusionDsp - ---- installed callbackRead Oct 24 02:05:54 volumio volumio[11246]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 24 02:05:54 volumio sudo[11447]: pam_unix(sudo:session): session closed for user root Oct 24 02:05:54 volumio volumio[11246]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Oct 24 02:05:54 volumio volumio[11246]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Oct 24 02:05:54 volumio volumio[11246]: at onErrorNT (internal/child_process.js:465:16) Oct 24 02:05:54 volumio volumio[11246]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Oct 24 02:05:54 volumio volumio[11246]: errno: -2, Oct 24 02:05:54 volumio volumio[11246]: code: 'ENOENT', Oct 24 02:05:54 volumio volumio[11246]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Oct 24 02:05:54 volumio volumio[11246]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Oct 24 02:05:54 volumio volumio[11246]: spawnargs: [ Oct 24 02:05:54 volumio volumio[11246]: '-p', Oct 24 02:05:54 volumio volumio[11246]: 9876, Oct 24 02:05:54 volumio volumio[11246]: '-o', Oct 24 02:05:54 volumio volumio[11246]: '/tmp/camilladsp.log', Oct 24 02:05:54 volumio volumio[11246]: '-l', Oct 24 02:05:54 volumio volumio[11246]: 'warn', Oct 24 02:05:54 volumio volumio[11246]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Oct 24 02:05:54 volumio volumio[11246]: ] Oct 24 02:05:54 volumio volumio[11246]: } Oct 24 02:05:54 volumio volumio[11246]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 24 02:05:55 volumio mpd[11436]: Oct 24 02:05 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 24 02:05:55 volumio systemd[1]: Started Music Player Daemon. Oct 24 02:05:55 volumio sudo[11370]: pam_unix(sudo:session): session closed for user root Oct 24 02:05:55 volumio sudo[11360]: pam_unix(sudo:session): session closed for user root Oct 24 02:05:55 volumio sudo[11420]: pam_unix(sudo:session): session closed for user root Oct 24 02:05:55 volumio sudo[11460]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-24 02:04 Oct 24 02:05:55 volumio sudo[11460]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 02:05:55 volumio sudo[11460]: pam_unix(sudo:session): session closed for user root Oct 24 02:05:55 volumio volumio-remote-updater[658]: [2024-10-24 02:05:55] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Oct 24 02:05:55 volumio volumio-remote-updater[658]: [2024-10-24 02:05:55] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Oct 24 02:05:55 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Oct 24 02:05:55 volumio sudo[11433]: pam_unix(sudo:session): session closed for user root Oct 24 02:05:55 volumio sudo[11409]: pam_unix(sudo:session): session closed for user root Oct 24 02:05:55 volumio sudo[11411]: pam_unix(sudo:session): session closed for user root Oct 24 02:05:55 volumio sudo[11415]: pam_unix(sudo:session): session closed for user root Oct 24 02:05:55 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Oct 24 02:05:55 volumio systemd[1]: Started dynamicswap service. Oct 24 02:05:55 volumio systemd[1]: dynamicswap.service: Succeeded. Oct 24 02:05:55 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Oct 24 02:05:55 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 24. Oct 24 02:05:55 volumio systemd[1]: Started dynamicswap service. Oct 24 02:05:55 volumio systemd[1]: Stopped Volumio Backend Module. Oct 24 02:05:55 volumio systemd[1]: Started Volumio Backend Module. Oct 24 02:05:55 volumio systemd[1]: dynamicswap.service: Succeeded. Oct 24 02:05:57 volumio volumio[11471]: info: ------------------------------------------- Oct 24 02:05:57 volumio volumio[11471]: info: ----- Volumio3 ---- Oct 24 02:05:57 volumio volumio[11471]: info: ------------------------------------------- Oct 24 02:05:57 volumio volumio[11471]: info: ----- System startup ---- Oct 24 02:05:57 volumio volumio[11471]: info: ------------------------------------------- Oct 24 02:05:58 volumio volumio[11471]: info: MYVOLUMIO Environment detected Oct 24 02:05:58 volumio volumio[11471]: info: Plugin folders cleanup Oct 24 02:05:58 volumio volumio[11471]: info: Scanning into folder /volumio/app/plugins/ Oct 24 02:05:58 volumio volumio[11471]: info: Scanning category audio_interface Oct 24 02:05:58 volumio volumio[11471]: info: Scanning category miscellanea Oct 24 02:05:58 volumio volumio[11471]: info: Scanning category music_service Oct 24 02:05:58 volumio volumio[11471]: info: Scanning category plugins.json Oct 24 02:05:58 volumio volumio[11471]: info: Scanning category system_controller Oct 24 02:05:58 volumio volumio[11471]: info: Scanning category user_interface Oct 24 02:05:58 volumio volumio[11471]: info: Scanning into folder /data/plugins/ Oct 24 02:05:58 volumio volumio[11471]: info: Scanning category audio_interface Oct 24 02:05:58 volumio volumio[11471]: info: Scanning category music_service Oct 24 02:05:58 volumio volumio[11471]: info: Scanning category system_controller Oct 24 02:05:58 volumio volumio[11471]: info: Plugin folders cleanup completed Oct 24 02:05:58 volumio volumio[11471]: info: ------------------------------------------- Oct 24 02:05:58 volumio volumio[11471]: info: ----- Core plugins startup ---- Oct 24 02:05:58 volumio volumio[11471]: info: ------------------------------------------- Oct 24 02:05:58 volumio volumio[11471]: info: Loading plugins from folder /volumio/app/plugins/ Oct 24 02:05:58 volumio volumio[11471]: info: Adding plugin upnp to MyMusic Plugins Oct 24 02:05:58 volumio volumio[11471]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 24 02:05:58 volumio volumio[11471]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 24 02:05:58 volumio volumio[11471]: info: Loading plugins from folder /data/plugins/ Oct 24 02:05:58 volumio volumio[11471]: info: Loading plugin "system"... Oct 24 02:05:58 volumio volumio[11471]: info: Loading plugin "appearance"... Oct 24 02:05:59 volumio volumio[11471]: info: Loading plugin "network"... Oct 24 02:05:59 volumio volumio[11471]: info: Refreshing Cached IP Addresses Oct 24 02:05:59 volumio sudo[11497]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 24 02:05:59 volumio sudo[11497]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 02:05:59 volumio sudo[11497]: pam_unix(sudo:session): session closed for user root Oct 24 02:05:59 volumio volumio[11471]: info: Loading plugin "services"... Oct 24 02:05:59 volumio sudo[11499]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 24 02:05:59 volumio sudo[11499]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 02:05:59 volumio volumio[11471]: info: Loading plugin "alsa_controller"... Oct 24 02:05:59 volumio sudo[11499]: pam_unix(sudo:session): session closed for user root Oct 24 02:05:59 volumio sudo[11502]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 24 02:05:59 volumio sudo[11502]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 02:05:59 volumio volumio[11471]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 24 02:05:59 volumio volumio[11471]: info: Loading plugin "wizard"... Oct 24 02:05:59 volumio volumio[11471]: info: Loading plugin "networkfs"... Oct 24 02:05:59 volumio volumio[11471]: info: Starting Udev Watcher for removable devices Oct 24 02:05:59 volumio volumio[11471]: info: Ignoring mount for partition: boot Oct 24 02:05:59 volumio volumio[11471]: info: Ignoring mount for partition: volumio Oct 24 02:05:59 volumio volumio[11471]: info: Ignoring mount for partition: volumio_data Oct 24 02:05:59 volumio volumio[11471]: info: Mounting Device 1303-1E3E Oct 24 02:05:59 volumio sudo[11513]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/1303-1E3E -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Oct 24 02:05:59 volumio sudo[11513]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 02:05:59 volumio sudo[11513]: pam_unix(sudo:session): session closed for user root Oct 24 02:05:59 volumio volumio[11471]: mount: /media/1303-1E3E: /dev/sda1 already mounted on /media/1303-1E3E. Oct 24 02:05:59 volumio volumio[11471]: error: Failed to mount 1303-1E3E: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sda1" "/mnt/USB/1303-1E3E" -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Oct 24 02:05:59 volumio volumio[11471]: mount: /media/1303-1E3E: /dev/sda1 already mounted on /media/1303-1E3E. Oct 24 02:05:59 volumio volumio[11471]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 24 02:05:59 volumio volumio[11471]: info: Loading plugin "volumio_command_line_client"... Oct 24 02:06:00 volumio volumio[11471]: info: Loading plugin "upnp"... Oct 24 02:06:00 volumio volumio[11471]: info: [1729724760005] Starting Upmpd Daemon Oct 24 02:06:00 volumio volumio[11471]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 24 02:06:00 volumio volumio[11471]: info: Loading plugin "my_music"... Oct 24 02:06:00 volumio volumio[11471]: info: Loading plugin "mpd"... Oct 24 02:06:00 volumio volumio[11471]: info: Loading plugin "upnp_browser"... Oct 24 02:06:00 volumio volumio-remote-updater[658]: [2024-10-24 02:06:00] [connect] Successful connection Oct 24 02:06:00 volumio sudo[11502]: pam_unix(sudo:session): session closed for user root Oct 24 02:06:00 volumio volumio[11471]: info: Loading plugin "alarm-clock"... Oct 24 02:06:00 volumio volumio[11471]: info: Loading plugin "airplay_emulation"... Oct 24 02:06:00 volumio volumio[11471]: info: Starting Shairport Sync Oct 24 02:06:00 volumio volumio[11471]: info: Loading plugin "last_100"... Oct 24 02:06:00 volumio volumio[11471]: info: Loading plugin "webradio"... Oct 24 02:06:00 volumio volumio[11471]: info: Loading plugin "i2s_dacs"... Oct 24 02:06:00 volumio volumio[11471]: info: I2S DAC not set, start Auto-detection Oct 24 02:06:01 volumio volumio[11471]: info: Loading plugin "volumiodiscovery"... Oct 24 02:06:01 volumio volumio[11471]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 24 02:06:01 volumio volumio[11471]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 24 02:06:01 volumio volumio[11471]: *** WARNING *** For more information see Oct 24 02:06:01 volumio node[11471]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 24 02:06:01 volumio volumio[11471]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 24 02:06:01 volumio volumio[11471]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 24 02:06:01 volumio volumio[11471]: *** WARNING *** For more information see Oct 24 02:06:01 volumio node[11471]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 24 02:06:01 volumio node[11471]: *** WARNING *** For more information see Oct 24 02:06:01 volumio node[11471]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 24 02:06:01 volumio node[11471]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 24 02:06:01 volumio node[11471]: *** WARNING *** For more information see Oct 24 02:06:01 volumio volumio[11471]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 24 02:06:02 volumio volumio[11471]: info: Discovery: Started advertising with name: Volumio Oct 24 02:06:02 volumio volumio[11471]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 24 02:06:02 volumio volumio[11471]: info: Loading plugin "calmradio"... Oct 24 02:06:03 volumio volumio[11471]: info: Loading plugin "autostart"... Oct 24 02:06:03 volumio volumio[11471]: info: Applying required configuration parameters for plugin autostart Oct 24 02:06:03 volumio volumio[11471]: info: AutoStart - onVolumioStart - read config.json Oct 24 02:06:03 volumio volumio[11471]: info: Loading plugin "outputs"... Oct 24 02:06:03 volumio volumio[11471]: info: Loading plugin "albumart"... Oct 24 02:06:03 volumio volumio[11471]: info: Plugin example_plugin is not enabled Oct 24 02:06:03 volumio volumio[11471]: info: Loading plugin "inputs"... Oct 24 02:06:03 volumio volumio[11471]: info: Loading plugin "updater_comm"... Oct 24 02:06:03 volumio volumio[11471]: info: Plugin mpdemulation is not enabled Oct 24 02:06:03 volumio volumio[11471]: info: Loading plugin "rest_api"... Oct 24 02:06:03 volumio volumio[11471]: info: Loading plugin "websocket"... Oct 24 02:06:03 volumio volumio[11471]: info: Starting Socket.io Server version 2.3.0 Oct 24 02:06:03 volumio volumio[11471]: info: Loading plugin "fusiondsp"... Oct 24 02:06:03 volumio volumio[11471]: Forking 3 albumart workers Oct 24 02:06:04 volumio volumio[11471]: info: Applying required configuration parameters for plugin fusiondsp Oct 24 02:06:04 volumio volumio[11471]: info: Loading plugin "80s80s"... Oct 24 02:06:04 volumio volumio[11471]: info: Applying required configuration parameters for plugin 80s80s Oct 24 02:06:04 volumio volumio[11471]: info: [1729724764455] [80s80s] API delay: 30 Oct 24 02:06:04 volumio volumio[11471]: info: Loading plugin "minidlna"... Oct 24 02:06:04 volumio volumio[11471]: info: Applying required configuration parameters for plugin minidlna Oct 24 02:06:04 volumio volumio[11471]: info: Plugin personal_radio is not enabled Oct 24 02:06:04 volumio volumio[11471]: info: Loading plugin "radio_paradise"... Oct 24 02:06:04 volumio volumio[11471]: Starting albumart workers Oct 24 02:06:05 volumio volumio[11471]: Starting albumart workers Oct 24 02:06:05 volumio volumio[11471]: Starting albumart workers Oct 24 02:06:05 volumio volumio[11471]: info: Applying required configuration parameters for plugin radio_paradise Oct 24 02:06:05 volumio volumio[11471]: info: [1729724765136] [RadioParadise] API delay: 5 Oct 24 02:06:05 volumio volumio[11471]: info: Loading i18n strings for locale ru Oct 24 02:06:05 volumio volumio[11471]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Oct 24 02:06:05 volumio volumio[11471]: Updating browse sources language Oct 24 02:06:05 volumio volumio[11471]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 24 02:06:05 volumio volumio[11471]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 24 02:06:05 volumio volumio[11471]: info: CoreCommandRouter::initPlayerControls Oct 24 02:06:05 volumio volumio[11471]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:06:05 volumio volumio[11471]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:06:05 volumio volumio[11471]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:06:05 volumio volumio[11471]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:06:05 volumio volumio[11471]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:06:05 volumio volumio[11471]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:06:05 volumio volumio[11471]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:06:05 volumio volumio[11471]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:06:05 volumio volumio[11471]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 24 02:06:05 volumio volumio[11471]: Express server listening on port 3000 Oct 24 02:06:05 volumio volumio[11471]: [Metrics] WebUI: 8s 300.57ms Oct 24 02:06:05 volumio volumio[11471]: info: CoreStateMachine::resetVolumioState Oct 24 02:06:05 volumio volumio[11471]: info: CoreStateMachine::getcurrentVolume Oct 24 02:06:05 volumio volumio[11471]: info: CoreCommandRouter::volumioRetrievevolume Oct 24 02:06:05 volumio volumio[11471]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 24 02:06:05 volumio volumio[11471]: verbose: New Socket.io Connection to 192.168.1.139 from 192.168.1.72 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Oct 24 02:06:05 volumio volumio[11471]: verbose: New Socket.io Connection to 192.168.1.139 from 192.168.1.72 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Oct 24 02:06:05 volumio volumio[11471]: verbose: New Socket.io Connection to 192.168.1.139 from 192.168.1.72 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Oct 24 02:06:05 volumio volumio[11471]: info: CoreCommandRouter::volumioGetState Oct 24 02:06:05 volumio volumio[11471]: info: CorePlayQueue::getTrack 0 Oct 24 02:06:05 volumio volumio[11471]: info: Reloading queue from file Oct 24 02:06:05 volumio volumio[11471]: info: VolumeController:: Volume=35 Mute =false Oct 24 02:06:05 volumio volumio[11471]: info: CoreStateMachine::pushState Oct 24 02:06:05 volumio volumio[11471]: info: CorePlayQueue::getTrack 0 Oct 24 02:06:05 volumio volumio[11471]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 24 02:06:05 volumio volumio[11471]: info: CoreCommandRouter::volumioPushState Oct 24 02:06:05 volumio volumio[11471]: info: CoreStateMachine::updateTrackBlock Oct 24 02:06:05 volumio volumio[11471]: info: CorePlayQueue::getTrackBlock Oct 24 02:06:05 volumio volumio[11471]: info: CoreCommandRouter::volumioRetrievevolume Oct 24 02:06:06 volumio volumio[11471]: info: CoreStateMachine::setRepeat false single undefined Oct 24 02:06:06 volumio volumio[11471]: info: CoreStateMachine::pushState Oct 24 02:06:06 volumio volumio[11471]: info: CorePlayQueue::getTrack 0 Oct 24 02:06:06 volumio volumio[11471]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 24 02:06:06 volumio volumio[11471]: info: CoreCommandRouter::volumioPushState Oct 24 02:06:06 volumio volumio[11471]: info: CoreStateMachine::setRandom null Oct 24 02:06:06 volumio volumio[11471]: info: CoreStateMachine::pushState Oct 24 02:06:06 volumio volumio[11471]: info: CorePlayQueue::getTrack 0 Oct 24 02:06:06 volumio volumio[11471]: info: CoreCommandRouter::volumioPushState Oct 24 02:06:06 volumio volumio[11471]: info: Setting Device type: Raspberry PI Oct 24 02:06:06 volumio volumio[11471]: info: Completed loading Core Plugins Oct 24 02:06:06 volumio volumio[11471]: info: Preparing to generate the ALSA configuration file Oct 24 02:06:06 volumio volumio[11471]: info: Discovery: adding 266418d9-d71f-4348-91b8-c2087d0064b7 Oct 24 02:06:06 volumio volumio[11471]: info: Discovery: Found device Volumio Oct 24 02:06:06 volumio volumio[11471]: info: CoreCommandRouter::volumioGetState Oct 24 02:06:06 volumio volumio[11471]: info: CorePlayQueue::getTrack 0 Oct 24 02:06:06 volumio volumio[11471]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Oct 24 02:06:06 volumio volumio[11471]: info: Reading ALSA contributions from plugins. Oct 24 02:06:06 volumio volumio[11471]: info: VolumeController:: Volume=35 Mute =false Oct 24 02:06:06 volumio volumio[11471]: info: CoreStateMachine::pushState Oct 24 02:06:06 volumio volumio[11471]: info: CorePlayQueue::getTrack 0 Oct 24 02:06:06 volumio volumio[11471]: info: CoreCommandRouter::volumioPushState Oct 24 02:06:06 volumio volumio[11471]: info: Asound.conf file unchanged, so no further update is needed Oct 24 02:06:06 volumio volumio[11471]: info: Output device has changed, restarting MPD Oct 24 02:06:06 volumio sudo[11584]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 24 02:06:06 volumio sudo[11584]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 02:06:06 volumio volumio[11471]: info: Output device has changed, restarting Shairport Sync Oct 24 02:06:06 volumio sudo[11584]: pam_unix(sudo:session): session closed for user root Oct 24 02:06:06 volumio volumio[11471]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:06:06 volumio volumio[11471]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 24 02:06:06 volumio sudo[11586]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 24 02:06:06 volumio sudo[11586]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 02:06:06 volumio systemd[1]: Stopping Music Player Daemon... Oct 24 02:06:06 volumio volumio[11471]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 24 02:06:06 volumio systemd[1]: mpd.service: Succeeded. Oct 24 02:06:06 volumio systemd[1]: Stopped Music Player Daemon. Oct 24 02:06:06 volumio volumio[11471]: info: ___________ START PLUGINS ___________ Oct 24 02:06:06 volumio volumio[11471]: info: ControllerMpd::onStart: Initializing MPD Oct 24 02:06:06 volumio volumio[11471]: info: Creating MPD Configuration file Oct 24 02:06:06 volumio sudo[11594]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 24 02:06:06 volumio sudo[11594]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 02:06:06 volumio volumio[11471]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 24 02:06:06 volumio volumio[11471]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 24 02:06:06 volumio volumio[11471]: info: [1729724766327] CoreMusicLibrary::Adding element Медиа-серверы Oct 24 02:06:06 volumio volumio[11471]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 24 02:06:06 volumio sudo[11594]: pam_unix(sudo:session): session closed for user root Oct 24 02:06:06 volumio systemd[1]: Starting Music Player Daemon... Oct 24 02:06:06 volumio sudo[11596]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 24 02:06:06 volumio sudo[11596]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 02:06:06 volumio volumio[11471]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:06:06 volumio volumio[11471]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 24 02:06:06 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Oct 24 02:06:06 volumio systemd[1]: mpd.service: Succeeded. Oct 24 02:06:06 volumio systemd[1]: Stopped Music Player Daemon. Oct 24 02:06:06 volumio systemd[1]: Starting Music Player Daemon... Oct 24 02:06:06 volumio volumio[11471]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 24 02:06:06 volumio volumio[11471]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:06:06 volumio volumio[11471]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 24 02:06:06 volumio volumio[11471]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 24 02:06:06 volumio volumio[11471]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 24 02:06:06 volumio volumio[11471]: info: [1729724766484] CoreMusicLibrary::Adding element Last_100 Oct 24 02:06:06 volumio volumio[11471]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 24 02:06:06 volumio volumio[11471]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 24 02:06:06 volumio volumio[11471]: info: [1729724766489] CoreMusicLibrary::Adding element Webradio Oct 24 02:06:06 volumio volumio[11471]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 24 02:06:06 volumio volumio[11471]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 24 02:06:06 volumio volumio[11471]: info: Initializing BBC Radios Oct 24 02:06:06 volumio sudo[11600]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 24 02:06:06 volumio sudo[11600]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 02:06:06 volumio sudo[11600]: pam_unix(sudo:session): session closed for user root Oct 24 02:06:06 volumio volumio[11471]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 24 02:06:06 volumio volumio[11471]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 24 02:06:06 volumio volumio[11471]: info: Adding Calm Radio to Browse Sources Oct 24 02:06:06 volumio volumio[11471]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 24 02:06:06 volumio volumio[11471]: info: [1729724766600] CoreMusicLibrary::Adding element Calm Radio Oct 24 02:06:06 volumio volumio[11471]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 24 02:06:06 volumio volumio[11471]: Cannot find translation for source Calm Radio Oct 24 02:06:06 volumio volumio[11471]: info: AutoStart - onStart Oct 24 02:06:06 volumio volumio[11471]: info: Loading i18n strings for locale ru Oct 24 02:06:06 volumio volumio[11471]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Oct 24 02:06:06 volumio volumio[11471]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Oct 24 02:06:06 volumio volumio[11471]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:06:06 volumio volumio[11471]: info: FusionDsp - mixtype--------------------- Hardware Oct 24 02:06:06 volumio volumio[11471]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 24 02:06:06 volumio volumio[11471]: info: [1729724766649] CoreMusicLibrary::Adding element 80s80s Radio Oct 24 02:06:06 volumio volumio[11471]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 24 02:06:06 volumio volumio[11471]: Cannot find translation for source Calm Radio Oct 24 02:06:06 volumio volumio[11471]: Cannot find translation for source 80s80s Radio Oct 24 02:06:06 volumio volumio[11471]: info: Loading i18n strings for locale ru Oct 24 02:06:06 volumio volumio[11471]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Oct 24 02:06:06 volumio volumio[11471]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 24 02:06:06 volumio volumio[11471]: info: [1729724766704] CoreMusicLibrary::Adding element Radio Paradise Oct 24 02:06:06 volumio volumio[11471]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 24 02:06:06 volumio volumio[11471]: Cannot find translation for source Calm Radio Oct 24 02:06:06 volumio volumio[11471]: Cannot find translation for source 80s80s Radio Oct 24 02:06:06 volumio volumio[11471]: Cannot find translation for source Radio Paradise Oct 24 02:06:06 volumio volumio[11471]: info: Volumio Calling Home Oct 24 02:06:06 volumio volumio[11471]: info: Preparing to generate the ALSA configuration file Oct 24 02:06:06 volumio volumio[11471]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Oct 24 02:06:06 volumio volumio[11471]: info: Reading ALSA contributions from plugins. Oct 24 02:06:07 volumio volumio[11471]: info: minidlna: Found miniDLNA version 1.2.1 Oct 24 02:06:07 volumio volumio[11471]: info: minidlna: Starting minidlna.service Oct 24 02:06:07 volumio volumio[11471]: info: MPD Permissions set Oct 24 02:06:07 volumio volumio[11471]: info: MPD Permissions set Oct 24 02:06:07 volumio volumio[11471]: info: Volumio called home Oct 24 02:06:07 volumio sudo[11624]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start minidlna.service Oct 24 02:06:07 volumio sudo[11624]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 02:06:07 volumio sudo[11624]: pam_unix(sudo:session): session closed for user root Oct 24 02:06:07 volumio volumio[11471]: 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 24 02:06:07 volumio volumio[11471]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:06:07 volumio volumio[11471]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:06:07 volumio volumio[11471]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:06:07 volumio volumio[11471]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:06:07 volumio volumio[11471]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:06:07 volumio volumio[11471]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:06:07 volumio volumio[11471]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:06:07 volumio volumio[11471]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:06:07 volumio volumio[11471]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 24 02:06:07 volumio volumio[11471]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:06:07 volumio volumio[11471]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:06:07 volumio volumio[11471]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:06:07 volumio volumio[11471]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:06:07 volumio volumio[11471]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:06:07 volumio volumio[11471]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:06:07 volumio volumio[11471]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:06:07 volumio volumio[11471]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:06:07 volumio volumio[11471]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:06:07 volumio volumio[11471]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 24 02:06:07 volumio volumio[11471]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:06:07 volumio volumio[11471]: info: minidlna: systemctl start minidlna.service succeeded. Oct 24 02:06:07 volumio volumio[11471]: info: Starting Shairport Sync Oct 24 02:06:07 volumio volumio[11471]: info: Starting Shairport Sync Oct 24 02:06:07 volumio sudo[11635]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 24 02:06:07 volumio sudo[11635]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 02:06:07 volumio volumio[11471]: info: Starting Shairport Sync Oct 24 02:06:07 volumio sudo[11637]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 24 02:06:07 volumio sudo[11637]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 02:06:07 volumio volumio[11471]: info: CoreCommandRouter::volumioGetState Oct 24 02:06:07 volumio volumio[11471]: info: CorePlayQueue::getTrack 0 Oct 24 02:06:07 volumio sudo[11641]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 24 02:06:07 volumio sudo[11641]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 02:06:07 volumio volumio[11471]: info: Asound.conf file unchanged, so no further update is needed Oct 24 02:06:07 volumio volumio[11471]: info: Output device has changed, restarting MPD Oct 24 02:06:07 volumio sudo[11644]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 24 02:06:07 volumio sudo[11644]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 02:06:07 volumio volumio[11471]: info: Output device has changed, restarting Shairport Sync Oct 24 02:06:07 volumio volumio[11471]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:06:07 volumio volumio[11471]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 24 02:06:07 volumio sudo[11644]: pam_unix(sudo:session): session closed for user root Oct 24 02:06:07 volumio sudo[11646]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 24 02:06:07 volumio sudo[11646]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 02:06:07 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Oct 24 02:06:07 volumio systemd[1]: mpd.service: Succeeded. Oct 24 02:06:07 volumio systemd[1]: Stopped Music Player Daemon. Oct 24 02:06:07 volumio volumio[11471]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 24 02:06:07 volumio systemd[1]: Starting Music Player Daemon... Oct 24 02:06:07 volumio volumio[11471]: info: MPD Permissions set Oct 24 02:06:07 volumio volumio[11471]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:06:07 volumio volumio[11471]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:06:07 volumio volumio[11471]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:06:07 volumio volumio[11471]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:06:07 volumio volumio[11471]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:06:07 volumio volumio[11471]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:06:07 volumio volumio[11471]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:06:07 volumio volumio[11471]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:06:07 volumio volumio[11471]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 24 02:06:07 volumio volumio[11471]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:06:07 volumio volumio[11471]: info: Starting Shairport Sync Oct 24 02:06:07 volumio sudo[11652]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 24 02:06:07 volumio sudo[11652]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 02:06:07 volumio sudo[11659]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 24 02:06:07 volumio sudo[11659]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 02:06:07 volumio sudo[11652]: pam_unix(sudo:session): session closed for user root Oct 24 02:06:08 volumio volumio[11471]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Oct 24 02:06:08 volumio volumio[11471]: 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 24 02:06:08 volumio volumio[11471]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Oct 24 02:06:08 volumio volumio[11471]: info: camilladsp spawned new process with pid undefined, instance 1, run: true Oct 24 02:06:08 volumio volumio[11471]: info: camilladsp service started and running in background, instance 1 Oct 24 02:06:08 volumio volumio[11471]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:06:08 volumio volumio[11471]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Oct 24 02:06:08 volumio volumio[11471]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Oct 24 02:06:08 volumio volumio[11471]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Oct 24 02:06:08 volumio volumio[11471]: info: FusionDsp loaded Oct 24 02:06:08 volumio volumio[11471]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 24 02:06:08 volumio sudo[11673]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Oct 24 02:06:08 volumio sudo[11673]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 02:06:08 volumio volumio[11471]: info: FusionDsp - Reporting Fusion DSP Enabled Oct 24 02:06:08 volumio volumio[11471]: info: Adding Signal Path Element [object Object] Oct 24 02:06:08 volumio volumio[11471]: info: Adding fusiondspeq DSP Signal Path Element Oct 24 02:06:08 volumio volumio[11471]: info: FusionDsp - ---- installed callbackRead Oct 24 02:06:08 volumio volumio[11471]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 24 02:06:08 volumio sudo[11673]: pam_unix(sudo:session): session closed for user root Oct 24 02:06:08 volumio volumio[11471]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Oct 24 02:06:08 volumio volumio[11471]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Oct 24 02:06:08 volumio volumio[11471]: at onErrorNT (internal/child_process.js:465:16) Oct 24 02:06:08 volumio volumio[11471]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Oct 24 02:06:08 volumio volumio[11471]: errno: -2, Oct 24 02:06:08 volumio volumio[11471]: code: 'ENOENT', Oct 24 02:06:08 volumio volumio[11471]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Oct 24 02:06:08 volumio volumio[11471]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Oct 24 02:06:08 volumio volumio[11471]: spawnargs: [ Oct 24 02:06:08 volumio volumio[11471]: '-p', Oct 24 02:06:08 volumio volumio[11471]: 9876, Oct 24 02:06:08 volumio volumio[11471]: '-o', Oct 24 02:06:08 volumio volumio[11471]: '/tmp/camilladsp.log', Oct 24 02:06:08 volumio volumio[11471]: '-l', Oct 24 02:06:08 volumio volumio[11471]: 'warn', Oct 24 02:06:08 volumio volumio[11471]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Oct 24 02:06:08 volumio volumio[11471]: ] Oct 24 02:06:08 volumio volumio[11471]: } Oct 24 02:06:08 volumio volumio[11471]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 24 02:06:09 volumio mpd[11662]: Oct 24 02:06 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 24 02:06:09 volumio systemd[1]: Started Music Player Daemon. Oct 24 02:06:09 volumio sudo[11596]: pam_unix(sudo:session): session closed for user root Oct 24 02:06:09 volumio sudo[11646]: pam_unix(sudo:session): session closed for user root Oct 24 02:06:09 volumio sudo[11586]: pam_unix(sudo:session): session closed for user root Oct 24 02:06:09 volumio sudo[11686]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-24 02:05 Oct 24 02:06:09 volumio sudo[11686]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 02:06:09 volumio sudo[11686]: pam_unix(sudo:session): session closed for user root Oct 24 02:06:09 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Oct 24 02:06:09 volumio sudo[11641]: pam_unix(sudo:session): session closed for user root Oct 24 02:06:09 volumio sudo[11659]: pam_unix(sudo:session): session closed for user root Oct 24 02:06:09 volumio sudo[11637]: pam_unix(sudo:session): session closed for user root Oct 24 02:06:09 volumio sudo[11635]: pam_unix(sudo:session): session closed for user root Oct 24 02:06:09 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Oct 24 02:06:09 volumio systemd[1]: Started dynamicswap service. Oct 24 02:06:09 volumio systemd[1]: dynamicswap.service: Succeeded. Oct 24 02:06:09 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Oct 24 02:06:09 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 25. Oct 24 02:06:09 volumio systemd[1]: Stopped Volumio Backend Module. Oct 24 02:06:09 volumio systemd[1]: Started Volumio Backend Module. Oct 24 02:06:09 volumio systemd[1]: Started dynamicswap service. Oct 24 02:06:09 volumio systemd[1]: dynamicswap.service: Succeeded. Oct 24 02:06:11 volumio volumio[11696]: info: ------------------------------------------- Oct 24 02:06:11 volumio volumio[11696]: info: ----- Volumio3 ---- Oct 24 02:06:11 volumio volumio[11696]: info: ------------------------------------------- Oct 24 02:06:11 volumio volumio[11696]: info: ----- System startup ---- Oct 24 02:06:11 volumio volumio[11696]: info: ------------------------------------------- Oct 24 02:06:12 volumio volumio[11696]: info: MYVOLUMIO Environment detected Oct 24 02:06:12 volumio volumio[11696]: info: Plugin folders cleanup Oct 24 02:06:12 volumio volumio[11696]: info: Scanning into folder /volumio/app/plugins/ Oct 24 02:06:12 volumio volumio[11696]: info: Scanning category audio_interface Oct 24 02:06:12 volumio volumio[11696]: info: Scanning category miscellanea Oct 24 02:06:12 volumio volumio[11696]: info: Scanning category music_service Oct 24 02:06:12 volumio volumio[11696]: info: Scanning category plugins.json Oct 24 02:06:12 volumio volumio[11696]: info: Scanning category system_controller Oct 24 02:06:12 volumio volumio[11696]: info: Scanning category user_interface Oct 24 02:06:12 volumio volumio[11696]: info: Scanning into folder /data/plugins/ Oct 24 02:06:12 volumio volumio[11696]: info: Scanning category audio_interface Oct 24 02:06:12 volumio volumio[11696]: info: Scanning category music_service Oct 24 02:06:12 volumio volumio[11696]: info: Scanning category system_controller Oct 24 02:06:12 volumio volumio[11696]: info: Plugin folders cleanup completed Oct 24 02:06:12 volumio volumio[11696]: info: ------------------------------------------- Oct 24 02:06:12 volumio volumio[11696]: info: ----- Core plugins startup ---- Oct 24 02:06:12 volumio volumio[11696]: info: ------------------------------------------- Oct 24 02:06:12 volumio volumio[11696]: info: Loading plugins from folder /volumio/app/plugins/ Oct 24 02:06:12 volumio volumio[11696]: info: Adding plugin upnp to MyMusic Plugins Oct 24 02:06:12 volumio volumio[11696]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 24 02:06:12 volumio volumio[11696]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 24 02:06:12 volumio volumio[11696]: info: Loading plugins from folder /data/plugins/ Oct 24 02:06:12 volumio volumio[11696]: info: Loading plugin "system"... Oct 24 02:06:12 volumio volumio[11696]: info: Loading plugin "appearance"... Oct 24 02:06:13 volumio volumio-remote-updater[658]: [2024-10-24 02:06:13] [connect] Successful connection Oct 24 02:06:13 volumio volumio[11696]: info: Loading plugin "network"... Oct 24 02:06:13 volumio volumio[11696]: info: Refreshing Cached IP Addresses Oct 24 02:06:13 volumio sudo[11782]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 24 02:06:13 volumio sudo[11782]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 02:06:13 volumio sudo[11782]: pam_unix(sudo:session): session closed for user root Oct 24 02:06:13 volumio sudo[11784]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 24 02:06:13 volumio sudo[11784]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 02:06:13 volumio volumio[11696]: info: Loading plugin "services"... Oct 24 02:06:13 volumio sudo[11784]: pam_unix(sudo:session): session closed for user root Oct 24 02:06:13 volumio volumio[11696]: info: Loading plugin "alsa_controller"... Oct 24 02:06:13 volumio sudo[11787]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 24 02:06:13 volumio sudo[11787]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 02:06:13 volumio volumio[11696]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 24 02:06:13 volumio volumio[11696]: info: Loading plugin "wizard"... Oct 24 02:06:13 volumio volumio[11696]: info: Loading plugin "networkfs"... Oct 24 02:06:13 volumio volumio[11696]: info: Starting Udev Watcher for removable devices Oct 24 02:06:13 volumio volumio[11696]: info: Ignoring mount for partition: boot Oct 24 02:06:13 volumio volumio[11696]: info: Ignoring mount for partition: volumio Oct 24 02:06:13 volumio volumio[11696]: info: Ignoring mount for partition: volumio_data Oct 24 02:06:14 volumio volumio[11696]: info: Mounting Device 1303-1E3E Oct 24 02:06:14 volumio sudo[11798]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/1303-1E3E -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Oct 24 02:06:14 volumio sudo[11798]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 02:06:14 volumio sudo[11798]: pam_unix(sudo:session): session closed for user root Oct 24 02:06:14 volumio volumio[11696]: mount: /media/1303-1E3E: /dev/sda1 already mounted on /media/1303-1E3E. Oct 24 02:06:14 volumio volumio[11696]: error: Failed to mount 1303-1E3E: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sda1" "/mnt/USB/1303-1E3E" -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Oct 24 02:06:14 volumio volumio[11696]: mount: /media/1303-1E3E: /dev/sda1 already mounted on /media/1303-1E3E. Oct 24 02:06:14 volumio volumio[11696]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 24 02:06:14 volumio volumio[11696]: info: Loading plugin "volumio_command_line_client"... Oct 24 02:06:14 volumio volumio[11696]: info: Loading plugin "upnp"... Oct 24 02:06:14 volumio volumio[11696]: info: [1729724774089] Starting Upmpd Daemon Oct 24 02:06:14 volumio volumio[11696]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 24 02:06:14 volumio volumio[11696]: info: Loading plugin "my_music"... Oct 24 02:06:14 volumio volumio[11696]: info: Loading plugin "mpd"... Oct 24 02:06:14 volumio volumio[11696]: info: Loading plugin "upnp_browser"... Oct 24 02:06:14 volumio sudo[11787]: pam_unix(sudo:session): session closed for user root Oct 24 02:06:14 volumio volumio[11696]: info: Loading plugin "alarm-clock"... Oct 24 02:06:14 volumio volumio[11696]: info: Loading plugin "airplay_emulation"... Oct 24 02:06:14 volumio volumio[11696]: info: Starting Shairport Sync Oct 24 02:06:14 volumio volumio[11696]: info: Loading plugin "last_100"... Oct 24 02:06:14 volumio volumio[11696]: info: Loading plugin "webradio"... Oct 24 02:06:15 volumio volumio[11696]: info: Loading plugin "i2s_dacs"... Oct 24 02:06:15 volumio volumio[11696]: info: I2S DAC not set, start Auto-detection Oct 24 02:06:15 volumio volumio[11696]: info: Loading plugin "volumiodiscovery"... Oct 24 02:06:15 volumio volumio[11696]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 24 02:06:15 volumio node[11696]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 24 02:06:15 volumio volumio[11696]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 24 02:06:15 volumio node[11696]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 24 02:06:15 volumio volumio[11696]: *** WARNING *** For more information see Oct 24 02:06:15 volumio node[11696]: *** WARNING *** For more information see Oct 24 02:06:15 volumio volumio[11696]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 24 02:06:15 volumio node[11696]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 24 02:06:15 volumio volumio[11696]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 24 02:06:15 volumio node[11696]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 24 02:06:15 volumio volumio[11696]: *** WARNING *** For more information see Oct 24 02:06:15 volumio node[11696]: *** WARNING *** For more information see Oct 24 02:06:15 volumio volumio[11696]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 24 02:06:15 volumio volumio[11696]: info: Discovery: Started advertising with name: Volumio Oct 24 02:06:15 volumio volumio[11696]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 24 02:06:15 volumio volumio[11696]: info: Loading plugin "calmradio"... Oct 24 02:06:16 volumio volumio[11696]: info: Loading plugin "autostart"... Oct 24 02:06:16 volumio volumio[11696]: info: Applying required configuration parameters for plugin autostart Oct 24 02:06:16 volumio volumio[11696]: info: AutoStart - onVolumioStart - read config.json Oct 24 02:06:16 volumio volumio[11696]: info: Loading plugin "outputs"... Oct 24 02:06:16 volumio volumio[11696]: info: Loading plugin "albumart"... Oct 24 02:06:16 volumio volumio[11696]: info: Plugin example_plugin is not enabled Oct 24 02:06:16 volumio volumio[11696]: info: Loading plugin "inputs"... Oct 24 02:06:16 volumio volumio[11696]: info: Loading plugin "updater_comm"... Oct 24 02:06:16 volumio volumio[11696]: info: Plugin mpdemulation is not enabled Oct 24 02:06:16 volumio volumio[11696]: info: Loading plugin "rest_api"... Oct 24 02:06:16 volumio volumio[11696]: info: Loading plugin "websocket"... Oct 24 02:06:16 volumio volumio[11696]: info: Starting Socket.io Server version 2.3.0 Oct 24 02:06:16 volumio volumio[11696]: info: Loading plugin "fusiondsp"... Oct 24 02:06:16 volumio volumio[11696]: Forking 3 albumart workers Oct 24 02:06:16 volumio volumio[11696]: info: Applying required configuration parameters for plugin fusiondsp Oct 24 02:06:17 volumio volumio[11696]: info: Loading plugin "80s80s"... Oct 24 02:06:17 volumio volumio[11696]: info: Applying required configuration parameters for plugin 80s80s Oct 24 02:06:17 volumio volumio[11696]: info: [1729724777360] [80s80s] API delay: 30 Oct 24 02:06:17 volumio volumio[11696]: info: Loading plugin "minidlna"... Oct 24 02:06:17 volumio volumio[11696]: info: Applying required configuration parameters for plugin minidlna Oct 24 02:06:17 volumio volumio[11696]: info: Plugin personal_radio is not enabled Oct 24 02:06:17 volumio volumio[11696]: info: Loading plugin "radio_paradise"... Oct 24 02:06:17 volumio volumio[11696]: Starting albumart workers Oct 24 02:06:17 volumio volumio[11696]: Starting albumart workers Oct 24 02:06:17 volumio volumio[11696]: Starting albumart workers Oct 24 02:06:18 volumio volumio[11696]: info: Applying required configuration parameters for plugin radio_paradise Oct 24 02:06:18 volumio volumio[11696]: info: [1729724778024] [RadioParadise] API delay: 5 Oct 24 02:06:18 volumio volumio[11696]: info: Loading i18n strings for locale ru Oct 24 02:06:18 volumio volumio[11696]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Oct 24 02:06:18 volumio volumio[11696]: Updating browse sources language Oct 24 02:06:18 volumio volumio[11696]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 24 02:06:18 volumio volumio[11696]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 24 02:06:18 volumio volumio[11696]: info: CoreCommandRouter::initPlayerControls Oct 24 02:06:18 volumio volumio[11696]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:06:18 volumio volumio[11696]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:06:18 volumio volumio[11696]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:06:18 volumio volumio[11696]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:06:18 volumio volumio[11696]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:06:18 volumio volumio[11696]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:06:18 volumio volumio[11696]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:06:18 volumio volumio[11696]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:06:18 volumio volumio[11696]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 24 02:06:18 volumio volumio[11696]: Express server listening on port 3000 Oct 24 02:06:18 volumio volumio[11696]: [Metrics] WebUI: 7s 210.41ms Oct 24 02:06:18 volumio volumio[11696]: info: CoreStateMachine::resetVolumioState Oct 24 02:06:18 volumio volumio[11696]: info: CoreStateMachine::getcurrentVolume Oct 24 02:06:18 volumio volumio[11696]: info: CoreCommandRouter::volumioRetrievevolume Oct 24 02:06:18 volumio volumio[11696]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 24 02:06:18 volumio volumio[11696]: verbose: New Socket.io Connection to 192.168.1.139 from 192.168.1.72 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Oct 24 02:06:18 volumio volumio[11696]: info: VolumeController:: Volume=35 Mute =false Oct 24 02:06:18 volumio volumio[11696]: info: CoreStateMachine::pushState Oct 24 02:06:18 volumio volumio[11696]: info: CorePlayQueue::getTrack 0 Oct 24 02:06:18 volumio volumio[11696]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 24 02:06:18 volumio volumio[11696]: info: CoreCommandRouter::volumioPushState Oct 24 02:06:18 volumio volumio[11696]: info: CoreStateMachine::updateTrackBlock Oct 24 02:06:18 volumio volumio[11696]: info: CorePlayQueue::getTrackBlock Oct 24 02:06:18 volumio volumio[11696]: info: CoreCommandRouter::volumioRetrievevolume Oct 24 02:06:18 volumio volumio[11696]: info: Reloading queue from file Oct 24 02:06:18 volumio volumio[11696]: info: CoreStateMachine::setRepeat false single undefined Oct 24 02:06:18 volumio volumio[11696]: info: CoreStateMachine::pushState Oct 24 02:06:18 volumio volumio[11696]: info: CorePlayQueue::getTrack 0 Oct 24 02:06:18 volumio volumio[11696]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 24 02:06:18 volumio volumio[11696]: info: CoreCommandRouter::volumioPushState Oct 24 02:06:18 volumio volumio[11696]: info: CoreStateMachine::setRandom null Oct 24 02:06:18 volumio volumio[11696]: info: CoreStateMachine::pushState Oct 24 02:06:18 volumio volumio[11696]: info: CorePlayQueue::getTrack 0 Oct 24 02:06:18 volumio volumio[11696]: info: CoreCommandRouter::volumioPushState Oct 24 02:06:18 volumio volumio[11696]: info: Setting Device type: Raspberry PI Oct 24 02:06:18 volumio volumio[11696]: info: Completed loading Core Plugins Oct 24 02:06:18 volumio volumio[11696]: info: Preparing to generate the ALSA configuration file Oct 24 02:06:18 volumio volumio[11696]: info: Discovery: adding 266418d9-d71f-4348-91b8-c2087d0064b7 Oct 24 02:06:18 volumio volumio[11696]: info: Discovery: Found device Volumio Oct 24 02:06:18 volumio volumio[11696]: info: CoreCommandRouter::volumioGetState Oct 24 02:06:18 volumio volumio[11696]: info: CorePlayQueue::getTrack 0 Oct 24 02:06:18 volumio volumio[11696]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Oct 24 02:06:18 volumio volumio[11696]: info: Reading ALSA contributions from plugins. Oct 24 02:06:18 volumio volumio[11696]: info: VolumeController:: Volume=35 Mute =false Oct 24 02:06:18 volumio volumio[11696]: info: CoreStateMachine::pushState Oct 24 02:06:18 volumio volumio[11696]: info: CorePlayQueue::getTrack 0 Oct 24 02:06:18 volumio volumio[11696]: info: CoreCommandRouter::volumioPushState Oct 24 02:06:18 volumio volumio[11696]: info: Asound.conf file unchanged, so no further update is needed Oct 24 02:06:18 volumio volumio[11696]: info: Output device has changed, restarting MPD Oct 24 02:06:19 volumio sudo[11867]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 24 02:06:19 volumio sudo[11867]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 02:06:19 volumio volumio[11696]: info: Output device has changed, restarting Shairport Sync Oct 24 02:06:19 volumio sudo[11867]: pam_unix(sudo:session): session closed for user root Oct 24 02:06:19 volumio volumio[11696]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:06:19 volumio volumio[11696]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 24 02:06:19 volumio sudo[11870]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 24 02:06:19 volumio sudo[11870]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 02:06:19 volumio systemd[1]: Stopping Music Player Daemon... Oct 24 02:06:19 volumio volumio[11696]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 24 02:06:19 volumio volumio[11696]: info: ___________ START PLUGINS ___________ Oct 24 02:06:19 volumio systemd[1]: mpd.service: Succeeded. Oct 24 02:06:19 volumio systemd[1]: Stopped Music Player Daemon. Oct 24 02:06:19 volumio volumio[11696]: info: ControllerMpd::onStart: Initializing MPD Oct 24 02:06:19 volumio volumio[11696]: info: Creating MPD Configuration file Oct 24 02:06:19 volumio sudo[11877]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 24 02:06:19 volumio sudo[11877]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 02:06:19 volumio volumio[11696]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 24 02:06:19 volumio volumio[11696]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 24 02:06:19 volumio volumio[11696]: info: [1729724779141] CoreMusicLibrary::Adding element Медиа-серверы Oct 24 02:06:19 volumio volumio[11696]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 24 02:06:19 volumio sudo[11877]: pam_unix(sudo:session): session closed for user root Oct 24 02:06:19 volumio systemd[1]: Starting Music Player Daemon... Oct 24 02:06:19 volumio sudo[11879]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 24 02:06:19 volumio sudo[11879]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 02:06:19 volumio volumio[11696]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:06:19 volumio volumio[11696]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 24 02:06:19 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Oct 24 02:06:19 volumio systemd[1]: mpd.service: Succeeded. Oct 24 02:06:19 volumio systemd[1]: Stopped Music Player Daemon. Oct 24 02:06:19 volumio systemd[1]: Starting Music Player Daemon... Oct 24 02:06:19 volumio volumio[11696]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 24 02:06:19 volumio volumio[11696]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:06:19 volumio volumio[11696]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 24 02:06:19 volumio volumio[11696]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 24 02:06:19 volumio volumio[11696]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 24 02:06:19 volumio volumio[11696]: info: [1729724779295] CoreMusicLibrary::Adding element Last_100 Oct 24 02:06:19 volumio volumio[11696]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 24 02:06:19 volumio volumio[11696]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 24 02:06:19 volumio volumio[11696]: info: [1729724779301] CoreMusicLibrary::Adding element Webradio Oct 24 02:06:19 volumio volumio[11696]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 24 02:06:19 volumio volumio[11696]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 24 02:06:19 volumio volumio[11696]: info: Initializing BBC Radios Oct 24 02:06:19 volumio sudo[11883]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 24 02:06:19 volumio sudo[11883]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 02:06:19 volumio sudo[11883]: pam_unix(sudo:session): session closed for user root Oct 24 02:06:19 volumio volumio[11696]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 24 02:06:19 volumio volumio[11696]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 24 02:06:19 volumio volumio[11696]: info: Adding Calm Radio to Browse Sources Oct 24 02:06:19 volumio volumio[11696]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 24 02:06:19 volumio volumio[11696]: info: [1729724779394] CoreMusicLibrary::Adding element Calm Radio Oct 24 02:06:19 volumio volumio[11696]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 24 02:06:19 volumio volumio[11696]: Cannot find translation for source Calm Radio Oct 24 02:06:19 volumio volumio[11696]: info: AutoStart - onStart Oct 24 02:06:19 volumio volumio[11696]: info: Loading i18n strings for locale ru Oct 24 02:06:19 volumio volumio[11696]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Oct 24 02:06:19 volumio volumio[11696]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Oct 24 02:06:19 volumio volumio[11696]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:06:19 volumio volumio[11696]: info: FusionDsp - mixtype--------------------- Hardware Oct 24 02:06:19 volumio volumio[11696]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 24 02:06:19 volumio volumio[11696]: info: [1729724779444] CoreMusicLibrary::Adding element 80s80s Radio Oct 24 02:06:19 volumio volumio[11696]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 24 02:06:19 volumio volumio[11696]: Cannot find translation for source Calm Radio Oct 24 02:06:19 volumio volumio[11696]: Cannot find translation for source 80s80s Radio Oct 24 02:06:19 volumio volumio[11696]: info: Loading i18n strings for locale ru Oct 24 02:06:19 volumio volumio[11696]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Oct 24 02:06:19 volumio volumio[11696]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 24 02:06:19 volumio volumio[11696]: info: [1729724779504] CoreMusicLibrary::Adding element Radio Paradise Oct 24 02:06:19 volumio volumio[11696]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 24 02:06:19 volumio volumio[11696]: Cannot find translation for source Calm Radio Oct 24 02:06:19 volumio volumio[11696]: Cannot find translation for source 80s80s Radio Oct 24 02:06:19 volumio volumio[11696]: Cannot find translation for source Radio Paradise Oct 24 02:06:19 volumio volumio[11696]: info: Volumio Calling Home Oct 24 02:06:19 volumio volumio[11696]: info: Preparing to generate the ALSA configuration file Oct 24 02:06:19 volumio volumio[11696]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Oct 24 02:06:19 volumio volumio[11696]: info: Reading ALSA contributions from plugins. Oct 24 02:06:19 volumio volumio[11696]: info: MPD Permissions set Oct 24 02:06:19 volumio volumio[11696]: info: MPD Permissions set Oct 24 02:06:19 volumio volumio[11696]: info: minidlna: Found miniDLNA version 1.2.1 Oct 24 02:06:19 volumio volumio[11696]: info: minidlna: Starting minidlna.service Oct 24 02:06:19 volumio volumio[11696]: info: Volumio called home Oct 24 02:06:19 volumio volumio[11696]: info: CoreCommandRouter::volumioGetState Oct 24 02:06:19 volumio volumio[11696]: info: CorePlayQueue::getTrack 0 Oct 24 02:06:19 volumio sudo[11907]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start minidlna.service Oct 24 02:06:19 volumio sudo[11907]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 02:06:19 volumio volumio[11696]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Oct 24 02:06:19 volumio volumio[11696]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:06:19 volumio volumio[11696]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:06:19 volumio volumio[11696]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:06:19 volumio volumio[11696]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:06:19 volumio volumio[11696]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:06:19 volumio volumio[11696]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:06:19 volumio volumio[11696]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:06:19 volumio volumio[11696]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:06:19 volumio sudo[11907]: pam_unix(sudo:session): session closed for user root Oct 24 02:06:19 volumio volumio[11696]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 24 02:06:19 volumio volumio[11696]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:06:19 volumio volumio[11696]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:06:19 volumio volumio[11696]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:06:19 volumio volumio[11696]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:06:19 volumio volumio[11696]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:06:19 volumio volumio[11696]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:06:19 volumio volumio[11696]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:06:19 volumio volumio[11696]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:06:19 volumio volumio[11696]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:06:19 volumio volumio[11696]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 24 02:06:19 volumio volumio[11696]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:06:20 volumio volumio[11696]: info: Listing playlists Oct 24 02:06:20 volumio volumio[11696]: info: Listing playlists Oct 24 02:06:20 volumio volumio[11696]: verbose: New Socket.io Connection to 192.168.1.139 from 192.168.1.72 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Oct 24 02:06:20 volumio volumio[11696]: info: minidlna: systemctl start minidlna.service succeeded. Oct 24 02:06:20 volumio volumio[11696]: info: CoreCommandRouter::volumioGetState Oct 24 02:06:20 volumio volumio[11696]: info: CorePlayQueue::getTrack 0 Oct 24 02:06:20 volumio volumio[11696]: info: Starting Shairport Sync Oct 24 02:06:20 volumio volumio[11696]: info: Starting Shairport Sync Oct 24 02:06:20 volumio volumio[11696]: info: Starting Shairport Sync Oct 24 02:06:20 volumio sudo[11918]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 24 02:06:20 volumio sudo[11918]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 02:06:20 volumio sudo[11920]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 24 02:06:20 volumio sudo[11920]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 02:06:20 volumio sudo[11924]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 24 02:06:20 volumio sudo[11924]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 02:06:20 volumio volumio[11696]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 24 02:06:20 volumio volumio[11696]: info: Listing playlists Oct 24 02:06:20 volumio volumio[11696]: info: CoreCommandRouter::volumioGetState Oct 24 02:06:20 volumio volumio[11696]: info: CorePlayQueue::getTrack 0 Oct 24 02:06:20 volumio volumio[11696]: info: Asound.conf file unchanged, so no further update is needed Oct 24 02:06:20 volumio volumio[11696]: info: Output device has changed, restarting MPD Oct 24 02:06:20 volumio sudo[11927]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 24 02:06:20 volumio volumio[11696]: info: Output device has changed, restarting Shairport Sync Oct 24 02:06:20 volumio volumio[11696]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:06:20 volumio volumio[11696]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 24 02:06:20 volumio sudo[11927]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 02:06:20 volumio sudo[11927]: pam_unix(sudo:session): session closed for user root Oct 24 02:06:20 volumio sudo[11929]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 24 02:06:20 volumio sudo[11929]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 02:06:20 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Oct 24 02:06:20 volumio systemd[1]: mpd.service: Succeeded. Oct 24 02:06:20 volumio systemd[1]: Stopped Music Player Daemon. Oct 24 02:06:20 volumio volumio[11696]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 24 02:06:20 volumio systemd[1]: Starting Music Player Daemon... Oct 24 02:06:20 volumio volumio[11696]: info: MPD Permissions set Oct 24 02:06:20 volumio volumio[11696]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:06:20 volumio volumio[11696]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:06:20 volumio volumio[11696]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:06:20 volumio volumio[11696]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:06:20 volumio volumio[11696]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:06:20 volumio volumio[11696]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:06:20 volumio volumio[11696]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:06:20 volumio volumio[11696]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:06:20 volumio volumio[11696]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 24 02:06:20 volumio volumio[11696]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 02:06:20 volumio volumio[11696]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 24 02:06:20 volumio volumio[11696]: TypeError: Cannot read property 'off' of undefined Oct 24 02:06:20 volumio volumio[11696]: at FusionDsp.onStop (/data/plugins/audio_interface/fusiondsp/index.js:107:15) Oct 24 02:06:20 volumio volumio[11696]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Oct 24 02:06:20 volumio volumio[11696]: at PluginManager.disableAndStopPlugin (/volumio/app/pluginmanager.js:1883:8) Oct 24 02:06:20 volumio volumio[11696]: at CoreCommandRouter.disableAndStopPlugin (/volumio/app/index.js:1537:29) Oct 24 02:06:20 volumio volumio[11696]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1387:47) Oct 24 02:06:20 volumio volumio[11696]: at Socket.emit (events.js:315:20) Oct 24 02:06:20 volumio volumio[11696]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Oct 24 02:06:20 volumio volumio[11696]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Oct 24 02:06:20 volumio volumio[11696]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 24 02:06:20 volumio sudo[11935]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 24 02:06:20 volumio sudo[11935]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 02:06:20 volumio sudo[11935]: pam_unix(sudo:session): session closed for user root Oct 24 02:06:21 volumio sudo[11952]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-24 02:05 Oct 24 02:06:21 volumio sudo[11952]: 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"