Oct 12 09:54:50 rivoplus bluetoothd[3416]: profiles/audio/bap.c:bap_adapter_probe() BAP requires ISO Socket which is not enabled Oct 12 09:54:50 rivoplus bluetoothd[3416]: bap: Operation not supported (95) Oct 12 09:54:50 rivoplus systemd[1]: setdatetime-helper.service: Deactivated successfully. Oct 12 09:54:50 rivoplus bluetoothd[3416]: Failed to Read Adv Monitor Features with status 0x01 Oct 12 09:54:50 rivoplus systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Oct 12 09:54:50 rivoplus systemd[1]: Starting dpkg-db-backup.service - Daily dpkg database backup service... Oct 12 09:54:50 rivoplus systemd[1]: Starting e2scrub_all.service - Online ext4 Metadata Check for All Filesystems... Oct 12 09:54:50 rivoplus wireless.js[2965]: WIRELESS.JS - INFO: InterfaceValidator: FAIL - wlan0 driver not initialized (operstate=unknown) Oct 12 09:54:50 rivoplus wireless.js[2965]: WIRELESS.JS - INFO: InterfaceValidator: TIMEOUT waiting for wlan0 after 2221068085ms (reason: driver_not_ready) Oct 12 09:54:50 rivoplus systemd[1]: Started ntpsec-rotate-stats.service - Rotate ntpd stats. Oct 12 09:54:50 rivoplus wireless.js[2965]: WIRELESS.JS - INFO: ensureInterfaceReady: Interface not ready: driver_not_ready Oct 12 09:54:50 rivoplus wireless.js[2965]: WIRELESS.JS - INFO: Interface setup failed, continuing anyway: Error: Interface not ready Oct 12 09:54:50 rivoplus systemd[1]: e2scrub_all.service: Deactivated successfully. Oct 12 09:54:50 rivoplus systemd[1]: Finished e2scrub_all.service - Online ext4 Metadata Check for All Filesystems. Oct 12 09:54:50 rivoplus systemd[1]: ntpsec-rotate-stats.service: Deactivated successfully. Oct 12 09:54:50 rivoplus sudo[3478]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Oct 12 09:54:50 rivoplus sudo[3478]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:54:50 rivoplus sudo[3478]: pam_unix(sudo:session): session closed for user root Oct 12 09:54:50 rivoplus sudo[3496]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Oct 12 09:54:50 rivoplus sudo[3496]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:54:50 rivoplus ntpd[3163]: IO: Listen normally on 3 eth0 192.168.2.160:123 Oct 12 09:54:50 rivoplus ntpd[3163]: IO: new interface(s) found: waking up resolver Oct 12 09:54:50 rivoplus ntpd[3163]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Oct 12 09:54:50 rivoplus winbindd[3395]: [2026/10/12 09:54:50.470091, 0] ../../source3/winbindd/winbindd_idmap.c:372(wb_parent_idmap_setup_lookupname_done) Oct 12 09:54:50 rivoplus winbindd[3395]: wb_parent_idmap_setup_lookupname_done: Lookup domain name 'RIVOPLUS' failed 'NT_STATUS_IO_TIMEOUT' Oct 12 09:54:50 rivoplus ntpd[3163]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Oct 12 09:54:50 rivoplus ntpd[3163]: DNS: Pool taking: 185.157.229.254 Oct 12 09:54:50 rivoplus ntpd[3163]: DNS: Pool taking: 89.46.74.148 Oct 12 09:54:50 rivoplus ntpd[3163]: DNS: Pool taking: 95.110.135.141 Oct 12 09:54:50 rivoplus ntpd[3163]: DNS: Pool taking: 172.232.208.229 Oct 12 09:54:50 rivoplus ntpd[3163]: DNS: dns_take_status: 3.debian.pool.ntp.org=>good, 8 Oct 12 09:54:50 rivoplus systemd[1]: dpkg-db-backup.service: Deactivated successfully. Oct 12 09:54:50 rivoplus systemd[1]: Finished dpkg-db-backup.service - Daily dpkg database backup service. Oct 12 09:54:50 rivoplus systemd[1]: Started smbd.service - Samba SMB Daemon. Oct 12 09:54:50 rivoplus dbus-daemon[2935]: [system] Successfully activated service 'org.freedesktop.hostname1' Oct 12 09:54:50 rivoplus systemd[1]: Started systemd-hostnamed.service - Hostname Service. Oct 12 09:54:50 rivoplus bluealsa[3455]: ../src/storage.c:90: Initializing persistent storage: /var/lib/bluealsa Oct 12 09:54:50 rivoplus bluealsa[3455]: main.c:663: Starting main dispatching loop Oct 12 09:54:50 rivoplus systemd[1]: Started bluealsa.service - BlueALSA service. Oct 12 09:54:50 rivoplus bluealsa[3455]: main.c:118: Acquired D-Bus service name: org.bluealsa Oct 12 09:54:50 rivoplus bluealsa[3455]: bluealsa-dbus.c:358: Registering BlueALSA D-Bus manager: /org/bluealsa Oct 12 09:54:50 rivoplus systemd[1]: Reached target bluetooth.target - Bluetooth Support. Oct 12 09:54:50 rivoplus bluealsa[3455]: bluez.c:153: Registering media application: /org/bluez/hci0 Oct 12 09:54:50 rivoplus bluealsa[3455]: bluez.c:743: Exporting media endpoint object: /org/bluez/hci0/A2DP/SBC/source/1 Oct 12 09:54:50 rivoplus bluealsa[3455]: bluez.c:743: Exporting media endpoint object: /org/bluez/hci0/A2DP/SBC/source/2 Oct 12 09:54:50 rivoplus bluealsa[3455]: bluez.c:743: Exporting media endpoint object: /org/bluez/hci0/A2DP/SBC/sink/1 Oct 12 09:54:50 rivoplus bluealsa[3455]: bluez.c:743: Exporting media endpoint object: /org/bluez/hci0/A2DP/SBC/sink/2 Oct 12 09:54:50 rivoplus bluetoothd[3416]: Endpoint registered: sender=:1.9 path=/org/bluez/hci0/A2DP/SBC/source/1 Oct 12 09:54:50 rivoplus bluetoothd[3416]: Endpoint registered: sender=:1.9 path=/org/bluez/hci0/A2DP/SBC/sink/2 Oct 12 09:54:50 rivoplus bluetoothd[3416]: Endpoint registered: sender=:1.9 path=/org/bluez/hci0/A2DP/SBC/source/2 Oct 12 09:54:50 rivoplus bluetoothd[3416]: Endpoint registered: sender=:1.9 path=/org/bluez/hci0/A2DP/SBC/sink/1 Oct 12 09:54:51 rivoplus ntpd[3163]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Oct 12 09:54:51 rivoplus ntpd[3163]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Oct 12 09:54:51 rivoplus ntpd[3163]: DNS: Pool taking: 85.199.214.99 Oct 12 09:54:51 rivoplus ntpd[3163]: DNS: Pool taking: 95.110.254.234 Oct 12 09:54:51 rivoplus ntpd[3163]: DNS: Pool taking: 212.45.144.3 Oct 12 09:54:51 rivoplus ntpd[3163]: DNS: Pool taking: 172.232.209.103 Oct 12 09:54:51 rivoplus ntpd[3163]: DNS: Pool taking: 2600:3c0b::f03c:94ff:fee2:cbb9 Oct 12 09:54:51 rivoplus ntpd[3163]: DNS: Pool taking: 2606:4700:f1::123 Oct 12 09:54:51 rivoplus ntpd[3163]: DNS: Pool taking: 2a00:6d41:200:2::12 Oct 12 09:54:51 rivoplus ntpd[3163]: DNS: Pool taking: 2600:3c0b::f03c:94ff:fe16:7f5a Oct 12 09:54:51 rivoplus ntpd[3163]: DNS: dns_take_status: 2.debian.pool.ntp.org=>good, 8 Oct 12 09:54:51 rivoplus volumio-remote-updater[2959]: [2026-10-12 09:54:51] [info] asio async_connect error: asio.system:111 (Connection refused) Oct 12 09:54:51 rivoplus volumio-remote-updater[2959]: [2026-10-12 09:54:51] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) Oct 12 09:54:51 rivoplus volumio-remote-updater[2959]: [2026-10-12 09:54:51] [error] handle_connect error: Connection refused Oct 12 09:54:52 rivoplus sudo[3496]: pam_unix(sudo:session): session closed for user root Oct 12 09:54:52 rivoplus wireless.js[2965]: WIRELESS.JS - INFO: SETTING APPROPRIATE REG DOMAIN: DE Oct 12 09:54:52 rivoplus sudo[3556]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set DE Oct 12 09:54:52 rivoplus sudo[3556]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:54:52 rivoplus sudo[3556]: pam_unix(sudo:session): session closed for user root Oct 12 09:54:52 rivoplus wireless.js[2965]: WIRELESS.JS - INFO: SUCCESSFULLY SET NEW REGDOMAIN: DE Oct 12 09:54:52 rivoplus wireless.js[2965]: WIRELESS.JS - INFO: netconfigured file not found, starting hotspot Oct 12 09:54:52 rivoplus wireless.js[2965]: WIRELESS.JS - INFO: Single Network Mode: Ethernet active, maintaining WiFi scan capability Oct 12 09:54:52 rivoplus wireless.js[2965]: WIRELESS.JS - INFO: SNM: Maintaining wlan0 UP without IP (scan mode) Oct 12 09:54:52 rivoplus wireless.js[2965]: WIRELESS.JS - INFO: SNM: Users can configure WiFi via WebUI while ethernet is active Oct 12 09:54:52 rivoplus wireless.js[2965]: WIRELESS.JS - INFO: InterfaceValidator: FAIL - wlan0 driver not initialized (operstate=unknown) Oct 12 09:54:52 rivoplus ntpd[3163]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Oct 12 09:54:52 rivoplus ntpd[3163]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Oct 12 09:54:52 rivoplus ntpd[3163]: DNS: Pool taking: 162.159.200.1 Oct 12 09:54:52 rivoplus ntpd[3163]: DNS: Pool taking: 162.159.200.123 Oct 12 09:54:52 rivoplus ntpd[3163]: DNS: Pool taking: 185.19.184.35 Oct 12 09:54:52 rivoplus ntpd[3163]: DNS: Pool taking: 37.247.53.178 Oct 12 09:54:52 rivoplus ntpd[3163]: DNS: dns_take_status: 1.debian.pool.ntp.org=>good, 8 Oct 12 09:54:52 rivoplus sudo[3566]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Oct 12 09:54:52 rivoplus sudo[3566]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 12 09:54:52 rivoplus sudo[3566]: pam_unix(sudo:session): session closed for user root Oct 12 09:54:52 rivoplus sudo[3570]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Oct 12 09:54:52 rivoplus sudo[3570]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 12 09:54:52 rivoplus sudo[3570]: pam_unix(sudo:session): session closed for user root Oct 12 09:54:52 rivoplus wpa_supplicant[3574]: Successfully initialized wpa_supplicant Oct 12 09:54:52 rivoplus dhcpcd[3059]: wlan0: carrier lost - roaming Oct 12 09:54:52 rivoplus dhcpcd[3059]: wlan0: carrier lost - roaming Oct 12 09:54:52 rivoplus kernel: P2P interface registered Oct 12 09:54:52 rivoplus kernel: wl_cfgp2p_add_p2p_disc_if: wdev: ffffffc043506400, wdev->net: (null) Oct 12 09:54:52 rivoplus kernel: WLC_E_IF: NO_IF set, event Ignored Oct 12 09:54:52 rivoplus kernel: P2P interface started Oct 12 09:54:52 rivoplus systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 1. Oct 12 09:54:52 rivoplus wireless.js[2965]: WIRELESS.JS - INFO: InterfaceValidator: READY - wlan0 is ready for operations Oct 12 09:54:52 rivoplus systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Oct 12 09:54:52 rivoplus wireless.js[2965]: WIRELESS.JS - INFO: InterfaceValidator: wlan0 became ready after 2221067887ms Oct 12 09:54:52 rivoplus wireless.js[2965]: WIRELESS.JS - INFO: ensureInterfaceReady: Interface ready (MAC: 40:d9:5a:9f:4a:4c) Oct 12 09:54:52 rivoplus systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Oct 12 09:54:53 rivoplus sudo[3612]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Oct 12 09:54:53 rivoplus sudo[3612]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:54:53 rivoplus sudo[3612]: pam_unix(sudo:session): session closed for user root Oct 12 09:54:53 rivoplus upmpdcli[3611]: Could not open config: /tmp/upmpdcli.conf Oct 12 09:54:53 rivoplus systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Oct 12 09:54:53 rivoplus systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Oct 12 09:54:53 rivoplus sudo[3619]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Oct 12 09:54:53 rivoplus sudo[3619]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:54:53 rivoplus ntpd[3163]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 Oct 12 09:54:53 rivoplus ntpd[3163]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Oct 12 09:54:53 rivoplus ntpd[3163]: DNS: Pool taking: 195.32.70.195 Oct 12 09:54:53 rivoplus ntpd[3163]: DNS: Pool taking: 204.216.214.76 Oct 12 09:54:53 rivoplus ntpd[3163]: DNS: Pool taking: 212.45.144.206 Oct 12 09:54:53 rivoplus ntpd[3163]: DNS: Pool taking: 151.22.209.90 Oct 12 09:54:53 rivoplus ntpd[3163]: DNS: dns_take_status: 0.debian.pool.ntp.org=>good, 8 Oct 12 09:54:53 rivoplus systemd[1]: systemd-rfkill.service: Deactivated successfully. Oct 12 09:54:55 rivoplus sudo[3619]: pam_unix(sudo:session): session closed for user root Oct 12 09:54:55 rivoplus wireless.js[2965]: WIRELESS.JS - INFO: SETTING APPROPRIATE REG DOMAIN: DE Oct 12 09:54:55 rivoplus sudo[3625]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set DE Oct 12 09:54:55 rivoplus sudo[3625]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:54:55 rivoplus sudo[3625]: pam_unix(sudo:session): session closed for user root Oct 12 09:54:55 rivoplus wireless.js[2965]: WIRELESS.JS - INFO: SUCCESSFULLY SET NEW REGDOMAIN: DE Oct 12 09:54:55 rivoplus wireless.js[2965]: WIRELESS.JS - INFO: SNM: Transition to scan mode completed in 2221072176ms Oct 12 09:54:55 rivoplus wireless.js[2965]: WIRELESS.JS - INFO: SNM: wlan0 is UP without IP, scan capable Oct 12 09:54:55 rivoplus wireless.js[2965]: Failed to connect to non-global ctrl_ifname: wlan0 error: No such file or directory Oct 12 09:54:55 rivoplus wireless.js[2965]: WIRELESS.JS - INFO: Notified systemd about wireless ready Oct 12 09:54:55 rivoplus systemd[1]: Started wireless.service - Wireless Services. Oct 12 09:54:55 rivoplus systemd[1]: Started volumio.service - Volumio Backend Module. Oct 12 09:54:55 rivoplus systemd[1]: Started firststart.service - First Start. Oct 12 09:54:55 rivoplus systemd[1]: Started volumio-kiosk.service - Start Volumio Kiosk. Oct 12 09:54:55 rivoplus systemd[1]: Started volumio_cpu_tweak.service - Volumio Cpu Tweaker. Oct 12 09:54:55 rivoplus systemd[1]: Reached target multi-user.target - Multi-User System. Oct 12 09:54:55 rivoplus systemd[1]: Reached target graphical.target - Graphical Interface. Oct 12 09:54:55 rivoplus systemd[1]: Starting systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP... Oct 12 09:54:55 rivoplus firststart.sh[3642]: Volumio first start configuration script Oct 12 09:54:55 rivoplus firststart.sh[3642]: Configuring unconfigured packages Oct 12 09:54:55 rivoplus startkiosk.sh[3643]: Starting Volumio Kiosk with arguments for cursor display Oct 12 09:54:55 rivoplus systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Oct 12 09:54:55 rivoplus systemd[1]: Finished systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP. Oct 12 09:54:55 rivoplus volumio-cpu-tweak[3644]: Setting RT Priority for mpd Oct 12 09:54:55 rivoplus systemd[1]: Startup finished in 14.392s (kernel) + 22.598s (userspace) = 36.990s. Oct 12 09:54:55 rivoplus volumio-cpu-tweak[3644]: Setting MPD Affinity Oct 12 09:54:55 rivoplus volumio-cpu-tweak[3669]: pid 3's current affinity mask: 1 Oct 12 09:54:55 rivoplus volumio-cpu-tweak[3644]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Oct 12 09:54:55 rivoplus volumio-cpu-tweak[3644]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Oct 12 09:54:55 rivoplus systemd[1]: volumio_cpu_tweak.service: Deactivated successfully. Oct 12 09:54:55 rivoplus firststart.sh[3642]: Creating /var/log/samba/cores folder Oct 12 09:54:55 rivoplus firststart.sh[3642]: Removing default SSH host keys Oct 12 09:54:55 rivoplus firststart.sh[3677]: removed '/etc/ssh/ssh_host_ecdsa_key' Oct 12 09:54:55 rivoplus firststart.sh[3677]: removed '/etc/ssh/ssh_host_ecdsa_key.pub' Oct 12 09:54:55 rivoplus firststart.sh[3677]: removed '/etc/ssh/ssh_host_ed25519_key' Oct 12 09:54:55 rivoplus firststart.sh[3677]: removed '/etc/ssh/ssh_host_ed25519_key.pub' Oct 12 09:54:55 rivoplus firststart.sh[3677]: removed '/etc/ssh/ssh_host_rsa_key' Oct 12 09:54:55 rivoplus firststart.sh[3677]: removed '/etc/ssh/ssh_host_rsa_key.pub' Oct 12 09:54:55 rivoplus firststart.sh[3642]: Generating SSH host keys Oct 12 09:54:55 rivoplus startkiosk.sh[3682]: xauth: file /root/.Xauthority does not exist Oct 12 09:54:55 rivoplus startkiosk.sh[3688]: X.Org X Server 1.21.1.7 Oct 12 09:54:55 rivoplus startkiosk.sh[3688]: X Protocol Version 11, Revision 0 Oct 12 09:54:55 rivoplus startkiosk.sh[3688]: Current Operating System: Linux rivoplus 4.9.241 #4 SMP PREEMPT Wed Jun 4 18:07:40 CEST 2025 aarch64 Oct 12 09:54:55 rivoplus startkiosk.sh[3688]: Kernel command line: imgpart=UUID=b6ead090-fb0f-42e0-9028-87be40acbd8f imgfile=/volumio_current.sqsh bootpart=UUID=004E-46A5 datapart=UUID=f58b0dd7-5dc6-49b6-9937-f7490e5900bf uuidconfig=env.system.txt hwdevice=VIM3 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:1d:30:01 fan=auto hwver=VIM3.V11 coherent_pool=2M quiet loglevel=0 splash plymouth.ignore-serial-consoles initramfs.clear splash bootdelay=7 Oct 12 09:54:55 rivoplus startkiosk.sh[3688]: xorg-server 2:21.1.7-3+deb12u12 (https://www.debian.org/support) Oct 12 09:54:55 rivoplus startkiosk.sh[3688]: Current version of pixman: 0.42.2 Oct 12 09:54:55 rivoplus startkiosk.sh[3688]: Before reporting problems, check http://wiki.x.org Oct 12 09:54:55 rivoplus startkiosk.sh[3688]: to make sure that you have the latest version. Oct 12 09:54:55 rivoplus startkiosk.sh[3688]: Markers: (--) probed, (**) from config file, (==) default setting, Oct 12 09:54:55 rivoplus startkiosk.sh[3688]: (++) from command line, (!!) notice, (II) informational, Oct 12 09:54:55 rivoplus startkiosk.sh[3688]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Oct 12 09:54:55 rivoplus startkiosk.sh[3688]: (==) Log file: "/var/log/Xorg.0.log", Time: Mon Oct 12 09:54:55 2026 Oct 12 09:54:55 rivoplus startkiosk.sh[3688]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Oct 12 09:54:55 rivoplus kernel: fb: osd[0] canvas.idx =0x40 Oct 12 09:54:55 rivoplus kernel: fb: osd[0] canvas.addr=0x70000000 Oct 12 09:54:55 rivoplus kernel: fb: osd[0] canvas.width=7680 Oct 12 09:54:55 rivoplus kernel: fb: osd[0] canvas.height=1080 Oct 12 09:54:55 rivoplus kernel: fb: osd[0] frame.width=1920 Oct 12 09:54:55 rivoplus kernel: fb: osd[0] frame.height=1080 Oct 12 09:54:55 rivoplus kernel: fb: osd[0] out_addr_id =0x1 Oct 12 09:54:55 rivoplus kernel: fb: osd[0] enable: 0 (Xorg) Oct 12 09:54:57 rivoplus ntpd[3163]: CLOCK: time stepped by 0.618941 Oct 12 09:54:57 rivoplus ntpd[3163]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Oct 12 09:54:57 rivoplus firststart.sh[3681]: debconf: unable to initialize frontend: Dialog Oct 12 09:54:57 rivoplus firststart.sh[3681]: debconf: (TERM is not set, so the dialog frontend is not usable.) Oct 12 09:54:57 rivoplus firststart.sh[3681]: debconf: falling back to frontend: Readline Oct 12 09:54:57 rivoplus firststart.sh[3681]: debconf: unable to initialize frontend: Readline Oct 12 09:54:57 rivoplus firststart.sh[3681]: debconf: (This frontend requires a controlling tty.) Oct 12 09:54:57 rivoplus firststart.sh[3681]: debconf: falling back to frontend: Teletype Oct 12 09:54:58 rivoplus kernel: fb: osd[0] enable: 1 (Xorg) Oct 12 09:54:59 rivoplus volumio[3641]: info: ------------------------------------------- Oct 12 09:54:59 rivoplus volumio[3641]: info: ----- Volumio3 ---- Oct 12 09:54:59 rivoplus volumio[3641]: info: ------------------------------------------- Oct 12 09:54:59 rivoplus volumio[3641]: info: ----- System startup ---- Oct 12 09:54:59 rivoplus volumio[3641]: info: ------------------------------------------- Oct 12 09:54:59 rivoplus kernel: fb: osd[0] enable: 1 (Xorg) Oct 12 09:55:00 rivoplus volumio[3641]: info: File /data/configuration/plugins.json does not exist. Copying from Volumio Oct 12 09:55:00 rivoplus volumio[3641]: info: MYVOLUMIO Environment detected Oct 12 09:55:00 rivoplus volumio[3641]: info: Plugin folders cleanup Oct 12 09:55:00 rivoplus volumio[3641]: info: Scanning into folder /volumio/app/plugins/ Oct 12 09:55:00 rivoplus volumio[3641]: info: Scanning category audio_interface Oct 12 09:55:00 rivoplus volumio[3641]: info: Scanning category miscellanea Oct 12 09:55:00 rivoplus volumio[3641]: info: Scanning category music_service Oct 12 09:55:00 rivoplus volumio[3641]: info: Scanning category plugins.json Oct 12 09:55:00 rivoplus volumio[3641]: info: Scanning category system_controller Oct 12 09:55:00 rivoplus volumio[3641]: info: Scanning category user_interface Oct 12 09:55:00 rivoplus volumio[3641]: info: Scanning into folder /data/plugins/ Oct 12 09:55:00 rivoplus volumio[3641]: info: Plugin folders cleanup completed Oct 12 09:55:00 rivoplus volumio[3641]: info: ------------------------------------------- Oct 12 09:55:00 rivoplus volumio[3641]: info: ----- Core plugins startup ---- Oct 12 09:55:00 rivoplus volumio[3641]: info: ------------------------------------------- Oct 12 09:55:00 rivoplus volumio[3641]: info: Loading plugins from folder /volumio/app/plugins/ Oct 12 09:55:00 rivoplus volumio[3641]: info: Adding plugin bluetooth to MyMusic Plugins Oct 12 09:55:00 rivoplus volumio[3641]: info: Adding plugin multiroom to MyMusic Plugins Oct 12 09:55:00 rivoplus volumio[3641]: info: Adding plugin upnp to MyMusic Plugins Oct 12 09:55:00 rivoplus volumio[3641]: info: Adding plugin metavolumio to MyMusic Plugins Oct 12 09:55:00 rivoplus volumio[3641]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 12 09:55:00 rivoplus volumio[3641]: info: Adding plugin cd_controller to MyMusic Plugins Oct 12 09:55:00 rivoplus volumio[3641]: info: Adding plugin qobuzconnect to MyMusic Plugins Oct 12 09:55:00 rivoplus volumio[3641]: info: Adding plugin raat to MyMusic Plugins Oct 12 09:55:00 rivoplus volumio[3641]: info: Adding plugin smart_inputs to MyMusic Plugins Oct 12 09:55:00 rivoplus volumio[3641]: info: Adding plugin tidalconnect to MyMusic Plugins Oct 12 09:55:00 rivoplus volumio[3641]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 12 09:55:00 rivoplus volumio[3641]: info: Loading plugins from folder /data/plugins/ Oct 12 09:55:00 rivoplus volumio[3641]: info: Loading plugin "system"... Oct 12 09:55:00 rivoplus volumio[3641]: info: No id defined. Creating one Oct 12 09:55:00 rivoplus volumio[3641]: info: Loading plugin "appearance"... Oct 12 09:55:02 rivoplus volumio-remote-updater[2959]: [2026-10-12 09:55:02] [connect] Successful connection Oct 12 09:55:02 rivoplus volumio[3641]: info: Loading plugin "network"... Oct 12 09:55:02 rivoplus volumio[3641]: info: Refreshing Cached IP Addresses Oct 12 09:55:02 rivoplus sudo[3852]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 12 09:55:02 rivoplus sudo[3852]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:55:03 rivoplus sudo[3854]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 12 09:55:03 rivoplus sudo[3854]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:55:03 rivoplus volumio[3641]: info: Loading plugin "services"... Oct 12 09:55:03 rivoplus sudo[3852]: pam_unix(sudo:session): session closed for user root Oct 12 09:55:03 rivoplus volumio[3641]: info: Loading plugin "volumio5onboarding"... Oct 12 09:55:03 rivoplus sudo[3854]: pam_unix(sudo:session): session closed for user root Oct 12 09:55:03 rivoplus volumio[3641]: info: Loading plugin "alsa_controller"... Oct 12 09:55:03 rivoplus sudo[3866]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 12 09:55:03 rivoplus sudo[3866]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:55:03 rivoplus kernel: wl_escan_set_scan: ioctl_ver=2 Oct 12 09:55:03 rivoplus volumio[3641]: warn: The ALSA output card is not set, defaulting to card [object Object] Oct 12 09:55:03 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 12 09:55:03 rivoplus volumio[3641]: info: Found match in Cards Database for ignoring default Mixer Oct 12 09:55:03 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f912000 drv_status 0x0 e_type 69 e_states 8 Oct 12 09:55:03 rivoplus volumio[3641]: info: Setting mixer PCM for card S/PDIF + AES/EBU + I2S Oct 12 09:55:03 rivoplus volumio[3641]: info: Ignoring Mixers Options Oct 12 09:55:03 rivoplus volumio[3641]: info: Device 5 does not have any Mixer Control Available Oct 12 09:55:03 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f912000 drv_status 0x0 e_type 69 e_states 8 Oct 12 09:55:03 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f912000 drv_status 0x0 e_type 69 e_states 8 Oct 12 09:55:03 rivoplus volumio[3641]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 12 09:55:03 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f912000 drv_status 0x0 e_type 69 e_states 8 Oct 12 09:55:03 rivoplus volumio[3641]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 12 09:55:03 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f912000 drv_status 0x0 e_type 69 e_states 8 Oct 12 09:55:03 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f912000 drv_status 0x0 e_type 69 e_states 8 Oct 12 09:55:03 rivoplus volumio[3641]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 12 09:55:03 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 12 09:55:03 rivoplus volumio[3641]: info: Loading plugin "wizard"... Oct 12 09:55:03 rivoplus volumio[3641]: info: Loading plugin "networkfs"... Oct 12 09:55:03 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f912000 drv_status 0x0 e_type 69 e_states 8 Oct 12 09:55:03 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f912000 drv_status 0x0 e_type 69 e_states 8 Oct 12 09:55:03 rivoplus volumio[3641]: info: Starting Udev Watcher for removable devices Oct 12 09:55:03 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f912000 drv_status 0x0 e_type 69 e_states 8 Oct 12 09:55:03 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f912000 drv_status 0x0 e_type 69 e_states 8 Oct 12 09:55:03 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f912000 drv_status 0x0 e_type 69 e_states 8 Oct 12 09:55:03 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f912000 drv_status 0x0 e_type 69 e_states 8 Oct 12 09:55:03 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f912000 drv_status 0x0 e_type 69 e_states 8 Oct 12 09:55:03 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f912000 drv_status 0x0 e_type 69 e_states 8 Oct 12 09:55:03 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f912000 drv_status 0x0 e_type 69 e_states 8 Oct 12 09:55:03 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f912000 drv_status 0x0 e_type 69 e_states 8 Oct 12 09:55:03 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f912000 drv_status 0x0 e_type 69 e_states 8 Oct 12 09:55:03 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f912000 drv_status 0x0 e_type 69 e_states 8 Oct 12 09:55:03 rivoplus volumio[3641]: info: Ignoring mount for partition: BOOT Oct 12 09:55:03 rivoplus volumio[3641]: info: Ignoring mount for partition: volumio Oct 12 09:55:03 rivoplus volumio[3641]: info: Ignoring mount for partition: volumio_data Oct 12 09:55:03 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 12 09:55:03 rivoplus volumio[3641]: info: Loading plugin "volumio_command_line_client"... Oct 12 09:55:03 rivoplus volumio[3641]: info: Loading plugin "upnp"... Oct 12 09:55:03 rivoplus volumio[3641]: info: [1791798903442] Starting Upmpd Daemon Oct 12 09:55:03 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 12 09:55:03 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f912000 drv_status 0x0 e_type 69 e_states 8 Oct 12 09:55:03 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f912000 drv_status 0x0 e_type 69 e_states 8 Oct 12 09:55:03 rivoplus volumio[3641]: info: Loading plugin "my_music"... Oct 12 09:55:03 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f912000 drv_status 0x0 e_type 69 e_states 8 Oct 12 09:55:03 rivoplus volumio[3641]: info: Loading plugin "mpd"... Oct 12 09:55:03 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f912000 drv_status 0x0 e_type 69 e_states 8 Oct 12 09:55:03 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f912000 drv_status 0x0 e_type 69 e_states 8 Oct 12 09:55:03 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f912000 drv_status 0x0 e_type 69 e_states 8 Oct 12 09:55:03 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f912000 drv_status 0x0 e_type 69 e_states 8 Oct 12 09:55:03 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f912000 drv_status 0x0 e_type 69 e_states 8 Oct 12 09:55:03 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f912000 drv_status 0x0 e_type 69 e_states 8 Oct 12 09:55:03 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f912000 drv_status 0x0 e_type 69 e_states 8 Oct 12 09:55:03 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f912000 drv_status 0x0 e_type 69 e_states 8 Oct 12 09:55:03 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f912000 drv_status 0x0 e_type 69 e_states 8 Oct 12 09:55:03 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f912000 drv_status 0x0 e_type 69 e_states 8 Oct 12 09:55:03 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f912000 drv_status 0x0 e_type 69 e_states 8 Oct 12 09:55:03 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f912000 drv_status 0x0 e_type 69 e_states 8 Oct 12 09:55:03 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f912000 drv_status 0x0 e_type 69 e_states 8 Oct 12 09:55:03 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f912000 drv_status 0x0 e_type 69 e_states 8 Oct 12 09:55:03 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f912000 drv_status 0x0 e_type 69 e_states 8 Oct 12 09:55:03 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f912000 drv_status 0x0 e_type 69 e_states 8 Oct 12 09:55:03 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f912000 drv_status 0x0 e_type 69 e_states 8 Oct 12 09:55:03 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f912000 drv_status 0x0 e_type 69 e_states 8 Oct 12 09:55:03 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f912000 drv_status 0x0 e_type 69 e_states 8 Oct 12 09:55:03 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f912000 drv_status 0x0 e_type 69 e_states 8 Oct 12 09:55:03 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f912000 drv_status 0x0 e_type 69 e_states 8 Oct 12 09:55:04 rivoplus volumio[3641]: info: Loading plugin "upnp_browser"... Oct 12 09:55:04 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f912000 drv_status 0x0 e_type 69 e_states 8 Oct 12 09:55:04 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f912000 drv_status 0x0 e_type 69 e_states 8 Oct 12 09:55:05 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f912000 drv_status 0x0 e_type 69 e_states 0 Oct 12 09:55:05 rivoplus sudo[3866]: pam_unix(sudo:session): session closed for user root Oct 12 09:55:05 rivoplus volumio[3641]: info: Starting UPNP Browser Oct 12 09:55:05 rivoplus volumio[3641]: info: Loading plugin "bluetooth"... Oct 12 09:55:05 rivoplus volumio[3641]: ------------------------------------ BT MESSAGE: [FUNC] onVolumioStart Oct 12 09:55:05 rivoplus volumio[3641]: info: Loading plugin "alarm-clock"... Oct 12 09:55:06 rivoplus volumio[3641]: info: Loading plugin "manifestui"... Oct 12 09:55:06 rivoplus firststart.sh[3720]: Creating SSH2 RSA key; this may take some time ... Oct 12 09:55:06 rivoplus volumio[3641]: info: Loading plugin "metavolumio"... Oct 12 09:55:06 rivoplus firststart.sh[3896]: 3072 SHA256:M+H3JoYKi+Vph1lxwUMMAkCtbDakFMwx0oXLhdZe5yk root@rivoplus (RSA) Oct 12 09:55:07 rivoplus systemd[1]: systemd-fsckd.service: Deactivated successfully. Oct 12 09:55:07 rivoplus firststart.sh[3720]: Creating SSH2 DSA key; this may take some time ... Oct 12 09:55:07 rivoplus firststart.sh[3901]: 1024 SHA256:/IY8rGOx9NlYqY07gk/jbEt+SRaEq7vRqeWPw/dEkgI root@rivoplus (DSA) Oct 12 09:55:07 rivoplus firststart.sh[3720]: Creating SSH2 ECDSA key; this may take some time ... Oct 12 09:55:07 rivoplus firststart.sh[3905]: 256 SHA256:UBSt/PFK0OERmiRZHIgSLOw8yJbyjPf3cgHcM2DmLbU root@rivoplus (ECDSA) Oct 12 09:55:07 rivoplus firststart.sh[3918]: insserv: warning: current start runlevel(s) (empty) of script `ssh' overrides LSB defaults (2 3 4 5). Oct 12 09:55:07 rivoplus firststart.sh[3918]: insserv: warning: current stop runlevel(s) (2 3 4 5) of script `ssh' overrides LSB defaults (empty). Oct 12 09:55:07 rivoplus firststart.sh[3918]: insserv: Script `ssh' has overlapping Default-Start and Default-Stop runlevels (2 3 4 5) and (2 3 4 5). This should be fixed. Oct 12 09:55:07 rivoplus firststart.sh[3918]: insserv: warning: current start runlevel(s) (empty) of script `ssh' overrides LSB defaults (2 3 4 5). Oct 12 09:55:07 rivoplus systemd[1]: Reloading. Oct 12 09:55:08 rivoplus volumio[3641]: info: Loading plugin "airplay_emulation"... Oct 12 09:55:08 rivoplus volumio[3641]: info: Starting Shairport Sync Oct 12 09:55:08 rivoplus volumio[3641]: info: Loading plugin "cd_controller"... Oct 12 09:55:08 rivoplus volumio[3641]: info: Loading plugin "last_100"... Oct 12 09:55:08 rivoplus volumio[3641]: info: Loading plugin "qobuzconnect"... Oct 12 09:55:08 rivoplus volumio[3641]: info: Loading plugin "raat"... Oct 12 09:55:08 rivoplus volumio[3641]: info: RAAT Plugin loaded Oct 12 09:55:08 rivoplus volumio[3641]: info: Adding restartRAATSocket REST API Endpoint Oct 12 09:55:08 rivoplus volumio[3641]: info: Adding restartRAATSocket REST Endpoint for plugin: music_service/raat Oct 12 09:55:08 rivoplus volumio[3641]: info: Loading plugin "streaming_services"... Oct 12 09:55:09 rivoplus systemd[1]: systemd-udevd.service: bpf-firewall: Preparation of BPF allow maps failed: Invalid argument Oct 12 09:55:09 rivoplus systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2. Oct 12 09:55:09 rivoplus systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Oct 12 09:55:09 rivoplus systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Oct 12 09:55:09 rivoplus upmpdcli[3940]: Could not open config: /tmp/upmpdcli.conf Oct 12 09:55:09 rivoplus systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Oct 12 09:55:09 rivoplus systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Oct 12 09:55:10 rivoplus volumio[3641]: info: Loading plugin "tidalconnect"... Oct 12 09:55:10 rivoplus systemd[1]: Reloading. Oct 12 09:55:10 rivoplus volumio[3641]: info: Loading plugin "webradio"... Oct 12 09:55:10 rivoplus volumio[3641]: info: Loading plugin "i2s_dacs"... Oct 12 09:55:10 rivoplus volumio[3641]: info: I2S DAC not set, start Auto-detection Oct 12 09:55:11 rivoplus volumio[3641]: info: Loading plugin "volumiodiscovery"... Oct 12 09:55:11 rivoplus volumio[3641]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 12 09:55:11 rivoplus volumio[3641]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 12 09:55:11 rivoplus volumio[3641]: *** WARNING *** For more information see Oct 12 09:55:11 rivoplus volumio[3641]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 12 09:55:11 rivoplus volumio[3641]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 12 09:55:11 rivoplus node[3641]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 12 09:55:11 rivoplus volumio[3641]: *** WARNING *** For more information see Oct 12 09:55:11 rivoplus node[3641]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 12 09:55:11 rivoplus node[3641]: *** WARNING *** For more information see Oct 12 09:55:11 rivoplus node[3641]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 12 09:55:11 rivoplus node[3641]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 12 09:55:11 rivoplus node[3641]: *** WARNING *** For more information see Oct 12 09:55:11 rivoplus volumio[3641]: info: Discovery: Started advertising with name: Rivoplus Oct 12 09:55:11 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 12 09:55:11 rivoplus volumio[3641]: info: Loading plugin "multiroom"... Oct 12 09:55:12 rivoplus systemd[1]: systemd-udevd.service: bpf-firewall: Preparation of BPF allow maps failed: Invalid argument Oct 12 09:55:12 rivoplus firststart.sh[3973]: rescue-ssh.target is a disabled or a static unit not running, not starting it. Oct 12 09:55:12 rivoplus firststart.sh[3973]: ssh.service is a disabled or a static unit not running, not starting it. Oct 12 09:55:13 rivoplus sudo[4002]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Oct 12 09:55:13 rivoplus sudo[4002]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:55:13 rivoplus sudo[4002]: pam_unix(sudo:session): session closed for user root Oct 12 09:55:13 rivoplus volumio[3641]: info: MRS: MultiRoom plugin initialized Oct 12 09:55:13 rivoplus volumio[3641]: info: MRS: STOPPING SNAPCLIENT Oct 12 09:55:13 rivoplus volumio[3641]: info: MRS: Snap server stop Oct 12 09:55:13 rivoplus volumio[3641]: info: MRS: STOPPING volumioStreaming Oct 12 09:55:13 rivoplus sudo[4018]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Oct 12 09:55:13 rivoplus sudo[4018]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:55:13 rivoplus sudo[4020]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Oct 12 09:55:13 rivoplus sudo[4020]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:55:13 rivoplus volumio[3641]: info: Loading plugin "outputs"... Oct 12 09:55:13 rivoplus sudo[4024]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Oct 12 09:55:13 rivoplus sudo[4024]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:55:13 rivoplus volumio[3641]: info: Loading plugin "albumart"... Oct 12 09:55:13 rivoplus sudo[4026]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Oct 12 09:55:13 rivoplus sudo[4026]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:55:13 rivoplus sudo[4026]: pam_unix(sudo:session): session closed for user root Oct 12 09:55:13 rivoplus volumio[3641]: info: Plugin example_plugin is not enabled Oct 12 09:55:13 rivoplus volumio[3641]: info: Loading plugin "hi_res_audio"... Oct 12 09:55:13 rivoplus sudo[4018]: pam_unix(sudo:session): session closed for user root Oct 12 09:55:13 rivoplus sudo[4024]: pam_unix(sudo:session): session closed for user root Oct 12 09:55:13 rivoplus sudo[4020]: pam_unix(sudo:session): session closed for user root Oct 12 09:55:13 rivoplus systemd[1]: Reloading. Oct 12 09:55:14 rivoplus volumio[4030]: Forking 3 albumart workers Oct 12 09:55:15 rivoplus volumio[4053]: Starting albumart workers Oct 12 09:55:15 rivoplus volumio[3641]: info: Loading plugin "inputs"... Oct 12 09:55:16 rivoplus systemd[1]: systemd-udevd.service: bpf-firewall: Preparation of BPF allow maps failed: Invalid argument Oct 12 09:55:16 rivoplus volumio[4055]: Starting albumart workers Oct 12 09:55:16 rivoplus volumio[4054]: Starting albumart workers Oct 12 09:55:16 rivoplus firststart.sh[4076]: ssh.socket is a disabled or a static unit not running, not starting it. Oct 12 09:55:16 rivoplus firststart.sh[3642]: Enabling SSH for first boot Oct 12 09:55:16 rivoplus systemd[1]: Starting ssh.service - OpenBSD Secure Shell server... Oct 12 09:55:17 rivoplus volumio[3641]: info: Loading plugin "qobuz"... Oct 12 09:55:17 rivoplus sshd[4111]: Server listening on 0.0.0.0 port 22. Oct 12 09:55:17 rivoplus sshd[4111]: Server listening on :: port 22. Oct 12 09:55:17 rivoplus systemd[1]: Started ssh.service - OpenBSD Secure Shell server. Oct 12 09:55:17 rivoplus firststart.sh[3642]: Disabling firststart service Oct 12 09:55:17 rivoplus systemctl[4120]: Removed "/etc/systemd/system/multi-user.target.wants/firststart.service". Oct 12 09:55:17 rivoplus systemd[1]: Reloading. Oct 12 09:55:17 rivoplus volumio-remote-updater[2959]: [2026-10-12 09:55:17] [connect] Successful connection Oct 12 09:55:18 rivoplus systemd[1]: systemd-udevd.service: bpf-firewall: Preparation of BPF allow maps failed: Invalid argument Oct 12 09:55:19 rivoplus firststart.sh[3642]: Finalizing Oct 12 09:55:19 rivoplus systemd[1]: firststart.service: Deactivated successfully. Oct 12 09:55:19 rivoplus volumio[3641]: info: Loading plugin "smart_inputs"... Oct 12 09:55:19 rivoplus systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Oct 12 09:55:19 rivoplus volumio[3641]: info: Loading plugin "tidal"... Oct 12 09:55:20 rivoplus systemd[1]: setdatetime-helper.service: Deactivated successfully. Oct 12 09:55:20 rivoplus systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Oct 12 09:55:20 rivoplus volumio[3641]: info: Loading plugin "rivopluscontrol"... Oct 12 09:55:21 rivoplus systemd[1]: systemd-hostnamed.service: Deactivated successfully. Oct 12 09:55:21 rivoplus volumio[3641]: info: Adding this device properties Oct 12 09:55:21 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: system , setThisDeviceVolumioProperties Oct 12 09:55:21 rivoplus volumio[3641]: info: Setting Additional Device Volumio Properties: [object Object] Oct 12 09:55:21 rivoplus volumio[3641]: info: Loading plugin "updater_comm"... Oct 12 09:55:21 rivoplus volumio[3641]: info: Loading plugin "cec_controller"... Oct 12 09:55:21 rivoplus kernel: fb: mem_free_work, free memory: addr:800000 Oct 12 09:55:21 rivoplus volumio[3641]: info: Plugin mpdemulation is not enabled Oct 12 09:55:21 rivoplus volumio[3641]: info: Loading plugin "rest_api"... Oct 12 09:55:21 rivoplus volumio[3641]: info: Loading plugin "websocket"... Oct 12 09:55:21 rivoplus volumio[3641]: info: Starting Socket.io Server version 1.7.4 Oct 12 09:55:21 rivoplus volumio[3641]: info: Loading i18n strings for locale en Oct 12 09:55:21 rivoplus volumio[3641]: Updating browse sources language Oct 12 09:55:21 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 12 09:55:22 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 09:55:22 rivoplus volumio[3641]: info: CoreCommandRouter::initPlayerControls Oct 12 09:55:22 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:55:22 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:55:22 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:55:22 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:55:22 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:55:22 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:55:22 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:55:22 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:55:22 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 09:55:22 rivoplus volumio[3641]: Express server listening on port 3000 Oct 12 09:55:22 rivoplus volumio[3641]: [Metrics] WebUI: 23s 732.97ms Oct 12 09:55:22 rivoplus volumio[3641]: info: CoreStateMachine::resetVolumioState Oct 12 09:55:22 rivoplus volumio[3641]: info: CoreStateMachine::getcurrentVolume Oct 12 09:55:22 rivoplus volumio[3641]: info: CoreCommandRouter::volumioRetrievevolume Oct 12 09:55:22 rivoplus volumio[3641]: info: CoreStateMachine::pushState Oct 12 09:55:22 rivoplus volumio[3641]: info: CorePlayQueue::getTrack 0 Oct 12 09:55:22 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 09:55:22 rivoplus volumio[3641]: info: CoreCommandRouter::volumioPushState Oct 12 09:55:22 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 09:55:22 rivoplus volumio[3641]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Oct 12 09:55:22 rivoplus volumio[3641]: info: CoreStateMachine::getcurrentVolume Oct 12 09:55:22 rivoplus volumio[3641]: info: CoreCommandRouter::volumioRetrievevolume Oct 12 09:55:22 rivoplus volumio[3641]: info: CoreStateMachine::pushState Oct 12 09:55:22 rivoplus volumio[3641]: info: CorePlayQueue::getTrack 0 Oct 12 09:55:22 rivoplus volumio[3641]: info: CoreCommandRouter::volumioPushState Oct 12 09:55:22 rivoplus sudo[4176]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 12 09:55:22 rivoplus sudo[4176]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:55:22 rivoplus volumio[3641]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Oct 12 09:55:22 rivoplus volumio[3641]: info: Completed loading Core Plugins Oct 12 09:55:22 rivoplus volumio[3641]: info: Preparing to generate the ALSA configuration file Oct 12 09:55:22 rivoplus sudo[4178]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 12 09:55:22 rivoplus sudo[4178]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:55:22 rivoplus sudo[4176]: pam_unix(sudo:session): session closed for user root Oct 12 09:55:22 rivoplus sudo[4178]: pam_unix(sudo:session): session closed for user root Oct 12 09:55:22 rivoplus volumio[3641]: info: Cannot read play queue from file Oct 12 09:55:22 rivoplus volumio[3641]: info: Volumio Network Manager: Network status updated: 1 Oct 12 09:55:22 rivoplus volumio[3641]: info: MRS: Removed streaming files Oct 12 09:55:22 rivoplus volumio[3641]: info: MRS: volumioStreaming STOPPED Oct 12 09:55:22 rivoplus volumio[3641]: info: MRS: SNAPSERVER STOPPED Oct 12 09:55:22 rivoplus volumio[3641]: info: MRS: SNAPCLIENT STOPPED Oct 12 09:55:22 rivoplus volumio[3641]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Oct 12 09:55:22 rivoplus volumio[3641]: verbose: New Socket.io Connection to 192.168.2.160 from 192.168.2.120 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/155.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Oct 12 09:55:22 rivoplus volumio[3641]: verbose: New Socket.io Connection to 192.168.2.160 from 192.168.2.120 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/155.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Oct 12 09:55:22 rivoplus volumio[3641]: info: Asound.conf file written Oct 12 09:55:22 rivoplus sudo[4189]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Oct 12 09:55:22 rivoplus sudo[4189]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:55:22 rivoplus sudo[4189]: pam_unix(sudo:session): session closed for user root Oct 12 09:55:22 rivoplus volumio[3641]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 12 09:55:22 rivoplus volumio[3641]: No state is present for card AMLAUGESOUNDMP1 Oct 12 09:55:22 rivoplus volumio[3641]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 12 09:55:22 rivoplus volumio[3641]: Found hardware: "AML-AUGESOUND-M" "" "" "" "" Oct 12 09:55:22 rivoplus volumio[3641]: Hardware is initialized using a generic method Oct 12 09:55:22 rivoplus volumio[3641]: No state is present for card AMLAUGESOUNDMP1 Oct 12 09:55:22 rivoplus volumio[3641]: No state is present for card Device Oct 12 09:55:22 rivoplus volumio[3641]: Found hardware: "USB-Audio" "USB Mixer" "USB2fc6:6016" "" "" Oct 12 09:55:22 rivoplus volumio[3641]: Hardware is initialized using a generic method Oct 12 09:55:22 rivoplus volumio[3641]: No state is present for card Device Oct 12 09:55:22 rivoplus volumio[3641]: info: Output device has changed, restarting MPD Oct 12 09:55:22 rivoplus volumio[3641]: info: Output device has changed, restarting Shairport Sync Oct 12 09:55:22 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:55:22 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 09:55:22 rivoplus sudo[4195]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 12 09:55:22 rivoplus sudo[4196]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 12 09:55:22 rivoplus sudo[4195]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:55:22 rivoplus sudo[4196]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:55:22 rivoplus sudo[4195]: pam_unix(sudo:session): session closed for user root Oct 12 09:55:22 rivoplus volumio[3641]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 12 09:55:22 rivoplus volumio[3641]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 12 09:55:22 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:55:22 rivoplus volumio[3641]: info: ___________ START PLUGINS ___________ Oct 12 09:55:22 rivoplus volumio[3641]: info: Setting Hotspot Unique name on first start: Rivoplus-CDF97 Oct 12 09:55:22 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: network , getConfigParam Oct 12 09:55:22 rivoplus volumio[3641]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getConfigParam] in plugin network Oct 12 09:55:22 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: network , saveHotspotSettings Oct 12 09:55:22 rivoplus volumio[3641]: info: CoreCommandRouter::Reload Ui Oct 12 09:55:22 rivoplus systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 12 09:55:22 rivoplus systemd[1]: Starting mpd.service - Music Player Daemon... Oct 12 09:55:23 rivoplus volumio[3641]: info: ControllerMpd::onStart: Initializing MPD Oct 12 09:55:23 rivoplus volumio[3641]: info: Creating MPD Configuration file Oct 12 09:55:23 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 09:55:23 rivoplus volumio[3641]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 12 09:55:23 rivoplus sudo[4206]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Oct 12 09:55:23 rivoplus sudo[4206]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:55:23 rivoplus volumio[3641]: info: [1791798923027] CoreMusicLibrary::Adding element Media Servers Oct 12 09:55:23 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 12 09:55:23 rivoplus volumio[3641]: info: UPNP Browser: Client initialized successfully Oct 12 09:55:23 rivoplus sudo[4209]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 12 09:55:23 rivoplus sudo[4209]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:55:23 rivoplus sudo[4211]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 12 09:55:23 rivoplus sudo[4211]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:55:23 rivoplus sudo[4209]: pam_unix(sudo:session): session closed for user root Oct 12 09:55:23 rivoplus volumio[3641]: ------------------------------------ BT MESSAGE: [FUNC] onStart Oct 12 09:55:23 rivoplus volumio[3641]: ------------------------------------ BT MESSAGE: Starting Volumio Bluetooth Service Oct 12 09:55:23 rivoplus volumio[3641]: ------------------------------------ BT MESSAGE: Boot config /etc/bluetooth/volumio.conf: cache mode = tmp Oct 12 09:55:23 rivoplus volumio[3641]: ------------------------------------ BT MESSAGE: [metaCache] Created directory: /tmp/bluetooth-cache/ Oct 12 09:55:23 rivoplus volumio[3641]: ------------------------------------ BT MESSAGE: [metaCache] Directory exists and is ready. Oct 12 09:55:23 rivoplus volumio[3641]: info: Adding METAVOLUMIO REST API Endpoints Oct 12 09:55:23 rivoplus volumio[3641]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Oct 12 09:55:23 rivoplus volumio[3641]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Oct 12 09:55:23 rivoplus volumio[3641]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Oct 12 09:55:23 rivoplus volumio[3641]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Oct 12 09:55:23 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:55:23 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 09:55:23 rivoplus systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Oct 12 09:55:23 rivoplus sudo[4206]: pam_unix(sudo:session): session closed for user root Oct 12 09:55:23 rivoplus volumio[3641]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 12 09:55:23 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:55:23 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 09:55:23 rivoplus bluetoothd[3416]: Path / reserved for Adv Monitor app :1.15 Oct 12 09:55:23 rivoplus bluetoothd[3416]: Adv Monitor app :1.15 disconnected from D-Bus Oct 12 09:55:23 rivoplus systemd[1]: mpd.service: Deactivated successfully. Oct 12 09:55:23 rivoplus systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 12 09:55:23 rivoplus systemd[1]: mpd.socket: Deactivated successfully. Oct 12 09:55:23 rivoplus systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 12 09:55:23 rivoplus systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 12 09:55:23 rivoplus volumio[3641]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 12 09:55:23 rivoplus volumio[3641]: info: Preparing CD Folders Oct 12 09:55:23 rivoplus volumio[3641]: info: Adding CD REST API Endpoints Oct 12 09:55:23 rivoplus volumio[3641]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Oct 12 09:55:23 rivoplus volumio[3641]: info: Starting UDEV Watcher for CD Oct 12 09:55:23 rivoplus volumio[3641]: info: Detecting CD presence with UDEV Oct 12 09:55:23 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Oct 12 09:55:23 rivoplus systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 12 09:55:23 rivoplus systemd[1]: Starting mpd.service - Music Player Daemon... Oct 12 09:55:23 rivoplus volumio5-onboarding[4220]: time=2026-10-12T09:55:23.598Z level=INFO msg="running volumio4-device-gateway" version=415db8e7+CHANGES buildDate=2026-09-15T15:08:58Z Oct 12 09:55:23 rivoplus sudo[4234]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 12 09:55:23 rivoplus sudo[4234]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 12 09:55:23 rivoplus sudo[4252]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Oct 12 09:55:23 rivoplus sudo[4234]: pam_unix(sudo:session): session closed for user root Oct 12 09:55:24 rivoplus systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 3. Oct 12 09:55:24 rivoplus systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Oct 12 09:55:24 rivoplus systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Oct 12 09:55:24 rivoplus upmpdcli[4254]: :3:../libupnpp/upnpplib.cxx:273::LibUPnP: Using IPV4 192.168.2.160 port 49152 IPV6 port 49152 Oct 12 09:55:24 rivoplus upmpdcli[4254]: :3:../src/mediaserver/cdplugins/plgwithslave.cxx:274::PlgWithSlave: starting streaming proxy on port 49149 Oct 12 09:55:26 rivoplus upmpdcli[4254]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Timeout Oct 12 09:55:26 rivoplus upmpdcli[4254]: :2:../src/main.cxx:732::MPD connection failed Oct 12 09:55:27 rivoplus mpd[4253]: 2026-10-12T09:55:27 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 12 09:55:27 rivoplus mpd[4253]: 2026-10-12T09:55:27 exception: Database corrupted Oct 12 09:55:27 rivoplus systemd[1]: Started mpd.service - Music Player Daemon. Oct 12 09:55:27 rivoplus sudo[4196]: pam_unix(sudo:session): session closed for user root Oct 12 09:55:27 rivoplus sudo[4211]: pam_unix(sudo:session): session closed for user root Oct 12 09:55:28 rivoplus volumio[3641]: warn: [cd-plugin] cdspeedctl: device or media not ready Oct 12 09:55:28 rivoplus volumio[3641]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 12 09:55:28 rivoplus volumio[3641]: info: [1791798928300] CoreMusicLibrary::Adding element Last_100 Oct 12 09:55:28 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 12 09:55:28 rivoplus volumio[3641]: info: Adding qc_getconfig REST Endpoint for plugin: music_service/qobuzconnect Oct 12 09:55:28 rivoplus volumio[3641]: info: QobuzConnect: Starting Qobuz Connect socket and service Oct 12 09:55:28 rivoplus volumio[3641]: info: Starting RAAT Plugin Oct 12 09:55:28 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , addAdditionalUISections Oct 12 09:55:28 rivoplus volumio[3641]: info: Additional UI Settings Added for plugin music_service/raat Oct 12 09:55:28 rivoplus volumio[3641]: info: Registering DSP Elements listener and retrieving current ones Oct 12 09:55:28 rivoplus volumio[3641]: info: Additional DSP elements updated Oct 12 09:55:28 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:55:28 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:55:28 rivoplus volumio[3641]: info: Updating RAAT Signal Path Oct 12 09:55:28 rivoplus sudo[4275]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 12 09:55:28 rivoplus sudo[4275]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:55:28 rivoplus volumio[3641]: error: Cannot write to RAAT Client: TypeError: Cannot read properties of undefined (reading 'write') Oct 12 09:55:28 rivoplus volumio[3641]: info: Streaming services startup Oct 12 09:55:28 rivoplus volumio[3641]: info: Starting Streaming Daemon Oct 12 09:55:28 rivoplus sudo[4275]: pam_unix(sudo:session): session closed for user root Oct 12 09:55:28 rivoplus volumio[3641]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 12 09:55:28 rivoplus volumio[3641]: info: [1791798928424] CoreMusicLibrary::Adding element Webradio Oct 12 09:55:28 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 12 09:55:28 rivoplus sudo[4280]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 12 09:55:28 rivoplus sudo[4280]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:55:28 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 12 09:55:28 rivoplus volumio[3641]: info: Initializing BBC Radios Oct 12 09:55:28 rivoplus sudo[4287]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 12 09:55:28 rivoplus sudo[4287]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:55:28 rivoplus sudo[4285]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 12 09:55:28 rivoplus sudo[4285]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:55:28 rivoplus sudo[4280]: pam_unix(sudo:session): session closed for user root Oct 12 09:55:28 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 12 09:55:28 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 09:55:28 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Oct 12 09:55:28 rivoplus volumio[3641]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 12 09:55:28 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:55:28 rivoplus systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Oct 12 09:55:28 rivoplus sudo[4285]: pam_unix(sudo:session): session closed for user root Oct 12 09:55:28 rivoplus volumio[3641]: error: Hi Res Audio Failed Login: Missing Login Data Oct 12 09:55:28 rivoplus volumio[3641]: info: Adding HIGHRESAUDIO REST API Endpoints Oct 12 09:55:28 rivoplus volumio[3641]: info: Adding saveAccountData_hi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Oct 12 09:55:28 rivoplus sudo[4287]: pam_unix(sudo:session): session closed for user root Oct 12 09:55:28 rivoplus volumio[3641]: info: Initializing Serial Communication on port /dev/ttyS3 Oct 12 09:55:28 rivoplus kernel: meson_uart ffd22000.serial: ttyS3 use xtal(24M) 24000000 change 0 to 9600 Oct 12 09:55:28 rivoplus kernel: meson_uart ffd22000.serial: ttyS3 use xtal(24M) 24000000 change 9600 to 9600 Oct 12 09:55:28 rivoplus kernel: meson_uart ffd22000.serial: ttyS3 use xtal(24M) 24000000 change 9600 to 115200 Oct 12 09:55:28 rivoplus volumio[3641]: info: Touch Event Listener Process Starting Oct 12 09:55:28 rivoplus volumio[3641]: info: Adding inputs REST Endpoints Oct 12 09:55:28 rivoplus volumio[3641]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Oct 12 09:55:28 rivoplus volumio[3641]: info: Scanning Audio Inputs Oct 12 09:55:28 rivoplus sudo[4307]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/xinput --test-xi2 --root Oct 12 09:55:28 rivoplus sudo[4307]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:55:28 rivoplus volumio[3641]: info: Checking against Known Cards name Oct 12 09:55:28 rivoplus volumio[3641]: info: Checking against Known Cards name Oct 12 09:55:28 rivoplus volumio[3641]: info: Checking against Known Cards name Oct 12 09:55:28 rivoplus volumio[3641]: info: Checking against Known Cards name Oct 12 09:55:28 rivoplus volumio[3641]: info: Checking against Known Cards name Oct 12 09:55:28 rivoplus volumio[3641]: info: Adding Server instance for streaming Oct 12 09:55:28 rivoplus sudo[4317]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x25 0x0 Oct 12 09:55:28 rivoplus sudo[4317]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:55:28 rivoplus sudo[4317]: pam_unix(sudo:session): session closed for user root Oct 12 09:55:28 rivoplus sudo[4320]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x28 0x0 Oct 12 09:55:28 rivoplus sudo[4320]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:55:28 rivoplus sudo[4320]: pam_unix(sudo:session): session closed for user root Oct 12 09:55:28 rivoplus sudo[4323]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x29 0x1 Oct 12 09:55:28 rivoplus sudo[4323]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:55:28 rivoplus sudo[4323]: pam_unix(sudo:session): session closed for user root Oct 12 09:55:28 rivoplus sudo[4326]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x33 0x1 Oct 12 09:55:28 rivoplus sudo[4326]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:55:28 rivoplus sudo[4326]: pam_unix(sudo:session): session closed for user root Oct 12 09:55:28 rivoplus volumio[3641]: info: Apply VIM3L Onboard LEDs Settings Oct 12 09:55:28 rivoplus volumio[3641]: info: Turning On Onboard LEDs Oct 12 09:55:28 rivoplus volumio[3641]: info: Starting CEC Event listener Oct 12 09:55:28 rivoplus volumio[3641]: info: Volumio Calling Home Oct 12 09:55:28 rivoplus sudo[4331]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/sys_led/trigger Oct 12 09:55:28 rivoplus sudo[4331]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:55:28 rivoplus sudo[4331]: pam_unix(sudo:session): session closed for user root Oct 12 09:55:28 rivoplus sudo[4336]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/red_red/trigger Oct 12 09:55:28 rivoplus sudo[4336]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:55:28 rivoplus sudo[4336]: pam_unix(sudo:session): session closed for user root Oct 12 09:55:28 rivoplus volumio[3641]: info: QobuzConnect: Opened /tmp/qbz-connect.socket socket, listening for connections Oct 12 09:55:28 rivoplus volumio[3641]: (node:3641) [DEP0005] DeprecationWarning: Buffer() is deprecated due to security and usability issues. Please use the Buffer.alloc(), Buffer.allocUnsafe(), or Buffer.from() methods instead. Oct 12 09:55:28 rivoplus volumio[3641]: (Use `node --trace-deprecation ...` to show where the warning was created) Oct 12 09:55:29 rivoplus volumio[3641]: info: Stopping AccessToken refresher cron for QOBUZ Oct 12 09:55:29 rivoplus volumio[3641]: info: AccessToken refresher cron started for QOBUZ Oct 12 09:55:29 rivoplus volumio[3641]: info: Adding TIDAL REST API Endpoints Oct 12 09:55:29 rivoplus volumio[3641]: info: Adding QOBUZ REST API Endpoints Oct 12 09:55:29 rivoplus volumio[3641]: verbose: New Socket.io Connection to 192.168.2.160 from 192.168.2.120 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/155.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Oct 12 09:55:29 rivoplus volumio[3641]: info: Serial port opened successfully Oct 12 09:55:29 rivoplus volumio[3641]: info: Sending serial start messages Oct 12 09:55:29 rivoplus sudo[4347]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hostapd/hostapd.conf Oct 12 09:55:29 rivoplus sudo[4347]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:55:29 rivoplus sudo[4347]: pam_unix(sudo:session): session closed for user root Oct 12 09:55:29 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:55:29 rivoplus volumio[3641]: info: Reporting MCU Network Status: 1 Oct 12 09:55:29 rivoplus volumio[3641]: error: Cannot start Volumio Streaming Daemon Oct 12 09:55:29 rivoplus volumio[3641]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 12 09:55:29 rivoplus volumio[3641]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 12 09:55:29 rivoplus volumio[3641]: info: RAAT Albumart path created successfully Oct 12 09:55:29 rivoplus volumio[3641]: ------------------------------------ BT MESSAGE: Bluetooth adapter powered on Oct 12 09:55:29 rivoplus volumio[3641]: info: MPD Permissions set Oct 12 09:55:29 rivoplus volumio[3641]: info: MPD Permissions set Oct 12 09:55:29 rivoplus sudo[4350]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Oct 12 09:55:29 rivoplus sudo[4350]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:55:29 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setDeviceVolumeOverride Oct 12 09:55:29 rivoplus volumio[3641]: info: Setting Device Volume Override Oct 12 09:55:29 rivoplus volumio[3641]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 12 09:55:29 rivoplus volumio[3641]: info: Updating Volume Controller Parameters: Device: 5 Name: S/PDIF + AES/EBU + I2S Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Oct 12 09:55:29 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 12 09:55:29 rivoplus volumio[3641]: info: Disabling external Volume Control Oct 12 09:55:29 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:55:29 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:55:29 rivoplus volumio[3641]: info: CoreStateMachine::pushState Oct 12 09:55:29 rivoplus volumio[3641]: info: CorePlayQueue::getTrack 0 Oct 12 09:55:29 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 09:55:29 rivoplus volumio[3641]: info: CoreCommandRouter::volumioPushState Oct 12 09:55:29 rivoplus volumio[3641]: info: CoreCommandRouter::volumioGetState Oct 12 09:55:29 rivoplus volumio[3641]: info: CorePlayQueue::getTrack 0 Oct 12 09:55:29 rivoplus systemd[1]: Started volumiobt.service - Volumio Bluetooth Module. Oct 12 09:55:29 rivoplus sudo[4350]: pam_unix(sudo:session): session closed for user root Oct 12 09:55:29 rivoplus volumio[3641]: info: CoreStateMachine::pushState Oct 12 09:55:29 rivoplus volumio[3641]: info: CorePlayQueue::getTrack 0 Oct 12 09:55:29 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 09:55:29 rivoplus volumio[3641]: info: CoreCommandRouter::volumioPushState Oct 12 09:55:29 rivoplus volumio[3641]: info: CoreCommandRouter::volumioGetState Oct 12 09:55:29 rivoplus volumio[3641]: info: CorePlayQueue::getTrack 0 Oct 12 09:55:29 rivoplus volumio[3641]: info: Discovery: adding fa7175c8-ab23-4fd7-af7e-93a21ac560e4 Oct 12 09:55:29 rivoplus volumio[3641]: info: Discovery: Found device Rivoplus Oct 12 09:55:29 rivoplus volumio[3641]: info: CoreCommandRouter::volumioGetState Oct 12 09:55:29 rivoplus volumio[3641]: info: CorePlayQueue::getTrack 0 Oct 12 09:55:29 rivoplus volumiobt[4358]: INFO [BTSTART] Ensuring Bluetooth directory exists... Oct 12 09:55:29 rivoplus volumio[3641]: info: MRS: Pushing multiroomSync output for this device Oct 12 09:55:29 rivoplus volumio[3641]: info: MRS: Pushing multiroomSync output Oct 12 09:55:29 rivoplus volumio[3641]: info: Adding audio output: Oct 12 09:55:29 rivoplus volumio[3641]: info: Adding audio output: Oct 12 09:55:29 rivoplus sudo[4359]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Oct 12 09:55:29 rivoplus sudo[4359]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:55:29 rivoplus sudo[4360]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth Oct 12 09:55:29 rivoplus sudo[4360]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:55:29 rivoplus sudo[4360]: pam_unix(sudo:session): session closed for user root Oct 12 09:55:29 rivoplus sudo[4359]: pam_unix(sudo:session): session closed for user root Oct 12 09:55:29 rivoplus sudo[4363]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth Oct 12 09:55:29 rivoplus sudo[4363]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:55:29 rivoplus sudo[4363]: pam_unix(sudo:session): session closed for user root Oct 12 09:55:29 rivoplus volumio[3641]: verbose: New Socket.io Connection to 192.168.2.160 from 192.168.2.120 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/155.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Oct 12 09:55:29 rivoplus volumiobt[4365]: INFO [BTSTART] Powering on Bluetooth if needed... Oct 12 09:55:29 rivoplus volumio[3641]: verbose: New Socket.io Connection to 192.168.2.160 from 192.168.2.120 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/155.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Oct 12 09:55:29 rivoplus volumio[3641]: info: Permissions for /etc/hostapd/hostapd.conf Oct 12 09:55:29 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Oct 12 09:55:29 rivoplus volumio[3641]: info: Volumio called home Oct 12 09:55:29 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: system , setAdditionalSVInfo Oct 12 09:55:29 rivoplus volumio[3641]: info: Setting Additional System Software info: Hardware Revision: 1.0 Oct 12 09:55:29 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: system , setHwFwVersion Oct 12 09:55:29 rivoplus volumio[3641]: info: Setting HW Firmware info: undefined Oct 12 09:55:29 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: system , setHwVersion Oct 12 09:55:29 rivoplus volumio[3641]: info: Setting HW Version info: 1.0 Oct 12 09:55:29 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: system , setAdditionalSVInfo Oct 12 09:55:29 rivoplus volumio[3641]: info: Setting Additional System Software info: Hardware Revision: 1.0, Firmware Version: 0.3.0 Oct 12 09:55:29 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: system , setHwFwVersion Oct 12 09:55:29 rivoplus volumio[3641]: info: Setting HW Firmware info: 0.3.0 Oct 12 09:55:29 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: system , setHwVersion Oct 12 09:55:29 rivoplus volumio[3641]: info: Setting HW Version info: 1.0 Oct 12 09:55:29 rivoplus volumio[3641]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Oct 12 09:55:29 rivoplus volumio[3641]: info: MCU Signalled Headphone Mode Disabled Oct 12 09:55:29 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Oct 12 09:55:29 rivoplus volumio[3641]: info: Reporting Headphone State: false Oct 12 09:55:29 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:55:29 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:55:29 rivoplus volumio[3641]: info: Updating RAAT Signal Path Oct 12 09:55:29 rivoplus volumio[3641]: error: Cannot write to RAAT Client: TypeError: Cannot read properties of undefined (reading 'write') Oct 12 09:55:29 rivoplus volumio[3641]: info: MCU Signalled Sleep Mode Disabled Oct 12 09:55:29 rivoplus sudo[4369]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Oct 12 09:55:29 rivoplus sudo[4369]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:55:29 rivoplus volumio[3641]: info: Enabling Advanced system settings configuration Oct 12 09:55:29 rivoplus bluetoothd[3416]: Adv Monitor app :1.18 disconnected from D-Bus Oct 12 09:55:29 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: system , addAdditionalUISections Oct 12 09:55:29 rivoplus volumio[3641]: info: Additional UI Settings Added for plugin music_service/inputs Oct 12 09:55:29 rivoplus volumio[3641]: info: MCU Signalled Auto Boot Mode On Power Disabled Oct 12 09:55:29 rivoplus volumiobt[4371]: INFO [BTSTART] Making Bluetooth discoverable and pairable... Oct 12 09:55:29 rivoplus systemd[1]: Stopping wireless.service - Wireless Services... Oct 12 09:55:29 rivoplus sudo[4373]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms force on Oct 12 09:55:29 rivoplus sudo[4373]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:55:29 rivoplus systemd[1]: wireless.service: Killing process 3607 (wpa_supplicant) with signal SIGKILL. Oct 12 09:55:29 rivoplus kernel: fb: osd[0] enable: 1 (Xorg) Oct 12 09:55:29 rivoplus sudo[4373]: pam_unix(sudo:session): session closed for user root Oct 12 09:55:29 rivoplus volumiobt[4374]: [176B blob data] Oct 12 09:55:29 rivoplus volumiobt[4374]: [157B blob data] Oct 12 09:55:29 rivoplus volumiobt[4374]: [157B blob data] Oct 12 09:55:29 rivoplus volumiobt[4374]: [157B blob data] Oct 12 09:55:29 rivoplus volumiobt[4374]: [113B blob data] Oct 12 09:55:29 rivoplus volumiobt[4374]: [bluetoothctl]> discoverable on Oct 12 09:55:29 rivoplus volumiobt[4374]: Warning: setting discoverable while discoverable-timeout not set(0) is not recommended Oct 12 09:55:29 rivoplus volumiobt[4374]: [bluetoothctl]> pairable on Oct 12 09:55:30 rivoplus bluetoothd[3416]: Adv Monitor app :1.19 disconnected from D-Bus Oct 12 09:55:30 rivoplus volumiobt[4374]: [bluetoothctl]> Oct 12 09:55:30 rivoplus volumiobt[4376]: INFO [BTSTART] Registering Bluetooth agent... Oct 12 09:55:30 rivoplus volumio[3641]: error: MPD error: The expression evaluated to a falsy value: Oct 12 09:55:30 rivoplus volumio[3641]: assert.ok(self.idling) Oct 12 09:55:30 rivoplus volumio[3641]: error: The expression evaluated to a falsy value: Oct 12 09:55:30 rivoplus volumio[3641]: assert.ok(self.idling) Oct 12 09:55:30 rivoplus volumiobt[4377]: [NEW] Media /org/bluez/hci0 Oct 12 09:55:30 rivoplus volumiobt[4377]: SupportedUUIDs: 0000110a-0000-1000-8000-00805f9b34fb Oct 12 09:55:30 rivoplus volumiobt[4377]: SupportedUUIDs: 0000110b-0000-1000-8000-00805f9b34fb Oct 12 09:55:30 rivoplus volumiobt[4377]: SupportedUUIDs: 0000FDF0-0000-1000-8000-00805f9b34fb Oct 12 09:55:30 rivoplus volumio[3641]: info: Upmpdcli Daemon Started Oct 12 09:55:30 rivoplus volumio[3641]: ------------------------------------ BT MESSAGE: volumiobt.service started successfully Oct 12 09:55:30 rivoplus volumio[3641]: ------------------------------------ BT MESSAGE: [FUNC] dbusStart Oct 12 09:55:30 rivoplus volumio[3641]: info: MPD running with PID4253 Oct 12 09:55:30 rivoplus volumio[3641]: ,establishing connection Oct 12 09:55:30 rivoplus bluetoothd[3416]: Adv Monitor app :1.20 disconnected from D-Bus Oct 12 09:55:30 rivoplus systemd[1]: wireless.service: Deactivated successfully. Oct 12 09:55:30 rivoplus systemd[1]: Stopped wireless.service - Wireless Services. Oct 12 09:55:30 rivoplus systemd[1]: Starting wireless.service - Wireless Services... Oct 12 09:55:30 rivoplus volumiobt[4378]: No agent is registered Oct 12 09:55:30 rivoplus volumiobt[4378]: [NEW] Media /org/bluez/hci0 Oct 12 09:55:30 rivoplus volumiobt[4378]: SupportedUUIDs: 0000110a-0000-1000-8000-00805f9b34fb Oct 12 09:55:30 rivoplus volumiobt[4378]: SupportedUUIDs: 0000110b-0000-1000-8000-00805f9b34fb Oct 12 09:55:30 rivoplus volumiobt[4378]: SupportedUUIDs: 0000FDF0-0000-1000-8000-00805f9b34fb Oct 12 09:55:30 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:55:30 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:55:30 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:55:30 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:55:30 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:55:30 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:55:30 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:55:30 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:55:30 rivoplus bluetoothd[3416]: Adv Monitor app :1.21 disconnected from D-Bus Oct 12 09:55:30 rivoplus volumiobt[4381]: INFO [BTSTART] Agent registered successfully. Oct 12 09:55:30 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 09:55:30 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 12 09:55:30 rivoplus volumiobt[4385]: INFO [BTSTART] Starting A2DP agent (a2dp-agent)... Oct 12 09:55:30 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:55:30 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:55:30 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:55:30 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:55:30 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:55:30 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:55:30 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:55:30 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:55:30 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:55:30 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 09:55:30 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 12 09:55:30 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:55:30 rivoplus volumio[3641]: error: Serial API: Failed to decode command: MAXVOL, message: 100 Oct 12 09:55:30 rivoplus volumio[3641]: info: Discovery: this is already registered, fa7175c8-ab23-4fd7-af7e-93a21ac560e4 Oct 12 09:55:30 rivoplus volumio[3641]: info: Discovery: Found device Rivoplus Oct 12 09:55:30 rivoplus volumio[3641]: info: CoreCommandRouter::volumioGetState Oct 12 09:55:30 rivoplus volumio[3641]: info: CorePlayQueue::getTrack 0 Oct 12 09:55:30 rivoplus volumio[3641]: error: updateQueue error: null Oct 12 09:55:30 rivoplus sudo[4403]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms 0 0 0 Oct 12 09:55:30 rivoplus sudo[4403]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:55:30 rivoplus sudo[4403]: pam_unix(sudo:session): session closed for user root Oct 12 09:55:30 rivoplus volumio[3641]: info: CorePlayQueue::getTrack 0 Oct 12 09:55:30 rivoplus volumio[3641]: info: MP1 GPIO: Signalled system ready via GPIO Oct 12 09:55:30 rivoplus volumio[3641]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 6 Oct 12 09:55:30 rivoplus volumio[3641]: error: updateQueue error: null Oct 12 09:55:30 rivoplus volumio[3641]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 6 Oct 12 09:55:30 rivoplus volumio[3641]: info: Received Get System Info Oct 12 09:55:30 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 12 09:55:30 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 12 09:55:30 rivoplus volumio[3641]: info: Discovery: Getting this device information Oct 12 09:55:30 rivoplus volumio[3641]: info: CoreCommandRouter::volumioGetState Oct 12 09:55:30 rivoplus volumio[3641]: info: CorePlayQueue::getTrack 0 Oct 12 09:55:30 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 12 09:55:30 rivoplus volumio5-onboarding[4220]: time=2026-10-12T09:55:30.688Z level=INFO msg="system info for cdf97801a86b3021d9ddab76432277d9" deviceName=Rivoplus deviceVariant=rivoplus deviceModel="Volumio Rivo+" softwareVersion=4.208 Oct 12 09:55:30 rivoplus volumio5-onboarding[4220]: time=2026-10-12T09:55:30.706Z level=INFO msg="bootstrapping state" hasInternet=true Oct 12 09:55:30 rivoplus volumio[3641]: info: Starting Shairport Sync Oct 12 09:55:30 rivoplus volumio[3641]: info: Starting Shairport Sync Oct 12 09:55:30 rivoplus volumio[3641]: info: Starting Shairport Sync Oct 12 09:55:30 rivoplus sudo[4410]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 12 09:55:30 rivoplus sudo[4410]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:55:30 rivoplus volumio[3641]: info: Adding Inputs via Serial API Oct 12 09:55:30 rivoplus sudo[4411]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 12 09:55:30 rivoplus sudo[4411]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:55:30 rivoplus sudo[4416]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 12 09:55:30 rivoplus sudo[4416]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:55:30 rivoplus systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 12 09:55:30 rivoplus systemd[1]: shairport-sync.service: Deactivated successfully. Oct 12 09:55:30 rivoplus systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 12 09:55:31 rivoplus systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 12 09:55:31 rivoplus volumio[3641]: info: Executing endpoint qc_getconfig Oct 12 09:55:31 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 12 09:55:31 rivoplus sudo[4416]: pam_unix(sudo:session): session closed for user root Oct 12 09:55:31 rivoplus sudo[4410]: pam_unix(sudo:session): session closed for user root Oct 12 09:55:31 rivoplus sudo[4411]: pam_unix(sudo:session): session closed for user root Oct 12 09:55:31 rivoplus qobuz-connect[4302]: 20261012 09:55:31.097 [4302.4302] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 12 09:55:31 rivoplus volumio[3641]: info: Adding Advanced Audio Settings via Serial API Oct 12 09:55:31 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , addAdditionalUISections Oct 12 09:55:31 rivoplus volumio[3641]: info: Additional UI Settings Added for plugin music_service/inputs Oct 12 09:55:31 rivoplus volumio[3641]: info: Received Get System Info Oct 12 09:55:31 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 12 09:55:31 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 12 09:55:31 rivoplus volumio[3641]: info: Discovery: Getting this device information Oct 12 09:55:31 rivoplus volumio[3641]: info: CoreCommandRouter::volumioGetState Oct 12 09:55:31 rivoplus volumio[3641]: info: CorePlayQueue::getTrack 0 Oct 12 09:55:31 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 12 09:55:31 rivoplus volumio[3641]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Oct 12 09:55:31 rivoplus volumio[3641]: info: QobuzConnect: QOBUZ Connect daemon connected Oct 12 09:55:31 rivoplus volumio[3641]: verbose: New Socket.io Connection to 192.168.2.160 from 192.168.2.120 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/155.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Oct 12 09:55:31 rivoplus volumio[3641]: info: Shairport-Sync Started Oct 12 09:55:31 rivoplus volumio[3641]: Error adding Membership: Error: addMembership EINVAL Oct 12 09:55:31 rivoplus volumio[3641]: info: Shairport-Sync Started Oct 12 09:55:31 rivoplus volumio[3641]: info: Shairport-Sync Started Oct 12 09:55:31 rivoplus volumio[3641]: info: Preparing to generate the ALSA configuration file Oct 12 09:55:31 rivoplus qobuz-connect[4302]: 20261012 09:55:31.317 [4302.4302] INFO VolumeManager: [0xab482aa8]: Setting new playback volume: 75 Oct 12 09:55:31 rivoplus qobuz-connect[4302]: 20261012 09:55:31.317 [4302.4302] INFO VolumeManager: [0xab482aa8]: Setting new mute state: 0 Oct 12 09:55:31 rivoplus qobuz-connect[4302]: 20261012 09:55:31.317 [4302.4302] INFO AudioStreamManager: [0xab482800]: Setting new audio download buffer size: 1048576 Oct 12 09:55:31 rivoplus qobuz-connect[4302]: 20261012 09:55:31.317 [4302.4302] INFO QobuzConnect: [0xab483370]: Client initialized! Oct 12 09:55:31 rivoplus qobuz-connect[4302]: 20261012 09:55:31.317 [4302.4302] INFO SampleApp: Starting Avahi advertising, name: Rivoplus, service name: _qobuz-connect._tcp Oct 12 09:55:31 rivoplus qobuz-connect[4302]: 20261012 09:55:31.344 [4302.4302] INFO LocalConfigManager: [0xab482528]: Starting Local Configuration server Oct 12 09:55:31 rivoplus qobuz-connect[4302]: 20261012 09:55:31.344 [4302.4302] INFO SampleApp: Starting Local configuration server Oct 12 09:55:31 rivoplus qobuz-connect[4302]: 20261012 09:55:31.344 [4302.4302] INFO SampleApp: Connected to UNIX socket client 0xab46d908 Oct 12 09:55:31 rivoplus qobuz-connect[4302]: 20261012 09:55:31.344 [4302.4302] INFO SampleApp: Playback volume changed: 75 Oct 12 09:55:31 rivoplus wireless.js[4379]: WIRELESS.JS - INFO: Single Network Mode enabled (default) - only one network device can be active at a time between ethernet and wireless Oct 12 09:55:31 rivoplus volumio[3641]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Oct 12 09:55:31 rivoplus wireless.js[4379]: WIRELESS.JS - INFO: === SNM TRANSITION === Oct 12 09:55:31 rivoplus wireless.js[4379]: WIRELESS.JS - INFO: Previous ethernet state: disconnected Oct 12 09:55:31 rivoplus wireless.js[4379]: WIRELESS.JS - INFO: New ethernet state: connected Oct 12 09:55:31 rivoplus volumio[3641]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 12 09:55:31 rivoplus wireless.js[4379]: WIRELESS.JS - INFO: Single Network Mode: enabled Oct 12 09:55:31 rivoplus volumio[3641]: info: Reading ALSA contributions from plugins. Oct 12 09:55:31 rivoplus wireless.js[4379]: WIRELESS.JS - INFO: First start: yes Oct 12 09:55:31 rivoplus wireless.js[4379]: WIRELESS.JS - INFO: Action: Switch to ethernet (WiFi scan mode) Oct 12 09:55:31 rivoplus wireless.js[4379]: WIRELESS.JS - INFO: === END TRANSITION === Oct 12 09:55:31 rivoplus volumio[3641]: info: CoreStateMachine::pushState Oct 12 09:55:31 rivoplus volumio[3641]: info: CorePlayQueue::getTrack 0 Oct 12 09:55:31 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 09:55:31 rivoplus volumio[3641]: info: CoreCommandRouter::volumioPushState Oct 12 09:55:31 rivoplus volumio[3641]: info: CoreCommandRouter::volumioGetState Oct 12 09:55:31 rivoplus volumio[3641]: info: CorePlayQueue::getTrack 0 Oct 12 09:55:31 rivoplus volumio[3641]: info: MRS: Pushing multiroomSync output update for this device Oct 12 09:55:31 rivoplus wireless.js[4379]: WIRELESS.JS - INFO: Wireless.js initializing wireless flow Oct 12 09:55:31 rivoplus volumio[3641]: info: MRS: Pushing multiroomSync output Oct 12 09:55:31 rivoplus volumio[3641]: info: CoreCommandRouter::servicePushState Oct 12 09:55:31 rivoplus volumio[3641]: info: CoreStateMachine::pushState Oct 12 09:55:31 rivoplus volumio[3641]: info: CorePlayQueue::getTrack 0 Oct 12 09:55:31 rivoplus volumio[3641]: info: CoreCommandRouter::volumioPushState Oct 12 09:55:31 rivoplus volumio[3641]: info: CoreCommandRouter::volumioGetState Oct 12 09:55:31 rivoplus volumio[3641]: info: CorePlayQueue::getTrack 0 Oct 12 09:55:31 rivoplus volumio[3641]: info: MRS: Pushing multiroomSync output update for this device Oct 12 09:55:31 rivoplus volumio[3641]: info: MRS: Pushing multiroomSync output Oct 12 09:55:31 rivoplus volumio[3641]: info: CorePlayQueue::getTrack 0 Oct 12 09:55:31 rivoplus volumio[3641]: verbose: STATE SERVICE {"status":"stop","service":"inputs","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"","seek":0,"duration":0,"samplerate":"","bitdepth":"","stream":true,"disableUiControls":false,"channels":2} Oct 12 09:55:31 rivoplus volumio[3641]: verbose: CURRENT POSITION 0 Oct 12 09:55:31 rivoplus volumio[3641]: info: CoreStateMachine::syncState stateService stop Oct 12 09:55:31 rivoplus volumio[3641]: info: CoreStateMachine::syncState currentStatus stop Oct 12 09:55:31 rivoplus volumio[3641]: info: CoreStateMachine::pushState Oct 12 09:55:31 rivoplus volumio[3641]: info: CorePlayQueue::getTrack 0 Oct 12 09:55:31 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 09:55:31 rivoplus volumio[3641]: info: CoreCommandRouter::volumioPushState Oct 12 09:55:31 rivoplus volumio[3641]: info: CoreCommandRouter::volumioGetState Oct 12 09:55:31 rivoplus volumio[3641]: info: CorePlayQueue::getTrack 0 Oct 12 09:55:31 rivoplus volumio[3641]: info: MRS: Pushing multiroomSync output update for this device Oct 12 09:55:31 rivoplus volumio[3641]: info: MRS: Pushing multiroomSync output Oct 12 09:55:31 rivoplus volumio[3641]: info: No code Oct 12 09:55:31 rivoplus volumio[3641]: info: CoreStateMachine::pushState Oct 12 09:55:31 rivoplus volumio[3641]: info: CorePlayQueue::getTrack 0 Oct 12 09:55:31 rivoplus volumio[3641]: info: CoreCommandRouter::volumioPushState Oct 12 09:55:31 rivoplus volumio[3641]: info: CoreCommandRouter::volumioGetState Oct 12 09:55:31 rivoplus volumio[3641]: info: CorePlayQueue::getTrack 0 Oct 12 09:55:31 rivoplus volumio[3641]: info: MRS: Pushing multiroomSync output update for this device Oct 12 09:55:31 rivoplus volumio[3641]: info: MRS: Pushing multiroomSync output Oct 12 09:55:31 rivoplus volumio[3641]: info: CoreCommandRouter::volumiosetSourceActiveno-source Oct 12 09:55:31 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 12 09:55:31 rivoplus volumio[3641]: info: CoreCommandRouter::volumioGetState Oct 12 09:55:31 rivoplus volumio[3641]: info: CorePlayQueue::getTrack 0 Oct 12 09:55:31 rivoplus volumio[3641]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Oct 12 09:55:31 rivoplus volumiobt[4387]: 2026-10-12 09:55:31 a2dp-agent [INFO] Connecting to system D-Bus Oct 12 09:55:31 rivoplus volumiobt[4387]: 2026-10-12 09:55:31 a2dp-agent [INFO] Connected to system D-Bus Oct 12 09:55:31 rivoplus volumiobt[4387]: 2026-10-12 09:55:31 bluezutils [INFO] Found adapter at: /org/bluez/hci0 Oct 12 09:55:31 rivoplus volumiobt[4387]: 2026-10-12 09:55:31 a2dp-agent [INFO] Found Bluetooth adapter: /org/bluez/hci0 Oct 12 09:55:31 rivoplus volumiobt[4387]: 2026-10-12 09:55:31 a2dp-agent [INFO] Set DiscoverableTimeout to infinite Oct 12 09:55:31 rivoplus volumiobt[4387]: 2026-10-12 09:55:31 a2dp-agent [INFO] Enabled Discoverable mode Oct 12 09:55:31 rivoplus volumio[3641]: info: Turning Off Onboard LEDs Oct 12 09:55:31 rivoplus volumiobt[4387]: 2026-10-12 09:55:31 a2dp-agent [INFO] Agent registered at /local/a2dpagent Oct 12 09:55:31 rivoplus volumiobt[4387]: 2026-10-12 09:55:31 a2dp-agent [INFO] Agent set as default Oct 12 09:55:31 rivoplus volumiobt[4387]: 2026-10-12 09:55:31 a2dp-agent [INFO] A2DP agent running, waiting for connections... Oct 12 09:55:31 rivoplus sudo[4454]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/sys_led/trigger Oct 12 09:55:31 rivoplus sudo[4454]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:55:31 rivoplus sudo[4454]: pam_unix(sudo:session): session closed for user root Oct 12 09:55:31 rivoplus volumio[3641]: info: TidalConnect service stoped! Oct 12 09:55:31 rivoplus sudo[4457]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/red_red/trigger Oct 12 09:55:31 rivoplus sudo[4457]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:55:31 rivoplus sudo[4457]: pam_unix(sudo:session): session closed for user root Oct 12 09:55:32 rivoplus volumio[3641]: info: Onboard LEDs initialized Oct 12 09:55:32 rivoplus volumio[3641]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 12 09:55:32 rivoplus volumio[3641]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 12 09:55:32 rivoplus sudo[4472]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 12 09:55:32 rivoplus sudo[4472]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:55:32 rivoplus volumio[3641]: info: CoreCommandRouter::volumioGetState Oct 12 09:55:32 rivoplus volumio[3641]: info: CorePlayQueue::getTrack 0 Oct 12 09:55:32 rivoplus sudo[4475]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Oct 12 09:55:32 rivoplus volumio[3641]: info: CoreCommandRouter::volumioGetState Oct 12 09:55:32 rivoplus volumio[3641]: info: CorePlayQueue::getTrack 0 Oct 12 09:55:32 rivoplus sudo[4475]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 12 09:55:32 rivoplus volumio[3641]: info: Asound.conf file written Oct 12 09:55:32 rivoplus volumio-remote-updater[2959]: [2026-10-12 09:55:32] [connect] Successful connection Oct 12 09:55:32 rivoplus sudo[4475]: pam_unix(sudo:session): session closed for user root Oct 12 09:55:32 rivoplus systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Oct 12 09:55:32 rivoplus sudo[4472]: pam_unix(sudo:session): session closed for user root Oct 12 09:55:32 rivoplus sudo[4480]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Oct 12 09:55:32 rivoplus sudo[4481]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Oct 12 09:55:32 rivoplus sudo[4481]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:55:32 rivoplus sudo[4480]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 12 09:55:32 rivoplus sudo[4481]: pam_unix(sudo:session): session closed for user root Oct 12 09:55:32 rivoplus kernel: dhd_stop: Enter ffffffc04f912000 Oct 12 09:55:32 rivoplus kernel: WLC_E_IF: NO_IF set, event Ignored Oct 12 09:55:32 rivoplus kernel: CFG80211-ERROR) wl_cfgp2p_down : Clean up the p2p discovery IF Oct 12 09:55:32 rivoplus kernel: wl_cfgp2p_del_p2p_disc_if: wdev: ffffffc043506400, wdev->net: (null) Oct 12 09:55:32 rivoplus kernel: CFGP2P-ERROR) wl_cfgp2p_disable_discovery : do nothing, not initialized Oct 12 09:55:32 rivoplus kernel: Exit. P2P interface stopped Oct 12 09:55:32 rivoplus volumio[3641]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 12 09:55:32 rivoplus volumio[3641]: No state is present for card AMLAUGESOUNDMP1 Oct 12 09:55:32 rivoplus volumio[3641]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 12 09:55:32 rivoplus volumio[3641]: Found hardware: "AML-AUGESOUND-M" "" "" "" "" Oct 12 09:55:32 rivoplus volumio[3641]: Hardware is initialized using a generic method Oct 12 09:55:32 rivoplus volumio[3641]: No state is present for card AMLAUGESOUNDMP1 Oct 12 09:55:32 rivoplus volumio[3641]: No state is present for card Device Oct 12 09:55:32 rivoplus volumio[3641]: Found hardware: "USB-Audio" "USB Mixer" "USB2fc6:6016" "" "" Oct 12 09:55:32 rivoplus volumio[3641]: Hardware is initialized using a generic method Oct 12 09:55:32 rivoplus volumio[3641]: No state is present for card Device Oct 12 09:55:32 rivoplus volumio[3641]: info: Output device has changed, restarting MPD Oct 12 09:55:32 rivoplus kernel: P2P interface unregistered Oct 12 09:55:32 rivoplus kernel: CFGP2P-ERROR) wl_cfgp2p_deinit_priv : In Oct 12 09:55:32 rivoplus kernel: dhd_tcpack_suppress_set: TCP ACK Suppress mode 1 -> mode 0 Oct 12 09:55:32 rivoplus kernel: wl_android_wifi_off in 1 Oct 12 09:55:32 rivoplus kernel: wl_android_wifi_off in 2: g_wifi_on=1, on_failure=1 Oct 12 09:55:32 rivoplus kernel: dhd_dbg_detach_pkt_monitor, 2204 Oct 12 09:55:32 rivoplus kernel: dhd_bus_devreset: == Power OFF == Oct 12 09:55:32 rivoplus kernel: bcmsdh_oob_intr_unregister: Enter Oct 12 09:55:32 rivoplus kernel: dhd_txglom_enable: enable 0 Oct 12 09:55:32 rivoplus kernel: dhd_conf_set_txglom_params: txglom_mode=copy Oct 12 09:55:32 rivoplus kernel: dhd_conf_set_txglom_params: txglomsize=0, deferred_tx_len=0 Oct 12 09:55:32 rivoplus kernel: dhd_conf_set_txglom_params: txinrx_thres=128, dhd_txminmax=-1 Oct 12 09:55:32 rivoplus kernel: dhd_conf_set_txglom_params: tx_max_offset=0, txctl_tmo_fix=300 Oct 12 09:55:32 rivoplus kernel: dhd_bus_devreset: WLAN OFF DONE Oct 12 09:55:32 rivoplus kernel: wifi_platform_set_power = 0 Oct 12 09:55:32 rivoplus kernel: ======== PULL WL_REG_ON(-1) LOW! ======== Oct 12 09:55:32 rivoplus kernel: aml_wifi wifi: [extern_wifi_set_enable] WIFI Disable! 482 Oct 12 09:55:32 rivoplus volumio[3641]: info: Output device has changed, restarting Shairport Sync Oct 12 09:55:32 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:55:32 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 09:55:32 rivoplus volumio[3641]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 12 09:55:32 rivoplus volumio[3641]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 12 09:55:32 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:55:32 rivoplus volumio[3641]: info: QobuzConnect: setDeactiveState invoked Oct 12 09:55:32 rivoplus volumio[3641]: info: CoreCommandRouter::volumioGetState Oct 12 09:55:32 rivoplus volumio[3641]: info: CorePlayQueue::getTrack 0 Oct 12 09:55:32 rivoplus volumio[3641]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Oct 12 09:55:32 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:55:32 rivoplus volumio[3641]: info: Preparing to generate the ALSA configuration file Oct 12 09:55:32 rivoplus kernel: wl_android_wifi_off out Oct 12 09:55:32 rivoplus kernel: wl_ext_iapsta_dettach_netdev: Enter Oct 12 09:55:32 rivoplus kernel: dhd_stop: Exit Oct 12 09:55:32 rivoplus dhcpcd[3059]: wlan0: carrier lost Oct 12 09:55:32 rivoplus sudo[4499]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 12 09:55:32 rivoplus sudo[4499]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:55:32 rivoplus sudo[4501]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 12 09:55:32 rivoplus sudo[4501]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:55:32 rivoplus sudo[4492]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 12 09:55:32 rivoplus sudo[4492]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:55:32 rivoplus sudo[4491]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 12 09:55:32 rivoplus sudo[4491]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:55:32 rivoplus sudo[4480]: pam_unix(sudo:session): session closed for user root Oct 12 09:55:32 rivoplus wireless.js[4379]: WIRELESS.JS - INFO: Cleaning previous... Oct 12 09:55:32 rivoplus sudo[4491]: pam_unix(sudo:session): session closed for user root Oct 12 09:55:32 rivoplus systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Oct 12 09:55:32 rivoplus volumio[3641]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 12 09:55:32 rivoplus sudo[4515]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 12 09:55:32 rivoplus sudo[4515]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:55:32 rivoplus volumio[3641]: info: Reading ALSA contributions from plugins. Oct 12 09:55:32 rivoplus systemd[1]: vtcs.service: Deactivated successfully. Oct 12 09:55:32 rivoplus systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Oct 12 09:55:32 rivoplus sudo[4518]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Oct 12 09:55:32 rivoplus sudo[4518]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 12 09:55:33 rivoplus volumio[3641]: info: MPD Permissions set Oct 12 09:55:33 rivoplus volumio-remote-updater[2959]: [2026-10-12 09:55:33] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1791798932 101 Oct 12 09:55:33 rivoplus volumio[3641]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 10 Oct 12 09:55:33 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:55:33 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:55:33 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:55:33 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:55:33 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:55:33 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:55:33 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:55:33 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:55:33 rivoplus kernel: dhd_open: Enter ffffffc04f912000 Oct 12 09:55:33 rivoplus kernel: dhd_open : no mutex held. set lock Oct 12 09:55:33 rivoplus kernel: Dongle Host Driver, version 1.579.77.41.10 (r) Oct 12 09:55:33 rivoplus kernel: wl_ext_iapsta_attach_netdev: ifidx=0, bssidx=0 Oct 12 09:55:33 rivoplus kernel: wl_android_wifi_on in 1 Oct 12 09:55:33 rivoplus kernel: wl_android_wifi_on in 2: g_wifi_on=0 Oct 12 09:55:33 rivoplus kernel: wifi_platform_set_power = 1 Oct 12 09:55:33 rivoplus kernel: ======== PULL WL_REG_ON(-1) HIGH! ======== Oct 12 09:55:33 rivoplus kernel: aml_wifi wifi: [extern_wifi_set_enable] WIFI Disable! 482 Oct 12 09:55:33 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 09:55:33 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 12 09:55:33 rivoplus sudo[4499]: pam_unix(sudo:session): session closed for user root Oct 12 09:55:33 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:55:33 rivoplus sudo[4515]: pam_unix(sudo:session): session closed for user root Oct 12 09:55:33 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Oct 12 09:55:33 rivoplus systemd[1]: Stopping mpd.service - Music Player Daemon... Oct 12 09:55:33 rivoplus upmpdcli[4254]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Oct 12 09:55:33 rivoplus volumio[3641]: info: MCU Signalled Playback Inactive Oct 12 09:55:33 rivoplus volumio[3641]: info: Starting Shairport Sync Oct 12 09:55:33 rivoplus sudo[4501]: pam_unix(sudo:session): session closed for user root Oct 12 09:55:33 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Oct 12 09:55:33 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:55:33 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:55:33 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:55:33 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:55:33 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:55:33 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:55:33 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:55:33 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:55:33 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 09:55:33 rivoplus volumio[3641]: info: Not Reporting Auto name since its the default one Oct 12 09:55:33 rivoplus volumio[3641]: info: Asound.conf file unchanged, so no further update is needed Oct 12 09:55:33 rivoplus volumio[3641]: info: Output device has changed, restarting MPD Oct 12 09:55:33 rivoplus kernel: aml_wifi wifi: [extern_wifi_set_enable] WIFI Enable! 482 Oct 12 09:55:33 rivoplus volumio[3641]: info: Output device has changed, restarting Shairport Sync Oct 12 09:55:33 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:55:33 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 09:55:33 rivoplus volumio[3641]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 12 09:55:33 rivoplus volumio[3641]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 12 09:55:33 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:55:33 rivoplus volumio[3641]: info: QobuzConnect: setDeactiveState invoked Oct 12 09:55:33 rivoplus volumio[3641]: info: CoreCommandRouter::volumioGetState Oct 12 09:55:33 rivoplus volumio[3641]: info: CorePlayQueue::getTrack 0 Oct 12 09:55:33 rivoplus volumio[3641]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Oct 12 09:55:33 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:55:33 rivoplus systemd[1]: mpd.service: Deactivated successfully. Oct 12 09:55:33 rivoplus systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 12 09:55:33 rivoplus systemd[1]: mpd.socket: Deactivated successfully. Oct 12 09:55:33 rivoplus systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 12 09:55:33 rivoplus systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 12 09:55:33 rivoplus volumio[3641]: info: MRS: Getting audio outputs on start Oct 12 09:55:33 rivoplus volumio[3641]: info: MRS: Requesting all other devices output Oct 12 09:55:33 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 12 09:55:33 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:55:33 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:55:33 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:55:33 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:55:33 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:55:33 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:55:33 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:55:33 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:55:33 rivoplus systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 12 09:55:33 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 09:55:33 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 12 09:55:33 rivoplus systemd[1]: Starting mpd.service - Music Player Daemon... Oct 12 09:55:33 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:55:33 rivoplus volumio[3641]: info: Starting Shairport Sync Oct 12 09:55:33 rivoplus volumio[3641]: verbose: New Socket.io Connection to 192.168.2.160 from 192.168.2.120 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/155.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Oct 12 09:55:33 rivoplus volumio[3641]: info: Command Delete User Data Received Oct 12 09:55:33 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: inputs , setMCUFactoryReset Oct 12 09:55:33 rivoplus volumio[3641]: info: Signalling MCU to Factory Reset Oct 12 09:55:33 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 12 09:55:33 rivoplus volumio[3641]: info: CoreCommandRouter::volumioGetVisibleSources Oct 12 09:55:33 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 12 09:55:33 rivoplus kernel: sdio_reset_comm(): Oct 12 09:55:33 rivoplus kernel: meson-mmc: actual_clock :400000, HHI_nand: 0x80 Oct 12 09:55:33 rivoplus kernel: meson-mmc: [meson_mmc_clk_set_rate_v3] after clock: 0x1000023c Oct 12 09:55:33 rivoplus kernel: meson-mmc: actual_clock :0, HHI_nand: 0x80 Oct 12 09:55:33 rivoplus kernel: meson-mmc: [meson_mmc_clk_set_rate_v3] after clock: 0x1000023c Oct 12 09:55:33 rivoplus volumio[3641]: info: CoreCommandRouter::volumioGetState Oct 12 09:55:33 rivoplus volumio[3641]: info: CorePlayQueue::getTrack 0 Oct 12 09:55:33 rivoplus volumio[3641]: info: CoreCommandRouter::volumioGetState Oct 12 09:55:33 rivoplus volumio[3641]: info: CorePlayQueue::getTrack 0 Oct 12 09:55:33 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 12 09:55:33 rivoplus volumio[3641]: info: CoreCommandRouter::volumioGetQueue Oct 12 09:55:33 rivoplus volumio[3641]: info: CoreStateMachine::getQueue Oct 12 09:55:33 rivoplus volumio[3641]: info: CorePlayQueue::getQueue Oct 12 09:55:33 rivoplus volumio[3641]: info: Listing playlists Oct 12 09:55:33 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 12 09:55:33 rivoplus volumio[3641]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Oct 12 09:55:33 rivoplus kernel: meson-mmc: actual_clock :400000, HHI_nand: 0x80 Oct 12 09:55:33 rivoplus kernel: meson-mmc: [meson_mmc_clk_set_rate_v3] after clock: 0x1000023c Oct 12 09:55:33 rivoplus volumio[3641]: info: Received Get System Info Oct 12 09:55:33 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 12 09:55:33 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 12 09:55:33 rivoplus volumio[3641]: info: Discovery: Getting this device information Oct 12 09:55:33 rivoplus volumio[3641]: info: CoreCommandRouter::volumioGetState Oct 12 09:55:33 rivoplus volumio[3641]: info: CorePlayQueue::getTrack 0 Oct 12 09:55:33 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 12 09:55:33 rivoplus volumio[3641]: info: CoreCommandRouter::volumioGetState Oct 12 09:55:33 rivoplus volumio[3641]: info: CorePlayQueue::getTrack 0 Oct 12 09:55:33 rivoplus kernel: sdio_read_cis: 2 callbacks suppressed Oct 12 09:55:33 rivoplus kernel: sdio: queuing unknown CIS tuple 0x80 (2 bytes) Oct 12 09:55:33 rivoplus kernel: sdio: queuing unknown CIS tuple 0x80 (3 bytes) Oct 12 09:55:33 rivoplus kernel: sdio: queuing unknown CIS tuple 0x80 (3 bytes) Oct 12 09:55:33 rivoplus kernel: sdio: queuing unknown CIS tuple 0x80 (7 bytes) Oct 12 09:55:33 rivoplus kernel: sdio: queuing unknown CIS tuple 0x81 (9 bytes) Oct 12 09:55:33 rivoplus kernel: sdio: queuing unknown CIS tuple 0x8e (9 bytes) Oct 12 09:55:34 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:55:34 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:55:34 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:55:34 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:55:34 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:55:34 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:55:34 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:55:34 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:55:34 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 09:55:34 rivoplus volumio[3641]: info: Not Reporting Auto name since its the default one Oct 12 09:55:34 rivoplus kernel: meson-aml-mmc ffe03000.sdio: divider requested rate 200000000 != actual rate 199999997: ret=0 Oct 12 09:55:34 rivoplus kernel: meson-mmc: actual_clock :199999997, HHI_nand: 0x80 Oct 12 09:55:34 rivoplus kernel: meson-mmc: [meson_mmc_clk_set_rate_v3] after clock: 0x10000245 Oct 12 09:55:34 rivoplus kernel: meson-mmc: Data 1 aligned delay is 0 Oct 12 09:55:34 rivoplus kernel: meson-mmc: sdio: clk 199999997 tuning start Oct 12 09:55:34 rivoplus kernel: meson-mmc: Tuning transfer error: nmatch=0 tuning_err:0xffffffac Oct 12 09:55:34 rivoplus kernel: meson-mmc: sdio: rx_tuning_result[1] = 40 Oct 12 09:55:34 rivoplus kernel: meson-mmc: sdio: rx_tuning_result[2] = 40 Oct 12 09:55:34 rivoplus kernel: meson-mmc: sdio: rx_tuning_result[3] = 40 Oct 12 09:55:34 rivoplus kernel: meson-mmc: sdio: rx_tuning_result[4] = 40 Oct 12 09:55:34 rivoplus kernel: meson-mmc: [prev_map] fixed_adj_win_map:30 Oct 12 09:55:34 rivoplus kernel: meson-mmc: [0]=0 Oct 12 09:55:34 rivoplus kernel: meson-mmc: [1]=1 Oct 12 09:55:34 rivoplus kernel: meson-mmc: [2]=1 Oct 12 09:55:34 rivoplus kernel: meson-mmc: [3]=1 Oct 12 09:55:34 rivoplus kernel: meson-mmc: [4]=1 Oct 12 09:55:34 rivoplus kernel: meson-mmc: [_find_fixed_adj_valid_win]retry test fixed adj... Oct 12 09:55:34 rivoplus kernel: meson-mmc: step:4, delay1:0x4104104, delay2:0x4004104 Oct 12 09:55:34 rivoplus kernel: meson-mmc: Tuning transfer error: nmatch=0 tuning_err:0xffffffac Oct 12 09:55:34 rivoplus kernel: meson-mmc: sdio: rx_tuning_result[1] = 40 Oct 12 09:55:34 rivoplus kernel: meson-mmc: sdio: rx_tuning_result[2] = 40 Oct 12 09:55:34 rivoplus kernel: meson-mmc: sdio: rx_tuning_result[3] = 40 Oct 12 09:55:34 rivoplus kernel: meson-mmc: sdio: rx_tuning_result[4] = 40 Oct 12 09:55:34 rivoplus kernel: meson-mmc: [_find_fixed_adj_valid_win]retry test fixed adj... Oct 12 09:55:34 rivoplus kernel: meson-mmc: step:8, delay1:0x8208208, delay2:0x8008208 Oct 12 09:55:34 rivoplus kernel: meson-mmc: Tuning transfer error: nmatch=0 tuning_err:0xffffffac Oct 12 09:55:34 rivoplus kernel: meson-mmc: sdio: rx_tuning_result[1] = 40 Oct 12 09:55:34 rivoplus kernel: meson-mmc: sdio: rx_tuning_result[2] = 40 Oct 12 09:55:34 rivoplus kernel: meson-mmc: sdio: rx_tuning_result[3] = 40 Oct 12 09:55:34 rivoplus kernel: meson-mmc: sdio: rx_tuning_result[4] = 40 Oct 12 09:55:34 rivoplus kernel: meson-mmc: [_find_fixed_adj_valid_win]retry test fixed adj... Oct 12 09:55:34 rivoplus kernel: meson-mmc: step:12, delay1:0xc30c30c, delay2:0xc00c30c Oct 12 09:55:34 rivoplus kernel: meson-mmc: sdio: rx_tuning_result[0] = 40 Oct 12 09:55:34 rivoplus kernel: meson-mmc: sdio: rx_tuning_result[1] = 40 Oct 12 09:55:34 rivoplus kernel: meson-mmc: sdio: rx_tuning_result[2] = 40 Oct 12 09:55:34 rivoplus kernel: meson-mmc: sdio: rx_tuning_result[3] = 40 Oct 12 09:55:34 rivoplus kernel: meson-mmc: sdio: rx_tuning_result[4] = 40 Oct 12 09:55:34 rivoplus kernel: meson-mmc: [_find_fixed_adj_valid_win]retry test fixed adj... Oct 12 09:55:34 rivoplus kernel: meson-mmc: step:16, delay1:0x10410410, delay2:0x10010410 Oct 12 09:55:34 rivoplus kernel: meson-mmc: sdio: rx_tuning_result[0] = 40 Oct 12 09:55:34 rivoplus kernel: meson-mmc: Tuning transfer error: nmatch=0 tuning_err:0xffffffac Oct 12 09:55:34 rivoplus kernel: meson-mmc: sdio: rx_tuning_result[2] = 40 Oct 12 09:55:34 rivoplus kernel: meson-mmc: sdio: rx_tuning_result[3] = 40 Oct 12 09:55:34 rivoplus kernel: meson-mmc: sdio: rx_tuning_result[4] = 40 Oct 12 09:55:34 rivoplus kernel: meson-mmc: [>>>>>>>>bitmap_xor_dst] fixed_adj_win_map:2 Oct 12 09:55:34 rivoplus kernel: meson-mmc: [0]=0 Oct 12 09:55:34 rivoplus kernel: meson-mmc: [1]=1 Oct 12 09:55:34 rivoplus kernel: meson-mmc: [2]=0 Oct 12 09:55:34 rivoplus kernel: meson-mmc: [3]=0 Oct 12 09:55:34 rivoplus kernel: meson-mmc: [4]=0 Oct 12 09:55:34 rivoplus kernel: meson-mmc: [_find_fixed_adj_valid_win] fir_adj:1 Oct 12 09:55:34 rivoplus kernel: meson-mmc: [>>>>>>>>prev_map_range] fixed_adj_win_map:495 Oct 12 09:55:34 rivoplus kernel: meson-mmc: [1]=1 Oct 12 09:55:34 rivoplus kernel: meson-mmc: [2]=1 Oct 12 09:55:34 rivoplus kernel: meson-mmc: [3]=1 Oct 12 09:55:34 rivoplus kernel: meson-mmc: [4]=1 Oct 12 09:55:34 rivoplus kernel: meson-mmc: [0]=0 Oct 12 09:55:34 rivoplus kernel: meson-mmc: left:3, right:0, mid:4, size:4 Oct 12 09:55:34 rivoplus kernel: meson-mmc: step:0, delay1:0x0, delay2:0x0 Oct 12 09:55:34 rivoplus kernel: meson-mmc: sdio: sd_emmc_regs->gclock=0x10000245,sd_emmc_regs->gadjust=0x32000 Oct 12 09:55:34 rivoplus kernel: meson-mmc: delay1:0x0, delay2:0x0 Oct 12 09:55:34 rivoplus kernel: sdioh_start: set sd_f2_blocksize 256 Oct 12 09:55:34 rivoplus kernel: dhd_bus_devreset: == WLAN ON == Oct 12 09:55:34 rivoplus kernel: F1 signature read @0x18000000=0x17294359 Oct 12 09:55:34 rivoplus kernel: F1 signature OK, socitype:0x1 chip:0x4359 rev:0x9 pkg:0x2 Oct 12 09:55:34 rivoplus kernel: DHD: dongle ram size is set to 917504(orig 917504) at 0x160000 Oct 12 09:55:34 rivoplus kernel: dhd_bus_set_default_min_res_mask: Unhandled chip id Oct 12 09:55:34 rivoplus kernel: dhd_os_open_image: /lib/firmware/brcm/config_bcm4359c0_ag.txt (185 bytes) open success Oct 12 09:55:34 rivoplus kernel: dhd_conf_read_sdio_params: dhd_slpauto = 0 Oct 12 09:55:34 rivoplus kernel: dhd_conf_read_others: ccode = ALL Oct 12 09:55:34 rivoplus kernel: dhd_conf_read_others: regrev = 38 Oct 12 09:55:34 rivoplus kernel: dhd_conf_read_nv_by_chip: nv_by_chip_count=5 Oct 12 09:55:34 rivoplus kernel: dhd_conf_read_nv_by_chip: chip=0xa9a6, chiprev=0, name=nvram_ap6212.txt Oct 12 09:55:34 rivoplus kernel: dhd_conf_read_nv_by_chip: chip=0xa9a6, chiprev=1, name=nvram_ap6212a.txt Oct 12 09:55:34 rivoplus kernel: dhd_conf_read_nv_by_chip: chip=0x4345, chiprev=6, name=nvram_ap6255.txt Oct 12 09:55:34 rivoplus kernel: dhd_conf_read_nv_by_chip: chip=0x4354, chiprev=2, name=nvram_ap6356.txt Oct 12 09:55:34 rivoplus kernel: dhd_conf_read_nv_by_chip: chip=0x4359, chiprev=9, name=nvram_ap6359sa.txt Oct 12 09:55:34 rivoplus kernel: Final fw_path=/lib/firmware/brcm/fw_bcm4359c0_ag.bin Oct 12 09:55:34 rivoplus kernel: Final nv_path=/lib/firmware/brcm/nvram_ap6359sa.txt Oct 12 09:55:34 rivoplus kernel: Final clm_path=/lib/firmware/brcm/clm.blob Oct 12 09:55:34 rivoplus kernel: Final conf_path=/lib/firmware/brcm/config_bcm4359c0_ag.txt Oct 12 09:55:34 rivoplus kernel: dhd_os_open_image: /lib/firmware/brcm/fw_bcm4359c0_ag.bin (595758 bytes) open success Oct 12 09:55:34 rivoplus volumio[3641]: info: MCU Signalled Auto Boot Mode On Power Disabled Oct 12 09:55:34 rivoplus volumio[3641]: info: MCU Signalled Factory reset: 1 Oct 12 09:55:34 rivoplus kernel: dhd_os_open_image: /lib/firmware/brcm/nvram_ap6359sa.txt (5861 bytes) open success Oct 12 09:55:34 rivoplus kernel: NVRAM version: AP6398S_NVRAM_V1.1_20170926 Oct 12 09:55:34 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 12 09:55:34 rivoplus kernel: dhdsdio_write_vars: Download, Upload and compare of NVRAM succeeded. Oct 12 09:55:34 rivoplus volumio[3641]: info: CoreCommandRouter::executeOnPlugin: system , deleteUserData Oct 12 09:55:34 rivoplus volumio[3641]: info: Created User Data delete file, rebooting Oct 12 09:55:34 rivoplus volumio[3641]: info: ___________ PLUGINS: Run onVolumioReboot Tasks ___________ Oct 12 09:55:34 rivoplus volumio[3641]: info: PLUGIN onReboot : networkfs Oct 12 09:55:34 rivoplus kernel: dhd_bus_init: enable 0x06, ready 0x06 (waited 0us) Oct 12 09:55:34 rivoplus kernel: si_get_pmu_reg_addr: addrRET: 18000670 Oct 12 09:55:34 rivoplus kernel: bcmsdh_oob_intr_register: HW_OOB irq=70 flags=0x8 Oct 12 09:55:34 rivoplus kernel: dhd_get_memdump_info: File [/data/misc/wifi/.memdump.info] doesn't exist Oct 12 09:55:34 rivoplus kernel: dhd_get_memdump_info: MEMDUMP ENABLED = 2 Oct 12 09:55:34 rivoplus kernel: dhd_tcpack_suppress_set: TCP ACK Suppress mode 0 -> mode 1 Oct 12 09:55:34 rivoplus kernel: dhd_apply_default_clm: Ignore clm file /lib/firmware/brcm/clm.blob Oct 12 09:55:34 rivoplus kernel: Firmware up: op_mode=0x0405, MAC=40:d9:5a:9f:4a:4c Oct 12 09:55:34 rivoplus kernel: Driver: 1.579.77.41.10 (r) Firmware: wl0: Jan 19 2018 12:14:30 version 9.87.51.11.8 (a85e25e@shgit) (r) FWID 01-cb5aa0a5 CLM: 9.7.5 Oct 12 09:55:34 rivoplus kernel: dhd_txglom_enable: enable 1 Oct 12 09:55:34 rivoplus kernel: dhd_conf_set_txglom_params: txglom_mode=copy Oct 12 09:55:34 rivoplus kernel: dhd_conf_set_txglom_params: txglomsize=36, deferred_tx_len=0 Oct 12 09:55:34 rivoplus kernel: dhd_conf_set_txglom_params: txinrx_thres=128, dhd_txminmax=-1 Oct 12 09:55:34 rivoplus kernel: dhd_conf_set_txglom_params: tx_max_offset=0, txctl_tmo_fix=300 Oct 12 09:55:34 rivoplus kernel: dhd_conf_get_disable_proptx: fw_proptx=0, disable_proptx=1 Oct 12 09:55:34 rivoplus kernel: dhd_pno_init: Support Android Location Service Oct 12 09:55:34 rivoplus kernel: rtt_do_get_ioctl: failed to send getbuf proxd iovar (CMD ID : 1), status=-23 Oct 12 09:55:34 rivoplus kernel: dhd_rtt_init : FTM is not supported Oct 12 09:55:34 rivoplus kernel: dhd_preinit_ioctls: SensorHub diabled 0 Oct 12 09:55:34 rivoplus kernel: dhd_preinit_ioctls failed to set ShubHub disable Oct 12 09:55:34 rivoplus kernel: failed to set WNM capabilities Oct 12 09:55:34 rivoplus kernel: dhd_conf_set_country: set country ALL, revision 38 Oct 12 09:55:34 rivoplus kernel: CONFIG-ERROR) dhd_conf_set_bufiovar: country setting failed -2 Oct 12 09:55:34 rivoplus kernel: Country code: US (US/0) Oct 12 09:55:34 rivoplus kernel: CONFIG-ERROR) dhd_conf_set_intiovar: txbf setting failed -23 Oct 12 09:55:34 rivoplus kernel: wl_android_wifi_on: Success Oct 12 09:55:34 rivoplus kernel: dhd_open : the lock is released. Oct 12 09:55:34 rivoplus kernel: dhd_open: Exit ret=0 Oct 12 09:55:34 rivoplus sudo[4518]: pam_unix(sudo:session): session closed for user root Oct 12 09:55:34 rivoplus sudo[4537]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 12 09:55:34 rivoplus sudo[4537]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:55:34 rivoplus sudo[4577]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 12 09:55:34 rivoplus sudo[4540]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 12 09:55:34 rivoplus sudo[4571]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 12 09:55:34 rivoplus sudo[4550]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 12 09:55:34 rivoplus sudo[4552]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 12 09:55:34 rivoplus sudo[4552]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:55:34 rivoplus sudo[4540]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:55:34 rivoplus sudo[4567]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 12 09:55:34 rivoplus sudo[4577]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 12 09:55:34 rivoplus sudo[4581]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 12 09:55:34 rivoplus sudo[4550]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:55:34 rivoplus sudo[4589]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 12 09:55:34 rivoplus sudo[4589]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:55:34 rivoplus sudo[4571]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:55:34 rivoplus sudo[4581]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:55:34 rivoplus sudo[4562]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 12 09:55:34 rivoplus sudo[4562]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:55:34 rivoplus sudo[4560]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 12 09:55:34 rivoplus sudo[4560]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:55:34 rivoplus sudo[4594]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reboot Oct 12 09:55:34 rivoplus volumio[3641]: info: MPD Permissions set Oct 12 09:55:34 rivoplus sudo[4594]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:55:34 rivoplus wireless.js[4379]: WIRELESS.JS - INFO: InterfaceValidator: READY - wlan0 is ready for operations Oct 12 09:55:34 rivoplus wireless.js[4379]: WIRELESS.JS - INFO: InterfaceValidator: wlan0 became ready after 5ms Oct 12 09:55:34 rivoplus sudo[4567]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:55:34 rivoplus wireless.js[4379]: WIRELESS.JS - INFO: ensureInterfaceReady: Interface ready (MAC: 40:d9:5a:9f:4a:4c) Oct 12 09:55:34 rivoplus sudo[4550]: pam_unix(sudo:session): session closed for user root Oct 12 09:55:34 rivoplus sudo[4577]: pam_unix(sudo:session): session closed for user root Oct 12 09:55:34 rivoplus systemd-logind[2949]: The system will reboot now! Oct 12 09:55:34 rivoplus sudo[4628]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Oct 12 09:55:34 rivoplus sudo[4628]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:55:34 rivoplus systemd-logind[2949]: System is rebooting. Oct 12 09:55:34 rivoplus sudo[4628]: pam_unix(sudo:session): session closed for user root Oct 12 09:55:34 rivoplus sudo[4644]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Oct 12 09:55:34 rivoplus sudo[4644]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:55:34 rivoplus sudo[4589]: pam_unix(sudo:session): session closed for user root Oct 12 09:55:34 rivoplus sudo[4650]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 12 09:55:34 rivoplus sudo[4650]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:55:34 rivoplus sudo[4369]: pam_unix(sudo:session): session closed for user root Oct 12 09:55:34 rivoplus sudo[4492]: pam_unix(sudo:session): session closed for user root Oct 12 09:55:34 rivoplus sudo[4594]: pam_unix(sudo:session): session closed for user root Oct 12 09:55:34 rivoplus volumio[3641]: info: Cannot restart MPD: Error: Command failed: /usr/bin/sudo /bin/systemctl restart mpd.service Oct 12 09:55:34 rivoplus volumio[3641]: Job for mpd.service canceled. Oct 12 09:55:34 rivoplus volumio[3641]: info: Rebooting Oct 12 09:55:35 rivoplus systemd[1]: Requested transaction contradicts existing jobs: Transaction for mpd.service/restart is destructive (shutdown.target has 'start' job queued, but 'stop' is included in transaction). Oct 12 09:55:35 rivoplus sudo[4552]: pam_unix(sudo:session): session closed for user root Oct 12 09:55:35 rivoplus volumio[3641]: info: Cannot restart MPD: Error: Command failed: /usr/bin/sudo /bin/systemctl restart mpd.service Oct 12 09:55:35 rivoplus volumio[3641]: Failed to restart mpd.service: Transaction for mpd.service/restart is destructive (shutdown.target has 'start' job queued, but 'stop' is included in transaction). Oct 12 09:55:35 rivoplus volumio[3641]: See system logs and 'systemctl status mpd.service' for details. Oct 12 09:55:35 rivoplus sudo[4537]: pam_unix(sudo:session): session closed for user root Oct 12 09:55:35 rivoplus volumio[3641]: error: QobuzConnect: could not execute /bin/systemctl to restart qobuz connect process. Reason: Job for qobuz-connect.service canceled. Oct 12 09:55:35 rivoplus volumio[3641]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 12 09:55:35 rivoplus upmpdcli[4254]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Connection closed by the server Oct 12 09:55:35 rivoplus upmpdcli[4254]: :2:../src/mpdcli.cxx:396::MPDCli::updStatus: connection failed Oct 12 09:55:35 rivoplus upmpdcli[4254]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Oct 12 09:55:35 rivoplus systemd[1]: Requested transaction contradicts existing jobs: Transaction for shairport-sync.service/restart is destructive (systemd-binfmt.service has 'stop' job queued, but 'start' is included in transaction). Oct 12 09:55:35 rivoplus sudo[4581]: pam_unix(sudo:session): session closed for user root Oct 12 09:55:35 rivoplus volumio[3641]: [UnhandledPromiseRejection: This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). The promise rejected with the reason "undefined".] { Oct 12 09:55:35 rivoplus volumio[3641]: code: 'ERR_UNHANDLED_REJECTION' Oct 12 09:55:35 rivoplus volumio[3641]: } Oct 12 09:55:35 rivoplus volumio[3641]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 12 09:55:35 rivoplus sudo[4571]: pam_unix(sudo:session): session closed for user root Oct 12 09:55:35 rivoplus sudo[4567]: pam_unix(sudo:session): session closed for user root Oct 12 09:55:35 rivoplus systemd[1]: Requested transaction contradicts existing jobs: Transaction for shairport-sync.service/restart is destructive (systemd-journal-flush.service has 'stop' job queued, but 'start' is included in transaction). Oct 12 09:55:35 rivoplus sudo[4540]: pam_unix(sudo:session): session closed for user root Oct 12 09:55:35 rivoplus sudo[4658]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 12 09:55:35 rivoplus sudo[4658]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:55:35 rivoplus systemd[1]: Requested transaction contradicts existing jobs: Transaction for raat-daemon.service/restart is destructive (systemd-modules-load.service has 'stop' job queued, but 'start' is included in transaction). Oct 12 09:55:35 rivoplus sudo[4650]: pam_unix(sudo:session): session closed for user root Oct 12 09:55:35 rivoplus sudo[4664]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 12 09:55:35 rivoplus sudo[4664]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:55:35 rivoplus systemd[1]: Requested transaction contradicts existing jobs: Transaction for raat-daemon.service/restart is destructive (sysinit.target has 'stop' job queued, but 'start' is included in transaction). Oct 12 09:55:35 rivoplus sudo[4658]: pam_unix(sudo:session): session closed for user root Oct 12 09:55:35 rivoplus systemd[1]: Requested transaction contradicts existing jobs: Transaction for qobuz-connect.service/restart is destructive (proc-sys-fs-binfmt_misc.automount has 'stop' job queued, but 'start' is included in transaction). Oct 12 09:55:35 rivoplus sudo[4664]: pam_unix(sudo:session): session closed for user root Oct 12 09:55:35 rivoplus systemd[1]: Removed slice system-modprobe.slice - Slice /system/modprobe. Oct 12 09:55:35 rivoplus systemd[1]: Stopped target bluetooth.target - Bluetooth Support. Oct 12 09:55:35 rivoplus systemd[1]: Stopped target graphical.target - Graphical Interface. Oct 12 09:55:35 rivoplus systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Oct 12 09:55:35 rivoplus systemd[1]: Stopped target rpc_pipefs.target. Oct 12 09:55:35 rivoplus systemd[1]: Stopped target rpcbind.target - RPC Port Mapper. Oct 12 09:55:35 rivoplus systemd[1]: Stopped target timers.target - Timer Units. Oct 12 09:55:35 rivoplus systemd[1]: apt-daily-upgrade.timer: Deactivated successfully. Oct 12 09:55:35 rivoplus systemd[1]: Stopped apt-daily-upgrade.timer - Daily apt upgrade and clean activities. Oct 12 09:55:35 rivoplus systemd[1]: apt-daily.timer: Deactivated successfully. Oct 12 09:55:35 rivoplus systemd[1]: Stopped apt-daily.timer - Daily apt download activities. Oct 12 09:55:35 rivoplus systemd[1]: dpkg-db-backup.timer: Deactivated successfully. Oct 12 09:55:35 rivoplus systemd[1]: Stopped dpkg-db-backup.timer - Daily dpkg database backup timer. Oct 12 09:55:35 rivoplus systemd[1]: e2scrub_all.timer: Deactivated successfully. Oct 12 09:55:35 rivoplus systemd[1]: Stopped e2scrub_all.timer - Periodic ext4 Online Metadata Check for All Filesystems. Oct 12 09:55:35 rivoplus systemd[1]: fstrim.timer: Deactivated successfully. Oct 12 09:55:35 rivoplus systemd[1]: Stopped fstrim.timer - Discard unused blocks once a week. Oct 12 09:55:35 rivoplus systemd[1]: ntpsec-rotate-stats.timer: Deactivated successfully. Oct 12 09:55:35 rivoplus systemd[1]: Stopped ntpsec-rotate-stats.timer - Rotate ntpd stats daily. Oct 12 09:55:35 rivoplus systemd[1]: setdatetime-helper.timer: Deactivated successfully. Oct 12 09:55:35 rivoplus systemd[1]: Stopped setdatetime-helper.timer - Volumio Time Sync Watchdog Timer. Oct 12 09:55:35 rivoplus systemd[1]: systemd-tmpfiles-clean.timer: Deactivated successfully. Oct 12 09:55:35 rivoplus systemd[1]: Stopped systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Oct 12 09:55:35 rivoplus systemd[1]: Stopped target usb-gadget.target - Hardware activated USB gadget. Oct 12 09:55:35 rivoplus systemd[1]: systemd-rfkill.socket: Deactivated successfully. Oct 12 09:55:35 rivoplus systemd[1]: Closed systemd-rfkill.socket - Load/Save RF Kill Switch Status /dev/rfkill Watch. Oct 12 09:55:35 rivoplus systemd[1]: Unmounting run-rpc_pipefs.mount - RPC Pipe File System... Oct 12 09:55:35 rivoplus bluealsa[3455]: ../src/ba-adapter.c:144: Freeing adapter: hci0 Oct 12 09:55:35 rivoplus systemd[1]: Stopping bluealsa.service - BlueALSA service... Oct 12 09:55:35 rivoplus bluetoothd[3416]: Endpoint unregistered: sender=:1.9 path=/org/bluez/hci0/A2DP/SBC/source/1 Oct 12 09:55:35 rivoplus bluetoothd[3416]: Endpoint unregistered: sender=:1.9 path=/org/bluez/hci0/A2DP/SBC/sink/2 Oct 12 09:55:35 rivoplus bluetoothd[3416]: Endpoint unregistered: sender=:1.9 path=/org/bluez/hci0/A2DP/SBC/source/2 Oct 12 09:55:35 rivoplus bluetoothd[3416]: Endpoint unregistered: sender=:1.9 path=/org/bluez/hci0/A2DP/SBC/sink/1 Oct 12 09:55:35 rivoplus systemd[1]: Starting plymouth-reboot.service - Show Plymouth Reboot Screen... Oct 12 09:55:35 rivoplus systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Oct 12 09:55:35 rivoplus systemd[1]: Stopping ssh.service - OpenBSD Secure Shell server... Oct 12 09:55:35 rivoplus sshd[4111]: Received signal 15; terminating. Oct 12 09:55:35 rivoplus qobuz-connect[4302]: 20261012 09:55:35.947 [4302.4302] INFO SampleApp: Stopping Local configuration server Oct 12 09:55:35 rivoplus systemd[1]: Stopping systemd-random-seed.service - Load/Save Random Seed... Oct 12 09:55:35 rivoplus systemd[1]: Stopping volumio5-onboarding.service - Volumio5 Onboarding Server... Oct 12 09:55:35 rivoplus systemd[1]: Stopping volumiobt.service - Volumio Bluetooth Module... Oct 12 09:55:36 rivoplus volumiobt[4673]: INFO [BTSTART] Disconnecting all Bluetooth devices... Oct 12 09:55:36 rivoplus systemd[1]: bluealsa.service: Deactivated successfully. Oct 12 09:55:36 rivoplus systemd[1]: Stopped bluealsa.service - BlueALSA service. Oct 12 09:55:36 rivoplus systemd[1]: ssh.service: Deactivated successfully. Oct 12 09:55:36 rivoplus systemd[1]: Stopped ssh.service - OpenBSD Secure Shell server. Oct 12 09:55:36 rivoplus systemd[1]: volumio5-onboarding.service: Deactivated successfully. Oct 12 09:55:36 rivoplus systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Oct 12 09:55:36 rivoplus systemd[1]: mpd.service: Deactivated successfully. Oct 12 09:55:36 rivoplus systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 12 09:55:36 rivoplus bluetoothd[3416]: Adv Monitor app :1.29 disconnected from D-Bus Oct 12 09:55:36 rivoplus volumiobt[4680]: INFO [BTSTART] Unregistering Bluetooth agent... Oct 12 09:55:36 rivoplus systemd[1]: run-rpc_pipefs.mount: Deactivated successfully. Oct 12 09:55:36 rivoplus systemd[1]: Unmounted run-rpc_pipefs.mount - RPC Pipe File System. Oct 12 09:55:36 rivoplus volumiobt[4681]: No agent is registered Oct 12 09:55:36 rivoplus volumiobt[4681]: [NEW] Media /org/bluez/hci0 Oct 12 09:55:36 rivoplus volumiobt[4681]: SupportedUUIDs: 0000110a-0000-1000-8000-00805f9b34fb Oct 12 09:55:36 rivoplus volumiobt[4681]: SupportedUUIDs: 0000110b-0000-1000-8000-00805f9b34fb Oct 12 09:55:36 rivoplus volumiobt[4681]: SupportedUUIDs: 0000FDF0-0000-1000-8000-00805f9b34fb Oct 12 09:55:36 rivoplus systemd[1]: systemd-random-seed.service: Deactivated successfully. Oct 12 09:55:36 rivoplus systemd[1]: Stopped systemd-random-seed.service - Load/Save Random Seed. Oct 12 09:55:36 rivoplus systemd[1]: Received SIGRTMIN+20 from PID 4671 (plymouthd). Oct 12 09:55:36 rivoplus bluetoothd[3416]: Adv Monitor app :1.30 disconnected from D-Bus Oct 12 09:55:36 rivoplus systemd[1]: Started plymouth-reboot.service - Show Plymouth Reboot Screen. Oct 12 09:55:36 rivoplus volumiobt[4683]: INFO [BTSTART] Stopping bluealsa-aplay (if any)... Oct 12 09:55:36 rivoplus sudo[4560]: pam_unix(sudo:session): session closed for user root Oct 12 09:55:36 rivoplus sudo[4562]: pam_unix(sudo:session): session closed for user root Oct 12 09:55:36 rivoplus volumiobt[4688]: INFO [BTSTART] Stopping A2DP agent (a2dp-agent)... Oct 12 09:55:36 rivoplus systemd[1]: plymouth-switch-root-initramfs.service - Tell Plymouth To Jump To initramfs was skipped because of an unmet condition check (ConditionPathExists=/run/initramfs/bin/sh). Oct 12 09:55:36 rivoplus systemd[1]: volumiobt.service: Deactivated successfully. Oct 12 09:55:36 rivoplus systemd[1]: Stopped volumiobt.service - Volumio Bluetooth Module. Oct 12 09:55:36 rivoplus systemd[1]: Stopped target multi-user.target - Multi-User System. Oct 12 09:55:36 rivoplus systemd[1]: Stopped target getty.target - Login Prompts. Oct 12 09:55:36 rivoplus systemd[1]: Stopping bluetooth-khadas.service - Khadas Bluetooth service... Oct 12 09:55:36 rivoplus bluetoothd[3416]: Terminating Oct 12 09:55:36 rivoplus systemd[1]: Stopping bluetooth.service - Bluetooth service... Oct 12 09:55:36 rivoplus bluetoothd[3416]: Destroy Adv Monitor Manager Oct 12 09:55:36 rivoplus bluetoothd[3416]: Battery Provider Manager destroyed Oct 12 09:55:36 rivoplus bluetoothd[3416]: Stopping SDP server Oct 12 09:55:36 rivoplus bluetoothd[3416]: Exit Oct 12 09:55:36 rivoplus systemd[1]: Stopping cpufrequtils.service - LSB: set CPUFreq kernel parameters... Oct 12 09:55:36 rivoplus systemd[1]: Stopping ifplugd.service - LSB: Brings up/down network automatically... Oct 12 09:55:36 rivoplus lircd-0.10.1[3115]: Notice: caught signal Oct 12 09:55:36 rivoplus lircd[3115]: lircd-0.10.1[3115]: Notice: caught signal Oct 12 09:55:36 rivoplus systemd[1]: Stopping lircd.service - Flexible IR remote input/output application support... Oct 12 09:55:36 rivoplus ntpd[3163]: ERR: ntpd exiting on signal 15 (Terminated) Oct 12 09:55:36 rivoplus systemd[1]: Stopping ntpsec.service - Network Time Service... Oct 12 09:55:36 rivoplus ntpd[3163]: PROTO: 185.157.229.254 unlink local addr 192.168.2.160 -> Oct 12 09:55:36 rivoplus systemd[1]: plymouth-quit.service: Deactivated successfully. Oct 12 09:55:36 rivoplus ntpd[3163]: PROTO: 89.46.74.148 unlink local addr 192.168.2.160 -> Oct 12 09:55:36 rivoplus systemd[1]: Stopped plymouth-quit.service - Terminate Plymouth Boot Screen. Oct 12 09:55:36 rivoplus ntpd[3163]: PROTO: 95.110.135.141 unlink local addr 192.168.2.160 -> Oct 12 09:55:36 rivoplus kernel: meson_uart ff803000.serial: ttyS0 use xtal(24M) 24000000 change 115200 to 115200 Oct 12 09:55:36 rivoplus ntpd[3163]: PROTO: 172.232.208.229 unlink local addr 192.168.2.160 -> Oct 12 09:55:36 rivoplus systemd[1]: Stopping serial-getty@ttyS0.service - Serial Getty on ttyS0... Oct 12 09:55:36 rivoplus ntpd[3163]: PROTO: 85.199.214.99 unlink local addr 192.168.2.160 -> Oct 12 09:55:36 rivoplus systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 12 09:55:36 rivoplus ntpd[3163]: PROTO: 95.110.254.234 unlink local addr 192.168.2.160 -> Oct 12 09:55:36 rivoplus ntpd[3163]: PROTO: 212.45.144.3 unlink local addr 192.168.2.160 -> Oct 12 09:55:36 rivoplus ntpd[3163]: PROTO: 172.232.209.103 unlink local addr 192.168.2.160 -> Oct 12 09:55:36 rivoplus ntpd[3163]: PROTO: 162.159.200.1 unlink local addr 192.168.2.160 -> Oct 12 09:55:36 rivoplus ntpd[3163]: PROTO: 162.159.200.123 unlink local addr 192.168.2.160 -> Oct 12 09:55:36 rivoplus ntpd[3163]: PROTO: 185.19.184.35 unlink local addr 192.168.2.160 -> Oct 12 09:55:36 rivoplus ntpd[3163]: PROTO: 37.247.53.178 unlink local addr 192.168.2.160 -> Oct 12 09:55:36 rivoplus ntpd[3163]: PROTO: 195.32.70.195 unlink local addr 192.168.2.160 -> Oct 12 09:55:36 rivoplus ntpd[3163]: PROTO: 204.216.214.76 unlink local addr 192.168.2.160 -> Oct 12 09:55:36 rivoplus ntpd[3163]: PROTO: 212.45.144.206 unlink local addr 192.168.2.160 -> Oct 12 09:55:36 rivoplus ntpd[3163]: PROTO: 151.22.209.90 unlink local addr 192.168.2.160 -> Oct 12 09:55:36 rivoplus systemd[1]: Stopping smbd.service - Samba SMB Daemon... Oct 12 09:55:36 rivoplus systemd[1]: Stopping systemd-logind.service - User Login Management... Oct 12 09:55:36 rivoplus systemd[1]: Stopping triggerhappy.service - triggerhappy global hotkey daemon... Oct 12 09:55:36 rivoplus systemd[1]: Stopping upmpdcli.service - UPnP Renderer front-end to MPD... Oct 12 09:55:36 rivoplus systemd[1]: Stopping volumio-ipchange.service - Volumio IP Change Monitor... Oct 12 09:55:36 rivoplus sudo[4307]: pam_unix(sudo:session): session closed for user root Oct 12 09:55:36 rivoplus startkiosk.sh[3687]: xinit: connection to X server lost Oct 12 09:55:36 rivoplus startkiosk.sh[3687]: Oct 12 09:55:36 rivoplus systemd[1]: Stopping volumio-kiosk.service - Start Volumio Kiosk... Oct 12 09:55:36 rivoplus systemd[1]: Stopping volumio-remote-updater.service - Volumio Remote OTA Updater... Oct 12 09:55:36 rivoplus systemd[1]: welcome.service: Deactivated successfully. Oct 12 09:55:36 rivoplus systemd[1]: Stopped welcome.service - Show a welcome message on console. Oct 12 09:55:36 rivoplus systemd[1]: volumio-remote-updater.service: Deactivated successfully. Oct 12 09:55:36 rivoplus systemd[1]: Stopped volumio-remote-updater.service - Volumio Remote OTA Updater. Oct 12 09:55:36 rivoplus bluetooth-khadas.sh[3246]: bcm43xx_init Oct 12 09:55:36 rivoplus bluetooth-khadas.sh[3246]: Chip Name: BCM4359C0 Oct 12 09:55:36 rivoplus bluetooth-khadas.sh[3246]: Flash firmware /etc/firmware/brcm/BCM4359C0.hcd Oct 12 09:55:36 rivoplus bluetooth-khadas.sh[3246]: Set Controller UART speed to 2000000 bit/s Oct 12 09:55:36 rivoplus bluetooth-khadas.sh[3246]: Device setup complete Oct 12 09:55:36 rivoplus systemd[1]: lircd.service: Deactivated successfully. Oct 12 09:55:36 rivoplus systemd[1]: Stopped lircd.service - Flexible IR remote input/output application support. Oct 12 09:55:36 rivoplus systemd[1]: volumio-ipchange.service: Deactivated successfully. Oct 12 09:55:36 rivoplus systemd[1]: Stopped volumio-ipchange.service - Volumio IP Change Monitor. Oct 12 09:55:36 rivoplus systemd[1]: ntpsec.service: Deactivated successfully. Oct 12 09:55:36 rivoplus systemd[1]: Stopped ntpsec.service - Network Time Service. Oct 12 09:55:36 rivoplus kernel: fb: osd[0] enable: 1 (Xorg) Oct 12 09:55:36 rivoplus sudo[4722]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-12 09:54' Oct 12 09:55:36 rivoplus kernel: fb: osd[0] canvas.idx =0x40 Oct 12 09:55:36 rivoplus kernel: fb: osd[0] canvas.addr=0x70000000 Oct 12 09:55:36 rivoplus kernel: fb: osd[0] canvas.width=7680 Oct 12 09:55:36 rivoplus kernel: fb: osd[0] canvas.height=2160 Oct 12 09:55:36 rivoplus kernel: fb: osd[0] frame.width=1920 Oct 12 09:55:36 rivoplus kernel: fb: osd[0] frame.height=1080 Oct 12 09:55:36 rivoplus kernel: fb: osd[0] out_addr_id =0x1 Oct 12 09:55:36 rivoplus sudo[4722]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:55:36 rivoplus systemd[1]: serial-getty@ttyS0.service: Deactivated successfully. Oct 12 09:55:36 rivoplus systemd[1]: Stopped serial-getty@ttyS0.service - Serial Getty on ttyS0. Oct 12 09:55:36 rivoplus systemd[1]: systemd-logind.service: Deactivated successfully. Oct 12 09:55:36 rivoplus systemd[1]: Stopped systemd-logind.service - User Login Management. Oct 12 09:55:37 rivoplus startkiosk.sh[3687]: waiting for X server to shut down Oct 12 09:55:37 rivoplus startkiosk.sh[3688]: (II) Server terminated successfully (0). Closing log file. Oct 12 09:55:37 rivoplus systemd[1]: smbd.service: Deactivated successfully. Oct 12 09:55:37 rivoplus sudo[4644]: pam_unix(sudo:session): session closed for user root Oct 12 09:55:37 rivoplus systemd[1]: Stopped smbd.service - Samba SMB Daemon. Oct 12 09:55:37 rivoplus wireless.js[4379]: WIRELESS.JS - INFO: SETTING APPROPRIATE REG DOMAIN: DE Oct 12 09:55:37 rivoplus systemd[1]: bluetooth.service: Deactivated successfully. Oct 12 09:55:37 rivoplus systemd[1]: Stopped bluetooth.service - Bluetooth service. Oct 12 09:55:37 rivoplus sudo[4727]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set DE Oct 12 09:55:37 rivoplus sudo[4727]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:55:37 rivoplus systemd[1]: shairport-sync.service: Deactivated successfully. Oct 12 09:55:37 rivoplus systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 12 09:55:37 rivoplus sudo[4727]: pam_unix(sudo:session): session closed for user root Oct 12 09:55:37 rivoplus wireless.js[4379]: WIRELESS.JS - INFO: SUCCESSFULLY SET NEW REGDOMAIN: DE Oct 12 09:55:37 rivoplus wireless.js[4379]: WIRELESS.JS - INFO: netconfigured file not found, starting hotspot Oct 12 09:55:37 rivoplus wireless.js[4379]: WIRELESS.JS - INFO: Single Network Mode: Ethernet active, maintaining WiFi scan capability Oct 12 09:55:37 rivoplus wireless.js[4379]: WIRELESS.JS - INFO: SNM: Maintaining wlan0 UP without IP (scan mode) Oct 12 09:55:37 rivoplus wireless.js[4379]: WIRELESS.JS - INFO: SNM: Users can configure WiFi via WebUI while ethernet is active Oct 12 09:55:37 rivoplus systemd[1]: triggerhappy.service: Deactivated successfully. Oct 12 09:55:37 rivoplus systemd[1]: Stopped triggerhappy.service - triggerhappy global hotkey daemon. Oct 12 09:55:37 rivoplus systemd[1]: bluetooth-khadas.service: Deactivated successfully. Oct 12 09:55:37 rivoplus systemd[1]: Stopped bluetooth-khadas.service - Khadas Bluetooth service. PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="8ed05bf20f96eb8557ed5652c420f84f39d66325" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0a52c1fb90959ba1872a2fb0225e56fe615b19ac" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivoplus" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed Sep 16 16:52:40 UTC 2026" VOLUMIO_VERSION="4.208" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo Plus" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo Plus" VOLUMIO_HASH="ba37532a86808e19439a6278b2d45f47"