-- Logs begin at Thu 2019-02-14 13:11:58 MSK, end at Thu 2025-04-17 08:18:07 MSK. -- Apr 17 08:17:33 rivo systemd-timedated[3401]: Changed local time to Thu Apr 17 08:17:33 2025 Apr 17 08:17:33 rivo sudo[3399]: pam_unix(sudo:session): session closed for user root Apr 17 08:17:33 rivo volumio-time-update[2832]: volumio-time-update-util: System time updated successfully. Apr 17 08:17:33 rivo systemd[1]: Starting Daily apt download activities... Apr 17 08:17:33 rivo systemd[1]: Starting Daily man-db regeneration... Apr 17 08:17:33 rivo systemd[1]: Started Volumio Time Update Utility. Apr 17 08:17:33 rivo systemd[1]: man-db.service: Succeeded. Apr 17 08:17:33 rivo systemd[1]: Started Daily man-db regeneration. Apr 17 08:17:33 rivo wireless.js[2836]: trying... Apr 17 08:17:33 rivo sudo[3413]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 17 08:17:33 rivo sudo[3413]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 08:17:33 rivo sudo[3413]: pam_unix(sudo:session): session closed for user root Apr 17 08:17:33 rivo wireless.js[2836]: Connected to: ----Beeline_2G_F54974 Apr 17 08:17:33 rivo wireless.js[2836]: ---- Apr 17 08:17:33 rivo sudo[3418]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 17 08:17:33 rivo sudo[3418]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 08:17:33 rivo systemd[1]: systemd-rfkill.service: Succeeded. Apr 17 08:17:33 rivo sudo[3418]: pam_unix(sudo:session): session closed for user root Apr 17 08:17:33 rivo wireless.js[2836]: ... joined AP, wlan0 IPv4 is 192.168.1.102, ipV6 is undefined Apr 17 08:17:33 rivo wireless.js[2836]: It's done! AP Apr 17 08:17:33 rivo systemd[1]: Started Wireless Services. Apr 17 08:17:33 rivo systemd[1]: Started Volumio Backend Module. Apr 17 08:17:33 rivo systemd[1]: Started Start Volumio Kiosk. Apr 17 08:17:33 rivo systemd[1]: Started Volumio Cpu Tweaker. Apr 17 08:17:33 rivo systemd[1]: Reached target Multi-User System. Apr 17 08:17:33 rivo systemd[1]: Reached target Graphical Interface. Apr 17 08:17:33 rivo systemd[1]: Starting Update UTMP about System Runlevel Changes... Apr 17 08:17:33 rivo startkiosk.sh[3434]: Starting Volumio Kiosk with arguments for cursor display Apr 17 08:17:33 rivo systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Apr 17 08:17:33 rivo systemd[1]: Started Update UTMP about System Runlevel Changes. Apr 17 08:17:33 rivo volumio-cpu-tweak[3435]: Setting RT Priority for mpd Apr 17 08:17:33 rivo volumio-cpu-tweak[3435]: Setting MPD Affinity Apr 17 08:17:33 rivo volumio-cpu-tweak[3435]: pid 3082's current affinity mask: f Apr 17 08:17:33 rivo volumio-cpu-tweak[3435]: pid 3082's new affinity mask: 3 Apr 17 08:17:33 rivo volumio-cpu-tweak[3435]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Apr 17 08:17:33 rivo volumio-cpu-tweak[3435]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Apr 17 08:17:33 rivo systemd[1]: volumio_cpu_tweak.service: Succeeded. Apr 17 08:17:33 rivo ntpd[3396]: Soliciting pool server 176.215.15.21 Apr 17 08:17:33 rivo startkiosk.sh[3434]: X.Org X Server 1.20.4 Apr 17 08:17:33 rivo startkiosk.sh[3434]: X Protocol Version 11, Revision 0 Apr 17 08:17:33 rivo startkiosk.sh[3434]: Build Operating System: Linux 4.19.0-18-armmp-lpae armv7l Debian Apr 17 08:17:33 rivo startkiosk.sh[3434]: Current Operating System: Linux rivo 4.9.241 #2 SMP PREEMPT Sat Dec 14 12:27:06 CET 2024 aarch64 Apr 17 08:17:33 rivo startkiosk.sh[3434]: Kernel command line: imgpart=UUID=0722432b-4c57-4329-8771-932078164848 imgfile=/volumio_current.sqsh bootpart=UUID=0051-66CA datapart=UUID=476d5141-077c-4ba2-bced-9f80da8e8ed6 uuidconfig=env.system.txt hwdevice=VIMX console=tty0 console=ttyS0,115200n8 no_console_suspend consoleblank=0 logo=osd0,loaded,0x3d800000,panel vout=panel,enable hdmimode=1080p60hz fsck.repair=yes net.ifnames=0 ddr_size= wol_enable= mac=02:00:00:0a:30:01 fan=auto hwver=Unknow coherent_pool=2M quiet loglevel=0 splash plymouth.ignore-serial-consoles initramfs.clear splash bootdelay=7 Apr 17 08:17:33 rivo startkiosk.sh[3434]: Build Date: 18 December 2021 09:05:36AM Apr 17 08:17:33 rivo startkiosk.sh[3434]: xorg-server 2:1.20.4-1+deb10u4 (https://www.debian.org/support) Apr 17 08:17:33 rivo startkiosk.sh[3434]: Current version of pixman: 0.36.0 Apr 17 08:17:33 rivo startkiosk.sh[3434]: Before reporting problems, check http://wiki.x.org Apr 17 08:17:33 rivo startkiosk.sh[3434]: to make sure that you have the latest version. Apr 17 08:17:33 rivo startkiosk.sh[3434]: Markers: (--) probed, (**) from config file, (==) default setting, Apr 17 08:17:33 rivo startkiosk.sh[3434]: (++) from command line, (!!) notice, (II) informational, Apr 17 08:17:33 rivo startkiosk.sh[3434]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Apr 17 08:17:33 rivo startkiosk.sh[3434]: (==) Log file: "/var/log/Xorg.0.log", Time: Thu Apr 17 08:17:33 2025 Apr 17 08:17:33 rivo startkiosk.sh[3434]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Apr 17 08:17:33 rivo kernel: fb: osd[0] canvas.idx =0x40 Apr 17 08:17:33 rivo kernel: fb: osd[0] canvas.addr=0x70000000 Apr 17 08:17:33 rivo kernel: fb: osd[0] canvas.width=7680 Apr 17 08:17:33 rivo kernel: fb: osd[0] canvas.height=1080 Apr 17 08:17:33 rivo kernel: fb: osd[0] frame.width=1920 Apr 17 08:17:33 rivo kernel: fb: osd[0] frame.height=1080 Apr 17 08:17:33 rivo kernel: fb: osd[0] out_addr_id =0x1 Apr 17 08:17:33 rivo kernel: fb: osd[0] enable: 0 (Xorg) Apr 17 08:17:34 rivo ntpd[3396]: Soliciting pool server 92.241.12.152 Apr 17 08:17:34 rivo ntpd[3396]: Soliciting pool server 193.106.93.116 Apr 17 08:17:35 rivo systemd[1]: apt-daily.service: Succeeded. Apr 17 08:17:35 rivo systemd[1]: Started Daily apt download activities. Apr 17 08:17:35 rivo systemd[1]: Starting Daily apt upgrade and clean activities... Apr 17 08:17:35 rivo kernel: fb: osd[0] enable: 1 (Xorg) Apr 17 08:17:35 rivo ntpd[3396]: Soliciting pool server 92.255.126.12 Apr 17 08:17:35 rivo ntpd[3396]: Soliciting pool server 82.142.168.18 Apr 17 08:17:35 rivo ntpd[3396]: Soliciting pool server 217.170.87.229 Apr 17 08:17:36 rivo kernel: fb: osd[0] enable: 1 (Xorg) Apr 17 08:17:36 rivo ntpd[3396]: Soliciting pool server 162.159.200.1 Apr 17 08:17:36 rivo ntpd[3396]: Soliciting pool server 91.201.254.110 Apr 17 08:17:36 rivo ntpd[3396]: Soliciting pool server 89.109.251.22 Apr 17 08:17:36 rivo ntpd[3396]: Soliciting pool server 195.54.177.84 Apr 17 08:17:36 rivo volumio[3433]: info: ------------------------------------------- Apr 17 08:17:36 rivo volumio[3433]: info: ----- Volumio3 ---- Apr 17 08:17:36 rivo volumio[3433]: info: ------------------------------------------- Apr 17 08:17:36 rivo volumio[3433]: info: ----- System startup ---- Apr 17 08:17:36 rivo volumio[3433]: info: ------------------------------------------- Apr 17 08:17:37 rivo systemd[1]: apt-daily-upgrade.service: Succeeded. Apr 17 08:17:37 rivo systemd[1]: Started Daily apt upgrade and clean activities. Apr 17 08:17:37 rivo systemd[1]: Startup finished in 12.899s (kernel) + 24.189s (userspace) = 37.088s. Apr 17 08:17:37 rivo ntpd[3396]: Soliciting pool server 151.0.2.53 Apr 17 08:17:37 rivo ntpd[3396]: Soliciting pool server 51.250.68.198 Apr 17 08:17:37 rivo ntpd[3396]: Soliciting pool server 78.36.199.163 Apr 17 08:17:37 rivo ntpd[3396]: Soliciting pool server 151.0.2.54 Apr 17 08:17:38 rivo volumio[3433]: info: MYVOLUMIO Environment detected Apr 17 08:17:38 rivo volumio[3433]: info: Plugin folders cleanup Apr 17 08:17:38 rivo volumio[3433]: info: Scanning into folder /volumio/app/plugins/ Apr 17 08:17:38 rivo volumio[3433]: info: Scanning category audio_interface Apr 17 08:17:38 rivo volumio[3433]: info: Scanning category miscellanea Apr 17 08:17:38 rivo volumio[3433]: info: Scanning category music_service Apr 17 08:17:38 rivo volumio[3433]: info: Scanning category plugins.json Apr 17 08:17:38 rivo volumio[3433]: info: Scanning category system_controller Apr 17 08:17:38 rivo volumio[3433]: info: Scanning category user_interface Apr 17 08:17:38 rivo volumio[3433]: info: Scanning into folder /data/plugins/ Apr 17 08:17:38 rivo volumio[3433]: info: Scanning category audio_interface Apr 17 08:17:38 rivo volumio[3433]: info: Plugin folders cleanup completed Apr 17 08:17:38 rivo volumio[3433]: info: ------------------------------------------- Apr 17 08:17:38 rivo volumio[3433]: info: ----- Core plugins startup ---- Apr 17 08:17:38 rivo volumio[3433]: info: ------------------------------------------- Apr 17 08:17:38 rivo volumio[3433]: info: Loading plugins from folder /volumio/app/plugins/ Apr 17 08:17:38 rivo volumio[3433]: info: Adding plugin bluetooth to MyMusic Plugins Apr 17 08:17:38 rivo volumio[3433]: info: Adding plugin multiroom to MyMusic Plugins Apr 17 08:17:38 rivo volumio[3433]: info: Adding plugin upnp to MyMusic Plugins Apr 17 08:17:38 rivo volumio[3433]: info: Adding plugin metavolumio to MyMusic Plugins Apr 17 08:17:38 rivo volumio[3433]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 17 08:17:38 rivo volumio[3433]: info: Adding plugin cd_controller to MyMusic Plugins Apr 17 08:17:38 rivo volumio[3433]: info: Adding plugin raat to MyMusic Plugins Apr 17 08:17:38 rivo volumio[3433]: info: Adding plugin smart_inputs to MyMusic Plugins Apr 17 08:17:38 rivo volumio[3433]: info: Adding plugin tidalconnect to MyMusic Plugins Apr 17 08:17:38 rivo volumio[3433]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 17 08:17:38 rivo volumio[3433]: info: Loading plugins from folder /data/plugins/ Apr 17 08:17:38 rivo volumio[3433]: info: Loading plugin "system"... Apr 17 08:17:38 rivo volumio[3433]: info: Loading plugin "appearance"... Apr 17 08:17:38 rivo ntpd[3396]: Soliciting pool server 45.141.102.99 Apr 17 08:17:38 rivo ntpd[3396]: Soliciting pool server 2a03:1ac0:2e92:e7bb::123 Apr 17 08:17:38 rivo ntpd[3396]: Soliciting pool server 193.192.36.3 Apr 17 08:17:39 rivo ntpd[3396]: Soliciting pool server 195.90.182.235 Apr 17 08:17:39 rivo ntpd[3396]: Soliciting pool server 89.109.251.25 Apr 17 08:17:39 rivo volumio[3433]: info: Loading plugin "network"... Apr 17 08:17:39 rivo volumio[3433]: info: Refreshing Cached IP Addresses Apr 17 08:17:40 rivo sudo[3624]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 17 08:17:40 rivo sudo[3624]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 08:17:40 rivo sudo[3626]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 17 08:17:40 rivo sudo[3626]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 08:17:40 rivo sudo[3624]: pam_unix(sudo:session): session closed for user root Apr 17 08:17:40 rivo volumio[3433]: info: Loading plugin "services"... Apr 17 08:17:40 rivo sudo[3626]: pam_unix(sudo:session): session closed for user root Apr 17 08:17:40 rivo volumio[3433]: info: Loading plugin "alsa_controller"... Apr 17 08:17:40 rivo sudo[3638]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 17 08:17:40 rivo sudo[3638]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 08:17:40 rivo kernel: wl_escan_set_scan: ioctl_ver=2 Apr 17 08:17:40 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04e602000 drv_status 0x0 e_type 69 e_states 8 Apr 17 08:17:40 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04e602000 drv_status 0x0 e_type 69 e_states 8 Apr 17 08:17:40 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04e602000 drv_status 0x0 e_type 69 e_states 8 Apr 17 08:17:40 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04e602000 drv_status 0x0 e_type 69 e_states 8 Apr 17 08:17:40 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04e602000 drv_status 0x0 e_type 69 e_states 8 Apr 17 08:17:40 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04e602000 drv_status 0x0 e_type 69 e_states 8 Apr 17 08:17:40 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04e602000 drv_status 0x0 e_type 69 e_states 8 Apr 17 08:17:40 rivo volumio[3433]: warn: Unable to locate the audio output device iFi (by AMR) HD USB Audio. Please configure a valid output device. Apr 17 08:17:40 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04e602000 drv_status 0x0 e_type 69 e_states 8 Apr 17 08:17:40 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04e602000 drv_status 0x0 e_type 69 e_states 8 Apr 17 08:17:40 rivo volumio[3433]: Invalid card number. Apr 17 08:17:40 rivo volumio[3433]: info: Hardware Mixer selected but no Hardware mixer available, detecting default mixer Apr 17 08:17:40 rivo volumio[3433]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 17 08:17:40 rivo volumio[3433]: Invalid card number. Apr 17 08:17:40 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04e602000 drv_status 0x0 e_type 69 e_states 8 Apr 17 08:17:40 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04e602000 drv_status 0x0 e_type 69 e_states 8 Apr 17 08:17:40 rivo volumio[3433]: info: CoreCommandRouter::volumioUpdateVolumeSettings Apr 17 08:17:40 rivo volumio[3433]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 17 08:17:40 rivo volumio[3433]: info: Loading plugin "wizard"... Apr 17 08:17:40 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04e602000 drv_status 0x0 e_type 69 e_states 8 Apr 17 08:17:40 rivo volumio[3433]: info: Loading plugin "networkfs"... Apr 17 08:17:40 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04e602000 drv_status 0x0 e_type 69 e_states 8 Apr 17 08:17:40 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04e602000 drv_status 0x0 e_type 69 e_states 8 Apr 17 08:17:40 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04e602000 drv_status 0x0 e_type 69 e_states 8 Apr 17 08:17:40 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04e602000 drv_status 0x0 e_type 69 e_states 8 Apr 17 08:17:40 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04e602000 drv_status 0x0 e_type 69 e_states 8 Apr 17 08:17:40 rivo volumio[3433]: info: Starting Udev Watcher for removable devices Apr 17 08:17:40 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04e602000 drv_status 0x0 e_type 69 e_states 8 Apr 17 08:17:40 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04e602000 drv_status 0x0 e_type 69 e_states 8 Apr 17 08:17:40 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04e602000 drv_status 0x0 e_type 69 e_states 8 Apr 17 08:17:40 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04e602000 drv_status 0x0 e_type 69 e_states 8 Apr 17 08:17:40 rivo volumio[3433]: info: Ignoring mount for partition: BOOT Apr 17 08:17:40 rivo volumio[3433]: info: Ignoring mount for partition: volumio Apr 17 08:17:40 rivo volumio[3433]: info: Ignoring mount for partition: volumio_data Apr 17 08:17:40 rivo volumio[3433]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 17 08:17:40 rivo volumio[3433]: info: Loading plugin "volumio_command_line_client"... Apr 17 08:17:40 rivo volumio[3433]: info: Loading plugin "upnp"... Apr 17 08:17:40 rivo volumio[3433]: info: [1744867060502] Starting Upmpd Daemon Apr 17 08:17:40 rivo volumio[3433]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 17 08:17:40 rivo volumio[3433]: info: Loading plugin "my_music"... Apr 17 08:17:40 rivo volumio[3433]: info: Loading plugin "mpd"... Apr 17 08:17:40 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04e602000 drv_status 0x0 e_type 69 e_states 8 Apr 17 08:17:40 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04e602000 drv_status 0x0 e_type 69 e_states 8 Apr 17 08:17:40 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04e602000 drv_status 0x0 e_type 69 e_states 8 Apr 17 08:17:40 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04e602000 drv_status 0x0 e_type 69 e_states 8 Apr 17 08:17:40 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04e602000 drv_status 0x0 e_type 69 e_states 8 Apr 17 08:17:40 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04e602000 drv_status 0x0 e_type 69 e_states 8 Apr 17 08:17:40 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04e602000 drv_status 0x0 e_type 69 e_states 8 Apr 17 08:17:40 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04e602000 drv_status 0x0 e_type 69 e_states 8 Apr 17 08:17:40 rivo ntpd[3396]: Soliciting pool server 185.108.208.78 Apr 17 08:17:40 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04e602000 drv_status 0x0 e_type 69 e_states 8 Apr 17 08:17:40 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04e602000 drv_status 0x0 e_type 69 e_states 8 Apr 17 08:17:40 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04e602000 drv_status 0x0 e_type 69 e_states 8 Apr 17 08:17:40 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04e602000 drv_status 0x0 e_type 69 e_states 8 Apr 17 08:17:40 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04e602000 drv_status 0x0 e_type 69 e_states 8 Apr 17 08:17:40 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04e602000 drv_status 0x0 e_type 69 e_states 8 Apr 17 08:17:40 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04e602000 drv_status 0x0 e_type 69 e_states 8 Apr 17 08:17:41 rivo volumio[3433]: info: Loading plugin "upnp_browser"... Apr 17 08:17:41 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04e602000 drv_status 0x0 e_type 69 e_states 8 Apr 17 08:17:41 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04e602000 drv_status 0x0 e_type 69 e_states 8 Apr 17 08:17:41 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04e602000 drv_status 0x0 e_type 69 e_states 8 Apr 17 08:17:41 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04e602000 drv_status 0x0 e_type 69 e_states 8 Apr 17 08:17:41 rivo volumio[3433]: info: Loading plugin "bluetooth"... Apr 17 08:17:41 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04e602000 drv_status 0x0 e_type 69 e_states 8 Apr 17 08:17:42 rivo ntpd[3396]: receive: Unexpected origin timestamp 0xebab0d75.b0c519bc does not match aorg 0000000000.00000000 from server@89.109.251.25 xmt 0xebab0d76.6b131e2d Apr 17 08:17:42 rivo ntpd[3396]: receive: Unexpected origin timestamp 0xebab0d75.b0d0cafd does not match aorg 0000000000.00000000 from server@89.109.251.22 xmt 0xebab0d76.6b0ff017 Apr 17 08:17:42 rivo volumio[3433]: info: [1744867062577] Starting BluetoothController Apr 17 08:17:42 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04e602000 drv_status 0x0 e_type 69 e_states 8 Apr 17 08:17:42 rivo volumio-remote-updater[2846]: [2025-04-17 08:17:42] [connect] Successful connection Apr 17 08:17:42 rivo volumio[3433]: info: Loading plugin "alarm-clock"... Apr 17 08:17:43 rivo volumio[3433]: info: Loading plugin "manifestui"... Apr 17 08:17:43 rivo volumio[3433]: info: Loading plugin "metavolumio"... Apr 17 08:17:43 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04e602000 drv_status 0x0 e_type 69 e_states 8 Apr 17 08:17:43 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04e602000 drv_status 0x0 e_type 69 e_states 8 Apr 17 08:17:44 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04e602000 drv_status 0x0 e_type 69 e_states 8 Apr 17 08:17:44 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04e602000 drv_status 0x0 e_type 69 e_states 8 Apr 17 08:17:45 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04e602000 drv_status 0x0 e_type 69 e_states 8 Apr 17 08:17:45 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04e602000 drv_status 0x0 e_type 69 e_states 0 Apr 17 08:17:45 rivo sudo[3638]: pam_unix(sudo:session): session closed for user root Apr 17 08:17:45 rivo volumio[3433]: info: Loading plugin "airplay_emulation"... Apr 17 08:17:45 rivo volumio[3433]: info: Starting Shairport Sync Apr 17 08:17:45 rivo volumio[3433]: info: Loading plugin "cd_controller"... Apr 17 08:17:45 rivo volumio[3433]: info: Loading plugin "last_100"... Apr 17 08:17:45 rivo volumio[3433]: info: Loading plugin "raat"... Apr 17 08:17:46 rivo volumio[3433]: info: RAAT Plugin loaded Apr 17 08:17:46 rivo volumio[3433]: info: Adding restartRAATSocket REST API Endpoint Apr 17 08:17:46 rivo volumio[3433]: info: Adding restartRAATSocket REST Endpoint for plugin: music_service/raat Apr 17 08:17:46 rivo volumio[3433]: info: Loading plugin "streaming_services"... Apr 17 08:17:47 rivo systemd[1]: systemd-fsckd.service: Succeeded. Apr 17 08:17:48 rivo volumio[3433]: info: Starting Streaming Service Transparent Proxy Apr 17 08:17:48 rivo volumio[3433]: info: Loading plugin "tidalconnect"... Apr 17 08:17:49 rivo volumio[3433]: info: Loading plugin "webradio"... Apr 17 08:17:49 rivo volumio[3433]: info: Loading plugin "i2s_dacs"... Apr 17 08:17:49 rivo volumio[3433]: info: I2S DAC not set, start Auto-detection Apr 17 08:17:49 rivo volumio[3433]: info: Loading plugin "volumiodiscovery"... Apr 17 08:17:49 rivo volumio[3433]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 17 08:17:49 rivo volumio[3433]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 17 08:17:49 rivo volumio[3433]: *** WARNING *** For more information see Apr 17 08:17:49 rivo node[3433]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 17 08:17:49 rivo volumio[3433]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 17 08:17:49 rivo volumio[3433]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 17 08:17:49 rivo volumio[3433]: *** WARNING *** For more information see Apr 17 08:17:49 rivo node[3433]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 17 08:17:49 rivo node[3433]: *** WARNING *** For more information see Apr 17 08:17:49 rivo node[3433]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 17 08:17:49 rivo node[3433]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 17 08:17:49 rivo node[3433]: *** WARNING *** For more information see Apr 17 08:17:49 rivo volumio[3433]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 17 08:17:49 rivo volumio[3433]: info: Discovery: Started advertising with name: Rivo Apr 17 08:17:49 rivo volumio[3433]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 17 08:17:49 rivo volumio[3433]: info: Loading plugin "multiroom"... Apr 17 08:17:50 rivo volumio[3433]: STREAMING PROXY: Starting server on port 3245 Apr 17 08:17:50 rivo volumio[3433]: Node JS runtime: 14 Apr 17 08:17:52 rivo volumio[3433]: info: Applying required configuration parameters for plugin multiroom Apr 17 08:17:52 rivo sudo[3860]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Apr 17 08:17:52 rivo sudo[3860]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 08:17:52 rivo sudo[3860]: pam_unix(sudo:session): session closed for user root Apr 17 08:17:52 rivo volumio[3433]: info: MRS: MultiRoom plugin initialized Apr 17 08:17:52 rivo volumio[3433]: info: MRS: STOPPING SNAPCLIENT Apr 17 08:17:52 rivo volumio[3433]: info: MRS: Snap server stop Apr 17 08:17:52 rivo volumio[3433]: info: MRS: STOPPING volumioStreaming Apr 17 08:17:52 rivo sudo[3876]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Apr 17 08:17:52 rivo sudo[3876]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 08:17:52 rivo sudo[3878]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Apr 17 08:17:52 rivo sudo[3878]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 08:17:52 rivo volumio[3433]: info: Loading plugin "outputs"... Apr 17 08:17:52 rivo sudo[3881]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Apr 17 08:17:52 rivo sudo[3881]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 08:17:52 rivo sudo[3876]: pam_unix(sudo:session): session closed for user root Apr 17 08:17:52 rivo volumio[3433]: info: Loading plugin "albumart"... Apr 17 08:17:52 rivo sudo[3884]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Apr 17 08:17:52 rivo sudo[3884]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 08:17:52 rivo sudo[3884]: pam_unix(sudo:session): session closed for user root Apr 17 08:17:52 rivo volumio[3433]: info: Plugin example_plugin is not enabled Apr 17 08:17:52 rivo volumio[3433]: info: Loading plugin "hi_res_audio"... Apr 17 08:17:52 rivo sudo[3878]: pam_unix(sudo:session): session closed for user root Apr 17 08:17:52 rivo sudo[3881]: pam_unix(sudo:session): session closed for user root Apr 17 08:17:52 rivo volumio[3433]: Forking 3 albumart workers Apr 17 08:17:53 rivo volumio[3433]: Starting albumart workers Apr 17 08:17:53 rivo volumio[3433]: Starting albumart workers Apr 17 08:17:53 rivo volumio[3433]: Starting albumart workers Apr 17 08:17:54 rivo volumio[3433]: info: Applying required configuration parameters for plugin hi_res_audio Apr 17 08:17:54 rivo volumio[3433]: info: Loading plugin "inputs"... Apr 17 08:17:55 rivo volumio[3433]: info: Loading plugin "qobuz"... Apr 17 08:17:57 rivo volumio[3433]: info: Loading plugin "smart_inputs"... Apr 17 08:17:57 rivo volumio[3433]: info: Loading plugin "tidal"... Apr 17 08:17:57 rivo volumio-remote-updater[2846]: [2025-04-17 08:17:57] [connect] Successful connection Apr 17 08:17:59 rivo volumio[3433]: info: Loading plugin "rivocontrol"... Apr 17 08:17:59 rivo systemd[1]: systemd-hostnamed.service: Succeeded. Apr 17 08:17:59 rivo volumio[3433]: info: Adding this device properties Apr 17 08:17:59 rivo volumio[3433]: info: CoreCommandRouter::executeOnPlugin: system , setThisDeviceVolumioProperties Apr 17 08:17:59 rivo volumio[3433]: info: Setting Additional Device Volumio Properties: [object Object] Apr 17 08:17:59 rivo volumio[3433]: info: Loading plugin "updater_comm"... Apr 17 08:18:00 rivo volumio[3433]: info: Loading plugin "cec_controller"... Apr 17 08:18:00 rivo volumio[3433]: info: Applying required configuration parameters for plugin cec_controller Apr 17 08:18:00 rivo volumio[3433]: info: Plugin mpdemulation is not enabled Apr 17 08:18:00 rivo volumio[3433]: info: Loading plugin "rest_api"... Apr 17 08:18:00 rivo volumio[3433]: info: Loading plugin "websocket"... Apr 17 08:18:00 rivo volumio[3433]: info: Starting Socket.io Server version 2.3.0 Apr 17 08:18:00 rivo volumio[3433]: info: Loading plugin "fusiondsp"... Apr 17 08:18:00 rivo volumio[3433]: info: Applying required configuration parameters for plugin fusiondsp Apr 17 08:18:01 rivo volumio[3433]: info: Loading i18n strings for locale ru Apr 17 08:18:01 rivo volumio[3433]: Updating browse sources language Apr 17 08:18:01 rivo volumio[3433]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 17 08:18:01 rivo volumio[3433]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 17 08:18:01 rivo volumio[3433]: info: CoreCommandRouter::initPlayerControls Apr 17 08:18:01 rivo volumio[3433]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 08:18:01 rivo volumio[3433]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 08:18:01 rivo volumio[3433]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 08:18:01 rivo volumio[3433]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 08:18:01 rivo volumio[3433]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 08:18:01 rivo volumio[3433]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 08:18:01 rivo volumio[3433]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 08:18:01 rivo volumio[3433]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 08:18:01 rivo volumio[3433]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 17 08:18:01 rivo volumio[3433]: Express server listening on port 3000 Apr 17 08:18:01 rivo volumio[3433]: [Metrics] WebUI: 25s 190.84ms Apr 17 08:18:01 rivo volumio[3433]: info: CoreStateMachine::resetVolumioState Apr 17 08:18:01 rivo volumio[3433]: info: CoreStateMachine::getcurrentVolume Apr 17 08:18:01 rivo volumio[3433]: info: CoreCommandRouter::volumioRetrievevolume Apr 17 08:18:01 rivo volumio[3433]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 17 08:18:01 rivo volumio[3433]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Apr 17 08:18:01 rivo volumio[3433]: info: CoreStateMachine::getcurrentVolume Apr 17 08:18:01 rivo volumio[3433]: info: CoreCommandRouter::volumioRetrievevolume Apr 17 08:18:01 rivo sudo[3965]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 17 08:18:01 rivo sudo[3965]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 08:18:01 rivo volumio[3433]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Apr 17 08:18:01 rivo volumio[3433]: info: Completed loading Core Plugins Apr 17 08:18:01 rivo sudo[3967]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 17 08:18:01 rivo sudo[3967]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 08:18:01 rivo sudo[3965]: pam_unix(sudo:session): session closed for user root Apr 17 08:18:01 rivo volumio[3433]: info: Preparing to generate the ALSA configuration file Apr 17 08:18:01 rivo sudo[3967]: pam_unix(sudo:session): session closed for user root Apr 17 08:18:01 rivo volumio[3433]: info: Volumio Network Manager: Network status updated: 3 Apr 17 08:18:01 rivo volumio[3433]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Apr 17 08:18:01 rivo volumio[3433]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Apr 17 08:18:01 rivo volumio[3433]: info: MRS: Removed streaming files Apr 17 08:18:01 rivo volumio[3433]: info: MRS: volumioStreaming STOPPED Apr 17 08:18:01 rivo volumio[3433]: info: MRS: SNAPSERVER STOPPED Apr 17 08:18:01 rivo volumio[3433]: info: MRS: SNAPCLIENT STOPPED Apr 17 08:18:01 rivo volumio[3433]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Apr 17 08:18:01 rivo volumio[3433]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Apr 17 08:18:01 rivo volumio[3433]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Apr 17 08:18:01 rivo volumio[3433]: info: Reading ALSA contributions from plugins. Apr 17 08:18:01 rivo volumio-remote-updater[2846]: [2025-04-17 08:18:01] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1744867077 101 Apr 17 08:18:01 rivo volumio[3433]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 Apr 17 08:18:01 rivo volumio[3433]: info: VolumeController:: Volume=undefined Mute =false Apr 17 08:18:01 rivo volumio[3433]: info: CoreStateMachine::pushState Apr 17 08:18:01 rivo volumio[3433]: info: CorePlayQueue::getTrack 0 Apr 17 08:18:01 rivo volumio[3433]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 17 08:18:01 rivo volumio[3433]: info: CoreCommandRouter::volumioPushState Apr 17 08:18:01 rivo volumio[3433]: info: CoreStateMachine::updateTrackBlock Apr 17 08:18:01 rivo volumio[3433]: info: CorePlayQueue::getTrackBlock Apr 17 08:18:01 rivo volumio[3433]: info: CoreCommandRouter::volumioRetrievevolume Apr 17 08:18:02 rivo volumio[3433]: info: VolumeController:: Volume=undefined Mute =false Apr 17 08:18:02 rivo volumio[3433]: info: CoreStateMachine::pushState Apr 17 08:18:02 rivo volumio[3433]: info: CorePlayQueue::getTrack 0 Apr 17 08:18:02 rivo volumio[3433]: info: CoreCommandRouter::volumioPushState Apr 17 08:18:02 rivo volumio[3433]: info: CoreStateMachine::updateTrackBlock Apr 17 08:18:02 rivo volumio[3433]: info: CorePlayQueue::getTrackBlock Apr 17 08:18:02 rivo volumio[3433]: info: CoreCommandRouter::volumioRetrievevolume Apr 17 08:18:02 rivo volumio[3433]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 17 08:18:02 rivo volumio[3433]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 17 08:18:02 rivo volumio[3433]: info: Discovery: Getting this device information Apr 17 08:18:02 rivo volumio[3433]: info: CoreCommandRouter::volumioGetState Apr 17 08:18:02 rivo volumio[3433]: info: CorePlayQueue::getTrack 0 Apr 17 08:18:02 rivo volumio[3433]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 17 08:18:02 rivo volumio[3433]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Apr 17 08:18:02 rivo volumio[3433]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Apr 17 08:18:02 rivo volumio[3433]: info: Reloading queue from file Apr 17 08:18:02 rivo volumio[3433]: info: VolumeController:: Volume=undefined Mute =false Apr 17 08:18:02 rivo volumio[3433]: info: CoreStateMachine::pushState Apr 17 08:18:02 rivo volumio[3433]: info: CorePlayQueue::getTrack 0 Apr 17 08:18:02 rivo volumio[3433]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 17 08:18:02 rivo volumio[3433]: info: CoreCommandRouter::volumioPushState Apr 17 08:18:02 rivo volumio[3433]: info: CoreStateMachine::setRepeat null single undefined Apr 17 08:18:02 rivo volumio[3433]: info: CoreStateMachine::pushState Apr 17 08:18:02 rivo volumio[3433]: info: CorePlayQueue::getTrack 0 Apr 17 08:18:02 rivo volumio[3433]: info: CoreCommandRouter::volumioPushState Apr 17 08:18:02 rivo volumio[3433]: info: CoreStateMachine::setRandom null Apr 17 08:18:02 rivo volumio[3433]: info: CoreStateMachine::pushState Apr 17 08:18:02 rivo volumio[3433]: info: CorePlayQueue::getTrack 0 Apr 17 08:18:02 rivo volumio[3433]: info: CoreCommandRouter::volumioPushState Apr 17 08:18:02 rivo volumio[3433]: info: VolumeController:: Volume=undefined Mute =false Apr 17 08:18:02 rivo volumio[3433]: info: CoreStateMachine::pushState Apr 17 08:18:02 rivo volumio[3433]: info: CorePlayQueue::getTrack 0 Apr 17 08:18:02 rivo volumio[3433]: info: CoreCommandRouter::volumioPushState Apr 17 08:18:02 rivo volumio[3433]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Apr 17 08:18:02 rivo volumio[3433]: info: Asound.conf file unchanged, so no further update is needed Apr 17 08:18:02 rivo volumio[3433]: info: Output device has changed, restarting MPD Apr 17 08:18:02 rivo sudo[3987]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Apr 17 08:18:02 rivo sudo[3987]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 08:18:02 rivo sudo[3990]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 17 08:18:02 rivo volumio[3433]: info: Output device has changed, restarting Shairport Sync Apr 17 08:18:02 rivo volumio[3433]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 08:18:02 rivo volumio[3433]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 17 08:18:02 rivo sudo[3990]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 08:18:02 rivo sudo[3990]: pam_unix(sudo:session): session closed for user root Apr 17 08:18:02 rivo systemd[1]: Started UPnP Renderer front-end to MPD. Apr 17 08:18:02 rivo sudo[3987]: pam_unix(sudo:session): session closed for user root Apr 17 08:18:02 rivo sudo[3992]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 17 08:18:02 rivo sudo[3992]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 08:18:02 rivo systemd[1]: Stopping Music Player Daemon... Apr 17 08:18:02 rivo volumio[3433]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 17 08:18:02 rivo volumio[3433]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 17 08:18:02 rivo volumio[3433]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 08:18:02 rivo volumio[3433]: info: ___________ START PLUGINS ___________ Apr 17 08:18:02 rivo volumio[3433]: info: ControllerMpd::onStart: Initializing MPD Apr 17 08:18:02 rivo volumio[3433]: info: Creating MPD Configuration file Apr 17 08:18:02 rivo sudo[4013]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 17 08:18:02 rivo sudo[4013]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 08:18:02 rivo volumio[3433]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 17 08:18:02 rivo sudo[4013]: pam_unix(sudo:session): session closed for user root Apr 17 08:18:02 rivo volumio[3433]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 17 08:18:02 rivo volumio[3433]: info: [1744867082772] CoreMusicLibrary::Adding element Медиа-серверы Apr 17 08:18:02 rivo volumio[3433]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 17 08:18:02 rivo systemd[1]: mpd.service: Succeeded. Apr 17 08:18:02 rivo systemd[1]: Stopped Music Player Daemon. Apr 17 08:18:02 rivo sudo[4016]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 17 08:18:02 rivo sudo[4016]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 08:18:02 rivo systemd[1]: Starting Music Player Daemon... Apr 17 08:18:02 rivo volumio[3433]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 17 08:18:02 rivo volumio[3433]: info: Adding METAVOLUMIO REST API Endpoints Apr 17 08:18:02 rivo volumio[3433]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Apr 17 08:18:02 rivo volumio[3433]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Apr 17 08:18:02 rivo volumio[3433]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Apr 17 08:18:02 rivo volumio[3433]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Apr 17 08:18:02 rivo volumio[3433]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 08:18:02 rivo volumio[3433]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 17 08:18:02 rivo sudo[4020]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name Rivo Apr 17 08:18:02 rivo sudo[4020]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 08:18:02 rivo systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Apr 17 08:18:02 rivo systemd[1]: mpd.service: Succeeded. Apr 17 08:18:02 rivo systemd[1]: Stopped Music Player Daemon. Apr 17 08:18:02 rivo sudo[4020]: pam_unix(sudo:session): session closed for user root Apr 17 08:18:02 rivo systemd[1]: Starting Music Player Daemon... Apr 17 08:18:02 rivo volumio[3433]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 17 08:18:02 rivo volumio[3433]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 08:18:02 rivo volumio[3433]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 17 08:18:02 rivo volumio[3433]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 17 08:18:02 rivo volumio[3433]: info: Preparing CD Folders Apr 17 08:18:02 rivo volumio[3433]: info: Adding CD REST API Endpoints Apr 17 08:18:02 rivo volumio[3433]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Apr 17 08:18:02 rivo volumio[3433]: info: Starting UDEV Watcher for CD Apr 17 08:18:02 rivo volumio[3433]: info: Detecting CD presence with UDEV Apr 17 08:18:02 rivo volumio[3433]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Apr 17 08:18:03 rivo volumio[3433]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 17 08:18:03 rivo volumio[3433]: info: [1744867083029] CoreMusicLibrary::Adding element Last_100 Apr 17 08:18:03 rivo volumio[3433]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 17 08:18:03 rivo volumio[3433]: info: Starting RAAT Plugin Apr 17 08:18:03 rivo sudo[4025]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 17 08:18:03 rivo sudo[4025]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 08:18:03 rivo sudo[4025]: pam_unix(sudo:session): session closed for user root Apr 17 08:18:03 rivo volumio[3433]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , addAdditionalUISections Apr 17 08:18:03 rivo volumio[3433]: info: Additional UI Settings Added for plugin music_service/raat Apr 17 08:18:03 rivo volumio[3433]: info: Registering DSP Elements listener and retrieving current ones Apr 17 08:18:03 rivo volumio[3433]: info: Additional DSP elements updated Apr 17 08:18:03 rivo volumio[3433]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 08:18:03 rivo volumio[3433]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 08:18:03 rivo volumio[3433]: info: Updating RAAT Signal Path Apr 17 08:18:03 rivo volumio[3433]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Apr 17 08:18:03 rivo volumio[3433]: info: Streaming services startup Apr 17 08:18:03 rivo volumio[3433]: info: Starting Streaming Daemon Apr 17 08:18:03 rivo sudo[4034]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Apr 17 08:18:03 rivo sudo[4034]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 08:18:03 rivo sudo[4034]: pam_unix(sudo:session): session closed for user root Apr 17 08:18:03 rivo volumio[3433]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 17 08:18:03 rivo volumio[3433]: info: [1744867083194] CoreMusicLibrary::Adding element Webradio Apr 17 08:18:03 rivo volumio[3433]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 17 08:18:03 rivo volumio[3433]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 17 08:18:03 rivo volumio[3433]: info: Initializing BBC Radios Apr 17 08:18:03 rivo sudo[4042]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 17 08:18:03 rivo sudo[4042]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 08:18:03 rivo sudo[4042]: pam_unix(sudo:session): session closed for user root Apr 17 08:18:03 rivo volumio[3433]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 17 08:18:03 rivo volumio[3433]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 17 08:18:03 rivo volumio[3433]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Apr 17 08:18:03 rivo volumio[3433]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 17 08:18:03 rivo volumio[3433]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 08:18:03 rivo volumio[3433]: error: Hi Res Audio Failed Login: Missing Login Data Apr 17 08:18:03 rivo volumio[3433]: info: Adding HIGHRESAUDIO REST API Endpoints Apr 17 08:18:03 rivo volumio[3433]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Apr 17 08:18:03 rivo volumio[3433]: info: Initializing Serial Communication on port /dev/ttyS3 Apr 17 08:18:03 rivo kernel: fb: mem_free_work, free memory: addr:800000 Apr 17 08:18:03 rivo volumio[3433]: info: Touch Event Listener Process Starting Apr 17 08:18:03 rivo kernel: meson_uart ffd22000.serial: ttyS3 use xtal(24M) 24000000 change 0 to 9600 Apr 17 08:18:03 rivo kernel: meson_uart ffd22000.serial: ttyS3 use xtal(24M) 24000000 change 9600 to 9600 Apr 17 08:18:03 rivo kernel: meson_uart ffd22000.serial: ttyS3 use xtal(24M) 24000000 change 9600 to 115200 Apr 17 08:18:03 rivo volumio[3433]: info: Refreshing QOBUZ token Apr 17 08:18:03 rivo sudo[4059]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xinput --test-xi2 --root Apr 17 08:18:03 rivo sudo[4059]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 08:18:03 rivo volumio[3433]: info: Adding inputs REST Endpoints Apr 17 08:18:03 rivo volumio[3433]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Apr 17 08:18:03 rivo volumio[3433]: info: Scanning Audio Inputs Apr 17 08:18:03 rivo volumio[3433]: info: Checking against Known Cards name Apr 17 08:18:03 rivo volumio[3433]: info: Checking against Known Cards name Apr 17 08:18:03 rivo volumio[3433]: info: Checking against Known Cards name Apr 17 08:18:03 rivo volumio[3433]: info: Checking against Known Cards name Apr 17 08:18:03 rivo volumio[3433]: info: Checking against Known Cards name Apr 17 08:18:03 rivo volumio[3433]: info: Adding Server instance for streaming Apr 17 08:18:03 rivo sudo[4070]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x25 0x0 Apr 17 08:18:03 rivo sudo[4070]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 08:18:03 rivo sudo[4070]: pam_unix(sudo:session): session closed for user root Apr 17 08:18:03 rivo sudo[4073]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x28 0x0 Apr 17 08:18:03 rivo sudo[4073]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 08:18:03 rivo sudo[4073]: pam_unix(sudo:session): session closed for user root Apr 17 08:18:03 rivo sudo[4076]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x29 0x1 Apr 17 08:18:03 rivo sudo[4076]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 08:18:03 rivo sudo[4076]: pam_unix(sudo:session): session closed for user root Apr 17 08:18:03 rivo sudo[4079]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x33 0x1 Apr 17 08:18:03 rivo sudo[4079]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 08:18:03 rivo sudo[4079]: pam_unix(sudo:session): session closed for user root Apr 17 08:18:03 rivo volumio[3433]: info: Apply VIM3L Onboard LEDs Settings Apr 17 08:18:03 rivo volumio[3433]: info: Turning On Onboard LEDs Apr 17 08:18:03 rivo systemd[1]: systemd-timedated.service: Succeeded. Apr 17 08:18:03 rivo sudo[4085]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/sys_led/trigger Apr 17 08:18:03 rivo sudo[4085]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 08:18:03 rivo volumio[3433]: info: Starting CEC Event listener Apr 17 08:18:03 rivo volumio[3433]: info: Loading i18n strings for locale ru Apr 17 08:18:03 rivo sudo[4085]: pam_unix(sudo:session): session closed for user root Apr 17 08:18:03 rivo volumio[3433]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Apr 17 08:18:03 rivo volumio[3433]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 08:18:03 rivo sudo[4089]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/red_red/trigger Apr 17 08:18:03 rivo sudo[4089]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 08:18:03 rivo volumio[3433]: info: FusionDsp - mixtype--------------------- undefined Apr 17 08:18:03 rivo volumio[3433]: info: Volumio Calling Home Apr 17 08:18:03 rivo sudo[4089]: pam_unix(sudo:session): session closed for user root Apr 17 08:18:03 rivo volumio[3433]: (node:3433) [DEP0005] DeprecationWarning: Buffer() is deprecated due to security and usability issues. Please use the Buffer.alloc(), Buffer.allocUnsafe(), or Buffer.from() methods instead. Apr 17 08:18:03 rivo volumio[3433]: (Use `node --trace-deprecation ...` to show where the warning was created) Apr 17 08:18:04 rivo volumio[3433]: info: Preparing to generate the ALSA configuration file Apr 17 08:18:04 rivo volumio[3433]: info: Stopping AccessToken refresher cron Apr 17 08:18:04 rivo volumio[3433]: info: AccessToken refresher cron started Apr 17 08:18:04 rivo volumio[3433]: info: Adding TIDAL REST API Endpoints Apr 17 08:18:04 rivo volumio[3433]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Apr 17 08:18:04 rivo volumio[3433]: info: Discovery: adding 89a07f45-1746-45f3-92dc-971a07509d3d Apr 17 08:18:04 rivo volumio[3433]: info: Discovery: Found device Rivo Apr 17 08:18:04 rivo volumio[3433]: info: CoreCommandRouter::volumioGetState Apr 17 08:18:04 rivo volumio[3433]: info: CorePlayQueue::getTrack 0 Apr 17 08:18:04 rivo volumio[3433]: info: MRS: Pushing multiroomSync output for this device Apr 17 08:18:04 rivo volumio[3433]: info: MRS: Pushing multiroomSync output Apr 17 08:18:04 rivo volumio[3433]: info: Adding audio output: Apr 17 08:18:04 rivo volumio[3433]: info: Adding audio output: Apr 17 08:18:04 rivo volumio[3433]: info: Serial port opened successfully Apr 17 08:18:04 rivo volumio[3433]: info: Sending serial start messages Apr 17 08:18:04 rivo volumio[3433]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 08:18:04 rivo volumio[3433]: info: Reporting MCU Network Status: 3 Apr 17 08:18:04 rivo volumio[3433]: info: MP1 GPIO: Signalled system ready via GPIO Apr 17 08:18:04 rivo volumio[3433]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Apr 17 08:18:04 rivo volumio[3433]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Apr 17 08:18:04 rivo volumio[3433]: info: Reading ALSA contributions from plugins. Apr 17 08:18:04 rivo volumio[3433]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Apr 17 08:18:04 rivo volumio[3433]: error: Cannot start Volumio Streaming Daemon Apr 17 08:18:04 rivo volumio[3433]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Apr 17 08:18:04 rivo volumio[3433]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Apr 17 08:18:04 rivo volumio[3433]: info: RAAT Albumart path created successfully Apr 17 08:18:04 rivo volumio[3433]: info: Bluetooth name changed to Rivo Apr 17 08:18:04 rivo volumio[3433]: info: MPD Permissions set Apr 17 08:18:04 rivo volumio[3433]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Apr 17 08:18:04 rivo volumio[3433]: info: MPD Permissions set Apr 17 08:18:04 rivo volumio[3433]: info: Upmpdcli Daemon Started Apr 17 08:18:04 rivo volumio[3433]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 17 08:18:04 rivo volumio[3433]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 17 08:18:04 rivo volumio[3433]: info: Discovery: Getting this device information Apr 17 08:18:04 rivo volumio[3433]: info: CoreCommandRouter::volumioGetState Apr 17 08:18:04 rivo volumio[3433]: info: CorePlayQueue::getTrack 0 Apr 17 08:18:04 rivo volumio[3433]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 17 08:18:04 rivo volumio[3433]: info: Volumio called home Apr 17 08:18:04 rivo volumio[3433]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Apr 17 08:18:04 rivo volumio[3433]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 08:18:04 rivo volumio[3433]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 08:18:04 rivo volumio[3433]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 08:18:04 rivo volumio[3433]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 08:18:04 rivo volumio[3433]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 08:18:04 rivo volumio[3433]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 08:18:04 rivo volumio[3433]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 08:18:04 rivo volumio[3433]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 08:18:04 rivo volumio[3433]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 17 08:18:04 rivo volumio[3433]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 17 08:18:04 rivo volumio[3433]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 08:18:04 rivo volumio[3433]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 08:18:04 rivo volumio[3433]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 08:18:04 rivo volumio[3433]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 08:18:04 rivo volumio[3433]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 08:18:04 rivo volumio[3433]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 08:18:04 rivo volumio[3433]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 08:18:04 rivo volumio[3433]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 08:18:04 rivo volumio[3433]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 08:18:04 rivo volumio[3433]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 17 08:18:04 rivo volumio[3433]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 17 08:18:04 rivo volumio[3433]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 08:18:04 rivo volumio[3433]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setDeviceVolumeOverride Apr 17 08:18:04 rivo volumio[3433]: info: Setting Device Volume Override Apr 17 08:18:04 rivo volumio[3433]: info: CoreCommandRouter::volumioUpdateVolumeSettings Apr 17 08:18:04 rivo volumio[3433]: info: Updating Volume Controller Parameters: Device: 5 Name: iFi (by AMR) HD USB Audio Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Apr 17 08:18:04 rivo volumio[3433]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Apr 17 08:18:04 rivo volumio[3433]: info: Disabling external Volume Control Apr 17 08:18:04 rivo volumio[3433]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 08:18:04 rivo volumio[3433]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 08:18:04 rivo volumio[3433]: info: CoreStateMachine::pushState Apr 17 08:18:04 rivo volumio[3433]: info: CorePlayQueue::getTrack 0 Apr 17 08:18:04 rivo volumio[3433]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 17 08:18:04 rivo volumio[3433]: info: CoreCommandRouter::volumioPushState Apr 17 08:18:04 rivo volumio[3433]: info: MRS: Pushing multiroomSync output update for this device Apr 17 08:18:04 rivo volumio[3433]: info: MRS: Pushing multiroomSync output Apr 17 08:18:04 rivo volumio[3433]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Apr 17 08:18:04 rivo volumio[3433]: info: CoreCommandRouter::executeOnPlugin: system , setAdditionalSVInfo Apr 17 08:18:04 rivo volumio[3433]: info: Setting Additional System Software info: Hardware Revision: 2.0 Apr 17 08:18:04 rivo volumio[3433]: info: CoreCommandRouter::executeOnPlugin: system , setHwFwVersion Apr 17 08:18:04 rivo volumio[3433]: info: Setting HW Firmware info: undefined Apr 17 08:18:04 rivo volumio[3433]: info: CoreCommandRouter::executeOnPlugin: system , setHwVersion Apr 17 08:18:04 rivo volumio[3433]: info: Setting HW Version info: 2.0 Apr 17 08:18:04 rivo volumio[3433]: info: CoreCommandRouter::executeOnPlugin: system , setAdditionalSVInfo Apr 17 08:18:04 rivo volumio[3433]: info: Setting Additional System Software info: Hardware Revision: 2.0, Firmware Version: 0.3.4 Apr 17 08:18:04 rivo volumio[3433]: info: CoreCommandRouter::executeOnPlugin: system , setHwFwVersion Apr 17 08:18:04 rivo volumio[3433]: info: Setting HW Firmware info: 0.3.4 Apr 17 08:18:04 rivo volumio[3433]: info: CoreCommandRouter::executeOnPlugin: system , setHwVersion Apr 17 08:18:04 rivo volumio[3433]: info: Setting HW Version info: 2.0 Apr 17 08:18:04 rivo volumio[3433]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Apr 17 08:18:04 rivo volumio[3433]: info: MCU Signalled Headphone Mode Disabled Apr 17 08:18:04 rivo volumio[3433]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Apr 17 08:18:04 rivo volumio[3433]: info: Reporting Headphone State: false Apr 17 08:18:04 rivo volumio[3433]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 08:18:04 rivo volumio[3433]: info: Updating RAAT Signal Path Apr 17 08:18:04 rivo volumio[3433]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Apr 17 08:18:04 rivo volumio[3433]: info: MCU Signalled Sleep Mode Disabled Apr 17 08:18:04 rivo volumio[3433]: info: Enabling Advanced system settings configuration Apr 17 08:18:04 rivo volumio[3433]: info: CoreCommandRouter::executeOnPlugin: system , addAdditionalUISections Apr 17 08:18:04 rivo volumio[3433]: info: Additional UI Settings Added for plugin music_service/inputs Apr 17 08:18:04 rivo volumio[3433]: info: MCU Signalled Auto Boot Mode On Power Disabled Apr 17 08:18:04 rivo sudo[4116]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms force on Apr 17 08:18:04 rivo sudo[4116]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 08:18:04 rivo kernel: fb: osd[0] enable: 1 (Xorg) Apr 17 08:18:04 rivo sudo[4116]: pam_unix(sudo:session): session closed for user root Apr 17 08:18:04 rivo volumio[3433]: verbose: New Socket.io Connection to 192.168.1.101:3000 from 192.168.1.77 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 4 Apr 17 08:18:04 rivo volumio[3433]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Apr 17 08:18:05 rivo volumio[3433]: error: Serial API: Failed to decode command: MAXVOL, message: 100 Apr 17 08:18:05 rivo volumio[3433]: info: Access Token successfully retrieved Apr 17 08:18:05 rivo volumio[3433]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 17 08:18:05 rivo volumio[3433]: info: [1744867085056] CoreMusicLibrary::Adding element QOBUZ Apr 17 08:18:05 rivo volumio[3433]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 17 08:18:05 rivo volumio[3433]: Cannot find translation for source QOBUZ Apr 17 08:18:05 rivo volumio[3433]: info: Stopping AccessToken refresher cron for QOBUZ Apr 17 08:18:05 rivo volumio[3433]: info: AccessToken refresher cron started for QOBUZ Apr 17 08:18:05 rivo volumio[3433]: info: Adding QOBUZ REST API Endpoints Apr 17 08:18:05 rivo volumio[3433]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Apr 17 08:18:05 rivo volumio[3433]: info: CoreCommandRouter::volumioGetState Apr 17 08:18:05 rivo volumio[3433]: info: CorePlayQueue::getTrack 0 Apr 17 08:18:05 rivo volumio[3433]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 17 08:18:05 rivo volumio[3433]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 17 08:18:05 rivo volumio[3433]: info: VolumeController:: Volume=undefined Mute =false Apr 17 08:18:05 rivo volumio[3433]: info: CoreStateMachine::pushState Apr 17 08:18:05 rivo volumio[3433]: info: CorePlayQueue::getTrack 0 Apr 17 08:18:05 rivo volumio[3433]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 17 08:18:05 rivo volumio[3433]: info: CoreCommandRouter::volumioPushState Apr 17 08:18:05 rivo volumio[3433]: info: MRS: Pushing multiroomSync output update for this device Apr 17 08:18:05 rivo volumio[3433]: info: MRS: Pushing multiroomSync output Apr 17 08:18:05 rivo volumio[3433]: info: Starting Shairport Sync Apr 17 08:18:05 rivo sudo[4120]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms 0 0 0 Apr 17 08:18:05 rivo sudo[4120]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 08:18:05 rivo volumio[3433]: info: Starting Shairport Sync Apr 17 08:18:05 rivo sudo[4120]: pam_unix(sudo:session): session closed for user root Apr 17 08:18:05 rivo mpd[4033]: Apr 17 08:18 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 17 08:18:05 rivo systemd[1]: Started Music Player Daemon. Apr 17 08:18:05 rivo sudo[3992]: pam_unix(sudo:session): session closed for user root Apr 17 08:18:05 rivo sudo[4016]: pam_unix(sudo:session): session closed for user root Apr 17 08:18:05 rivo sudo[4123]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 17 08:18:05 rivo sudo[4123]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 08:18:05 rivo volumio[3433]: info: Starting Shairport Sync Apr 17 08:18:05 rivo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 17 08:18:05 rivo systemd[1]: shairport-sync.service: Succeeded. Apr 17 08:18:05 rivo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 17 08:18:05 rivo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 17 08:18:05 rivo sudo[4123]: pam_unix(sudo:session): session closed for user root Apr 17 08:18:05 rivo sudo[4131]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 17 08:18:05 rivo sudo[4131]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 08:18:05 rivo sudo[4134]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 17 08:18:05 rivo sudo[4134]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 08:18:05 rivo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 17 08:18:05 rivo systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Apr 17 08:18:05 rivo systemd[1]: shairport-sync.service: Succeeded. Apr 17 08:18:05 rivo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 17 08:18:05 rivo volumio[3433]: info: CoreCommandRouter::volumioGetState Apr 17 08:18:05 rivo volumio[3433]: info: CorePlayQueue::getTrack 0 Apr 17 08:18:05 rivo volumio[3433]: Upnp client error: Error: This socket has been ended by the other party Apr 17 08:18:05 rivo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 17 08:18:05 rivo sudo[4131]: pam_unix(sudo:session): session closed for user root Apr 17 08:18:05 rivo volumio[3433]: info: CoreCommandRouter::volumioGetState Apr 17 08:18:05 rivo volumio[3433]: info: CorePlayQueue::getTrack 0 Apr 17 08:18:05 rivo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 17 08:18:05 rivo systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Apr 17 08:18:05 rivo systemd[1]: shairport-sync.service: Succeeded. Apr 17 08:18:05 rivo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 17 08:18:05 rivo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 17 08:18:05 rivo sudo[4134]: pam_unix(sudo:session): session closed for user root Apr 17 08:18:05 rivo volumio[3433]: info: Shairport-Sync Started Apr 17 08:18:05 rivo volumio[3433]: Error adding Membership: Error: addMembership EINVAL Apr 17 08:18:05 rivo volumio[3433]: info: Asound.conf file unchanged, so no further update is needed Apr 17 08:18:05 rivo volumio[3433]: info: Output device has changed, restarting MPD Apr 17 08:18:05 rivo sudo[4145]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 17 08:18:05 rivo sudo[4145]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 08:18:05 rivo volumio[3433]: info: Output device has changed, restarting Shairport Sync Apr 17 08:18:05 rivo volumio[3433]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 08:18:05 rivo volumio[3433]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 17 08:18:05 rivo sudo[4145]: pam_unix(sudo:session): session closed for user root Apr 17 08:18:05 rivo sudo[4147]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 17 08:18:05 rivo sudo[4147]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 08:18:05 rivo systemd[1]: Stopping Music Player Daemon... Apr 17 08:18:05 rivo volumio[3433]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 17 08:18:05 rivo volumio[3433]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 17 08:18:05 rivo volumio[3433]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 08:18:05 rivo systemd[1]: mpd.service: Succeeded. Apr 17 08:18:05 rivo systemd[1]: Stopped Music Player Daemon. Apr 17 08:18:05 rivo systemd[1]: Starting Music Player Daemon... Apr 17 08:18:05 rivo volumio[3433]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Apr 17 08:18:05 rivo volumio[3433]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 08:18:05 rivo volumio[3433]: info: Preparing to generate the ALSA configuration file Apr 17 08:18:05 rivo volumio[3433]: info: Adding Inputs via Serial API Apr 17 08:18:05 rivo volumio[3433]: info: Shairport-Sync Started Apr 17 08:18:05 rivo volumio[3433]: info: Shairport-Sync Started Apr 17 08:18:05 rivo volumio[3433]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Apr 17 08:18:05 rivo volumio[3433]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Apr 17 08:18:05 rivo volumio[3433]: info: Reading ALSA contributions from plugins. Apr 17 08:18:05 rivo volumio[3433]: info: CoreStateMachine::pushState Apr 17 08:18:05 rivo volumio[3433]: info: CorePlayQueue::getTrack 0 Apr 17 08:18:05 rivo volumio[3433]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 17 08:18:05 rivo volumio[3433]: info: CoreCommandRouter::volumioPushState Apr 17 08:18:05 rivo volumio[3433]: info: MRS: Pushing multiroomSync output update for this device Apr 17 08:18:05 rivo volumio[3433]: info: MRS: Pushing multiroomSync output Apr 17 08:18:05 rivo volumio[3433]: error: MPD error: Error: write EPIPE Apr 17 08:18:05 rivo volumio[3433]: error: write EPIPE {"errno":-32,"code":"EPIPE","syscall":"write"} Apr 17 08:18:05 rivo sudo[4155]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 17 08:18:05 rivo sudo[4155]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 08:18:05 rivo sudo[4155]: pam_unix(sudo:session): session closed for user root Apr 17 08:18:05 rivo volumio[3433]: error: MPD error: The expression evaluated to a falsy value: Apr 17 08:18:05 rivo volumio[3433]: assert.ok(self.idling) Apr 17 08:18:05 rivo volumio[3433]: error: The expression evaluated to a falsy value: Apr 17 08:18:05 rivo volumio[3433]: assert.ok(self.idling) Apr 17 08:18:05 rivo volumio[3433]: error: MPD error: Error: write EPIPE Apr 17 08:18:05 rivo volumio[3433]: error: write EPIPE {"errno":-32,"code":"EPIPE","syscall":"write"} Apr 17 08:18:06 rivo volumio[3433]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Apr 17 08:18:06 rivo volumio[3433]: info: MPD Permissions set Apr 17 08:18:06 rivo volumio[3433]: info: MPD running with PID4033 Apr 17 08:18:06 rivo volumio[3433]: ,establishing connection Apr 17 08:18:06 rivo sudo[4162]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Apr 17 08:18:06 rivo sudo[4162]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 08:18:06 rivo systemd[1]: Started Volumio Bluetooth Module. Apr 17 08:18:06 rivo sudo[4162]: pam_unix(sudo:session): session closed for user root Apr 17 08:18:06 rivo volumio[3433]: info: camilladsp spawned new process with pid undefined, instance 1, run: true Apr 17 08:18:06 rivo volumio[3433]: info: camilladsp service started and running in background, instance 1 Apr 17 08:18:06 rivo volumio[3433]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 08:18:06 rivo sudo[4172]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Apr 17 08:18:06 rivo sudo[4172]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 08:18:06 rivo volumiobt[4170]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Apr 17 08:18:06 rivo sudo[4172]: pam_unix(sudo:session): session closed for user root Apr 17 08:18:06 rivo volumio[3433]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Apr 17 08:18:06 rivo volumio[3433]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Apr 17 08:18:06 rivo volumio[3433]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Apr 17 08:18:06 rivo volumio[3433]: info: FusionDsp loaded Apr 17 08:18:06 rivo volumio[3433]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 17 08:18:06 rivo sudo[4179]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Apr 17 08:18:06 rivo sudo[4179]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 08:18:06 rivo volumio[3433]: info: FusionDsp - Reporting Fusion DSP Enabled Apr 17 08:18:06 rivo volumio[3433]: info: Adding Signal Path Element [object Object] Apr 17 08:18:06 rivo volumio[3433]: info: Adding fusiondspeq DSP Signal Path Element Apr 17 08:18:06 rivo volumio[3433]: info: Additional DSP elements updated Apr 17 08:18:06 rivo volumio[3433]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 08:18:06 rivo volumio[3433]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 08:18:06 rivo volumio[3433]: info: Updating RAAT Signal Path Apr 17 08:18:06 rivo volumio[3433]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Apr 17 08:18:06 rivo volumio[3433]: info: FusionDsp - ---- installed callbackRead Apr 17 08:18:06 rivo volumio[3433]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 17 08:18:06 rivo systemd[1]: Started FusionDsp Daemon. Apr 17 08:18:06 rivo volumio[3433]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Apr 17 08:18:06 rivo volumio[3433]: at Process.ChildProcess._handle.onexit (internal/child_process.js:277:19) Apr 17 08:18:06 rivo volumio[3433]: at onErrorNT (internal/child_process.js:472:16) Apr 17 08:18:06 rivo volumio[3433]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Apr 17 08:18:06 rivo volumio[3433]: at runNextTicks (internal/process/task_queues.js:64:3) Apr 17 08:18:06 rivo volumio[3433]: at listOnTimeout (internal/timers.js:526:9) Apr 17 08:18:06 rivo volumio[3433]: at processTimers (internal/timers.js:500:7) { Apr 17 08:18:06 rivo volumio[3433]: errno: -2, Apr 17 08:18:06 rivo volumio[3433]: code: 'ENOENT', Apr 17 08:18:06 rivo volumio[3433]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Apr 17 08:18:06 rivo volumio[3433]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Apr 17 08:18:06 rivo volumio[3433]: spawnargs: [ Apr 17 08:18:06 rivo volumio[3433]: '-p', Apr 17 08:18:06 rivo volumio[3433]: 9876, Apr 17 08:18:06 rivo volumio[3433]: '-o', Apr 17 08:18:06 rivo volumio[3433]: '/tmp/camilladsp.log', Apr 17 08:18:06 rivo volumio[3433]: '-l', Apr 17 08:18:06 rivo volumio[3433]: 'warn', Apr 17 08:18:06 rivo volumio[3433]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Apr 17 08:18:06 rivo volumio[3433]: ] Apr 17 08:18:06 rivo volumio[3433]: } Apr 17 08:18:06 rivo volumio[3433]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 17 08:18:06 rivo sudo[4179]: pam_unix(sudo:session): session closed for user root Apr 17 08:18:06 rivo volumio[4181]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Apr 17 08:18:06 rivo systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Apr 17 08:18:06 rivo systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Apr 17 08:18:07 rivo sudo[4192]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-17 08:17 Apr 17 08:18:07 rivo sudo[4192]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="e32c2c71ae78852844c70357816bdd871c155ab8" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 31 Mar 2025 08:01:39 PM CEST" VOLUMIO_VERSION="3.799" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="71b19b3a3eb3d257ef93eee35eba5cf1"