Oct 02 16:33:15 rivoplus systemd[1]: setdatetime-helper.service: Deactivated successfully. Oct 02 16:33:15 rivoplus systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Oct 02 16:33:15 rivoplus wireless.js[2958]: WIRELESS.JS - INFO: InterfaceValidator: FAIL - wlan0 driver not initialized (operstate=unknown) Oct 02 16:33:15 rivoplus wireless.js[2958]: WIRELESS.JS - INFO: InterfaceValidator: FAIL - wlan0 driver not initialized (operstate=unknown) Oct 02 16:33:15 rivoplus kernel: meson_uart ffd24000.serial: ttyS1 use xtal(24M) 24000000 change 115200 to 2000000 Oct 02 16:33:15 rivoplus systemd[1]: Starting systemd-rfkill.service - Load/Save RF Kill Switch Status... Oct 02 16:33:15 rivoplus (udev-worker)[2623]: hci0: Process '/usr/bin/hciconfig hci0 up' failed with exit code 1. Oct 02 16:33:15 rivoplus systemd[1]: Started systemd-rfkill.service - Load/Save RF Kill Switch Status. Oct 02 16:33:15 rivoplus sh[2863]: eth0: leased 192.168.0.63 for 1440 seconds Oct 02 16:33:15 rivoplus dhcpcd[2868]: eth0: leased 192.168.0.63 for 1440 seconds Oct 02 16:33:15 rivoplus dhcpcd[2868]: eth0: adding route to 192.168.0.0/24 Oct 02 16:33:15 rivoplus systemd[1]: Starting bluetooth.service - Bluetooth service... Oct 02 16:33:15 rivoplus sh[2863]: eth0: adding route to 192.168.0.0/24 Oct 02 16:33:15 rivoplus sh[2863]: eth0: adding default route via 192.168.0.1 Oct 02 16:33:15 rivoplus dhcpcd[2868]: eth0: adding default route via 192.168.0.1 Oct 02 16:33:15 rivoplus winbindd[3357]: [2026/10/02 16:33:15.284748, 0] ../../source3/winbindd/winbindd_idmap.c:372(wb_parent_idmap_setup_lookupname_done) Oct 02 16:33:15 rivoplus winbindd[3357]: wb_parent_idmap_setup_lookupname_done: Lookup domain name 'RIVOPLUS' failed 'NT_STATUS_IO_TIMEOUT' Oct 02 16:33:15 rivoplus nmbd[3204]: [2026/10/02 16:33:15.357478, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response) Oct 02 16:33:15 rivoplus nmbd[3204]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.0.63 for name WORKGROUP<1d>. Oct 02 16:33:15 rivoplus nmbd[3204]: This response was from IP 192.168.0.79, reporting an IP address of 192.168.0.80. Oct 02 16:33:15 rivoplus nmbd[3204]: [2026/10/02 16:33:15.357974, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response) Oct 02 16:33:15 rivoplus nmbd[3204]: query_name_response: Multiple (3) responses received for a query on subnet 192.168.0.63 for name WORKGROUP<1d>. Oct 02 16:33:15 rivoplus nmbd[3204]: This response was from IP 192.168.0.79, reporting an IP address of 192.168.0.80. Oct 02 16:33:15 rivoplus nmbd[3204]: [2026/10/02 16:33:15.358301, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response) Oct 02 16:33:15 rivoplus nmbd[3204]: query_name_response: Multiple (4) responses received for a query on subnet 192.168.0.63 for name WORKGROUP<1d>. Oct 02 16:33:15 rivoplus nmbd[3204]: This response was from IP 192.168.0.79, reporting an IP address of 192.168.0.80. Oct 02 16:33:15 rivoplus nmbd[3204]: [2026/10/02 16:33:15.358639, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response) Oct 02 16:33:15 rivoplus nmbd[3204]: query_name_response: Multiple (5) responses received for a query on subnet 192.168.0.63 for name WORKGROUP<1d>. Oct 02 16:33:15 rivoplus nmbd[3204]: This response was from IP 192.168.0.78, reporting an IP address of 192.168.0.80. Oct 02 16:33:15 rivoplus systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Oct 02 16:33:15 rivoplus nmbd[3204]: [2026/10/02 16:33:15.358926, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response) Oct 02 16:33:15 rivoplus systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Oct 02 16:33:15 rivoplus nmbd[3204]: query_name_response: Multiple (6) responses received for a query on subnet 192.168.0.63 for name WORKGROUP<1d>. Oct 02 16:33:15 rivoplus systemd[1]: welcome.service: Deactivated successfully. Oct 02 16:33:15 rivoplus nmbd[3204]: This response was from IP 192.168.0.78, reporting an IP address of 192.168.0.80. Oct 02 16:33:15 rivoplus systemd[1]: Stopped welcome.service - Show a welcome message on console. Oct 02 16:33:15 rivoplus nmbd[3204]: [2026/10/02 16:33:15.359317, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response) Oct 02 16:33:15 rivoplus systemd[1]: Stopping welcome.service - Show a welcome message on console... Oct 02 16:33:15 rivoplus nmbd[3204]: query_name_response: Multiple (7) responses received for a query on subnet 192.168.0.63 for name WORKGROUP<1d>. Oct 02 16:33:15 rivoplus systemd[1]: Starting welcome.service - Show a welcome message on console... Oct 02 16:33:15 rivoplus nmbd[3204]: This response was from IP 192.168.0.78, reporting an IP address of 192.168.0.80. Oct 02 16:33:15 rivoplus nmbd[3204]: [2026/10/02 16:33:15.359612, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response) Oct 02 16:33:15 rivoplus nmbd[3204]: query_name_response: Multiple (8) responses received for a query on subnet 192.168.0.63 for name WORKGROUP<1d>. Oct 02 16:33:15 rivoplus nmbd[3204]: This response was from IP 192.168.0.80, reporting an IP address of 192.168.0.80. Oct 02 16:33:15 rivoplus nmbd[3204]: [2026/10/02 16:33:15.359911, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response) Oct 02 16:33:15 rivoplus nmbd[3204]: query_name_response: Multiple (9) responses received for a query on subnet 192.168.0.63 for name WORKGROUP<1d>. Oct 02 16:33:15 rivoplus nmbd[3204]: This response was from IP 192.168.0.80, reporting an IP address of 192.168.0.80. Oct 02 16:33:15 rivoplus nmbd[3204]: [2026/10/02 16:33:15.360198, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response) Oct 02 16:33:15 rivoplus nmbd[3204]: query_name_response: Multiple (10) responses received for a query on subnet 192.168.0.63 for name WORKGROUP<1d>. Oct 02 16:33:15 rivoplus nmbd[3204]: This response was from IP 192.168.0.79, reporting an IP address of 192.168.0.80. Oct 02 16:33:15 rivoplus nmbd[3204]: [2026/10/02 16:33:15.360486, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response) Oct 02 16:33:15 rivoplus nmbd[3204]: query_name_response: Multiple (11) responses received for a query on subnet 192.168.0.63 for name WORKGROUP<1d>. Oct 02 16:33:15 rivoplus nmbd[3204]: This response was from IP 192.168.0.79, reporting an IP address of 192.168.0.80. Oct 02 16:33:15 rivoplus nmbd[3204]: [2026/10/02 16:33:15.360785, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response) Oct 02 16:33:15 rivoplus nmbd[3204]: query_name_response: Multiple (12) responses received for a query on subnet 192.168.0.63 for name WORKGROUP<1d>. Oct 02 16:33:15 rivoplus nmbd[3204]: This response was from IP 192.168.0.79, reporting an IP address of 192.168.0.80. Oct 02 16:33:15 rivoplus welcome[3404]: Resolved ip:[1] 192.168.0.63 Oct 02 16:33:15 rivoplus wireless.js[2958]: WIRELESS.JS - INFO: InterfaceValidator: FAIL - wlan0 driver not initialized (operstate=unknown) Oct 02 16:33:15 rivoplus wireless.js[2958]: WIRELESS.JS - INFO: InterfaceValidator: FAIL - wlan0 driver not initialized (operstate=unknown) Oct 02 16:33:15 rivoplus systemd[1]: Finished welcome.service - Show a welcome message on console. Oct 02 16:33:15 rivoplus systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Oct 02 16:33:15 rivoplus sh[2863]: forked to background, child pid 2867 Oct 02 16:33:15 rivoplus smbd[3375]: [2026/10/02 16:33:15.818526, 0] ../../source3/smbd/server.c:1741(main) Oct 02 16:33:15 rivoplus smbd[3375]: smbd version 4.17.12-Debian started. Oct 02 16:33:15 rivoplus smbd[3375]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Oct 02 16:33:16 rivoplus systemd[1]: Started smbd.service - Samba SMB Daemon. Oct 02 16:33:16 rivoplus wireless.js[2958]: WIRELESS.JS - INFO: InterfaceValidator: FAIL - wlan0 driver not initialized (operstate=unknown) Oct 02 16:33:16 rivoplus wireless.js[2958]: WIRELESS.JS - INFO: InterfaceValidator: FAIL - wlan0 driver not initialized (operstate=unknown) Oct 02 16:33:16 rivoplus ifplugd(eth0)[3176]: client: ifup: interface eth0 already configured Oct 02 16:33:16 rivoplus sh[3456]: eth0=eth0 Oct 02 16:33:16 rivoplus bluetoothd[3385]: Bluetooth daemon 5.83 Oct 02 16:33:16 rivoplus systemd[1]: Started bluetooth.service - Bluetooth service. Oct 02 16:33:16 rivoplus bluetoothd[3385]: Starting SDP server Oct 02 16:33:16 rivoplus bluetoothd[3385]: Excluding (cli) sap Oct 02 16:33:16 rivoplus bluetoothd[3385]: Excluding (cli) scanparam Oct 02 16:33:16 rivoplus bluetoothd[3385]: Excluding (cli) midi Oct 02 16:33:16 rivoplus bluetoothd[3385]: Excluding (cli) battery Oct 02 16:33:16 rivoplus systemd[1]: Starting bluealsa.service - BlueALSA service... Oct 02 16:33:16 rivoplus ifplugd(eth0)[3176]: Program executed successfully. Oct 02 16:33:16 rivoplus bluetoothd[3385]: Bluetooth management interface 1.14 initialized Oct 02 16:33:16 rivoplus bluetoothd[3385]: src/adapter.c:reset_adv_monitors_complete() Failed to reset Adv Monitors: Unknown Command (0x01) Oct 02 16:33:16 rivoplus bluetoothd[3385]: Battery Provider Manager created Oct 02 16:33:16 rivoplus dbus-daemon[2944]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.7' (uid=0 pid=3385 comm="/usr/libexec/bluetooth/bluetoothd --noplugin=sap,b" label="kernel") Oct 02 16:33:16 rivoplus systemd[1]: Starting systemd-hostnamed.service - Hostname Service... Oct 02 16:33:16 rivoplus kernel: IPVS: Creating netns size=1928 id=3 Oct 02 16:33:16 rivoplus bluetoothd[3385]: profiles/audio/bap.c:bap_adapter_probe() BAP requires ISO Socket which is not enabled Oct 02 16:33:16 rivoplus bluetoothd[3385]: bap: Operation not supported (95) Oct 02 16:33:16 rivoplus bluetoothd[3385]: Failed to Read Adv Monitor Features with status 0x01 Oct 02 16:33:16 rivoplus wireless.js[2958]: WIRELESS.JS - INFO: InterfaceValidator: FAIL - wlan0 driver not initialized (operstate=unknown) Oct 02 16:33:16 rivoplus wireless.js[2958]: WIRELESS.JS - INFO: InterfaceValidator: TIMEOUT waiting for wlan0 after 7247011ms (reason: driver_not_ready) Oct 02 16:33:16 rivoplus wireless.js[2958]: WIRELESS.JS - INFO: ensureInterfaceReady: Interface not ready: driver_not_ready Oct 02 16:33:16 rivoplus wireless.js[2958]: WIRELESS.JS - INFO: Interface setup failed, continuing anyway: Error: Interface not ready Oct 02 16:33:16 rivoplus sudo[3470]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Oct 02 16:33:16 rivoplus sudo[3470]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:33:16 rivoplus sudo[3470]: pam_unix(sudo:session): session closed for user root Oct 02 16:33:16 rivoplus sudo[3477]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Oct 02 16:33:16 rivoplus sudo[3477]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:33:16 rivoplus winbindd[3360]: [2026/10/02 16:33:16.761485, 0] ../../source3/winbindd/winbindd_dual.c:162(child_write_response) Oct 02 16:33:16 rivoplus winbindd[3360]: Could not write result Oct 02 16:33:16 rivoplus ntpd[3156]: IO: Listen normally on 3 eth0 192.168.0.63:123 Oct 02 16:33:16 rivoplus ntpd[3156]: IO: new interface(s) found: waking up resolver Oct 02 16:33:16 rivoplus ntpd[3156]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Oct 02 16:33:16 rivoplus dbus-daemon[2944]: [system] Successfully activated service 'org.freedesktop.hostname1' Oct 02 16:33:16 rivoplus systemd[1]: Started systemd-hostnamed.service - Hostname Service. Oct 02 16:33:16 rivoplus ntpd[3156]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Oct 02 16:33:16 rivoplus ntpd[3156]: DNS: Pool taking: 67.219.100.202 Oct 02 16:33:16 rivoplus ntpd[3156]: DNS: Pool taking: 44.32.200.249 Oct 02 16:33:16 rivoplus ntpd[3156]: DNS: Pool taking: 144.6.238.198 Oct 02 16:33:16 rivoplus ntpd[3156]: DNS: Pool taking: 27.124.125.251 Oct 02 16:33:16 rivoplus ntpd[3156]: DNS: Pool taking: 2001:8004:11d0:4e2a::a29f:c87b Oct 02 16:33:16 rivoplus ntpd[3156]: DNS: Pool taking: 2001:8004:11d0:4e2a::c2c3:fd3a Oct 02 16:33:16 rivoplus ntpd[3156]: DNS: Pool taking: 2001:8004:11d0:4e2a::2be0:b5cf Oct 02 16:33:16 rivoplus ntpd[3156]: DNS: Pool taking: 2001:8004:11d0:4e2a::9006:eec6 Oct 02 16:33:16 rivoplus ntpd[3156]: DNS: dns_take_status: 3.debian.pool.ntp.org=>good, 8 Oct 02 16:33:16 rivoplus bluealsa[3457]: ../src/storage.c:90: Initializing persistent storage: /var/lib/bluealsa Oct 02 16:33:16 rivoplus bluealsa[3457]: main.c:663: Starting main dispatching loop Oct 02 16:33:17 rivoplus systemd[1]: Started bluealsa.service - BlueALSA service. Oct 02 16:33:17 rivoplus systemd[1]: Reached target bluetooth.target - Bluetooth Support. Oct 02 16:33:17 rivoplus bluealsa[3457]: main.c:118: Acquired D-Bus service name: org.bluealsa Oct 02 16:33:17 rivoplus bluealsa[3457]: bluealsa-dbus.c:358: Registering BlueALSA D-Bus manager: /org/bluealsa Oct 02 16:33:17 rivoplus bluealsa[3457]: bluez.c:153: Registering media application: /org/bluez/hci0 Oct 02 16:33:17 rivoplus bluealsa[3457]: bluez.c:743: Exporting media endpoint object: /org/bluez/hci0/A2DP/SBC/source/1 Oct 02 16:33:17 rivoplus bluealsa[3457]: bluez.c:743: Exporting media endpoint object: /org/bluez/hci0/A2DP/SBC/source/2 Oct 02 16:33:17 rivoplus bluealsa[3457]: bluez.c:743: Exporting media endpoint object: /org/bluez/hci0/A2DP/SBC/sink/1 Oct 02 16:33:17 rivoplus bluealsa[3457]: bluez.c:743: Exporting media endpoint object: /org/bluez/hci0/A2DP/SBC/sink/2 Oct 02 16:33:17 rivoplus bluetoothd[3385]: Endpoint registered: sender=:1.9 path=/org/bluez/hci0/A2DP/SBC/sink/1 Oct 02 16:33:17 rivoplus bluetoothd[3385]: Endpoint registered: sender=:1.9 path=/org/bluez/hci0/A2DP/SBC/sink/2 Oct 02 16:33:17 rivoplus bluetoothd[3385]: Endpoint registered: sender=:1.9 path=/org/bluez/hci0/A2DP/SBC/source/1 Oct 02 16:33:17 rivoplus bluetoothd[3385]: Endpoint registered: sender=:1.9 path=/org/bluez/hci0/A2DP/SBC/source/2 Oct 02 16:33:17 rivoplus volumio-remote-updater[2956]: [2026-10-02 16:33:17] [info] asio async_connect error: asio.system:111 (Connection refused) Oct 02 16:33:17 rivoplus volumio-remote-updater[2956]: [2026-10-02 16:33:17] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) Oct 02 16:33:17 rivoplus volumio-remote-updater[2956]: [2026-10-02 16:33:17] [error] handle_connect error: Connection refused Oct 02 16:33:17 rivoplus ntpd[3156]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Oct 02 16:33:18 rivoplus ntpd[3156]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Oct 02 16:33:18 rivoplus ntpd[3156]: DNS: Pool taking: 44.32.200.123 Oct 02 16:33:18 rivoplus ntpd[3156]: DNS: Pool taking: 119.18.13.199 Oct 02 16:33:18 rivoplus ntpd[3156]: DNS: Pool taking: 27.124.125.252 Oct 02 16:33:18 rivoplus ntpd[3156]: DNS: Pool taking: 129.250.35.251 Oct 02 16:33:18 rivoplus ntpd[3156]: DNS: Pool taking: 2401:d002:3e03:1f01::123 Oct 02 16:33:18 rivoplus ntpd[3156]: DNS: Pool taking: 2a06:a005:5a5::123 Oct 02 16:33:18 rivoplus ntpd[3156]: DNS: Pool taking: 2404:5c0:153::123 Oct 02 16:33:18 rivoplus ntpd[3156]: DNS: Pool taking: 2603:c023:c001:4300:0:41a:3283:e392 Oct 02 16:33:18 rivoplus ntpd[3156]: DNS: dns_take_status: 2.debian.pool.ntp.org=>good, 8 Oct 02 16:33:18 rivoplus sudo[3477]: pam_unix(sudo:session): session closed for user root Oct 02 16:33:18 rivoplus wireless.js[2958]: WIRELESS.JS - INFO: SETTING APPROPRIATE REG DOMAIN: AU Oct 02 16:33:18 rivoplus sudo[3488]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set AU Oct 02 16:33:18 rivoplus sudo[3488]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:33:18 rivoplus sudo[3488]: pam_unix(sudo:session): session closed for user root Oct 02 16:33:18 rivoplus wireless.js[2958]: WIRELESS.JS - INFO: SUCCESSFULLY SET NEW REGDOMAIN: AU Oct 02 16:33:18 rivoplus wireless.js[2958]: WIRELESS.JS - INFO: netconfigured file not found, starting hotspot Oct 02 16:33:18 rivoplus wireless.js[2958]: WIRELESS.JS - INFO: Single Network Mode: Ethernet active, maintaining WiFi scan capability Oct 02 16:33:18 rivoplus wireless.js[2958]: WIRELESS.JS - INFO: SNM: Maintaining wlan0 UP without IP (scan mode) Oct 02 16:33:18 rivoplus wireless.js[2958]: WIRELESS.JS - INFO: SNM: Users can configure WiFi via WebUI while ethernet is active Oct 02 16:33:18 rivoplus wireless.js[2958]: WIRELESS.JS - INFO: InterfaceValidator: FAIL - wlan0 driver not initialized (operstate=unknown) Oct 02 16:33:18 rivoplus ntpd[3156]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Oct 02 16:33:19 rivoplus sudo[3498]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Oct 02 16:33:19 rivoplus sudo[3498]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 02 16:33:19 rivoplus sudo[3498]: pam_unix(sudo:session): session closed for user root Oct 02 16:33:19 rivoplus ntpd[3156]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Oct 02 16:33:19 rivoplus ntpd[3156]: DNS: Pool taking: 112.213.32.219 Oct 02 16:33:19 rivoplus ntpd[3156]: DNS: Pool taking: 51.161.134.84 Oct 02 16:33:19 rivoplus ntpd[3156]: DNS: Pool taking: 44.32.200.120 Oct 02 16:33:19 rivoplus ntpd[3156]: DNS: Pool taking: 103.252.195.117 Oct 02 16:33:19 rivoplus ntpd[3156]: DNS: Pool taking: 2001:8004:11d0:4e2a::67fc:c375 Oct 02 16:33:19 rivoplus ntpd[3156]: DNS: Pool taking: 2001:8004:11d0:4e2a::8b63:87f7 Oct 02 16:33:19 rivoplus ntpd[3156]: DNS: Pool taking: 2001:8004:11d0:4e2a::8bb4:a052 Oct 02 16:33:19 rivoplus ntpd[3156]: DNS: Pool taking: 2001:8004:11d0:4e2a::33a1:8654 Oct 02 16:33:19 rivoplus ntpd[3156]: DNS: dns_take_status: 1.debian.pool.ntp.org=>good, 8 Oct 02 16:33:19 rivoplus sudo[3502]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Oct 02 16:33:19 rivoplus sudo[3502]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 02 16:33:19 rivoplus sudo[3502]: pam_unix(sudo:session): session closed for user root Oct 02 16:33:19 rivoplus wpa_supplicant[3506]: Successfully initialized wpa_supplicant Oct 02 16:33:19 rivoplus dhcpcd[2993]: wlan0: carrier lost - roaming Oct 02 16:33:19 rivoplus dhcpcd[2993]: wlan0: carrier lost - roaming Oct 02 16:33:19 rivoplus kernel: P2P interface registered Oct 02 16:33:19 rivoplus kernel: wl_cfgp2p_add_p2p_disc_if: wdev: ffffffc03caefc00, wdev->net: (null) Oct 02 16:33:19 rivoplus kernel: WLC_E_IF: NO_IF set, event Ignored Oct 02 16:33:19 rivoplus kernel: P2P interface started Oct 02 16:33:19 rivoplus wireless.js[2958]: WIRELESS.JS - INFO: InterfaceValidator: READY - wlan0 is ready for operations Oct 02 16:33:19 rivoplus wireless.js[2958]: WIRELESS.JS - INFO: InterfaceValidator: wlan0 became ready after 7246810ms Oct 02 16:33:19 rivoplus wireless.js[2958]: WIRELESS.JS - INFO: ensureInterfaceReady: Interface ready (MAC: 40:d9:5a:6f:91:72) Oct 02 16:33:19 rivoplus systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 1. Oct 02 16:33:19 rivoplus systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Oct 02 16:33:19 rivoplus sudo[3545]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Oct 02 16:33:19 rivoplus sudo[3545]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:33:19 rivoplus systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Oct 02 16:33:19 rivoplus sudo[3545]: pam_unix(sudo:session): session closed for user root Oct 02 16:33:19 rivoplus upmpdcli[3548]: Could not open config: /tmp/upmpdcli.conf Oct 02 16:33:19 rivoplus sudo[3553]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Oct 02 16:33:19 rivoplus sudo[3553]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:33:19 rivoplus systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Oct 02 16:33:19 rivoplus systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Oct 02 16:33:20 rivoplus systemd[1]: systemd-rfkill.service: Deactivated successfully. Oct 02 16:33:21 rivoplus sudo[3553]: pam_unix(sudo:session): session closed for user root Oct 02 16:33:21 rivoplus wireless.js[2958]: WIRELESS.JS - INFO: SETTING APPROPRIATE REG DOMAIN: AU Oct 02 16:33:21 rivoplus sudo[3558]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set AU Oct 02 16:33:21 rivoplus sudo[3558]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:33:21 rivoplus sudo[3558]: pam_unix(sudo:session): session closed for user root Oct 02 16:33:21 rivoplus wireless.js[2958]: WIRELESS.JS - INFO: SUCCESSFULLY SET NEW REGDOMAIN: AU Oct 02 16:33:21 rivoplus wireless.js[2958]: WIRELESS.JS - INFO: SNM: Transition to scan mode completed in 7250942ms Oct 02 16:33:21 rivoplus wireless.js[2958]: WIRELESS.JS - INFO: SNM: wlan0 is UP without IP, scan capable Oct 02 16:33:21 rivoplus wireless.js[2958]: Failed to connect to non-global ctrl_ifname: wlan0 error: No such file or directory Oct 02 16:33:21 rivoplus wireless.js[2958]: WIRELESS.JS - INFO: Notified systemd about wireless ready Oct 02 16:33:21 rivoplus systemd[1]: Started wireless.service - Wireless Services. Oct 02 16:33:21 rivoplus systemd[1]: Started volumio.service - Volumio Backend Module. Oct 02 16:33:21 rivoplus systemd[1]: Started volumio-kiosk.service - Start Volumio Kiosk. Oct 02 16:33:21 rivoplus systemd[1]: Started volumio_cpu_tweak.service - Volumio Cpu Tweaker. Oct 02 16:33:21 rivoplus systemd[1]: Reached target multi-user.target - Multi-User System. Oct 02 16:33:21 rivoplus systemd[1]: Reached target graphical.target - Graphical Interface. Oct 02 16:33:21 rivoplus systemd[1]: Starting systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP... Oct 02 16:33:21 rivoplus startkiosk.sh[3575]: Starting Volumio Kiosk with arguments for cursor display Oct 02 16:33:21 rivoplus systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Oct 02 16:33:21 rivoplus systemd[1]: Finished systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP. Oct 02 16:33:21 rivoplus systemd[1]: Startup finished in 13.251s (kernel) + 22.292s (userspace) = 35.543s. Oct 02 16:33:21 rivoplus volumio-cpu-tweak[3576]: Setting RT Priority for mpd Oct 02 16:33:21 rivoplus volumio-cpu-tweak[3576]: Setting MPD Affinity Oct 02 16:33:21 rivoplus volumio-cpu-tweak[3601]: pid 3's current affinity mask: 1 Oct 02 16:33:21 rivoplus volumio-cpu-tweak[3576]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Oct 02 16:33:21 rivoplus volumio-cpu-tweak[3576]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Oct 02 16:33:21 rivoplus systemd[1]: volumio_cpu_tweak.service: Deactivated successfully. Oct 02 16:33:22 rivoplus startkiosk.sh[3615]: X.Org X Server 1.21.1.7 Oct 02 16:33:22 rivoplus startkiosk.sh[3615]: X Protocol Version 11, Revision 0 Oct 02 16:33:22 rivoplus startkiosk.sh[3615]: Current Operating System: Linux rivoplus 4.9.241 #4 SMP PREEMPT Wed Jun 4 18:07:40 CEST 2025 aarch64 Oct 02 16:33:22 rivoplus startkiosk.sh[3615]: Kernel command line: imgpart=UUID=5bfae2d1-816d-4021-882b-e6e8770ae8b6 imgfile=/volumio_current.sqsh bootpart=UUID=0050-9281 datapart=UUID=486353cb-10af-4424-92c0-1e14805db08a uuidconfig=env.system.txt hwdevice=VIM3 console=tty0 console=ttyS0,115200n8 no_console_suspend consoleblank=0 logo=osd0,loaded,0x3d800000,1080p60hz vout=1080p60hz,enable hdmimode=1080p60hz fsck.repair=yes net.ifnames=0 ddr_size= wol_enable= mac=02:00:00:13:28:01 fan=auto hwver=VIM3.V11 coherent_pool=2M quiet loglevel=0 splash plymouth.ignore-serial-consoles initramfs.clear splash bootdelay=7 Oct 02 16:33:22 rivoplus startkiosk.sh[3615]: xorg-server 2:21.1.7-3+deb12u12 (https://www.debian.org/support) Oct 02 16:33:22 rivoplus startkiosk.sh[3615]: Current version of pixman: 0.42.2 Oct 02 16:33:22 rivoplus startkiosk.sh[3615]: Before reporting problems, check http://wiki.x.org Oct 02 16:33:22 rivoplus startkiosk.sh[3615]: to make sure that you have the latest version. Oct 02 16:33:22 rivoplus startkiosk.sh[3615]: Markers: (--) probed, (**) from config file, (==) default setting, Oct 02 16:33:22 rivoplus startkiosk.sh[3615]: (++) from command line, (!!) notice, (II) informational, Oct 02 16:33:22 rivoplus startkiosk.sh[3615]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Oct 02 16:33:22 rivoplus startkiosk.sh[3615]: (==) Log file: "/var/log/Xorg.0.log", Time: Fri Oct 2 16:33:22 2026 Oct 02 16:33:22 rivoplus startkiosk.sh[3615]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Oct 02 16:33:22 rivoplus kernel: fb: osd[0] canvas.idx =0x40 Oct 02 16:33:22 rivoplus kernel: fb: osd[0] canvas.addr=0x70000000 Oct 02 16:33:22 rivoplus kernel: fb: osd[0] canvas.width=7680 Oct 02 16:33:22 rivoplus kernel: fb: osd[0] canvas.height=1080 Oct 02 16:33:22 rivoplus kernel: fb: osd[0] frame.width=1920 Oct 02 16:33:22 rivoplus kernel: fb: osd[0] frame.height=1080 Oct 02 16:33:22 rivoplus kernel: fb: osd[0] out_addr_id =0x1 Oct 02 16:33:22 rivoplus kernel: fb: osd[0] enable: 0 (Xorg) Oct 02 16:33:23 rivoplus ntpd[3156]: CLOCK: time stepped by 0.777861 Oct 02 16:33:23 rivoplus ntpd[3156]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Oct 02 16:33:25 rivoplus kernel: fb: osd[0] enable: 1 (Xorg) Oct 02 16:33:25 rivoplus volumio[3574]: info: ------------------------------------------- Oct 02 16:33:25 rivoplus volumio[3574]: info: ----- Volumio3 ---- Oct 02 16:33:25 rivoplus volumio[3574]: info: ------------------------------------------- Oct 02 16:33:25 rivoplus volumio[3574]: info: ----- System startup ---- Oct 02 16:33:25 rivoplus volumio[3574]: info: ------------------------------------------- Oct 02 16:33:26 rivoplus kernel: fb: osd[0] enable: 1 (Xorg) Oct 02 16:33:26 rivoplus volumio[3574]: info: MYVOLUMIO Environment detected Oct 02 16:33:27 rivoplus volumio[3574]: info: Plugin folders cleanup Oct 02 16:33:27 rivoplus volumio[3574]: info: Scanning into folder /volumio/app/plugins/ Oct 02 16:33:27 rivoplus volumio[3574]: info: Scanning category audio_interface Oct 02 16:33:27 rivoplus volumio[3574]: info: Scanning category miscellanea Oct 02 16:33:27 rivoplus volumio[3574]: info: Scanning category music_service Oct 02 16:33:27 rivoplus volumio[3574]: info: Scanning category plugins.json Oct 02 16:33:27 rivoplus volumio[3574]: info: Scanning category system_controller Oct 02 16:33:27 rivoplus volumio[3574]: info: Scanning category user_interface Oct 02 16:33:27 rivoplus volumio[3574]: info: Scanning into folder /data/plugins/ Oct 02 16:33:27 rivoplus volumio[3574]: info: Scanning category audio_interface Oct 02 16:33:27 rivoplus volumio[3574]: info: Plugin folders cleanup completed Oct 02 16:33:27 rivoplus volumio[3574]: info: ------------------------------------------- Oct 02 16:33:27 rivoplus volumio[3574]: info: ----- Core plugins startup ---- Oct 02 16:33:27 rivoplus volumio[3574]: info: ------------------------------------------- Oct 02 16:33:27 rivoplus volumio[3574]: info: Loading plugins from folder /volumio/app/plugins/ Oct 02 16:33:27 rivoplus volumio[3574]: info: Adding plugin bluetooth to MyMusic Plugins Oct 02 16:33:27 rivoplus volumio[3574]: info: Adding plugin multiroom to MyMusic Plugins Oct 02 16:33:27 rivoplus volumio[3574]: info: Adding plugin upnp to MyMusic Plugins Oct 02 16:33:27 rivoplus volumio[3574]: info: Adding plugin metavolumio to MyMusic Plugins Oct 02 16:33:27 rivoplus volumio[3574]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 02 16:33:27 rivoplus volumio[3574]: info: Adding plugin cd_controller to MyMusic Plugins Oct 02 16:33:27 rivoplus volumio[3574]: info: Adding plugin qobuzconnect to MyMusic Plugins Oct 02 16:33:27 rivoplus volumio[3574]: info: Adding plugin raat to MyMusic Plugins Oct 02 16:33:27 rivoplus volumio[3574]: info: Adding plugin smart_inputs to MyMusic Plugins Oct 02 16:33:27 rivoplus volumio[3574]: info: Adding plugin tidalconnect to MyMusic Plugins Oct 02 16:33:27 rivoplus volumio[3574]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 02 16:33:27 rivoplus volumio[3574]: info: Loading plugins from folder /data/plugins/ Oct 02 16:33:27 rivoplus volumio[3574]: info: Loading plugin "system"... Oct 02 16:33:27 rivoplus volumio[3574]: info: Loading plugin "appearance"... Oct 02 16:33:28 rivoplus volumio-remote-updater[2956]: [2026-10-02 16:33:28] [connect] Successful connection Oct 02 16:33:29 rivoplus volumio[3574]: info: Loading plugin "network"... Oct 02 16:33:29 rivoplus volumio[3574]: info: Refreshing Cached IP Addresses Oct 02 16:33:29 rivoplus sudo[3674]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 02 16:33:29 rivoplus sudo[3674]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:33:29 rivoplus sudo[3676]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 02 16:33:29 rivoplus sudo[3676]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:33:29 rivoplus volumio[3574]: info: Loading plugin "services"... Oct 02 16:33:29 rivoplus volumio[3574]: info: Loading plugin "volumio5onboarding"... Oct 02 16:33:29 rivoplus sudo[3674]: pam_unix(sudo:session): session closed for user root Oct 02 16:33:29 rivoplus sudo[3681]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 02 16:33:29 rivoplus sudo[3681]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:33:29 rivoplus sudo[3676]: pam_unix(sudo:session): session closed for user root Oct 02 16:33:29 rivoplus volumio[3574]: info: Loading plugin "alsa_controller"... Oct 02 16:33:29 rivoplus kernel: wl_escan_set_scan: ioctl_ver=2 Oct 02 16:33:29 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03cba6000 drv_status 0x0 e_type 69 e_states 8 Oct 02 16:33:29 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03cba6000 drv_status 0x0 e_type 69 e_states 8 Oct 02 16:33:29 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03cba6000 drv_status 0x0 e_type 69 e_states 8 Oct 02 16:33:29 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 02 16:33:29 rivoplus volumio[3574]: info: Loading plugin "wizard"... Oct 02 16:33:29 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03cba6000 drv_status 0x0 e_type 69 e_states 8 Oct 02 16:33:29 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03cba6000 drv_status 0x0 e_type 69 e_states 8 Oct 02 16:33:29 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03cba6000 drv_status 0x0 e_type 69 e_states 8 Oct 02 16:33:29 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03cba6000 drv_status 0x0 e_type 69 e_states 8 Oct 02 16:33:29 rivoplus volumio[3574]: info: Loading plugin "networkfs"... Oct 02 16:33:29 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03cba6000 drv_status 0x0 e_type 69 e_states 8 Oct 02 16:33:29 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03cba6000 drv_status 0x0 e_type 69 e_states 8 Oct 02 16:33:29 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03cba6000 drv_status 0x0 e_type 69 e_states 8 Oct 02 16:33:29 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03cba6000 drv_status 0x0 e_type 69 e_states 8 Oct 02 16:33:29 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03cba6000 drv_status 0x0 e_type 69 e_states 8 Oct 02 16:33:29 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03cba6000 drv_status 0x0 e_type 69 e_states 8 Oct 02 16:33:29 rivoplus volumio[3574]: info: Starting Udev Watcher for removable devices Oct 02 16:33:29 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03cba6000 drv_status 0x0 e_type 69 e_states 8 Oct 02 16:33:29 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03cba6000 drv_status 0x0 e_type 69 e_states 8 Oct 02 16:33:29 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03cba6000 drv_status 0x0 e_type 69 e_states 8 Oct 02 16:33:29 rivoplus volumio[3574]: info: Ignoring mount for partition: BOOT Oct 02 16:33:29 rivoplus volumio[3574]: info: Ignoring mount for partition: volumio Oct 02 16:33:29 rivoplus volumio[3574]: info: Ignoring mount for partition: volumio_data Oct 02 16:33:29 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 02 16:33:29 rivoplus volumio[3574]: info: Loading plugin "volumio_command_line_client"... Oct 02 16:33:29 rivoplus volumio[3574]: info: Loading plugin "upnp"... Oct 02 16:33:29 rivoplus volumio[3574]: info: [1790922809582] Starting Upmpd Daemon Oct 02 16:33:29 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 02 16:33:29 rivoplus volumio[3574]: info: Loading plugin "my_music"... Oct 02 16:33:29 rivoplus volumio[3574]: info: Loading plugin "mpd"... Oct 02 16:33:29 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03cba6000 drv_status 0x0 e_type 69 e_states 8 Oct 02 16:33:29 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03cba6000 drv_status 0x0 e_type 69 e_states 8 Oct 02 16:33:29 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03cba6000 drv_status 0x0 e_type 69 e_states 8 Oct 02 16:33:29 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03cba6000 drv_status 0x0 e_type 69 e_states 8 Oct 02 16:33:29 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03cba6000 drv_status 0x0 e_type 69 e_states 8 Oct 02 16:33:29 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03cba6000 drv_status 0x0 e_type 69 e_states 8 Oct 02 16:33:29 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03cba6000 drv_status 0x0 e_type 69 e_states 8 Oct 02 16:33:29 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03cba6000 drv_status 0x0 e_type 69 e_states 8 Oct 02 16:33:29 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03cba6000 drv_status 0x0 e_type 69 e_states 8 Oct 02 16:33:29 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03cba6000 drv_status 0x0 e_type 69 e_states 8 Oct 02 16:33:29 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03cba6000 drv_status 0x0 e_type 69 e_states 8 Oct 02 16:33:29 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03cba6000 drv_status 0x0 e_type 69 e_states 8 Oct 02 16:33:30 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03cba6000 drv_status 0x0 e_type 69 e_states 8 Oct 02 16:33:30 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03cba6000 drv_status 0x0 e_type 69 e_states 8 Oct 02 16:33:30 rivoplus volumio[3574]: info: Loading plugin "upnp_browser"... Oct 02 16:33:30 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03cba6000 drv_status 0x0 e_type 69 e_states 8 Oct 02 16:33:30 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03cba6000 drv_status 0x0 e_type 69 e_states 8 Oct 02 16:33:30 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03cba6000 drv_status 0x0 e_type 69 e_states 8 Oct 02 16:33:30 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03cba6000 drv_status 0x0 e_type 69 e_states 8 Oct 02 16:33:30 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03cba6000 drv_status 0x0 e_type 69 e_states 8 Oct 02 16:33:31 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03cba6000 drv_status 0x0 e_type 69 e_states 8 Oct 02 16:33:31 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03cba6000 drv_status 0x0 e_type 69 e_states 8 Oct 02 16:33:31 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03cba6000 drv_status 0x0 e_type 69 e_states 0 Oct 02 16:33:31 rivoplus sudo[3681]: pam_unix(sudo:session): session closed for user root Oct 02 16:33:31 rivoplus volumio[3574]: info: Starting UPNP Browser Oct 02 16:33:31 rivoplus volumio[3574]: info: Loading plugin "bluetooth"... Oct 02 16:33:32 rivoplus volumio[3574]: info: Applying required configuration parameters for plugin bluetooth Oct 02 16:33:32 rivoplus volumio[3574]: info: Applying required configuration parameters for plugin bluetooth Oct 02 16:33:32 rivoplus volumio[3574]: ------------------------------------ BT MESSAGE: [FUNC] onVolumioStart Oct 02 16:33:32 rivoplus volumio[3574]: info: Loading plugin "alarm-clock"... Oct 02 16:33:32 rivoplus volumio[3574]: info: Loading plugin "manifestui"... Oct 02 16:33:32 rivoplus volumio[3574]: info: Loading plugin "metavolumio"... Oct 02 16:33:33 rivoplus systemd[1]: systemd-fsckd.service: Deactivated successfully. Oct 02 16:33:34 rivoplus volumio[3574]: info: Loading plugin "airplay_emulation"... Oct 02 16:33:34 rivoplus volumio[3574]: info: Starting Shairport Sync Oct 02 16:33:34 rivoplus volumio[3574]: info: Loading plugin "cd_controller"... Oct 02 16:33:34 rivoplus volumio[3574]: info: Loading plugin "last_100"... Oct 02 16:33:34 rivoplus volumio[3574]: info: Loading plugin "qobuzconnect"... Oct 02 16:33:34 rivoplus volumio[3574]: info: Loading plugin "raat"... Oct 02 16:33:34 rivoplus volumio[3574]: info: RAAT Plugin loaded Oct 02 16:33:34 rivoplus volumio[3574]: info: Adding restartRAATSocket REST API Endpoint Oct 02 16:33:34 rivoplus volumio[3574]: info: Adding restartRAATSocket REST Endpoint for plugin: music_service/raat Oct 02 16:33:34 rivoplus volumio[3574]: info: Loading plugin "streaming_services"... Oct 02 16:33:35 rivoplus systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2. Oct 02 16:33:35 rivoplus systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Oct 02 16:33:35 rivoplus systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Oct 02 16:33:35 rivoplus upmpdcli[3710]: Could not open config: /tmp/upmpdcli.conf Oct 02 16:33:35 rivoplus systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Oct 02 16:33:35 rivoplus systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Oct 02 16:33:36 rivoplus volumio[3574]: info: Loading plugin "tidalconnect"... Oct 02 16:33:36 rivoplus volumio[3574]: info: Loading plugin "webradio"... Oct 02 16:33:36 rivoplus volumio[3574]: info: Loading plugin "i2s_dacs"... Oct 02 16:33:36 rivoplus volumio[3574]: info: I2S DAC not set, start Auto-detection Oct 02 16:33:36 rivoplus volumio[3574]: info: Loading plugin "volumiodiscovery"... Oct 02 16:33:36 rivoplus volumio[3574]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 02 16:33:36 rivoplus volumio[3574]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 02 16:33:36 rivoplus volumio[3574]: *** WARNING *** For more information see Oct 02 16:33:36 rivoplus volumio[3574]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 02 16:33:36 rivoplus volumio[3574]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 02 16:33:36 rivoplus node[3574]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 02 16:33:36 rivoplus volumio[3574]: *** WARNING *** For more information see Oct 02 16:33:36 rivoplus node[3574]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 02 16:33:36 rivoplus node[3574]: *** WARNING *** For more information see Oct 02 16:33:36 rivoplus node[3574]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 02 16:33:36 rivoplus node[3574]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 02 16:33:36 rivoplus node[3574]: *** WARNING *** For more information see Oct 02 16:33:36 rivoplus volumio[3574]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 02 16:33:36 rivoplus volumio[3574]: info: Discovery: Started advertising with name: Rivoplus Oct 02 16:33:36 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 02 16:33:36 rivoplus volumio[3574]: info: Loading plugin "multiroom"... Oct 02 16:33:39 rivoplus volumio[3574]: info: Applying required configuration parameters for plugin multiroom Oct 02 16:33:39 rivoplus sudo[3722]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Oct 02 16:33:39 rivoplus sudo[3722]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:33:39 rivoplus sudo[3722]: pam_unix(sudo:session): session closed for user root Oct 02 16:33:39 rivoplus volumio[3574]: info: MRS: MultiRoom plugin initialized Oct 02 16:33:39 rivoplus volumio[3574]: info: MRS: STOPPING SNAPCLIENT Oct 02 16:33:39 rivoplus volumio[3574]: info: MRS: Snap server stop Oct 02 16:33:39 rivoplus volumio[3574]: info: MRS: STOPPING volumioStreaming Oct 02 16:33:39 rivoplus sudo[3738]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Oct 02 16:33:39 rivoplus sudo[3738]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:33:39 rivoplus sudo[3740]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Oct 02 16:33:39 rivoplus sudo[3740]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:33:39 rivoplus volumio[3574]: info: Loading plugin "outputs"... Oct 02 16:33:39 rivoplus sudo[3742]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Oct 02 16:33:39 rivoplus sudo[3742]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:33:39 rivoplus volumio[3574]: info: Loading plugin "albumart"... Oct 02 16:33:39 rivoplus sudo[3746]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Oct 02 16:33:39 rivoplus sudo[3746]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:33:39 rivoplus volumio[3574]: info: Plugin example_plugin is not enabled Oct 02 16:33:39 rivoplus volumio[3574]: info: Loading plugin "hi_res_audio"... Oct 02 16:33:39 rivoplus sudo[3746]: pam_unix(sudo:session): session closed for user root Oct 02 16:33:39 rivoplus sudo[3738]: pam_unix(sudo:session): session closed for user root Oct 02 16:33:39 rivoplus sudo[3740]: pam_unix(sudo:session): session closed for user root Oct 02 16:33:39 rivoplus sudo[3742]: pam_unix(sudo:session): session closed for user root Oct 02 16:33:39 rivoplus volumio[3748]: Forking 3 albumart workers Oct 02 16:33:41 rivoplus volumio[3773]: Starting albumart workers Oct 02 16:33:41 rivoplus volumio[3774]: Starting albumart workers Oct 02 16:33:41 rivoplus volumio[3574]: info: Applying required configuration parameters for plugin hi_res_audio Oct 02 16:33:41 rivoplus volumio[3574]: info: Loading plugin "inputs"... Oct 02 16:33:41 rivoplus volumio[3775]: Starting albumart workers Oct 02 16:33:42 rivoplus volumio[3574]: info: Loading plugin "qobuz"... Oct 02 16:33:43 rivoplus volumio-remote-updater[2956]: [2026-10-02 16:33:43] [connect] Successful connection Oct 02 16:33:44 rivoplus volumio[3574]: info: Loading plugin "smart_inputs"... Oct 02 16:33:44 rivoplus volumio[3574]: info: Loading plugin "tidal"... Oct 02 16:33:45 rivoplus volumio[3574]: info: Loading plugin "rivopluscontrol"... Oct 02 16:33:46 rivoplus volumio[3574]: info: Adding this device properties Oct 02 16:33:46 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: system , setThisDeviceVolumioProperties Oct 02 16:33:46 rivoplus volumio[3574]: info: Setting Additional Device Volumio Properties: [object Object] Oct 02 16:33:46 rivoplus volumio[3574]: info: Loading plugin "updater_comm"... Oct 02 16:33:46 rivoplus volumio[3574]: info: Loading plugin "cec_controller"... Oct 02 16:33:46 rivoplus volumio[3574]: info: Applying required configuration parameters for plugin cec_controller Oct 02 16:33:46 rivoplus volumio[3574]: info: Plugin mpdemulation is not enabled Oct 02 16:33:46 rivoplus volumio[3574]: info: Loading plugin "rest_api"... Oct 02 16:33:46 rivoplus volumio[3574]: info: Loading plugin "websocket"... Oct 02 16:33:46 rivoplus volumio[3574]: info: Starting Socket.io Server version 1.7.4 Oct 02 16:33:46 rivoplus volumio[3574]: info: Loading plugin "fusiondsp"... Oct 02 16:33:47 rivoplus volumio[3574]: info: Applying required configuration parameters for plugin fusiondsp Oct 02 16:33:47 rivoplus volumio[3574]: info: Loading i18n strings for locale en Oct 02 16:33:47 rivoplus systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Oct 02 16:33:47 rivoplus volumio[3574]: Updating browse sources language Oct 02 16:33:47 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 02 16:33:47 rivoplus systemd[1]: systemd-hostnamed.service: Deactivated successfully. Oct 02 16:33:47 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 16:33:47 rivoplus volumio[3574]: info: CoreCommandRouter::initPlayerControls Oct 02 16:33:47 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:33:47 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:33:47 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:33:47 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:33:47 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:33:47 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:33:47 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:33:47 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:33:47 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 16:33:47 rivoplus volumio[3574]: Express server listening on port 3000 Oct 02 16:33:47 rivoplus volumio[3574]: [Metrics] WebUI: 23s 12.91ms Oct 02 16:33:47 rivoplus volumio[3574]: info: CoreStateMachine::resetVolumioState Oct 02 16:33:47 rivoplus volumio[3574]: info: CoreStateMachine::getcurrentVolume Oct 02 16:33:47 rivoplus volumio[3574]: info: CoreCommandRouter::volumioRetrievevolume Oct 02 16:33:47 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 16:33:47 rivoplus sudo[3860]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 02 16:33:47 rivoplus sudo[3860]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:33:47 rivoplus volumio[3574]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Oct 02 16:33:47 rivoplus volumio[3574]: info: Completed loading Core Plugins Oct 02 16:33:47 rivoplus volumio[3574]: info: Preparing to generate the ALSA configuration file Oct 02 16:33:47 rivoplus sudo[3862]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 02 16:33:47 rivoplus sudo[3862]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:33:47 rivoplus sudo[3860]: pam_unix(sudo:session): session closed for user root Oct 02 16:33:47 rivoplus sudo[3862]: pam_unix(sudo:session): session closed for user root Oct 02 16:33:48 rivoplus volumio[3574]: info: Volumio Network Manager: Network status updated: 1 Oct 02 16:33:48 rivoplus volumio[3574]: info: CoreStateMachine::pushState Oct 02 16:33:48 rivoplus volumio[3574]: info: CorePlayQueue::getTrack 0 Oct 02 16:33:48 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 16:33:48 rivoplus volumio[3574]: info: CoreCommandRouter::volumioPushState Oct 02 16:33:48 rivoplus volumio[3574]: info: CoreStateMachine::updateTrackBlock Oct 02 16:33:48 rivoplus volumio[3574]: info: CorePlayQueue::getTrackBlock Oct 02 16:33:48 rivoplus volumio[3574]: info: CoreCommandRouter::volumioRetrievevolume Oct 02 16:33:48 rivoplus volumio[3574]: info: MRS: Removed streaming files Oct 02 16:33:48 rivoplus volumio[3574]: info: MRS: volumioStreaming STOPPED Oct 02 16:33:48 rivoplus volumio[3574]: info: MRS: SNAPSERVER STOPPED Oct 02 16:33:48 rivoplus volumio[3574]: info: MRS: SNAPCLIENT STOPPED Oct 02 16:33:48 rivoplus volumio[3574]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Oct 02 16:33:48 rivoplus volumio[3574]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Oct 02 16:33:48 rivoplus volumio[3574]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 02 16:33:48 rivoplus volumio[3574]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Oct 02 16:33:48 rivoplus volumio[3574]: info: Reading ALSA contributions from plugins. Oct 02 16:33:48 rivoplus volumio[3574]: info: CoreStateMachine::pushState Oct 02 16:33:48 rivoplus volumio[3574]: info: CorePlayQueue::getTrack 0 Oct 02 16:33:48 rivoplus volumio[3574]: info: CoreCommandRouter::volumioPushState Oct 02 16:33:48 rivoplus volumio[3574]: info: Reloading queue from file Oct 02 16:33:48 rivoplus volumio[3574]: verbose: New Socket.io Connection to 192.168.0.63:3000 from 192.168.0.122 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 1 Oct 02 16:33:48 rivoplus volumio[3574]: info: CoreStateMachine::setRepeat null single undefined Oct 02 16:33:48 rivoplus volumio[3574]: info: CoreStateMachine::pushState Oct 02 16:33:48 rivoplus volumio[3574]: info: CorePlayQueue::getTrack 0 Oct 02 16:33:48 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 16:33:48 rivoplus volumio[3574]: info: CoreCommandRouter::volumioPushState Oct 02 16:33:48 rivoplus volumio[3574]: info: CoreStateMachine::setRandom true Oct 02 16:33:48 rivoplus volumio[3574]: info: CoreStateMachine::pushState Oct 02 16:33:48 rivoplus volumio[3574]: info: CorePlayQueue::getTrack 0 Oct 02 16:33:48 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 16:33:48 rivoplus volumio[3574]: info: CoreCommandRouter::volumioPushState Oct 02 16:33:48 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 02 16:33:48 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 02 16:33:48 rivoplus volumio[3574]: info: Discovery: Getting this device information Oct 02 16:33:48 rivoplus volumio[3574]: info: CoreCommandRouter::volumioGetState Oct 02 16:33:48 rivoplus volumio[3574]: info: CorePlayQueue::getTrack 0 Oct 02 16:33:48 rivoplus sudo[3880]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Oct 02 16:33:48 rivoplus sudo[3880]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:33:48 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 02 16:33:48 rivoplus volumio-remote-updater[2956]: [2026-10-02 16:33:48] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1790922823 101 Oct 02 16:33:48 rivoplus volumio[3574]: 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: 1 Oct 02 16:33:48 rivoplus volumio[3574]: info: Asound.conf file unchanged, so no further update is needed Oct 02 16:33:48 rivoplus volumio[3574]: info: Output device has changed, restarting MPD Oct 02 16:33:48 rivoplus volumio[3574]: info: Output device has changed, restarting Shairport Sync Oct 02 16:33:48 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:33:48 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 16:33:48 rivoplus sudo[3883]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 02 16:33:48 rivoplus sudo[3883]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:33:48 rivoplus sudo[3883]: pam_unix(sudo:session): session closed for user root Oct 02 16:33:48 rivoplus sudo[3885]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 02 16:33:48 rivoplus sudo[3885]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:33:48 rivoplus volumio[3574]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 02 16:33:48 rivoplus volumio[3574]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 02 16:33:48 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:33:48 rivoplus volumio[3574]: info: ___________ START PLUGINS ___________ Oct 02 16:33:48 rivoplus volumio[3574]: info: ControllerMpd::onStart: Initializing MPD Oct 02 16:33:48 rivoplus volumio[3574]: info: Creating MPD Configuration file Oct 02 16:33:48 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 16:33:48 rivoplus volumio[3574]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 02 16:33:48 rivoplus volumio[3574]: info: [1790922828627] CoreMusicLibrary::Adding element Media Servers Oct 02 16:33:48 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 02 16:33:48 rivoplus sudo[3894]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Oct 02 16:33:48 rivoplus sudo[3894]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:33:48 rivoplus systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 02 16:33:48 rivoplus volumio[3574]: info: UPNP Browser: Client initialized successfully Oct 02 16:33:48 rivoplus systemd[1]: Starting mpd.service - Music Player Daemon... Oct 02 16:33:48 rivoplus sudo[3896]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 02 16:33:48 rivoplus sudo[3896]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:33:48 rivoplus volumio[3574]: ------------------------------------ BT MESSAGE: [FUNC] onStart Oct 02 16:33:48 rivoplus volumio[3574]: ------------------------------------ BT MESSAGE: Starting Volumio Bluetooth Service Oct 02 16:33:48 rivoplus sudo[3896]: pam_unix(sudo:session): session closed for user root Oct 02 16:33:48 rivoplus volumio[3574]: ------------------------------------ BT MESSAGE: Boot config /etc/bluetooth/volumio.conf: cache mode = tmp Oct 02 16:33:48 rivoplus volumio[3574]: ------------------------------------ BT MESSAGE: [metaCache] Created directory: /tmp/bluetooth-cache/ Oct 02 16:33:48 rivoplus volumio[3574]: ------------------------------------ BT MESSAGE: [metaCache] Directory exists and is ready. Oct 02 16:33:48 rivoplus sudo[3900]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 02 16:33:48 rivoplus sudo[3900]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:33:48 rivoplus volumio[3574]: info: Adding METAVOLUMIO REST API Endpoints Oct 02 16:33:48 rivoplus volumio[3574]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Oct 02 16:33:48 rivoplus volumio[3574]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Oct 02 16:33:48 rivoplus volumio[3574]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Oct 02 16:33:48 rivoplus volumio[3574]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Oct 02 16:33:48 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:33:48 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 16:33:48 rivoplus systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Oct 02 16:33:48 rivoplus sudo[3894]: pam_unix(sudo:session): session closed for user root Oct 02 16:33:48 rivoplus volumio[3574]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 02 16:33:48 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:33:48 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 16:33:48 rivoplus bluetoothd[3385]: Path / reserved for Adv Monitor app :1.16 Oct 02 16:33:48 rivoplus bluetoothd[3385]: Adv Monitor app :1.16 disconnected from D-Bus Oct 02 16:33:48 rivoplus systemd[1]: mpd.service: Deactivated successfully. Oct 02 16:33:48 rivoplus systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 02 16:33:48 rivoplus volumio[3574]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 02 16:33:48 rivoplus systemd[1]: mpd.socket: Deactivated successfully. Oct 02 16:33:48 rivoplus systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 02 16:33:48 rivoplus systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 02 16:33:48 rivoplus volumio[3574]: info: Preparing CD Folders Oct 02 16:33:48 rivoplus volumio[3574]: info: Adding CD REST API Endpoints Oct 02 16:33:48 rivoplus volumio[3574]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Oct 02 16:33:48 rivoplus volumio[3574]: info: Starting UDEV Watcher for CD Oct 02 16:33:48 rivoplus volumio[3574]: info: Detecting CD presence with UDEV Oct 02 16:33:48 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Oct 02 16:33:48 rivoplus systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 02 16:33:48 rivoplus systemd[1]: Starting mpd.service - Music Player Daemon... Oct 02 16:33:49 rivoplus volumio5-onboarding[3906]: time=2026-10-02T16:33:49.231+10:00 level=INFO msg="running volumio4-device-gateway" version=415db8e7+CHANGES buildDate=2026-09-15T15:08:58Z Oct 02 16:33:49 rivoplus sudo[3914]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 02 16:33:49 rivoplus sudo[3914]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 02 16:33:49 rivoplus sudo[3925]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Oct 02 16:33:49 rivoplus sudo[3914]: pam_unix(sudo:session): session closed for user root Oct 02 16:33:49 rivoplus kernel: fb: mem_free_work, free memory: addr:800000 Oct 02 16:33:50 rivoplus systemd[1]: setdatetime-helper.service: Deactivated successfully. Oct 02 16:33:50 rivoplus systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Oct 02 16:33:50 rivoplus systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 3. Oct 02 16:33:50 rivoplus systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Oct 02 16:33:50 rivoplus systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Oct 02 16:33:50 rivoplus sudo[3880]: pam_unix(sudo:session): session closed for user root Oct 02 16:33:53 rivoplus mpd[3926]: 2026-10-02T16:33:53 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 02 16:33:53 rivoplus systemd[1]: Started mpd.service - Music Player Daemon. Oct 02 16:33:53 rivoplus sudo[3900]: pam_unix(sudo:session): session closed for user root Oct 02 16:33:53 rivoplus sudo[3885]: pam_unix(sudo:session): session closed for user root Oct 02 16:33:53 rivoplus volumio[3574]: warn: [cd-plugin] cdspeedctl: device or media not ready Oct 02 16:33:53 rivoplus volumio[3574]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 02 16:33:53 rivoplus volumio[3574]: info: [1790922833925] CoreMusicLibrary::Adding element Last_100 Oct 02 16:33:53 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 02 16:33:53 rivoplus volumio[3574]: info: Adding qc_getconfig REST Endpoint for plugin: music_service/qobuzconnect Oct 02 16:33:53 rivoplus volumio[3574]: info: QobuzConnect: Starting Qobuz Connect socket and service Oct 02 16:33:53 rivoplus volumio[3574]: info: Starting RAAT Plugin Oct 02 16:33:53 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , addAdditionalUISections Oct 02 16:33:53 rivoplus volumio[3574]: info: Additional UI Settings Added for plugin music_service/raat Oct 02 16:33:53 rivoplus volumio[3574]: info: Registering DSP Elements listener and retrieving current ones Oct 02 16:33:53 rivoplus volumio[3574]: info: Additional DSP elements updated Oct 02 16:33:53 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:33:53 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:33:53 rivoplus volumio[3574]: info: Updating RAAT Signal Path Oct 02 16:33:53 rivoplus volumio[3574]: error: Cannot write to RAAT Client: TypeError: Cannot read properties of undefined (reading 'write') Oct 02 16:33:53 rivoplus volumio[3574]: info: Streaming services startup Oct 02 16:33:53 rivoplus volumio[3574]: info: Starting Streaming Daemon Oct 02 16:33:54 rivoplus sudo[3973]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 02 16:33:54 rivoplus sudo[3973]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:33:54 rivoplus sudo[3977]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 02 16:33:54 rivoplus sudo[3977]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:33:54 rivoplus sudo[3973]: pam_unix(sudo:session): session closed for user root Oct 02 16:33:54 rivoplus volumio[3574]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 02 16:33:54 rivoplus volumio[3574]: info: [1790922834048] CoreMusicLibrary::Adding element Webradio Oct 02 16:33:54 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 02 16:33:54 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 02 16:33:54 rivoplus volumio[3574]: info: Initializing BBC Radios Oct 02 16:33:54 rivoplus sudo[3977]: pam_unix(sudo:session): session closed for user root Oct 02 16:33:54 rivoplus sudo[3984]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 02 16:33:54 rivoplus sudo[3984]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:33:54 rivoplus sudo[3985]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 02 16:33:54 rivoplus sudo[3985]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:33:54 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 02 16:33:54 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 16:33:54 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Oct 02 16:33:54 rivoplus systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Oct 02 16:33:54 rivoplus volumio[3574]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 02 16:33:54 rivoplus sudo[3984]: pam_unix(sudo:session): session closed for user root Oct 02 16:33:54 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:33:54 rivoplus sudo[3985]: pam_unix(sudo:session): session closed for user root Oct 02 16:33:54 rivoplus volumio[3574]: error: Hi Res Audio Failed Login: Missing Login Data Oct 02 16:33:54 rivoplus volumio[3574]: info: Adding HIGHRESAUDIO REST API Endpoints Oct 02 16:33:54 rivoplus volumio[3574]: info: Adding saveAccountData_hi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Oct 02 16:33:54 rivoplus volumio[3574]: info: Initializing Serial Communication on port /dev/ttyS3 Oct 02 16:33:54 rivoplus volumio[3574]: info: Touch Event Listener Process Starting Oct 02 16:33:54 rivoplus kernel: meson_uart ffd22000.serial: ttyS3 use xtal(24M) 24000000 change 0 to 9600 Oct 02 16:33:54 rivoplus kernel: meson_uart ffd22000.serial: ttyS3 use xtal(24M) 24000000 change 9600 to 9600 Oct 02 16:33:54 rivoplus kernel: meson_uart ffd22000.serial: ttyS3 use xtal(24M) 24000000 change 9600 to 115200 Oct 02 16:33:54 rivoplus volumio[3574]: info: Adding inputs REST Endpoints Oct 02 16:33:54 rivoplus volumio[3574]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Oct 02 16:33:54 rivoplus volumio[3574]: info: Scanning Audio Inputs Oct 02 16:33:54 rivoplus sudo[4004]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/xinput --test-xi2 --root Oct 02 16:33:54 rivoplus sudo[4004]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:33:54 rivoplus volumio[3574]: info: Checking against Known Cards name Oct 02 16:33:54 rivoplus volumio[3574]: info: Checking against Known Cards name Oct 02 16:33:54 rivoplus volumio[3574]: info: Checking against Known Cards name Oct 02 16:33:54 rivoplus volumio[3574]: info: Checking against Known Cards name Oct 02 16:33:54 rivoplus volumio[3574]: info: Checking against Known Cards name Oct 02 16:33:54 rivoplus volumio[3574]: info: Adding Server instance for streaming Oct 02 16:33:54 rivoplus volumio[3574]: info: Adding TIDAL to Browse Sources Oct 02 16:33:54 rivoplus volumio[3574]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 02 16:33:54 rivoplus volumio[3574]: info: [1790922834264] CoreMusicLibrary::Adding element TIDAL Oct 02 16:33:54 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 02 16:33:54 rivoplus volumio[3574]: Cannot find translation for source TIDAL Oct 02 16:33:54 rivoplus volumio[3574]: info: Refreshing TIDAL token Oct 02 16:33:54 rivoplus sudo[4014]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x25 0x0 Oct 02 16:33:54 rivoplus sudo[4014]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:33:54 rivoplus sudo[4014]: pam_unix(sudo:session): session closed for user root Oct 02 16:33:54 rivoplus sudo[4017]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x28 0x0 Oct 02 16:33:54 rivoplus sudo[4017]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:33:54 rivoplus sudo[4017]: pam_unix(sudo:session): session closed for user root Oct 02 16:33:54 rivoplus sudo[4020]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x29 0x1 Oct 02 16:33:54 rivoplus sudo[4020]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:33:54 rivoplus sudo[4020]: pam_unix(sudo:session): session closed for user root Oct 02 16:33:54 rivoplus sudo[4023]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x33 0x1 Oct 02 16:33:54 rivoplus sudo[4023]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:33:54 rivoplus sudo[4023]: pam_unix(sudo:session): session closed for user root Oct 02 16:33:54 rivoplus volumio[3574]: info: Apply VIM3L Onboard LEDs Settings Oct 02 16:33:54 rivoplus volumio[3574]: info: Turning On Onboard LEDs Oct 02 16:33:54 rivoplus volumio[3574]: info: Starting CEC Event listener Oct 02 16:33:54 rivoplus sudo[4028]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/sys_led/trigger Oct 02 16:33:54 rivoplus sudo[4028]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:33:54 rivoplus sudo[4028]: pam_unix(sudo:session): session closed for user root Oct 02 16:33:54 rivoplus sudo[4031]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/red_red/trigger Oct 02 16:33:54 rivoplus sudo[4031]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:33:54 rivoplus volumio[3574]: info: FusionDsp - CamillaDSP binary found. Oct 02 16:33:54 rivoplus volumio[3574]: info: Loading i18n strings for locale en Oct 02 16:33:54 rivoplus sudo[4031]: pam_unix(sudo:session): session closed for user root Oct 02 16:33:54 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Oct 02 16:33:54 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:33:54 rivoplus volumio[3574]: info: FusionDsp - mixtype--------------------- Software Oct 02 16:33:54 rivoplus volumio[3574]: info: Volumio Calling Home Oct 02 16:33:54 rivoplus volumio[3574]: info: QobuzConnect: Opened /tmp/qbz-connect.socket socket, listening for connections Oct 02 16:33:54 rivoplus volumio[3574]: (node:3574) [DEP0005] DeprecationWarning: Buffer() is deprecated due to security and usability issues. Please use the Buffer.alloc(), Buffer.allocUnsafe(), or Buffer.from() methods instead. Oct 02 16:33:54 rivoplus volumio[3574]: (Use `node --trace-deprecation ...` to show where the warning was created) Oct 02 16:33:54 rivoplus volumio[3574]: info: Stopping AccessToken refresher cron for QOBUZ Oct 02 16:33:54 rivoplus volumio[3574]: info: AccessToken refresher cron started for QOBUZ Oct 02 16:33:54 rivoplus volumio[3574]: info: Preparing to generate the ALSA configuration file Oct 02 16:33:54 rivoplus volumio[3574]: info: Adding QOBUZ REST API Endpoints Oct 02 16:33:55 rivoplus kernel: cectx ff80023c.aocec: bad iniator with self:CEC RX len 5: 0f 87 08 00 46 Oct 02 16:33:55 rivoplus volumio[3574]: info: Discovery: adding e632d229-6114-4272-a81a-5d9cff6077f2 Oct 02 16:33:55 rivoplus volumio[3574]: info: Discovery: Found device Rivoplus Oct 02 16:33:55 rivoplus volumio[3574]: info: CoreCommandRouter::volumioGetState Oct 02 16:33:55 rivoplus volumio[3574]: info: CorePlayQueue::getTrack 0 Oct 02 16:33:55 rivoplus volumio[3574]: info: MRS: Pushing multiroomSync output for this device Oct 02 16:33:55 rivoplus volumio[3574]: info: MRS: Pushing multiroomSync output Oct 02 16:33:55 rivoplus volumio[3574]: info: Adding audio output: Oct 02 16:33:55 rivoplus volumio[3574]: info: Adding audio output: Oct 02 16:33:55 rivoplus volumio[3574]: info: Discovery: this is already registered, e632d229-6114-4272-a81a-5d9cff6077f2 Oct 02 16:33:55 rivoplus volumio[3574]: info: Discovery: Found device Rivoplus Oct 02 16:33:55 rivoplus volumio[3574]: info: CoreCommandRouter::volumioGetState Oct 02 16:33:55 rivoplus volumio[3574]: info: CorePlayQueue::getTrack 0 Oct 02 16:33:55 rivoplus volumio[3574]: info: Serial port opened successfully Oct 02 16:33:55 rivoplus volumio[3574]: info: Sending serial start messages Oct 02 16:33:55 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:33:55 rivoplus volumio[3574]: info: Reporting MCU Network Status: 1 Oct 02 16:33:55 rivoplus volumio[3574]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Oct 02 16:33:55 rivoplus volumio[3574]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 02 16:33:55 rivoplus volumio[3574]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Oct 02 16:33:55 rivoplus volumio[3574]: info: Reading ALSA contributions from plugins. Oct 02 16:33:55 rivoplus volumio[3574]: error: Cannot start Volumio Streaming Daemon Oct 02 16:33:55 rivoplus volumio[3574]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 02 16:33:55 rivoplus volumio[3574]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 02 16:33:55 rivoplus volumio[3574]: info: RAAT Albumart path created successfully Oct 02 16:33:55 rivoplus volumio[3574]: ------------------------------------ BT MESSAGE: Bluetooth adapter powered on Oct 02 16:33:55 rivoplus volumio[3574]: info: MPD Permissions set Oct 02 16:33:55 rivoplus sudo[4041]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Oct 02 16:33:55 rivoplus sudo[4041]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:33:55 rivoplus volumio[3574]: info: MPD Permissions set Oct 02 16:33:55 rivoplus volumio[3574]: info: Upmpdcli Daemon Started Oct 02 16:33:55 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setDeviceVolumeOverride Oct 02 16:33:55 rivoplus volumio[3574]: info: Setting Device Volume Override Oct 02 16:33:55 rivoplus systemd[1]: Started volumiobt.service - Volumio Bluetooth Module. Oct 02 16:33:55 rivoplus sudo[4041]: pam_unix(sudo:session): session closed for user root Oct 02 16:33:55 rivoplus volumio[3574]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 02 16:33:55 rivoplus volumio[3574]: info: Updating Volume Controller Parameters: Device: 5 Name: softvolume Mixer: SoftMaster Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Oct 02 16:33:55 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 02 16:33:55 rivoplus volumio[3574]: info: Disabling external Volume Control Oct 02 16:33:55 rivoplus volumiobt[4050]: INFO [BTSTART] Ensuring Bluetooth directory exists... Oct 02 16:33:55 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:33:55 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:33:55 rivoplus volumio[3574]: info: CoreStateMachine::pushState Oct 02 16:33:55 rivoplus volumio[3574]: info: CorePlayQueue::getTrack 0 Oct 02 16:33:55 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 16:33:55 rivoplus volumio[3574]: info: CoreCommandRouter::volumioPushState Oct 02 16:33:55 rivoplus volumio[3574]: info: CoreCommandRouter::volumioGetState Oct 02 16:33:55 rivoplus volumio[3574]: info: CorePlayQueue::getTrack 0 Oct 02 16:33:55 rivoplus volumio[3574]: info: MRS: Pushing multiroomSync output update for this device Oct 02 16:33:55 rivoplus volumio[3574]: info: MRS: Pushing multiroomSync output Oct 02 16:33:55 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 02 16:33:55 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 02 16:33:55 rivoplus volumio[3574]: info: Discovery: Getting this device information Oct 02 16:33:55 rivoplus volumio[3574]: info: CoreCommandRouter::volumioGetState Oct 02 16:33:55 rivoplus volumio[3574]: info: CorePlayQueue::getTrack 0 Oct 02 16:33:55 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 02 16:33:55 rivoplus sudo[4052]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth Oct 02 16:33:55 rivoplus sudo[4052]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:33:55 rivoplus sudo[4052]: pam_unix(sudo:session): session closed for user root Oct 02 16:33:55 rivoplus sudo[4059]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth Oct 02 16:33:55 rivoplus sudo[4059]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:33:55 rivoplus volumio[3574]: 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: 2 Oct 02 16:33:55 rivoplus sudo[4059]: pam_unix(sudo:session): session closed for user root Oct 02 16:33:55 rivoplus volumiobt[4061]: INFO [BTSTART] Powering on Bluetooth if needed... Oct 02 16:33:55 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: system , setAdditionalSVInfo Oct 02 16:33:55 rivoplus volumio[3574]: info: Setting Additional System Software info: Hardware Revision: 1.0 Oct 02 16:33:55 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: system , setHwFwVersion Oct 02 16:33:55 rivoplus volumio[3574]: info: Setting HW Firmware info: undefined Oct 02 16:33:55 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: system , setHwVersion Oct 02 16:33:55 rivoplus volumio[3574]: info: Setting HW Version info: 1.0 Oct 02 16:33:55 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: system , setAdditionalSVInfo Oct 02 16:33:55 rivoplus volumio[3574]: info: Setting Additional System Software info: Hardware Revision: 1.0, Firmware Version: 0.3.0 Oct 02 16:33:55 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: system , setHwFwVersion Oct 02 16:33:55 rivoplus volumio[3574]: info: Setting HW Firmware info: 0.3.0 Oct 02 16:33:55 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: system , setHwVersion Oct 02 16:33:55 rivoplus volumio[3574]: info: Setting HW Version info: 1.0 Oct 02 16:33:55 rivoplus volumio[3574]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Oct 02 16:33:55 rivoplus volumio[3574]: info: MCU Signalled Headphone Mode Disabled Oct 02 16:33:55 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Oct 02 16:33:55 rivoplus volumio[3574]: info: Reporting Headphone State: false Oct 02 16:33:55 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:33:55 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:33:55 rivoplus volumio[3574]: info: Updating RAAT Signal Path Oct 02 16:33:55 rivoplus volumio[3574]: error: Cannot write to RAAT Client: TypeError: Cannot read properties of undefined (reading 'write') Oct 02 16:33:55 rivoplus volumio[3574]: info: MCU Signalled Sleep Mode Disabled Oct 02 16:33:55 rivoplus volumio[3574]: info: Enabling Advanced system settings configuration Oct 02 16:33:55 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: system , addAdditionalUISections Oct 02 16:33:55 rivoplus volumio[3574]: info: Additional UI Settings Added for plugin music_service/inputs Oct 02 16:33:55 rivoplus volumio[3574]: info: MCU Signalled Auto Boot Mode On Power Disabled Oct 02 16:33:55 rivoplus bluetoothd[3385]: Path / reserved for Adv Monitor app :1.20 Oct 02 16:33:55 rivoplus sudo[4067]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms force on Oct 02 16:33:55 rivoplus sudo[4067]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:33:55 rivoplus volumio[3574]: 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: 2 Oct 02 16:33:55 rivoplus volumio[3574]: info: Received Get System Info Oct 02 16:33:55 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 02 16:33:55 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 02 16:33:55 rivoplus volumio[3574]: info: Discovery: Getting this device information Oct 02 16:33:55 rivoplus volumio[3574]: info: CoreCommandRouter::volumioGetState Oct 02 16:33:55 rivoplus bluetoothd[3385]: Adv Monitor app :1.20 disconnected from D-Bus Oct 02 16:33:55 rivoplus volumio[3574]: info: CorePlayQueue::getTrack 0 Oct 02 16:33:55 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 02 16:33:55 rivoplus volumio5-onboarding[3906]: time=2026-10-02T16:33:55.667+10:00 level=INFO msg="system info for 4ce7a2325c4b09568e080862cf14e9e7" deviceName=Rivoplus deviceVariant=rivoplus deviceModel="Volumio Rivo+" softwareVersion=4.208 Oct 02 16:33:55 rivoplus volumiobt[4069]: INFO [BTSTART] Making Bluetooth discoverable and pairable... Oct 02 16:33:55 rivoplus kernel: fb: osd[0] enable: 1 (Xorg) Oct 02 16:33:55 rivoplus sudo[4067]: pam_unix(sudo:session): session closed for user root Oct 02 16:33:55 rivoplus volumio5-onboarding[3906]: time=2026-10-02T16:33:55.696+10:00 level=INFO msg="bootstrapping state" hasInternet=true Oct 02 16:33:55 rivoplus volumiobt[4070]: [176B blob data] Oct 02 16:33:55 rivoplus volumiobt[4070]: [157B blob data] Oct 02 16:33:55 rivoplus volumiobt[4070]: [157B blob data] Oct 02 16:33:55 rivoplus volumiobt[4070]: [157B blob data] Oct 02 16:33:55 rivoplus volumiobt[4070]: [113B blob data] Oct 02 16:33:55 rivoplus volumiobt[4070]: [bluetoothctl]> discoverable on Oct 02 16:33:55 rivoplus volumiobt[4070]: Warning: setting discoverable while discoverable-timeout not set(0) is not recommended Oct 02 16:33:55 rivoplus volumiobt[4070]: [bluetoothctl]> pairable on Oct 02 16:33:55 rivoplus bluetoothd[3385]: Path / reserved for Adv Monitor app :1.21 Oct 02 16:33:55 rivoplus bluetoothd[3385]: Adv Monitor app :1.21 disconnected from D-Bus Oct 02 16:33:55 rivoplus volumiobt[4070]: [bluetoothctl]> Oct 02 16:33:55 rivoplus volumiobt[4071]: INFO [BTSTART] Registering Bluetooth agent... Oct 02 16:33:55 rivoplus volumiobt[4072]: [NEW] Media /org/bluez/hci0 Oct 02 16:33:55 rivoplus volumiobt[4072]: SupportedUUIDs: 0000110a-0000-1000-8000-00805f9b34fb Oct 02 16:33:55 rivoplus volumiobt[4072]: SupportedUUIDs: 0000110b-0000-1000-8000-00805f9b34fb Oct 02 16:33:55 rivoplus volumiobt[4072]: SupportedUUIDs: 0000FDF0-0000-1000-8000-00805f9b34fb Oct 02 16:33:55 rivoplus bluetoothd[3385]: Adv Monitor app :1.22 disconnected from D-Bus Oct 02 16:33:55 rivoplus volumiobt[4073]: No agent is registered Oct 02 16:33:55 rivoplus volumiobt[4073]: [NEW] Media /org/bluez/hci0 Oct 02 16:33:55 rivoplus volumiobt[4073]: SupportedUUIDs: 0000110a-0000-1000-8000-00805f9b34fb Oct 02 16:33:55 rivoplus volumiobt[4073]: SupportedUUIDs: 0000110b-0000-1000-8000-00805f9b34fb Oct 02 16:33:55 rivoplus volumiobt[4073]: SupportedUUIDs: 0000FDF0-0000-1000-8000-00805f9b34fb Oct 02 16:33:55 rivoplus bluetoothd[3385]: Adv Monitor app :1.23 disconnected from D-Bus Oct 02 16:33:55 rivoplus volumiobt[4074]: INFO [BTSTART] Agent registered successfully. Oct 02 16:33:55 rivoplus volumiobt[4075]: INFO [BTSTART] Starting A2DP agent (a2dp-agent)... Oct 02 16:33:55 rivoplus volumio[3574]: error: MPD error: The expression evaluated to a falsy value: Oct 02 16:33:55 rivoplus volumio[3574]: assert.ok(self.idling) Oct 02 16:33:55 rivoplus volumio[3574]: error: The expression evaluated to a falsy value: Oct 02 16:33:55 rivoplus volumio[3574]: assert.ok(self.idling) Oct 02 16:33:55 rivoplus volumio[3574]: info: CoreStateMachine::pushState Oct 02 16:33:55 rivoplus volumio[3574]: info: CorePlayQueue::getTrack 0 Oct 02 16:33:55 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 16:33:55 rivoplus volumio[3574]: info: CoreCommandRouter::volumioPushState Oct 02 16:33:55 rivoplus volumio[3574]: info: CoreCommandRouter::volumioGetState Oct 02 16:33:55 rivoplus volumio[3574]: info: CorePlayQueue::getTrack 0 Oct 02 16:33:55 rivoplus volumio[3574]: info: MRS: Pushing multiroomSync output update for this device Oct 02 16:33:55 rivoplus volumio[3574]: info: MRS: Pushing multiroomSync output Oct 02 16:33:55 rivoplus volumio[3574]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'SoftMaster',0 Oct 02 16:33:55 rivoplus volumio[3574]: ------------------------------------ BT MESSAGE: volumiobt.service started successfully Oct 02 16:33:55 rivoplus volumio[3574]: ------------------------------------ BT MESSAGE: [FUNC] dbusStart Oct 02 16:33:55 rivoplus volumio[3574]: info: MPD running with PID3926 Oct 02 16:33:55 rivoplus volumio[3574]: ,establishing connection Oct 02 16:33:55 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:33:55 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:33:55 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:33:55 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:33:55 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:33:55 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:33:55 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:33:55 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:33:56 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 16:33:56 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 02 16:33:56 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:33:56 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:33:56 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:33:56 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:33:56 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:33:56 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:33:56 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:33:56 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:33:56 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:33:56 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 16:33:56 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 02 16:33:56 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:33:56 rivoplus volumio[3574]: info: Received Get System Info Oct 02 16:33:56 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 02 16:33:56 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 02 16:33:56 rivoplus volumio[3574]: info: Discovery: Getting this device information Oct 02 16:33:56 rivoplus volumio[3574]: info: CoreCommandRouter::volumioGetState Oct 02 16:33:56 rivoplus volumio[3574]: info: CorePlayQueue::getTrack 0 Oct 02 16:33:56 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 02 16:33:56 rivoplus volumio[3574]: error: updateQueue error: null Oct 02 16:33:56 rivoplus kernel: cectx ff80023c.aocec: bad iniator with self:CEC RX len 5: 0f 87 08 00 46 Oct 02 16:33:56 rivoplus volumio[3574]: info: Volumio called home Oct 02 16:33:56 rivoplus sudo[4092]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms 0 0 0 Oct 02 16:33:56 rivoplus sudo[4092]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:33:56 rivoplus sudo[4092]: pam_unix(sudo:session): session closed for user root Oct 02 16:33:56 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 02 16:33:56 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 02 16:33:56 rivoplus volumio[3574]: info: Discovery: Getting this device information Oct 02 16:33:56 rivoplus volumio[3574]: info: CoreCommandRouter::volumioGetState Oct 02 16:33:56 rivoplus volumio[3574]: info: CorePlayQueue::getTrack 0 Oct 02 16:33:56 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 02 16:33:56 rivoplus volumio[3574]: info: MP1 GPIO: Signalled system ready via GPIO Oct 02 16:33:56 rivoplus volumio-remote-updater[2956]: Test mode disabled Oct 02 16:33:56 rivoplus volumio-remote-updater[2956]: Alpha mode disabled Oct 02 16:33:56 rivoplus volumio-remote-updater[2956]: Alpha legacy test mode disabled Oct 02 16:33:56 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 02 16:33:56 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 02 16:33:56 rivoplus volumio[3574]: info: Discovery: Getting this device information Oct 02 16:33:56 rivoplus volumio[3574]: info: CoreCommandRouter::volumioGetState Oct 02 16:33:56 rivoplus volumio[3574]: info: CorePlayQueue::getTrack 0 Oct 02 16:33:56 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 02 16:33:56 rivoplus volumio[3574]: error: Serial API: Failed to decode command: MAXVOL, message: 100 Oct 02 16:33:56 rivoplus volumio[3574]: error: updateQueue error: null Oct 02 16:33:56 rivoplus volumio[3574]: info: Executing endpoint qc_getconfig Oct 02 16:33:56 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 02 16:33:56 rivoplus qobuz-connect[3999]: 20261002 16:33:56.625 [3999.3999] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 02 16:33:56 rivoplus volumio[3574]: info: Starting Shairport Sync Oct 02 16:33:56 rivoplus volumio[3574]: info: Starting Shairport Sync Oct 02 16:33:56 rivoplus volumio[3574]: info: Starting Shairport Sync Oct 02 16:33:56 rivoplus sudo[4102]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 02 16:33:56 rivoplus sudo[4102]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:33:56 rivoplus volumio[3574]: info: Adding Inputs via Serial API Oct 02 16:33:56 rivoplus volumio[3574]: info: Adding Advanced Audio Settings via Serial API Oct 02 16:33:56 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , addAdditionalUISections Oct 02 16:33:56 rivoplus volumio[3574]: info: Additional UI Settings Added for plugin music_service/inputs Oct 02 16:33:56 rivoplus sudo[4104]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 02 16:33:56 rivoplus sudo[4104]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:33:56 rivoplus volumio[3574]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Oct 02 16:33:56 rivoplus volumio[3574]: info: QobuzConnect: QOBUZ Connect daemon connected Oct 02 16:33:56 rivoplus sudo[4107]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 02 16:33:56 rivoplus sudo[4107]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:33:56 rivoplus volumio[3574]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Oct 02 16:33:56 rivoplus volumio[3574]: info: MRS: Found cast device: BRAVIA-4K-AE2-30c8faee25f89be71e098ec9d1a00baa Oct 02 16:33:56 rivoplus volumio[3574]: info: Adding audio output: Oct 02 16:33:56 rivoplus systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 02 16:33:56 rivoplus systemd[1]: shairport-sync.service: Deactivated successfully. Oct 02 16:33:56 rivoplus systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 02 16:33:56 rivoplus qobuz-connect[3999]: 20261002 16:33:56.797 [3999.3999] INFO VolumeManager: [0xacc02018]: Setting new playback volume: 75 Oct 02 16:33:56 rivoplus qobuz-connect[3999]: 20261002 16:33:56.797 [3999.3999] INFO VolumeManager: [0xacc02018]: Setting new mute state: 0 Oct 02 16:33:56 rivoplus qobuz-connect[3999]: 20261002 16:33:56.797 [3999.3999] INFO AudioStreamManager: [0xacc01d70]: Setting new audio download buffer size: 1048576 Oct 02 16:33:56 rivoplus qobuz-connect[3999]: 20261002 16:33:56.797 [3999.3999] INFO QobuzConnect: [0xacc028e0]: Client initialized! Oct 02 16:33:56 rivoplus qobuz-connect[3999]: 20261002 16:33:56.797 [3999.3999] INFO SampleApp: Starting Avahi advertising, name: Rivoplus, service name: _qobuz-connect._tcp Oct 02 16:33:56 rivoplus volumio[3574]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Oct 02 16:33:56 rivoplus volumiobt[4076]: 2026-10-02 16:33:56 a2dp-agent [INFO] Connecting to system D-Bus Oct 02 16:33:56 rivoplus volumiobt[4076]: 2026-10-02 16:33:56 a2dp-agent [INFO] Connected to system D-Bus Oct 02 16:33:56 rivoplus qobuz-connect[3999]: 20261002 16:33:56.834 [3999.3999] INFO LocalConfigManager: [0xacc01a98]: Starting Local Configuration server Oct 02 16:33:56 rivoplus qobuz-connect[3999]: 20261002 16:33:56.834 [3999.3999] INFO SampleApp: Starting Local configuration server Oct 02 16:33:56 rivoplus qobuz-connect[3999]: 20261002 16:33:56.835 [3999.3999] INFO SampleApp: Connected to UNIX socket client 0xacbec908 Oct 02 16:33:56 rivoplus qobuz-connect[3999]: 20261002 16:33:56.835 [3999.3999] INFO SampleApp: Playback volume changed: 75 Oct 02 16:33:56 rivoplus volumiobt[4076]: 2026-10-02 16:33:56 bluezutils [INFO] Found adapter at: /org/bluez/hci0 Oct 02 16:33:56 rivoplus volumiobt[4076]: 2026-10-02 16:33:56 a2dp-agent [INFO] Found Bluetooth adapter: /org/bluez/hci0 Oct 02 16:33:56 rivoplus systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 02 16:33:56 rivoplus sudo[4104]: pam_unix(sudo:session): session closed for user root Oct 02 16:33:56 rivoplus volumio[3574]: info: Asound.conf file unchanged, so no further update is needed Oct 02 16:33:56 rivoplus volumio[3574]: info: Output device has changed, restarting MPD Oct 02 16:33:56 rivoplus sudo[4102]: pam_unix(sudo:session): session closed for user root Oct 02 16:33:56 rivoplus volumiobt[4076]: 2026-10-02 16:33:56 a2dp-agent [INFO] Set DiscoverableTimeout to infinite Oct 02 16:33:56 rivoplus volumiobt[4076]: 2026-10-02 16:33:56 a2dp-agent [INFO] Enabled Discoverable mode Oct 02 16:33:56 rivoplus systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 02 16:33:56 rivoplus systemd[1]: shairport-sync.service: Deactivated successfully. Oct 02 16:33:56 rivoplus systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 02 16:33:56 rivoplus volumio[3574]: info: Output device has changed, restarting Shairport Sync Oct 02 16:33:56 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:33:56 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 16:33:56 rivoplus volumiobt[4076]: 2026-10-02 16:33:56 a2dp-agent [INFO] Agent registered at /local/a2dpagent Oct 02 16:33:56 rivoplus systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 02 16:33:56 rivoplus volumiobt[4076]: 2026-10-02 16:33:56 a2dp-agent [INFO] Agent set as default Oct 02 16:33:56 rivoplus volumiobt[4076]: 2026-10-02 16:33:56 a2dp-agent [INFO] A2DP agent running, waiting for connections... Oct 02 16:33:56 rivoplus sudo[4107]: pam_unix(sudo:session): session closed for user root Oct 02 16:33:56 rivoplus sudo[4116]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 02 16:33:56 rivoplus sudo[4116]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:33:56 rivoplus sudo[4118]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 02 16:33:56 rivoplus sudo[4118]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:33:56 rivoplus sudo[4116]: pam_unix(sudo:session): session closed for user root Oct 02 16:33:56 rivoplus volumio[3574]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 02 16:33:56 rivoplus volumio[3574]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 02 16:33:56 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:33:56 rivoplus volumio[3574]: info: QobuzConnect: setDeactiveState invoked Oct 02 16:33:56 rivoplus volumio[3574]: info: CoreCommandRouter::volumioGetState Oct 02 16:33:56 rivoplus volumio[3574]: info: CorePlayQueue::getTrack 0 Oct 02 16:33:56 rivoplus volumio[3574]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Oct 02 16:33:56 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:33:56 rivoplus volumio[3574]: info: Preparing to generate the ALSA configuration file Oct 02 16:33:56 rivoplus systemd[1]: Stopping mpd.service - Music Player Daemon... Oct 02 16:33:57 rivoplus volumio[3574]: info: CoreCommandRouter::volumioGetState Oct 02 16:33:57 rivoplus volumio[3574]: info: CorePlayQueue::getTrack 0 Oct 02 16:33:57 rivoplus volumio[3574]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Oct 02 16:33:57 rivoplus volumio[3574]: info: Shairport-Sync Started Oct 02 16:33:57 rivoplus sudo[4145]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 02 16:33:57 rivoplus sudo[4145]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:33:57 rivoplus volumio[3574]: Error adding Membership: Error: addMembership EINVAL Oct 02 16:33:57 rivoplus volumio[3574]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Oct 02 16:33:57 rivoplus volumio[3574]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 02 16:33:57 rivoplus volumio[3574]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Oct 02 16:33:57 rivoplus volumio[3574]: info: Reading ALSA contributions from plugins. Oct 02 16:33:57 rivoplus volumio[3574]: info: Access Token successfully retrieved Oct 02 16:33:57 rivoplus sudo[4145]: pam_unix(sudo:session): session closed for user root Oct 02 16:33:57 rivoplus volumio[3574]: verbose: New Socket.io Connection to 192.168.0.63:3000 from 192.168.0.122 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Oct 02 16:33:57 rivoplus sudo[4147]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 02 16:33:57 rivoplus sudo[4147]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:33:57 rivoplus volumio[3574]: info: MPD Permissions set Oct 02 16:33:57 rivoplus volumio[3574]: info: Shairport-Sync Started Oct 02 16:33:57 rivoplus volumio[3574]: info: Shairport-Sync Started Oct 02 16:33:57 rivoplus volumio[3574]: info: CoreStateMachine::pushState Oct 02 16:33:57 rivoplus volumio[3574]: info: CorePlayQueue::getTrack 0 Oct 02 16:33:57 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 16:33:57 rivoplus volumio[3574]: info: CoreCommandRouter::volumioPushState Oct 02 16:33:57 rivoplus volumio[3574]: info: CoreCommandRouter::volumioGetState Oct 02 16:33:57 rivoplus volumio[3574]: info: CorePlayQueue::getTrack 0 Oct 02 16:33:57 rivoplus volumio[3574]: info: MRS: Pushing multiroomSync output update for this device Oct 02 16:33:57 rivoplus qobuz-connect[3999]: 20261002 16:33:57.237 [3999.3999] INFO SampleApp: Stopping Local configuration server Oct 02 16:33:57 rivoplus volumio[3574]: info: MRS: Pushing multiroomSync output Oct 02 16:33:57 rivoplus systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Oct 02 16:33:57 rivoplus volumio[3574]: info: CoreCommandRouter::servicePushState Oct 02 16:33:57 rivoplus volumio[3574]: info: CoreStateMachine::pushState Oct 02 16:33:57 rivoplus volumio[3574]: info: CorePlayQueue::getTrack 0 Oct 02 16:33:57 rivoplus volumio[3574]: info: CoreCommandRouter::volumioPushState Oct 02 16:33:57 rivoplus volumio[3574]: info: CoreCommandRouter::volumioGetState Oct 02 16:33:57 rivoplus volumio[3574]: info: CorePlayQueue::getTrack 0 Oct 02 16:33:57 rivoplus volumio[3574]: info: MRS: Pushing multiroomSync output update for this device Oct 02 16:33:57 rivoplus volumio[3574]: info: MRS: Pushing multiroomSync output Oct 02 16:33:57 rivoplus volumio[3574]: info: CorePlayQueue::getTrack 0 Oct 02 16:33:57 rivoplus volumio[3574]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received inputs Oct 02 16:33:57 rivoplus volumio[3574]: info: CoreCommandRouter::volumiosetSourceActiveno-source Oct 02 16:33:57 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 02 16:33:57 rivoplus systemd[1]: mpd.service: Deactivated successfully. Oct 02 16:33:57 rivoplus volumio[3574]: Cannot find translation for source TIDAL Oct 02 16:33:57 rivoplus systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 02 16:33:57 rivoplus systemd[1]: mpd.socket: Deactivated successfully. Oct 02 16:33:57 rivoplus systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 02 16:33:57 rivoplus systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 02 16:33:57 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 02 16:33:57 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 02 16:33:57 rivoplus systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 02 16:33:57 rivoplus volumio[3574]: info: TidalConnect service stoped! Oct 02 16:33:57 rivoplus systemd[1]: Starting mpd.service - Music Player Daemon... Oct 02 16:33:57 rivoplus volumio[3574]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Oct 02 16:33:57 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Oct 02 16:33:57 rivoplus volumio[3574]: verbose: New Socket.io Connection to 192.168.0.63:3000 from 192.168.0.122 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Oct 02 16:33:57 rivoplus volumio[3574]: info: Turning Off Onboard LEDs Oct 02 16:33:57 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:33:57 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:33:57 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:33:57 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:33:57 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:33:57 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:33:57 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:33:57 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:33:57 rivoplus sudo[4161]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/sys_led/trigger Oct 02 16:33:57 rivoplus sudo[4161]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:33:57 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 16:33:57 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 02 16:33:57 rivoplus sudo[4161]: pam_unix(sudo:session): session closed for user root Oct 02 16:33:57 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:33:57 rivoplus sudo[4168]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/red_red/trigger Oct 02 16:33:57 rivoplus sudo[4168]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:33:57 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 02 16:33:57 rivoplus sudo[4168]: pam_unix(sudo:session): session closed for user root Oct 02 16:33:57 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:33:57 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:33:57 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:33:57 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:33:57 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:33:57 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:33:57 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:33:57 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:33:57 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 16:33:57 rivoplus volumio[3574]: info: Not Reporting Auto name since its the default one Oct 02 16:33:57 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 02 16:33:57 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 02 16:33:57 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 02 16:33:57 rivoplus volumio[3574]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 02 16:33:57 rivoplus volumio[3574]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 02 16:33:57 rivoplus volumio[3574]: info: Onboard LEDs initialized Oct 02 16:33:57 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 02 16:33:57 rivoplus sudo[4182]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 02 16:33:57 rivoplus sudo[4182]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:33:57 rivoplus volumio[3574]: info: Starting Shairport Sync Oct 02 16:33:57 rivoplus volumio[3574]: info: Received Get System Info Oct 02 16:33:57 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 02 16:33:57 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 02 16:33:57 rivoplus volumio[3574]: info: Discovery: Getting this device information Oct 02 16:33:57 rivoplus volumio[3574]: info: CoreCommandRouter::volumioGetState Oct 02 16:33:57 rivoplus volumio[3574]: info: CorePlayQueue::getTrack 0 Oct 02 16:33:57 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 02 16:33:57 rivoplus systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Oct 02 16:33:57 rivoplus sudo[4182]: pam_unix(sudo:session): session closed for user root Oct 02 16:33:57 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 02 16:33:57 rivoplus sudo[4186]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 02 16:33:57 rivoplus sudo[4186]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:33:57 rivoplus systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 02 16:33:57 rivoplus systemd[1]: shairport-sync.service: Deactivated successfully. Oct 02 16:33:57 rivoplus systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 02 16:33:57 rivoplus systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 02 16:33:57 rivoplus sudo[4186]: pam_unix(sudo:session): session closed for user root Oct 02 16:33:57 rivoplus sudo[4158]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 02 16:33:57 rivoplus volumio5-onboarding[3906]: time=2026-10-02T16:33:57.971+10:00 level=INFO msg="enabling local network discovery" Oct 02 16:33:57 rivoplus volumio5-onboarding[3906]: time=2026-10-02T16:33:57.972+10:00 level=INFO msg="enabling BLE discovery" Oct 02 16:33:58 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 02 16:33:58 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 02 16:33:57 rivoplus sudo[4158]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 02 16:33:58 rivoplus sudo[4158]: pam_unix(sudo:session): session closed for user root Oct 02 16:33:58 rivoplus sudo[4195]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 02 16:33:58 rivoplus sudo[4195]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:33:58 rivoplus bluealsa[3457]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_63_1A_F2_2D_0A_7E, ...) Oct 02 16:33:58 rivoplus volumio[3574]: info: Successfully retrieved User Session From TIDAL Oct 02 16:33:58 rivoplus sudo[4195]: pam_unix(sudo:session): session closed for user root Oct 02 16:33:58 rivoplus volumio[3574]: info: Shairport-Sync Started Oct 02 16:33:58 rivoplus sudo[4207]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 02 16:33:58 rivoplus sudo[4207]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:33:58 rivoplus volumio[3574]: verbose: New Socket.io Connection to 192.168.0.63:3000 from 192.168.0.122 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Oct 02 16:33:58 rivoplus systemd[1]: Started raat-daemon.service - RAAT DAEMON. Oct 02 16:33:58 rivoplus sudo[4207]: pam_unix(sudo:session): session closed for user root Oct 02 16:33:58 rivoplus volumio[3574]: info: Successfully retrieved User Subscription From TIDAL Oct 02 16:33:58 rivoplus volumio[3574]: info: Raat Daemon started successfully Oct 02 16:33:58 rivoplus volumio[3574]: info: Asound.conf file written Oct 02 16:33:58 rivoplus sudo[4239]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Oct 02 16:33:58 rivoplus sudo[4239]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:33:58 rivoplus sudo[4239]: pam_unix(sudo:session): session closed for user root Oct 02 16:33:58 rivoplus kernel: Fine tdm clk setting range (0~2000000), 12287994 Oct 02 16:33:58 rivoplus kernel: Err: can not set rate to 4282967296! Oct 02 16:33:58 rivoplus kernel: Range[3920000 - 500000000] Oct 02 16:33:58 rivoplus kernel: out of value, fixed it Oct 02 16:33:58 rivoplus kernel: id=0 set inskew=0 Oct 02 16:33:58 rivoplus volumio[3574]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 02 16:33:58 rivoplus volumio[3574]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 02 16:33:58 rivoplus volumio[3574]: Found hardware: "AML-AUGESOUND-M" "" "" "" "" Oct 02 16:33:58 rivoplus volumio[3574]: Hardware is initialized using a generic method Oct 02 16:33:58 rivoplus volumio[3574]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:eARC_TX CDS:0' : Operation not permitted Oct 02 16:33:58 rivoplus volumio[3574]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:I2SIn CLK:0' : Operation not permitted Oct 02 16:33:58 rivoplus volumio[3574]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:SPDIFIN audio samplerate:0' : Operation not permitted Oct 02 16:33:58 rivoplus volumio[3574]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:SPDIFIN Audio Type:0' : Operation not permitted Oct 02 16:33:58 rivoplus volumio[3574]: Found hardware: "USB-Audio" "USB Mixer" "USB2fc6:6016" "" "" Oct 02 16:33:58 rivoplus volumio[3574]: Hardware is initialized using a generic method Oct 02 16:33:58 rivoplus volumio[3574]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:Clock Source 5 Validity:0' : Operation not permitted Oct 02 16:33:58 rivoplus volumio[3574]: info: Output device has changed, restarting MPD Oct 02 16:33:58 rivoplus volumio[3574]: info: Output device has changed, restarting Shairport Sync Oct 02 16:33:58 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:33:58 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 16:33:58 rivoplus sudo[4244]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 02 16:33:58 rivoplus sudo[4244]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:33:58 rivoplus sudo[4246]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 02 16:33:58 rivoplus sudo[4246]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:33:58 rivoplus sudo[4244]: pam_unix(sudo:session): session closed for user root Oct 02 16:33:58 rivoplus volumio[3574]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 02 16:33:58 rivoplus volumio[3574]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 02 16:33:58 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:33:58 rivoplus volumio[3574]: info: QobuzConnect: setDeactiveState invoked Oct 02 16:33:58 rivoplus volumio[3574]: info: CoreCommandRouter::volumioGetState Oct 02 16:33:58 rivoplus volumio[3574]: info: CorePlayQueue::getTrack 0 Oct 02 16:33:58 rivoplus qobuz-connect[3999]: 20261002 16:33:58.845 [3999.3999] INFO SampleApp: shat down connection on UNIX socket Oct 02 16:33:58 rivoplus volumio[3574]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Oct 02 16:33:58 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:33:58 rivoplus systemd[1]: qobuz-connect.service: Deactivated successfully. Oct 02 16:33:58 rivoplus systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Oct 02 16:33:58 rivoplus volumio[3574]: info: Preparing to generate the ALSA configuration file Oct 02 16:33:58 rivoplus systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Oct 02 16:33:58 rivoplus sudo[4255]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 02 16:33:58 rivoplus sudo[4255]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:33:58 rivoplus sudo[4147]: pam_unix(sudo:session): session closed for user root Oct 02 16:33:59 rivoplus sudo[4258]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 02 16:33:59 rivoplus sudo[4258]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:33:59 rivoplus volumio[3574]: info: CoreCommandRouter::volumioGetState Oct 02 16:33:59 rivoplus volumio[3574]: info: CorePlayQueue::getTrack 0 Oct 02 16:33:59 rivoplus volumio[3574]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Oct 02 16:33:59 rivoplus volumio[3574]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 02 16:33:59 rivoplus volumio[3574]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Oct 02 16:33:59 rivoplus volumio[3574]: info: Reading ALSA contributions from plugins. Oct 02 16:33:59 rivoplus volumio5-onboarding[3906]: time=2026-10-02T16:33:59.069+10:00 level=INFO msg="service successfully established" component=discovery/localnet Oct 02 16:33:59 rivoplus sudo[4265]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 02 16:33:59 rivoplus sudo[4265]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:33:59 rivoplus volumio[3574]: info: Successfully retrieved User Session From TIDAL Oct 02 16:33:59 rivoplus systemd[1]: mpd.service: Deactivated successfully. Oct 02 16:33:59 rivoplus systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 02 16:33:59 rivoplus volumio[3574]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read properties of undefined (reading 'split') Oct 02 16:33:59 rivoplus volumio[3574]: info: MPD Permissions set Oct 02 16:33:59 rivoplus volumio[3574]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Oct 02 16:33:59 rivoplus volumio[3574]: info: QobuzConnect: setDeactiveState invoked Oct 02 16:33:59 rivoplus volumio[3574]: info: CoreCommandRouter::volumioGetState Oct 02 16:33:59 rivoplus volumio[3574]: info: CorePlayQueue::getTrack 0 Oct 02 16:33:59 rivoplus volumio[3574]: info: MCU Signalled Playback Inactive Oct 02 16:33:59 rivoplus sudo[4265]: pam_unix(sudo:session): session closed for user root Oct 02 16:33:59 rivoplus systemd[1]: mpd.socket: Deactivated successfully. Oct 02 16:33:59 rivoplus volumio[3574]: error: Upnp client error: Error: connect ECONNRESET 127.0.0.1:6600 Oct 02 16:33:59 rivoplus volumio[3574]: info: MRS: Getting audio outputs on start Oct 02 16:33:59 rivoplus volumio[3574]: info: MRS: Requesting all other devices output Oct 02 16:33:59 rivoplus systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 02 16:33:59 rivoplus systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 02 16:33:59 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 02 16:33:59 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 02 16:33:59 rivoplus volumio[3574]: info: Discovery: Getting this device information Oct 02 16:33:59 rivoplus volumio[3574]: info: CoreCommandRouter::volumioGetState Oct 02 16:33:59 rivoplus volumio[3574]: info: CorePlayQueue::getTrack 0 Oct 02 16:33:59 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 02 16:33:59 rivoplus volumio[3574]: info: CoreCommandRouter::volumioGetState Oct 02 16:33:59 rivoplus volumio[3574]: info: CorePlayQueue::getTrack 0 Oct 02 16:33:59 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:33:59 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:33:59 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:33:59 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:33:59 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:33:59 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:33:59 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:33:59 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:33:59 rivoplus systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 02 16:33:59 rivoplus sudo[4267]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 02 16:33:59 rivoplus sudo[4267]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:33:59 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 16:33:59 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 02 16:33:59 rivoplus systemd[1]: Starting mpd.service - Music Player Daemon... Oct 02 16:33:59 rivoplus systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Oct 02 16:33:59 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:33:59 rivoplus systemd[1]: vtcs.service: Deactivated successfully. Oct 02 16:33:59 rivoplus systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Oct 02 16:33:59 rivoplus volumio[3574]: info: Executing endpoint qc_getconfig Oct 02 16:33:59 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 02 16:33:59 rivoplus sudo[4255]: pam_unix(sudo:session): session closed for user root Oct 02 16:33:59 rivoplus qobuz-connect[4259]: 20261002 16:33:59.385 [4259.4259] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 02 16:33:59 rivoplus sudo[4258]: pam_unix(sudo:session): session closed for user root Oct 02 16:33:59 rivoplus qobuz-connect[4259]: 20261002 16:33:59.400 [4259.4259] INFO VolumeManager: [0xabc7f018]: Setting new playback volume: 75 Oct 02 16:33:59 rivoplus qobuz-connect[4259]: 20261002 16:33:59.400 [4259.4259] INFO VolumeManager: [0xabc7f018]: Setting new mute state: 0 Oct 02 16:33:59 rivoplus qobuz-connect[4259]: 20261002 16:33:59.400 [4259.4259] INFO AudioStreamManager: [0xabc7ed70]: Setting new audio download buffer size: 1048576 Oct 02 16:33:59 rivoplus qobuz-connect[4259]: 20261002 16:33:59.401 [4259.4259] INFO QobuzConnect: [0xabc7f8e0]: Client initialized! Oct 02 16:33:59 rivoplus qobuz-connect[4259]: 20261002 16:33:59.401 [4259.4259] INFO SampleApp: Starting Avahi advertising, name: Rivoplus, service name: _qobuz-connect._tcp Oct 02 16:33:59 rivoplus qobuz-connect[4259]: 20261002 16:33:59.418 [4259.4259] INFO LocalConfigManager: [0xabc7ea98]: Starting Local Configuration server Oct 02 16:33:59 rivoplus qobuz-connect[4259]: 20261002 16:33:59.418 [4259.4259] INFO SampleApp: Starting Local configuration server Oct 02 16:33:59 rivoplus qobuz-connect[4259]: 20261002 16:33:59.418 [4259.4259] INFO SampleApp: Connected to UNIX socket client 0xabc69908 Oct 02 16:33:59 rivoplus qobuz-connect[4259]: 20261002 16:33:59.436 [4259.4259] INFO SampleApp: Stopping Local configuration server Oct 02 16:33:59 rivoplus systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Oct 02 16:33:59 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:33:59 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:33:59 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:33:59 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:33:59 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:33:59 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:33:59 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:33:59 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:33:59 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 16:33:59 rivoplus volumio[3574]: info: Not Reporting Auto name since its the default one Oct 02 16:33:59 rivoplus volumio5-onboarding[3906]: time=2026-10-02T16:33:59.457+10:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.0.122:61124 Oct 02 16:33:59 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 02 16:33:59 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 02 16:33:59 rivoplus volumio[3574]: info: Discovery: Getting this device information Oct 02 16:33:59 rivoplus volumio[3574]: info: CoreCommandRouter::volumioGetState Oct 02 16:33:59 rivoplus volumio[3574]: info: CorePlayQueue::getTrack 0 Oct 02 16:33:59 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 02 16:33:59 rivoplus volumio[3574]: info: Successfully retrieved User Subscription From TIDAL Oct 02 16:33:59 rivoplus volumio[3574]: info: Adding TIDAL REST API Endpoints Oct 02 16:33:59 rivoplus volumio[3574]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Oct 02 16:33:59 rivoplus volumio[3574]: info: QobuzConnect: QOBUZ Connect daemon connected Oct 02 16:33:59 rivoplus volumio[3574]: info: Executing endpoint tc_getconfig Oct 02 16:33:59 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Oct 02 16:33:59 rivoplus volumio[3574]: info: Starting Shairport Sync Oct 02 16:33:59 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 02 16:33:59 rivoplus volumio[3574]: info: Asound.conf file unchanged, so no further update is needed Oct 02 16:33:59 rivoplus volumio[3574]: info: Output device has changed, restarting MPD Oct 02 16:33:59 rivoplus sudo[4298]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 02 16:33:59 rivoplus sudo[4298]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:33:59 rivoplus volumio[3574]: info: Output device has changed, restarting Shairport Sync Oct 02 16:33:59 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:33:59 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 16:33:59 rivoplus sudo[4301]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 02 16:33:59 rivoplus sudo[4301]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:33:59 rivoplus sudo[4301]: pam_unix(sudo:session): session closed for user root Oct 02 16:33:59 rivoplus systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 02 16:33:59 rivoplus sudo[4303]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 02 16:33:59 rivoplus sudo[4303]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:33:59 rivoplus systemd[1]: shairport-sync.service: Deactivated successfully. Oct 02 16:33:59 rivoplus systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 02 16:33:59 rivoplus volumio[3574]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 02 16:33:59 rivoplus volumio[3574]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 02 16:33:59 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:33:59 rivoplus systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 02 16:33:59 rivoplus sudo[4298]: pam_unix(sudo:session): session closed for user root Oct 02 16:33:59 rivoplus volumio[3574]: info: QobuzConnect: setDeactiveState invoked Oct 02 16:33:59 rivoplus volumio[3574]: info: CoreCommandRouter::volumioGetState Oct 02 16:33:59 rivoplus volumio[3574]: info: CorePlayQueue::getTrack 0 Oct 02 16:33:59 rivoplus volumio[3574]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Oct 02 16:33:59 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:33:59 rivoplus volumio[3574]: info: Preparing to generate the ALSA configuration file Oct 02 16:33:59 rivoplus sudo[4322]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 02 16:33:59 rivoplus sudo[4322]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:33:59 rivoplus systemd[1]: mpd.service: Deactivated successfully. Oct 02 16:33:59 rivoplus systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 02 16:33:59 rivoplus sudo[4328]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 02 16:33:59 rivoplus sudo[4328]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:33:59 rivoplus systemd[1]: mpd.socket: Deactivated successfully. Oct 02 16:33:59 rivoplus systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 02 16:33:59 rivoplus systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 02 16:33:59 rivoplus systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 02 16:33:59 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 02 16:33:59 rivoplus sudo[4355]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 02 16:33:59 rivoplus sudo[4355]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:33:59 rivoplus systemd[1]: Starting mpd.service - Music Player Daemon... Oct 02 16:33:59 rivoplus volumio[3574]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Oct 02 16:33:59 rivoplus volumio[3574]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 02 16:33:59 rivoplus volumio[3574]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Oct 02 16:33:59 rivoplus volumio[3574]: info: Reading ALSA contributions from plugins. Oct 02 16:33:59 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Oct 02 16:33:59 rivoplus volumio[3574]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read properties of undefined (reading 'split') Oct 02 16:33:59 rivoplus volumio[3574]: info: MPD Permissions set Oct 02 16:33:59 rivoplus volumio[3574]: info: Shairport-Sync Started Oct 02 16:33:59 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Oct 02 16:33:59 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:33:59 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:33:59 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:33:59 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:33:59 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:33:59 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:33:59 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:33:59 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:33:59 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 16:33:59 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 02 16:33:59 rivoplus sudo[4360]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 02 16:33:59 rivoplus sudo[4360]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:34:00 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:34:00 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Oct 02 16:34:00 rivoplus sudo[4322]: pam_unix(sudo:session): session closed for user root Oct 02 16:34:00 rivoplus sudo[4379]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Oct 02 16:34:00 rivoplus sudo[4379]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:34:00 rivoplus sudo[4355]: pam_unix(sudo:session): session closed for user root Oct 02 16:34:00 rivoplus sudo[4374]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Oct 02 16:34:00 rivoplus sudo[4374]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:34:00 rivoplus sudo[4386]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Oct 02 16:34:00 rivoplus sudo[4386]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:34:00 rivoplus sudo[4360]: pam_unix(sudo:session): session closed for user root Oct 02 16:34:00 rivoplus sudo[4379]: pam_unix(sudo:session): session closed for user root Oct 02 16:34:00 rivoplus sudo[4392]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Oct 02 16:34:00 rivoplus sudo[4392]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:34:00 rivoplus sudo[4386]: pam_unix(sudo:session): session closed for user root Oct 02 16:34:00 rivoplus sudo[4328]: pam_unix(sudo:session): session closed for user root Oct 02 16:34:00 rivoplus sudo[4390]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 02 16:34:00 rivoplus sudo[4374]: pam_unix(sudo:session): session closed for user root Oct 02 16:34:00 rivoplus sudo[4390]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:34:00 rivoplus sudo[4398]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 02 16:34:00 rivoplus sudo[4392]: pam_unix(sudo:session): session closed for user root Oct 02 16:34:00 rivoplus sudo[4398]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:34:00 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:34:00 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:34:00 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:34:00 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:34:00 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:34:00 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:34:00 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:34:00 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:34:00 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 16:34:00 rivoplus volumio[3574]: info: Not Reporting Auto name since its the default one Oct 02 16:34:00 rivoplus sudo[4400]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 02 16:34:00 rivoplus sudo[4400]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:34:00 rivoplus sudo[4405]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 02 16:34:00 rivoplus sudo[4405]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:34:00 rivoplus volumio[3574]: info: Starting Shairport Sync Oct 02 16:34:00 rivoplus volumio[3574]: info: Executing endpoint restartRAATSocket Oct 02 16:34:00 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Oct 02 16:34:00 rivoplus sudo[4400]: pam_unix(sudo:session): session closed for user root Oct 02 16:34:00 rivoplus sudo[4405]: pam_unix(sudo:session): session closed for user root Oct 02 16:34:00 rivoplus sudo[4418]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 02 16:34:00 rivoplus sudo[4418]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:34:00 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 02 16:34:00 rivoplus systemd[1]: Stopping raat-daemon.service - RAAT DAEMON... Oct 02 16:34:00 rivoplus systemd[1]: raat-daemon.service: Deactivated successfully. Oct 02 16:34:00 rivoplus systemd[1]: Stopped raat-daemon.service - RAAT DAEMON. Oct 02 16:34:00 rivoplus systemd[1]: Started raat-daemon.service - RAAT DAEMON. Oct 02 16:34:00 rivoplus sudo[4398]: pam_unix(sudo:session): session closed for user root Oct 02 16:34:00 rivoplus volumio[3574]: info: CorePlayQueue::getTrack 0 Oct 02 16:34:00 rivoplus systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 02 16:34:00 rivoplus sudo[4425]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 02 16:34:00 rivoplus volumio[3574]: info: Raat Daemon started successfully Oct 02 16:34:00 rivoplus sudo[4425]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:34:00 rivoplus volumio[3574]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Oct 02 16:34:00 rivoplus volumio[3574]: info: Asound.conf file written Oct 02 16:34:00 rivoplus systemd[1]: shairport-sync.service: Deactivated successfully. Oct 02 16:34:00 rivoplus systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 02 16:34:00 rivoplus systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 02 16:34:00 rivoplus sudo[4418]: pam_unix(sudo:session): session closed for user root Oct 02 16:34:00 rivoplus sudo[4433]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Oct 02 16:34:00 rivoplus sudo[4433]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:34:00 rivoplus sudo[4433]: pam_unix(sudo:session): session closed for user root Oct 02 16:34:00 rivoplus sudo[4425]: pam_unix(sudo:session): session closed for user root Oct 02 16:34:00 rivoplus sudo[4352]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 02 16:34:00 rivoplus sudo[4352]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 02 16:34:00 rivoplus sudo[4352]: pam_unix(sudo:session): session closed for user root Oct 02 16:34:00 rivoplus sudo[4436]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 02 16:34:00 rivoplus sudo[4436]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:34:00 rivoplus kernel: Fine tdm clk setting range (0~2000000), 12287994 Oct 02 16:34:00 rivoplus kernel: Err: can not set rate to 4278967296! Oct 02 16:34:00 rivoplus kernel: Range[3920000 - 500000000] Oct 02 16:34:00 rivoplus kernel: out of value, fixed it Oct 02 16:34:00 rivoplus kernel: id=0 set inskew=0 Oct 02 16:34:00 rivoplus volumio[3574]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 02 16:34:00 rivoplus volumio[3574]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 02 16:34:00 rivoplus volumio[3574]: Found hardware: "AML-AUGESOUND-M" "" "" "" "" Oct 02 16:34:00 rivoplus volumio[3574]: Hardware is initialized using a generic method Oct 02 16:34:00 rivoplus volumio[3574]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:eARC_TX CDS:0' : Operation not permitted Oct 02 16:34:00 rivoplus volumio[3574]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:I2SIn CLK:0' : Operation not permitted Oct 02 16:34:00 rivoplus volumio[3574]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:SPDIFIN audio samplerate:0' : Operation not permitted Oct 02 16:34:00 rivoplus volumio[3574]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:SPDIFIN Audio Type:0' : Operation not permitted Oct 02 16:34:00 rivoplus volumio[3574]: Found hardware: "USB-Audio" "USB Mixer" "USB2fc6:6016" "" "" Oct 02 16:34:00 rivoplus volumio[3574]: Hardware is initialized using a generic method Oct 02 16:34:00 rivoplus volumio[3574]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:Clock Source 5 Validity:0' : Operation not permitted Oct 02 16:34:00 rivoplus volumio[3574]: info: Output device has changed, restarting MPD Oct 02 16:34:00 rivoplus volumio[3574]: info: Output device has changed, restarting Shairport Sync Oct 02 16:34:00 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:34:00 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 16:34:00 rivoplus volumio[3574]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 02 16:34:00 rivoplus volumio[3574]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 02 16:34:00 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:34:00 rivoplus sudo[4459]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 02 16:34:00 rivoplus sudo[4459]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:34:00 rivoplus sudo[4457]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 02 16:34:00 rivoplus sudo[4457]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:34:00 rivoplus systemd[1]: Stopping raat-daemon.service - RAAT DAEMON... Oct 02 16:34:00 rivoplus volumio[3574]: info: QobuzConnect: setDeactiveState invoked Oct 02 16:34:00 rivoplus systemd[1]: raat-daemon.service: Deactivated successfully. Oct 02 16:34:00 rivoplus systemd[1]: Stopped raat-daemon.service - RAAT DAEMON. Oct 02 16:34:00 rivoplus volumio[3574]: info: CoreCommandRouter::volumioGetState Oct 02 16:34:00 rivoplus volumio[3574]: info: CorePlayQueue::getTrack 0 Oct 02 16:34:00 rivoplus volumio[3574]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Oct 02 16:34:00 rivoplus sudo[4457]: pam_unix(sudo:session): session closed for user root Oct 02 16:34:00 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:34:00 rivoplus systemd[1]: Started raat-daemon.service - RAAT DAEMON. Oct 02 16:34:00 rivoplus sudo[4468]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 02 16:34:00 rivoplus sudo[4468]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:34:00 rivoplus sudo[4436]: pam_unix(sudo:session): session closed for user root Oct 02 16:34:00 rivoplus sudo[4475]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 02 16:34:00 rivoplus sudo[4475]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:34:01 rivoplus sudo[4468]: pam_unix(sudo:session): session closed for user root Oct 02 16:34:01 rivoplus sudo[4482]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 02 16:34:01 rivoplus sudo[4482]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:34:01 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 02 16:34:01 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 02 16:34:01 rivoplus volumio[3574]: info: RAAT: Requesting Headphone Status Oct 02 16:34:01 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Oct 02 16:34:01 rivoplus volumio[3574]: info: MPD Permissions set Oct 02 16:34:01 rivoplus volumio[3574]: info: Raat Daemon started successfully Oct 02 16:34:01 rivoplus volumio[3574]: info: TidalConnect service started! Oct 02 16:34:01 rivoplus volumio[3574]: info: Completed starting Core Plugins Oct 02 16:34:01 rivoplus volumio[3574]: info: ------------------------------------------- Oct 02 16:34:01 rivoplus volumio[3574]: info: ----- MyVolumio plugins startup ---- Oct 02 16:34:01 rivoplus volumio[3574]: info: ------------------------------------------- Oct 02 16:34:01 rivoplus volumio[3574]: info: [MyVolumio PluginManager] Fetching plans data.... Oct 02 16:34:01 rivoplus volumio[3574]: info: Shairport-Sync Started Oct 02 16:34:01 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 02 16:34:01 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 02 16:34:01 rivoplus volumio[3574]: info: Discovery: Getting this device information Oct 02 16:34:01 rivoplus volumio[3574]: info: CoreCommandRouter::volumioGetState Oct 02 16:34:01 rivoplus volumio[3574]: info: CorePlayQueue::getTrack 0 Oct 02 16:34:01 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 02 16:34:01 rivoplus volumio[3574]: info: MCU Signalled Headphone Mode Disabled Oct 02 16:34:01 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Oct 02 16:34:01 rivoplus volumio[3574]: info: Reporting Headphone State: false Oct 02 16:34:01 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:34:01 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:34:01 rivoplus volumio[3574]: info: Updating RAAT Signal Path Oct 02 16:34:01 rivoplus sudo[4475]: pam_unix(sudo:session): session closed for user root Oct 02 16:34:01 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:34:01 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:34:01 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:34:01 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:34:01 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:34:01 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:34:01 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:34:01 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:34:01 rivoplus sudo[4482]: pam_unix(sudo:session): session closed for user root Oct 02 16:34:01 rivoplus systemd[1]: mpd.service: Deactivated successfully. Oct 02 16:34:01 rivoplus systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 02 16:34:01 rivoplus systemd[1]: mpd.socket: Deactivated successfully. Oct 02 16:34:01 rivoplus volumio5-onboarding[3906]: time=2026-10-02T16:34:01.289+10:00 level=INFO msg="new address was allocated" component=ble/conn old=1 new=2 Oct 02 16:34:01 rivoplus systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 02 16:34:01 rivoplus systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 02 16:34:01 rivoplus systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 02 16:34:01 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 16:34:01 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 02 16:34:01 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:34:01 rivoplus sudo[4486]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 02 16:34:01 rivoplus sudo[4486]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:34:01 rivoplus systemd[1]: Starting mpd.service - Music Player Daemon... Oct 02 16:34:01 rivoplus qobuz-connect[4259]: 20261002 16:34:01.437 [4259.4259] INFO SampleApp: shat down connection on UNIX socket Oct 02 16:34:01 rivoplus systemd[1]: qobuz-connect.service: Deactivated successfully. Oct 02 16:34:01 rivoplus systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Oct 02 16:34:01 rivoplus systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Oct 02 16:34:01 rivoplus sudo[4267]: pam_unix(sudo:session): session closed for user root Oct 02 16:34:01 rivoplus sudo[4390]: pam_unix(sudo:session): session closed for user root Oct 02 16:34:01 rivoplus systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Oct 02 16:34:01 rivoplus systemd[1]: qobuz-connect.service: Deactivated successfully. Oct 02 16:34:01 rivoplus systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Oct 02 16:34:01 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:34:01 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:34:01 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:34:01 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:34:01 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:34:01 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:34:01 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:34:01 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:34:01 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 16:34:01 rivoplus volumio[3574]: info: Not Reporting Auto name since its the default one Oct 02 16:34:01 rivoplus volumio[3574]: info: CoreCommandRouter::volumioGetState Oct 02 16:34:01 rivoplus volumio[3574]: info: CorePlayQueue::getTrack 0 Oct 02 16:34:01 rivoplus systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Oct 02 16:34:01 rivoplus sudo[4486]: pam_unix(sudo:session): session closed for user root Oct 02 16:34:01 rivoplus volumio[3574]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Oct 02 16:34:01 rivoplus volumio[3574]: info: QobuzConnect: setDeactiveState invoked Oct 02 16:34:01 rivoplus volumio[3574]: info: CoreCommandRouter::volumioGetState Oct 02 16:34:01 rivoplus volumio[3574]: info: CorePlayQueue::getTrack 0 Oct 02 16:34:01 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 02 16:34:01 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 02 16:34:01 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 02 16:34:01 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 02 16:34:01 rivoplus bluealsa[3457]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_63_1A_F2_2D_0A_7E/service0001, ...) Oct 02 16:34:01 rivoplus volumio5-onboarding[3906]: time=2026-10-02T16:34:01.764+10:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.0.122:61124 @ 0x23ceed0" latency=78.489873ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Oct 02 16:34:01 rivoplus volumio5-onboarding[3906]: time=2026-10-02T16:34:01.782+10:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.122:61124 @ 0x23ceed0" latency=84.229208ms timeout=10s Oct 02 16:34:01 rivoplus bluealsa[3457]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_63_1A_F2_2D_0A_7E/service0001/char0002, ...) Oct 02 16:34:01 rivoplus bluealsa[3457]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_63_1A_F2_2D_0A_7E/service0001/char0004, ...) Oct 02 16:34:01 rivoplus bluealsa[3457]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_63_1A_F2_2D_0A_7E/service0006, ...) Oct 02 16:34:01 rivoplus bluealsa[3457]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_63_1A_F2_2D_0A_7E/service0006/char0007, ...) Oct 02 16:34:01 rivoplus bluealsa[3457]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_63_1A_F2_2D_0A_7E/service0006/char0007/desc0009, ...) Oct 02 16:34:01 rivoplus bluealsa[3457]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_63_1A_F2_2D_0A_7E/service000a, ...) Oct 02 16:34:01 rivoplus bluealsa[3457]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_63_1A_F2_2D_0A_7E/service000a/char000b, ...) Oct 02 16:34:01 rivoplus bluealsa[3457]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_63_1A_F2_2D_0A_7E/service000a/char000d, ...) Oct 02 16:34:01 rivoplus bluealsa[3457]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_63_1A_F2_2D_0A_7E/service000f, ...) Oct 02 16:34:01 rivoplus bluealsa[3457]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_63_1A_F2_2D_0A_7E/service000f/char0010, ...) Oct 02 16:34:01 rivoplus bluealsa[3457]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_63_1A_F2_2D_0A_7E/service000f/char0010/desc0012, ...) Oct 02 16:34:01 rivoplus bluealsa[3457]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_63_1A_F2_2D_0A_7E/service000f/char0010/desc0013, ...) Oct 02 16:34:01 rivoplus bluealsa[3457]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_63_1A_F2_2D_0A_7E/service0014, ...) Oct 02 16:34:01 rivoplus bluealsa[3457]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_63_1A_F2_2D_0A_7E/service0014/char0015, ...) Oct 02 16:34:01 rivoplus bluealsa[3457]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_63_1A_F2_2D_0A_7E/service0014/char0015/desc0017, ...) Oct 02 16:34:01 rivoplus bluealsa[3457]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_63_1A_F2_2D_0A_7E/service0014/char0015/desc0018, ...) Oct 02 16:34:01 rivoplus bluealsa[3457]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_63_1A_F2_2D_0A_7E/service0019, ...) Oct 02 16:34:01 rivoplus bluealsa[3457]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_63_1A_F2_2D_0A_7E/service0019/char001a, ...) Oct 02 16:34:01 rivoplus bluealsa[3457]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_63_1A_F2_2D_0A_7E/service0019/char001a/desc001c, ...) Oct 02 16:34:01 rivoplus bluealsa[3457]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_63_1A_F2_2D_0A_7E/service001d, ...) Oct 02 16:34:01 rivoplus bluealsa[3457]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_63_1A_F2_2D_0A_7E/service001d/char001e, ...) Oct 02 16:34:01 rivoplus bluealsa[3457]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_63_1A_F2_2D_0A_7E/service001d/char001e/desc0020, ...) Oct 02 16:34:01 rivoplus bluealsa[3457]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_63_1A_F2_2D_0A_7E/service001d/char0021, ...) Oct 02 16:34:01 rivoplus bluealsa[3457]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_63_1A_F2_2D_0A_7E/service0023, ...) Oct 02 16:34:01 rivoplus bluealsa[3457]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_63_1A_F2_2D_0A_7E/service0023/char0024, ...) Oct 02 16:34:01 rivoplus bluealsa[3457]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_63_1A_F2_2D_0A_7E/service0023/char0024/desc0026, ...) Oct 02 16:34:01 rivoplus bluealsa[3457]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_63_1A_F2_2D_0A_7E/service0023/char0027, ...) Oct 02 16:34:01 rivoplus bluealsa[3457]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_63_1A_F2_2D_0A_7E/service0023/char0027/desc0029, ...) Oct 02 16:34:01 rivoplus bluealsa[3457]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_63_1A_F2_2D_0A_7E/service0023/char002a, ...) Oct 02 16:34:01 rivoplus bluealsa[3457]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_63_1A_F2_2D_0A_7E/service0023/char002a/desc002c, ...) Oct 02 16:34:01 rivoplus bluealsa[3457]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_63_1A_F2_2D_0A_7E/service002d, ...) Oct 02 16:34:01 rivoplus bluealsa[3457]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_63_1A_F2_2D_0A_7E/service002d/char002e, ...) Oct 02 16:34:01 rivoplus bluealsa[3457]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_63_1A_F2_2D_0A_7E/service002d/char002e/desc0030, ...) Oct 02 16:34:01 rivoplus bluealsa[3457]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_63_1A_F2_2D_0A_7E/service002d/char002e/desc0031, ...) Oct 02 16:34:01 rivoplus bluealsa[3457]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_63_1A_F2_2D_0A_7E/service002d/char0032, ...) Oct 02 16:34:01 rivoplus bluealsa[3457]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_63_1A_F2_2D_0A_7E/service002d/char0032/desc0034, ...) Oct 02 16:34:01 rivoplus bluealsa[3457]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_63_1A_F2_2D_0A_7E/service002d/char0032/desc0035, ...) Oct 02 16:34:01 rivoplus bluealsa[3457]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_63_1A_F2_2D_0A_7E/service002d/char0036, ...) Oct 02 16:34:01 rivoplus bluealsa[3457]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_63_1A_F2_2D_0A_7E/service002d/char0036/desc0038, ...) Oct 02 16:34:01 rivoplus volumio[3574]: info: Starting Shairport Sync Oct 02 16:34:01 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 02 16:34:01 rivoplus volumio5-onboarding[3906]: time=2026-10-02T16:34:01.963+10:00 level=INFO msg="new address was allocated" component=ble/conn old=2 new=3 Oct 02 16:34:02 rivoplus sudo[4524]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 02 16:34:02 rivoplus volumio[3574]: info: Preparing to generate the ALSA configuration file Oct 02 16:34:02 rivoplus sudo[4524]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:34:02 rivoplus sudo[4528]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 02 16:34:02 rivoplus sudo[4528]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:34:02 rivoplus volumio[3574]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Oct 02 16:34:02 rivoplus volumio[3574]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 02 16:34:02 rivoplus volumio[3574]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Oct 02 16:34:02 rivoplus volumio[3574]: info: Reading ALSA contributions from plugins. Oct 02 16:34:02 rivoplus dbus-daemon[2944]: [system] Rejected send message, 0 matched rules; type="error", sender=":1.19" (uid=0 pid=3906 comm="/usr/bin/volumio5-onboarding" label="kernel") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.UnknownMethod" requested_reply="0" destination=":1.7" (uid=0 pid=3385 comm="/usr/libexec/bluetooth/bluetoothd --noplugin=sap,b" label="kernel") Oct 02 16:34:02 rivoplus systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 02 16:34:02 rivoplus systemd[1]: shairport-sync.service: Deactivated successfully. Oct 02 16:34:02 rivoplus systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 02 16:34:02 rivoplus volumio[3574]: info: Executing endpoint qc_getconfig Oct 02 16:34:02 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 02 16:34:02 rivoplus systemd[1]: shairport-sync.service: Start request repeated too quickly. Oct 02 16:34:02 rivoplus systemd[1]: shairport-sync.service: Failed with result 'start-limit-hit'. Oct 02 16:34:02 rivoplus systemd[1]: Failed to start shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 02 16:34:02 rivoplus sudo[4528]: pam_unix(sudo:session): session closed for user root Oct 02 16:34:02 rivoplus sudo[4524]: pam_unix(sudo:session): session closed for user root Oct 02 16:34:02 rivoplus qobuz-connect[4506]: 20261002 16:34:02.324 [4506.4506] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 02 16:34:02 rivoplus volumio[3574]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Oct 02 16:34:02 rivoplus volumio[3574]: info: QobuzConnect: QOBUZ Connect daemon connected Oct 02 16:34:02 rivoplus qobuz-connect[4506]: 20261002 16:34:02.337 [4506.4506] INFO VolumeManager: [0xab814018]: Setting new playback volume: 75 Oct 02 16:34:02 rivoplus qobuz-connect[4506]: 20261002 16:34:02.338 [4506.4506] INFO VolumeManager: [0xab814018]: Setting new mute state: 0 Oct 02 16:34:02 rivoplus qobuz-connect[4506]: 20261002 16:34:02.338 [4506.4506] INFO AudioStreamManager: [0xab813d70]: Setting new audio download buffer size: 1048576 Oct 02 16:34:02 rivoplus qobuz-connect[4506]: 20261002 16:34:02.338 [4506.4506] INFO QobuzConnect: [0xab8148e0]: Client initialized! Oct 02 16:34:02 rivoplus qobuz-connect[4506]: 20261002 16:34:02.338 [4506.4506] INFO SampleApp: Starting Avahi advertising, name: Rivoplus, service name: _qobuz-connect._tcp Oct 02 16:34:02 rivoplus volumio[3574]: info: Shairport-sync error: Error: Command failed: /usr/bin/sudo /bin/systemctl restart shairport-sync Oct 02 16:34:02 rivoplus volumio[3574]: Job for shairport-sync.service failed because start of the service was attempted too often. Oct 02 16:34:02 rivoplus volumio[3574]: See "systemctl status shairport-sync.service" and "journalctl -xeu shairport-sync.service" for details. Oct 02 16:34:02 rivoplus volumio[3574]: To force a start use "systemctl reset-failed shairport-sync.service" Oct 02 16:34:02 rivoplus volumio[3574]: followed by "systemctl start shairport-sync.service" again. Oct 02 16:34:02 rivoplus volumio[3574]: info: Asound.conf file written Oct 02 16:34:02 rivoplus qobuz-connect[4506]: 20261002 16:34:02.375 [4506.4506] INFO LocalConfigManager: [0xab813a98]: Starting Local Configuration server Oct 02 16:34:02 rivoplus qobuz-connect[4506]: 20261002 16:34:02.375 [4506.4506] INFO SampleApp: Starting Local configuration server Oct 02 16:34:02 rivoplus qobuz-connect[4506]: 20261002 16:34:02.376 [4506.4506] INFO SampleApp: Connected to UNIX socket client 0xab7fe908 Oct 02 16:34:02 rivoplus sudo[4533]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 02 16:34:02 rivoplus sudo[4533]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:34:02 rivoplus sudo[4538]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Oct 02 16:34:02 rivoplus sudo[4538]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:34:02 rivoplus qobuz-connect[4506]: 20261002 16:34:02.474 [4506.4506] INFO SampleApp: Playback volume changed: 75 Oct 02 16:34:02 rivoplus systemd[1]: Stopping raat-daemon.service - RAAT DAEMON... Oct 02 16:34:02 rivoplus sudo[4538]: pam_unix(sudo:session): session closed for user root Oct 02 16:34:02 rivoplus systemd[1]: raat-daemon.service: Deactivated successfully. Oct 02 16:34:02 rivoplus systemd[1]: Stopped raat-daemon.service - RAAT DAEMON. Oct 02 16:34:02 rivoplus sudo[4498]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 02 16:34:02 rivoplus sudo[4498]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 02 16:34:02 rivoplus sudo[4498]: pam_unix(sudo:session): session closed for user root Oct 02 16:34:02 rivoplus systemd[1]: Started raat-daemon.service - RAAT DAEMON. Oct 02 16:34:02 rivoplus kernel: Fine tdm clk setting range (0~2000000), 12287994 Oct 02 16:34:02 rivoplus kernel: Err: can not set rate to 4274967296! Oct 02 16:34:02 rivoplus kernel: Range[3920000 - 500000000] Oct 02 16:34:02 rivoplus kernel: out of value, fixed it Oct 02 16:34:02 rivoplus kernel: id=0 set inskew=0 Oct 02 16:34:02 rivoplus sudo[4533]: pam_unix(sudo:session): session closed for user root Oct 02 16:34:02 rivoplus volumio5-onboarding[3906]: time=2026-10-02T16:34:02.628+10:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.122:61124 @ 0x23ceed0" latency=84.229208ms timeout=10s endpoint=http://pushupdates.volumio.org duration=822.148805ms Oct 02 16:34:02 rivoplus volumio[3574]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 02 16:34:02 rivoplus volumio[3574]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 02 16:34:02 rivoplus volumio[3574]: Found hardware: "AML-AUGESOUND-M" "" "" "" "" Oct 02 16:34:02 rivoplus volumio[3574]: Hardware is initialized using a generic method Oct 02 16:34:02 rivoplus volumio[3574]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:eARC_TX CDS:0' : Operation not permitted Oct 02 16:34:02 rivoplus volumio[3574]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:I2SIn CLK:0' : Operation not permitted Oct 02 16:34:02 rivoplus volumio[3574]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:SPDIFIN audio samplerate:0' : Operation not permitted Oct 02 16:34:02 rivoplus volumio[3574]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:SPDIFIN Audio Type:0' : Operation not permitted Oct 02 16:34:02 rivoplus volumio[3574]: Found hardware: "USB-Audio" "USB Mixer" "USB2fc6:6016" "" "" Oct 02 16:34:02 rivoplus volumio[3574]: Hardware is initialized using a generic method Oct 02 16:34:02 rivoplus volumio[3574]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:Clock Source 5 Validity:0' : Operation not permitted Oct 02 16:34:02 rivoplus volumio[3574]: info: Output device has changed, restarting MPD Oct 02 16:34:02 rivoplus volumio[3574]: info: Output device has changed, restarting Shairport Sync Oct 02 16:34:02 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:34:02 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 16:34:02 rivoplus sudo[4550]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 02 16:34:02 rivoplus sudo[4550]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:34:02 rivoplus volumio[3574]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 02 16:34:02 rivoplus volumio[3574]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 02 16:34:02 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:34:02 rivoplus sudo[4550]: pam_unix(sudo:session): session closed for user root Oct 02 16:34:02 rivoplus volumio[3574]: info: QobuzConnect: setDeactiveState invoked Oct 02 16:34:02 rivoplus volumio[3574]: info: CoreCommandRouter::volumioGetState Oct 02 16:34:02 rivoplus volumio[3574]: info: CorePlayQueue::getTrack 0 Oct 02 16:34:02 rivoplus volumio[3574]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Oct 02 16:34:02 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:34:02 rivoplus sudo[4552]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 02 16:34:02 rivoplus sudo[4552]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:34:02 rivoplus volumio[3574]: info: CoreCommandRouter::volumioGetState Oct 02 16:34:02 rivoplus volumio[3574]: info: CorePlayQueue::getTrack 0 Oct 02 16:34:02 rivoplus volumio[3574]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read properties of undefined (reading 'split') Oct 02 16:34:02 rivoplus volumio[3574]: info: MPD Permissions set Oct 02 16:34:02 rivoplus volumio[3574]: info: Raat Daemon started successfully Oct 02 16:34:02 rivoplus volumio[3574]: info: TidalConnect service stoped! Oct 02 16:34:02 rivoplus systemd[1]: mpd.service: Deactivated successfully. Oct 02 16:34:02 rivoplus systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 02 16:34:02 rivoplus systemd[1]: mpd.socket: Deactivated successfully. Oct 02 16:34:02 rivoplus systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 02 16:34:02 rivoplus systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 02 16:34:02 rivoplus sudo[4562]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 02 16:34:02 rivoplus sudo[4562]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:34:02 rivoplus sudo[4563]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 02 16:34:02 rivoplus sudo[4563]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:34:02 rivoplus volumio[3574]: info: TidalConnect service stoped! Oct 02 16:34:02 rivoplus volumio[3574]: info: RAAT: Requesting Headphone Status Oct 02 16:34:02 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Oct 02 16:34:02 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:34:02 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:34:02 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:34:02 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:34:02 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:34:02 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:34:02 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:34:02 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:34:03 rivoplus systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 02 16:34:03 rivoplus sudo[4568]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 02 16:34:03 rivoplus sudo[4568]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:34:03 rivoplus systemd[1]: Starting mpd.service - Music Player Daemon... Oct 02 16:34:03 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 16:34:03 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 02 16:34:03 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:34:03 rivoplus volumio[3574]: info: TidalConnect service stoped! Oct 02 16:34:03 rivoplus volumio5-onboarding[3906]: time=2026-10-02T16:34:03.196+10:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.122:61124 @ 0x23ceed0" latency=84.229208ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=1.413731091s Oct 02 16:34:03 rivoplus volumio[3574]: info: MCU Signalled Headphone Mode Disabled Oct 02 16:34:03 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Oct 02 16:34:03 rivoplus volumio[3574]: info: Reporting Headphone State: false Oct 02 16:34:03 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:34:03 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:34:03 rivoplus volumio[3574]: info: Updating RAAT Signal Path Oct 02 16:34:03 rivoplus volumio[3574]: info: Starting Shairport Sync Oct 02 16:34:03 rivoplus volumio5-onboarding[3906]: time=2026-10-02T16:34:03.230+10:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.122:61124 @ 0x23ceed0" latency=84.229208ms timeout=10s endpoint=http://plugins.volumio.org duration=1.413159532s Oct 02 16:34:03 rivoplus volumio[3574]: info: Preparing to generate the ALSA configuration file Oct 02 16:34:03 rivoplus volumio5-onboarding[3906]: time=2026-10-02T16:34:03.264+10:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.122:61124 @ 0x23ceed0" latency=84.229208ms timeout=10s endpoint=https://www.googleapis.com duration=1.458939963s Oct 02 16:34:03 rivoplus sudo[4568]: pam_unix(sudo:session): session closed for user root Oct 02 16:34:03 rivoplus volumio[3574]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 02 16:34:03 rivoplus volumio[3574]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 02 16:34:03 rivoplus sudo[4589]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 02 16:34:03 rivoplus sudo[4589]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:34:03 rivoplus sudo[4590]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 02 16:34:03 rivoplus sudo[4590]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:34:03 rivoplus sudo[4563]: pam_unix(sudo:session): session closed for user root Oct 02 16:34:03 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:34:03 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:34:03 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:34:03 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:34:03 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:34:03 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:34:03 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:34:03 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:34:03 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 16:34:03 rivoplus volumio[3574]: info: Not Reporting Auto name since its the default one Oct 02 16:34:03 rivoplus volumio[3574]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Oct 02 16:34:03 rivoplus volumio[3574]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 02 16:34:03 rivoplus volumio[3574]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Oct 02 16:34:03 rivoplus volumio[3574]: info: Reading ALSA contributions from plugins. Oct 02 16:34:03 rivoplus volumio5-onboarding[3906]: time=2026-10-02T16:34:03.465+10:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.122:61124 @ 0x23ceed0" latency=84.229208ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=1.647341832s Oct 02 16:34:03 rivoplus sudo[4562]: pam_unix(sudo:session): session closed for user root Oct 02 16:34:03 rivoplus volumio[3574]: info: TidalConnect service stoped! Oct 02 16:34:03 rivoplus sudo[4593]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 02 16:34:03 rivoplus sudo[4593]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:34:03 rivoplus volumio[3574]: info: RAAT: Requesting Headphone Status Oct 02 16:34:03 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Oct 02 16:34:03 rivoplus volumio5-onboarding[3906]: time=2026-10-02T16:34:03.529+10:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.122:61124 @ 0x23ceed0" latency=84.229208ms timeout=10s endpoint=https://securetoken.googleapis.com duration=1.72121765s Oct 02 16:34:03 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 02 16:34:03 rivoplus systemd[1]: shairport-sync.service: Start request repeated too quickly. Oct 02 16:34:03 rivoplus systemd[1]: shairport-sync.service: Failed with result 'start-limit-hit'. Oct 02 16:34:03 rivoplus systemd[1]: Failed to start shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 02 16:34:03 rivoplus sudo[4610]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 02 16:34:03 rivoplus sudo[4610]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:34:03 rivoplus sudo[4589]: pam_unix(sudo:session): session closed for user root Oct 02 16:34:03 rivoplus volumio[3574]: info: FusionDsp - If filter freq >samplerate/2 then disable it Oct 02 16:34:03 rivoplus qobuz-connect[4506]: 20261002 16:34:03.817 [4506.4506] INFO SampleApp: Stopping Local configuration server Oct 02 16:34:03 rivoplus volumio[3574]: info: FusionDsp - Clipping test mode disabled, output will be sent to postDsp in camilla Oct 02 16:34:03 rivoplus volumio[3574]: info: FusionDsp - Effects disabled Oct 02 16:34:03 rivoplus systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Oct 02 16:34:03 rivoplus systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Oct 02 16:34:03 rivoplus sudo[4593]: pam_unix(sudo:session): session closed for user root Oct 02 16:34:03 rivoplus volumio5-onboarding[3906]: time=2026-10-02T16:34:03.965+10:00 level=INFO msg="new address was allocated" component=ble/conn old=3 new=4 Oct 02 16:34:03 rivoplus volumio[3574]: info: camilladsp service started and running in background, instance 1 Oct 02 16:34:03 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:34:04 rivoplus sudo[4610]: pam_unix(sudo:session): session closed for user root Oct 02 16:34:04 rivoplus volumio[3574]: cannot open device 'volumioHw': Device or resource busy Oct 02 16:34:04 rivoplus volumio[3574]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Oct 02 16:34:04 rivoplus volumio[3574]: cannot open device 'volumioHw': Device or resource busy Oct 02 16:34:04 rivoplus volumio5-onboarding[3906]: time=2026-10-02T16:34:04.066+10:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.122:61124 @ 0x23ceed0" latency=84.229208ms timeout=10s endpoint=https://google.com duration=2.272329377s Oct 02 16:34:04 rivoplus volumio[3574]: info: FusionDsp loaded Oct 02 16:34:04 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 02 16:34:04 rivoplus volumio[3574]: info: FusionDsp - --[object Object] Oct 02 16:34:04 rivoplus volumio[3574]: info: FusionDsp - Reporting Fusion DSP Enabled Oct 02 16:34:04 rivoplus volumio[3574]: info: Adding Signal Path Element [object Object] Oct 02 16:34:04 rivoplus volumio[3574]: info: Adding fusiondspeq DSP Signal Path Element Oct 02 16:34:04 rivoplus volumio[3574]: info: Additional DSP elements updated Oct 02 16:34:04 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:34:04 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:34:04 rivoplus volumio[3574]: info: Updating RAAT Signal Path Oct 02 16:34:04 rivoplus volumio[3574]: info: FusionDsp - ---- installed callbackRead (debounced 200ms) Oct 02 16:34:04 rivoplus volumio[3574]: info: FusionDsp - PEQ graph server listening on port 10015 Oct 02 16:34:04 rivoplus sudo[4625]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 02 16:34:04 rivoplus sudo[4625]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:34:04 rivoplus volumio[3574]: info: Shairport-sync error: Error: Command failed: /usr/bin/sudo /bin/systemctl restart shairport-sync Oct 02 16:34:04 rivoplus volumio[3574]: Job for shairport-sync.service failed because start of the service was attempted too often. Oct 02 16:34:04 rivoplus volumio[3574]: See "systemctl status shairport-sync.service" and "journalctl -xeu shairport-sync.service" for details. Oct 02 16:34:04 rivoplus volumio[3574]: To force a start use "systemctl reset-failed shairport-sync.service" Oct 02 16:34:04 rivoplus volumio[3574]: followed by "systemctl start shairport-sync.service" again. Oct 02 16:34:04 rivoplus volumio[3574]: info: MCU Signalled Headphone Mode Disabled Oct 02 16:34:04 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Oct 02 16:34:04 rivoplus volumio[3574]: info: Reporting Headphone State: false Oct 02 16:34:04 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:34:04 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:34:04 rivoplus volumio[3574]: info: Updating RAAT Signal Path Oct 02 16:34:04 rivoplus volumio[3574]: error: FusionDsp - Reload WebSocket error: [object Object] Oct 02 16:34:04 rivoplus volumio[3574]: warn: FusionDsp - CamillaDSP reload failed; continuing without rejecting the Promise to avoid an unhandled rejection: connect ECONNREFUSED 127.0.0.1:9876 Oct 02 16:34:04 rivoplus volumio[3574]: info: TidalConnect service stoped! Oct 02 16:34:04 rivoplus sudo[4627]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Oct 02 16:34:04 rivoplus sudo[4627]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:34:04 rivoplus volumio[3574]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 02 16:34:04 rivoplus volumio[3574]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 02 16:34:04 rivoplus volumio[3574]: info: TidalConnect service stoped! Oct 02 16:34:04 rivoplus volumio[3574]: info: Asound.conf file written Oct 02 16:34:04 rivoplus sudo[4635]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 02 16:34:04 rivoplus sudo[4635]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:34:04 rivoplus volumio5-onboarding[3906]: time=2026-10-02T16:34:04.378+10:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.122:61124 @ 0x23ceed0" latency=84.229208ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=2.55787624s Oct 02 16:34:04 rivoplus sudo[4646]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Oct 02 16:34:04 rivoplus sudo[4646]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:34:04 rivoplus qobuz-connect[4506]: 20261002 16:34:04.400 [4506.4506] INFO SampleApp: shat down connection on UNIX socket Oct 02 16:34:04 rivoplus volumio5-onboarding[3906]: time=2026-10-02T16:34:04.413+10:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.122:61124 @ 0x23ceed0" latency=84.229208ms timeout=10s endpoint=https://functions.volumio.cloud duration=2.608688859s Oct 02 16:34:04 rivoplus sudo[4578]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 02 16:34:04 rivoplus volumio5-onboarding[3906]: time=2026-10-02T16:34:04.425+10:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.122:61124 @ 0x23ceed0" latency=84.229208ms timeout=10s endpoint=https://functions.volumio.cloud duration=2.604958569s Oct 02 16:34:04 rivoplus sudo[4578]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 02 16:34:04 rivoplus systemd[1]: qobuz-connect.service: Deactivated successfully. Oct 02 16:34:04 rivoplus volumio5-onboarding[3906]: time=2026-10-02T16:34:04.436+10:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.122:61124 @ 0x23ceed0" latency=84.229208ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=2.641501055s Oct 02 16:34:04 rivoplus systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Oct 02 16:34:04 rivoplus sudo[4646]: pam_unix(sudo:session): session closed for user root Oct 02 16:34:04 rivoplus sudo[4578]: pam_unix(sudo:session): session closed for user root Oct 02 16:34:04 rivoplus kernel: Fine tdm clk setting range (0~2000000), 12287994 Oct 02 16:34:04 rivoplus kernel: Err: can not set rate to 4270967296! Oct 02 16:34:04 rivoplus kernel: Range[3920000 - 500000000] Oct 02 16:34:04 rivoplus kernel: out of value, fixed it Oct 02 16:34:04 rivoplus kernel: id=0 set inskew=0 Oct 02 16:34:04 rivoplus volumio5-onboarding[3906]: time=2026-10-02T16:34:04.515+10:00 level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.122:61124 @ 0x23ceed0" latency=84.229208ms timeout=10s endpoint=http://cddb.volumio.org duration=2.706699804s error="Head \"http://gnudb.gnudb.org/~cddb/cddb.cgi/\": EOF" Oct 02 16:34:04 rivoplus systemd[1]: Stopping raat-daemon.service - RAAT DAEMON... Oct 02 16:34:04 rivoplus systemd[1]: Starting fusiondsp.service - FusionDsp Daemon... Oct 02 16:34:04 rivoplus volumio[3574]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 02 16:34:04 rivoplus volumio[3574]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 02 16:34:04 rivoplus volumio[3574]: Found hardware: "AML-AUGESOUND-M" "" "" "" "" Oct 02 16:34:04 rivoplus volumio[3574]: Hardware is initialized using a generic method Oct 02 16:34:04 rivoplus volumio[3574]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:eARC_TX CDS:0' : Operation not permitted Oct 02 16:34:04 rivoplus volumio[3574]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:I2SIn CLK:0' : Operation not permitted Oct 02 16:34:04 rivoplus volumio[3574]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:SPDIFIN audio samplerate:0' : Operation not permitted Oct 02 16:34:04 rivoplus volumio[3574]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:SPDIFIN Audio Type:0' : Operation not permitted Oct 02 16:34:04 rivoplus volumio[3574]: Found hardware: "USB-Audio" "USB Mixer" "USB2fc6:6016" "" "" Oct 02 16:34:04 rivoplus volumio[3574]: Hardware is initialized using a generic method Oct 02 16:34:04 rivoplus volumio[3574]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:Clock Source 5 Validity:0' : Operation not permitted Oct 02 16:34:04 rivoplus volumio[3574]: info: Output device has changed, restarting MPD Oct 02 16:34:04 rivoplus systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Oct 02 16:34:04 rivoplus sudo[4590]: pam_unix(sudo:session): session closed for user root Oct 02 16:34:04 rivoplus sudo[4635]: pam_unix(sudo:session): session closed for user root Oct 02 16:34:04 rivoplus volumio[3574]: info: Output device has changed, restarting Shairport Sync Oct 02 16:34:04 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:34:04 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 16:34:04 rivoplus sudo[4655]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 02 16:34:04 rivoplus sudo[4655]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:34:04 rivoplus systemd[1]: raat-daemon.service: Deactivated successfully. Oct 02 16:34:04 rivoplus systemd[1]: Stopped raat-daemon.service - RAAT DAEMON. Oct 02 16:34:04 rivoplus sudo[4655]: pam_unix(sudo:session): session closed for user root Oct 02 16:34:04 rivoplus volumio[3574]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 02 16:34:04 rivoplus volumio[3574]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 02 16:34:04 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:34:04 rivoplus sudo[4658]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 02 16:34:04 rivoplus sudo[4658]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:34:04 rivoplus volumio[3574]: info: QobuzConnect: setDeactiveState invoked Oct 02 16:34:04 rivoplus volumio[3574]: info: CoreCommandRouter::volumioGetState Oct 02 16:34:04 rivoplus volumio[3574]: info: CorePlayQueue::getTrack 0 Oct 02 16:34:04 rivoplus volumio[3574]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Oct 02 16:34:04 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:34:04 rivoplus sudo[4668]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 02 16:34:04 rivoplus systemd[1]: Started raat-daemon.service - RAAT DAEMON. Oct 02 16:34:04 rivoplus sudo[4625]: pam_unix(sudo:session): session closed for user root Oct 02 16:34:04 rivoplus volumio5-onboarding[3906]: time=2026-10-02T16:34:04.885+10:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.122:61124 @ 0x23ceed0" latency=84.229208ms timeout=10s endpoint=https://database.volumio.cloud duration=3.068346279s Oct 02 16:34:04 rivoplus sudo[4675]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 02 16:34:04 rivoplus sudo[4675]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:34:04 rivoplus systemd[1]: mpd.service: Deactivated successfully. Oct 02 16:34:04 rivoplus systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 02 16:34:04 rivoplus sudo[4668]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:34:04 rivoplus sudo[4683]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 02 16:34:04 rivoplus sudo[4683]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:34:04 rivoplus systemd[1]: mpd.socket: Deactivated successfully. Oct 02 16:34:05 rivoplus volumio[3574]: info: Checking for updated MCU Firmware Oct 02 16:34:05 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 02 16:34:05 rivoplus volumio[3574]: info: Firware on device is on latest version, no need to update Oct 02 16:34:05 rivoplus volumio[3574]: info: MPD Permissions set Oct 02 16:34:05 rivoplus volumio[3574]: info: Raat Daemon started successfully Oct 02 16:34:05 rivoplus systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 02 16:34:05 rivoplus systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 02 16:34:05 rivoplus volumio[3574]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Oct 02 16:34:05 rivoplus volumio[3574]: info: QobuzConnect: setDeactiveState invoked Oct 02 16:34:05 rivoplus volumio[3574]: info: CoreCommandRouter::volumioGetState Oct 02 16:34:05 rivoplus volumio[3574]: info: CorePlayQueue::getTrack 0 Oct 02 16:34:05 rivoplus volumio[3574]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 02 16:34:05 rivoplus volumio[3574]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 02 16:34:05 rivoplus systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 02 16:34:05 rivoplus volumio[3574]: info: RAAT: Requesting Headphone Status Oct 02 16:34:05 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Oct 02 16:34:05 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:34:05 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:34:05 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:34:05 rivoplus systemd[1]: Starting mpd.service - Music Player Daemon... Oct 02 16:34:05 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:34:05 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:34:05 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:34:05 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:34:05 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:34:05 rivoplus sudo[4699]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 02 16:34:05 rivoplus sudo[4699]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:34:05 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 16:34:05 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 02 16:34:05 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:34:05 rivoplus sudo[4683]: pam_unix(sudo:session): session closed for user root Oct 02 16:34:05 rivoplus sudo[4675]: pam_unix(sudo:session): session closed for user root Oct 02 16:34:05 rivoplus sudo[4668]: pam_unix(sudo:session): session closed for user root Oct 02 16:34:05 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:34:05 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:34:05 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:34:05 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:34:05 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:34:05 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:34:05 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:34:05 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:34:05 rivoplus sudo[4709]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 02 16:34:05 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 16:34:05 rivoplus sudo[4699]: pam_unix(sudo:session): session closed for user root Oct 02 16:34:05 rivoplus volumio[3574]: info: Not Reporting Auto name since its the default one Oct 02 16:34:05 rivoplus sudo[4709]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 16:34:05 rivoplus volumio[3574]: info: Executing endpoint restartRAATSocket Oct 02 16:34:05 rivoplus volumio[3574]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Oct 02 16:34:05 rivoplus volumio[3574]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 02 16:34:05 rivoplus volumio[3574]: Error: Command failed: /usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3 Oct 02 16:34:05 rivoplus volumio[3574]: Job for vtcs.service canceled. Oct 02 16:34:05 rivoplus volumio[3574]: at ChildProcess.exithandler (node:child_process:421:12) Oct 02 16:34:05 rivoplus volumio[3574]: at ChildProcess.emit (node:events:514:28) Oct 02 16:34:05 rivoplus volumio[3574]: at maybeClose (node:internal/child_process:1105:16) Oct 02 16:34:05 rivoplus volumio[3574]: at Socket. (node:internal/child_process:457:11) Oct 02 16:34:05 rivoplus volumio[3574]: at Socket.emit (node:events:514:28) Oct 02 16:34:05 rivoplus volumio[3574]: at Pipe. (node:net:337:12) { Oct 02 16:34:05 rivoplus volumio[3574]: code: 1, Oct 02 16:34:05 rivoplus volumio[3574]: killed: false, Oct 02 16:34:05 rivoplus volumio[3574]: signal: null, Oct 02 16:34:05 rivoplus volumio[3574]: cmd: '/usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3', Oct 02 16:34:05 rivoplus volumio[3574]: stdout: '', Oct 02 16:34:05 rivoplus volumio[3574]: stderr: 'Job for vtcs.service canceled.\n' Oct 02 16:34:05 rivoplus volumio[3574]: } Oct 02 16:34:05 rivoplus volumio[3574]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 02 16:34:05 rivoplus systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Oct 02 16:34:05 rivoplus systemd[1]: qobuz-connect.service: Deactivated successfully. Oct 02 16:34:05 rivoplus systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Oct 02 16:34:05 rivoplus systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Oct 02 16:34:05 rivoplus sudo[4709]: pam_unix(sudo:session): session closed for user root Oct 02 16:34:05 rivoplus sudo[4700]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 02 16:34:05 rivoplus sudo[4700]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 02 16:34:05 rivoplus sudo[4700]: pam_unix(sudo:session): session closed for user root Oct 02 16:34:06 rivoplus sudo[4738]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-02 16:33' Oct 02 16:34:06 rivoplus sudo[4738]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) 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"