Aug 27 09:09:00 rivo systemd[1]: rc-local.service: Control process exited, code=exited, status=2/INVALIDARGUMENT Aug 27 09:09:00 rivo systemd[1]: rc-local.service: Failed with result 'exit-code'. Aug 27 09:09:00 rivo ntpd[3130]: INIT: ntpd ntpsec-1.2.2: Starting Aug 27 09:09:00 rivo ntp-systemd-wrapper[3130]: 2026-08-27T09:09:00 ntpd[3130]: INIT: ntpd ntpsec-1.2.2: Starting Aug 27 09:09:00 rivo ntp-systemd-wrapper[3130]: 2026-08-27T09:09:00 ntpd[3130]: INIT: Command line: /usr/sbin/ntpd -p /run/ntpd.pid -c /etc/ntpsec/ntp.conf -g -N -u ntpsec:ntpsec Aug 27 09:09:00 rivo ntpd[3130]: INIT: Command line: /usr/sbin/ntpd -p /run/ntpd.pid -c /etc/ntpsec/ntp.conf -g -N -u ntpsec:ntpsec Aug 27 09:09:00 rivo systemd[1]: Failed to start rc-local.service - /etc/rc.local Compatibility. Aug 27 09:09:00 rivo welcome[3133]: Resolved ip:[0] Aug 27 09:09:00 rivo systemd[1]: Started ntpsec.service - Network Time Service. Aug 27 09:09:00 rivo systemd[1]: Started rpc-statd-notify.service - Notify NFS peers of a restart. Aug 27 09:09:00 rivo systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Aug 27 09:09:00 rivo ntpd[3140]: INIT: precision = 1.541 usec (-19) Aug 27 09:09:00 rivo ntpd[3140]: INIT: successfully locked into RAM Aug 27 09:09:00 rivo ntpd[3140]: CONFIG: readconfig: parsing file: /etc/ntpsec/ntp.conf Aug 27 09:09:00 rivo ntpd[3140]: CONFIG: restrict nopeer ignored Aug 27 09:09:00 rivo ntpd[3140]: CLOCK: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Aug 27 09:09:00 rivo ntpd[3140]: CLOCK: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2026-12-28T00:00Z last=2017-01-01T00:00Z ofs=37 Aug 27 09:09:00 rivo ntpd[3140]: INIT: Using SO_TIMESTAMPNS(ns) Aug 27 09:09:00 rivo ntpd[3140]: IO: Listen and drop on 0 v6wildcard [::]:123 Aug 27 09:09:00 rivo ntpd[3140]: IO: Listen and drop on 1 v4wildcard 0.0.0.0:123 Aug 27 09:09:00 rivo ntpd[3140]: IO: Listen normally on 2 lo 127.0.0.1:123 Aug 27 09:09:00 rivo ntpd[3140]: IO: Listening on routing socket on fd #19 for interface updates Aug 27 09:09:00 rivo lircd-0.10.1[3098]: Info: Initial device: auto Aug 27 09:09:00 rivo lircd-0.10.1[3098]: Info: Initial device: auto Aug 27 09:09:00 rivo lircd-0.10.1[3098]: Notice: Options: driver: devinput Aug 27 09:09:00 rivo lircd[3098]: lircd-0.10.1[3098]: Info: lircd: Opening log, level: Info Aug 27 09:09:00 rivo lircd-0.10.1[3098]: Notice: Options: output: /var/run/lirc/lircd Aug 27 09:09:00 rivo lircd-0.10.1[3098]: Notice: Options: nodaemon: 1 Aug 27 09:09:00 rivo lircd-0.10.1[3098]: Notice: Options: plugindir: /usr/lib/arm-linux-gnueabihf/lirc/plugins Aug 27 09:09:00 rivo lircd-0.10.1[3098]: Notice: Options: logfile: syslog Aug 27 09:09:00 rivo lircd-0.10.1[3098]: Notice: Options: immediate-init: 0 Aug 27 09:09:00 rivo lircd-0.10.1[3098]: Notice: Options: permission: 666 Aug 27 09:09:00 rivo lircd[3098]: lircd-0.10.1[3098]: Notice: Using systemd fd Aug 27 09:09:00 rivo lircd[3098]: lircd-0.10.1[3098]: Warning: Running as root Aug 27 09:09:00 rivo lircd-0.10.1[3098]: Notice: Options: driver-options: Aug 27 09:09:00 rivo lircd-0.10.1[3098]: Notice: Options: pidfile: /var/run/lirc/lircd.pid Aug 27 09:09:00 rivo lircd[3098]: lircd-0.10.1[3098]: Info: Using remote: devinput-64. Aug 27 09:09:00 rivo lircd-0.10.1[3098]: Notice: Options: listen: 0 Aug 27 09:09:00 rivo lircd-0.10.1[3098]: Notice: Options: connect: (null) Aug 27 09:09:00 rivo wireless.js[2957]: WIRELESS.JS - INFO: Single Network Mode enabled (default) - only one network device can be active at a time between ethernet and wireless Aug 27 09:09:00 rivo lircd-0.10.1[3098]: Notice: Options: userelease: 0 Aug 27 09:09:00 rivo lircd-0.10.1[3098]: Notice: Options: effective_user: (null) Aug 27 09:09:00 rivo lircd-0.10.1[3098]: Notice: Options: release_suffix: _EVUP Aug 27 09:09:00 rivo lircd[3098]: lircd-0.10.1[3098]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-64: Multiple values for same code: BTN_MISC Aug 27 09:09:00 rivo lircd[3098]: lircd-0.10.1[3098]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-64: Multiple values for same code: BTN_MOUSE Aug 27 09:09:00 rivo lircd[3098]: lircd-0.10.1[3098]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-64: Multiple values for same code: BTN_SOUTH Aug 27 09:09:00 rivo lircd[3098]: lircd-0.10.1[3098]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-64: Multiple values for same code: BTN_TOOL_PEN Aug 27 09:09:00 rivo lircd[3098]: lircd-0.10.1[3098]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-64: Multiple values for same code: BTN_TRIGGER Aug 27 09:09:00 rivo lircd[3098]: lircd-0.10.1[3098]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-64: Multiple values for same code: BTN_TRIGGER_HAPPY1 Aug 27 09:09:00 rivo lircd[3098]: lircd-0.10.1[3098]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-64: Multiple values for same code: BTN_WHEEL Aug 27 09:09:00 rivo lircd[3098]: lircd-0.10.1[3098]: Info: Using remote: devinput-32. Aug 27 09:09:00 rivo lircd-0.10.1[3098]: Notice: Options: allow_simulate: 0 Aug 27 09:09:00 rivo lircd-0.10.1[3098]: Notice: Options: repeat_max: 600 Aug 27 09:09:00 rivo lircd-0.10.1[3098]: Notice: Options: configfile: /etc/lirc/lircd.conf Aug 27 09:09:00 rivo lircd-0.10.1[3098]: Notice: Options: dynamic_codes: (null) Aug 27 09:09:00 rivo lircd-0.10.1[3098]: Notice: Current driver: devinput Aug 27 09:09:00 rivo lircd-0.10.1[3098]: Notice: Driver API version: 4 Aug 27 09:09:00 rivo lircd-0.10.1[3098]: Notice: Driver version: 0.10.0 Aug 27 09:09:00 rivo lircd[3098]: lircd-0.10.1[3098]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-32: Multiple values for same code: BTN_MISC Aug 27 09:09:00 rivo lircd[3098]: lircd-0.10.1[3098]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-32: Multiple values for same code: BTN_MOUSE Aug 27 09:09:00 rivo lircd[3098]: lircd-0.10.1[3098]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-32: Multiple values for same code: BTN_SOUTH Aug 27 09:09:00 rivo lircd-0.10.1[3098]: Notice: Driver info: See file:///usr/share/doc/lirc/plugindocs/devinput.html Aug 27 09:09:00 rivo lircd[3098]: lircd-0.10.1[3098]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-32: Multiple values for same code: BTN_TOOL_PEN Aug 27 09:09:00 rivo lircd[3098]: lircd-0.10.1[3098]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-32: Multiple values for same code: BTN_TRIGGER Aug 27 09:09:00 rivo lircd[3098]: lircd-0.10.1[3098]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-32: Multiple values for same code: BTN_TRIGGER_HAPPY1 Aug 27 09:09:00 rivo lircd[3098]: lircd-0.10.1[3098]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-32: Multiple values for same code: BTN_WHEEL Aug 27 09:09:00 rivo lircd-0.10.1[3098]: Info: lircd: Opening log, level: Info Aug 27 09:09:00 rivo lircd-0.10.1[3098]: Notice: Using systemd fd Aug 27 09:09:00 rivo lircd-0.10.1[3098]: Warning: Running as root Aug 27 09:09:00 rivo lircd-0.10.1[3098]: Info: Using remote: devinput-64. Aug 27 09:09:00 rivo lircd-0.10.1[3098]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-64: Multiple values for same code: BTN_MISC Aug 27 09:09:00 rivo lircd-0.10.1[3098]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-64: Multiple values for same code: BTN_MOUSE Aug 27 09:09:00 rivo lircd-0.10.1[3098]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-64: Multiple values for same code: BTN_SOUTH Aug 27 09:09:00 rivo lircd-0.10.1[3098]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-64: Multiple values for same code: BTN_TOOL_PEN Aug 27 09:09:00 rivo lircd-0.10.1[3098]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-64: Multiple values for same code: BTN_TRIGGER Aug 27 09:09:00 rivo lircd-0.10.1[3098]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-64: Multiple values for same code: BTN_TRIGGER_HAPPY1 Aug 27 09:09:00 rivo lircd-0.10.1[3098]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-64: Multiple values for same code: BTN_WHEEL Aug 27 09:09:00 rivo lircd-0.10.1[3098]: Info: Using remote: devinput-32. Aug 27 09:09:00 rivo lircd-0.10.1[3098]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-32: Multiple values for same code: BTN_MISC Aug 27 09:09:00 rivo lircd-0.10.1[3098]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-32: Multiple values for same code: BTN_MOUSE Aug 27 09:09:00 rivo lircd-0.10.1[3098]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-32: Multiple values for same code: BTN_SOUTH Aug 27 09:09:00 rivo lircd-0.10.1[3098]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-32: Multiple values for same code: BTN_TOOL_PEN Aug 27 09:09:00 rivo lircd-0.10.1[3098]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-32: Multiple values for same code: BTN_TRIGGER Aug 27 09:09:00 rivo lircd-0.10.1[3098]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-32: Multiple values for same code: BTN_TRIGGER_HAPPY1 Aug 27 09:09:00 rivo lircd-0.10.1[3098]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-32: Multiple values for same code: BTN_WHEEL Aug 27 09:09:00 rivo ntpd[3140]: INIT: This system has a 32-bit time_t. Aug 27 09:09:00 rivo lircd[3098]: lircd-0.10.1[3098]: Notice: lircd(devinput) ready, using /var/run/lirc/lircd Aug 27 09:09:00 rivo ntpd[3140]: INIT: This ntpd will fail on 2038-01-19T03:14:07Z. Aug 27 09:09:00 rivo ntpd[3140]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Aug 27 09:09:00 rivo lircd-0.10.1[3098]: Notice: lircd(devinput) ready, using /var/run/lirc/lircd Aug 27 09:09:00 rivo ntpd[3140]: INIT: Built with OpenSSL 3.0.9 30 May 2023, 30000090 Aug 27 09:09:00 rivo ntpd[3140]: INIT: Running with OpenSSL 3.0.20 7 Apr 2026, 30000140 Aug 27 09:09:00 rivo ntpd[3140]: NTSc: Using system default root certificates. Aug 27 09:09:00 rivo systemd[1]: Started lircd.service - Flexible IR remote input/output application support. Aug 27 09:09:00 rivo dhcpcd[3036]: wlan0: soliciting a DHCP lease Aug 27 09:09:00 rivo systemd[1]: Finished welcome.service - Show a welcome message on console. Aug 27 09:09:00 rivo systemd[1]: Starting plymouth-quit-wait.service - Hold until boot process finishes up... Aug 27 09:09:00 rivo wireless.js[2957]: WIRELESS.JS - INFO: Wireless.js initializing wireless flow Aug 27 09:09:00 rivo dhcpcd[3036]: wlan0: soliciting an IPv6 router Aug 27 09:09:00 rivo systemd[1]: Starting plymouth-quit.service - Terminate Plymouth Boot Screen... Aug 27 09:09:00 rivo systemd[1]: Received SIGRTMIN+21 from PID 2471 (plymouthd). Aug 27 09:09:00 rivo avahi-daemon[2936]: Server startup complete. Host name is rivo.local. Local service cookie is 649612248. Aug 27 09:09:00 rivo systemd[1]: Received SIGRTMIN+21 from PID 2471 (plymouthd). Aug 27 09:09:00 rivo systemd[1]: Finished plymouth-quit.service - Terminate Plymouth Boot Screen. Aug 27 09:09:00 rivo systemd[1]: Finished plymouth-quit-wait.service - Hold until boot process finishes up. Aug 27 09:09:00 rivo ifplugd(eth0)[3178]: ifplugd 0.28 initializing. Aug 27 09:09:00 rivo kernel: fb: osd_release now.index=3,open_count=1 Aug 27 09:09:00 rivo kernel: fb: osd_release now.index=2,open_count=1 Aug 27 09:09:00 rivo kernel: fb: osd_release now.index=1,open_count=1 Aug 27 09:09:00 rivo kernel: BT_RADIO going: on Aug 27 09:09:00 rivo kernel: AML_BT: going ON Aug 27 09:09:00 rivo ifplugd[3096]: Network Interface Plugging Daemon...start eth0...done. Aug 27 09:09:00 rivo systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Aug 27 09:09:00 rivo ifplugd(eth0)[3178]: Using interface eth0/02:00:00:11:2B:01 with driver (version: Jan_2016) Aug 27 09:09:00 rivo systemd[1]: Reached target getty.target - Login Prompts. Aug 27 09:09:00 rivo ifplugd(eth0)[3178]: Using detection mode: SIOCETHTOOL Aug 27 09:09:00 rivo systemd[1]: Started ifplugd.service - LSB: Brings up/down network automatically. Aug 27 09:09:00 rivo ifplugd(eth0)[3178]: Initialization complete, link beat not detected. Aug 27 09:09:00 rivo ntpd[3140]: statistics directory /var/log/ntpsec/ does not exist or is unwriteable, error No such file or directory Aug 27 09:09:00 rivo rfkill[3188]: unblock set for id 0 Aug 27 09:09:00 rivo systemd[1]: samba-ad-dc.service: Skipped due to 'exec-condition'. Aug 27 09:09:00 rivo systemd[1]: Condition check resulted in samba-ad-dc.service - Samba AD Daemon being skipped. Aug 27 09:09:00 rivo sshd[3196]: Server listening on 0.0.0.0 port 22. Aug 27 09:09:00 rivo sshd[3196]: Server listening on :: port 22. Aug 27 09:09:00 rivo systemd[1]: Started ssh.service - OpenBSD Secure Shell server. Aug 27 09:09:00 rivo systemd[1]: e2scrub_reap.service: Deactivated successfully. Aug 27 09:09:00 rivo systemd[1]: Finished e2scrub_reap.service - Remove Stale Online ext4 Metadata Check Snapshots. Aug 27 09:09:01 rivo sudo[3210]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Aug 27 09:09:01 rivo sudo[3210]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 27 09:09:01 rivo sudo[3210]: pam_unix(sudo:session): session closed for user root Aug 27 09:09:01 rivo nmbd[3204]: [2026/08/27 09:09:01.482061, 0] ../../source3/nmbd/nmbd.c:901(main) Aug 27 09:09:01 rivo nmbd[3204]: nmbd version 4.17.12-Debian started. Aug 27 09:09:01 rivo nmbd[3204]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Aug 27 09:09:01 rivo nmbd[3204]: [2026/08/27 09:09:01.487754, 0] ../../source3/nmbd/asyncdns.c:158(start_async_dns) Aug 27 09:09:01 rivo nmbd[3204]: started asyncdns process 3218 Aug 27 09:09:01 rivo sudo[3213]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Aug 27 09:09:01 rivo sudo[3213]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 27 09:09:01 rivo nmbd[3204]: [2026/08/27 09:09:01.494697, 0] ../../lib/util/become_daemon.c:150(daemon_status) Aug 27 09:09:01 rivo nmbd[3204]: daemon_status: daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Aug 27 09:09:01 rivo nmbd[3204]: [2026/08/27 09:09:01.494898, 0] ../../source3/nmbd/nmbd_subnetdb.c:252(create_subnets) Aug 27 09:09:01 rivo nmbd[3204]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Aug 27 09:09:01 rivo kernel: dhd_stop: Enter ffffffc04fd6d000 Aug 27 09:09:01 rivo kernel: CFGP2P-ERROR) wl_cfgp2p_disable_discovery : do nothing, not initialized Aug 27 09:09:01 rivo kernel: CFGP2P-ERROR) wl_cfgp2p_deinit_priv : In Aug 27 09:09:01 rivo kernel: dhd_tcpack_suppress_set: TCP ACK Suppress mode 1 -> mode 0 Aug 27 09:09:01 rivo kernel: wl_android_wifi_off in 1 Aug 27 09:09:01 rivo kernel: wl_android_wifi_off in 2: g_wifi_on=1, on_failure=1 Aug 27 09:09:01 rivo kernel: dhd_dbg_detach_pkt_monitor, 2204 Aug 27 09:09:01 rivo kernel: dhd_bus_devreset: == Power OFF == Aug 27 09:09:01 rivo kernel: bcmsdh_oob_intr_unregister: Enter Aug 27 09:09:01 rivo kernel: dhd_txglom_enable: enable 0 Aug 27 09:09:01 rivo kernel: dhd_conf_set_txglom_params: txglom_mode=copy Aug 27 09:09:01 rivo kernel: dhd_conf_set_txglom_params: txglomsize=0, deferred_tx_len=0 Aug 27 09:09:01 rivo kernel: dhd_conf_set_txglom_params: txinrx_thres=128, dhd_txminmax=-1 Aug 27 09:09:01 rivo kernel: dhd_conf_set_txglom_params: tx_max_offset=0, txctl_tmo_fix=300 Aug 27 09:09:01 rivo kernel: dhd_bus_devreset: WLAN OFF DONE Aug 27 09:09:01 rivo kernel: wifi_platform_set_power = 0 Aug 27 09:09:01 rivo kernel: ======== PULL WL_REG_ON(-1) LOW! ======== Aug 27 09:09:01 rivo kernel: aml_wifi wifi: [extern_wifi_set_enable] WIFI Disable! 482 Aug 27 09:09:01 rivo ntpd[3140]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 Aug 27 09:09:01 rivo dhcpcd[3036]: wlan0: carrier lost Aug 27 09:09:01 rivo kernel: wl_android_wifi_off out Aug 27 09:09:01 rivo kernel: wl_ext_iapsta_dettach_netdev: Enter Aug 27 09:09:01 rivo kernel: dhd_stop: Exit Aug 27 09:09:01 rivo ntpd[3140]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Aug 27 09:09:01 rivo ntpd[3140]: DNS: dns_check: DNS error: -11, System error Aug 27 09:09:01 rivo ntpd[3140]: DNS: dns_take_status: 0.debian.pool.ntp.org=>error, 12 Aug 27 09:09:01 rivo sudo[3213]: pam_unix(sudo:session): session closed for user root Aug 27 09:09:01 rivo wireless.js[2957]: WIRELESS.JS - INFO: Cleaning previous... Aug 27 09:09:01 rivo wireless.js[2957]: WIRELESS.JS - INFO: === EMERGENCY OVERRIDE === Aug 27 09:09:01 rivo wireless.js[2957]: WIRELESS.JS - INFO: WiFi DISABLED in config, but no ethernet available Aug 27 09:09:01 rivo wireless.js[2957]: WIRELESS.JS - INFO: Forcing hotspot for system accessibility Aug 27 09:09:01 rivo wireless.js[2957]: WIRELESS.JS - INFO: User can disable hotspot after connecting via emergency AP Aug 27 09:09:01 rivo wireless.js[2957]: WIRELESS.JS - INFO: ========================== Aug 27 09:09:01 rivo wireless.js[2957]: WIRELESS.JS - INFO: Fallback: WiFi disabled. No hotspot started. Aug 27 09:09:01 rivo wireless.js[2957]: WIRELESS.JS - INFO: Notified systemd about wireless ready Aug 27 09:09:01 rivo iw[3238]: command failed: Input/output error (-5) Aug 27 09:09:01 rivo iwconfig[3239]: Error for wireless request "Set Power Management" (8B2C) : Aug 27 09:09:01 rivo iwconfig[3239]: SET failed on device wlan0 ; Invalid argument. Aug 27 09:09:01 rivo kernel: dhd_ioctl_entry: Interface is down Aug 27 09:09:01 rivo systemd[1]: Started wireless.service - Wireless Services. Aug 27 09:09:01 rivo systemd[1]: Started volumio.service - Volumio Backend Module. Aug 27 09:09:01 rivo kernel: meson_uart ffd24000.serial: ttyS1 use xtal(24M) 24000000 change 0 to 9600 Aug 27 09:09:01 rivo kernel: meson_uart ffd24000.serial: ttyS1 use xtal(24M) 24000000 change 9600 to 9600 Aug 27 09:09:01 rivo kernel: meson_uart ffd24000.serial: ttyS1 use xtal(24M) 24000000 change 9600 to 115200 Aug 27 09:09:01 rivo systemd[1]: Started volumio-kiosk.service - Start Volumio Kiosk. Aug 27 09:09:01 rivo systemd[1]: Started volumio_cpu_tweak.service - Volumio Cpu Tweaker. Aug 27 09:09:01 rivo startkiosk.sh[3242]: Starting Volumio Kiosk with arguments for cursor display Aug 27 09:09:02 rivo volumio-cpu-tweak[3243]: Setting RT Priority for mpd Aug 27 09:09:02 rivo volumio-cpu-tweak[3243]: Setting MPD Affinity Aug 27 09:09:02 rivo volumio-cpu-tweak[3261]: pid 3's current affinity mask: 1 Aug 27 09:09:02 rivo volumio-cpu-tweak[3243]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Aug 27 09:09:02 rivo volumio-cpu-tweak[3243]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Aug 27 09:09:02 rivo systemd[1]: volumio_cpu_tweak.service: Deactivated successfully. Aug 27 09:09:02 rivo startkiosk.sh[3281]: X.Org X Server 1.21.1.7 Aug 27 09:09:02 rivo startkiosk.sh[3281]: X Protocol Version 11, Revision 0 Aug 27 09:09:02 rivo startkiosk.sh[3281]: Current Operating System: Linux rivo 4.9.241 #4 SMP PREEMPT Wed Jun 4 18:07:40 CEST 2025 aarch64 Aug 27 09:09:02 rivo startkiosk.sh[3281]: Kernel command line: imgpart=UUID=31551b0e-fa00-4b7b-8e1b-da28f8b03393 imgfile=/volumio_current.sqsh bootpart=UUID=0051-4524 datapart=UUID=efaca331-6ac5-4f55-8db0-7a8e908a5fab uuidconfig=env.system.txt hwdevice=VIMX console=tty0 console=ttyS0,115200n8 no_console_suspend consoleblank=0 logo=osd0,loaded,0x3d800000,1080p60hz vout=1080p60hz,enable hdmimode=1280x400p60hz fsck.repair=yes net.ifnames=0 ddr_size= wol_enable= mac=02:00:00:11:2b:01 fan=auto hwver=Unknow coherent_pool=2M quiet loglevel=0 splash plymouth.ignore-serial-consoles initramfs.clear splash bootdelay=7 Aug 27 09:09:02 rivo startkiosk.sh[3281]: xorg-server 2:21.1.7-3+deb12u12 (https://www.debian.org/support) Aug 27 09:09:02 rivo startkiosk.sh[3281]: Current version of pixman: 0.42.2 Aug 27 09:09:02 rivo startkiosk.sh[3281]: Before reporting problems, check http://wiki.x.org Aug 27 09:09:02 rivo startkiosk.sh[3281]: to make sure that you have the latest version. Aug 27 09:09:02 rivo startkiosk.sh[3281]: Markers: (--) probed, (**) from config file, (==) default setting, Aug 27 09:09:02 rivo startkiosk.sh[3281]: (++) from command line, (!!) notice, (II) informational, Aug 27 09:09:02 rivo startkiosk.sh[3281]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Aug 27 09:09:02 rivo startkiosk.sh[3281]: (==) Log file: "/var/log/Xorg.0.log", Time: Thu Aug 27 09:09:02 2026 Aug 27 09:09:02 rivo startkiosk.sh[3281]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Aug 27 09:09:02 rivo kernel: fb: osd[0] canvas.idx =0x40 Aug 27 09:09:02 rivo kernel: fb: osd[0] canvas.addr=0x70000000 Aug 27 09:09:02 rivo kernel: fb: osd[0] canvas.width=7680 Aug 27 09:09:02 rivo kernel: fb: osd[0] canvas.height=1080 Aug 27 09:09:02 rivo kernel: fb: osd[0] frame.width=1920 Aug 27 09:09:02 rivo kernel: fb: osd[0] frame.height=1080 Aug 27 09:09:02 rivo kernel: fb: osd[0] out_addr_id =0x1 Aug 27 09:09:02 rivo kernel: fb: osd[0] enable: 0 (Xorg) Aug 27 09:09:02 rivo ntpd[3140]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Aug 27 09:09:02 rivo ntpd[3140]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Aug 27 09:09:02 rivo ntpd[3140]: DNS: dns_check: DNS error: -11, System error Aug 27 09:09:02 rivo ntpd[3140]: DNS: dns_take_status: 1.debian.pool.ntp.org=>error, 12 Aug 27 09:09:03 rivo dhcpcd[3036]: eth0: carrier acquired Aug 27 09:09:03 rivo dhcpcd[2864]: eth0: carrier acquired Aug 27 09:09:03 rivo kernel: meson6-dwmac ff3f0000.ethernet eth0: Link is Up - 1Gbps/Full - flow control off Aug 27 09:09:03 rivo sh[2859]: eth0: carrier acquired Aug 27 09:09:03 rivo dhcpcd[3036]: eth0: IAID 00:11:2b:01 Aug 27 09:09:03 rivo dhcpcd[2864]: eth0: IAID 00:11:2b:01 Aug 27 09:09:03 rivo sh[2859]: eth0: IAID 00:11:2b:01 Aug 27 09:09:03 rivo ntpd[3140]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Aug 27 09:09:03 rivo ntpd[3140]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Aug 27 09:09:03 rivo ntpd[3140]: DNS: dns_check: DNS error: -11, System error Aug 27 09:09:03 rivo ntpd[3140]: DNS: dns_take_status: 2.debian.pool.ntp.org=>error, 12 Aug 27 09:09:03 rivo ifplugd(eth0)[3178]: Link beat detected. Aug 27 09:09:03 rivo ifplugd(eth0)[3178]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Aug 27 09:09:03 rivo ifplugd(eth0)[3178]: client: ifup: waiting for lock on /run/network/ifstate.eth0 Aug 27 09:09:03 rivo dhcpcd[3036]: eth0: soliciting an IPv6 router Aug 27 09:09:03 rivo dhcpcd[3036]: eth0: soliciting a DHCP lease Aug 27 09:09:04 rivo dhcpcd[3036]: eth0: offered 192.168.0.10 from 192.168.0.1 Aug 27 09:09:04 rivo dhcpcd[2864]: eth0: soliciting an IPv6 router Aug 27 09:09:04 rivo sh[2859]: eth0: soliciting an IPv6 router Aug 27 09:09:04 rivo volumio-remote-updater[2956]: [2026-08-27 09:09:04] [connect] Successful connection Aug 27 09:09:04 rivo dhcpcd[2864]: eth0: soliciting a DHCP lease Aug 27 09:09:04 rivo sh[2859]: eth0: soliciting a DHCP lease Aug 27 09:09:04 rivo dhcpcd[2864]: eth0: offered 192.168.0.10 from 192.168.0.1 Aug 27 09:09:04 rivo sh[2859]: eth0: offered 192.168.0.10 from 192.168.0.1 Aug 27 09:09:04 rivo ntpd[3140]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Aug 27 09:09:04 rivo ntpd[3140]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Aug 27 09:09:04 rivo ntpd[3140]: DNS: dns_check: DNS error: -11, System error Aug 27 09:09:04 rivo ntpd[3140]: DNS: dns_take_status: 3.debian.pool.ntp.org=>error, 12 Aug 27 09:09:04 rivo kernel: fb: osd[0] enable: 1 (Xorg) Aug 27 09:09:04 rivo wireless.js[2957]: WIRELESS.JS - INFO: === SNM TRANSITION === Aug 27 09:09:04 rivo wireless.js[2957]: WIRELESS.JS - INFO: Previous ethernet state: disconnected Aug 27 09:09:04 rivo wireless.js[2957]: WIRELESS.JS - INFO: New ethernet state: connected Aug 27 09:09:04 rivo wireless.js[2957]: WIRELESS.JS - INFO: Single Network Mode: enabled Aug 27 09:09:04 rivo wireless.js[2957]: WIRELESS.JS - INFO: First start: no Aug 27 09:09:04 rivo wireless.js[2957]: WIRELESS.JS - INFO: Action: Switch to ethernet (WiFi scan mode) Aug 27 09:09:04 rivo wireless.js[2957]: WIRELESS.JS - INFO: === END TRANSITION === Aug 27 09:09:04 rivo wireless.js[2957]: WIRELESS.JS - INFO: SNM: Ethernet connected, switching to ethernet (WiFi scan mode) Aug 27 09:09:04 rivo sudo[3332]: root : PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd -k wlan0 Aug 27 09:09:04 rivo sudo[3332]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 27 09:09:04 rivo dhcpcd[3343]: dhcpcd not running Aug 27 09:09:04 rivo sudo[3332]: pam_unix(sudo:session): session closed for user root Aug 27 09:09:04 rivo wireless.js[2957]: dhcpcd not running Aug 27 09:09:04 rivo wireless.js[2957]: WIRELESS.JS - INFO: Wireless.js initializing wireless flow Aug 27 09:09:05 rivo volumio[3241]: info: ------------------------------------------- Aug 27 09:09:05 rivo volumio[3241]: info: ----- Volumio3 ---- Aug 27 09:09:05 rivo volumio[3241]: info: ------------------------------------------- Aug 27 09:09:05 rivo volumio[3241]: info: ----- System startup ---- Aug 27 09:09:05 rivo volumio[3241]: info: ------------------------------------------- Aug 27 09:09:05 rivo sudo[3352]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Aug 27 09:09:05 rivo sudo[3352]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 27 09:09:05 rivo sudo[3352]: pam_unix(sudo:session): session closed for user root Aug 27 09:09:05 rivo sudo[3355]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Aug 27 09:09:05 rivo sudo[3355]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 27 09:09:05 rivo sudo[3355]: pam_unix(sudo:session): session closed for user root Aug 27 09:09:05 rivo wireless.js[2957]: WIRELESS.JS - INFO: Cleaning previous... Aug 27 09:09:05 rivo kernel: fb: osd[0] enable: 1 (Xorg) Aug 27 09:09:05 rivo systemd[1]: systemd-rfkill.service: Deactivated successfully. Aug 27 09:09:06 rivo volumio[3241]: info: MYVOLUMIO Environment detected Aug 27 09:09:06 rivo volumio[3241]: info: Plugin folders cleanup Aug 27 09:09:06 rivo volumio[3241]: info: Scanning into folder /volumio/app/plugins/ Aug 27 09:09:06 rivo volumio[3241]: info: Scanning category audio_interface Aug 27 09:09:06 rivo volumio[3241]: info: Scanning category miscellanea Aug 27 09:09:06 rivo volumio[3241]: info: Scanning category music_service Aug 27 09:09:06 rivo volumio[3241]: info: Scanning category plugins.json Aug 27 09:09:06 rivo volumio[3241]: info: Scanning category system_controller Aug 27 09:09:06 rivo volumio[3241]: info: Scanning category user_interface Aug 27 09:09:06 rivo volumio[3241]: info: Scanning into folder /data/plugins/ Aug 27 09:09:06 rivo volumio[3241]: info: Scanning category music_service Aug 27 09:09:06 rivo volumio[3241]: info: Scanning category user_interface Aug 27 09:09:06 rivo volumio[3241]: info: Plugin folders cleanup completed Aug 27 09:09:06 rivo volumio[3241]: info: ------------------------------------------- Aug 27 09:09:06 rivo volumio[3241]: info: ----- Core plugins startup ---- Aug 27 09:09:06 rivo volumio[3241]: info: ------------------------------------------- Aug 27 09:09:06 rivo volumio[3241]: info: Loading plugins from folder /volumio/app/plugins/ Aug 27 09:09:06 rivo volumio[3241]: info: Adding plugin bluetooth to MyMusic Plugins Aug 27 09:09:06 rivo volumio[3241]: info: Adding plugin multiroom to MyMusic Plugins Aug 27 09:09:06 rivo volumio[3241]: info: Adding plugin upnp to MyMusic Plugins Aug 27 09:09:06 rivo volumio[3241]: info: Adding plugin metavolumio to MyMusic Plugins Aug 27 09:09:06 rivo volumio[3241]: info: Adding plugin airplay_emulation to MyMusic Plugins Aug 27 09:09:06 rivo volumio[3241]: info: Adding plugin cd_controller to MyMusic Plugins Aug 27 09:09:06 rivo volumio[3241]: info: Adding plugin qobuzconnect to MyMusic Plugins Aug 27 09:09:06 rivo volumio[3241]: info: Adding plugin raat to MyMusic Plugins Aug 27 09:09:06 rivo volumio[3241]: info: Adding plugin smart_inputs to MyMusic Plugins Aug 27 09:09:06 rivo volumio[3241]: info: Adding plugin tidalconnect to MyMusic Plugins Aug 27 09:09:06 rivo volumio[3241]: info: Adding plugin upnp_browser to MyMusic Plugins Aug 27 09:09:06 rivo volumio[3241]: info: Loading plugins from folder /data/plugins/ Aug 27 09:09:06 rivo volumio[3241]: info: Loading plugin "system"... Aug 27 09:09:06 rivo volumio[3241]: info: Loading plugin "appearance"... Aug 27 09:09:06 rivo bash[3118]: setdatetime-helper: all HTTPS Date fallbacks failed Aug 27 09:09:06 rivo systemd[1]: setdatetime-helper.service: Deactivated successfully. Aug 27 09:09:06 rivo systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Aug 27 09:09:07 rivo dhcpcd[2864]: eth0: probing address 192.168.0.10/24 Aug 27 09:09:07 rivo sh[2859]: eth0: probing address 192.168.0.10/24 Aug 27 09:09:07 rivo haveged[2894]: haveged: ver: 1.9.14; arch: generic; vend: ; build: (gcc 12.2.0 CTV); collect: 128K Aug 27 09:09:07 rivo haveged[2894]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 12/40; sz: 15126/57786 Aug 27 09:09:07 rivo haveged[2894]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 7.99981 Aug 27 09:09:07 rivo haveged[2894]: haveged: fills: 0, generated: 0 Aug 27 09:09:08 rivo volumio[3241]: info: Loading plugin "network"... Aug 27 09:09:08 rivo volumio[3241]: info: Refreshing Cached IP Addresses Aug 27 09:09:08 rivo sudo[3396]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 27 09:09:08 rivo volumio[3241]: info: Loading plugin "services"... Aug 27 09:09:08 rivo sudo[3396]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 09:09:08 rivo sudo[3398]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 27 09:09:08 rivo sudo[3398]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 09:09:08 rivo volumio[3241]: info: Loading plugin "volumio5onboarding"... Aug 27 09:09:08 rivo sudo[3396]: pam_unix(sudo:session): session closed for user root Aug 27 09:09:08 rivo sudo[3398]: pam_unix(sudo:session): session closed for user root Aug 27 09:09:08 rivo volumio[3241]: info: Loading plugin "alsa_controller"... Aug 27 09:09:08 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 27 09:09:08 rivo volumio[3241]: info: Loading plugin "wizard"... Aug 27 09:09:08 rivo volumio[3241]: info: Loading plugin "networkfs"... Aug 27 09:09:09 rivo volumio[3241]: info: Starting Udev Watcher for removable devices Aug 27 09:09:09 rivo sudo[3424]: 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 27 09:09:09 rivo sudo[3424]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 09:09:09 rivo sudo[3426]: 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 27 09:09:09 rivo sudo[3426]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 09:09:09 rivo kernel: FS-Cache: Netfs 'nfs' registered for caching Aug 27 09:09:09 rivo volumio[3241]: info: Ignoring mount for partition: BOOT Aug 27 09:09:09 rivo volumio[3241]: info: Ignoring mount for partition: volumio Aug 27 09:09:09 rivo volumio[3241]: info: Ignoring mount for partition: volumio_data Aug 27 09:09:09 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 27 09:09:09 rivo volumio[3241]: info: Loading plugin "volumio_command_line_client"... Aug 27 09:09:09 rivo dhcpcd[3036]: eth0: probing address 192.168.0.10/24 Aug 27 09:09:09 rivo volumio[3241]: info: Loading plugin "upnp"... Aug 27 09:09:09 rivo volumio[3241]: info: [1787814549205] Starting Upmpd Daemon Aug 27 09:09:09 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 27 09:09:09 rivo kernel: NFS: Registering the id_resolver key type Aug 27 09:09:09 rivo kernel: Key type id_resolver registered Aug 27 09:09:09 rivo kernel: Key type id_legacy registered Aug 27 09:09:09 rivo volumio[3241]: info: Loading plugin "my_music"... Aug 27 09:09:09 rivo volumio[3241]: info: Loading plugin "mpd"... Aug 27 09:09:09 rivo sudo[3424]: pam_unix(sudo:session): session closed for user root Aug 27 09:09:09 rivo sudo[3426]: pam_unix(sudo:session): session closed for user root Aug 27 09:09:09 rivo kernel: meson_uart ffd24000.serial: ttyS1 use xtal(24M) 24000000 change 115200 to 2000000 Aug 27 09:09:09 rivo systemd[1]: Starting systemd-rfkill.service - Load/Save RF Kill Switch Status... Aug 27 09:09:09 rivo (udev-worker)[2624]: hci0: Process '/usr/bin/hciconfig hci0 up' failed with exit code 1. Aug 27 09:09:09 rivo systemd[1]: Starting bluetooth.service - Bluetooth service... Aug 27 09:09:09 rivo systemd[1]: Started systemd-rfkill.service - Load/Save RF Kill Switch Status. Aug 27 09:09:09 rivo volumio[3241]: info: Loading plugin "upnp_browser"... Aug 27 09:09:09 rivo bluetoothd[3447]: Bluetooth daemon 5.83 Aug 27 09:09:09 rivo systemd[1]: Started bluetooth.service - Bluetooth service. Aug 27 09:09:09 rivo bluetoothd[3447]: Starting SDP server Aug 27 09:09:09 rivo bluetoothd[3447]: Excluding (cli) sap Aug 27 09:09:09 rivo bluetoothd[3447]: Excluding (cli) scanparam Aug 27 09:09:09 rivo bluetoothd[3447]: Excluding (cli) midi Aug 27 09:09:09 rivo bluetoothd[3447]: Excluding (cli) battery Aug 27 09:09:09 rivo systemd[1]: Starting bluealsa.service - BlueALSA service... Aug 27 09:09:09 rivo dbus-daemon[2939]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.7' (uid=0 pid=3447 comm="/usr/libexec/bluetooth/bluetoothd --noplugin=sap,b" label="kernel") Aug 27 09:09:09 rivo bluetoothd[3447]: Bluetooth management interface 1.14 initialized Aug 27 09:09:09 rivo bluetoothd[3447]: src/adapter.c:reset_adv_monitors_complete() Failed to reset Adv Monitors: Unknown Command (0x01) Aug 27 09:09:09 rivo bluetoothd[3447]: Battery Provider Manager created Aug 27 09:09:09 rivo systemd[1]: Starting systemd-hostnamed.service - Hostname Service... Aug 27 09:09:09 rivo kernel: IPVS: Creating netns size=1928 id=3 Aug 27 09:09:10 rivo bluetoothd[3447]: profiles/audio/bap.c:bap_adapter_probe() BAP requires ISO Socket which is not enabled Aug 27 09:09:10 rivo bluetoothd[3447]: bap: Operation not supported (95) Aug 27 09:09:10 rivo bluetoothd[3447]: Failed to Read Adv Monitor Features with status 0x01 Aug 27 09:09:10 rivo dbus-daemon[2939]: [system] Successfully activated service 'org.freedesktop.hostname1' Aug 27 09:09:10 rivo systemd[1]: Started systemd-hostnamed.service - Hostname Service. Aug 27 09:09:10 rivo bluealsa[3448]: ../src/storage.c:90: Initializing persistent storage: /var/lib/bluealsa Aug 27 09:09:10 rivo bluealsa[3448]: main.c:663: Starting main dispatching loop Aug 27 09:09:10 rivo systemd[1]: Started bluealsa.service - BlueALSA service. Aug 27 09:09:10 rivo systemd[1]: Reached target bluetooth.target - Bluetooth Support. Aug 27 09:09:10 rivo bluealsa[3448]: main.c:118: Acquired D-Bus service name: org.bluealsa Aug 27 09:09:10 rivo bluealsa[3448]: bluealsa-dbus.c:358: Registering BlueALSA D-Bus manager: /org/bluealsa Aug 27 09:09:10 rivo bluealsa[3448]: bluez.c:153: Registering media application: /org/bluez/hci0 Aug 27 09:09:10 rivo bluealsa[3448]: bluez.c:743: Exporting media endpoint object: /org/bluez/hci0/A2DP/SBC/source/1 Aug 27 09:09:10 rivo bluealsa[3448]: bluez.c:743: Exporting media endpoint object: /org/bluez/hci0/A2DP/SBC/source/2 Aug 27 09:09:10 rivo bluealsa[3448]: bluez.c:743: Exporting media endpoint object: /org/bluez/hci0/A2DP/SBC/sink/1 Aug 27 09:09:10 rivo bluealsa[3448]: bluez.c:743: Exporting media endpoint object: /org/bluez/hci0/A2DP/SBC/sink/2 Aug 27 09:09:10 rivo bluetoothd[3447]: Endpoint registered: sender=:1.9 path=/org/bluez/hci0/A2DP/SBC/source/1 Aug 27 09:09:10 rivo bluetoothd[3447]: Endpoint registered: sender=:1.9 path=/org/bluez/hci0/A2DP/SBC/sink/2 Aug 27 09:09:10 rivo bluetoothd[3447]: Endpoint registered: sender=:1.9 path=/org/bluez/hci0/A2DP/SBC/source/2 Aug 27 09:09:10 rivo bluetoothd[3447]: Endpoint registered: sender=:1.9 path=/org/bluez/hci0/A2DP/SBC/sink/1 Aug 27 09:09:11 rivo volumio[3241]: info: Starting UPNP Browser Aug 27 09:09:11 rivo volumio[3241]: info: Plugin bluetooth is not enabled Aug 27 09:09:11 rivo volumio[3241]: info: Loading plugin "alarm-clock"... Aug 27 09:09:11 rivo volumio[3241]: info: Loading plugin "manifestui"... Aug 27 09:09:11 rivo volumio[3241]: info: Loading plugin "metavolumio"... Aug 27 09:09:12 rivo dhcpcd[2864]: eth0: leased 192.168.0.10 for 3600 seconds Aug 27 09:09:12 rivo sh[2859]: eth0: leased 192.168.0.10 for 3600 seconds Aug 27 09:09:12 rivo avahi-daemon[2936]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.0.10. Aug 27 09:09:12 rivo avahi-daemon[2936]: New relevant interface eth0.IPv4 for mDNS. Aug 27 09:09:12 rivo sh[2859]: eth0: adding route to 192.168.0.0/24 Aug 27 09:09:12 rivo sh[2859]: eth0: adding default route via 192.168.0.1 Aug 27 09:09:12 rivo dhcpcd[2864]: eth0: adding route to 192.168.0.0/24 Aug 27 09:09:12 rivo avahi-daemon[2936]: Registering new address record for 192.168.0.10 on eth0.IPv4. Aug 27 09:09:12 rivo dhcpcd[2864]: eth0: adding default route via 192.168.0.1 Aug 27 09:09:12 rivo systemd[1]: welcome.service: Deactivated successfully. Aug 27 09:09:12 rivo systemd[1]: Stopped welcome.service - Show a welcome message on console. Aug 27 09:09:12 rivo systemd[1]: Stopping welcome.service - Show a welcome message on console... Aug 27 09:09:12 rivo systemd[1]: Starting welcome.service - Show a welcome message on console... Aug 27 09:09:12 rivo sh[2859]: forked to background, child pid 2863 Aug 27 09:09:12 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 27 09:09:12 rivo systemd[1]: Started nmbd.service - Samba NMB Daemon. Aug 27 09:09:12 rivo systemd[1]: Starting winbind.service - Samba Winbind Daemon... Aug 27 09:09:12 rivo welcome[3482]: Resolved ip:[1] 192.168.0.10 Aug 27 09:09:12 rivo systemd[1]: Finished welcome.service - Show a welcome message on console. Aug 27 09:09:12 rivo systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Aug 27 09:09:12 rivo ifplugd(eth0)[3178]: client: ifup: interface eth0 already configured Aug 27 09:09:12 rivo sh[3518]: eth0=eth0 Aug 27 09:09:12 rivo ifplugd(eth0)[3178]: Program executed successfully. Aug 27 09:09:12 rivo winbindd[3519]: [2026/08/27 09:09:12.928962, 0] ../../source3/winbindd/winbindd.c:1440(main) Aug 27 09:09:12 rivo winbindd[3519]: winbindd version 4.17.12-Debian started. Aug 27 09:09:12 rivo winbindd[3519]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Aug 27 09:09:12 rivo winbindd[3519]: [2026/08/27 09:09:12.953772, 0] ../../source3/winbindd/winbindd_cache.c:3117(initialize_winbindd_cache) Aug 27 09:09:12 rivo winbindd[3519]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Aug 27 09:09:12 rivo systemd[1]: Started winbind.service - Samba Winbind Daemon. Aug 27 09:09:12 rivo systemd[1]: Starting smbd.service - Samba SMB Daemon... Aug 27 09:09:13 rivo systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 1. Aug 27 09:09:13 rivo systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Aug 27 09:09:13 rivo systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Aug 27 09:09:13 rivo upmpdcli[3536]: Could not open config: /tmp/upmpdcli.conf Aug 27 09:09:13 rivo systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Aug 27 09:09:13 rivo systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Aug 27 09:09:13 rivo ntpd[3140]: IO: Listen normally on 3 eth0 192.168.0.10:123 Aug 27 09:09:13 rivo ntpd[3140]: IO: new interface(s) found: waking up resolver Aug 27 09:09:13 rivo ntpd[3140]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Aug 27 09:09:13 rivo ntpd[3140]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Aug 27 09:09:13 rivo ntpd[3140]: DNS: Pool taking: 195.133.18.24 Aug 27 09:09:13 rivo ntpd[3140]: DNS: Pool taking: 217.24.20.5 Aug 27 09:09:13 rivo ntpd[3140]: DNS: Pool taking: 162.159.200.1 Aug 27 09:09:13 rivo ntpd[3140]: DNS: Pool taking: 162.159.200.123 Aug 27 09:09:13 rivo ntpd[3140]: DNS: dns_take_status: 3.debian.pool.ntp.org=>good, 8 Aug 27 09:09:13 rivo smbd[3526]: [2026/08/27 09:09:13.922585, 0] ../../source3/smbd/server.c:1741(main) Aug 27 09:09:13 rivo smbd[3526]: smbd version 4.17.12-Debian started. Aug 27 09:09:13 rivo smbd[3526]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Aug 27 09:09:13 rivo volumio[3241]: info: Loading plugin "airplay_emulation"... Aug 27 09:09:14 rivo volumio[3241]: info: Starting Shairport Sync Aug 27 09:09:14 rivo volumio[3241]: info: Loading plugin "cd_controller"... Aug 27 09:09:14 rivo systemd[1]: systemd-rfkill.service: Deactivated successfully. Aug 27 09:09:14 rivo volumio[3241]: info: Loading plugin "last_100"... Aug 27 09:09:14 rivo volumio[3241]: info: Loading plugin "qobuzconnect"... Aug 27 09:09:14 rivo volumio[3241]: info: Loading plugin "raat"... Aug 27 09:09:14 rivo ntpd[3140]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Aug 27 09:09:14 rivo systemd[1]: Started smbd.service - Samba SMB Daemon. Aug 27 09:09:14 rivo systemd[1]: Reached target multi-user.target - Multi-User System. Aug 27 09:09:14 rivo systemd[1]: Reached target graphical.target - Graphical Interface. Aug 27 09:09:14 rivo systemd[1]: Starting systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP... Aug 27 09:09:14 rivo systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Aug 27 09:09:14 rivo systemd[1]: Finished systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP. Aug 27 09:09:14 rivo systemd[1]: Startup finished in 13.449s (kernel) + 21.245s (userspace) = 34.695s. Aug 27 09:09:14 rivo volumio[3241]: info: RAAT Plugin loaded Aug 27 09:09:14 rivo volumio[3241]: info: Adding restartRAATSocket REST API Endpoint Aug 27 09:09:14 rivo volumio[3241]: info: Adding restartRAATSocket REST Endpoint for plugin: music_service/raat Aug 27 09:09:14 rivo ntpd[3140]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Aug 27 09:09:14 rivo ntpd[3140]: DNS: Pool skipping: 195.133.18.24 Aug 27 09:09:14 rivo volumio[3241]: info: Loading plugin "streaming_services"... Aug 27 09:09:14 rivo ntpd[3140]: DNS: Pool skipping: 217.24.20.5 Aug 27 09:09:14 rivo ntpd[3140]: DNS: Pool skipping: 162.159.200.1 Aug 27 09:09:14 rivo ntpd[3140]: DNS: Pool skipping: 162.159.200.123 Aug 27 09:09:14 rivo ntpd[3140]: DNS: Pool taking: 2001:ac8:7d:42:0:2:0:10 Aug 27 09:09:14 rivo ntpd[3140]: DNS: Pool taking: 2001:ac8:7d:42:0:2:0:20 Aug 27 09:09:14 rivo ntpd[3140]: DNS: Pool taking: 2606:4700:f1::1 Aug 27 09:09:14 rivo ntpd[3140]: DNS: Pool taking: 2a13:9400:10:1002::123 Aug 27 09:09:14 rivo ntpd[3140]: DNS: dns_take_status: 2.debian.pool.ntp.org=>good, 8 Aug 27 09:09:14 rivo dhcpcd[3036]: eth0: leased 192.168.0.10 for 3600 seconds Aug 27 09:09:14 rivo dhcpcd[3036]: eth0: adding route to 192.168.0.0/24 Aug 27 09:09:14 rivo dhcpcd[3036]: eth0: adding default route via 192.168.0.1 Aug 27 09:09:14 rivo systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Aug 27 09:09:14 rivo systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Aug 27 09:09:14 rivo systemd[1]: welcome.service: Deactivated successfully. Aug 27 09:09:14 rivo systemd[1]: Stopped welcome.service - Show a welcome message on console. Aug 27 09:09:14 rivo systemd[1]: Stopping welcome.service - Show a welcome message on console... Aug 27 09:09:14 rivo systemd[1]: Starting welcome.service - Show a welcome message on console... Aug 27 09:09:14 rivo welcome[3562]: Resolved ip:[1] 192.168.0.10 Aug 27 09:09:14 rivo systemd[1]: Finished welcome.service - Show a welcome message on console. Aug 27 09:09:14 rivo systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Aug 27 09:09:15 rivo ntpd[3140]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Aug 27 09:09:15 rivo ntpd[3140]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Aug 27 09:09:15 rivo ntpd[3140]: DNS: Pool taking: 195.250.114.105 Aug 27 09:09:15 rivo ntpd[3140]: DNS: Pool skipping: 195.133.18.24 Aug 27 09:09:15 rivo ntpd[3140]: DNS: Pool skipping: 162.159.200.123 Aug 27 09:09:15 rivo ntpd[3140]: DNS: Pool skipping: 162.159.200.1 Aug 27 09:09:15 rivo ntpd[3140]: DNS: dns_take_status: 1.debian.pool.ntp.org=>good, 8 Aug 27 09:09:16 rivo volumio[3241]: info: Loading plugin "tidalconnect"... Aug 27 09:09:16 rivo volumio[3241]: info: Loading plugin "webradio"... Aug 27 09:09:16 rivo ntpd[3140]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 Aug 27 09:09:16 rivo ntpd[3140]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Aug 27 09:09:16 rivo ntpd[3140]: DNS: Pool skipping: 195.133.18.24 Aug 27 09:09:16 rivo ntpd[3140]: DNS: Pool skipping: 217.24.20.5 Aug 27 09:09:16 rivo ntpd[3140]: DNS: Pool skipping: 162.159.200.1 Aug 27 09:09:16 rivo ntpd[3140]: DNS: Pool skipping: 162.159.200.123 Aug 27 09:09:16 rivo ntpd[3140]: DNS: dns_take_status: 0.debian.pool.ntp.org=>good, 8 Aug 27 09:09:16 rivo volumio[3241]: info: Loading plugin "i2s_dacs"... Aug 27 09:09:16 rivo volumio[3241]: info: I2S DAC not set, start Auto-detection Aug 27 09:09:16 rivo volumio[3241]: info: Loading plugin "volumiodiscovery"... Aug 27 09:09:16 rivo volumio[3241]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 27 09:09:16 rivo volumio[3241]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 27 09:09:16 rivo volumio[3241]: *** WARNING *** For more information see Aug 27 09:09:16 rivo volumio[3241]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 27 09:09:16 rivo volumio[3241]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 27 09:09:16 rivo volumio[3241]: *** WARNING *** For more information see Aug 27 09:09:16 rivo node[3241]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 27 09:09:16 rivo node[3241]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 27 09:09:16 rivo node[3241]: *** WARNING *** For more information see Aug 27 09:09:16 rivo node[3241]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 27 09:09:16 rivo node[3241]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 27 09:09:16 rivo node[3241]: *** WARNING *** For more information see Aug 27 09:09:16 rivo volumio[3241]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 27 09:09:16 rivo volumio[3241]: info: Discovery: Started advertising with name: Rivo Aug 27 09:09:16 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 27 09:09:16 rivo volumio[3241]: info: Loading plugin "now_playing"... Aug 27 09:09:16 rivo volumio-remote-updater[2956]: [2026-08-27 09:09:16] [connect] Successful connection Aug 27 09:09:18 rivo volumio[3241]: info: Loading plugin "multiroom"... Aug 27 09:09:18 rivo ntpd[3140]: CLOCK: time stepped by -1.291304 Aug 27 09:09:18 rivo systemd-journald[2594]: Time jumped backwards, rotating. Aug 27 09:09:18 rivo ntpd[3140]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Aug 27 09:09:19 rivo volumio[3241]: info: Applying required configuration parameters for plugin multiroom Aug 27 09:09:19 rivo sudo[3597]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Aug 27 09:09:19 rivo sudo[3597]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 09:09:19 rivo sudo[3597]: pam_unix(sudo:session): session closed for user root Aug 27 09:09:19 rivo volumio[3241]: info: MRS: MultiRoom plugin initialized Aug 27 09:09:19 rivo volumio[3241]: info: MRS: STOPPING SNAPCLIENT Aug 27 09:09:19 rivo volumio[3241]: info: MRS: Snap server stop Aug 27 09:09:19 rivo volumio[3241]: info: MRS: STOPPING volumioStreaming Aug 27 09:09:19 rivo sudo[3614]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Aug 27 09:09:19 rivo sudo[3614]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 09:09:19 rivo sudo[3616]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Aug 27 09:09:19 rivo sudo[3616]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 09:09:19 rivo volumio[3241]: info: Loading plugin "outputs"... Aug 27 09:09:19 rivo sudo[3618]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Aug 27 09:09:19 rivo sudo[3618]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 09:09:19 rivo volumio[3241]: info: Loading plugin "albumart"... Aug 27 09:09:19 rivo sudo[3623]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Aug 27 09:09:19 rivo sudo[3623]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 09:09:19 rivo volumio[3241]: info: Plugin example_plugin is not enabled Aug 27 09:09:19 rivo volumio[3241]: info: Loading plugin "hi_res_audio"... Aug 27 09:09:19 rivo sudo[3623]: pam_unix(sudo:session): session closed for user root Aug 27 09:09:19 rivo sudo[3614]: pam_unix(sudo:session): session closed for user root Aug 27 09:09:19 rivo sudo[3616]: pam_unix(sudo:session): session closed for user root Aug 27 09:09:19 rivo sudo[3618]: pam_unix(sudo:session): session closed for user root Aug 27 09:09:20 rivo volumio[3624]: Forking 3 albumart workers Aug 27 09:09:21 rivo volumio[3632]: Starting albumart workers Aug 27 09:09:21 rivo volumio[3634]: Starting albumart workers Aug 27 09:09:21 rivo volumio[3241]: info: Applying required configuration parameters for plugin hi_res_audio Aug 27 09:09:21 rivo volumio[3241]: info: Loading plugin "inputs"... Aug 27 09:09:21 rivo volumio[3633]: Starting albumart workers Aug 27 09:09:22 rivo volumio[3241]: info: Loading plugin "qobuz"... Aug 27 09:09:24 rivo volumio[3241]: info: Loading plugin "smart_inputs"... Aug 27 09:09:24 rivo volumio[3241]: info: Loading plugin "tidal"... Aug 27 09:09:25 rivo systemd[1]: systemd-fsckd.service: Deactivated successfully. Aug 27 09:09:26 rivo volumio[3241]: info: Loading plugin "rivocontrol"... Aug 27 09:09:26 rivo volumio[3241]: info: Adding this device properties Aug 27 09:09:26 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: system , setThisDeviceVolumioProperties Aug 27 09:09:26 rivo volumio[3241]: info: Setting Additional Device Volumio Properties: [object Object] Aug 27 09:09:26 rivo volumio[3241]: info: Loading plugin "updater_comm"... Aug 27 09:09:26 rivo volumio[3241]: info: Loading plugin "cec_controller"... Aug 27 09:09:27 rivo volumio[3241]: info: Applying required configuration parameters for plugin cec_controller Aug 27 09:09:27 rivo volumio[3241]: info: Plugin mpdemulation is not enabled Aug 27 09:09:27 rivo volumio[3241]: info: Loading plugin "rest_api"... Aug 27 09:09:27 rivo volumio[3241]: info: Loading plugin "websocket"... Aug 27 09:09:27 rivo volumio[3241]: info: Starting Socket.io Server version 1.7.4 Aug 27 09:09:27 rivo volumio[3241]: info: Loading plugin "minidlna"... Aug 27 09:09:27 rivo systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2. Aug 27 09:09:27 rivo systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Aug 27 09:09:27 rivo systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Aug 27 09:09:27 rivo volumio[3241]: info: Applying required configuration parameters for plugin minidlna Aug 27 09:09:27 rivo upmpdcli[3680]: Could not open config: /tmp/upmpdcli.conf Aug 27 09:09:27 rivo systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Aug 27 09:09:27 rivo systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Aug 27 09:09:27 rivo volumio[3241]: info: Loading i18n strings for locale en Aug 27 09:09:27 rivo volumio[3241]: Updating browse sources language Aug 27 09:09:27 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 27 09:09:27 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 09:09:27 rivo volumio[3241]: info: CoreCommandRouter::initPlayerControls Aug 27 09:09:27 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 09:09:27 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 09:09:27 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 09:09:27 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 09:09:27 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 09:09:27 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 09:09:27 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 09:09:27 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 09:09:27 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 09:09:27 rivo volumio[3241]: Express server listening on port 3000 Aug 27 09:09:27 rivo volumio[3241]: [Metrics] WebUI: 24s 931.84ms Aug 27 09:09:27 rivo volumio[3241]: info: CoreStateMachine::resetVolumioState Aug 27 09:09:27 rivo volumio[3241]: info: CoreStateMachine::getcurrentVolume Aug 27 09:09:27 rivo volumio[3241]: info: CoreCommandRouter::volumioRetrievevolume Aug 27 09:09:27 rivo volumio[3241]: info: CoreStateMachine::pushState Aug 27 09:09:27 rivo volumio[3241]: info: CorePlayQueue::getTrack 0 Aug 27 09:09:27 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 09:09:27 rivo volumio[3241]: info: CoreCommandRouter::volumioPushState Aug 27 09:09:27 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 09:09:28 rivo sudo[3711]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 27 09:09:28 rivo sudo[3711]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 09:09:28 rivo volumio[3241]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Aug 27 09:09:28 rivo volumio[3241]: info: Completed loading Core Plugins Aug 27 09:09:28 rivo volumio[3241]: info: Preparing to generate the ALSA configuration file Aug 27 09:09:28 rivo sudo[3713]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 27 09:09:28 rivo sudo[3713]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 09:09:28 rivo sudo[3711]: pam_unix(sudo:session): session closed for user root Aug 27 09:09:28 rivo sudo[3713]: pam_unix(sudo:session): session closed for user root Aug 27 09:09:28 rivo volumio[3241]: info: Volumio Network Manager: Network status updated: 0 Aug 27 09:09:28 rivo volumio[3241]: info: MRS: Removed streaming files Aug 27 09:09:28 rivo volumio[3241]: info: MRS: volumioStreaming STOPPED Aug 27 09:09:28 rivo volumio[3241]: info: MRS: SNAPSERVER STOPPED Aug 27 09:09:28 rivo volumio[3241]: info: MRS: SNAPCLIENT STOPPED Aug 27 09:09:28 rivo volumio[3241]: info: Cannot mount NAS MJUZA_2 at system boot, trial number 1 ,retrying in 5 seconds Aug 27 09:09:28 rivo volumio[3241]: info: Cannot mount NAS MJUZA_1 at system boot, trial number 1 ,retrying in 5 seconds Aug 27 09:09:28 rivo volumio[3241]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Aug 27 09:09:28 rivo volumio[3241]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Aug 27 09:09:28 rivo volumio[3241]: info: Reading ALSA contributions from plugins. Aug 27 09:09:28 rivo volumio[3241]: info: Reloading queue from file Aug 27 09:09:28 rivo volumio[3241]: info: CoreStateMachine::setRepeat false single undefined Aug 27 09:09:28 rivo volumio[3241]: info: CoreStateMachine::pushState Aug 27 09:09:28 rivo volumio[3241]: info: CorePlayQueue::getTrack 0 Aug 27 09:09:28 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 09:09:28 rivo volumio[3241]: info: CoreCommandRouter::volumioPushState Aug 27 09:09:28 rivo volumio[3241]: info: CoreStateMachine::setRandom null Aug 27 09:09:28 rivo volumio[3241]: info: CoreStateMachine::pushState Aug 27 09:09:28 rivo volumio[3241]: info: CorePlayQueue::getTrack 0 Aug 27 09:09:28 rivo volumio[3241]: info: CoreCommandRouter::volumioPushState Aug 27 09:09:28 rivo sudo[3725]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Aug 27 09:09:28 rivo sudo[3725]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 09:09:28 rivo volumio[3241]: info: Asound.conf file unchanged, so no further update is needed Aug 27 09:09:28 rivo volumio[3241]: info: Output device has changed, restarting MPD Aug 27 09:09:28 rivo volumio[3241]: info: Output device has changed, restarting Shairport Sync Aug 27 09:09:28 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 09:09:28 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 09:09:28 rivo sudo[3728]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 27 09:09:28 rivo sudo[3728]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 09:09:28 rivo sudo[3730]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 27 09:09:28 rivo sudo[3730]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 09:09:28 rivo sudo[3728]: pam_unix(sudo:session): session closed for user root Aug 27 09:09:28 rivo volumio[3241]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 27 09:09:28 rivo volumio[3241]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Aug 27 09:09:28 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 09:09:28 rivo volumio[3241]: info: ___________ START PLUGINS ___________ Aug 27 09:09:28 rivo volumio[3241]: info: ControllerMpd::onStart: Initializing MPD Aug 27 09:09:28 rivo volumio[3241]: info: Creating MPD Configuration file Aug 27 09:09:28 rivo systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 27 09:09:28 rivo systemd[1]: Starting mpd.service - Music Player Daemon... Aug 27 09:09:28 rivo sudo[3739]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Aug 27 09:09:28 rivo sudo[3739]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 09:09:28 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 09:09:28 rivo volumio[3241]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 27 09:09:28 rivo volumio[3241]: info: [1787814568624] CoreMusicLibrary::Adding element Media Servers Aug 27 09:09:28 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 27 09:09:28 rivo volumio[3241]: info: UPNP Browser: Client initialized successfully Aug 27 09:09:28 rivo sudo[3742]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 27 09:09:28 rivo sudo[3742]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 09:09:28 rivo sudo[3742]: pam_unix(sudo:session): session closed for user root Aug 27 09:09:28 rivo volumio[3241]: info: Adding METAVOLUMIO REST API Endpoints Aug 27 09:09:28 rivo volumio[3241]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Aug 27 09:09:28 rivo volumio[3241]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Aug 27 09:09:28 rivo volumio[3241]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Aug 27 09:09:28 rivo volumio[3241]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Aug 27 09:09:28 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 09:09:28 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 09:09:28 rivo sudo[3746]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 27 09:09:28 rivo sudo[3746]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 09:09:28 rivo systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Aug 27 09:09:28 rivo sudo[3739]: pam_unix(sudo:session): session closed for user root Aug 27 09:09:28 rivo volumio[3241]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 27 09:09:28 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 09:09:28 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 09:09:28 rivo volumio[3241]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 27 09:09:28 rivo volumio[3241]: info: Preparing CD Folders Aug 27 09:09:28 rivo volumio[3241]: info: Adding CD REST API Endpoints Aug 27 09:09:28 rivo volumio[3241]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Aug 27 09:09:28 rivo volumio[3241]: info: Starting UDEV Watcher for CD Aug 27 09:09:28 rivo volumio[3241]: info: Detecting CD presence with UDEV Aug 27 09:09:28 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Aug 27 09:09:28 rivo systemd[1]: mpd.service: Deactivated successfully. Aug 27 09:09:28 rivo systemd[1]: Stopped mpd.service - Music Player Daemon. Aug 27 09:09:28 rivo systemd[1]: mpd.socket: Deactivated successfully. Aug 27 09:09:28 rivo systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Aug 27 09:09:28 rivo systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Aug 27 09:09:28 rivo systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 27 09:09:28 rivo systemd[1]: Starting mpd.service - Music Player Daemon... Aug 27 09:09:29 rivo volumio5-onboarding[3752]: time=2026-08-27T09:09:29.157+02:00 level=INFO msg="running volumio4-device-gateway" version=2a6bbeeb+CHANGES buildDate=2026-07-03T07:51:28Z Aug 27 09:09:29 rivo sudo[3758]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 27 09:09:29 rivo sudo[3758]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 27 09:09:29 rivo sudo[3769]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Aug 27 09:09:29 rivo sudo[3758]: pam_unix(sudo:session): session closed for user root Aug 27 09:09:30 rivo volumio-remote-updater[2956]: [2026-08-27 09:09:30] [connect] Successful connection Aug 27 09:09:33 rivo mpd[3770]: 2026-08-27T09:09:33 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 27 09:09:33 rivo volumio[3241]: warn: [cd-plugin] cdspeedctl: device or media not ready Aug 27 09:09:33 rivo volumio[3241]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 27 09:09:33 rivo volumio[3241]: info: [1787814573847] CoreMusicLibrary::Adding element Last_100 Aug 27 09:09:33 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 27 09:09:33 rivo volumio[3241]: info: Adding qc_getconfig REST Endpoint for plugin: music_service/qobuzconnect Aug 27 09:09:33 rivo volumio[3241]: info: QobuzConnect: Starting Qobuz Connect socket and service Aug 27 09:09:33 rivo volumio[3241]: info: Starting RAAT Plugin Aug 27 09:09:33 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , addAdditionalUISections Aug 27 09:09:33 rivo volumio[3241]: info: Additional UI Settings Added for plugin music_service/raat Aug 27 09:09:33 rivo volumio[3241]: info: Registering DSP Elements listener and retrieving current ones Aug 27 09:09:33 rivo volumio[3241]: info: Additional DSP elements updated Aug 27 09:09:33 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 09:09:33 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 09:09:33 rivo volumio[3241]: info: Updating RAAT Signal Path Aug 27 09:09:33 rivo volumio[3241]: error: Cannot write to RAAT Client: TypeError: Cannot read properties of undefined (reading 'write') Aug 27 09:09:33 rivo volumio[3241]: info: Streaming services startup Aug 27 09:09:33 rivo volumio[3241]: info: Starting Streaming Daemon Aug 27 09:09:33 rivo sudo[3777]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Aug 27 09:09:33 rivo sudo[3777]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 09:09:33 rivo sudo[3777]: pam_unix(sudo:session): session closed for user root Aug 27 09:09:33 rivo sudo[3782]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Aug 27 09:09:33 rivo sudo[3782]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 09:09:33 rivo volumio[3241]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 27 09:09:33 rivo volumio[3241]: info: [1787814573963] CoreMusicLibrary::Adding element Webradio Aug 27 09:09:33 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 27 09:09:33 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 27 09:09:33 rivo volumio[3241]: info: Initializing BBC Radios Aug 27 09:09:33 rivo sudo[3788]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Aug 27 09:09:33 rivo sudo[3788]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 09:09:34 rivo sudo[3782]: pam_unix(sudo:session): session closed for user root Aug 27 09:09:34 rivo sudo[3790]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 27 09:09:34 rivo sudo[3790]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 09:09:34 rivo systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Aug 27 09:09:34 rivo sudo[3788]: pam_unix(sudo:session): session closed for user root Aug 27 09:09:34 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 27 09:09:34 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 09:09:34 rivo volumio[3241]: info: [now-playing] ConfigUpdater: config is up to date. Aug 27 09:09:34 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Aug 27 09:09:34 rivo sudo[3790]: pam_unix(sudo:session): session closed for user root Aug 27 09:09:34 rivo volumio[3241]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Aug 27 09:09:34 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 09:09:34 rivo volumio[3241]: info: Adding HIGHRESAUDIO REST API Endpoints Aug 27 09:09:34 rivo volumio[3241]: info: Adding saveAccountData_hi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Aug 27 09:09:34 rivo volumio[3241]: info: Initializing Serial Communication on port /dev/ttyS3 Aug 27 09:09:34 rivo volumio[3241]: info: Touch Event Listener Process Starting Aug 27 09:09:34 rivo kernel: meson_uart ffd22000.serial: ttyS3 use xtal(24M) 24000000 change 0 to 9600 Aug 27 09:09:34 rivo kernel: meson_uart ffd22000.serial: ttyS3 use xtal(24M) 24000000 change 9600 to 9600 Aug 27 09:09:34 rivo kernel: meson_uart ffd22000.serial: ttyS3 use xtal(24M) 24000000 change 9600 to 115200 Aug 27 09:09:34 rivo volumio[3241]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 27 09:09:34 rivo volumio[3241]: info: [1787814574240] CoreMusicLibrary::Adding element QOBUZ Aug 27 09:09:34 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 27 09:09:34 rivo volumio[3241]: Cannot find translation for source QOBUZ Aug 27 09:09:34 rivo volumio[3241]: info: Refreshing QOBUZ token Aug 27 09:09:34 rivo sudo[3813]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/xinput --test-xi2 --root Aug 27 09:09:34 rivo sudo[3813]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 09:09:34 rivo volumio[3241]: info: Adding inputs REST Endpoints Aug 27 09:09:34 rivo volumio[3241]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Aug 27 09:09:34 rivo volumio[3241]: info: Scanning Audio Inputs Aug 27 09:09:34 rivo volumio[3241]: info: Checking against Known Cards name Aug 27 09:09:34 rivo volumio[3241]: info: Checking against Known Cards name Aug 27 09:09:34 rivo volumio[3241]: info: Checking against Known Cards name Aug 27 09:09:34 rivo volumio[3241]: info: Checking against Known Cards name Aug 27 09:09:34 rivo volumio[3241]: info: Checking against Known Cards name Aug 27 09:09:34 rivo volumio[3241]: info: Adding Server instance for streaming Aug 27 09:09:34 rivo volumio[3241]: info: Adding TIDAL to Browse Sources Aug 27 09:09:34 rivo volumio[3241]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 27 09:09:34 rivo volumio[3241]: info: [1787814574315] CoreMusicLibrary::Adding element TIDAL Aug 27 09:09:34 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 27 09:09:34 rivo volumio[3241]: Cannot find translation for source QOBUZ Aug 27 09:09:34 rivo volumio[3241]: Cannot find translation for source TIDAL Aug 27 09:09:34 rivo volumio[3241]: info: Refreshing TIDAL token Aug 27 09:09:34 rivo sudo[3824]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x25 0x0 Aug 27 09:09:34 rivo sudo[3824]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 09:09:34 rivo sudo[3824]: pam_unix(sudo:session): session closed for user root Aug 27 09:09:34 rivo sudo[3827]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x28 0x0 Aug 27 09:09:34 rivo sudo[3827]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 09:09:34 rivo sudo[3827]: pam_unix(sudo:session): session closed for user root Aug 27 09:09:34 rivo sudo[3830]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x29 0x1 Aug 27 09:09:34 rivo sudo[3830]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 09:09:34 rivo sudo[3830]: pam_unix(sudo:session): session closed for user root Aug 27 09:09:34 rivo sudo[3833]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x33 0x1 Aug 27 09:09:34 rivo sudo[3833]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 09:09:34 rivo sudo[3833]: pam_unix(sudo:session): session closed for user root Aug 27 09:09:34 rivo volumio[3241]: info: Apply VIM3L Onboard LEDs Settings Aug 27 09:09:34 rivo volumio[3241]: info: Turning On Onboard LEDs Aug 27 09:09:34 rivo volumio[3241]: info: Starting CEC Event listener Aug 27 09:09:34 rivo sudo[3837]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/sys_led/trigger Aug 27 09:09:34 rivo sudo[3837]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 09:09:34 rivo volumio[3241]: info: Loading i18n strings for locale en Aug 27 09:09:34 rivo sudo[3837]: pam_unix(sudo:session): session closed for user root Aug 27 09:09:34 rivo systemd[1]: Started mpd.service - Music Player Daemon. Aug 27 09:09:34 rivo sudo[3730]: pam_unix(sudo:session): session closed for user root Aug 27 09:09:34 rivo sudo[3746]: pam_unix(sudo:session): session closed for user root Aug 27 09:09:34 rivo volumio[3241]: info: Volumio Calling Home Aug 27 09:09:34 rivo sudo[3841]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/red_red/trigger Aug 27 09:09:34 rivo sudo[3841]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 09:09:34 rivo sudo[3841]: pam_unix(sudo:session): session closed for user root Aug 27 09:09:34 rivo volumio[3241]: info: QobuzConnect: Opened /tmp/qbz-connect.socket socket, listening for connections Aug 27 09:09:34 rivo volumio[3241]: (node:3241) [DEP0005] DeprecationWarning: Buffer() is deprecated due to security and usability issues. Please use the Buffer.alloc(), Buffer.allocUnsafe(), or Buffer.from() methods instead. Aug 27 09:09:34 rivo volumio[3241]: (Use `node --trace-deprecation ...` to show where the warning was created) Aug 27 09:09:35 rivo volumio[3241]: info: [now-playing] App is listening on port 4004. Aug 27 09:09:35 rivo volumio[3241]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Aug 27 09:09:35 rivo kernel: cectx ff80023c.aocec: bad iniator with self:CEC RX len 5: 0f 87 00 00 f0 Aug 27 09:09:35 rivo volumio[3241]: info: Serial port opened successfully Aug 27 09:09:35 rivo volumio[3241]: info: Sending serial start messages Aug 27 09:09:35 rivo sudo[3856]: 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 27 09:09:35 rivo sudo[3856]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 09:09:35 rivo sudo[3858]: 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 27 09:09:35 rivo sudo[3858]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 09:09:35 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 09:09:35 rivo volumio[3241]: info: Reporting MCU Network Status: 0 Aug 27 09:09:35 rivo volumio[3241]: info: MP1 GPIO: Signalled system ready via GPIO Aug 27 09:09:35 rivo volumio[3241]: error: Cannot start Volumio Streaming Daemon Aug 27 09:09:35 rivo volumio[3241]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Aug 27 09:09:35 rivo volumio[3241]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Aug 27 09:09:35 rivo volumio[3241]: info: RAAT Albumart path created successfully Aug 27 09:09:35 rivo systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Aug 27 09:09:35 rivo systemd[1]: Starting rpc-statd.service - NFS status monitor for NFSv2/3 locking.... Aug 27 09:09:35 rivo volumio[3241]: info: MPD Permissions set Aug 27 09:09:35 rivo volumio[3241]: info: MPD Permissions set Aug 27 09:09:35 rivo rpc.statd[3871]: Version 2.6.2 starting Aug 27 09:09:35 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setDeviceVolumeOverride Aug 27 09:09:35 rivo volumio[3241]: info: Setting Device Volume Override Aug 27 09:09:35 rivo rpc.statd[3871]: Flags: TI-RPC Aug 27 09:09:35 rivo systemd[1]: Started rpc-statd.service - NFS status monitor for NFSv2/3 locking.. Aug 27 09:09:35 rivo pidof[3870]: can't read from 3868/stat Aug 27 09:09:35 rivo volumio[3241]: info: CoreCommandRouter::volumioUpdateVolumeSettings Aug 27 09:09:35 rivo volumio[3241]: info: Updating Volume Controller Parameters: Device: 5 Name: X-SABRE Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Aug 27 09:09:35 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Aug 27 09:09:35 rivo volumio[3241]: info: Disabling external Volume Control Aug 27 09:09:35 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 09:09:35 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 09:09:35 rivo volumio[3241]: info: CoreStateMachine::pushState Aug 27 09:09:35 rivo volumio[3241]: info: CorePlayQueue::getTrack 0 Aug 27 09:09:35 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 09:09:35 rivo volumio[3241]: info: CoreCommandRouter::volumioPushState Aug 27 09:09:35 rivo volumio[3241]: info: CoreCommandRouter::volumioGetState Aug 27 09:09:35 rivo volumio[3241]: info: CorePlayQueue::getTrack 0 Aug 27 09:09:35 rivo volumio[3241]: info: CoreStateMachine::pushState Aug 27 09:09:35 rivo volumio[3241]: info: CorePlayQueue::getTrack 0 Aug 27 09:09:35 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 09:09:35 rivo volumio[3241]: info: CoreCommandRouter::volumioPushState Aug 27 09:09:35 rivo volumio[3241]: info: CoreCommandRouter::volumioGetState Aug 27 09:09:35 rivo volumio[3241]: info: CorePlayQueue::getTrack 0 Aug 27 09:09:35 rivo volumio[3241]: info: Volumio called home Aug 27 09:09:35 rivo volumio[3241]: info: minidlna: Found miniDLNA version 1.3.0 Aug 27 09:09:35 rivo volumio[3241]: info: minidlna: Starting minidlna.service Aug 27 09:09:35 rivo systemd[1]: Reloading. Aug 27 09:09:35 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: system , setAdditionalSVInfo Aug 27 09:09:35 rivo volumio[3241]: info: Setting Additional System Software info: Hardware Revision: 2.0 Aug 27 09:09:35 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: system , setHwFwVersion Aug 27 09:09:35 rivo volumio[3241]: info: Setting HW Firmware info: undefined Aug 27 09:09:35 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: system , setHwVersion Aug 27 09:09:35 rivo volumio[3241]: info: Setting HW Version info: 2.0 Aug 27 09:09:35 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: system , setAdditionalSVInfo Aug 27 09:09:35 rivo volumio[3241]: info: Setting Additional System Software info: Hardware Revision: 2.0, Firmware Version: 0.3.5 Aug 27 09:09:35 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: system , setHwFwVersion Aug 27 09:09:35 rivo volumio[3241]: info: Setting HW Firmware info: 0.3.5 Aug 27 09:09:35 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: system , setHwVersion Aug 27 09:09:35 rivo volumio[3241]: info: Setting HW Version info: 2.0 Aug 27 09:09:35 rivo volumio[3241]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Aug 27 09:09:35 rivo volumio[3241]: info: MCU Signalled Headphone Mode Disabled Aug 27 09:09:35 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Aug 27 09:09:35 rivo volumio[3241]: info: Reporting Headphone State: false Aug 27 09:09:35 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 09:09:35 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 09:09:35 rivo volumio[3241]: info: Updating RAAT Signal Path Aug 27 09:09:35 rivo volumio[3241]: error: Cannot write to RAAT Client: TypeError: Cannot read properties of undefined (reading 'write') Aug 27 09:09:35 rivo volumio[3241]: info: MCU Signalled Sleep Mode Disabled Aug 27 09:09:35 rivo sudo[3878]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start minidlna.service Aug 27 09:09:35 rivo sudo[3878]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 09:09:35 rivo volumio[3241]: info: Enabling Advanced system settings configuration Aug 27 09:09:35 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: system , addAdditionalUISections Aug 27 09:09:35 rivo volumio[3241]: info: Additional UI Settings Added for plugin music_service/inputs Aug 27 09:09:35 rivo sudo[3881]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms force on Aug 27 09:09:35 rivo sudo[3881]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 09:09:35 rivo kernel: fb: osd[0] enable: 1 (Xorg) Aug 27 09:09:35 rivo sudo[3881]: pam_unix(sudo:session): session closed for user root Aug 27 09:09:35 rivo volumio[3241]: error: MPD error: The expression evaluated to a falsy value: Aug 27 09:09:35 rivo volumio[3241]: assert.ok(self.idling) Aug 27 09:09:35 rivo volumio[3241]: error: The expression evaluated to a falsy value: Aug 27 09:09:35 rivo volumio[3241]: assert.ok(self.idling) Aug 27 09:09:35 rivo volumio[3241]: info: MPD running with PID3770 Aug 27 09:09:35 rivo volumio[3241]: ,establishing connection Aug 27 09:09:35 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 09:09:35 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 09:09:35 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 09:09:35 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 09:09:35 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 09:09:35 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 09:09:35 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 09:09:35 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 09:09:35 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 09:09:35 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Aug 27 09:09:35 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 09:09:35 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 09:09:35 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 09:09:35 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 09:09:35 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 09:09:35 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 09:09:35 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 09:09:35 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 09:09:35 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 09:09:35 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 09:09:35 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Aug 27 09:09:35 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 09:09:35 rivo volumio[3241]: info: MCU Signalled Auto Boot Mode On Power Disabled Aug 27 09:09:35 rivo volumio[3241]: error: Serial API: Failed to decode command: MAXVOL, message: 100 Aug 27 09:09:35 rivo volumio[3241]: error: updateQueue error: null Aug 27 09:09:35 rivo sudo[3913]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms 0 0 0 Aug 27 09:09:35 rivo sudo[3913]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 09:09:35 rivo sudo[3913]: pam_unix(sudo:session): session closed for user root Aug 27 09:09:35 rivo volumio[3241]: error: updateQueue error: null Aug 27 09:09:35 rivo volumio[3241]: 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: 1 Aug 27 09:09:35 rivo volumio[3241]: info: Starting Shairport Sync Aug 27 09:09:35 rivo volumio[3241]: info: Starting Shairport Sync Aug 27 09:09:35 rivo volumio[3241]: info: Starting Shairport Sync Aug 27 09:09:35 rivo volumio[3241]: info: Adding Inputs via Serial API Aug 27 09:09:35 rivo sudo[3917]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 27 09:09:35 rivo sudo[3917]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 09:09:35 rivo sudo[3919]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 27 09:09:35 rivo sudo[3919]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 09:09:36 rivo sudo[3922]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 27 09:09:36 rivo sudo[3922]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 09:09:36 rivo volumio[3241]: 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: 1 Aug 27 09:09:36 rivo volumio[3241]: info: Received Get System Info Aug 27 09:09:36 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 27 09:09:36 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 27 09:09:36 rivo volumio[3241]: info: Discovery: Getting this device information Aug 27 09:09:36 rivo volumio[3241]: info: CoreCommandRouter::volumioGetState Aug 27 09:09:36 rivo volumio[3241]: info: CorePlayQueue::getTrack 0 Aug 27 09:09:36 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 27 09:09:36 rivo volumio5-onboarding[3752]: time=2026-08-27T09:09:36.046+02:00 level=INFO msg="system info for f86ae99edda56fd241971db7ab04d4e5" deviceName=Rivo deviceVariant=rivo deviceModel="Volumio Rivo" softwareVersion=4.185 Aug 27 09:09:36 rivo volumio[3241]: info: CoreStateMachine::pushState Aug 27 09:09:36 rivo volumio[3241]: info: CorePlayQueue::getTrack 0 Aug 27 09:09:36 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 09:09:36 rivo volumio5-onboarding[3752]: time=2026-08-27T09:09:36.067+02:00 level=INFO msg="bootstrapping state" hasInternet=true Aug 27 09:09:36 rivo volumio[3241]: info: CoreCommandRouter::volumioPushState Aug 27 09:09:36 rivo volumio[3241]: info: CoreCommandRouter::volumioGetState Aug 27 09:09:36 rivo volumio[3241]: info: CorePlayQueue::getTrack 0 Aug 27 09:09:36 rivo volumio[3241]: info: CoreCommandRouter::servicePushState Aug 27 09:09:36 rivo volumio[3241]: info: CoreStateMachine::pushState Aug 27 09:09:36 rivo volumio[3241]: info: CorePlayQueue::getTrack 0 Aug 27 09:09:36 rivo volumio[3241]: info: CoreCommandRouter::volumioPushState Aug 27 09:09:36 rivo volumio[3241]: info: CoreCommandRouter::volumioGetState Aug 27 09:09:36 rivo volumio[3241]: info: CorePlayQueue::getTrack 0 Aug 27 09:09:36 rivo volumio[3241]: info: CorePlayQueue::getTrack 0 Aug 27 09:09:36 rivo volumio[3241]: verbose: STATE SERVICE {"status":"stop","service":"inputs","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"","seek":0,"duration":0,"samplerate":"","bitdepth":"","stream":true,"disableUiControls":false,"channels":2} Aug 27 09:09:36 rivo volumio[3241]: verbose: CURRENT POSITION 0 Aug 27 09:09:36 rivo volumio[3241]: info: CoreStateMachine::syncState stateService stop Aug 27 09:09:36 rivo volumio[3241]: info: CoreStateMachine::syncState currentStatus stop Aug 27 09:09:36 rivo volumio[3241]: info: CoreStateMachine::pushState Aug 27 09:09:36 rivo volumio[3241]: info: CorePlayQueue::getTrack 0 Aug 27 09:09:36 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 09:09:36 rivo volumio[3241]: info: CoreCommandRouter::volumioPushState Aug 27 09:09:36 rivo volumio[3241]: info: CoreCommandRouter::volumioGetState Aug 27 09:09:36 rivo volumio[3241]: info: CorePlayQueue::getTrack 0 Aug 27 09:09:36 rivo volumio[3241]: info: No code Aug 27 09:09:36 rivo volumio[3241]: info: CoreStateMachine::pushState Aug 27 09:09:36 rivo volumio[3241]: info: CorePlayQueue::getTrack 0 Aug 27 09:09:36 rivo volumio[3241]: info: CoreCommandRouter::volumioPushState Aug 27 09:09:36 rivo volumio[3241]: info: CoreCommandRouter::volumioGetState Aug 27 09:09:36 rivo volumio[3241]: info: CorePlayQueue::getTrack 0 Aug 27 09:09:36 rivo volumio[3241]: info: CoreCommandRouter::volumiosetSourceActiveno-source Aug 27 09:09:36 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 27 09:09:36 rivo volumio[3241]: Cannot find translation for source QOBUZ Aug 27 09:09:36 rivo volumio[3241]: Cannot find translation for source TIDAL Aug 27 09:09:36 rivo kernel: cectx ff80023c.aocec: bad iniator with self:CEC RX len 5: 0f 87 00 00 f0 Aug 27 09:09:36 rivo volumio[3241]: info: Executing endpoint qc_getconfig Aug 27 09:09:36 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Aug 27 09:09:36 rivo qobuz-connect[3803]: 20260827 09:09:36.212 [3803.3803] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Aug 27 09:09:36 rivo volumio[3241]: info: Received Get System Info Aug 27 09:09:36 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 27 09:09:36 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 27 09:09:36 rivo volumio[3241]: info: Discovery: Getting this device information Aug 27 09:09:36 rivo volumio[3241]: info: CoreCommandRouter::volumioGetState Aug 27 09:09:36 rivo volumio[3241]: info: CorePlayQueue::getTrack 0 Aug 27 09:09:36 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 27 09:09:36 rivo volumio[3241]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Aug 27 09:09:36 rivo volumio[3241]: info: QobuzConnect: QOBUZ Connect daemon connected Aug 27 09:09:36 rivo qobuz-connect[3803]: 20260827 09:09:36.379 [3803.3803] INFO VolumeManager: [0xab8fcf68]: Setting new playback volume: 75 Aug 27 09:09:36 rivo qobuz-connect[3803]: 20260827 09:09:36.380 [3803.3803] INFO VolumeManager: [0xab8fcf68]: Setting new mute state: 0 Aug 27 09:09:36 rivo qobuz-connect[3803]: 20260827 09:09:36.380 [3803.3803] INFO AudioStreamManager: [0xab8fccc0]: Setting new audio download buffer size: 1048576 Aug 27 09:09:36 rivo qobuz-connect[3803]: 20260827 09:09:36.380 [3803.3803] INFO QobuzConnect: [0xab8fd830]: Client initialized! Aug 27 09:09:36 rivo qobuz-connect[3803]: 20260827 09:09:36.380 [3803.3803] INFO SampleApp: Starting Avahi advertising, name: Rivo, service name: _qobuz-connect._tcp Aug 27 09:09:36 rivo qobuz-connect[3803]: 20260827 09:09:36.411 [3803.3803] INFO LocalConfigManager: [0xab8fc9e8]: Starting Local Configuration server Aug 27 09:09:36 rivo qobuz-connect[3803]: 20260827 09:09:36.411 [3803.3803] INFO SampleApp: Starting Local configuration server Aug 27 09:09:36 rivo qobuz-connect[3803]: 20260827 09:09:36.412 [3803.3803] INFO SampleApp: Connected to UNIX socket client 0xab8e7908 Aug 27 09:09:36 rivo qobuz-connect[3803]: 20260827 09:09:36.412 [3803.3803] INFO SampleApp: Playback volume changed: 75 Aug 27 09:09:36 rivo volumio[3241]: info: CoreCommandRouter::volumioGetState Aug 27 09:09:36 rivo volumio[3241]: info: CorePlayQueue::getTrack 0 Aug 27 09:09:37 rivo volumio[3241]: info: TidalConnect service stoped! Aug 27 09:09:37 rivo systemd[1]: systemd-udevd.service: bpf-firewall: Preparation of BPF allow maps failed: Invalid argument Aug 27 09:09:37 rivo volumio[3241]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Aug 27 09:09:37 rivo volumio[3241]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Aug 27 09:09:37 rivo sudo[3960]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Aug 27 09:09:37 rivo sudo[3960]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 09:09:37 rivo volumio[3241]: info: Turning Off Onboard LEDs Aug 27 09:09:37 rivo sudo[3964]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/sys_led/trigger Aug 27 09:09:37 rivo sudo[3964]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 09:09:37 rivo sudo[3964]: pam_unix(sudo:session): session closed for user root Aug 27 09:09:37 rivo sudo[3969]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/red_red/trigger Aug 27 09:09:37 rivo sudo[3969]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 09:09:37 rivo sudo[3969]: pam_unix(sudo:session): session closed for user root Aug 27 09:09:37 rivo volumio[3241]: info: Onboard LEDs initialized Aug 27 09:09:37 rivo systemd[1]: Started minidlna.service - MiniDLNA lightweight DLNA/UPnP-AV server. Aug 27 09:09:37 rivo sudo[3878]: pam_unix(sudo:session): session closed for user root Aug 27 09:09:37 rivo systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Aug 27 09:09:37 rivo volumio[3241]: info: minidlna: systemctl start minidlna.service succeeded. Aug 27 09:09:37 rivo systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Aug 27 09:09:37 rivo sudo[3960]: pam_unix(sudo:session): session closed for user root Aug 27 09:09:37 rivo sudo[3858]: pam_unix(sudo:session): session closed for user root Aug 27 09:09:37 rivo sudo[3856]: pam_unix(sudo:session): session closed for user root Aug 27 09:09:37 rivo nfsrahead[3977]: setting /mnt/NAS/MJUZA_1 readahead to 128 Aug 27 09:09:37 rivo nfsrahead[3978]: setting /mnt/NAS/MJUZA_2 readahead to 128 Aug 27 09:09:37 rivo systemd[1]: shairport-sync.service: Deactivated successfully. Aug 27 09:09:37 rivo systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 27 09:09:37 rivo systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 27 09:09:37 rivo sudo[3922]: pam_unix(sudo:session): session closed for user root Aug 27 09:09:37 rivo sudo[3917]: pam_unix(sudo:session): session closed for user root Aug 27 09:09:37 rivo sudo[3919]: pam_unix(sudo:session): session closed for user root Aug 27 09:09:37 rivo volumio[3241]: info: Shairport-Sync Started Aug 27 09:09:37 rivo volumio[3241]: Error adding Membership: Error: addMembership EINVAL Aug 27 09:09:37 rivo volumio[3241]: info: Shairport-Sync Started Aug 27 09:09:37 rivo volumio[3241]: info: Shairport-Sync Started Aug 27 09:09:37 rivo volumio[3241]: info: Reporting MCU Network Status: 1 Aug 27 09:09:37 rivo volumio[3241]: info: Volumio Network Manager: Network status updated: 1 Aug 27 09:09:37 rivo systemd[1]: minidlna.service: Deactivated successfully. Aug 27 09:09:38 rivo volumio[3241]: info: Executing endpoint tc_getconfig Aug 27 09:09:38 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Aug 27 09:09:38 rivo vtcs[3979]: STARTING TidalConnect services, version: 1.6.1 Aug 27 09:09:38 rivo vtcs[3979]: STARTED TidalConnect services. Aug 27 09:09:38 rivo volumio[3241]: info: Executing endpoint tc_connect Aug 27 09:09:38 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Aug 27 09:09:38 rivo volumio[3241]: info: Connecting to TidalConnect Aug 27 09:09:38 rivo volumio[3241]: info: CoreCommandRouter::servicePushState Aug 27 09:09:38 rivo volumio[3241]: info: CoreStateMachine::pushState Aug 27 09:09:38 rivo volumio[3241]: info: CorePlayQueue::getTrack 0 Aug 27 09:09:38 rivo volumio[3241]: info: CoreCommandRouter::volumioPushState Aug 27 09:09:38 rivo volumio[3241]: info: CoreCommandRouter::volumioGetState Aug 27 09:09:38 rivo volumio[3241]: info: CorePlayQueue::getTrack 0 Aug 27 09:09:38 rivo volumio[3241]: info: CorePlayQueue::getTrack 0 Aug 27 09:09:38 rivo volumio[3241]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Aug 27 09:09:38 rivo volumio[3241]: verbose: CURRENT POSITION 0 Aug 27 09:09:38 rivo volumio[3241]: info: CoreStateMachine::syncState stateService stop Aug 27 09:09:38 rivo volumio[3241]: info: CoreStateMachine::syncState currentStatus stop Aug 27 09:09:38 rivo volumio[3241]: info: CoreStateMachine::pushState Aug 27 09:09:38 rivo volumio[3241]: info: CorePlayQueue::getTrack 0 Aug 27 09:09:38 rivo volumio[3241]: info: CoreCommandRouter::volumioPushState Aug 27 09:09:38 rivo volumio[3241]: info: CoreCommandRouter::volumioGetState Aug 27 09:09:38 rivo volumio[3241]: info: CorePlayQueue::getTrack 0 Aug 27 09:09:38 rivo volumio[3241]: info: No code Aug 27 09:09:38 rivo volumio[3241]: info: CoreStateMachine::pushState Aug 27 09:09:38 rivo volumio[3241]: info: CorePlayQueue::getTrack 0 Aug 27 09:09:38 rivo volumio[3241]: info: CoreCommandRouter::volumioPushState Aug 27 09:09:38 rivo volumio[3241]: info: CoreCommandRouter::volumioGetState Aug 27 09:09:38 rivo volumio[3241]: info: CorePlayQueue::getTrack 0 Aug 27 09:09:38 rivo volumio[3241]: info: CoreCommandRouter::servicePushState Aug 27 09:09:38 rivo volumio[3241]: info: CoreStateMachine::pushState Aug 27 09:09:38 rivo volumio[3241]: info: CorePlayQueue::getTrack 0 Aug 27 09:09:38 rivo volumio[3241]: info: CoreCommandRouter::volumioPushState Aug 27 09:09:38 rivo volumio[3241]: info: CoreCommandRouter::volumioGetState Aug 27 09:09:38 rivo volumio[3241]: info: CorePlayQueue::getTrack 0 Aug 27 09:09:38 rivo volumio[3241]: info: CorePlayQueue::getTrack 0 Aug 27 09:09:38 rivo volumio[3241]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Aug 27 09:09:38 rivo volumio[3241]: verbose: CURRENT POSITION 0 Aug 27 09:09:38 rivo volumio[3241]: info: CoreStateMachine::syncState stateService stop Aug 27 09:09:38 rivo volumio[3241]: info: CoreStateMachine::syncState currentStatus stop Aug 27 09:09:38 rivo volumio[3241]: info: CoreStateMachine::pushState Aug 27 09:09:38 rivo volumio[3241]: info: CorePlayQueue::getTrack 0 Aug 27 09:09:38 rivo volumio[3241]: info: CoreCommandRouter::volumioPushState Aug 27 09:09:38 rivo volumio[3241]: info: CoreCommandRouter::volumioGetState Aug 27 09:09:38 rivo volumio[3241]: info: CorePlayQueue::getTrack 0 Aug 27 09:09:38 rivo volumio[3241]: info: No code Aug 27 09:09:38 rivo volumio[3241]: info: CoreStateMachine::pushState Aug 27 09:09:38 rivo volumio[3241]: info: CorePlayQueue::getTrack 0 Aug 27 09:09:38 rivo volumio[3241]: info: CoreCommandRouter::volumioPushState Aug 27 09:09:38 rivo volumio[3241]: info: CoreCommandRouter::volumioGetState Aug 27 09:09:38 rivo volumio[3241]: info: CorePlayQueue::getTrack 0 Aug 27 09:09:39 rivo volumio[3241]: info: MRS: Getting audio outputs on start Aug 27 09:09:39 rivo volumio[3241]: info: MRS: Requesting all other devices output Aug 27 09:09:39 rivo systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Aug 27 09:09:39 rivo systemd[1]: systemd-hostnamed.service: Deactivated successfully. Aug 27 09:09:39 rivo sudo[4044]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 27 09:09:39 rivo sudo[4044]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 09:09:39 rivo sudo[4046]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 27 09:09:39 rivo sudo[4046]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 09:09:39 rivo sudo[4044]: pam_unix(sudo:session): session closed for user root Aug 27 09:09:39 rivo sudo[4046]: pam_unix(sudo:session): session closed for user root Aug 27 09:09:40 rivo volumio[3241]: 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: 2 Aug 27 09:09:40 rivo volumio[3241]: info: TidalConnect service started! Aug 27 09:09:40 rivo systemd[1]: setdatetime-helper.service: Deactivated successfully. Aug 27 09:09:40 rivo systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Aug 27 09:09:41 rivo kernel: fb: mem_free_work, free memory: addr:800000 Aug 27 09:09:42 rivo systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 3. Aug 27 09:09:42 rivo systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Aug 27 09:09:42 rivo systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Aug 27 09:09:42 rivo sudo[3725]: pam_unix(sudo:session): session closed for user root Aug 27 09:09:42 rivo volumio[3241]: info: Upmpdcli Daemon Started Aug 27 09:09:43 rivo sudo[4098]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 27 09:09:43 rivo sudo[4098]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 09:09:43 rivo sudo[4100]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 27 09:09:43 rivo sudo[4100]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 09:09:43 rivo sudo[4098]: pam_unix(sudo:session): session closed for user root Aug 27 09:09:43 rivo sudo[4100]: pam_unix(sudo:session): session closed for user root Aug 27 09:09:43 rivo volumio[3241]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Aug 27 09:09:45 rivo volumio[3241]: info: Checking for updated MCU Firmware Aug 27 09:09:45 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 27 09:09:45 rivo volumio[3241]: info: Firware on device is on latest version, no need to update Aug 27 09:09:45 rivo volumio-remote-updater[2956]: [2026-08-27 09:09:45] [connect] Successful connection Aug 27 09:09:46 rivo volumio[3241]: 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 27 09:09:57 rivo volumio[3241]: info: Discovery: adding 03ab7cbd-71ad-4a1f-bf87-b8c8b0e4a6d1 Aug 27 09:09:57 rivo volumio[3241]: info: Discovery: Found device Rivo Aug 27 09:09:57 rivo volumio[3241]: info: CoreCommandRouter::volumioGetState Aug 27 09:09:57 rivo volumio[3241]: info: CorePlayQueue::getTrack 0 Aug 27 09:09:57 rivo volumio[3241]: info: MRS: Pushing multiroomSync output for this device Aug 27 09:09:57 rivo volumio[3241]: info: MRS: Pushing multiroomSync output Aug 27 09:09:57 rivo volumio[3241]: info: Adding audio output: Aug 27 09:09:57 rivo volumio[3241]: info: Adding audio output: Aug 27 09:09:57 rivo volumio[3241]: info: Discovery: this is already registered, 03ab7cbd-71ad-4a1f-bf87-b8c8b0e4a6d1 Aug 27 09:09:57 rivo volumio[3241]: info: Discovery: Found device Rivo Aug 27 09:09:57 rivo volumio[3241]: info: CoreCommandRouter::volumioGetState Aug 27 09:09:57 rivo volumio[3241]: info: CorePlayQueue::getTrack 0 Aug 27 09:10:00 rivo volumio-remote-updater[2956]: [2026-08-27 09:10:00] [connect] Successful connection Aug 27 09:10:02 rivo volumio[3241]: info: Access Token successfully retrieved Aug 27 09:10:03 rivo volumio[3241]: info: Stopping AccessToken refresher cron for QOBUZ Aug 27 09:10:03 rivo volumio[3241]: info: AccessToken refresher cron started for QOBUZ Aug 27 09:10:03 rivo volumio[3241]: info: Adding QOBUZ REST API Endpoints Aug 27 09:10:03 rivo volumio[3241]: info: Access Token successfully retrieved Aug 27 09:10:03 rivo upmpdcli[4436]: writing RSA key Aug 27 09:10:04 rivo volumio[3241]: error: Hi Res Audio Failed Login: [object Object] Aug 27 09:10:05 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 27 09:10:05 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 09:10:05 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 27 09:10:05 rivo volumio[3241]: error: Plugin music_service tidal failed to complete 'onStart' in a timely fashion Aug 27 09:10:05 rivo volumio[3241]: info: ------------------------------------------- Aug 27 09:10:05 rivo volumio[3241]: info: ----- MyVolumio plugins startup ---- Aug 27 09:10:05 rivo volumio[3241]: info: ------------------------------------------- Aug 27 09:10:05 rivo volumio[3241]: info: [MyVolumio PluginManager] Fetching plans data.... Aug 27 09:10:06 rivo volumio5-onboarding[3752]: failed to bootstrap state: failed to check for software update: could not check for updates: context deadline exceeded Aug 27 09:10:06 rivo systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Aug 27 09:10:06 rivo systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Aug 27 09:10:06 rivo systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 1. Aug 27 09:10:06 rivo systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Aug 27 09:10:06 rivo systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Aug 27 09:10:06 rivo volumio5-onboarding[4445]: time=2026-08-27T09:10:06.466+02:00 level=INFO msg="running volumio4-device-gateway" version=2a6bbeeb+CHANGES buildDate=2026-07-03T07:51:28Z Aug 27 09:10:07 rivo volumio[3241]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Aug 27 09:10:09 rivo volumio[3241]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Aug 27 09:10:12 rivo volumio[3241]: 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: 5 Aug 27 09:10:14 rivo volumio[3241]: 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: 5 Aug 27 09:10:14 rivo volumio[3241]: info: Received Get System Info Aug 27 09:10:14 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 27 09:10:14 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 27 09:10:14 rivo volumio[3241]: info: Discovery: Getting this device information Aug 27 09:10:14 rivo volumio[3241]: info: CoreCommandRouter::volumioGetState Aug 27 09:10:14 rivo volumio[3241]: info: CorePlayQueue::getTrack 0 Aug 27 09:10:14 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 27 09:10:14 rivo volumio5-onboarding[4445]: time=2026-08-27T09:10:14.501+02:00 level=INFO msg="system info for f86ae99edda56fd241971db7ab04d4e5" deviceName=Rivo deviceVariant=rivo deviceModel="Volumio Rivo" softwareVersion=4.185 Aug 27 09:10:14 rivo volumio5-onboarding[4445]: time=2026-08-27T09:10:14.520+02:00 level=INFO msg="bootstrapping state" hasInternet=true Aug 27 09:10:15 rivo volumio-remote-updater[2956]: [2026-08-27 09:10:15] [connect] Successful connection Aug 27 09:10:17 rivo volumio[3241]: info: Received Get System Info Aug 27 09:10:17 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 27 09:10:17 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 27 09:10:17 rivo volumio[3241]: info: Discovery: Getting this device information Aug 27 09:10:17 rivo volumio[3241]: info: CoreCommandRouter::volumioGetState Aug 27 09:10:17 rivo volumio[3241]: info: CorePlayQueue::getTrack 0 Aug 27 09:10:17 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 27 09:10:19 rivo volumio-remote-updater[2956]: [2026-08-27 09:10:19] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1787814615 101 Aug 27 09:10:19 rivo volumio[3241]: 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: 6 Aug 27 09:10:25 rivo volumio[3241]: info: MRS: Found cast device: CHSTB02-88837a1d9d2011fd891ba3df03e82fab Aug 27 09:10:25 rivo volumio[3241]: info: Adding audio output: Aug 27 09:10:25 rivo volumio[3241]: info: MRS: Found cast device: CHSTB02-2c07cb952969f3ad2bbb27b23c87610d Aug 27 09:10:25 rivo volumio[3241]: info: Adding audio output: Aug 27 09:10:27 rivo volumio[3241]: error: MyVolumio Plugin failed to start in a timely fashion Aug 27 09:10:27 rivo volumio[3241]: [Metrics] CommandRouter: 83s 446.96ms Aug 27 09:10:27 rivo volumio[3241]: info: CoreCommandRouter::volumiosetStartupVolume Aug 27 09:10:27 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 09:10:27 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 09:10:27 rivo volumio[3241]: info: CoreCommandRouter::Close All Modals sent Aug 27 09:10:27 rivo volumio[3241]: info: CoreCommandRouter::Close All Modals sent Aug 27 09:10:29 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Aug 27 09:10:29 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 27 09:10:29 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Aug 27 09:10:41 rivo volumio[3241]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Aug 27 09:10:41 rivo volumio[3241]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Aug 27 09:10:41 rivo volumio[3241]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Aug 27 09:10:41 rivo volumio[3241]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Aug 27 09:10:41 rivo volumio[3241]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Aug 27 09:10:41 rivo volumio[3241]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Aug 27 09:10:41 rivo volumio[3241]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Aug 27 09:10:41 rivo volumio[3241]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Aug 27 09:10:41 rivo volumio[3241]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Aug 27 09:10:41 rivo volumio[3241]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Aug 27 09:10:41 rivo volumio[3241]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Aug 27 09:10:41 rivo volumio[3241]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Aug 27 09:10:41 rivo volumio[3241]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Aug 27 09:10:41 rivo volumio[3241]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Aug 27 09:10:41 rivo volumio[3241]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Aug 27 09:10:41 rivo volumio[3241]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Aug 27 09:10:41 rivo volumio[3241]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Aug 27 09:10:41 rivo volumio[3241]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Aug 27 09:10:41 rivo volumio[3241]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Aug 27 09:10:41 rivo volumio[3241]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Aug 27 09:10:41 rivo volumio[3241]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Aug 27 09:10:41 rivo volumio[3241]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Aug 27 09:10:41 rivo volumio[3241]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Aug 27 09:10:41 rivo volumio[3241]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Aug 27 09:10:41 rivo volumio[3241]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Aug 27 09:10:41 rivo volumio[3241]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Aug 27 09:10:41 rivo volumio[3241]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Aug 27 09:10:41 rivo volumio[3241]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Aug 27 09:10:41 rivo volumio[3241]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Aug 27 09:10:43 rivo volumio[3241]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Aug 27 09:10:43 rivo volumio[3241]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Aug 27 09:10:43 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 09:10:43 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 09:10:43 rivo volumio[3241]: info: Starting MyVolumio Remote Streaming Endpoints Aug 27 09:10:43 rivo volumio[3241]: info: MyVolumio login type: Token Aug 27 09:10:43 rivo volumio[3241]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Aug 27 09:10:43 rivo volumio[3241]: 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 27 09:10:43 rivo volumio[3241]: 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 27 09:10:43 rivo volumio[3241]: info: BOOT COMPLETED Aug 27 09:10:43 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 09:10:43 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 09:10:43 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 09:10:43 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 09:10:43 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 09:10:43 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 09:10:43 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 09:10:43 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 09:10:43 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 09:10:43 rivo volumio[3241]: info: Not Reporting Auto name since its the default one Aug 27 09:10:43 rivo volumio[3241]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 27 09:10:43 rivo volumio[3241]: Error [ERR_INTERNAL_ASSERTION]: This is caused by either a bug in Node.js or incorrect usage of Node.js internals. Aug 27 09:10:43 rivo volumio[3241]: Please open an issue with this stack trace at https://github.com/nodejs/node/issues Aug 27 09:10:43 rivo volumio[3241]: at new NodeError (node:internal/errors:405:5) Aug 27 09:10:43 rivo volumio[3241]: at assert (node:internal/assert:14:11) Aug 27 09:10:43 rivo volumio[3241]: at internalConnectMultiple (node:net:1118:3) Aug 27 09:10:43 rivo volumio[3241]: at Timeout.internalConnectMultipleTimeout (node:net:1687:3) Aug 27 09:10:43 rivo volumio[3241]: at listOnTimeout (node:internal/timers:575:11) Aug 27 09:10:43 rivo volumio[3241]: at process.processTimers (node:internal/timers:514:7) { Aug 27 09:10:43 rivo volumio[3241]: code: 'ERR_INTERNAL_ASSERTION' Aug 27 09:10:43 rivo volumio[3241]: } Aug 27 09:10:43 rivo volumio[3241]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 27 09:10:44 rivo volumio5-onboarding[4445]: failed to bootstrap state: failed to check for software update: could not check for updates: context deadline exceeded Aug 27 09:10:44 rivo systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Aug 27 09:10:44 rivo systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Aug 27 09:10:44 rivo sudo[4601]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-27 09:09' Aug 27 09:10:44 rivo sudo[4601]: 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"