Aug 31 09:20:00 rivo volumio[3225]: info: ------------------------------------------- Aug 31 09:20:00 rivo volumio[3225]: info: ----- Volumio3 ---- Aug 31 09:20:00 rivo volumio[3225]: info: ------------------------------------------- Aug 31 09:20:00 rivo volumio[3225]: info: ----- System startup ---- Aug 31 09:20:00 rivo volumio[3225]: info: ------------------------------------------- Aug 31 09:20:00 rivo kernel: fb: osd[0] enable: 1 (Xorg) Aug 31 09:20:00 rivo systemd[1]: systemd-rfkill.service: Deactivated successfully. Aug 31 09:20:01 rivo volumio[3225]: info: MYVOLUMIO Environment detected Aug 31 09:20:01 rivo volumio[3225]: info: Plugin folders cleanup Aug 31 09:20:01 rivo volumio[3225]: info: Scanning into folder /volumio/app/plugins/ Aug 31 09:20:01 rivo volumio[3225]: info: Scanning category audio_interface Aug 31 09:20:01 rivo volumio[3225]: info: Scanning category miscellanea Aug 31 09:20:01 rivo volumio[3225]: info: Scanning category music_service Aug 31 09:20:01 rivo volumio[3225]: info: Scanning category plugins.json Aug 31 09:20:01 rivo volumio[3225]: info: Scanning category system_controller Aug 31 09:20:01 rivo volumio[3225]: info: Scanning category user_interface Aug 31 09:20:01 rivo volumio[3225]: info: Scanning into folder /data/plugins/ Aug 31 09:20:01 rivo volumio[3225]: info: Scanning category user_interface Aug 31 09:20:01 rivo volumio[3225]: info: Plugin folders cleanup completed Aug 31 09:20:01 rivo volumio[3225]: info: ------------------------------------------- Aug 31 09:20:01 rivo volumio[3225]: info: ----- Core plugins startup ---- Aug 31 09:20:01 rivo volumio[3225]: info: ------------------------------------------- Aug 31 09:20:01 rivo volumio[3225]: info: Loading plugins from folder /volumio/app/plugins/ Aug 31 09:20:01 rivo volumio[3225]: info: Adding plugin bluetooth to MyMusic Plugins Aug 31 09:20:01 rivo volumio[3225]: info: Adding plugin multiroom to MyMusic Plugins Aug 31 09:20:01 rivo volumio[3225]: info: Adding plugin upnp to MyMusic Plugins Aug 31 09:20:01 rivo volumio[3225]: info: Adding plugin metavolumio to MyMusic Plugins Aug 31 09:20:01 rivo volumio[3225]: info: Adding plugin airplay_emulation to MyMusic Plugins Aug 31 09:20:01 rivo volumio[3225]: info: Adding plugin cd_controller to MyMusic Plugins Aug 31 09:20:01 rivo volumio[3225]: info: Adding plugin qobuzconnect to MyMusic Plugins Aug 31 09:20:01 rivo volumio[3225]: info: Adding plugin raat to MyMusic Plugins Aug 31 09:20:01 rivo volumio[3225]: info: Adding plugin smart_inputs to MyMusic Plugins Aug 31 09:20:01 rivo volumio[3225]: info: Adding plugin tidalconnect to MyMusic Plugins Aug 31 09:20:01 rivo volumio[3225]: info: Adding plugin upnp_browser to MyMusic Plugins Aug 31 09:20:01 rivo volumio[3225]: info: Loading plugins from folder /data/plugins/ Aug 31 09:20:01 rivo volumio[3225]: info: Loading plugin "system"... Aug 31 09:20:01 rivo volumio[3225]: info: Loading plugin "appearance"... Aug 31 09:20:01 rivo haveged[2887]: haveged: ver: 1.9.14; arch: generic; vend: ; build: (gcc 12.2.0 CTV); collect: 128K Aug 31 09:20:01 rivo haveged[2887]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 12/40; sz: 15126/57786 Aug 31 09:20:01 rivo haveged[2887]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 8.00352 Aug 31 09:20:01 rivo haveged[2887]: haveged: fills: 0, generated: 0 Aug 31 09:20:01 rivo bash[3138]: setdatetime-helper: all HTTPS Date fallbacks failed Aug 31 09:20:01 rivo systemd[1]: setdatetime-helper.service: Deactivated successfully. Aug 31 09:20:01 rivo systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Aug 31 09:20:03 rivo volumio[3225]: info: Loading plugin "network"... Aug 31 09:20:03 rivo volumio[3225]: info: Refreshing Cached IP Addresses Aug 31 09:20:03 rivo sudo[3389]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 31 09:20:03 rivo volumio[3225]: info: Loading plugin "services"... Aug 31 09:20:03 rivo sudo[3389]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 09:20:03 rivo sudo[3391]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 31 09:20:03 rivo volumio[3225]: info: Loading plugin "volumio5onboarding"... Aug 31 09:20:03 rivo sudo[3391]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 09:20:03 rivo sudo[3389]: pam_unix(sudo:session): session closed for user root Aug 31 09:20:03 rivo sudo[3391]: pam_unix(sudo:session): session closed for user root Aug 31 09:20:03 rivo volumio[3225]: info: Loading plugin "alsa_controller"... Aug 31 09:20:03 rivo sh[2854]: eth0: leased 192.168.0.10 for 3600 seconds Aug 31 09:20:03 rivo dhcpcd[2862]: eth0: leased 192.168.0.10 for 3600 seconds Aug 31 09:20:03 rivo avahi-daemon[2920]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.0.10. Aug 31 09:20:03 rivo sh[2854]: eth0: adding route to 192.168.0.0/24 Aug 31 09:20:03 rivo sh[2854]: eth0: adding default route via 192.168.0.1 Aug 31 09:20:03 rivo avahi-daemon[2920]: New relevant interface eth0.IPv4 for mDNS. Aug 31 09:20:03 rivo avahi-daemon[2920]: Registering new address record for 192.168.0.10 on eth0.IPv4. Aug 31 09:20:03 rivo dhcpcd[2862]: eth0: adding route to 192.168.0.0/24 Aug 31 09:20:03 rivo dhcpcd[2862]: eth0: adding default route via 192.168.0.1 Aug 31 09:20:03 rivo systemd[1]: welcome.service: Deactivated successfully. Aug 31 09:20:03 rivo systemd[1]: Stopped welcome.service - Show a welcome message on console. Aug 31 09:20:03 rivo systemd[1]: Stopping welcome.service - Show a welcome message on console... Aug 31 09:20:03 rivo sh[2854]: forked to background, child pid 2861 Aug 31 09:20:03 rivo systemd[1]: Starting welcome.service - Show a welcome message on console... Aug 31 09:20:03 rivo kernel: nf_conntrack: default automatic helper assignment has been turned off for security reasons and CT-based firewall rule not found. Use the iptables CT target to attach helpers instead. Aug 31 09:20:03 rivo systemd[1]: Started nmbd.service - Samba NMB Daemon. Aug 31 09:20:03 rivo welcome[3435]: Resolved ip:[1] 192.168.0.10 Aug 31 09:20:03 rivo systemd[1]: Starting winbind.service - Samba Winbind Daemon... Aug 31 09:20:03 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 31 09:20:03 rivo volumio[3225]: info: Loading plugin "wizard"... Aug 31 09:20:03 rivo volumio[3225]: info: Loading plugin "networkfs"... Aug 31 09:20:03 rivo systemd[1]: Finished welcome.service - Show a welcome message on console. Aug 31 09:20:03 rivo systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Aug 31 09:20:03 rivo volumio[3225]: info: Starting Udev Watcher for removable devices Aug 31 09:20:03 rivo sudo[3468]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t nfs -o ro,soft,noauto '192.168.0.14:/volume1/muzika1/stari D/MJUZA' /mnt/NAS/MJUZA_1 Aug 31 09:20:03 rivo sudo[3468]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 09:20:03 rivo kernel: meson_uart ffd24000.serial: ttyS1 use xtal(24M) 24000000 change 115200 to 2000000 Aug 31 09:20:03 rivo sudo[3470]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t nfs -o ro,soft,noauto 192.168.0.14:/volume2/muzika2/MJUZA /mnt/NAS/MJUZA_2 Aug 31 09:20:03 rivo sudo[3470]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 09:20:04 rivo systemd[1]: Starting systemd-rfkill.service - Load/Save RF Kill Switch Status... Aug 31 09:20:04 rivo (udev-worker)[2626]: hci0: Process '/usr/bin/hciconfig hci0 up' failed with exit code 1. Aug 31 09:20:04 rivo volumio[3225]: info: Ignoring mount for partition: BOOT Aug 31 09:20:04 rivo volumio[3225]: info: Ignoring mount for partition: volumio Aug 31 09:20:04 rivo volumio[3225]: info: Ignoring mount for partition: volumio_data Aug 31 09:20:04 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 31 09:20:04 rivo kernel: FS-Cache: Netfs 'nfs' registered for caching Aug 31 09:20:04 rivo systemd[1]: Started systemd-rfkill.service - Load/Save RF Kill Switch Status. Aug 31 09:20:04 rivo volumio[3225]: info: Loading plugin "volumio_command_line_client"... Aug 31 09:20:04 rivo volumio[3225]: info: Loading plugin "upnp"... Aug 31 09:20:04 rivo volumio[3225]: info: [1788160804122] Starting Upmpd Daemon Aug 31 09:20:04 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 31 09:20:04 rivo volumio[3225]: info: Loading plugin "my_music"... Aug 31 09:20:04 rivo systemd[1]: Starting bluetooth.service - Bluetooth service... Aug 31 09:20:04 rivo volumio[3225]: info: Loading plugin "mpd"... Aug 31 09:20:04 rivo ifplugd(eth0)[3187]: client: ifup: interface eth0 already configured Aug 31 09:20:04 rivo kernel: NFS: Registering the id_resolver key type Aug 31 09:20:04 rivo kernel: Key type id_resolver registered Aug 31 09:20:04 rivo kernel: Key type id_legacy registered Aug 31 09:20:04 rivo sh[3495]: eth0=eth0 Aug 31 09:20:04 rivo ifplugd(eth0)[3187]: Program executed successfully. Aug 31 09:20:04 rivo systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Aug 31 09:20:04 rivo systemd[1]: Starting rpc-statd.service - NFS status monitor for NFSv2/3 locking.... Aug 31 09:20:04 rivo rpc.statd[3505]: Version 2.6.2 starting Aug 31 09:20:04 rivo rpc.statd[3505]: Flags: TI-RPC Aug 31 09:20:04 rivo systemd[1]: Started rpc-statd.service - NFS status monitor for NFSv2/3 locking.. Aug 31 09:20:04 rivo systemd[1]: Reloading. Aug 31 09:20:04 rivo winbindd[3490]: [2026/08/31 09:20:04.599606, 0] ../../source3/winbindd/winbindd.c:1440(main) Aug 31 09:20:04 rivo winbindd[3490]: winbindd version 4.17.12-Debian started. Aug 31 09:20:04 rivo winbindd[3490]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Aug 31 09:20:04 rivo winbindd[3490]: [2026/08/31 09:20:04.648646, 0] ../../source3/winbindd/winbindd_cache.c:3117(initialize_winbindd_cache) Aug 31 09:20:04 rivo winbindd[3490]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Aug 31 09:20:04 rivo bluetoothd[3492]: Bluetooth daemon 5.83 Aug 31 09:20:04 rivo ntpd[3157]: IO: Listen normally on 3 eth0 192.168.0.10:123 Aug 31 09:20:04 rivo ntpd[3157]: IO: new interface(s) found: waking up resolver Aug 31 09:20:04 rivo ntpd[3157]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Aug 31 09:20:04 rivo ntpd[3157]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Aug 31 09:20:04 rivo ntpd[3157]: DNS: Pool taking: 195.133.18.24 Aug 31 09:20:04 rivo ntpd[3157]: DNS: Pool taking: 217.24.20.5 Aug 31 09:20:04 rivo ntpd[3157]: DNS: Pool taking: 162.159.200.123 Aug 31 09:20:04 rivo ntpd[3157]: DNS: Pool taking: 162.159.200.1 Aug 31 09:20:04 rivo ntpd[3157]: DNS: dns_take_status: 3.debian.pool.ntp.org=>good, 8 Aug 31 09:20:04 rivo bluetoothd[3492]: Starting SDP server Aug 31 09:20:04 rivo bluetoothd[3492]: Excluding (cli) sap Aug 31 09:20:04 rivo bluetoothd[3492]: Excluding (cli) scanparam Aug 31 09:20:04 rivo bluetoothd[3492]: Excluding (cli) midi Aug 31 09:20:04 rivo bluetoothd[3492]: Excluding (cli) battery Aug 31 09:20:04 rivo dbus-daemon[2923]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.7' (uid=0 pid=3492 comm="/usr/libexec/bluetooth/bluetoothd --noplugin=sap,b" label="kernel") Aug 31 09:20:04 rivo bluetoothd[3492]: Bluetooth management interface 1.14 initialized Aug 31 09:20:04 rivo bluetoothd[3492]: src/adapter.c:reset_adv_monitors_complete() Failed to reset Adv Monitors: Unknown Command (0x01) Aug 31 09:20:04 rivo bluetoothd[3492]: Battery Provider Manager created Aug 31 09:20:04 rivo dhcpcd[3022]: eth0: leased 192.168.0.10 for 3600 seconds Aug 31 09:20:04 rivo dhcpcd[3022]: eth0: adding route to 192.168.0.0/24 Aug 31 09:20:04 rivo dhcpcd[3022]: eth0: adding default route via 192.168.0.1 Aug 31 09:20:04 rivo bluetoothd[3492]: profiles/audio/bap.c:bap_adapter_probe() BAP requires ISO Socket which is not enabled Aug 31 09:20:04 rivo bluetoothd[3492]: bap: Operation not supported (95) Aug 31 09:20:04 rivo bluetoothd[3492]: Failed to Read Adv Monitor Features with status 0x01 Aug 31 09:20:04 rivo volumio[3225]: info: Loading plugin "upnp_browser"... Aug 31 09:20:05 rivo volumio-remote-updater[2940]: [2026-08-31 09:20:05] [connect] Successful connection Aug 31 09:20:05 rivo ntpd[3157]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Aug 31 09:20:05 rivo ntpd[3157]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Aug 31 09:20:05 rivo ntpd[3157]: DNS: Pool skipping: 195.133.18.24 Aug 31 09:20:05 rivo ntpd[3157]: DNS: Pool skipping: 162.159.200.1 Aug 31 09:20:05 rivo ntpd[3157]: DNS: Pool skipping: 162.159.200.123 Aug 31 09:20:05 rivo ntpd[3157]: DNS: Pool taking: 147.91.26.20 Aug 31 09:20:05 rivo ntpd[3157]: DNS: Pool taking: 2a13:9400:10:1002::123 Aug 31 09:20:05 rivo ntpd[3157]: DNS: Pool taking: 2001:ac8:7d:42:0:2:0:20 Aug 31 09:20:05 rivo ntpd[3157]: DNS: Pool taking: 2606:4700:f1::123 Aug 31 09:20:05 rivo ntpd[3157]: DNS: Pool taking: 2001:ac8:7d:42:0:2:0:10 Aug 31 09:20:05 rivo ntpd[3157]: DNS: dns_take_status: 2.debian.pool.ntp.org=>good, 8 Aug 31 09:20:06 rivo ntpd[3157]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Aug 31 09:20:06 rivo ntpd[3157]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Aug 31 09:20:06 rivo ntpd[3157]: DNS: Pool skipping: 195.133.18.24 Aug 31 09:20:06 rivo ntpd[3157]: DNS: Pool skipping: 217.24.20.5 Aug 31 09:20:06 rivo ntpd[3157]: DNS: Pool skipping: 162.159.200.1 Aug 31 09:20:06 rivo ntpd[3157]: DNS: Pool skipping: 162.159.200.123 Aug 31 09:20:06 rivo ntpd[3157]: DNS: dns_take_status: 1.debian.pool.ntp.org=>good, 8 Aug 31 09:20:06 rivo systemd[1]: systemd-udevd.service: bpf-firewall: Preparation of BPF allow maps failed: Invalid argument Aug 31 09:20:06 rivo systemd[1]: Started winbind.service - Samba Winbind Daemon. Aug 31 09:20:06 rivo systemd[1]: Started bluetooth.service - Bluetooth service. Aug 31 09:20:06 rivo systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Aug 31 09:20:06 rivo systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Aug 31 09:20:06 rivo systemd[1]: Starting bluealsa.service - BlueALSA service... Aug 31 09:20:06 rivo systemd[1]: Starting smbd.service - Samba SMB Daemon... Aug 31 09:20:06 rivo systemd[1]: Starting systemd-hostnamed.service - Hostname Service... Aug 31 09:20:06 rivo systemd[1]: welcome.service: Deactivated successfully. Aug 31 09:20:06 rivo systemd[1]: Stopped welcome.service - Show a welcome message on console. Aug 31 09:20:06 rivo systemd[1]: Stopping welcome.service - Show a welcome message on console... Aug 31 09:20:06 rivo systemd[1]: Starting welcome.service - Show a welcome message on console... Aug 31 09:20:06 rivo kernel: IPVS: Creating netns size=1928 id=3 Aug 31 09:20:06 rivo welcome[3576]: Resolved ip:[1] 192.168.0.10 Aug 31 09:20:07 rivo sudo[3468]: pam_unix(sudo:session): session closed for user root Aug 31 09:20:07 rivo sudo[3470]: pam_unix(sudo:session): session closed for user root Aug 31 09:20:07 rivo nfsrahead[3583]: setting /mnt/NAS/MJUZA_2 readahead to 128 Aug 31 09:20:07 rivo systemd[1]: Finished welcome.service - Show a welcome message on console. Aug 31 09:20:07 rivo nfsrahead[3585]: setting /mnt/NAS/MJUZA_1 readahead to 128 Aug 31 09:20:07 rivo systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Aug 31 09:20:07 rivo volumio[3225]: info: Starting UPNP Browser Aug 31 09:20:07 rivo volumio[3225]: info: Plugin bluetooth is not enabled Aug 31 09:20:07 rivo volumio[3225]: info: Loading plugin "alarm-clock"... Aug 31 09:20:07 rivo volumio[3225]: info: Loading plugin "manifestui"... Aug 31 09:20:07 rivo smbd[3587]: [2026/08/31 09:20:07.567829, 0] ../../source3/smbd/server.c:1741(main) Aug 31 09:20:07 rivo smbd[3587]: smbd version 4.17.12-Debian started. Aug 31 09:20:07 rivo smbd[3587]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Aug 31 09:20:07 rivo systemd[1]: Started smbd.service - Samba SMB Daemon. Aug 31 09:20:07 rivo systemd[1]: Reached target multi-user.target - Multi-User System. Aug 31 09:20:07 rivo systemd[1]: Reached target graphical.target - Graphical Interface. Aug 31 09:20:07 rivo volumio[3225]: info: Loading plugin "metavolumio"... Aug 31 09:20:07 rivo systemd[1]: Starting systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP... Aug 31 09:20:07 rivo ntpd[3157]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 Aug 31 09:20:07 rivo ntpd[3157]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Aug 31 09:20:07 rivo ntpd[3157]: DNS: Pool skipping: 195.133.18.24 Aug 31 09:20:07 rivo ntpd[3157]: DNS: Pool skipping: 217.24.20.5 Aug 31 09:20:07 rivo ntpd[3157]: DNS: Pool skipping: 162.159.200.1 Aug 31 09:20:07 rivo ntpd[3157]: DNS: Pool skipping: 162.159.200.123 Aug 31 09:20:07 rivo ntpd[3157]: DNS: dns_take_status: 0.debian.pool.ntp.org=>good, 8 Aug 31 09:20:07 rivo systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Aug 31 09:20:07 rivo systemd[1]: Finished systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP. Aug 31 09:20:07 rivo dbus-daemon[2923]: [system] Successfully activated service 'org.freedesktop.hostname1' Aug 31 09:20:07 rivo systemd[1]: Started systemd-hostnamed.service - Hostname Service. Aug 31 09:20:07 rivo bluealsa[3572]: ../src/storage.c:90: Initializing persistent storage: /var/lib/bluealsa Aug 31 09:20:07 rivo bluealsa[3572]: main.c:663: Starting main dispatching loop Aug 31 09:20:07 rivo systemd[1]: Started bluealsa.service - BlueALSA service. Aug 31 09:20:07 rivo bluealsa[3572]: main.c:118: Acquired D-Bus service name: org.bluealsa Aug 31 09:20:07 rivo bluealsa[3572]: bluealsa-dbus.c:358: Registering BlueALSA D-Bus manager: /org/bluealsa Aug 31 09:20:07 rivo systemd[1]: Reached target bluetooth.target - Bluetooth Support. Aug 31 09:20:07 rivo systemd[1]: Startup finished in 13.212s (kernel) + 19.610s (userspace) = 32.822s. Aug 31 09:20:07 rivo bluealsa[3572]: bluez.c:153: Registering media application: /org/bluez/hci0 Aug 31 09:20:07 rivo bluealsa[3572]: bluez.c:743: Exporting media endpoint object: /org/bluez/hci0/A2DP/SBC/source/1 Aug 31 09:20:07 rivo bluealsa[3572]: bluez.c:743: Exporting media endpoint object: /org/bluez/hci0/A2DP/SBC/source/2 Aug 31 09:20:07 rivo bluealsa[3572]: bluez.c:743: Exporting media endpoint object: /org/bluez/hci0/A2DP/SBC/sink/1 Aug 31 09:20:07 rivo bluealsa[3572]: bluez.c:743: Exporting media endpoint object: /org/bluez/hci0/A2DP/SBC/sink/2 Aug 31 09:20:07 rivo bluetoothd[3492]: Endpoint registered: sender=:1.10 path=/org/bluez/hci0/A2DP/SBC/source/1 Aug 31 09:20:07 rivo bluetoothd[3492]: Endpoint registered: sender=:1.10 path=/org/bluez/hci0/A2DP/SBC/source/2 Aug 31 09:20:07 rivo bluetoothd[3492]: Endpoint registered: sender=:1.10 path=/org/bluez/hci0/A2DP/SBC/sink/1 Aug 31 09:20:07 rivo bluetoothd[3492]: Endpoint registered: sender=:1.10 path=/org/bluez/hci0/A2DP/SBC/sink/2 Aug 31 09:20:08 rivo systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 1. Aug 31 09:20:08 rivo systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Aug 31 09:20:08 rivo systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Aug 31 09:20:08 rivo upmpdcli[3599]: Could not open config: /tmp/upmpdcli.conf Aug 31 09:20:08 rivo systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Aug 31 09:20:08 rivo systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Aug 31 09:20:09 rivo systemd[1]: systemd-rfkill.service: Deactivated successfully. Aug 31 09:20:09 rivo volumio[3225]: info: Loading plugin "airplay_emulation"... Aug 31 09:20:09 rivo volumio[3225]: info: Starting Shairport Sync Aug 31 09:20:09 rivo volumio[3225]: info: Loading plugin "cd_controller"... Aug 31 09:20:09 rivo volumio[3225]: info: Loading plugin "last_100"... Aug 31 09:20:09 rivo volumio[3225]: info: Loading plugin "qobuzconnect"... Aug 31 09:20:09 rivo volumio[3225]: info: Loading plugin "raat"... Aug 31 09:20:10 rivo volumio[3225]: info: RAAT Plugin loaded Aug 31 09:20:10 rivo volumio[3225]: info: Adding restartRAATSocket REST API Endpoint Aug 31 09:20:10 rivo volumio[3225]: info: Adding restartRAATSocket REST Endpoint for plugin: music_service/raat Aug 31 09:20:10 rivo volumio[3225]: info: Loading plugin "streaming_services"... Aug 31 09:20:10 rivo ntpd[3157]: CLOCK: time stepped by -0.739660 Aug 31 09:20:10 rivo systemd-journald[2588]: Time jumped backwards, rotating. Aug 31 09:20:10 rivo ntpd[3157]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Aug 31 09:20:10 rivo volumio[3225]: info: Loading plugin "tidalconnect"... Aug 31 09:20:11 rivo volumio[3225]: info: Loading plugin "webradio"... Aug 31 09:20:11 rivo volumio[3225]: info: Loading plugin "i2s_dacs"... Aug 31 09:20:11 rivo volumio[3225]: info: I2S DAC not set, start Auto-detection Aug 31 09:20:11 rivo volumio[3225]: info: Loading plugin "volumiodiscovery"... Aug 31 09:20:11 rivo volumio[3225]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 31 09:20:11 rivo volumio[3225]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 31 09:20:11 rivo volumio[3225]: *** WARNING *** For more information see Aug 31 09:20:11 rivo volumio[3225]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 31 09:20:11 rivo volumio[3225]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 31 09:20:11 rivo volumio[3225]: *** WARNING *** For more information see Aug 31 09:20:11 rivo node[3225]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 31 09:20:11 rivo node[3225]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 31 09:20:11 rivo node[3225]: *** WARNING *** For more information see Aug 31 09:20:11 rivo node[3225]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 31 09:20:11 rivo node[3225]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 31 09:20:11 rivo node[3225]: *** WARNING *** For more information see Aug 31 09:20:11 rivo volumio[3225]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 31 09:20:11 rivo volumio[3225]: info: Discovery: Started advertising with name: Rivo Aug 31 09:20:11 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 31 09:20:11 rivo volumio[3225]: info: Loading plugin "now_playing"... Aug 31 09:20:13 rivo volumio[3225]: info: Loading plugin "multiroom"... Aug 31 09:20:15 rivo volumio[3225]: info: Applying required configuration parameters for plugin multiroom Aug 31 09:20:15 rivo sudo[3623]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Aug 31 09:20:15 rivo sudo[3623]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 09:20:15 rivo sudo[3623]: pam_unix(sudo:session): session closed for user root Aug 31 09:20:15 rivo volumio[3225]: info: MRS: MultiRoom plugin initialized Aug 31 09:20:15 rivo volumio[3225]: info: MRS: STOPPING SNAPCLIENT Aug 31 09:20:15 rivo volumio[3225]: info: MRS: Snap server stop Aug 31 09:20:15 rivo volumio[3225]: info: MRS: STOPPING volumioStreaming Aug 31 09:20:15 rivo sudo[3639]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Aug 31 09:20:15 rivo sudo[3639]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 09:20:15 rivo volumio[3225]: info: Loading plugin "outputs"... Aug 31 09:20:15 rivo sudo[3641]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Aug 31 09:20:15 rivo sudo[3641]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 09:20:15 rivo sudo[3643]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Aug 31 09:20:15 rivo sudo[3643]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 09:20:15 rivo volumio[3225]: info: Loading plugin "albumart"... Aug 31 09:20:15 rivo volumio[3225]: info: Plugin example_plugin is not enabled Aug 31 09:20:15 rivo volumio[3225]: info: Loading plugin "hi_res_audio"... Aug 31 09:20:15 rivo sudo[3647]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Aug 31 09:20:15 rivo sudo[3647]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 09:20:15 rivo sudo[3647]: pam_unix(sudo:session): session closed for user root Aug 31 09:20:15 rivo sudo[3639]: pam_unix(sudo:session): session closed for user root Aug 31 09:20:15 rivo sudo[3643]: pam_unix(sudo:session): session closed for user root Aug 31 09:20:15 rivo sudo[3641]: pam_unix(sudo:session): session closed for user root Aug 31 09:20:16 rivo volumio[3649]: Forking 3 albumart workers Aug 31 09:20:17 rivo volumio[3659]: Starting albumart workers Aug 31 09:20:17 rivo volumio[3225]: info: Applying required configuration parameters for plugin hi_res_audio Aug 31 09:20:17 rivo volumio[3225]: info: Loading plugin "inputs"... Aug 31 09:20:17 rivo volumio[3657]: Starting albumart workers Aug 31 09:20:17 rivo volumio[3658]: Starting albumart workers Aug 31 09:20:18 rivo volumio[3225]: info: Loading plugin "qobuz"... Aug 31 09:20:19 rivo volumio-remote-updater[2940]: [2026-08-31 09:20:19] [connect] Successful connection Aug 31 09:20:20 rivo volumio[3225]: info: Loading plugin "smart_inputs"... Aug 31 09:20:20 rivo volumio[3225]: info: Loading plugin "tidal"... Aug 31 09:20:21 rivo systemd[1]: systemd-fsckd.service: Deactivated successfully. Aug 31 09:20:22 rivo volumio[3225]: info: Loading plugin "rivocontrol"... Aug 31 09:20:22 rivo volumio[3225]: info: Adding this device properties Aug 31 09:20:22 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: system , setThisDeviceVolumioProperties Aug 31 09:20:22 rivo volumio[3225]: info: Setting Additional Device Volumio Properties: [object Object] Aug 31 09:20:22 rivo volumio[3225]: info: Loading plugin "updater_comm"... Aug 31 09:20:22 rivo volumio[3225]: info: Loading plugin "cec_controller"... Aug 31 09:20:22 rivo systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2. Aug 31 09:20:22 rivo systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Aug 31 09:20:22 rivo systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Aug 31 09:20:22 rivo upmpdcli[3705]: Could not open config: /tmp/upmpdcli.conf Aug 31 09:20:22 rivo systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Aug 31 09:20:22 rivo systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Aug 31 09:20:23 rivo volumio[3225]: info: Applying required configuration parameters for plugin cec_controller Aug 31 09:20:23 rivo volumio[3225]: info: Plugin mpdemulation is not enabled Aug 31 09:20:23 rivo volumio[3225]: info: Loading plugin "rest_api"... Aug 31 09:20:23 rivo volumio[3225]: info: Loading plugin "websocket"... Aug 31 09:20:23 rivo volumio[3225]: info: Starting Socket.io Server version 1.7.4 Aug 31 09:20:23 rivo volumio[3225]: info: Loading i18n strings for locale en Aug 31 09:20:23 rivo volumio[3225]: Updating browse sources language Aug 31 09:20:23 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 31 09:20:23 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 09:20:23 rivo volumio[3225]: info: CoreCommandRouter::initPlayerControls Aug 31 09:20:23 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 09:20:23 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 09:20:23 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 09:20:23 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 09:20:23 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 09:20:23 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 09:20:23 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 09:20:23 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 09:20:23 rivo volumio[3225]: info: VolumeController:: Starting alsactl monitor Aug 31 09:20:23 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 09:20:23 rivo volumio[3225]: Express server listening on port 3000 Aug 31 09:20:23 rivo volumio[3225]: [Metrics] WebUI: 25s 62.80ms Aug 31 09:20:23 rivo volumio[3225]: info: CoreStateMachine::resetVolumioState Aug 31 09:20:23 rivo volumio[3225]: info: CoreStateMachine::getcurrentVolume Aug 31 09:20:23 rivo volumio[3225]: info: CoreCommandRouter::volumioRetrievevolume Aug 31 09:20:23 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 09:20:23 rivo sudo[3734]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 31 09:20:23 rivo sudo[3734]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 09:20:23 rivo volumio[3225]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Aug 31 09:20:23 rivo volumio[3225]: info: Completed loading Core Plugins Aug 31 09:20:23 rivo sudo[3736]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 31 09:20:23 rivo sudo[3736]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 09:20:23 rivo volumio[3225]: info: Preparing to generate the ALSA configuration file Aug 31 09:20:23 rivo sudo[3734]: pam_unix(sudo:session): session closed for user root Aug 31 09:20:23 rivo sudo[3736]: pam_unix(sudo:session): session closed for user root Aug 31 09:20:23 rivo volumio[3225]: info: Volumio Network Manager: Network status updated: 0 Aug 31 09:20:23 rivo volumio[3225]: info: MRS: Removed streaming files Aug 31 09:20:23 rivo volumio[3225]: info: MRS: volumioStreaming STOPPED Aug 31 09:20:23 rivo volumio[3225]: info: MRS: SNAPSERVER STOPPED Aug 31 09:20:23 rivo volumio[3225]: info: MRS: SNAPCLIENT STOPPED Aug 31 09:20:23 rivo volumio[3225]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Aug 31 09:20:23 rivo volumio[3225]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Aug 31 09:20:23 rivo volumio[3225]: info: Reading ALSA contributions from plugins. Aug 31 09:20:23 rivo volumio[3225]: info: VolumeController:: Volume=100 Mute =false Aug 31 09:20:23 rivo volumio[3225]: info: CoreStateMachine::pushState Aug 31 09:20:23 rivo volumio[3225]: info: CorePlayQueue::getTrack 0 Aug 31 09:20:23 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 09:20:23 rivo volumio[3225]: info: CoreCommandRouter::volumioPushState Aug 31 09:20:23 rivo volumio[3225]: info: CoreStateMachine::updateTrackBlock Aug 31 09:20:23 rivo volumio[3225]: info: CorePlayQueue::getTrackBlock Aug 31 09:20:23 rivo volumio[3225]: info: CoreCommandRouter::volumioRetrievevolume Aug 31 09:20:23 rivo volumio[3225]: info: Reloading queue from file Aug 31 09:20:23 rivo volumio[3225]: info: CoreStateMachine::setRepeat false single undefined Aug 31 09:20:23 rivo volumio[3225]: info: CoreStateMachine::pushState Aug 31 09:20:23 rivo volumio[3225]: info: CorePlayQueue::getTrack 0 Aug 31 09:20:23 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 09:20:23 rivo volumio[3225]: info: CoreCommandRouter::volumioPushState Aug 31 09:20:23 rivo volumio[3225]: info: CoreStateMachine::setRandom null Aug 31 09:20:23 rivo volumio[3225]: info: CoreStateMachine::pushState Aug 31 09:20:23 rivo volumio[3225]: info: CorePlayQueue::getTrack 0 Aug 31 09:20:23 rivo volumio[3225]: info: CoreCommandRouter::volumioPushState Aug 31 09:20:24 rivo sudo[3751]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Aug 31 09:20:24 rivo sudo[3751]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 09:20:24 rivo volumio[3225]: info: VolumeController:: Volume=100 Mute =false Aug 31 09:20:24 rivo volumio[3225]: info: CoreStateMachine::pushState Aug 31 09:20:24 rivo volumio[3225]: info: CorePlayQueue::getTrack 0 Aug 31 09:20:24 rivo volumio[3225]: info: CoreCommandRouter::volumioPushState Aug 31 09:20:24 rivo volumio-remote-updater[2940]: [2026-08-31 09:20:24] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1788160819 101 Aug 31 09:20:24 rivo volumio[3225]: 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 Aug 31 09:20:24 rivo volumio[3225]: info: Asound.conf file unchanged, so no further update is needed Aug 31 09:20:24 rivo volumio[3225]: info: Output device has changed, restarting MPD Aug 31 09:20:24 rivo volumio[3225]: info: Output device has changed, restarting Shairport Sync Aug 31 09:20:24 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 09:20:24 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 09:20:24 rivo sudo[3755]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 31 09:20:24 rivo sudo[3755]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 09:20:24 rivo sudo[3757]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 31 09:20:24 rivo sudo[3757]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 09:20:24 rivo sudo[3755]: pam_unix(sudo:session): session closed for user root Aug 31 09:20:24 rivo volumio[3225]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 31 09:20:24 rivo volumio[3225]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Aug 31 09:20:24 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 09:20:24 rivo volumio[3225]: info: ___________ START PLUGINS ___________ Aug 31 09:20:24 rivo volumio[3225]: info: ControllerMpd::onStart: Initializing MPD Aug 31 09:20:24 rivo volumio[3225]: info: Creating MPD Configuration file Aug 31 09:20:24 rivo systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 31 09:20:24 rivo sudo[3766]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Aug 31 09:20:24 rivo sudo[3766]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 09:20:24 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 09:20:24 rivo volumio[3225]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 31 09:20:24 rivo volumio[3225]: info: [1788160824230] CoreMusicLibrary::Adding element Media Servers Aug 31 09:20:24 rivo systemd[1]: Starting mpd.service - Music Player Daemon... Aug 31 09:20:24 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 31 09:20:24 rivo sudo[3768]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 31 09:20:24 rivo sudo[3768]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 09:20:24 rivo volumio[3225]: info: UPNP Browser: Client initialized successfully Aug 31 09:20:24 rivo sudo[3768]: pam_unix(sudo:session): session closed for user root Aug 31 09:20:24 rivo sudo[3773]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 31 09:20:24 rivo sudo[3773]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 09:20:24 rivo volumio[3225]: info: Adding METAVOLUMIO REST API Endpoints Aug 31 09:20:24 rivo volumio[3225]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Aug 31 09:20:24 rivo volumio[3225]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Aug 31 09:20:24 rivo volumio[3225]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Aug 31 09:20:24 rivo volumio[3225]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Aug 31 09:20:24 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 09:20:24 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 09:20:24 rivo volumio[3225]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 31 09:20:24 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 09:20:24 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 09:20:24 rivo systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Aug 31 09:20:24 rivo sudo[3766]: pam_unix(sudo:session): session closed for user root Aug 31 09:20:24 rivo volumio[3225]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 31 09:20:24 rivo volumio[3225]: info: Preparing CD Folders Aug 31 09:20:24 rivo volumio[3225]: info: Adding CD REST API Endpoints Aug 31 09:20:24 rivo volumio[3225]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Aug 31 09:20:24 rivo volumio[3225]: info: Starting UDEV Watcher for CD Aug 31 09:20:24 rivo volumio[3225]: info: Detecting CD presence with UDEV Aug 31 09:20:24 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Aug 31 09:20:24 rivo systemd[1]: mpd.service: Deactivated successfully. Aug 31 09:20:24 rivo systemd[1]: Stopped mpd.service - Music Player Daemon. Aug 31 09:20:24 rivo systemd[1]: mpd.socket: Deactivated successfully. Aug 31 09:20:24 rivo systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Aug 31 09:20:24 rivo systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Aug 31 09:20:24 rivo systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 31 09:20:24 rivo systemd[1]: Starting mpd.service - Music Player Daemon... Aug 31 09:20:24 rivo volumio5-onboarding[3780]: time=2026-08-31T09:20:24.794+02:00 level=INFO msg="running volumio4-device-gateway" version=2a6bbeeb+CHANGES buildDate=2026-07-03T07:51:28Z Aug 31 09:20:24 rivo sudo[3787]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 31 09:20:24 rivo sudo[3787]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 31 09:20:24 rivo sudo[3795]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Aug 31 09:20:24 rivo sudo[3787]: pam_unix(sudo:session): session closed for user root Aug 31 09:20:29 rivo mpd[3796]: 2026-08-31T09:20:29 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 31 09:20:29 rivo volumio[3225]: warn: [cd-plugin] cdspeedctl: device or media not ready Aug 31 09:20:29 rivo volumio[3225]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 31 09:20:29 rivo volumio[3225]: info: [1788160829455] CoreMusicLibrary::Adding element Last_100 Aug 31 09:20:29 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 31 09:20:29 rivo volumio[3225]: info: Adding qc_getconfig REST Endpoint for plugin: music_service/qobuzconnect Aug 31 09:20:29 rivo volumio[3225]: info: QobuzConnect: Starting Qobuz Connect socket and service Aug 31 09:20:29 rivo volumio[3225]: info: Starting RAAT Plugin Aug 31 09:20:29 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , addAdditionalUISections Aug 31 09:20:29 rivo volumio[3225]: info: Additional UI Settings Added for plugin music_service/raat Aug 31 09:20:29 rivo volumio[3225]: info: Registering DSP Elements listener and retrieving current ones Aug 31 09:20:29 rivo volumio[3225]: info: Additional DSP elements updated Aug 31 09:20:29 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 09:20:29 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 09:20:29 rivo volumio[3225]: info: Updating RAAT Signal Path Aug 31 09:20:29 rivo volumio[3225]: error: Cannot write to RAAT Client: TypeError: Cannot read properties of undefined (reading 'write') Aug 31 09:20:29 rivo volumio[3225]: info: Streaming services startup Aug 31 09:20:29 rivo volumio[3225]: info: Starting Streaming Daemon Aug 31 09:20:29 rivo sudo[3803]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Aug 31 09:20:29 rivo sudo[3803]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 09:20:29 rivo sudo[3803]: pam_unix(sudo:session): session closed for user root Aug 31 09:20:29 rivo volumio[3225]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 31 09:20:29 rivo volumio[3225]: info: [1788160829568] CoreMusicLibrary::Adding element Webradio Aug 31 09:20:29 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 31 09:20:29 rivo sudo[3808]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Aug 31 09:20:29 rivo sudo[3808]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 09:20:29 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 31 09:20:29 rivo volumio[3225]: info: Initializing BBC Radios Aug 31 09:20:29 rivo sudo[3814]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Aug 31 09:20:29 rivo sudo[3814]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 09:20:29 rivo sudo[3808]: pam_unix(sudo:session): session closed for user root Aug 31 09:20:29 rivo sudo[3816]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 31 09:20:29 rivo sudo[3816]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 09:20:29 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 31 09:20:29 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 09:20:29 rivo systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Aug 31 09:20:29 rivo sudo[3814]: pam_unix(sudo:session): session closed for user root Aug 31 09:20:29 rivo volumio[3225]: info: [now-playing] ConfigUpdater: config is up to date. Aug 31 09:20:29 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Aug 31 09:20:29 rivo volumio[3225]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Aug 31 09:20:29 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 09:20:29 rivo sudo[3816]: pam_unix(sudo:session): session closed for user root Aug 31 09:20:29 rivo volumio[3225]: info: Adding HIGHRESAUDIO REST API Endpoints Aug 31 09:20:29 rivo volumio[3225]: info: Adding saveAccountData_hi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Aug 31 09:20:29 rivo volumio[3225]: info: Initializing Serial Communication on port /dev/ttyS3 Aug 31 09:20:29 rivo kernel: meson_uart ffd22000.serial: ttyS3 use xtal(24M) 24000000 change 0 to 9600 Aug 31 09:20:29 rivo kernel: meson_uart ffd22000.serial: ttyS3 use xtal(24M) 24000000 change 9600 to 9600 Aug 31 09:20:29 rivo kernel: meson_uart ffd22000.serial: ttyS3 use xtal(24M) 24000000 change 9600 to 115200 Aug 31 09:20:29 rivo volumio[3225]: info: Touch Event Listener Process Starting Aug 31 09:20:29 rivo volumio[3225]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 31 09:20:29 rivo volumio[3225]: info: [1788160829847] CoreMusicLibrary::Adding element QOBUZ Aug 31 09:20:29 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 31 09:20:29 rivo volumio[3225]: Cannot find translation for source QOBUZ Aug 31 09:20:29 rivo volumio[3225]: info: Refreshing QOBUZ token Aug 31 09:20:29 rivo sudo[3839]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/xinput --test-xi2 --root Aug 31 09:20:29 rivo sudo[3839]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 09:20:29 rivo volumio[3225]: info: Adding inputs REST Endpoints Aug 31 09:20:29 rivo volumio[3225]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Aug 31 09:20:29 rivo volumio[3225]: info: Scanning Audio Inputs Aug 31 09:20:29 rivo volumio[3225]: info: Checking against Known Cards name Aug 31 09:20:29 rivo volumio[3225]: info: Checking against Known Cards name Aug 31 09:20:29 rivo volumio[3225]: info: Checking against Known Cards name Aug 31 09:20:29 rivo volumio[3225]: info: Checking against Known Cards name Aug 31 09:20:29 rivo volumio[3225]: info: Checking against Known Cards name Aug 31 09:20:29 rivo volumio[3225]: info: Adding Server instance for streaming Aug 31 09:20:29 rivo volumio[3225]: info: Adding TIDAL to Browse Sources Aug 31 09:20:29 rivo volumio[3225]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 31 09:20:29 rivo volumio[3225]: info: [1788160829923] CoreMusicLibrary::Adding element TIDAL Aug 31 09:20:29 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 31 09:20:29 rivo volumio[3225]: Cannot find translation for source QOBUZ Aug 31 09:20:29 rivo volumio[3225]: Cannot find translation for source TIDAL Aug 31 09:20:29 rivo volumio[3225]: info: Refreshing TIDAL token Aug 31 09:20:30 rivo sudo[3850]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x25 0x0 Aug 31 09:20:30 rivo sudo[3850]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 09:20:30 rivo sudo[3850]: pam_unix(sudo:session): session closed for user root Aug 31 09:20:30 rivo sudo[3853]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x28 0x0 Aug 31 09:20:30 rivo sudo[3853]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 09:20:30 rivo sudo[3853]: pam_unix(sudo:session): session closed for user root Aug 31 09:20:30 rivo sudo[3856]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x29 0x1 Aug 31 09:20:30 rivo sudo[3856]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 09:20:30 rivo sudo[3856]: pam_unix(sudo:session): session closed for user root Aug 31 09:20:30 rivo sudo[3860]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x33 0x1 Aug 31 09:20:30 rivo sudo[3860]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 09:20:30 rivo sudo[3860]: pam_unix(sudo:session): session closed for user root Aug 31 09:20:30 rivo volumio[3225]: info: Apply VIM3L Onboard LEDs Settings Aug 31 09:20:30 rivo volumio[3225]: info: Turning On Onboard LEDs Aug 31 09:20:30 rivo volumio[3225]: info: Starting CEC Event listener Aug 31 09:20:30 rivo volumio[3225]: info: Volumio Calling Home Aug 31 09:20:30 rivo sudo[3864]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/sys_led/trigger Aug 31 09:20:30 rivo sudo[3864]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 09:20:30 rivo sudo[3864]: pam_unix(sudo:session): session closed for user root Aug 31 09:20:30 rivo volumio[3225]: info: QobuzConnect: Opened /tmp/qbz-connect.socket socket, listening for connections Aug 31 09:20:30 rivo sudo[3870]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/red_red/trigger Aug 31 09:20:30 rivo sudo[3870]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 09:20:30 rivo volumio[3225]: (node:3225) [DEP0005] DeprecationWarning: Buffer() is deprecated due to security and usability issues. Please use the Buffer.alloc(), Buffer.allocUnsafe(), or Buffer.from() methods instead. Aug 31 09:20:30 rivo volumio[3225]: (Use `node --trace-deprecation ...` to show where the warning was created) Aug 31 09:20:30 rivo sudo[3870]: pam_unix(sudo:session): session closed for user root Aug 31 09:20:30 rivo systemd[1]: Started mpd.service - Music Player Daemon. Aug 31 09:20:30 rivo sudo[3757]: pam_unix(sudo:session): session closed for user root Aug 31 09:20:30 rivo sudo[3773]: pam_unix(sudo:session): session closed for user root Aug 31 09:20:30 rivo volumio[3225]: info: [now-playing] App is listening on port 4004. Aug 31 09:20:30 rivo volumio[3225]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Aug 31 09:20:30 rivo volumio[3225]: info: Serial port opened successfully Aug 31 09:20:30 rivo volumio[3225]: info: Sending serial start messages Aug 31 09:20:30 rivo kernel: cectx ff80023c.aocec: bad iniator with self:CEC RX len 5: 0f 87 00 00 f0 Aug 31 09:20:30 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 09:20:30 rivo volumio[3225]: info: Reporting MCU Network Status: 0 Aug 31 09:20:30 rivo volumio[3225]: info: MP1 GPIO: Signalled system ready via GPIO Aug 31 09:20:30 rivo volumio[3225]: error: Cannot start Volumio Streaming Daemon Aug 31 09:20:30 rivo volumio[3225]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Aug 31 09:20:30 rivo volumio[3225]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Aug 31 09:20:30 rivo volumio[3225]: info: RAAT Albumart path created successfully Aug 31 09:20:30 rivo volumio[3225]: info: MPD Permissions set Aug 31 09:20:30 rivo volumio[3225]: info: MPD Permissions set Aug 31 09:20:30 rivo volumio[3225]: error: MPD error: The expression evaluated to a falsy value: Aug 31 09:20:30 rivo volumio[3225]: assert.ok(self.idling) Aug 31 09:20:30 rivo volumio[3225]: error: The expression evaluated to a falsy value: Aug 31 09:20:30 rivo volumio[3225]: assert.ok(self.idling) Aug 31 09:20:30 rivo volumio[3225]: info: Volumio called home Aug 31 09:20:30 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 09:20:30 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 09:20:30 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 09:20:30 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 09:20:30 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 09:20:30 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 09:20:30 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 09:20:30 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 09:20:31 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 09:20:31 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Aug 31 09:20:31 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 09:20:31 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 09:20:31 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 09:20:31 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 09:20:31 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 09:20:31 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 09:20:31 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 09:20:31 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 09:20:31 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 09:20:31 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 09:20:31 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Aug 31 09:20:31 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 09:20:31 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setDeviceVolumeOverride Aug 31 09:20:31 rivo volumio[3225]: info: Setting Device Volume Override Aug 31 09:20:31 rivo volumio[3225]: info: CoreCommandRouter::volumioUpdateVolumeSettings Aug 31 09:20:31 rivo volumio[3225]: info: Updating Volume Controller Parameters: Device: 5 Name: X-SABRE Mixer: X-SABRE Output Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Aug 31 09:20:31 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Aug 31 09:20:31 rivo volumio[3225]: info: Disabling external Volume Control Aug 31 09:20:31 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 09:20:31 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 09:20:31 rivo volumio[3225]: info: CoreStateMachine::pushState Aug 31 09:20:31 rivo volumio[3225]: info: CorePlayQueue::getTrack 0 Aug 31 09:20:31 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 09:20:31 rivo volumio[3225]: info: CoreCommandRouter::volumioPushState Aug 31 09:20:31 rivo volumio[3225]: info: CoreCommandRouter::volumioGetState Aug 31 09:20:31 rivo volumio[3225]: info: CorePlayQueue::getTrack 0 Aug 31 09:20:31 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: system , setAdditionalSVInfo Aug 31 09:20:31 rivo volumio[3225]: info: Setting Additional System Software info: Hardware Revision: 2.0 Aug 31 09:20:31 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: system , setHwFwVersion Aug 31 09:20:31 rivo volumio[3225]: info: Setting HW Firmware info: undefined Aug 31 09:20:31 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: system , setHwVersion Aug 31 09:20:31 rivo volumio[3225]: info: Setting HW Version info: 2.0 Aug 31 09:20:31 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: system , setAdditionalSVInfo Aug 31 09:20:31 rivo volumio[3225]: info: Setting Additional System Software info: Hardware Revision: 2.0, Firmware Version: 0.3.5 Aug 31 09:20:31 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: system , setHwFwVersion Aug 31 09:20:31 rivo volumio[3225]: info: Setting HW Firmware info: 0.3.5 Aug 31 09:20:31 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: system , setHwVersion Aug 31 09:20:31 rivo volumio[3225]: info: Setting HW Version info: 2.0 Aug 31 09:20:31 rivo volumio[3225]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Aug 31 09:20:31 rivo volumio[3225]: info: MCU Signalled Headphone Mode Disabled Aug 31 09:20:31 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Aug 31 09:20:31 rivo volumio[3225]: info: Reporting Headphone State: false Aug 31 09:20:31 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 09:20:31 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 09:20:31 rivo volumio[3225]: info: Updating RAAT Signal Path Aug 31 09:20:31 rivo volumio[3225]: error: Cannot write to RAAT Client: TypeError: Cannot read properties of undefined (reading 'write') Aug 31 09:20:31 rivo volumio[3225]: info: MCU Signalled Sleep Mode Disabled Aug 31 09:20:31 rivo volumio[3225]: info: Enabling Advanced system settings configuration Aug 31 09:20:31 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: system , addAdditionalUISections Aug 31 09:20:31 rivo volumio[3225]: info: Additional UI Settings Added for plugin music_service/inputs Aug 31 09:20:31 rivo volumio[3225]: info: MCU Signalled Auto Boot Mode On Power Disabled Aug 31 09:20:31 rivo sudo[3897]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms force on Aug 31 09:20:31 rivo sudo[3897]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 09:20:31 rivo volumio[3225]: error: updateQueue error: null Aug 31 09:20:31 rivo kernel: fb: osd[0] enable: 1 (Xorg) Aug 31 09:20:31 rivo volumio[3225]: info: MPD running with PID3796 Aug 31 09:20:31 rivo volumio[3225]: ,establishing connection Aug 31 09:20:31 rivo sudo[3897]: pam_unix(sudo:session): session closed for user root Aug 31 09:20:31 rivo volumio[3225]: error: Serial API: Failed to decode command: MAXVOL, message: 100 Aug 31 09:20:31 rivo volumio[3225]: 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 Aug 31 09:20:31 rivo volumio[3225]: info: VolumeController:: alsactl monitor closed, code: 0 Aug 31 09:20:31 rivo volumio[3225]: info: VolumeController:: Starting alsactl monitor Aug 31 09:20:31 rivo sudo[3903]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms 0 0 0 Aug 31 09:20:31 rivo sudo[3903]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 09:20:31 rivo sudo[3903]: pam_unix(sudo:session): session closed for user root Aug 31 09:20:31 rivo volumio[3225]: 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 Aug 31 09:20:31 rivo volumio[3225]: info: Received Get System Info Aug 31 09:20:31 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 31 09:20:31 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 31 09:20:31 rivo volumio[3225]: info: Discovery: Getting this device information Aug 31 09:20:31 rivo volumio[3225]: info: CoreCommandRouter::volumioGetState Aug 31 09:20:31 rivo volumio[3225]: info: CorePlayQueue::getTrack 0 Aug 31 09:20:31 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 31 09:20:31 rivo volumio5-onboarding[3780]: time=2026-08-31T09:20:31.342+02:00 level=INFO msg="system info for f86ae99edda56fd241971db7ab04d4e5" deviceName=Rivo deviceVariant=rivo deviceModel="Volumio Rivo" softwareVersion=4.185 Aug 31 09:20:31 rivo volumio5-onboarding[3780]: time=2026-08-31T09:20:31.357+02:00 level=INFO msg="bootstrapping state" hasInternet=true Aug 31 09:20:31 rivo volumio[3225]: info: Executing endpoint qc_getconfig Aug 31 09:20:31 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Aug 31 09:20:31 rivo qobuz-connect[3829]: 20260831 09:20:31.431 [3829.3829] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Aug 31 09:20:31 rivo volumio[3225]: info: Starting Shairport Sync Aug 31 09:20:31 rivo volumio[3225]: info: Starting Shairport Sync Aug 31 09:20:31 rivo volumio[3225]: info: Starting Shairport Sync Aug 31 09:20:31 rivo sudo[3910]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 31 09:20:31 rivo sudo[3910]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 09:20:31 rivo volumio[3225]: info: Received Get System Info Aug 31 09:20:31 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 31 09:20:31 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 31 09:20:31 rivo volumio[3225]: info: Discovery: Getting this device information Aug 31 09:20:31 rivo volumio[3225]: info: CoreCommandRouter::volumioGetState Aug 31 09:20:31 rivo volumio[3225]: info: CorePlayQueue::getTrack 0 Aug 31 09:20:31 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 31 09:20:31 rivo sudo[3912]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 31 09:20:31 rivo sudo[3912]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 09:20:31 rivo volumio[3225]: error: updateQueue error: null Aug 31 09:20:31 rivo volumio[3225]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Aug 31 09:20:31 rivo volumio[3225]: info: QobuzConnect: QOBUZ Connect daemon connected Aug 31 09:20:31 rivo sudo[3915]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 31 09:20:31 rivo sudo[3915]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 09:20:31 rivo volumio[3225]: info: VolumeController:: Volume=100 Mute =false Aug 31 09:20:31 rivo volumio[3225]: info: CoreStateMachine::pushState Aug 31 09:20:31 rivo volumio[3225]: info: CorePlayQueue::getTrack 0 Aug 31 09:20:31 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 09:20:31 rivo volumio[3225]: info: CoreCommandRouter::volumioPushState Aug 31 09:20:31 rivo volumio[3225]: info: CoreCommandRouter::volumioGetState Aug 31 09:20:31 rivo volumio[3225]: info: CorePlayQueue::getTrack 0 Aug 31 09:20:31 rivo volumio[3225]: info: Adding Inputs via Serial API Aug 31 09:20:31 rivo qobuz-connect[3829]: 20260831 09:20:31.557 [3829.3829] INFO VolumeManager: [0xac459f68]: Setting new playback volume: 75 Aug 31 09:20:31 rivo qobuz-connect[3829]: 20260831 09:20:31.557 [3829.3829] INFO VolumeManager: [0xac459f68]: Setting new mute state: 0 Aug 31 09:20:31 rivo qobuz-connect[3829]: 20260831 09:20:31.557 [3829.3829] INFO AudioStreamManager: [0xac459cc0]: Setting new audio download buffer size: 1048576 Aug 31 09:20:31 rivo qobuz-connect[3829]: 20260831 09:20:31.557 [3829.3829] INFO QobuzConnect: [0xac45a830]: Client initialized! Aug 31 09:20:31 rivo qobuz-connect[3829]: 20260831 09:20:31.557 [3829.3829] INFO SampleApp: Starting Avahi advertising, name: Rivo, service name: _qobuz-connect._tcp Aug 31 09:20:31 rivo systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Aug 31 09:20:31 rivo systemd[1]: shairport-sync.service: Deactivated successfully. Aug 31 09:20:31 rivo systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 31 09:20:31 rivo volumio-remote-updater[2940]: Test mode enabled Aug 31 09:20:31 rivo volumio-remote-updater[2940]: Alpha mode disabled Aug 31 09:20:31 rivo volumio-remote-updater[2940]: Alpha legacy test mode disabled Aug 31 09:20:31 rivo qobuz-connect[3829]: 20260831 09:20:31.597 [3829.3829] INFO LocalConfigManager: [0xac4599e8]: Starting Local Configuration server Aug 31 09:20:31 rivo qobuz-connect[3829]: 20260831 09:20:31.597 [3829.3829] INFO SampleApp: Starting Local configuration server Aug 31 09:20:31 rivo qobuz-connect[3829]: 20260831 09:20:31.597 [3829.3829] INFO SampleApp: Connected to UNIX socket client 0xac444908 Aug 31 09:20:31 rivo qobuz-connect[3829]: 20260831 09:20:31.598 [3829.3829] INFO SampleApp: Playback volume changed: 75 Aug 31 09:20:31 rivo volumio[3225]: info: CoreCommandRouter::servicePushState Aug 31 09:20:31 rivo volumio[3225]: info: CoreStateMachine::pushState Aug 31 09:20:31 rivo volumio[3225]: info: CorePlayQueue::getTrack 0 Aug 31 09:20:31 rivo volumio[3225]: info: CoreCommandRouter::volumioPushState Aug 31 09:20:31 rivo volumio[3225]: info: CoreCommandRouter::volumioGetState Aug 31 09:20:31 rivo volumio[3225]: info: CorePlayQueue::getTrack 0 Aug 31 09:20:31 rivo volumio[3225]: info: CorePlayQueue::getTrack 0 Aug 31 09:20:31 rivo volumio[3225]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received inputs Aug 31 09:20:31 rivo volumio[3225]: info: CoreCommandRouter::volumiosetSourceActiveno-source Aug 31 09:20:31 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 31 09:20:31 rivo volumio[3225]: Cannot find translation for source QOBUZ Aug 31 09:20:31 rivo volumio[3225]: Cannot find translation for source TIDAL Aug 31 09:20:31 rivo systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 31 09:20:31 rivo volumio[3225]: info: CoreCommandRouter::volumioGetState Aug 31 09:20:31 rivo volumio[3225]: info: CorePlayQueue::getTrack 0 Aug 31 09:20:31 rivo sudo[3912]: pam_unix(sudo:session): session closed for user root Aug 31 09:20:31 rivo sudo[3910]: pam_unix(sudo:session): session closed for user root Aug 31 09:20:31 rivo sudo[3915]: pam_unix(sudo:session): session closed for user root Aug 31 09:20:31 rivo volumio[3225]: info: Shairport-Sync Started Aug 31 09:20:31 rivo volumio[3225]: Error adding Membership: Error: addMembership EINVAL Aug 31 09:20:31 rivo volumio[3225]: info: Shairport-Sync Started Aug 31 09:20:31 rivo volumio[3225]: info: Shairport-Sync Started Aug 31 09:20:31 rivo volumio[3225]: info: CoreStateMachine::pushState Aug 31 09:20:31 rivo volumio[3225]: info: CorePlayQueue::getTrack 0 Aug 31 09:20:31 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 09:20:31 rivo volumio[3225]: info: CoreCommandRouter::volumioPushState Aug 31 09:20:31 rivo volumio[3225]: info: CoreCommandRouter::volumioGetState Aug 31 09:20:31 rivo volumio[3225]: info: CorePlayQueue::getTrack 0 Aug 31 09:20:31 rivo kernel: cectx ff80023c.aocec: bad iniator with self:CEC RX len 5: 0f 87 00 00 f0 Aug 31 09:20:31 rivo volumio[3225]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Aug 31 09:20:31 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Aug 31 09:20:31 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 31 09:20:31 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 31 09:20:31 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 31 09:20:31 rivo volumio[3225]: info: Received Get System Info Aug 31 09:20:31 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 31 09:20:31 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 31 09:20:31 rivo volumio[3225]: info: Discovery: Getting this device information Aug 31 09:20:31 rivo volumio[3225]: info: CoreCommandRouter::volumioGetState Aug 31 09:20:31 rivo volumio[3225]: info: CorePlayQueue::getTrack 0 Aug 31 09:20:31 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 31 09:20:31 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 31 09:20:31 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 31 09:20:31 rivo volumio5-onboarding[3780]: time=2026-08-31T09:20:31.829+02:00 level=INFO msg="enabling local network discovery" Aug 31 09:20:31 rivo volumio5-onboarding[3780]: time=2026-08-31T09:20:31.830+02:00 level=INFO msg="enabling BLE discovery" Aug 31 09:20:32 rivo volumio5-onboarding[3780]: time=2026-08-31T09:20:32.677+02:00 level=INFO msg="service successfully established" component=discovery/localnet Aug 31 09:20:32 rivo volumio[3225]: info: Turning Off Onboard LEDs Aug 31 09:20:32 rivo sudo[3960]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/sys_led/trigger Aug 31 09:20:32 rivo sudo[3960]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 09:20:32 rivo sudo[3960]: pam_unix(sudo:session): session closed for user root Aug 31 09:20:32 rivo volumio[3225]: info: TidalConnect service stoped! Aug 31 09:20:32 rivo sudo[3964]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/red_red/trigger Aug 31 09:20:32 rivo sudo[3964]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 09:20:32 rivo sudo[3964]: pam_unix(sudo:session): session closed for user root Aug 31 09:20:32 rivo volumio[3225]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Aug 31 09:20:32 rivo volumio[3225]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Aug 31 09:20:32 rivo volumio[3225]: info: Onboard LEDs initialized Aug 31 09:20:32 rivo sudo[3974]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Aug 31 09:20:32 rivo sudo[3974]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 09:20:32 rivo systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Aug 31 09:20:32 rivo sudo[3974]: pam_unix(sudo:session): session closed for user root Aug 31 09:20:33 rivo volumio[3225]: info: Reporting MCU Network Status: 1 Aug 31 09:20:33 rivo volumio[3225]: info: Volumio Network Manager: Network status updated: 1 Aug 31 09:20:33 rivo volumio[3225]: info: Executing endpoint tc_getconfig Aug 31 09:20:33 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Aug 31 09:20:33 rivo vtcs[3978]: STARTING TidalConnect services, version: 1.6.1 Aug 31 09:20:33 rivo vtcs[3978]: STARTED TidalConnect services. Aug 31 09:20:33 rivo volumio[3225]: info: Executing endpoint tc_connect Aug 31 09:20:33 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Aug 31 09:20:33 rivo volumio[3225]: info: Connecting to TidalConnect Aug 31 09:20:33 rivo volumio[3225]: info: CoreCommandRouter::servicePushState Aug 31 09:20:33 rivo volumio[3225]: info: CoreStateMachine::pushState Aug 31 09:20:33 rivo volumio[3225]: info: CorePlayQueue::getTrack 0 Aug 31 09:20:33 rivo volumio[3225]: info: CoreCommandRouter::volumioPushState Aug 31 09:20:33 rivo volumio[3225]: info: CoreCommandRouter::volumioGetState Aug 31 09:20:33 rivo volumio[3225]: info: CorePlayQueue::getTrack 0 Aug 31 09:20:33 rivo volumio[3225]: info: CorePlayQueue::getTrack 0 Aug 31 09:20:33 rivo volumio[3225]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received tidalconnect Aug 31 09:20:33 rivo volumio[3225]: info: CoreCommandRouter::servicePushState Aug 31 09:20:33 rivo volumio[3225]: info: CoreStateMachine::pushState Aug 31 09:20:33 rivo volumio[3225]: info: CorePlayQueue::getTrack 0 Aug 31 09:20:33 rivo volumio[3225]: info: CoreCommandRouter::volumioPushState Aug 31 09:20:33 rivo volumio[3225]: info: CoreCommandRouter::volumioGetState Aug 31 09:20:33 rivo volumio[3225]: info: CorePlayQueue::getTrack 0 Aug 31 09:20:33 rivo volumio[3225]: info: CorePlayQueue::getTrack 0 Aug 31 09:20:33 rivo volumio[3225]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received tidalconnect Aug 31 09:20:34 rivo volumio[3225]: info: MRS: Getting audio outputs on start Aug 31 09:20:34 rivo volumio[3225]: info: MRS: Requesting all other devices output Aug 31 09:20:35 rivo sudo[4013]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 31 09:20:35 rivo sudo[4014]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 31 09:20:35 rivo sudo[4013]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 09:20:35 rivo sudo[4014]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 09:20:35 rivo sudo[4013]: pam_unix(sudo:session): session closed for user root Aug 31 09:20:35 rivo sudo[4014]: pam_unix(sudo:session): session closed for user root Aug 31 09:20:35 rivo volumio[3225]: verbose: New Socket.io Connection to 192.168.0.10 from 192.168.0.16 UA: Mozilla/5.0 (iPad; CPU OS 9_3_5 like Mac OS X) AppleWebKit/601.1.46 (KHTML, like Gecko) Mobile/13G36 Engine version: 3 Transport: polling Total Clients: 3 Aug 31 09:20:35 rivo volumio[3225]: info: TidalConnect service started! Aug 31 09:20:36 rivo systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Aug 31 09:20:36 rivo kernel: fb: mem_free_work, free memory: addr:800000 Aug 31 09:20:37 rivo systemd[1]: systemd-hostnamed.service: Deactivated successfully. Aug 31 09:20:37 rivo systemd[1]: setdatetime-helper.service: Deactivated successfully. Aug 31 09:20:37 rivo systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Aug 31 09:20:37 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 31 09:20:37 rivo volumio[3225]: info: CoreCommandRouter::volumioGetVisibleSources Aug 31 09:20:37 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 31 09:20:37 rivo volumio[3225]: info: CoreCommandRouter::volumioGetState Aug 31 09:20:37 rivo volumio[3225]: info: CorePlayQueue::getTrack 0 Aug 31 09:20:37 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 31 09:20:37 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 31 09:20:37 rivo volumio[3225]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Aug 31 09:20:37 rivo volumio[3225]: info: Received Get System Info Aug 31 09:20:37 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 31 09:20:37 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 31 09:20:37 rivo volumio[3225]: info: Discovery: Getting this device information Aug 31 09:20:37 rivo volumio[3225]: info: CoreCommandRouter::volumioGetState Aug 31 09:20:37 rivo volumio[3225]: info: CorePlayQueue::getTrack 0 Aug 31 09:20:37 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 31 09:20:37 rivo volumio[3225]: info: CoreCommandRouter::volumioGetState Aug 31 09:20:37 rivo volumio[3225]: info: CorePlayQueue::getTrack 0 Aug 31 09:20:37 rivo volumio[3225]: info: Listing playlists Aug 31 09:20:37 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 31 09:20:37 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 31 09:20:38 rivo systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 3. Aug 31 09:20:38 rivo systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Aug 31 09:20:38 rivo systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Aug 31 09:20:38 rivo sudo[3751]: pam_unix(sudo:session): session closed for user root Aug 31 09:20:38 rivo volumio[3225]: info: Upmpdcli Daemon Started Aug 31 09:20:38 rivo volumio[3225]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Aug 31 09:20:39 rivo sudo[4102]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 31 09:20:39 rivo sudo[4102]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 09:20:39 rivo sudo[4102]: pam_unix(sudo:session): session closed for user root Aug 31 09:20:39 rivo sudo[4104]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 31 09:20:39 rivo sudo[4104]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 09:20:40 rivo sudo[4104]: pam_unix(sudo:session): session closed for user root Aug 31 09:20:40 rivo volumio[3225]: info: Checking for updated MCU Firmware Aug 31 09:20:40 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 31 09:20:40 rivo volumio[3225]: info: Firware on device is on latest version, no need to update Aug 31 09:20:41 rivo volumio[3225]: verbose: New Socket.io Connection to 192.168.0.10 from 192.168.0.16 UA: Mozilla/5.0 (iPad; CPU OS 9_3_5 like Mac OS X) AppleWebKit/601.1.46 (KHTML, like Gecko) Mobile/13G36 Engine version: 3 Transport: polling Total Clients: 4 Aug 31 09:20:52 rivo volumio[3225]: info: Discovery: adding 03ab7cbd-71ad-4a1f-bf87-b8c8b0e4a6d1 Aug 31 09:20:52 rivo volumio[3225]: info: Discovery: Found device Rivo Aug 31 09:20:52 rivo volumio[3225]: info: CoreCommandRouter::volumioGetState Aug 31 09:20:52 rivo volumio[3225]: info: CorePlayQueue::getTrack 0 Aug 31 09:20:52 rivo volumio[3225]: info: MRS: Pushing multiroomSync output for this device Aug 31 09:20:52 rivo volumio[3225]: info: MRS: Pushing multiroomSync output Aug 31 09:20:52 rivo volumio[3225]: info: Adding audio output: Aug 31 09:20:52 rivo volumio[3225]: info: Adding audio output: Aug 31 09:20:52 rivo volumio[3225]: info: Discovery: this is already registered, 03ab7cbd-71ad-4a1f-bf87-b8c8b0e4a6d1 Aug 31 09:20:52 rivo volumio[3225]: info: Discovery: Found device Rivo Aug 31 09:20:52 rivo volumio[3225]: info: CoreCommandRouter::volumioGetState Aug 31 09:20:52 rivo volumio[3225]: info: CorePlayQueue::getTrack 0 Aug 31 09:20:54 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 31 09:20:57 rivo volumio[3225]: info: Access Token successfully retrieved Aug 31 09:20:57 rivo volumio[3225]: info: Stopping AccessToken refresher cron for QOBUZ Aug 31 09:20:57 rivo volumio[3225]: info: AccessToken refresher cron started for QOBUZ Aug 31 09:20:57 rivo volumio[3225]: info: Adding QOBUZ REST API Endpoints Aug 31 09:20:59 rivo volumio[3225]: info: Access Token successfully retrieved Aug 31 09:20:59 rivo volumio[3225]: error: Hi Res Audio Failed Login: [object Object] Aug 31 09:21:00 rivo volumio[3225]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Aug 31 09:21:00 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 31 09:21:00 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 09:21:00 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 31 09:21:00 rivo volumio[3225]: error: Plugin music_service tidal failed to complete 'onStart' in a timely fashion Aug 31 09:21:00 rivo volumio[3225]: info: ------------------------------------------- Aug 31 09:21:00 rivo volumio[3225]: info: ----- MyVolumio plugins startup ---- Aug 31 09:21:00 rivo volumio[3225]: info: ------------------------------------------- Aug 31 09:21:00 rivo volumio[3225]: info: [MyVolumio PluginManager] Fetching plans data.... Aug 31 09:21:02 rivo volumio[3225]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Aug 31 09:21:12 rivo upmpdcli[4458]: writing RSA key Aug 31 09:21:21 rivo volumio[3225]: info: MRS: Found cast device: CHSTB02-88837a1d9d2011fd891ba3df03e82fab Aug 31 09:21:21 rivo volumio[3225]: info: Adding audio output: Aug 31 09:21:21 rivo volumio[3225]: info: MRS: Found cast device: CHSTB02-2c07cb952969f3ad2bbb27b23c87610d Aug 31 09:21:21 rivo volumio[3225]: info: Adding audio output: Aug 31 09:21:21 rivo volumio[3225]: error: MyVolumio Plugin failed to start in a timely fashion Aug 31 09:21:21 rivo volumio[3225]: [Metrics] CommandRouter: 82s 193.83ms Aug 31 09:21:21 rivo volumio[3225]: info: CoreCommandRouter::volumiosetStartupVolume Aug 31 09:21:21 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 09:21:21 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 09:21:21 rivo volumio[3225]: info: CoreCommandRouter::Close All Modals sent Aug 31 09:21:21 rivo volumio[3225]: info: CoreCommandRouter::Close All Modals sent Aug 31 09:21:23 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Aug 31 09:21:23 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 31 09:21:23 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Aug 31 09:21:25 rivo volumio[3225]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Aug 31 09:21:28 rivo volumio[3225]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Aug 31 09:21:28 rivo volumio[3225]: info: BOOT COMPLETED Aug 31 09:21:37 rivo volumio[3225]: info: Successfully retrieved User Session From TIDAL Aug 31 09:21:37 rivo volumio[3225]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Aug 31 09:21:37 rivo volumio[3225]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Aug 31 09:21:37 rivo volumio[3225]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Aug 31 09:21:37 rivo volumio[3225]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Aug 31 09:21:37 rivo volumio[3225]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Aug 31 09:21:37 rivo volumio[3225]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Aug 31 09:21:37 rivo volumio[3225]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Aug 31 09:21:37 rivo volumio[3225]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Aug 31 09:21:37 rivo volumio[3225]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Aug 31 09:21:37 rivo volumio[3225]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Aug 31 09:21:37 rivo volumio[3225]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Aug 31 09:21:37 rivo volumio[3225]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Aug 31 09:21:37 rivo volumio[3225]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Aug 31 09:21:37 rivo volumio[3225]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Aug 31 09:21:37 rivo volumio[3225]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Aug 31 09:21:37 rivo volumio[3225]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Aug 31 09:21:37 rivo volumio[3225]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Aug 31 09:21:37 rivo volumio[3225]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Aug 31 09:21:37 rivo volumio[3225]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Aug 31 09:21:37 rivo volumio[3225]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Aug 31 09:21:37 rivo volumio[3225]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Aug 31 09:21:37 rivo volumio[3225]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Aug 31 09:21:37 rivo volumio[3225]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Aug 31 09:21:37 rivo volumio[3225]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Aug 31 09:21:37 rivo volumio[3225]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Aug 31 09:21:37 rivo volumio[3225]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Aug 31 09:21:37 rivo volumio[3225]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Aug 31 09:21:37 rivo volumio[3225]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Aug 31 09:21:37 rivo volumio[3225]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Aug 31 09:21:39 rivo volumio[3225]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Aug 31 09:21:39 rivo volumio[3225]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Aug 31 09:21:39 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 09:21:39 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 09:21:39 rivo volumio[3225]: info: Starting MyVolumio Remote Streaming Endpoints Aug 31 09:21:39 rivo volumio[3225]: info: MyVolumio login type: Token Aug 31 09:21:39 rivo volumio[3225]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Aug 31 09:21:39 rivo volumio[3225]: error: [MyVolumio PluginManager] Could not read package.json file: Error: /myvolumio/plugins/music_service/streaming_services//package.json: ENOENT: no such file or directory, open '/myvolumio/plugins/music_service/streaming_services//package.json' Aug 31 09:21:39 rivo volumio[3225]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Aug 31 09:21:39 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 09:21:39 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 09:21:39 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 09:21:39 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 09:21:39 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 09:21:39 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 09:21:39 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 09:21:39 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 09:21:39 rivo volumio[3225]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 09:21:39 rivo volumio[3225]: info: Not Reporting Auto name since its the default one Aug 31 09:21:39 rivo volumio[3225]: error: error Aug 31 09:21:39 rivo volumio[3225]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 31 09:21:39 rivo volumio[3225]: Error [ERR_INTERNAL_ASSERTION]: This is caused by either a bug in Node.js or incorrect usage of Node.js internals. Aug 31 09:21:39 rivo volumio[3225]: Please open an issue with this stack trace at https://github.com/nodejs/node/issues Aug 31 09:21:39 rivo volumio[3225]: at new NodeError (node:internal/errors:405:5) Aug 31 09:21:39 rivo volumio[3225]: at assert (node:internal/assert:14:11) Aug 31 09:21:39 rivo volumio[3225]: at internalConnectMultiple (node:net:1118:3) Aug 31 09:21:39 rivo volumio[3225]: at Timeout.internalConnectMultipleTimeout (node:net:1687:3) Aug 31 09:21:39 rivo volumio[3225]: at listOnTimeout (node:internal/timers:575:11) Aug 31 09:21:39 rivo volumio[3225]: at process.processTimers (node:internal/timers:514:7) { Aug 31 09:21:39 rivo volumio[3225]: code: 'ERR_INTERNAL_ASSERTION' Aug 31 09:21:39 rivo volumio[3225]: } Aug 31 09:21:39 rivo volumio[3225]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 31 09:21:40 rivo sudo[4571]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-31 09:20' Aug 31 09:21:40 rivo sudo[4571]: 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="ad2b65e62ee66106fc9799a5d56d019303babfad" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="9938d7179e3b7c4e41f3e2d60c255985cff08fee" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed Jul 29 10:41:31 UTC 2026" VOLUMIO_VERSION="4.185" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="8dbed02e26c8684a8f81563b979083f3"