-- 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"