-- Logs begin at Mon 2024-05-06 20:16:40 CEST, end at Mon 2024-05-06 20:18:56 CEST. -- May 06 20:17:00 volumio systemd[1]: Started Volumio Backend Module. May 06 20:17:00 volumio systemd[1]: Started Start Volumio Kiosk. May 06 20:17:00 volumio systemd[1]: Started Intel SST and HDA soundcard init service. May 06 20:17:00 volumio systemd[1]: Started Volumio Cpu Tweaker. May 06 20:17:00 volumio systemd[1]: Reached target Multi-User System. May 06 20:17:00 volumio systemd[1]: Reached target Graphical Interface. May 06 20:17:00 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... May 06 20:17:00 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. May 06 20:17:00 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. May 06 20:17:00 volumio systemd[1]: Startup finished in 8.207s (firmware) + 6.831s (loader) + 17.122s (kernel) + 20.081s (userspace) = 52.243s. May 06 20:17:00 volumio volumio-cpu-tweak[1042]: Setting RT Priority for mpd May 06 20:17:00 volumio volumio-cpu-tweak[1042]: pid 35's current scheduling policy: SCHED_FIFO May 06 20:17:00 volumio volumio-cpu-tweak[1042]: pid 35's current scheduling priority: 99 May 06 20:17:00 volumio volumio-cpu-tweak[1042]: Setting MPD Affinity May 06 20:17:00 volumio volumio-cpu-tweak[1042]: pid 3's current affinity mask: f May 06 20:17:00 volumio volumio-cpu-tweak[1042]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance May 06 20:17:00 volumio systemd[1]: volumio_cpu_tweak.service: Succeeded. May 06 20:17:00 volumio ntpd[1028]: Soliciting pool server 62.75.236.38 May 06 20:17:00 volumio startx[1040]: X.Org X Server 1.20.4 May 06 20:17:00 volumio startx[1040]: X Protocol Version 11, Revision 0 May 06 20:17:00 volumio startx[1040]: Build Operating System: Linux 5.10.0-10-amd64 x86_64 Debian May 06 20:17:00 volumio startx[1040]: Current Operating System: Linux volumio 6.6.25-volumio #2 SMP PREEMPT_DYNAMIC Sun Apr 7 20:18:18 CEST 2024 x86_64 May 06 20:17:00 volumio startx[1040]: Kernel command line: BOOT_IMAGE=/vmlinuz biosdevname=0 splash plymouth.ignore-serial-consoles quiet ro rootwait imgpart=UUID=7ab46900-3cb8-4deb-a4a8-9a1a2f978606 bootpart=UUID=E2BB-AF0D datapart=UUID=6c12c2b2-7468-42cb-89d5-2730f50d15fd imgfile=/volumio_current.sqsh logo.nologo vt.global_cursor_default=0 acpi_backlight=vendor use_kmsg=yes net.ifnames=0 loglevel=0 May 06 20:17:00 volumio startx[1040]: Build Date: 18 December 2021 09:05:36AM May 06 20:17:00 volumio startx[1040]: xorg-server 2:1.20.4-1+deb10u4 (https://www.debian.org/support) May 06 20:17:00 volumio startx[1040]: Current version of pixman: 0.36.0 May 06 20:17:00 volumio startx[1040]: Before reporting problems, check http://wiki.x.org May 06 20:17:00 volumio startx[1040]: to make sure that you have the latest version. May 06 20:17:00 volumio startx[1040]: Markers: (--) probed, (**) from config file, (==) default setting, May 06 20:17:00 volumio startx[1040]: (++) from command line, (!!) notice, (II) informational, May 06 20:17:00 volumio startx[1040]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. May 06 20:17:00 volumio startx[1040]: (==) Log file: "/var/log/Xorg.0.log", Time: Mon May 6 20:17:00 2024 May 06 20:17:00 volumio startx[1040]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" May 06 20:17:00 volumio systemd[1]: soundcard-init.service: Succeeded. May 06 20:17:00 volumio kernel: [drm] PCIE gen 2 link speeds already enabled May 06 20:17:00 volumio kernel: [drm] PCIE GART of 2048M enabled (table at 0x0000000000040000). May 06 20:17:00 volumio kernel: radeon 0000:09:00.0: WB enabled May 06 20:17:00 volumio kernel: radeon 0000:09:00.0: fence driver on ring 0 use gpu addr 0x0000000080000c00 May 06 20:17:00 volumio kernel: radeon 0000:09:00.0: fence driver on ring 1 use gpu addr 0x0000000080000c04 May 06 20:17:00 volumio kernel: radeon 0000:09:00.0: fence driver on ring 2 use gpu addr 0x0000000080000c08 May 06 20:17:00 volumio kernel: radeon 0000:09:00.0: fence driver on ring 3 use gpu addr 0x0000000080000c0c May 06 20:17:00 volumio kernel: radeon 0000:09:00.0: fence driver on ring 4 use gpu addr 0x0000000080000c10 May 06 20:17:00 volumio kernel: debugfs: File 'radeon_ring_gfx' in directory '0' already present! May 06 20:17:00 volumio kernel: debugfs: File 'radeon_ring_cp1' in directory '0' already present! May 06 20:17:00 volumio kernel: debugfs: File 'radeon_ring_cp2' in directory '0' already present! May 06 20:17:00 volumio kernel: debugfs: File 'radeon_ring_dma1' in directory '0' already present! May 06 20:17:00 volumio kernel: debugfs: File 'radeon_ring_dma2' in directory '0' already present! May 06 20:17:01 volumio volumio[1039]: info: ------------------------------------------- May 06 20:17:01 volumio volumio[1039]: info: ----- Volumio3 ---- May 06 20:17:01 volumio volumio[1039]: info: ------------------------------------------- May 06 20:17:01 volumio volumio[1039]: info: ----- System startup ---- May 06 20:17:01 volumio volumio[1039]: info: ------------------------------------------- May 06 20:17:01 volumio kernel: [drm] ring test on 0 succeeded in 1 usecs May 06 20:17:01 volumio kernel: [drm] ring test on 1 succeeded in 1 usecs May 06 20:17:01 volumio kernel: [drm] ring test on 2 succeeded in 1 usecs May 06 20:17:01 volumio kernel: [drm] ring test on 3 succeeded in 4 usecs May 06 20:17:01 volumio kernel: [drm] ring test on 4 succeeded in 4 usecs May 06 20:17:01 volumio kernel: [drm] ib test on ring 0 succeeded in 0 usecs May 06 20:17:01 volumio kernel: [drm] ib test on ring 1 succeeded in 0 usecs May 06 20:17:01 volumio kernel: [drm] ib test on ring 2 succeeded in 0 usecs May 06 20:17:01 volumio kernel: [drm] ib test on ring 3 succeeded in 0 usecs May 06 20:17:01 volumio kernel: [drm] ib test on ring 4 succeeded in 0 usecs May 06 20:17:01 volumio ntpd[1028]: Soliciting pool server 45.142.247.74 May 06 20:17:01 volumio ntpd[1028]: Soliciting pool server 161.97.164.152 May 06 20:17:01 volumio startx[1040]: (II) [KMS] Kernel modesetting enabled. May 06 20:17:01 volumio kernel: broken atomic modeset userspace detected, disabling atomic May 06 20:17:01 volumio volumio[1039]: info: MYVOLUMIO Environment detected May 06 20:17:02 volumio ntpd[1028]: Soliciting pool server 5.9.19.62 May 06 20:17:02 volumio ntpd[1028]: Soliciting pool server 212.18.3.19 May 06 20:17:02 volumio ntpd[1028]: Soliciting pool server 94.16.122.152 May 06 20:17:02 volumio volumio[1039]: info: Plugin folders cleanup May 06 20:17:02 volumio volumio[1039]: info: Scanning into folder /volumio/app/plugins/ May 06 20:17:02 volumio volumio[1039]: info: Scanning category audio_interface May 06 20:17:02 volumio volumio[1039]: info: Scanning category miscellanea May 06 20:17:02 volumio volumio[1039]: info: Scanning category music_service May 06 20:17:02 volumio volumio[1039]: info: Scanning category plugins.json May 06 20:17:02 volumio volumio[1039]: info: Scanning category system_controller May 06 20:17:02 volumio volumio[1039]: info: Scanning category user_interface May 06 20:17:02 volumio volumio[1039]: info: Scanning into folder /data/plugins/ May 06 20:17:02 volumio volumio[1039]: info: Scanning category music_service May 06 20:17:02 volumio volumio[1039]: info: Plugin folders cleanup completed May 06 20:17:02 volumio volumio[1039]: info: ------------------------------------------- May 06 20:17:02 volumio volumio[1039]: info: ----- Core plugins startup ---- May 06 20:17:02 volumio volumio[1039]: info: ------------------------------------------- May 06 20:17:02 volumio volumio[1039]: info: Loading plugins from folder /volumio/app/plugins/ May 06 20:17:02 volumio volumio[1039]: info: Adding plugin upnp to MyMusic Plugins May 06 20:17:02 volumio volumio[1039]: info: Adding plugin airplay_emulation to MyMusic Plugins May 06 20:17:02 volumio volumio[1039]: info: Adding plugin upnp_browser to MyMusic Plugins May 06 20:17:02 volumio volumio[1039]: info: Loading plugins from folder /data/plugins/ May 06 20:17:02 volumio volumio[1039]: info: Loading plugin "system"... May 06 20:17:02 volumio volumio[1039]: info: Loading plugin "appearance"... May 06 20:17:03 volumio ntpd[1028]: Soliciting pool server 45.9.61.155 May 06 20:17:03 volumio ntpd[1028]: Soliciting pool server 213.239.234.28 May 06 20:17:03 volumio ntpd[1028]: Soliciting pool server 75.119.140.230 May 06 20:17:03 volumio ntpd[1028]: Soliciting pool server 176.9.157.155 May 06 20:17:03 volumio startx[1040]: (II) modeset(0): Initializing kms color map for depth 24, 8 bpc. May 06 20:17:03 volumio volumio[1039]: info: Loading plugin "network"... May 06 20:17:03 volumio volumio[1039]: info: Refreshing Cached IP Addresses May 06 20:17:03 volumio sudo[1218]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 06 20:17:03 volumio sudo[1218]: pam_unix(sudo:session): session opened for user root by (uid=0) May 06 20:17:03 volumio sudo[1218]: pam_unix(sudo:session): session closed for user root May 06 20:17:03 volumio sudo[1220]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 06 20:17:03 volumio sudo[1220]: pam_unix(sudo:session): session opened for user root by (uid=0) May 06 20:17:03 volumio volumio[1039]: info: Loading plugin "services"... May 06 20:17:03 volumio volumio[1039]: info: Loading plugin "alsa_controller"... May 06 20:17:03 volumio sudo[1220]: pam_unix(sudo:session): session closed for user root May 06 20:17:03 volumio sudo[1223]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 06 20:17:03 volumio sudo[1223]: pam_unix(sudo:session): session opened for user root by (uid=0) May 06 20:17:03 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 06 20:17:03 volumio volumio[1039]: info: Loading plugin "wizard"... May 06 20:17:03 volumio volumio[1039]: info: Loading plugin "networkfs"... May 06 20:17:03 volumio volumio[1039]: info: Starting Udev Watcher for removable devices May 06 20:17:03 volumio volumio[1039]: info: Ignoring mount for partition: boot May 06 20:17:03 volumio volumio[1039]: info: Ignoring mount for partition: volumio May 06 20:17:03 volumio volumio[1039]: info: Ignoring mount for partition: volumio_data May 06 20:17:03 volumio volumio[1039]: info: Ignoring mount for partition: Музыка_рус. May 06 20:17:03 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 06 20:17:03 volumio volumio[1039]: info: Loading plugin "volumio_command_line_client"... May 06 20:17:03 volumio volumio[1039]: info: Loading plugin "upnp"... May 06 20:17:03 volumio volumio[1039]: info: [1715019423981] Starting Upmpd Daemon May 06 20:17:03 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 06 20:17:03 volumio volumio[1039]: info: Loading plugin "my_music"... May 06 20:17:03 volumio volumio[1039]: info: Loading plugin "mpd"... May 06 20:17:04 volumio volumio[1039]: info: Loading plugin "upnp_browser"... May 06 20:17:04 volumio ntpd[1028]: Soliciting pool server 79.133.44.138 May 06 20:17:04 volumio ntpd[1028]: Soliciting pool server 78.47.118.0 May 06 20:17:04 volumio ntpd[1028]: Soliciting pool server 159.69.64.189 May 06 20:17:04 volumio volumio[1039]: info: Loading plugin "alarm-clock"... May 06 20:17:04 volumio volumio[1039]: info: Loading plugin "airplay_emulation"... May 06 20:17:04 volumio volumio[1039]: info: Starting Shairport Sync May 06 20:17:04 volumio volumio[1039]: info: Loading plugin "last_100"... May 06 20:17:04 volumio volumio[1039]: info: Loading plugin "webradio"... May 06 20:17:04 volumio volumio[1039]: info: Loading plugin "i2s_dacs"... May 06 20:17:04 volumio volumio[1039]: info: I2S DAC not set, start Auto-detection May 06 20:17:04 volumio volumio[1039]: info: Loading plugin "volumiodiscovery"... May 06 20:17:05 volumio volumio[1039]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 06 20:17:05 volumio volumio[1039]: *** WARNING *** Please fix your application to use the native API of Avahi! May 06 20:17:05 volumio volumio[1039]: *** WARNING *** For more information see May 06 20:17:05 volumio volumio[1039]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 06 20:17:05 volumio volumio[1039]: *** WARNING *** Please fix your application to use the native API of Avahi! May 06 20:17:05 volumio volumio[1039]: *** WARNING *** For more information see May 06 20:17:05 volumio node[1039]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 06 20:17:05 volumio node[1039]: *** WARNING *** Please fix your application to use the native API of Avahi! May 06 20:17:05 volumio node[1039]: *** WARNING *** For more information see May 06 20:17:05 volumio node[1039]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 06 20:17:05 volumio node[1039]: *** WARNING *** Please fix your application to use the native API of Avahi! May 06 20:17:05 volumio node[1039]: *** WARNING *** For more information see May 06 20:17:05 volumio volumio[1039]: info: Applying required configuration parameters for plugin volumiodiscovery May 06 20:17:05 volumio volumio[1039]: info: Discovery: Started advertising with name: Volumio May 06 20:17:05 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 06 20:17:05 volumio volumio[1039]: info: Plugin spop is not enabled May 06 20:17:05 volumio volumio[1039]: info: Loading plugin "outputs"... May 06 20:17:05 volumio volumio[1039]: info: Loading plugin "albumart"... May 06 20:17:05 volumio volumio[1039]: info: Plugin example_plugin is not enabled May 06 20:17:05 volumio volumio[1039]: info: Loading plugin "inputs"... May 06 20:17:05 volumio volumio[1039]: info: Loading plugin "updater_comm"... May 06 20:17:05 volumio volumio[1039]: Forking 3 albumart workers May 06 20:17:05 volumio ntpd[1028]: Soliciting pool server 185.232.69.65 May 06 20:17:05 volumio ntpd[1028]: Soliciting pool server 129.250.35.250 May 06 20:17:05 volumio volumio[1039]: Starting albumart workers May 06 20:17:05 volumio volumio[1039]: Starting albumart workers May 06 20:17:05 volumio volumio[1039]: Starting albumart workers May 06 20:17:05 volumio volumio[1039]: info: Plugin mpdemulation is not enabled May 06 20:17:05 volumio volumio[1039]: info: Loading plugin "rest_api"... May 06 20:17:05 volumio volumio[1039]: info: Loading plugin "websocket"... May 06 20:17:05 volumio volumio[1039]: info: Loading i18n strings for locale ru May 06 20:17:05 volumio volumio[1039]: Updating browse sources language May 06 20:17:05 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 06 20:17:06 volumio ntpd[1028]: Soliciting pool server 158.101.188.125 May 06 20:17:06 volumio ntpd[1028]: Soliciting pool server 2a02:8106:21:9400::1 May 06 20:17:06 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 06 20:17:06 volumio volumio[1039]: info: CoreCommandRouter::initPlayerControls May 06 20:17:06 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 20:17:06 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 20:17:06 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 20:17:06 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 20:17:06 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 20:17:06 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 20:17:06 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 20:17:06 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 20:17:06 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 06 20:17:06 volumio volumio[1039]: Express server listening on port 3000 May 06 20:17:06 volumio volumio[1039]: [Metrics] WebUI: 5s 826.19ms May 06 20:17:06 volumio volumio[1039]: info: Setting Device type: x86 May 06 20:17:06 volumio volumio[1039]: info: CoreStateMachine::resetVolumioState May 06 20:17:06 volumio volumio[1039]: info: CoreStateMachine::getcurrentVolume May 06 20:17:06 volumio volumio[1039]: info: CoreCommandRouter::volumioRetrievevolume May 06 20:17:06 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 06 20:17:06 volumio volumio[1039]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' May 06 20:17:06 volumio volumio[1039]: info: Completed loading Core Plugins May 06 20:17:06 volumio volumio[1039]: info: Preparing to generate the ALSA configuration file May 06 20:17:06 volumio volumio[1039]: info: Cannot read play queue from file May 06 20:17:06 volumio volumio[1039]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision May 06 20:17:06 volumio volumio[1039]: verbose: New Socket.io Connection to 192.168.0.53 from 192.168.0.199 UA: okhttp/4.9.2 Total Clients: 1 May 06 20:17:06 volumio volumio[1039]: verbose: New Socket.io Connection to 192.168.0.248 from 192.168.0.199 UA: okhttp/4.9.2 Total Clients: 2 May 06 20:17:06 volumio volumio[1039]: verbose: New Socket.io Connection to 192.168.0.53 from 192.168.0.199 UA: okhttp/4.9.2 Total Clients: 3 May 06 20:17:06 volumio volumio[1039]: verbose: New Socket.io Connection to 192.168.0.248 from 192.168.0.199 UA: okhttp/4.9.2 Total Clients: 4 May 06 20:17:06 volumio volumio[1039]: verbose: New Socket.io Connection to 192.168.0.53 from 192.168.0.199 UA: okhttp/4.9.2 Total Clients: 5 May 06 20:17:06 volumio volumio[1039]: verbose: New Socket.io Connection to 192.168.0.248 from 192.168.0.199 UA: okhttp/4.9.2 Total Clients: 6 May 06 20:17:06 volumio volumio[1039]: verbose: New Socket.io Connection to 192.168.0.53 from 192.168.0.199 UA: okhttp/4.9.2 Total Clients: 7 May 06 20:17:06 volumio volumio[1039]: verbose: New Socket.io Connection to 192.168.0.248 from 192.168.0.199 UA: okhttp/4.9.2 Total Clients: 8 May 06 20:17:06 volumio volumio[1039]: verbose: New Socket.io Connection to 192.168.0.53 from 192.168.0.199 UA: okhttp/4.9.2 Total Clients: 9 May 06 20:17:06 volumio volumio[1039]: verbose: New Socket.io Connection to 192.168.0.248 from 192.168.0.199 UA: okhttp/4.9.2 Total Clients: 10 May 06 20:17:06 volumio volumio[1039]: info: VolumeController:: Volume=100 Mute =false May 06 20:17:06 volumio volumio[1039]: info: CoreStateMachine::pushState May 06 20:17:06 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 May 06 20:17:06 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 20:17:06 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState May 06 20:17:06 volumio volumio[1039]: info: CoreStateMachine::updateTrackBlock May 06 20:17:06 volumio volumio[1039]: info: CorePlayQueue::getTrackBlock May 06 20:17:06 volumio volumio[1039]: info: CoreCommandRouter::volumioRetrievevolume May 06 20:17:06 volumio volumio[1039]: verbose: New Socket.io Connection to 192.168.0.248 from 192.168.0.199 UA: okhttp/4.9.2 Total Clients: 11 May 06 20:17:06 volumio volumio[1039]: info: Asound.conf file unchanged, so no further update is needed May 06 20:17:06 volumio volumio[1039]: info: Output device has changed, restarting MPD May 06 20:17:06 volumio volumio[1039]: info: Output device has changed, restarting Shairport Sync May 06 20:17:06 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 20:17:06 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 06 20:17:06 volumio sudo[1355]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 06 20:17:06 volumio sudo[1355]: pam_unix(sudo:session): session opened for user root by (uid=0) May 06 20:17:06 volumio sudo[1357]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 06 20:17:06 volumio sudo[1357]: pam_unix(sudo:session): session opened for user root by (uid=0) May 06 20:17:06 volumio sudo[1355]: pam_unix(sudo:session): session closed for user root May 06 20:17:06 volumio volumio[1039]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 06 20:17:06 volumio volumio[1039]: info: ___________ START PLUGINS ___________ May 06 20:17:06 volumio volumio[1039]: info: ControllerMpd::onStart: Initializing MPD May 06 20:17:06 volumio volumio[1039]: info: Creating MPD Configuration file May 06 20:17:06 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 06 20:17:06 volumio volumio[1039]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 06 20:17:06 volumio volumio[1039]: info: [1715019426843] CoreMusicLibrary::Adding element Медиа-серверы May 06 20:17:06 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 06 20:17:06 volumio sudo[1366]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 06 20:17:06 volumio sudo[1364]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 06 20:17:06 volumio sudo[1364]: pam_unix(sudo:session): session opened for user root by (uid=0) May 06 20:17:06 volumio sudo[1366]: pam_unix(sudo:session): session opened for user root by (uid=0) May 06 20:17:06 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 20:17:06 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 06 20:17:06 volumio sudo[1364]: pam_unix(sudo:session): session closed for user root May 06 20:17:06 volumio volumio[1039]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 06 20:17:06 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 20:17:06 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 06 20:17:06 volumio volumio[1039]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 06 20:17:06 volumio volumio[1039]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 06 20:17:06 volumio volumio[1039]: info: [1715019426892] CoreMusicLibrary::Adding element Last_100 May 06 20:17:06 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 06 20:17:06 volumio volumio[1039]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 06 20:17:06 volumio volumio[1039]: info: [1715019426893] CoreMusicLibrary::Adding element Webradio May 06 20:17:06 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 06 20:17:06 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 06 20:17:06 volumio volumio[1039]: info: Volumio Calling Home May 06 20:17:06 volumio systemd[1]: Listening on mpd.socket. May 06 20:17:06 volumio volumio[1039]: verbose: New Socket.io Connection to 192.168.0.53 from 192.168.0.199 UA: okhttp/4.9.2 Total Clients: 12 May 06 20:17:06 volumio systemd[1]: Starting Music Player Daemon... May 06 20:17:06 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM May 06 20:17:06 volumio systemd[1]: mpd.service: Succeeded. May 06 20:17:06 volumio systemd[1]: Stopped Music Player Daemon. May 06 20:17:06 volumio systemd[1]: Starting Music Player Daemon... May 06 20:17:06 volumio volumio[1039]: info: CoreStateMachine::setRepeat null single undefined May 06 20:17:06 volumio volumio[1039]: info: CoreStateMachine::pushState May 06 20:17:06 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 May 06 20:17:06 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState May 06 20:17:06 volumio volumio[1039]: info: CoreStateMachine::setRandom null May 06 20:17:06 volumio volumio[1039]: info: CoreStateMachine::pushState May 06 20:17:06 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 May 06 20:17:06 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState May 06 20:17:06 volumio volumio[1039]: info: MPD Permissions set May 06 20:17:06 volumio volumio[1039]: info: MPD Permissions set May 06 20:17:06 volumio volumio[1039]: info: VolumeController:: Volume=100 Mute =false May 06 20:17:06 volumio volumio[1039]: info: CoreStateMachine::pushState May 06 20:17:06 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 May 06 20:17:06 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState May 06 20:17:06 volumio volumio[1039]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 13 May 06 20:17:06 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 20:17:06 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 20:17:06 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 20:17:06 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 20:17:06 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 20:17:06 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 20:17:06 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 20:17:07 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 20:17:07 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 06 20:17:07 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 06 20:17:07 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 20:17:07 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 20:17:07 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 20:17:07 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 20:17:07 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 20:17:07 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 20:17:07 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 20:17:07 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 20:17:07 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 20:17:07 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 06 20:17:07 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 06 20:17:07 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 20:17:07 volumio volumio[1039]: info: Discovery: adding 5f519feb-1935-4e7d-b41f-4ce389f1755c May 06 20:17:07 volumio volumio[1039]: info: Discovery: Found device Volumio May 06 20:17:07 volumio volumio[1039]: info: CoreCommandRouter::volumioGetState May 06 20:17:07 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 May 06 20:17:07 volumio volumio[1039]: info: Discovery: this is already registered, 5f519feb-1935-4e7d-b41f-4ce389f1755c May 06 20:17:07 volumio volumio[1039]: info: Discovery: Found device Volumio May 06 20:17:07 volumio volumio[1039]: info: CoreCommandRouter::volumioGetState May 06 20:17:07 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 May 06 20:17:07 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 06 20:17:07 volumio volumio[1039]: info: Volumio called home May 06 20:17:07 volumio volumio[1039]: info: Starting Shairport Sync May 06 20:17:07 volumio volumio[1039]: info: Starting Shairport Sync May 06 20:17:07 volumio volumio[1039]: info: Starting Shairport Sync May 06 20:17:07 volumio sudo[1399]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 06 20:17:07 volumio sudo[1397]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 06 20:17:07 volumio sudo[1399]: pam_unix(sudo:session): session opened for user root by (uid=0) May 06 20:17:07 volumio sudo[1397]: pam_unix(sudo:session): session opened for user root by (uid=0) May 06 20:17:07 volumio sudo[1395]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 06 20:17:07 volumio sudo[1395]: pam_unix(sudo:session): session opened for user root by (uid=0) May 06 20:17:07 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 06 20:17:07 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 06 20:17:07 volumio volumio[1039]: info: CoreCommandRouter::volumioGetVisibleSources May 06 20:17:07 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 06 20:17:07 volumio volumio[1039]: info: CoreCommandRouter::volumioGetState May 06 20:17:07 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 May 06 20:17:07 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 06 20:17:07 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 06 20:17:07 volumio volumio[1039]: info: Received Get System Info May 06 20:17:07 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 06 20:17:07 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 06 20:17:07 volumio volumio[1039]: info: Discovery: Getting this device information May 06 20:17:07 volumio volumio[1039]: info: CoreCommandRouter::volumioGetState May 06 20:17:07 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 May 06 20:17:07 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 06 20:17:07 volumio volumio[1039]: info: CoreCommandRouter::volumioGetState May 06 20:17:07 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 May 06 20:17:07 volumio volumio[1039]: info: Listing playlists May 06 20:17:07 volumio volumio[1039]: info: CoreCommandRouter::volumioGetQueue May 06 20:17:07 volumio volumio[1039]: info: CoreStateMachine::getQueue May 06 20:17:07 volumio volumio[1039]: info: CorePlayQueue::getQueue May 06 20:17:07 volumio volumio[1039]: info: Received Get System Info May 06 20:17:07 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 06 20:17:07 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 06 20:17:07 volumio volumio[1039]: info: Discovery: Getting this device information May 06 20:17:07 volumio volumio[1039]: info: CoreCommandRouter::volumioGetState May 06 20:17:07 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 May 06 20:17:07 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 06 20:17:07 volumio volumio[1039]: info: CoreCommandRouter::volumioGetState May 06 20:17:07 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 May 06 20:17:07 volumio systemd[1]: shairport-sync.service: Succeeded. May 06 20:17:07 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 06 20:17:07 volumio volumio[1039]: info: Received Get System Info May 06 20:17:07 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 06 20:17:07 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 06 20:17:07 volumio volumio[1039]: info: Discovery: Getting this device information May 06 20:17:07 volumio volumio[1039]: info: CoreCommandRouter::volumioGetState May 06 20:17:07 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 May 06 20:17:07 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 06 20:17:07 volumio volumio[1039]: info: CoreCommandRouter::volumioGetState May 06 20:17:07 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 May 06 20:17:07 volumio volumio[1039]: info: Received Get System Info May 06 20:17:07 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 06 20:17:07 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 06 20:17:07 volumio volumio[1039]: info: Discovery: Getting this device information May 06 20:17:07 volumio volumio[1039]: info: CoreCommandRouter::volumioGetState May 06 20:17:07 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 May 06 20:17:07 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 06 20:17:07 volumio volumio[1039]: info: CoreCommandRouter::volumioGetState May 06 20:17:07 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 May 06 20:17:07 volumio volumio[1039]: info: CoreCommandRouter::volumioGetState May 06 20:17:07 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 May 06 20:17:07 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 06 20:17:07 volumio sudo[1399]: pam_unix(sudo:session): session closed for user root May 06 20:17:07 volumio sudo[1397]: pam_unix(sudo:session): session closed for user root May 06 20:17:07 volumio sudo[1395]: pam_unix(sudo:session): session closed for user root May 06 20:17:07 volumio volumio[1039]: info: Shairport-Sync Started May 06 20:17:07 volumio volumio[1039]: Error adding Membership: Error: addMembership EINVAL May 06 20:17:07 volumio volumio[1039]: info: Shairport-Sync Started May 06 20:17:07 volumio volumio[1039]: info: Shairport-Sync Started May 06 20:17:08 volumio volumio-remote-updater[610]: [2024-05-06 20:17:08] [connect] Successful connection May 06 20:17:08 volumio volumio-remote-updater[610]: [2024-05-06 20:17:08] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1715019428 101 May 06 20:17:08 volumio volumio[1039]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 14 May 06 20:17:09 volumio ntpd[1028]: receive: Unexpected origin timestamp 0xe9e39d25.457a0a81 does not match aorg 0000000000.00000000 from server@176.9.157.155 xmt 0xe9e39d25.90e91e18 May 06 20:17:09 volumio ntpd[1028]: receive: Unexpected origin timestamp 0xe9e39d25.4577c81f does not match aorg 0000000000.00000000 from server@78.47.118.0 xmt 0xe9e39d25.90bb1eeb May 06 20:17:09 volumio ntpd[1028]: receive: Unexpected origin timestamp 0xe9e39d25.457c9076 does not match aorg 0000000000.00000000 from server@5.9.19.62 xmt 0xe9e39d25.90da4d8a May 06 20:17:09 volumio ntpd[1028]: receive: Unexpected origin timestamp 0xe9e39d25.457b232d does not match aorg 0000000000.00000000 from server@75.119.140.230 xmt 0xe9e39d25.90cd5a47 May 06 20:17:09 volumio ntpd[1028]: receive: Unexpected origin timestamp 0xe9e39d25.457c0eff does not match aorg 0000000000.00000000 from server@212.18.3.19 xmt 0xe9e39d25.91248824 May 06 20:17:09 volumio ntpd[1028]: receive: Unexpected origin timestamp 0xe9e39d25.457d0aeb does not match aorg 0000000000.00000000 from server@45.142.247.74 xmt 0xe9e39d25.91b8c4e5 May 06 20:17:09 volumio sudo[1223]: pam_unix(sudo:session): session closed for user root May 06 20:17:10 volumio mpd[1380]: May 06 20:17 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 06 20:17:10 volumio systemd[1]: Started Music Player Daemon. May 06 20:17:10 volumio sudo[1366]: pam_unix(sudo:session): session closed for user root May 06 20:17:10 volumio sudo[1357]: pam_unix(sudo:session): session closed for user root May 06 20:17:10 volumio volumio[1039]: info: Completed starting Core Plugins May 06 20:17:10 volumio volumio[1039]: info: ------------------------------------------- May 06 20:17:10 volumio volumio[1039]: info: ----- MyVolumio plugins startup ---- May 06 20:17:10 volumio volumio[1039]: info: ------------------------------------------- May 06 20:17:10 volumio volumio[1039]: info: [MyVolumio PluginManager] Fetching plans data.... May 06 20:17:10 volumio volumio[1039]: info: MPD running with PID1380 May 06 20:17:10 volumio volumio[1039]: ,establishing connection May 06 20:17:10 volumio volumio[1039]: error: MPD error: The expression evaluated to a falsy value: May 06 20:17:10 volumio volumio[1039]: assert.ok(self.idling) May 06 20:17:10 volumio volumio[1039]: error: The expression evaluated to a falsy value: May 06 20:17:10 volumio volumio[1039]: assert.ok(self.idling) May 06 20:17:10 volumio volumio[1039]: error: MPD error: The expression evaluated to a falsy value: May 06 20:17:10 volumio volumio[1039]: assert.ok(self.idling) May 06 20:17:10 volumio volumio[1039]: error: The expression evaluated to a falsy value: May 06 20:17:10 volumio volumio[1039]: assert.ok(self.idling) May 06 20:17:10 volumio volumio[1039]: error: updateQueue error: null May 06 20:17:11 volumio dbus-daemon[605]: [system] Activating via systemd: service name='org.bluez' unit='dbus-org.bluez.service' requested by ':1.12' (uid=0 pid=1271 comm="/usr/lib/chromium/chromium --show-component-extens") May 06 20:17:11 volumio systemd[1]: Starting Bluetooth service... May 06 20:17:11 volumio bluetoothd[1425]: Bluetooth daemon 5.50 May 06 20:17:11 volumio kernel: Bluetooth: Core ver 2.22 May 06 20:17:11 volumio kernel: NET: Registered PF_BLUETOOTH protocol family May 06 20:17:11 volumio kernel: Bluetooth: HCI device and connection manager initialized May 06 20:17:11 volumio kernel: Bluetooth: HCI socket layer initialized May 06 20:17:11 volumio kernel: Bluetooth: L2CAP socket layer initialized May 06 20:17:11 volumio kernel: Bluetooth: SCO socket layer initialized May 06 20:17:12 volumio bluetoothd[1425]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf May 06 20:17:12 volumio dbus-daemon[605]: [system] Successfully activated service 'org.bluez' May 06 20:17:12 volumio systemd[1]: Started Bluetooth service. May 06 20:17:12 volumio bluetoothd[1425]: Starting SDP server May 06 20:17:12 volumio bluetoothd[1425]: Excluding (cli) sap May 06 20:17:12 volumio kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 May 06 20:17:12 volumio kernel: Bluetooth: BNEP filters: protocol multicast May 06 20:17:12 volumio kernel: Bluetooth: BNEP socket layer initialized May 06 20:17:12 volumio dbus-daemon[605]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.13' (uid=0 pid=1425 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") May 06 20:17:12 volumio bluetoothd[1425]: Bluetooth management interface 1.22 initialized May 06 20:17:12 volumio systemd[1]: Starting Hostname Service... May 06 20:17:12 volumio dbus-daemon[605]: [system] Successfully activated service 'org.freedesktop.hostname1' May 06 20:17:12 volumio systemd[1]: Started Hostname Service. May 06 20:17:12 volumio kernel: [drm] PCIE gen 2 link speeds already enabled May 06 20:17:12 volumio kernel: [drm] PCIE GART of 2048M enabled (table at 0x0000000000040000). May 06 20:17:12 volumio kernel: radeon 0000:09:00.0: WB enabled May 06 20:17:12 volumio kernel: radeon 0000:09:00.0: fence driver on ring 0 use gpu addr 0x0000000080000c00 May 06 20:17:12 volumio kernel: radeon 0000:09:00.0: fence driver on ring 1 use gpu addr 0x0000000080000c04 May 06 20:17:12 volumio kernel: radeon 0000:09:00.0: fence driver on ring 2 use gpu addr 0x0000000080000c08 May 06 20:17:12 volumio kernel: radeon 0000:09:00.0: fence driver on ring 3 use gpu addr 0x0000000080000c0c May 06 20:17:12 volumio kernel: radeon 0000:09:00.0: fence driver on ring 4 use gpu addr 0x0000000080000c10 May 06 20:17:12 volumio kernel: debugfs: File 'radeon_ring_gfx' in directory '0' already present! May 06 20:17:12 volumio kernel: debugfs: File 'radeon_ring_cp1' in directory '0' already present! May 06 20:17:12 volumio kernel: debugfs: File 'radeon_ring_cp2' in directory '0' already present! May 06 20:17:12 volumio kernel: debugfs: File 'radeon_ring_dma1' in directory '0' already present! May 06 20:17:12 volumio kernel: debugfs: File 'radeon_ring_dma2' in directory '0' already present! May 06 20:17:12 volumio kernel: [drm] ring test on 0 succeeded in 1 usecs May 06 20:17:12 volumio kernel: [drm] ring test on 1 succeeded in 1 usecs May 06 20:17:12 volumio kernel: [drm] ring test on 2 succeeded in 1 usecs May 06 20:17:12 volumio kernel: [drm] ring test on 3 succeeded in 4 usecs May 06 20:17:12 volumio kernel: [drm] ring test on 4 succeeded in 4 usecs May 06 20:17:12 volumio kernel: [drm] ib test on ring 0 succeeded in 0 usecs May 06 20:17:12 volumio kernel: [drm] ib test on ring 1 succeeded in 0 usecs May 06 20:17:12 volumio kernel: [drm] ib test on ring 2 succeeded in 0 usecs May 06 20:17:12 volumio kernel: [drm] ib test on ring 3 succeeded in 0 usecs May 06 20:17:12 volumio kernel: [drm] ib test on ring 4 succeeded in 0 usecs May 06 20:17:13 volumio volumio[1039]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Total Clients: 15 May 06 20:17:14 volumio volumio[1039]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Total Clients: 16 May 06 20:17:14 volumio sudo[1537]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 06 20:17:14 volumio sudo[1539]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 06 20:17:14 volumio sudo[1537]: pam_unix(sudo:session): session opened for user root by (uid=0) May 06 20:17:14 volumio sudo[1539]: pam_unix(sudo:session): session opened for user root by (uid=0) May 06 20:17:14 volumio sudo[1537]: pam_unix(sudo:session): session closed for user root May 06 20:17:14 volumio sudo[1539]: pam_unix(sudo:session): session closed for user root May 06 20:17:14 volumio sudo[1543]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 06 20:17:14 volumio sudo[1543]: pam_unix(sudo:session): session opened for user root by (uid=0) May 06 20:17:14 volumio systemd[1]: Started UPnP Renderer front-end to MPD. May 06 20:17:14 volumio sudo[1543]: pam_unix(sudo:session): session closed for user root May 06 20:17:14 volumio volumio[1039]: info: Upmpdcli Daemon Started May 06 20:17:14 volumio volumio[1545]: Generating RSA private key, 4096 bit long modulus (2 primes) May 06 20:17:14 volumio systemd[1]: systemd-fsckd.service: Succeeded. May 06 20:17:14 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 06 20:17:15 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 06 20:17:15 volumio volumio[1039]: info: CoreCommandRouter::volumioGetVisibleSources May 06 20:17:15 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 06 20:17:15 volumio volumio[1039]: info: CoreCommandRouter::volumioGetState May 06 20:17:15 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 May 06 20:17:15 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 06 20:17:15 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 06 20:17:15 volumio volumio[1039]: info: Received Get System Info May 06 20:17:15 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 06 20:17:15 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 06 20:17:15 volumio volumio[1039]: info: Discovery: Getting this device information May 06 20:17:15 volumio volumio[1039]: info: CoreCommandRouter::volumioGetState May 06 20:17:15 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 May 06 20:17:15 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 06 20:17:15 volumio volumio[1039]: info: CoreCommandRouter::volumioGetState May 06 20:17:15 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 May 06 20:17:15 volumio volumio[1039]: info: Listing playlists May 06 20:17:15 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 06 20:17:15 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 06 20:17:15 volumio volumio[1039]: info: CoreCommandRouter::volumioGetState May 06 20:17:15 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 May 06 20:17:15 volumio volumio[1039]: verbose: New Socket.io Connection to 192.168.0.53 from 192.168.0.199 UA: okhttp/4.9.2 Total Clients: 17 May 06 20:17:15 volumio volumio[1039]: info: CoreCommandRouter::volumioGetState May 06 20:17:15 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 May 06 20:17:15 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 06 20:17:15 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 06 20:17:15 volumio volumio[1039]: verbose: New Socket.io Connection to 192.168.0.248 from 192.168.0.199 UA: okhttp/4.9.2 Total Clients: 18 May 06 20:17:15 volumio volumio[1039]: info: CoreCommandRouter::volumioGetState May 06 20:17:15 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 May 06 20:17:15 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 06 20:17:15 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 06 20:17:15 volumio volumio[1039]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory May 06 20:17:15 volumio volumio[1545]: ..................................................................................................................................................................................................++++ May 06 20:17:15 volumio volumio[1545]: ...............++++ May 06 20:17:15 volumio volumio[1545]: e is 65537 (0x010001) May 06 20:17:15 volumio volumio[1545]: writing RSA key May 06 20:17:16 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 06 20:17:16 volumio volumio[1039]: info: Received Get System Info May 06 20:17:16 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 06 20:17:16 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 06 20:17:16 volumio volumio[1039]: info: Discovery: Getting this device information May 06 20:17:16 volumio volumio[1039]: info: CoreCommandRouter::volumioGetState May 06 20:17:16 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 May 06 20:17:16 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 06 20:17:19 volumio volumio[1039]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium May 06 20:17:19 volumio volumio[1039]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium May 06 20:17:19 volumio volumio[1039]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium May 06 20:17:19 volumio volumio[1039]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium May 06 20:17:19 volumio volumio[1039]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium May 06 20:17:19 volumio volumio[1039]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium May 06 20:17:19 volumio volumio[1039]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium May 06 20:17:19 volumio volumio[1039]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium May 06 20:17:19 volumio volumio[1039]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium May 06 20:17:19 volumio volumio[1039]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium May 06 20:17:19 volumio volumio[1039]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar May 06 20:17:19 volumio volumio[1039]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar May 06 20:17:19 volumio volumio[1039]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar May 06 20:17:19 volumio volumio[1039]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar May 06 20:17:19 volumio volumio[1039]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar May 06 20:17:19 volumio volumio[1039]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar May 06 20:17:19 volumio volumio[1039]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar May 06 20:17:19 volumio volumio[1039]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar May 06 20:17:19 volumio volumio[1039]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar May 06 20:17:19 volumio volumio[1039]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar May 06 20:17:19 volumio volumio[1039]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso May 06 20:17:19 volumio volumio[1039]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso May 06 20:17:19 volumio volumio[1039]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso May 06 20:17:19 volumio volumio[1039]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso May 06 20:17:19 volumio volumio[1039]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso May 06 20:17:19 volumio volumio[1039]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso May 06 20:17:19 volumio volumio[1039]: info: Adding plugin bluetooth to MyMusic Plugins May 06 20:17:19 volumio volumio[1039]: info: Adding plugin multiroom to MyMusic Plugins May 06 20:17:19 volumio volumio[1039]: info: Adding plugin metavolumio to MyMusic Plugins May 06 20:17:19 volumio volumio[1039]: info: Adding plugin cd_controller to MyMusic Plugins May 06 20:17:19 volumio volumio[1039]: info: Adding plugin smart_inputs to MyMusic Plugins May 06 20:17:19 volumio volumio[1039]: info: Adding plugin tidalconnect to MyMusic Plugins May 06 20:17:19 volumio volumio[1039]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... May 06 20:17:19 volumio volumio[1039]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded May 06 20:17:19 volumio volumio[1039]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio May 06 20:17:19 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 06 20:17:19 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 06 20:17:19 volumio volumio[1039]: info: Starting MyVolumio Remote Streaming Endpoints May 06 20:17:19 volumio volumio[1039]: info: MyVolumio login type: Token May 06 20:17:19 volumio volumio[1039]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started May 06 20:17:19 volumio volumio[1039]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... May 06 20:17:20 volumio volumio[1039]: info: Starting Streaming Service Transparent Proxy May 06 20:17:20 volumio volumio[1039]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded May 06 20:17:20 volumio volumio[1039]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services May 06 20:17:20 volumio volumio[1039]: info: Streaming services startup May 06 20:17:20 volumio volumio[1039]: info: Starting Streaming Daemon May 06 20:17:20 volumio sudo[1583]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 06 20:17:20 volumio sudo[1583]: pam_unix(sudo:session): session opened for user root by (uid=0) May 06 20:17:20 volumio volumio[1039]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started May 06 20:17:20 volumio sudo[1583]: pam_unix(sudo:session): session closed for user root May 06 20:17:20 volumio volumio[1039]: error: Cannot start Volumio Streaming Daemon May 06 20:17:20 volumio volumio[1039]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 06 20:17:20 volumio volumio[1039]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 06 20:17:20 volumio volumio[1039]: STREAMING PROXY: Starting server on port 3245 May 06 20:17:20 volumio volumio[1039]: Node JS runtime: 14 May 06 20:17:20 volumio volumio[1039]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN May 06 20:17:22 volumio volumio[1039]: info: MyVolumio token set successfully May 06 20:17:22 volumio volumio[1039]: info: MYVOLUMIO: Adding device May 06 20:17:22 volumio volumio[1039]: info: MYVOLUMIO: Evaluating Server May 06 20:17:22 volumio volumio[1039]: info: MyVolumio status changed May 06 20:17:22 volumio volumio[1039]: info: Streaming services startup May 06 20:17:22 volumio volumio[1039]: info: Starting Streaming Daemon May 06 20:17:22 volumio volumio[1039]: info: Removing browser output: myVolumio user plan is not superstar May 06 20:17:22 volumio volumio[1039]: info: Removing audio output: May 06 20:17:22 volumio volumio[1039]: info: Stoppping Tunnel 1 May 06 20:17:22 volumio sudo[1790]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 06 20:17:22 volumio sudo[1790]: pam_unix(sudo:session): session opened for user root by (uid=0) May 06 20:17:22 volumio sudo[1790]: pam_unix(sudo:session): session closed for user root May 06 20:17:22 volumio volumio[1039]: error: Cannot start Volumio Streaming Daemon May 06 20:17:22 volumio sudo[1793]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service May 06 20:17:22 volumio volumio[1039]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 06 20:17:22 volumio volumio[1039]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 06 20:17:22 volumio sudo[1793]: pam_unix(sudo:session): session opened for user root by (uid=0) May 06 20:17:22 volumio sudo[1793]: pam_unix(sudo:session): session closed for user root May 06 20:17:22 volumio volumio[1039]: info: Remote SSH Stopped May 06 20:17:23 volumio volumio[1039]: info: Setting Geolocation for MyVolumio to eu10 May 06 20:17:23 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 06 20:17:23 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 06 20:17:23 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 06 20:17:23 volumio volumio[1039]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} May 06 20:17:24 volumio volumio[1039]: info: Updating MyVolumio device info May 06 20:17:24 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 06 20:17:24 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 06 20:17:24 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 06 20:17:24 volumio volumio[1039]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} May 06 20:17:27 volumio volumio[1039]: info: MYVOLUMIO: Adding device May 06 20:17:27 volumio volumio[1039]: info: MYVOLUMIO: Evaluating Server May 06 20:17:28 volumio volumio[1039]: info: Setting Geolocation for MyVolumio to eu6 May 06 20:17:28 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 06 20:17:28 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 06 20:17:28 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 06 20:17:29 volumio volumio[1039]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} May 06 20:17:29 volumio volumio[1039]: info: Updating MyVolumio device info May 06 20:17:29 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 06 20:17:29 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 06 20:17:29 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 06 20:17:30 volumio volumio[1039]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} May 06 20:17:32 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 06 20:17:32 volumio volumio[1039]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 06 20:17:32 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 06 20:17:32 volumio volumio[1039]: info: Received Get System Version May 06 20:17:32 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 06 20:17:32 volumio volumio[1039]: info: Received Get System Info May 06 20:17:32 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 06 20:17:32 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 06 20:17:32 volumio volumio[1039]: info: Discovery: Getting this device information May 06 20:17:32 volumio volumio[1039]: info: CoreCommandRouter::volumioGetState May 06 20:17:32 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 May 06 20:17:32 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 06 20:17:34 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 06 20:17:34 volumio volumio[1039]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 19 May 06 20:17:34 volumio volumio[1039]: info: CoreCommandRouter::volumioGetState May 06 20:17:34 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 May 06 20:17:37 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 06 20:17:37 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 06 20:17:37 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 06 20:17:37 volumio volumio-remote-updater[610]: No test mode May 06 20:17:37 volumio volumio-remote-updater[610]: No alpha test mode May 06 20:17:37 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled May 06 20:17:37 volumio volumio[1039]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} May 06 20:17:37 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 06 20:17:39 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 20:17:39 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 20:17:39 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam May 06 20:17:39 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 06 20:17:39 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject May 06 20:17:39 volumio volumio[1039]: info: CoreCommandRouter::volumioGetBrowseSources May 06 20:17:39 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 06 20:17:40 volumio volumio[1039]: error: MyVolumio Plugin failed to authenticate in a timely fashion May 06 20:17:40 volumio volumio[1039]: info: Completed starting MyVolumio Plugin May 06 20:17:40 volumio volumio[1039]: info: BOOT COMPLETED May 06 20:17:40 volumio volumio[1039]: [Metrics] CommandRouter: 38s 659.73ms May 06 20:17:40 volumio volumio[1039]: info: CoreCommandRouter::volumiosetStartupVolume May 06 20:17:40 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 20:17:40 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 06 20:17:40 volumio volumio[1039]: info: CoreCommandRouter::Close All Modals sent May 06 20:17:40 volumio volumio[1039]: info: CoreCommandRouter::Close All Modals sent May 06 20:17:41 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable May 06 20:17:41 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 06 20:17:41 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect May 06 20:17:42 volumio systemd[1]: systemd-hostnamed.service: Succeeded. May 06 20:17:58 volumio volumio[1039]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/volumio/buster/amd64 May 06 20:18:01 volumio volumio[1039]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/volumio/buster/amd64 May 06 20:18:01 volumio volumio[1039]: info: Folder /tmp/plugins removed May 06 20:18:01 volumio volumio[1039]: info: Check plugin dependencies May 06 20:18:01 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 06 20:18:01 volumio volumio[1039]: info: Checking if plugin already exists May 06 20:18:01 volumio volumio[1039]: info: Rename folder May 06 20:18:01 volumio volumio[1039]: info: Folder /tmp/downloaded_plugin.zip removed May 06 20:18:01 volumio volumio[1039]: info: Move to category May 06 20:18:03 volumio volumio[1039]: info: Checking if install.sh is present May 06 20:18:03 volumio volumio[1039]: info: Executing install.sh May 06 20:18:03 volumio sudo[1874]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/audio_interface/fusiondsp/install.sh May 06 20:18:03 volumio sudo[1874]: pam_unix(sudo:session): session opened for user root by (uid=0) May 06 20:18:03 volumio volumio[1039]: info: creating filters folder and copying demo filters May 06 20:18:03 volumio volumio[1039]: info: copying demo flters May 06 20:18:03 volumio volumio[1039]: info: Installing/fusiondsp dependencies May 06 20:18:03 volumio sudo[1895]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/apt update May 06 20:18:03 volumio sudo[1895]: pam_unix(sudo:session): session opened for user root by (uid=0) May 06 20:18:03 volumio volumio[1039]: info: Get:1 http://deb.debian.org/debian buster InRelease [122 kB] May 06 20:18:03 volumio volumio[1039]: info: Get:2 https://deb.nodesource.com/node_14.x buster InRelease [4584 B] May 06 20:18:04 volumio volumio[1039]: info: Get:3 http://deb.debian.org/debian buster/main amd64 Packages [7909 kB] May 06 20:18:04 volumio volumio[1039]: info: Get:4 https://deb.nodesource.com/node_14.x buster/main amd64 Packages [776 B] May 06 20:18:07 volumio volumio[1039]: info: Get:5 http://deb.debian.org/debian buster/main i386 Packages [7866 kB] May 06 20:18:10 volumio volumio[1039]: info: Get:6 http://deb.debian.org/debian buster/main Translation-en [5969 kB] May 06 20:18:11 volumio volumio[1039]: info: Get:7 http://deb.debian.org/debian buster/contrib amd64 Packages [50.1 kB] May 06 20:18:11 volumio volumio[1039]: info: Get:8 http://deb.debian.org/debian buster/contrib i386 Packages [46.0 kB] May 06 20:18:11 volumio volumio[1039]: info: Get:9 http://deb.debian.org/debian buster/contrib Translation-en [44.2 kB] May 06 20:18:11 volumio volumio[1039]: info: Get:10 http://deb.debian.org/debian buster/non-free i386 Packages [76.3 kB] May 06 20:18:11 volumio volumio[1039]: info: Get:11 http://deb.debian.org/debian buster/non-free amd64 Packages [87.8 kB] May 06 20:18:11 volumio volumio[1039]: info: Get:12 http://deb.debian.org/debian buster/non-free Translation-en [88.9 kB] May 06 20:18:13 volumio volumio[1039]: info: Fetched 22.3 MB in 10s (2252 kB/s) May 06 20:18:14 volumio volumio[1039]: info: Reading package lists... May 06 20:18:14 volumio volumio[1039]: info: Building dependency tree... May 06 20:18:14 volumio volumio[1039]: info: Reading state information... May 06 20:18:14 volumio volumio[1039]: info: All packages are up to date. May 06 20:18:14 volumio sudo[1895]: pam_unix(sudo:session): session closed for user root May 06 20:18:15 volumio sudo[2112]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/apt -y install python3-aiohttp python3-pip May 06 20:18:15 volumio sudo[2112]: pam_unix(sudo:session): session opened for user root by (uid=0) May 06 20:18:15 volumio volumio[1039]: info: Reading package lists... May 06 20:18:15 volumio volumio[1039]: info: Building dependency tree... May 06 20:18:15 volumio volumio[1039]: info: Reading state information... May 06 20:18:15 volumio volumio[1039]: info: The following additional packages will be installed: May 06 20:18:15 volumio volumio[1039]: info: binutils binutils-common binutils-x86-64-linux-gnu build-essential bzip2 May 06 20:18:15 volumio volumio[1039]: info: dh-python dpkg-dev fakeroot g++ g++-8 gcc gcc-8 libalgorithm-diff-perl May 06 20:18:15 volumio volumio[1039]: info: libalgorithm-diff-xs-perl libalgorithm-merge-perl libasan5 libbinutils May 06 20:18:15 volumio volumio[1039]: info: libcc1-0 libexpat1-dev libfakeroot libgcc-8-dev libitm1 liblsan0 libmpx2 May 06 20:18:15 volumio volumio[1039]: info: libpython3-dev libpython3.7 libpython3.7-dev libquadmath0 libstdc++-8-dev May 06 20:18:15 volumio volumio[1039]: info: libtsan0 libubsan1 make patch python-pip-whl python3-asn1crypto May 06 20:18:15 volumio volumio[1039]: info: python3-async-timeout python3-attr python3-cffi-backend python3-crypto May 06 20:18:15 volumio volumio[1039]: info: python3-cryptography python3-dbus python3-dev python3-entrypoints python3-gi May 06 20:18:15 volumio volumio[1039]: info: python3-keyring python3-keyrings.alt python3-multidict python3-secretstorage May 06 20:18:15 volumio volumio[1039]: info: python3-setuptools python3-wheel python3-xdg python3-yarl python3.7-dev May 06 20:18:15 volumio volumio[1039]: info: Suggested packages: May 06 20:18:15 volumio volumio[1039]: info: binutils-doc bzip2-doc debian-keyring g++-multilib g++-8-multilib gcc-8-doc May 06 20:18:15 volumio volumio[1039]: info: libstdc++6-8-dbg gcc-multilib autoconf automake libtool flex bison gdb May 06 20:18:15 volumio volumio[1039]: info: gcc-doc gcc-8-multilib gcc-8-locales libgcc1-dbg libgomp1-dbg libitm1-dbg May 06 20:18:15 volumio volumio[1039]: info: libatomic1-dbg libasan5-dbg liblsan0-dbg libtsan0-dbg libubsan1-dbg May 06 20:18:15 volumio volumio[1039]: info: libmpx2-dbg libquadmath0-dbg libstdc++-8-doc make-doc ed diffutils-doc May 06 20:18:15 volumio volumio[1039]: info: python-attr-doc python-crypto-doc python-cryptography-doc May 06 20:18:15 volumio volumio[1039]: info: python3-cryptography-vectors python-dbus-doc python3-dbus-dbg gnome-keyring May 06 20:18:15 volumio volumio[1039]: info: libkf5wallet-bin gir1.2-gnomekeyring-1.0 python-secretstorage-doc May 06 20:18:15 volumio volumio[1039]: info: python-setuptools-doc May 06 20:18:15 volumio volumio[1039]: info: The following NEW packages will be installed: May 06 20:18:15 volumio volumio[1039]: info: binutils binutils-common binutils-x86-64-linux-gnu build-essential bzip2 May 06 20:18:15 volumio volumio[1039]: info: dh-python dpkg-dev fakeroot g++ g++-8 gcc gcc-8 libalgorithm-diff-perl May 06 20:18:15 volumio volumio[1039]: info: libalgorithm-diff-xs-perl libalgorithm-merge-perl libasan5 libbinutils May 06 20:18:15 volumio volumio[1039]: info: libcc1-0 libexpat1-dev libfakeroot libgcc-8-dev libitm1 liblsan0 libmpx2 May 06 20:18:15 volumio volumio[1039]: info: libpython3-dev libpython3.7 libpython3.7-dev libquadmath0 libstdc++-8-dev May 06 20:18:15 volumio volumio[1039]: info: libtsan0 libubsan1 make patch python-pip-whl python3-aiohttp May 06 20:18:15 volumio volumio[1039]: info: python3-asn1crypto python3-async-timeout python3-attr python3-cffi-backend May 06 20:18:15 volumio volumio[1039]: info: python3-crypto python3-cryptography python3-dbus python3-dev May 06 20:18:15 volumio volumio[1039]: info: python3-entrypoints python3-gi python3-keyring python3-keyrings.alt May 06 20:18:15 volumio volumio[1039]: info: python3-multidict python3-pip python3-secretstorage python3-setuptools May 06 20:18:15 volumio volumio[1039]: info: python3-wheel python3-xdg python3-yarl python3.7-dev May 06 20:18:15 volumio volumio[1039]: info: 0 upgraded, 55 newly installed, 0 to remove and 0 not upgraded. May 06 20:18:15 volumio volumio[1039]: info: Need to get 85.3 MB of archives. May 06 20:18:15 volumio volumio[1039]: info: After this operation, 227 MB of additional disk space will be used. May 06 20:18:15 volumio volumio[1039]: info: Get:1 http://deb.debian.org/debian buster/main amd64 bzip2 amd64 1.0.6-9.2~deb10u1 [48.4 kB] May 06 20:18:15 volumio volumio[1039]: info: Get:2 http://deb.debian.org/debian buster/main amd64 binutils-common amd64 2.31.1-16 [2073 kB] May 06 20:18:16 volumio volumio[1039]: info: Get:3 http://deb.debian.org/debian buster/main amd64 libbinutils amd64 2.31.1-16 [478 kB] May 06 20:18:16 volumio volumio[1039]: info: Get:4 http://deb.debian.org/debian buster/main amd64 binutils-x86-64-linux-gnu amd64 2.31.1-16 [1823 kB] May 06 20:18:16 volumio volumio[1039]: info: Get:5 http://deb.debian.org/debian buster/main amd64 binutils amd64 2.31.1-16 [56.8 kB] May 06 20:18:16 volumio volumio[1039]: info: Get:6 http://deb.debian.org/debian buster/main amd64 libcc1-0 amd64 8.3.0-6 [46.6 kB] May 06 20:18:16 volumio volumio[1039]: info: Get:7 http://deb.debian.org/debian buster/main amd64 libitm1 amd64 8.3.0-6 [27.7 kB] May 06 20:18:16 volumio volumio[1039]: info: Get:8 http://deb.debian.org/debian buster/main amd64 libasan5 amd64 8.3.0-6 [362 kB] May 06 20:18:17 volumio volumio[1039]: info: Get:9 http://deb.debian.org/debian buster/main amd64 liblsan0 amd64 8.3.0-6 [131 kB] May 06 20:18:17 volumio volumio[1039]: info: Get:10 http://deb.debian.org/debian buster/main amd64 libtsan0 amd64 8.3.0-6 [283 kB] May 06 20:18:17 volumio volumio[1039]: info: Get:11 http://deb.debian.org/debian buster/main amd64 libubsan1 amd64 8.3.0-6 [120 kB] May 06 20:18:17 volumio volumio[1039]: info: Get:12 http://deb.debian.org/debian buster/main amd64 libmpx2 amd64 8.3.0-6 [11.4 kB] May 06 20:18:17 volumio volumio[1039]: info: Get:13 http://deb.debian.org/debian buster/main amd64 libquadmath0 amd64 8.3.0-6 [133 kB] May 06 20:18:17 volumio volumio[1039]: info: Get:14 http://deb.debian.org/debian buster/main amd64 libgcc-8-dev amd64 8.3.0-6 [2298 kB] May 06 20:18:17 volumio volumio[1039]: info: Get:15 http://deb.debian.org/debian buster/main amd64 gcc-8 amd64 8.3.0-6 [9452 kB] May 06 20:18:20 volumio volumio[1039]: info: Get:16 http://deb.debian.org/debian buster/main amd64 gcc amd64 4:8.3.0-1 [5196 B] May 06 20:18:20 volumio volumio[1039]: info: Get:17 http://deb.debian.org/debian buster/main amd64 libstdc++-8-dev amd64 8.3.0-6 [1532 kB] May 06 20:18:20 volumio volumio[1039]: info: Get:18 http://deb.debian.org/debian buster/main amd64 g++-8 amd64 8.3.0-6 [9752 kB] May 06 20:18:23 volumio volumio[1039]: info: Get:19 http://deb.debian.org/debian buster/main amd64 g++ amd64 4:8.3.0-1 [1644 B] May 06 20:18:23 volumio volumio[1039]: info: Get:20 http://deb.debian.org/debian buster/main amd64 make amd64 4.2.1-1.2 [341 kB] May 06 20:18:23 volumio volumio[1039]: info: Get:21 http://deb.debian.org/debian buster/main amd64 patch amd64 2.7.6-3+deb10u1 [126 kB] May 06 20:18:23 volumio volumio[1039]: info: Get:22 http://deb.debian.org/debian buster/main amd64 dpkg-dev all 1.19.8 [1776 kB] May 06 20:18:23 volumio volumio[1039]: info: Get:23 http://deb.debian.org/debian buster/main amd64 build-essential amd64 12.6 [7576 B] May 06 20:18:23 volumio volumio[1039]: info: Get:24 http://deb.debian.org/debian buster/main amd64 dh-python all 3.20190308 [99.3 kB] May 06 20:18:23 volumio volumio[1039]: info: Get:25 http://deb.debian.org/debian buster/main amd64 libfakeroot amd64 1.23-1 [45.9 kB] May 06 20:18:23 volumio volumio[1039]: info: Get:26 http://deb.debian.org/debian buster/main amd64 fakeroot amd64 1.23-1 [85.8 kB] May 06 20:18:23 volumio volumio[1039]: info: Get:27 http://deb.debian.org/debian buster/main amd64 libalgorithm-diff-perl all 1.19.03-2 [47.9 kB] May 06 20:18:23 volumio volumio[1039]: info: Get:28 http://deb.debian.org/debian buster/main amd64 libalgorithm-diff-xs-perl amd64 0.04-5+b1 [11.8 kB] May 06 20:18:23 volumio volumio[1039]: info: Get:29 http://deb.debian.org/debian buster/main amd64 libalgorithm-merge-perl all 0.08-3 [12.7 kB] May 06 20:18:23 volumio volumio[1039]: info: Get:30 http://deb.debian.org/debian buster/main amd64 libexpat1-dev amd64 2.2.6-2+deb10u4 [155 kB] May 06 20:18:23 volumio volumio[1039]: info: Get:31 http://deb.debian.org/debian buster/main amd64 libpython3.7 amd64 3.7.3-2+deb10u3 [1498 kB] May 06 20:18:23 volumio volumio[1039]: info: Get:32 http://deb.debian.org/debian buster/main amd64 libpython3.7-dev amd64 3.7.3-2+deb10u3 [48.4 MB] May 06 20:18:27 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 06 20:18:27 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 06 20:18:45 volumio volumio[1039]: info: Get:33 http://deb.debian.org/debian buster/main amd64 libpython3-dev amd64 3.7.3-1 [20.1 kB] May 06 20:18:45 volumio volumio[1039]: info: Get:34 http://deb.debian.org/debian buster/main amd64 python-pip-whl all 18.1-5 [1591 kB] May 06 20:18:45 volumio volumio[1039]: info: Get:35 http://deb.debian.org/debian buster/main amd64 python3-multidict amd64 4.5.2-1 [60.6 kB] May 06 20:18:45 volumio volumio[1039]: info: Get:36 http://deb.debian.org/debian buster/main amd64 python3-yarl amd64 1.3.0-1 [48.3 kB] May 06 20:18:45 volumio volumio[1039]: info: Get:37 http://deb.debian.org/debian buster/main amd64 python3-async-timeout all 3.0.1-1 [6292 B] May 06 20:18:45 volumio volumio[1039]: info: Get:38 http://deb.debian.org/debian buster/main amd64 python3-attr all 18.2.0-1 [37.3 kB] May 06 20:18:45 volumio volumio[1039]: info: Get:39 http://deb.debian.org/debian buster/main amd64 python3-aiohttp amd64 3.5.1-1+deb10u1 [261 kB] May 06 20:18:45 volumio volumio[1039]: info: Get:40 http://deb.debian.org/debian buster/main amd64 python3-asn1crypto all 0.24.0-1 [78.2 kB] May 06 20:18:45 volumio volumio[1039]: info: Get:41 http://deb.debian.org/debian buster/main amd64 python3-cffi-backend amd64 1.12.2-1 [79.7 kB] May 06 20:18:45 volumio volumio[1039]: info: Get:42 http://deb.debian.org/debian buster/main amd64 python3-crypto amd64 2.6.1-9+b1 [263 kB] May 06 20:18:45 volumio volumio[1039]: info: Get:43 http://deb.debian.org/debian buster/main amd64 python3-cryptography amd64 2.6.1-3+deb10u2 [219 kB] May 06 20:18:46 volumio volumio[1039]: info: Get:44 http://deb.debian.org/debian buster/main amd64 python3-dbus amd64 1.2.8-3 [103 kB] May 06 20:18:46 volumio volumio[1039]: info: Get:45 http://deb.debian.org/debian buster/main amd64 python3.7-dev amd64 3.7.3-2+deb10u3 [510 kB] May 06 20:18:46 volumio volumio[1039]: info: Get:46 http://deb.debian.org/debian buster/main amd64 python3-dev amd64 3.7.3-1 [1264 B] May 06 20:18:46 volumio volumio[1039]: info: Get:47 http://deb.debian.org/debian buster/main amd64 python3-entrypoints all 0.3-1 [5508 B] May 06 20:18:46 volumio volumio[1039]: info: Get:48 http://deb.debian.org/debian buster/main amd64 python3-gi amd64 3.30.4-1 [180 kB] May 06 20:18:46 volumio volumio[1039]: info: Get:49 http://deb.debian.org/debian buster/main amd64 python3-secretstorage all 2.3.1-2 [14.2 kB] May 06 20:18:46 volumio volumio[1039]: info: Get:50 http://deb.debian.org/debian buster/main amd64 python3-keyring all 17.1.1-1 [43.1 kB] May 06 20:18:46 volumio volumio[1039]: info: Get:51 http://deb.debian.org/debian buster/main amd64 python3-keyrings.alt all 3.1.1-1 [18.2 kB] May 06 20:18:46 volumio volumio[1039]: info: Get:52 http://deb.debian.org/debian buster/main amd64 python3-pip all 18.1-5 [171 kB] May 06 20:18:46 volumio volumio[1039]: info: Get:53 http://deb.debian.org/debian buster/main amd64 python3-setuptools all 40.8.0-1 [306 kB] May 06 20:18:46 volumio volumio[1039]: info: Get:54 http://deb.debian.org/debian buster/main amd64 python3-wheel all 0.32.3-2 [19.4 kB] May 06 20:18:46 volumio volumio[1039]: info: Get:55 http://deb.debian.org/debian buster/main amd64 python3-xdg all 0.25-5 [35.9 kB] May 06 20:18:49 volumio volumio[1039]: info: Fetched 85.3 MB in 31s (2728 kB/s) May 06 20:18:51 volumio volumio[1039]: info: Selecting previously unselected package bzip2. May 06 20:18:52 volumio volumio[1039]: [620B blob data] May 06 20:18:52 volumio volumio[1039]: info: Preparing to unpack .../00-bzip2_1.0.6-9.2~deb10u1_amd64.deb ... May 06 20:18:53 volumio volumio[1039]: info: Unpacking bzip2 (1.0.6-9.2~deb10u1) ... May 06 20:18:54 volumio volumio[1039]: info: Enabling plugin fusiondsp May 06 20:18:54 volumio volumio[1039]: info: Loading plugin "fusiondsp"... May 06 20:18:54 volumio volumio[1039]: info: Preparing to generate the ALSA configuration file May 06 20:18:54 volumio volumio[1039]: info: Asound.conf file unchanged, so no further update is needed May 06 20:18:54 volumio volumio[1039]: info: Output device has changed, restarting MPD May 06 20:18:54 volumio sudo[2211]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 06 20:18:54 volumio sudo[2211]: pam_unix(sudo:session): session opened for user root by (uid=0) May 06 20:18:54 volumio volumio[1039]: info: Output device has changed, restarting Shairport Sync May 06 20:18:54 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 20:18:54 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 06 20:18:54 volumio sudo[2211]: pam_unix(sudo:session): session closed for user root May 06 20:18:54 volumio sudo[2214]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 06 20:18:54 volumio sudo[2214]: pam_unix(sudo:session): session opened for user root by (uid=0) May 06 20:18:54 volumio systemd[1]: Stopping Music Player Daemon... May 06 20:18:54 volumio volumio[1039]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 06 20:18:54 volumio volumio[1039]: info: PLUGIN START: fusiondsp May 06 20:18:54 volumio volumio[1039]: info: Loading i18n strings for locale ru May 06 20:18:54 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile May 06 20:18:54 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 20:18:54 volumio volumio[1039]: info: FusionDsp - mixtype--------------------- Hardware May 06 20:18:54 volumio volumio[1039]: info: Preparing to generate the ALSA configuration file May 06 20:18:54 volumio volumio[1039]: info: Done. May 06 20:18:54 volumio volumio[1039]: info: MPD Permissions set May 06 20:18:54 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 20:18:54 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 20:18:54 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 20:18:54 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 20:18:54 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 20:18:54 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 20:18:54 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 20:18:54 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 20:18:54 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 06 20:18:54 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 06 20:18:54 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 20:18:54 volumio volumio[1039]: info: Asound.conf file unchanged, so no further update is needed May 06 20:18:54 volumio volumio[1039]: info: Output device has changed, restarting MPD May 06 20:18:54 volumio sudo[2226]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 06 20:18:54 volumio sudo[2226]: pam_unix(sudo:session): session opened for user root by (uid=0) May 06 20:18:54 volumio volumio[1039]: info: Output device has changed, restarting Shairport Sync May 06 20:18:54 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 20:18:54 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 06 20:18:54 volumio sudo[2226]: pam_unix(sudo:session): session closed for user root May 06 20:18:54 volumio sudo[2229]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 06 20:18:54 volumio sudo[2229]: pam_unix(sudo:session): session opened for user root by (uid=0) May 06 20:18:54 volumio volumio[1039]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 06 20:18:54 volumio volumio[1039]: info: CoreCommandRouter::getUIConfigOnPlugin May 06 20:18:54 volumio volumio[1039]: info: MPD Permissions set May 06 20:18:54 volumio volumio[1039]: info: Starting Shairport Sync May 06 20:18:54 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 20:18:54 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 20:18:54 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 20:18:54 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 20:18:54 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 20:18:54 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 20:18:54 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 20:18:54 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 20:18:54 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 06 20:18:54 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 06 20:18:54 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 20:18:54 volumio sudo[2235]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 06 20:18:54 volumio sudo[2235]: pam_unix(sudo:session): session opened for user root by (uid=0) May 06 20:18:54 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 06 20:18:54 volumio systemd[1]: shairport-sync.service: Succeeded. May 06 20:18:54 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 06 20:18:54 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 06 20:18:54 volumio volumio[1039]: info: Starting Shairport Sync May 06 20:18:54 volumio systemd[1]: mpd.service: Succeeded. May 06 20:18:54 volumio sudo[2235]: pam_unix(sudo:session): session closed for user root May 06 20:18:54 volumio systemd[1]: Stopped Music Player Daemon. May 06 20:18:54 volumio systemd[1]: Starting Music Player Daemon... May 06 20:18:54 volumio volumio[1039]: info: Shairport-Sync Started May 06 20:18:54 volumio sudo[2246]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 06 20:18:54 volumio sudo[2246]: pam_unix(sudo:session): session opened for user root by (uid=0) May 06 20:18:54 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 06 20:18:54 volumio systemd[1]: shairport-sync.service: Succeeded. May 06 20:18:54 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 06 20:18:54 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 06 20:18:54 volumio sudo[2246]: pam_unix(sudo:session): session closed for user root May 06 20:18:54 volumio volumio[1039]: info: Shairport-Sync Started May 06 20:18:55 volumio mpd[2244]: May 06 20:18 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 06 20:18:55 volumio systemd[1]: Started Music Player Daemon. May 06 20:18:55 volumio sudo[2214]: pam_unix(sudo:session): session closed for user root May 06 20:18:55 volumio sudo[2229]: pam_unix(sudo:session): session closed for user root May 06 20:18:55 volumio volumio[1039]: error: MPD error: The expression evaluated to a falsy value: May 06 20:18:55 volumio volumio[1039]: assert.ok(self.idling) May 06 20:18:55 volumio volumio[1039]: error: The expression evaluated to a falsy value: May 06 20:18:55 volumio volumio[1039]: assert.ok(self.idling) May 06 20:18:55 volumio volumio[1039]: error: updateQueue error: null May 06 20:18:56 volumio volumio[1039]: info: camilladsp spawned new process with pid undefined, instance 1, run: true May 06 20:18:56 volumio volumio[1039]: info: camilladsp service started and running in background, instance 1 May 06 20:18:56 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 06 20:18:56 volumio volumio[1039]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found May 06 20:18:56 volumio volumio[1039]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json May 06 20:18:56 volumio volumio[1039]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found May 06 20:18:56 volumio volumio[1039]: info: FusionDsp loaded May 06 20:18:56 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 06 20:18:56 volumio sudo[2265]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service May 06 20:18:56 volumio sudo[2265]: pam_unix(sudo:session): session opened for user root by (uid=0) May 06 20:18:56 volumio volumio[1039]: info: FusionDsp - Reporting Fusion DSP Enabled May 06 20:18:56 volumio volumio[1039]: info: Adding Signal Path Element [object Object] May 06 20:18:56 volumio volumio[1039]: info: Adding fusiondspeq DSP Signal Path Element May 06 20:18:56 volumio volumio[1039]: info: FusionDsp - ---- installed callbackRead May 06 20:18:56 volumio volumio[1039]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 06 20:18:56 volumio sudo[2265]: pam_unix(sudo:session): session closed for user root May 06 20:18:56 volumio volumio[1039]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT May 06 20:18:56 volumio volumio[1039]: at Process.ChildProcess._handle.onexit (internal/child_process.js:277:19) May 06 20:18:56 volumio volumio[1039]: at onErrorNT (internal/child_process.js:472:16) May 06 20:18:56 volumio volumio[1039]: at processTicksAndRejections (internal/process/task_queues.js:82:21) May 06 20:18:56 volumio volumio[1039]: at runNextTicks (internal/process/task_queues.js:64:3) May 06 20:18:56 volumio volumio[1039]: at listOnTimeout (internal/timers.js:526:9) May 06 20:18:56 volumio volumio[1039]: at processTimers (internal/timers.js:500:7) { May 06 20:18:56 volumio volumio[1039]: errno: -2, May 06 20:18:56 volumio volumio[1039]: code: 'ENOENT', May 06 20:18:56 volumio volumio[1039]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', May 06 20:18:56 volumio volumio[1039]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', May 06 20:18:56 volumio volumio[1039]: spawnargs: [ May 06 20:18:56 volumio volumio[1039]: '-p', May 06 20:18:56 volumio volumio[1039]: 9876, May 06 20:18:56 volumio volumio[1039]: '-o', May 06 20:18:56 volumio volumio[1039]: '/tmp/camilladsp.log', May 06 20:18:56 volumio volumio[1039]: '-l', May 06 20:18:56 volumio volumio[1039]: 'warn', May 06 20:18:56 volumio volumio[1039]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' May 06 20:18:56 volumio volumio[1039]: ] May 06 20:18:56 volumio volumio[1039]: } May 06 20:18:56 volumio volumio[1039]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 06 20:18:56 volumio sudo[2276]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-06 20:17 May 06 20:18:56 volumio sudo[2276]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="e7445fab547cd22e23d9bef72406cfb610bc1d7d" VOLUMIO_FE_VERSION="5d5e99f8f803b3ec5fc5e8ca13bb4023600f3eee" VOLUMIO_FE3_VERSION="a6ae210575eae1ff43f06ab71e1cd17576840623" VOLUMIO_BE_VERSION="a9e1e1dd6072ffb15c0fcb8938d9ea0d8900c0da" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 24 Apr 2024 02:42:03 PM CEST" VOLUMIO_VERSION="3.661" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="c434411fe8754b648dc7feb1d8f43761"