-- Logs begin at Sat 2025-11-15 12:47:36 CET, end at Tue 2025-12-16 11:01:37 CET. -- Dec 16 11:00:21 rivoplus systemd-timedated[3345]: Changed local time to Tue Dec 16 11:00:21 2025 Dec 16 11:00:21 rivoplus systemd[1]: Started Volumio Time Update Utility. Dec 16 11:00:21 rivoplus volumio-time-update[2830]: volumio-time-update-util: System time updated successfully. Dec 16 11:00:21 rivoplus sudo[3313]: pam_unix(sudo:session): session closed for user root Dec 16 11:00:21 rivoplus systemd[1]: Starting Daily apt download activities... Dec 16 11:00:21 rivoplus systemd[1]: Starting Daily man-db regeneration... Dec 16 11:00:21 rivoplus kernel: fb: osd[0] canvas.idx =0x40 Dec 16 11:00:21 rivoplus kernel: fb: osd[0] canvas.addr=0x70000000 Dec 16 11:00:21 rivoplus kernel: fb: osd[0] canvas.width=7680 Dec 16 11:00:21 rivoplus kernel: fb: osd[0] canvas.height=1080 Dec 16 11:00:21 rivoplus kernel: fb: osd[0] frame.width=1920 Dec 16 11:00:21 rivoplus kernel: fb: osd[0] frame.height=1080 Dec 16 11:00:21 rivoplus kernel: fb: osd[0] out_addr_id =0x1 Dec 16 11:00:21 rivoplus kernel: fb: osd[0] enable: 0 (Xorg) Dec 16 11:00:21 rivoplus systemd[1]: man-db.service: Succeeded. Dec 16 11:00:21 rivoplus systemd[1]: Started Daily man-db regeneration. Dec 16 11:00:21 rivoplus systemd[1]: systemd-rfkill.service: Succeeded. Dec 16 11:00:21 rivoplus ntpd[3354]: Soliciting pool server 217.147.223.78 Dec 16 11:00:22 rivoplus kernel: fb: osd[0] enable: 1 (Xorg) Dec 16 11:00:22 rivoplus ntpd[3354]: Soliciting pool server 212.51.144.46 Dec 16 11:00:22 rivoplus ntpd[3354]: Soliciting pool server 193.134.29.12 Dec 16 11:00:22 rivoplus sh[2752]: eth0: leased 192.168.178.201 for 30 seconds Dec 16 11:00:22 rivoplus sh[2752]: eth0: adding route to 192.168.178.0/24 Dec 16 11:00:22 rivoplus sh[2752]: eth0: adding default route via 192.168.178.2 Dec 16 11:00:22 rivoplus dhcpcd[2804]: eth0: leased 192.168.178.201 for 30 seconds Dec 16 11:00:22 rivoplus avahi-daemon[2829]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.178.201. Dec 16 11:00:22 rivoplus dhcpcd[2804]: eth0: adding route to 192.168.178.0/24 Dec 16 11:00:22 rivoplus avahi-daemon[2829]: New relevant interface eth0.IPv4 for mDNS. Dec 16 11:00:22 rivoplus dhcpcd[2804]: eth0: adding default route via 192.168.178.2 Dec 16 11:00:22 rivoplus avahi-daemon[2829]: Registering new address record for 192.168.178.201 on eth0.IPv4. Dec 16 11:00:23 rivoplus sh[2752]: Warning: The unit file, source configuration file or drop-ins of systemd-timesyncd.service changed on disk. Run 'systemctl daemon-reload' to reload units. Dec 16 11:00:23 rivoplus dhcpcd[3355]: eth0: leased 192.168.178.201 for 30 seconds Dec 16 11:00:23 rivoplus dhcpcd[3355]: eth0: adding route to 192.168.178.0/24 Dec 16 11:00:23 rivoplus dhcpcd[3355]: eth0: adding default route via 192.168.178.2 Dec 16 11:00:23 rivoplus ntpd[3354]: ntpd exiting on signal 15 (Terminated) Dec 16 11:00:23 rivoplus ntpd[3354]: 217.147.223.78 local addr 192.168.178.40 -> Dec 16 11:00:23 rivoplus ntpd[3354]: 212.51.144.46 local addr 192.168.178.40 -> Dec 16 11:00:23 rivoplus ntpd[3354]: 193.134.29.12 local addr 192.168.178.40 -> Dec 16 11:00:23 rivoplus systemd[1]: Stopping Network Time Service... Dec 16 11:00:23 rivoplus kernel: fb: osd[0] enable: 1 (Xorg) Dec 16 11:00:23 rivoplus systemd[1]: ntp.service: Succeeded. Dec 16 11:00:23 rivoplus systemd[1]: Stopped Network Time Service. Dec 16 11:00:23 rivoplus systemd[1]: Starting Network Time Service... Dec 16 11:00:23 rivoplus ntpd[3493]: ntpd 4.2.8p12@1.3728-o (1): Starting Dec 16 11:00:23 rivoplus ntpd[3493]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Dec 16 11:00:23 rivoplus systemd[1]: Started Network Time Service. Dec 16 11:00:23 rivoplus sh[2752]: forked to background, child pid 3519 Dec 16 11:00:23 rivoplus dhcpcd[2804]: forked to background, child pid 3519 Dec 16 11:00:23 rivoplus ntpd[3518]: proto: precision = 1.208 usec (-20) Dec 16 11:00:23 rivoplus ntpd[3518]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Dec 16 11:00:23 rivoplus ntpd[3518]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Dec 16 11:00:23 rivoplus ntpd[3518]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 1085 days ago Dec 16 11:00:23 rivoplus ntpd[3518]: Listen and drop on 0 v6wildcard [::]:123 Dec 16 11:00:23 rivoplus ntpd[3518]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Dec 16 11:00:23 rivoplus ntpd[3518]: Listen normally on 2 lo 127.0.0.1:123 Dec 16 11:00:23 rivoplus ntpd[3518]: Listen normally on 3 eth0 192.168.178.201:123 Dec 16 11:00:23 rivoplus ntpd[3518]: Listen normally on 4 wlan0 192.168.178.40:123 Dec 16 11:00:23 rivoplus ntpd[3518]: Listening on routing socket on fd #21 for interface updates Dec 16 11:00:23 rivoplus ntpd[3518]: kernel reports TIME_ERROR: 0x4041: Clock Unsynchronized Dec 16 11:00:23 rivoplus ntpd[3518]: kernel reports TIME_ERROR: 0x4041: Clock Unsynchronized Dec 16 11:00:23 rivoplus systemd[1]: apt-daily.service: Succeeded. Dec 16 11:00:23 rivoplus systemd[1]: Started Daily apt download activities. Dec 16 11:00:23 rivoplus systemd[1]: Starting Daily apt upgrade and clean activities... Dec 16 11:00:23 rivoplus ntpd[3518]: ntpd exiting on signal 15 (Terminated) Dec 16 11:00:23 rivoplus systemd[1]: Stopping Network Time Service... Dec 16 11:00:23 rivoplus nmbd[3050]: [2025/12/16 11:00:23.661118, 0] ../lib/util/become_daemon.c:138(daemon_ready) Dec 16 11:00:23 rivoplus 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. Dec 16 11:00:23 rivoplus nmbd[3050]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Dec 16 11:00:23 rivoplus systemd[1]: Started Samba NMB Daemon. Dec 16 11:00:23 rivoplus systemd[1]: Starting Samba Winbind Daemon... Dec 16 11:00:23 rivoplus ifplugd(eth0)[3083]: client: ifup: interface eth0 already configured Dec 16 11:00:23 rivoplus systemd[1]: ntp.service: Succeeded. Dec 16 11:00:23 rivoplus systemd[1]: Stopped Network Time Service. Dec 16 11:00:23 rivoplus sh[2752]: eth0=eth0 Dec 16 11:00:23 rivoplus systemd[1]: Starting Network Time Service... Dec 16 11:00:23 rivoplus ifplugd(eth0)[3083]: Program executed successfully. Dec 16 11:00:23 rivoplus ntpd[3572]: ntpd 4.2.8p12@1.3728-o (1): Starting Dec 16 11:00:23 rivoplus ntpd[3572]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Dec 16 11:00:23 rivoplus systemd[1]: Started Network Time Service. Dec 16 11:00:23 rivoplus ntpd[3580]: proto: precision = 1.250 usec (-20) Dec 16 11:00:23 rivoplus ntpd[3580]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Dec 16 11:00:23 rivoplus ntpd[3580]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Dec 16 11:00:23 rivoplus ntpd[3580]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 1085 days ago Dec 16 11:00:23 rivoplus ntpd[3580]: Listen and drop on 0 v6wildcard [::]:123 Dec 16 11:00:23 rivoplus ntpd[3580]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Dec 16 11:00:23 rivoplus ntpd[3580]: Listen normally on 2 lo 127.0.0.1:123 Dec 16 11:00:23 rivoplus ntpd[3580]: Listen normally on 3 eth0 192.168.178.201:123 Dec 16 11:00:23 rivoplus ntpd[3580]: Listen normally on 4 wlan0 192.168.178.40:123 Dec 16 11:00:23 rivoplus ntpd[3580]: Listening on routing socket on fd #21 for interface updates Dec 16 11:00:23 rivoplus ntpd[3580]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Dec 16 11:00:23 rivoplus ntpd[3580]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Dec 16 11:00:24 rivoplus volumio[3283]: info: ------------------------------------------- Dec 16 11:00:24 rivoplus volumio[3283]: info: ----- Volumio3 ---- Dec 16 11:00:24 rivoplus volumio[3283]: info: ------------------------------------------- Dec 16 11:00:24 rivoplus volumio[3283]: info: ----- System startup ---- Dec 16 11:00:24 rivoplus volumio[3283]: info: ------------------------------------------- Dec 16 11:00:24 rivoplus winbindd[3566]: [2025/12/16 11:00:24.154394, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Dec 16 11:00:24 rivoplus winbindd[3566]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Dec 16 11:00:24 rivoplus winbindd[3566]: [2025/12/16 11:00:24.171366, 0] ../lib/util/become_daemon.c:138(daemon_ready) Dec 16 11:00:24 rivoplus systemd[1]: Started Samba Winbind Daemon. Dec 16 11:00:24 rivoplus winbindd[3566]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Dec 16 11:00:24 rivoplus systemd[1]: Starting Samba SMB Daemon... Dec 16 11:00:24 rivoplus smbd[3599]: [2025/12/16 11:00:24.872997, 0] ../lib/util/become_daemon.c:138(daemon_ready) Dec 16 11:00:24 rivoplus systemd[1]: Started Samba SMB Daemon. Dec 16 11:00:24 rivoplus smbd[3599]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Dec 16 11:00:24 rivoplus systemd[1]: Reached target Multi-User System. Dec 16 11:00:24 rivoplus systemd[1]: Reached target Graphical Interface. Dec 16 11:00:24 rivoplus systemd[1]: Starting Update UTMP about System Runlevel Changes... Dec 16 11:00:24 rivoplus systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Dec 16 11:00:24 rivoplus systemd[1]: Started Update UTMP about System Runlevel Changes. Dec 16 11:00:25 rivoplus volumio[3283]: info: MYVOLUMIO Environment detected Dec 16 11:00:25 rivoplus volumio[3283]: info: Plugin folders cleanup Dec 16 11:00:25 rivoplus volumio[3283]: info: Scanning into folder /volumio/app/plugins/ Dec 16 11:00:25 rivoplus volumio[3283]: info: Scanning category audio_interface Dec 16 11:00:25 rivoplus volumio[3283]: info: Scanning category miscellanea Dec 16 11:00:25 rivoplus volumio[3283]: info: Scanning category music_service Dec 16 11:00:25 rivoplus volumio[3283]: info: Scanning category plugins.json Dec 16 11:00:25 rivoplus volumio[3283]: info: Scanning category system_controller Dec 16 11:00:25 rivoplus volumio[3283]: info: Scanning category user_interface Dec 16 11:00:25 rivoplus volumio[3283]: info: Scanning into folder /data/plugins/ Dec 16 11:00:25 rivoplus volumio[3283]: info: Scanning category audio_interface Dec 16 11:00:25 rivoplus volumio[3283]: info: Scanning category music_service Dec 16 11:00:25 rivoplus volumio[3283]: info: Scanning category user_interface Dec 16 11:00:25 rivoplus volumio[3283]: info: Plugin folders cleanup completed Dec 16 11:00:25 rivoplus volumio[3283]: info: ------------------------------------------- Dec 16 11:00:25 rivoplus volumio[3283]: info: ----- Core plugins startup ---- Dec 16 11:00:25 rivoplus volumio[3283]: info: ------------------------------------------- Dec 16 11:00:25 rivoplus volumio[3283]: info: Loading plugins from folder /volumio/app/plugins/ Dec 16 11:00:25 rivoplus volumio[3283]: info: Adding plugin bluetooth to MyMusic Plugins Dec 16 11:00:25 rivoplus volumio[3283]: info: Adding plugin multiroom to MyMusic Plugins Dec 16 11:00:25 rivoplus volumio[3283]: info: Adding plugin upnp to MyMusic Plugins Dec 16 11:00:25 rivoplus volumio[3283]: info: Adding plugin metavolumio to MyMusic Plugins Dec 16 11:00:25 rivoplus volumio[3283]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 16 11:00:25 rivoplus volumio[3283]: info: Adding plugin cd_controller to MyMusic Plugins Dec 16 11:00:25 rivoplus volumio[3283]: info: Adding plugin qobuzconnect to MyMusic Plugins Dec 16 11:00:25 rivoplus volumio[3283]: info: Adding plugin raat to MyMusic Plugins Dec 16 11:00:25 rivoplus volumio[3283]: info: Adding plugin smart_inputs to MyMusic Plugins Dec 16 11:00:25 rivoplus volumio[3283]: info: Adding plugin tidalconnect to MyMusic Plugins Dec 16 11:00:25 rivoplus volumio[3283]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 16 11:00:25 rivoplus volumio[3283]: info: Loading plugins from folder /data/plugins/ Dec 16 11:00:25 rivoplus volumio[3283]: info: Loading plugin "system"... Dec 16 11:00:25 rivoplus systemd[1]: apt-daily-upgrade.service: Succeeded. Dec 16 11:00:25 rivoplus systemd[1]: Started Daily apt upgrade and clean activities. Dec 16 11:00:25 rivoplus systemd[1]: Startup finished in 12.852s (kernel) + 24.708s (userspace) = 37.561s. Dec 16 11:00:25 rivoplus volumio[3283]: info: Loading plugin "appearance"... Dec 16 11:00:26 rivoplus ntpd[3580]: Soliciting pool server 217.147.223.78 Dec 16 11:00:27 rivoplus volumio[3283]: info: Loading plugin "network"... Dec 16 11:00:27 rivoplus volumio[3283]: info: Refreshing Cached IP Addresses Dec 16 11:00:27 rivoplus sudo[3654]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 16 11:00:27 rivoplus sudo[3654]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 11:00:27 rivoplus sudo[3656]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 16 11:00:27 rivoplus sudo[3656]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 11:00:27 rivoplus sudo[3654]: pam_unix(sudo:session): session closed for user root Dec 16 11:00:27 rivoplus volumio[3283]: info: Loading plugin "services"... Dec 16 11:00:27 rivoplus volumio[3283]: info: Loading plugin "alsa_controller"... Dec 16 11:00:27 rivoplus sudo[3656]: pam_unix(sudo:session): session closed for user root Dec 16 11:00:27 rivoplus sudo[3667]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 16 11:00:27 rivoplus sudo[3667]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 11:00:27 rivoplus kernel: wl_escan_set_scan: ioctl_ver=2 Dec 16 11:00:27 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc05167c000 drv_status 0x0 e_type 69 e_states 8 Dec 16 11:00:27 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc05167c000 drv_status 0x0 e_type 69 e_states 8 Dec 16 11:00:27 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc05167c000 drv_status 0x0 e_type 69 e_states 8 Dec 16 11:00:27 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc05167c000 drv_status 0x0 e_type 69 e_states 8 Dec 16 11:00:27 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc05167c000 drv_status 0x0 e_type 69 e_states 8 Dec 16 11:00:27 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 16 11:00:27 rivoplus volumio[3283]: info: Loading plugin "wizard"... Dec 16 11:00:27 rivoplus volumio[3283]: info: Loading plugin "networkfs"... Dec 16 11:00:27 rivoplus volumio[3283]: info: Starting Udev Watcher for removable devices Dec 16 11:00:27 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc05167c000 drv_status 0x0 e_type 69 e_states 8 Dec 16 11:00:27 rivoplus volumio[3283]: info: Ignoring mount for partition: BOOT Dec 16 11:00:27 rivoplus volumio[3283]: info: Ignoring mount for partition: volumio Dec 16 11:00:27 rivoplus volumio[3283]: info: Ignoring mount for partition: volumio_data Dec 16 11:00:27 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 16 11:00:27 rivoplus volumio[3283]: info: Loading plugin "volumio_command_line_client"... Dec 16 11:00:27 rivoplus volumio[3283]: info: Loading plugin "upnp"... Dec 16 11:00:27 rivoplus volumio[3283]: info: [1765879227909] Starting Upmpd Daemon Dec 16 11:00:27 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 16 11:00:27 rivoplus volumio[3283]: info: Loading plugin "my_music"... Dec 16 11:00:27 rivoplus volumio[3283]: info: Loading plugin "mpd"... Dec 16 11:00:28 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc05167c000 drv_status 0x0 e_type 69 e_states 8 Dec 16 11:00:28 rivoplus volumio[3283]: info: Loading plugin "upnp_browser"... Dec 16 11:00:28 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc05167c000 drv_status 0x0 e_type 69 e_states 8 Dec 16 11:00:28 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc05167c000 drv_status 0x0 e_type 69 e_states 8 Dec 16 11:00:28 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc05167c000 drv_status 0x0 e_type 69 e_states 8 Dec 16 11:00:28 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc05167c000 drv_status 0x0 e_type 69 e_states 8 Dec 16 11:00:28 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc05167c000 drv_status 0x0 e_type 69 e_states 8 Dec 16 11:00:28 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc05167c000 drv_status 0x0 e_type 69 e_states 8 Dec 16 11:00:28 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc05167c000 drv_status 0x0 e_type 69 e_states 8 Dec 16 11:00:28 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc05167c000 drv_status 0x0 e_type 69 e_states 8 Dec 16 11:00:28 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc05167c000 drv_status 0x0 e_type 69 e_states 8 Dec 16 11:00:28 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc05167c000 drv_status 0x0 e_type 69 e_states 8 Dec 16 11:00:28 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc05167c000 drv_status 0x0 e_type 69 e_states 8 Dec 16 11:00:28 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc05167c000 drv_status 0x0 e_type 69 e_states 8 Dec 16 11:00:29 rivoplus volumio[3283]: info: Loading plugin "bluetooth"... Dec 16 11:00:29 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc05167c000 drv_status 0x0 e_type 69 e_states 8 Dec 16 11:00:29 rivoplus volumio[3283]: info: [1765879229294] Starting BluetoothController Dec 16 11:00:29 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc05167c000 drv_status 0x0 e_type 69 e_states 8 Dec 16 11:00:29 rivoplus volumio[3283]: info: Loading plugin "alarm-clock"... Dec 16 11:00:29 rivoplus volumio[3283]: info: Loading plugin "manifestui"... Dec 16 11:00:30 rivoplus volumio[3283]: info: Loading plugin "metavolumio"... Dec 16 11:00:30 rivoplus volumio-remote-updater[2827]: [2025-12-16 11:00:30] [connect] Successful connection Dec 16 11:00:30 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc05167c000 drv_status 0x0 e_type 69 e_states 8 Dec 16 11:00:30 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc05167c000 drv_status 0x0 e_type 69 e_states 8 Dec 16 11:00:30 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc05167c000 drv_status 0x0 e_type 69 e_states 8 Dec 16 11:00:30 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc05167c000 drv_status 0x0 e_type 69 e_states 8 Dec 16 11:00:30 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc05167c000 drv_status 0x0 e_type 69 e_states 8 Dec 16 11:00:30 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc05167c000 drv_status 0x0 e_type 69 e_states 8 Dec 16 11:00:30 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc05167c000 drv_status 0x0 e_type 69 e_states 8 Dec 16 11:00:30 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc05167c000 drv_status 0x0 e_type 69 e_states 8 Dec 16 11:00:30 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc05167c000 drv_status 0x0 e_type 69 e_states 8 Dec 16 11:00:31 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc05167c000 drv_status 0x0 e_type 69 e_states 8 Dec 16 11:00:31 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc05167c000 drv_status 0x0 e_type 69 e_states 0 Dec 16 11:00:31 rivoplus sudo[3667]: pam_unix(sudo:session): session closed for user root Dec 16 11:00:31 rivoplus ntpd[3580]: Soliciting pool server 193.134.29.12 Dec 16 11:00:32 rivoplus volumio[3283]: info: Loading plugin "airplay_emulation"... Dec 16 11:00:32 rivoplus volumio[3283]: info: Starting Shairport Sync Dec 16 11:00:32 rivoplus volumio[3283]: info: Loading plugin "cd_controller"... Dec 16 11:00:32 rivoplus volumio[3283]: info: Loading plugin "last_100"... Dec 16 11:00:32 rivoplus volumio[3283]: info: Loading plugin "qobuzconnect"... Dec 16 11:00:32 rivoplus volumio[3283]: info: Loading plugin "raat"... Dec 16 11:00:33 rivoplus volumio[3283]: info: RAAT Plugin loaded Dec 16 11:00:33 rivoplus volumio[3283]: info: Adding restartRAATSocket REST API Endpoint Dec 16 11:00:33 rivoplus volumio[3283]: info: Adding restartRAATSocket REST Endpoint for plugin: music_service/raat Dec 16 11:00:33 rivoplus volumio[3283]: info: Loading plugin "streaming_services"... Dec 16 11:00:34 rivoplus systemd[1]: systemd-fsckd.service: Succeeded. Dec 16 11:00:35 rivoplus volumio[3283]: info: Starting Streaming Service Transparent Proxy Dec 16 11:00:35 rivoplus volumio[3283]: info: Loading plugin "tidalconnect"... Dec 16 11:00:35 rivoplus volumio[3283]: info: Loading plugin "webradio"... Dec 16 11:00:36 rivoplus volumio[3283]: info: Loading plugin "i2s_dacs"... Dec 16 11:00:36 rivoplus volumio[3283]: info: I2S DAC not set, start Auto-detection Dec 16 11:00:36 rivoplus volumio[3283]: info: Loading plugin "volumiodiscovery"... Dec 16 11:00:36 rivoplus volumio[3283]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 16 11:00:36 rivoplus volumio[3283]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 16 11:00:36 rivoplus node[3283]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 16 11:00:36 rivoplus volumio[3283]: *** WARNING *** For more information see Dec 16 11:00:36 rivoplus volumio[3283]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 16 11:00:36 rivoplus volumio[3283]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 16 11:00:36 rivoplus volumio[3283]: *** WARNING *** For more information see Dec 16 11:00:36 rivoplus node[3283]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 16 11:00:36 rivoplus node[3283]: *** WARNING *** For more information see Dec 16 11:00:36 rivoplus node[3283]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 16 11:00:36 rivoplus node[3283]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 16 11:00:36 rivoplus node[3283]: *** WARNING *** For more information see Dec 16 11:00:36 rivoplus volumio[3283]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 16 11:00:36 rivoplus volumio[3283]: info: Discovery: Started advertising with name: Rivoplus Dec 16 11:00:36 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 16 11:00:36 rivoplus volumio[3283]: info: Loading plugin "mixcloud"... Dec 16 11:00:37 rivoplus ntpd[3580]: Soliciting pool server 195.141.190.190 Dec 16 11:00:37 rivoplus volumio[3283]: STREAMING PROXY: Starting server on port 3245 Dec 16 11:00:37 rivoplus volumio[3283]: Node JS runtime: 14 Dec 16 11:00:37 rivoplus volumio[3283]: info: Loading plugin "soundcloud"... Dec 16 11:00:38 rivoplus volumio[3283]: info: Loading plugin "spop"... Dec 16 11:00:39 rivoplus volumio[3283]: info: Loading plugin "ytmusic"... Dec 16 11:00:42 rivoplus ntpd[3580]: Soliciting pool server 193.33.30.39 Dec 16 11:00:43 rivoplus volumio[3283]: info: Plugin multiroom is not enabled Dec 16 11:00:43 rivoplus volumio[3283]: info: Loading plugin "outputs"... Dec 16 11:00:43 rivoplus volumio[3283]: info: Loading plugin "albumart"... Dec 16 11:00:43 rivoplus volumio[3283]: info: Plugin example_plugin is not enabled Dec 16 11:00:43 rivoplus volumio[3283]: info: Loading plugin "hi_res_audio"... Dec 16 11:00:44 rivoplus volumio[3283]: Forking 3 albumart workers Dec 16 11:00:45 rivoplus volumio-remote-updater[2827]: [2025-12-16 11:00:45] [connect] Successful connection Dec 16 11:00:45 rivoplus volumio[3283]: Starting albumart workers Dec 16 11:00:45 rivoplus volumio[3283]: Starting albumart workers Dec 16 11:00:45 rivoplus volumio[3283]: Starting albumart workers Dec 16 11:00:45 rivoplus volumio[3283]: info: Applying required configuration parameters for plugin hi_res_audio Dec 16 11:00:45 rivoplus volumio[3283]: info: Loading plugin "inputs"... Dec 16 11:00:46 rivoplus volumio[3283]: info: Loading plugin "qobuz"... Dec 16 11:00:47 rivoplus dhcpcd[3519]: eth0: failed to renew DHCP, rebinding Dec 16 11:00:47 rivoplus dhcpcd[3519]: eth0: reject NAK via 192.168.178.1 Dec 16 11:00:48 rivoplus dhcpcd[3355]: eth0: failed to renew DHCP, rebinding Dec 16 11:00:48 rivoplus dhcpcd[3355]: eth0: reject NAK via 192.168.178.1 Dec 16 11:00:48 rivoplus dhcpcd[3355]: eth0: reject NAK via 192.168.178.1 Dec 16 11:00:48 rivoplus volumio[3283]: info: Loading plugin "smart_inputs"... Dec 16 11:00:49 rivoplus volumio[3283]: info: Loading plugin "tidal"... Dec 16 11:00:50 rivoplus volumio[3283]: info: Loading plugin "rivopluscontrol"... Dec 16 11:00:50 rivoplus kernel: fb: mem_free_work, free memory: addr:800000 Dec 16 11:00:50 rivoplus volumio[3283]: info: Adding this device properties Dec 16 11:00:50 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: system , setThisDeviceVolumioProperties Dec 16 11:00:50 rivoplus volumio[3283]: info: Setting Additional Device Volumio Properties: [object Object] Dec 16 11:00:50 rivoplus volumio[3283]: info: Loading plugin "updater_comm"... Dec 16 11:00:51 rivoplus systemd[1]: systemd-timedated.service: Succeeded. Dec 16 11:00:51 rivoplus volumio[3283]: info: Loading plugin "cec_controller"... Dec 16 11:00:51 rivoplus volumio[3283]: info: Applying required configuration parameters for plugin cec_controller Dec 16 11:00:51 rivoplus volumio[3283]: info: Plugin mpdemulation is not enabled Dec 16 11:00:51 rivoplus volumio[3283]: info: Loading plugin "rest_api"... Dec 16 11:00:51 rivoplus volumio[3283]: info: Loading plugin "websocket"... Dec 16 11:00:51 rivoplus volumio[3283]: info: Starting Socket.io Server version 2.3.0 Dec 16 11:00:51 rivoplus volumio[3283]: info: Loading plugin "fusiondsp"... Dec 16 11:00:52 rivoplus dhcpcd[3519]: eth0: reject NAK via 192.168.178.1 Dec 16 11:00:52 rivoplus volumio[3283]: info: Applying required configuration parameters for plugin fusiondsp Dec 16 11:00:52 rivoplus volumio[3283]: info: Loading plugin "80s80s"... Dec 16 11:00:52 rivoplus dhcpcd[3355]: eth0: reject NAK via 192.168.178.1 Dec 16 11:00:52 rivoplus dhcpcd[3355]: eth0: reject NAK via 192.168.178.1 Dec 16 11:00:52 rivoplus volumio[3283]: info: Applying required configuration parameters for plugin 80s80s Dec 16 11:00:52 rivoplus volumio[3283]: info: [1765879252576] [80s80s] API delay: 30 Dec 16 11:00:52 rivoplus volumio[3283]: info: Loading plugin "ferrum_streaming_control_technology"... Dec 16 11:00:52 rivoplus volumio[3283]: info: Applying required configuration parameters for plugin ferrum_streaming_control_technology Dec 16 11:00:52 rivoplus volumio[3283]: info: Loading plugin "randomizer"... Dec 16 11:00:52 rivoplus dhcpcd[3519]: eth0: DHCP lease expired Dec 16 11:00:52 rivoplus avahi-daemon[2829]: Withdrawing address record for 192.168.178.201 on eth0. Dec 16 11:00:52 rivoplus avahi-daemon[2829]: Leaving mDNS multicast group on interface eth0.IPv4 with address 192.168.178.201. Dec 16 11:00:52 rivoplus dhcpcd[3355]: eth0: pid -1143648774 deleted IP address 192.168.178.201/24 Dec 16 11:00:52 rivoplus dhcpcd[3355]: eth0: deleting route to 192.168.178.0/24 Dec 16 11:00:52 rivoplus avahi-daemon[2829]: Interface eth0.IPv4 no longer relevant for mDNS. Dec 16 11:00:52 rivoplus dhcpcd[3355]: eth0: deleting default route via 192.168.178.2 Dec 16 11:00:52 rivoplus dhcpcd[3519]: eth0: deleting route to 192.168.178.0/24 Dec 16 11:00:52 rivoplus dhcpcd[3519]: eth0: deleting default route via 192.168.178.2 Dec 16 11:00:53 rivoplus dhcpcd[3355]: eth0: rebinding lease of 192.168.178.201 Dec 16 11:00:53 rivoplus dhcpcd[3355]: eth0: reject NAK via 192.168.178.1 Dec 16 11:00:53 rivoplus dhcpcd[3355]: eth0: reject NAK via 192.168.178.1 Dec 16 11:00:53 rivoplus dhcpcd[3519]: eth0: soliciting a DHCP lease Dec 16 11:00:53 rivoplus dhcpcd[3519]: eth0: offered 192.168.178.104 from 192.168.178.1 Dec 16 11:00:53 rivoplus dhcpcd[3519]: eth0: probing address 192.168.178.104/24 Dec 16 11:00:53 rivoplus volumio[3283]: info: Applying required configuration parameters for plugin randomizer Dec 16 11:00:53 rivoplus volumio[3283]: info: Loading i18n strings for locale de Dec 16 11:00:53 rivoplus volumio[3283]: Updating browse sources language Dec 16 11:00:53 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 11:00:53 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 11:00:53 rivoplus volumio[3283]: info: CoreCommandRouter::initPlayerControls Dec 16 11:00:53 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 11:00:53 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 11:00:53 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 11:00:53 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 11:00:53 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 11:00:53 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 11:00:53 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 11:00:53 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 11:00:53 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 11:00:53 rivoplus volumio[3283]: Express server listening on port 3000 Dec 16 11:00:53 rivoplus volumio[3283]: [Metrics] WebUI: 31s 202.17ms Dec 16 11:00:53 rivoplus volumio[3283]: info: CoreStateMachine::resetVolumioState Dec 16 11:00:53 rivoplus volumio[3283]: info: CoreStateMachine::getcurrentVolume Dec 16 11:00:53 rivoplus volumio[3283]: info: CoreCommandRouter::volumioRetrievevolume Dec 16 11:00:53 rivoplus volumio[3283]: info: CoreStateMachine::pushState Dec 16 11:00:54 rivoplus volumio[3283]: info: CorePlayQueue::getTrack 0 Dec 16 11:00:54 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 11:00:54 rivoplus volumio[3283]: info: CoreCommandRouter::volumioPushState Dec 16 11:00:54 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 11:00:54 rivoplus sudo[3988]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 16 11:00:54 rivoplus sudo[3988]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 11:00:54 rivoplus sudo[3988]: pam_unix(sudo:session): session closed for user root Dec 16 11:00:54 rivoplus volumio[3283]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Dec 16 11:00:54 rivoplus volumio[3283]: info: Completed loading Core Plugins Dec 16 11:00:54 rivoplus volumio[3283]: info: Preparing to generate the ALSA configuration file Dec 16 11:00:54 rivoplus sudo[3990]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 16 11:00:54 rivoplus sudo[3990]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 11:00:54 rivoplus sudo[3990]: pam_unix(sudo:session): session closed for user root Dec 16 11:00:54 rivoplus volumio[3283]: info: Volumio Network Manager: Network status updated: 3 Dec 16 11:00:54 rivoplus volumio[3283]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Dec 16 11:00:54 rivoplus volumio[3283]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Dec 16 11:00:54 rivoplus volumio[3283]: info: Reading ALSA contributions from plugins. Dec 16 11:00:54 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 16 11:00:54 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 16 11:00:54 rivoplus volumio[3283]: info: Discovery: Getting this device information Dec 16 11:00:54 rivoplus volumio[3283]: info: CoreCommandRouter::volumioGetState Dec 16 11:00:54 rivoplus volumio[3283]: info: CorePlayQueue::getTrack 0 Dec 16 11:00:54 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 16 11:00:54 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 16 11:00:54 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 16 11:00:54 rivoplus volumio[3283]: info: Discovery: Getting this device information Dec 16 11:00:54 rivoplus volumio[3283]: info: CoreCommandRouter::volumioGetState Dec 16 11:00:54 rivoplus volumio[3283]: info: CorePlayQueue::getTrack 0 Dec 16 11:00:54 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 16 11:00:54 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 16 11:00:54 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 16 11:00:54 rivoplus volumio[3283]: info: Discovery: Getting this device information Dec 16 11:00:54 rivoplus volumio[3283]: info: CoreCommandRouter::volumioGetState Dec 16 11:00:54 rivoplus volumio[3283]: info: CorePlayQueue::getTrack 0 Dec 16 11:00:54 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 16 11:00:54 rivoplus volumio[3283]: info: Cannot read play queue from file Dec 16 11:00:54 rivoplus volumio[3283]: info: CoreStateMachine::setRepeat null single undefined Dec 16 11:00:54 rivoplus volumio[3283]: info: CoreStateMachine::pushState Dec 16 11:00:54 rivoplus volumio[3283]: info: CorePlayQueue::getTrack 0 Dec 16 11:00:54 rivoplus volumio[3283]: info: CoreCommandRouter::volumioPushState Dec 16 11:00:54 rivoplus volumio[3283]: info: CorePlayQueue::getTrack 0 Dec 16 11:00:54 rivoplus volumio[3283]: verbose: New Socket.io Connection to 192.168.178.40:3000 from 192.168.178.157 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 1 Dec 16 11:00:54 rivoplus ntpd[3580]: Deleting interface #3 eth0, 192.168.178.201#123, interface stats: received=0, sent=4, dropped=0, active_time=31 secs Dec 16 11:00:54 rivoplus avahi-daemon[2829]: Host name conflict, retrying with rivoplus-2 Dec 16 11:00:54 rivoplus avahi-daemon[2829]: Registering new address record for 192.168.178.40 on wlan0.IPv4. Dec 16 11:00:54 rivoplus sudo[4004]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Dec 16 11:00:54 rivoplus sudo[4004]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 11:00:54 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 16 11:00:54 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 16 11:00:54 rivoplus volumio[3283]: info: Discovery: adding b4c43cc9-f094-4c1b-a695-98c2a44972ac Dec 16 11:00:54 rivoplus volumio[3283]: info: Discovery: Found device Rivoplus Dec 16 11:00:54 rivoplus volumio[3283]: info: CoreCommandRouter::volumioGetState Dec 16 11:00:54 rivoplus volumio[3283]: info: CorePlayQueue::getTrack 0 Dec 16 11:00:54 rivoplus systemd[1]: Started UPnP Renderer front-end to MPD. Dec 16 11:00:54 rivoplus sudo[4004]: pam_unix(sudo:session): session closed for user root Dec 16 11:00:54 rivoplus volumio[3283]: info: Discovery: A device disappeared from network Dec 16 11:00:54 rivoplus volumio[3283]: info: Discovery: Device rivoplus disappeared from network Dec 16 11:00:54 rivoplus volumio[3283]: verbose: New Socket.io Connection to 192.168.178.40:3000 from 192.168.178.157 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 2 Dec 16 11:00:54 rivoplus volumio[3283]: info: Asound.conf file unchanged, so no further update is needed Dec 16 11:00:54 rivoplus volumio[3283]: info: Output device has changed, restarting MPD Dec 16 11:00:55 rivoplus sudo[4015]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 16 11:00:55 rivoplus sudo[4015]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 11:00:55 rivoplus volumio[3283]: info: Output device has changed, restarting Shairport Sync Dec 16 11:00:55 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 11:00:55 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 11:00:55 rivoplus sudo[4015]: pam_unix(sudo:session): session closed for user root Dec 16 11:00:55 rivoplus sudo[4018]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 16 11:00:55 rivoplus sudo[4018]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 11:00:55 rivoplus systemd[1]: Listening on mpd.socket. Dec 16 11:00:55 rivoplus systemd[1]: Starting Music Player Daemon... Dec 16 11:00:55 rivoplus volumio[3283]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 16 11:00:55 rivoplus volumio[3283]: info: ___________ START PLUGINS ___________ Dec 16 11:00:55 rivoplus volumio[3283]: info: ControllerMpd::onStart: Initializing MPD Dec 16 11:00:55 rivoplus volumio[3283]: info: Creating MPD Configuration file Dec 16 11:00:55 rivoplus sudo[4025]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 16 11:00:55 rivoplus sudo[4025]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 11:00:55 rivoplus sudo[4025]: pam_unix(sudo:session): session closed for user root Dec 16 11:00:55 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 11:00:55 rivoplus volumio[3283]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 16 11:00:55 rivoplus volumio[3283]: info: [1765879255263] CoreMusicLibrary::Adding element Medienserver Dec 16 11:00:55 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 11:00:55 rivoplus sudo[4028]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 16 11:00:55 rivoplus sudo[4028]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 11:00:55 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 16 11:00:55 rivoplus sudo[4023]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 16 11:00:55 rivoplus sudo[4023]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 11:00:55 rivoplus sudo[4023]: pam_unix(sudo:session): session closed for user root Dec 16 11:00:55 rivoplus volumio[3283]: info: Adding METAVOLUMIO REST API Endpoints Dec 16 11:00:55 rivoplus volumio[3283]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Dec 16 11:00:55 rivoplus volumio[3283]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Dec 16 11:00:55 rivoplus volumio[3283]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Dec 16 11:00:55 rivoplus volumio[3283]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Dec 16 11:00:55 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 11:00:55 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 11:00:55 rivoplus sudo[4031]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name Rivoplus Dec 16 11:00:55 rivoplus sudo[4031]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 11:00:55 rivoplus systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Dec 16 11:00:55 rivoplus systemd[1]: mpd.service: Succeeded. Dec 16 11:00:55 rivoplus systemd[1]: Stopped Music Player Daemon. Dec 16 11:00:55 rivoplus sudo[4031]: pam_unix(sudo:session): session closed for user root Dec 16 11:00:55 rivoplus systemd[1]: Starting Music Player Daemon... Dec 16 11:00:55 rivoplus volumio[3283]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 16 11:00:55 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 11:00:55 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 11:00:55 rivoplus volumio[3283]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 16 11:00:55 rivoplus volumio[3283]: info: Preparing CD Folders Dec 16 11:00:55 rivoplus volumio[3283]: info: Adding CD REST API Endpoints Dec 16 11:00:55 rivoplus volumio[3283]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Dec 16 11:00:55 rivoplus volumio[3283]: info: Starting UDEV Watcher for CD Dec 16 11:00:55 rivoplus volumio[3283]: info: Detecting CD presence with UDEV Dec 16 11:00:55 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Dec 16 11:00:55 rivoplus sudo[4036]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 16 11:00:55 rivoplus sudo[4036]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 11:00:55 rivoplus sudo[4036]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Dec 16 11:00:55 rivoplus sudo[4036]: pam_unix(sudo:session): session closed for user root Dec 16 11:00:55 rivoplus volumio[3283]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 16 11:00:55 rivoplus volumio[3283]: info: [1765879255606] CoreMusicLibrary::Adding element Last_100 Dec 16 11:00:55 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 11:00:55 rivoplus volumio[3283]: info: Adding qc_getconfig REST Endpoint for plugin: music_service/qobuzconnect Dec 16 11:00:55 rivoplus volumio[3283]: info: QobuzConnect: Starting Qobuz Connect socket and service Dec 16 11:00:55 rivoplus volumio[3283]: info: Starting RAAT Plugin Dec 16 11:00:55 rivoplus sudo[4053]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 16 11:00:55 rivoplus sudo[4053]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 11:00:55 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , addAdditionalUISections Dec 16 11:00:55 rivoplus volumio[3283]: info: Additional UI Settings Added for plugin music_service/raat Dec 16 11:00:55 rivoplus volumio[3283]: info: Registering DSP Elements listener and retrieving current ones Dec 16 11:00:55 rivoplus volumio[3283]: info: Additional DSP elements updated Dec 16 11:00:55 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 11:00:55 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 11:00:55 rivoplus volumio[3283]: info: Updating RAAT Signal Path Dec 16 11:00:55 rivoplus volumio[3283]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Dec 16 11:00:55 rivoplus volumio[3283]: info: Streaming services startup Dec 16 11:00:55 rivoplus volumio[3283]: info: Starting Streaming Daemon Dec 16 11:00:55 rivoplus avahi-daemon[2829]: Server startup complete. Host name is rivoplus-2.local. Local service cookie is 2122057179. Dec 16 11:00:55 rivoplus sudo[4053]: pam_unix(sudo:session): session closed for user root Dec 16 11:00:55 rivoplus sudo[4057]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 16 11:00:55 rivoplus sudo[4057]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 11:00:55 rivoplus sudo[4059]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 16 11:00:55 rivoplus sudo[4059]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 11:00:55 rivoplus systemd[1]: Started Volumio Qobuz Connect Service. Dec 16 11:00:55 rivoplus sudo[4057]: pam_unix(sudo:session): session closed for user root Dec 16 11:00:55 rivoplus sudo[4059]: pam_unix(sudo:session): session closed for user root Dec 16 11:00:55 rivoplus volumio[3283]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 16 11:00:55 rivoplus volumio[3283]: info: [1765879255988] CoreMusicLibrary::Adding element Webradio Dec 16 11:00:55 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 11:00:55 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 16 11:00:56 rivoplus volumio[3283]: info: Initializing BBC Radios Dec 16 11:00:56 rivoplus sudo[4070]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 16 11:00:56 rivoplus sudo[4070]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 11:00:56 rivoplus sudo[4070]: pam_unix(sudo:session): session closed for user root Dec 16 11:00:56 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 16 11:00:56 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 11:00:56 rivoplus volumio[3283]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 16 11:00:56 rivoplus volumio[3283]: info: [1765879256168] CoreMusicLibrary::Adding element Mixcloud Dec 16 11:00:56 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 11:00:56 rivoplus volumio[3283]: Cannot find translation for source Mixcloud Dec 16 11:00:56 rivoplus volumio[3283]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 16 11:00:56 rivoplus volumio[3283]: info: [1765879256184] CoreMusicLibrary::Adding element SoundCloud Dec 16 11:00:56 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 11:00:56 rivoplus volumio[3283]: Cannot find translation for source Mixcloud Dec 16 11:00:56 rivoplus volumio[3283]: Cannot find translation for source SoundCloud Dec 16 11:00:56 rivoplus volumio[3283]: info: Creating Spotify config file Dec 16 11:00:56 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 11:00:56 rivoplus volumio[3283]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 16 11:00:56 rivoplus volumio[3283]: info: [1765879256224] CoreMusicLibrary::Adding element YouTube Music Dec 16 11:00:56 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 11:00:56 rivoplus volumio[3283]: Cannot find translation for source Mixcloud Dec 16 11:00:56 rivoplus volumio[3283]: Cannot find translation for source SoundCloud Dec 16 11:00:56 rivoplus volumio[3283]: Cannot find translation for source YouTube Music Dec 16 11:00:56 rivoplus volumio[3283]: error: Hi Res Audio Failed Login: Missing Login Data Dec 16 11:00:56 rivoplus volumio[3283]: info: Adding HIGHRESAUDIO REST API Endpoints Dec 16 11:00:56 rivoplus volumio[3283]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Dec 16 11:00:56 rivoplus volumio[3283]: info: Adding saveAccountData_hi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Dec 16 11:00:56 rivoplus volumio[3283]: info: Initializing Serial Communication on port /dev/ttyS3 Dec 16 11:00:56 rivoplus volumio[3283]: info: Touch Event Listener Process Starting Dec 16 11:00:56 rivoplus kernel: meson_uart ffd22000.serial: ttyS3 use xtal(24M) 24000000 change 0 to 9600 Dec 16 11:00:56 rivoplus kernel: meson_uart ffd22000.serial: ttyS3 use xtal(24M) 24000000 change 9600 to 9600 Dec 16 11:00:56 rivoplus kernel: meson_uart ffd22000.serial: ttyS3 use xtal(24M) 24000000 change 9600 to 115200 Dec 16 11:00:56 rivoplus volumio[3283]: info: Adding inputs REST Endpoints Dec 16 11:00:56 rivoplus volumio[3283]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Dec 16 11:00:56 rivoplus volumio[3283]: info: Scanning Audio Inputs Dec 16 11:00:56 rivoplus sudo[4085]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xinput --test-xi2 --root Dec 16 11:00:56 rivoplus sudo[4085]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 11:00:56 rivoplus volumio[3283]: info: Checking against Known Cards name Dec 16 11:00:56 rivoplus volumio[3283]: info: Checking against Known Cards name Dec 16 11:00:56 rivoplus volumio[3283]: info: Checking against Known Cards name Dec 16 11:00:56 rivoplus volumio[3283]: info: Checking against Known Cards name Dec 16 11:00:56 rivoplus volumio[3283]: info: Checking against Known Cards name Dec 16 11:00:56 rivoplus volumio[3283]: info: Adding Server instance for streaming Dec 16 11:00:56 rivoplus volumio[3283]: info: Refreshing TIDAL token Dec 16 11:00:56 rivoplus sudo[4095]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x25 0x0 Dec 16 11:00:56 rivoplus sudo[4095]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 11:00:56 rivoplus sudo[4095]: pam_unix(sudo:session): session closed for user root Dec 16 11:00:56 rivoplus sudo[4098]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x28 0x0 Dec 16 11:00:56 rivoplus sudo[4098]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 11:00:56 rivoplus sudo[4098]: pam_unix(sudo:session): session closed for user root Dec 16 11:00:56 rivoplus sudo[4101]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x29 0x1 Dec 16 11:00:56 rivoplus sudo[4101]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 11:00:56 rivoplus sudo[4101]: pam_unix(sudo:session): session closed for user root Dec 16 11:00:56 rivoplus dhcpcd[3355]: eth0: reject NAK via 192.168.178.1 Dec 16 11:00:56 rivoplus dhcpcd[3355]: eth0: reject NAK via 192.168.178.1 Dec 16 11:00:56 rivoplus sudo[4104]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x33 0x1 Dec 16 11:00:56 rivoplus sudo[4104]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 11:00:56 rivoplus sudo[4104]: pam_unix(sudo:session): session closed for user root Dec 16 11:00:56 rivoplus volumio[3283]: info: Apply VIM3L Onboard LEDs Settings Dec 16 11:00:56 rivoplus volumio[3283]: info: Turning On Onboard LEDs Dec 16 11:00:56 rivoplus sudo[4108]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/sys_led/trigger Dec 16 11:00:56 rivoplus sudo[4108]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 11:00:56 rivoplus sudo[4108]: pam_unix(sudo:session): session closed for user root Dec 16 11:00:56 rivoplus volumio[3283]: info: Starting CEC Event listener Dec 16 11:00:56 rivoplus volumio[3283]: info: Loading i18n strings for locale de Dec 16 11:00:56 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Dec 16 11:00:56 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 11:00:56 rivoplus volumio[3283]: info: FusionDsp - mixtype--------------------- None Dec 16 11:00:57 rivoplus sudo[4112]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/red_red/trigger Dec 16 11:00:57 rivoplus sudo[4112]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 11:00:57 rivoplus sudo[4112]: pam_unix(sudo:session): session closed for user root Dec 16 11:00:57 rivoplus volumio[3283]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 16 11:00:57 rivoplus volumio[3283]: info: [1765879257028] CoreMusicLibrary::Adding element 80s80s Radio Dec 16 11:00:57 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 11:00:57 rivoplus volumio[3283]: Cannot find translation for source Mixcloud Dec 16 11:00:57 rivoplus volumio[3283]: Cannot find translation for source SoundCloud Dec 16 11:00:57 rivoplus volumio[3283]: Cannot find translation for source YouTube Music Dec 16 11:00:57 rivoplus volumio[3283]: Cannot find translation for source 80s80s Radio Dec 16 11:00:57 rivoplus fsct-plugin[3283]: Failed to initialize device 20b1:3033: BOS descriptor not available, unsupported usb version 2.00 Dec 16 11:00:57 rivoplus fsct-plugin[3283]: Failed to initialize device 1d6b:0002: BOS descriptor not available, unsupported usb version 2.00 Dec 16 11:00:57 rivoplus fsct-plugin[3283]: Failed to initialize device 1a40:0101: BOS descriptor not available, unsupported usb version 2.00 Dec 16 11:00:57 rivoplus fsct-plugin[3283]: Failed to initialize device 2fc6:6016: BOS descriptor not available, unsupported usb version 2.00 Dec 16 11:00:57 rivoplus fsct-plugin[3283]: Failed to initialize device 1d6b:0003: IO error -> Permission denied (os error 13) Dec 16 11:00:57 rivoplus fsct-plugin[3283]: Failed to initialize device 0424:2514: BOS descriptor not available, unsupported usb version 2.00 Dec 16 11:00:57 rivoplus fsct-plugin[3283]: Failed to initialize device 2e8a:0005: BOS descriptor not available, unsupported usb version 2.00 Dec 16 11:00:57 rivoplus volumio[3283]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 16 11:00:57 rivoplus volumio[3283]: info: [1765879257199] CoreMusicLibrary::Adding element Randomizer Dec 16 11:00:57 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 11:00:57 rivoplus volumio[3283]: Cannot find translation for source Mixcloud Dec 16 11:00:57 rivoplus volumio[3283]: Cannot find translation for source SoundCloud Dec 16 11:00:57 rivoplus volumio[3283]: Cannot find translation for source YouTube Music Dec 16 11:00:57 rivoplus volumio[3283]: Cannot find translation for source 80s80s Radio Dec 16 11:00:57 rivoplus volumio[3283]: Cannot find translation for source Randomizer Dec 16 11:00:57 rivoplus volumio[3283]: info: Volumio Calling Home Dec 16 11:00:57 rivoplus volumio[3283]: info: QobuzConnect: Opened /tmp/qbz-connect.socket socket, listening for connections Dec 16 11:00:57 rivoplus volumio[3283]: (node:3283) [DEP0005] DeprecationWarning: Buffer() is deprecated due to security and usability issues. Please use the Buffer.alloc(), Buffer.allocUnsafe(), or Buffer.from() methods instead. Dec 16 11:00:57 rivoplus volumio[3283]: (Use `node --trace-deprecation ...` to show where the warning was created) Dec 16 11:00:57 rivoplus volumio[3283]: info: Preparing to generate the ALSA configuration file Dec 16 11:00:57 rivoplus volumio[3283]: info: Stopping AccessToken refresher cron for QOBUZ Dec 16 11:00:57 rivoplus volumio[3283]: info: AccessToken refresher cron started for QOBUZ Dec 16 11:00:57 rivoplus volumio[3283]: info: Adding QOBUZ REST API Endpoints Dec 16 11:00:57 rivoplus volumio[3283]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Dec 16 11:00:57 rivoplus volumio[3283]: info: Upmpdcli Daemon Started Dec 16 11:00:57 rivoplus volumio[3283]: info: Serial port opened successfully Dec 16 11:00:57 rivoplus volumio[3283]: info: Sending serial start messages Dec 16 11:00:57 rivoplus dhcpcd[3519]: eth0: leased 192.168.178.104 for 864000 seconds Dec 16 11:00:57 rivoplus dhcpcd[3519]: eth0: adding route to 192.168.178.0/24 Dec 16 11:00:57 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 11:00:57 rivoplus volumio[3283]: info: Reporting MCU Network Status: 3 Dec 16 11:00:57 rivoplus dhcpcd[3519]: eth0: adding default route via 192.168.178.1 Dec 16 11:00:57 rivoplus avahi-daemon[2829]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.178.104. Dec 16 11:00:57 rivoplus avahi-daemon[2829]: New relevant interface eth0.IPv4 for mDNS. Dec 16 11:00:57 rivoplus avahi-daemon[2829]: Registering new address record for 192.168.178.104 on eth0.IPv4. Dec 16 11:00:57 rivoplus volumio[3283]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Dec 16 11:00:57 rivoplus volumio[3283]: info: Reading ALSA contributions from plugins. Dec 16 11:00:57 rivoplus volumio[3283]: error: No access token received for TIDAL Dec 16 11:00:57 rivoplus volumio[3283]: info: CoreCommandRouter::volumioGetState Dec 16 11:00:57 rivoplus volumio[3283]: info: CorePlayQueue::getTrack 0 Dec 16 11:00:57 rivoplus volumio[3283]: info: FSCT Started Dec 16 11:00:57 rivoplus volumio[3283]: error: Cannot start Volumio Streaming Daemon Dec 16 11:00:57 rivoplus volumio[3283]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 16 11:00:57 rivoplus volumio[3283]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 16 11:00:57 rivoplus volumio[3283]: info: RAAT Albumart path created successfully Dec 16 11:00:57 rivoplus volumio[3283]: info: Bluetooth name changed to Rivoplus Dec 16 11:00:57 rivoplus volumio[3283]: info: MPD Permissions set Dec 16 11:00:58 rivoplus volumio[3283]: info: Executing endpoint qc_getconfig Dec 16 11:00:58 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 16 11:00:58 rivoplus dhcpcd[3355]: eth0: probing for an IPv4LL address Dec 16 11:00:58 rivoplus dhcpcd[3355]: eth0: DHCP lease expired Dec 16 11:00:58 rivoplus qobuz-connect[4064]: 20251216 11:00:58.100 [4064.4064] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Dec 16 11:00:58 rivoplus ntpd[3580]: ntpd exiting on signal 15 (Terminated) Dec 16 11:00:58 rivoplus volumio[3283]: verbose: New Socket.io Connection to 192.168.178.104:3000 from 192.168.178.157 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 2 Dec 16 11:00:58 rivoplus systemd[1]: Stopping Network Time Service... Dec 16 11:00:58 rivoplus systemd[1]: ntp.service: Succeeded. Dec 16 11:00:58 rivoplus systemd[1]: Stopped Network Time Service. Dec 16 11:00:58 rivoplus volumio[3283]: info: Spotify config file written Dec 16 11:00:58 rivoplus systemd[1]: Starting Network Time Service... Dec 16 11:00:58 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 16 11:00:58 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 16 11:00:58 rivoplus volumio[3283]: info: Discovery: Getting this device information Dec 16 11:00:58 rivoplus volumio[3283]: info: CoreCommandRouter::volumioGetState Dec 16 11:00:58 rivoplus volumio[3283]: info: CorePlayQueue::getTrack 0 Dec 16 11:00:58 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 16 11:00:58 rivoplus sudo[4205]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Dec 16 11:00:58 rivoplus sudo[4205]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 11:00:58 rivoplus ntpd[4187]: ntpd 4.2.8p12@1.3728-o (1): Starting Dec 16 11:00:58 rivoplus ntpd[4187]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Dec 16 11:00:58 rivoplus qobuz-connect[4064]: 20251216 11:00:58.316 [4064.4064] INFO VolumeManager: [0xabcf0700]: Setting new playback volume: 75 Dec 16 11:00:58 rivoplus qobuz-connect[4064]: 20251216 11:00:58.317 [4064.4064] INFO VolumeManager: [0xabcf0700]: Setting new mute state: 0 Dec 16 11:00:58 rivoplus qobuz-connect[4064]: 20251216 11:00:58.317 [4064.4064] INFO QobuzConnect: [0xabcf10d0]: Client initialized! Dec 16 11:00:58 rivoplus qobuz-connect[4064]: 20251216 11:00:58.317 [4064.4064] INFO SampleApp: Starting Avahi advertising, name: Rivoplus, service name: _qobuz-connect._tcp Dec 16 11:00:58 rivoplus systemd[1]: Started Network Time Service. Dec 16 11:00:58 rivoplus ntpd[4211]: proto: precision = 1.250 usec (-20) Dec 16 11:00:58 rivoplus ntpd[4211]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Dec 16 11:00:58 rivoplus ntpd[4211]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Dec 16 11:00:58 rivoplus ntpd[4211]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 1085 days ago Dec 16 11:00:58 rivoplus ntpd[4211]: Listen and drop on 0 v6wildcard [::]:123 Dec 16 11:00:58 rivoplus ntpd[4211]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Dec 16 11:00:58 rivoplus ntpd[4211]: Listen normally on 2 lo 127.0.0.1:123 Dec 16 11:00:58 rivoplus ntpd[4211]: Listen normally on 3 eth0 192.168.178.104:123 Dec 16 11:00:58 rivoplus ntpd[4211]: Listen normally on 4 wlan0 192.168.178.40:123 Dec 16 11:00:58 rivoplus ntpd[4211]: Listening on routing socket on fd #21 for interface updates Dec 16 11:00:58 rivoplus ntpd[4211]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Dec 16 11:00:58 rivoplus ntpd[4211]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Dec 16 11:00:58 rivoplus qobuz-connect[4064]: 20251216 11:00:58.383 [4064.4064] INFO LocalConfigManager: [0xabceffe0]: Starting Local Configuration server Dec 16 11:00:58 rivoplus qobuz-connect[4064]: 20251216 11:00:58.383 [4064.4064] INFO SampleApp: Starting Local configuration server Dec 16 11:00:58 rivoplus qobuz-connect[4064]: 20251216 11:00:58.384 [4064.4064] INFO SampleApp: Playback volume changed: 75 Dec 16 11:00:58 rivoplus qobuz-connect[4064]: 20251216 11:00:58.384 [4064.4064] INFO SampleApp: Connected to UNIX socket client 0xabce5ed8 Dec 16 11:00:58 rivoplus dhcpcd[3355]: eth0: soliciting a DHCP lease Dec 16 11:00:58 rivoplus dhcpcd[3355]: eth0: offered 192.168.178.104 from 192.168.178.1 Dec 16 11:00:58 rivoplus dhcpcd[3355]: eth0: leased 192.168.178.104 for 864000 seconds Dec 16 11:00:58 rivoplus dhcpcd[3355]: eth0: adding route to 192.168.178.0/24 Dec 16 11:00:58 rivoplus dhcpcd[3355]: eth0: adding default route via 192.168.178.1 Dec 16 11:00:58 rivoplus systemd[1]: Started go-librespot Daemon. Dec 16 11:00:58 rivoplus go-librespot[4218]: go-librespot daemon starting... Dec 16 11:00:58 rivoplus sudo[4205]: pam_unix(sudo:session): session closed for user root Dec 16 11:00:58 rivoplus volumio[3283]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Dec 16 11:00:58 rivoplus volumio[3283]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Dec 16 11:00:58 rivoplus volumio[3283]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Dec 16 11:00:58 rivoplus volumio[3283]: info: QobuzConnect: QOBUZ Connect daemon connected Dec 16 11:00:58 rivoplus go-librespot[4218]: time="2025-12-16T11:00:58+01:00" level=info msg="running go-librespot 0.4.0" Dec 16 11:00:58 rivoplus go-librespot[4218]: time="2025-12-16T11:00:58+01:00" level=debug msg="app state loaded" Dec 16 11:00:58 rivoplus go-librespot[4218]: time="2025-12-16T11:00:58+01:00" level=debug msg="stored credentials not found" Dec 16 11:00:58 rivoplus go-librespot[4218]: time="2025-12-16T11:00:58+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 16 11:00:58 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 11:00:58 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 11:00:58 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 11:00:58 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 11:00:58 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 11:00:58 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 11:00:58 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 11:00:58 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 11:00:58 rivoplus sudo[4255]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Dec 16 11:00:58 rivoplus sudo[4255]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 11:00:58 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 11:00:58 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 16 11:00:58 rivoplus systemd[1]: Started Volumio Bluetooth Module. Dec 16 11:00:58 rivoplus sudo[4255]: pam_unix(sudo:session): session closed for user root Dec 16 11:00:58 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 11:00:58 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 11:00:58 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 11:00:58 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 11:00:58 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 11:00:58 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 11:00:58 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 11:00:58 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 11:00:58 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 11:00:58 rivoplus ntpd[4211]: ntpd exiting on signal 15 (Terminated) Dec 16 11:00:58 rivoplus systemd[1]: Stopping Network Time Service... Dec 16 11:00:58 rivoplus sudo[4266]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Dec 16 11:00:58 rivoplus sudo[4266]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 11:00:58 rivoplus systemd[1]: ntp.service: Succeeded. Dec 16 11:00:58 rivoplus volumiobt[4264]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Dec 16 11:00:58 rivoplus systemd[1]: Stopped Network Time Service. Dec 16 11:00:58 rivoplus sudo[4266]: pam_unix(sudo:session): session closed for user root Dec 16 11:00:58 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 11:00:58 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 16 11:00:58 rivoplus systemd[1]: Starting Network Time Service... Dec 16 11:00:58 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 11:00:58 rivoplus volumio[3283]: info: No need to fix Spotify hosts Dec 16 11:00:58 rivoplus ntpd[4273]: ntpd 4.2.8p12@1.3728-o (1): Starting Dec 16 11:00:58 rivoplus ntpd[4273]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Dec 16 11:00:58 rivoplus systemd[1]: Started Network Time Service. Dec 16 11:00:59 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setDeviceVolumeOverride Dec 16 11:00:59 rivoplus volumio[3283]: info: Setting Device Volume Override Dec 16 11:00:59 rivoplus ntpd[4284]: proto: precision = 1.208 usec (-20) Dec 16 11:00:59 rivoplus ntpd[4284]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Dec 16 11:00:59 rivoplus ntpd[4284]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Dec 16 11:00:59 rivoplus ntpd[4284]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 1085 days ago Dec 16 11:00:59 rivoplus ntpd[4284]: Listen and drop on 0 v6wildcard [::]:123 Dec 16 11:00:59 rivoplus ntpd[4284]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Dec 16 11:00:59 rivoplus ntpd[4284]: Listen normally on 2 lo 127.0.0.1:123 Dec 16 11:00:59 rivoplus ntpd[4284]: Listen normally on 3 eth0 192.168.178.104:123 Dec 16 11:00:59 rivoplus ntpd[4284]: Listen normally on 4 wlan0 192.168.178.40:123 Dec 16 11:00:59 rivoplus ntpd[4284]: Listening on routing socket on fd #21 for interface updates Dec 16 11:00:59 rivoplus ntpd[4284]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Dec 16 11:00:59 rivoplus ntpd[4284]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Dec 16 11:00:59 rivoplus volumio[3283]: No protocol specified Dec 16 11:00:59 rivoplus volumio[3283]: xcb_connection_has_error() returned true Dec 16 11:00:59 rivoplus volumio[3283]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 16 11:00:59 rivoplus volumio[3283]: info: Updating Volume Controller Parameters: Device: 5 Name: DAC-R30 Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Dec 16 11:00:59 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Dec 16 11:00:59 rivoplus volumio[3283]: info: Disabling external Volume Control Dec 16 11:00:59 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 11:00:59 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 11:00:59 rivoplus volumio[3283]: info: CoreStateMachine::pushState Dec 16 11:00:59 rivoplus volumio[3283]: info: CorePlayQueue::getTrack 0 Dec 16 11:00:59 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 11:00:59 rivoplus volumio[3283]: info: CoreCommandRouter::volumioPushState Dec 16 11:00:59 rivoplus volumio[3283]: info: CoreCommandRouter::volumioGetState Dec 16 11:00:59 rivoplus volumio[3283]: info: CorePlayQueue::getTrack 0 Dec 16 11:00:59 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: system , setAdditionalSVInfo Dec 16 11:00:59 rivoplus volumio[3283]: info: Setting Additional System Software info: Hardware Revision: 1.0 Dec 16 11:00:59 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: system , setHwFwVersion Dec 16 11:00:59 rivoplus volumio[3283]: info: Setting HW Firmware info: undefined Dec 16 11:00:59 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: system , setHwVersion Dec 16 11:00:59 rivoplus volumio[3283]: info: Setting HW Version info: 1.0 Dec 16 11:00:59 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: system , setAdditionalSVInfo Dec 16 11:00:59 rivoplus volumio[3283]: info: Setting Additional System Software info: Hardware Revision: 1.0, Firmware Version: 0.2.7 Dec 16 11:00:59 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: system , setHwFwVersion Dec 16 11:00:59 rivoplus volumio[3283]: info: Setting HW Firmware info: 0.2.7 Dec 16 11:00:59 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: system , setHwVersion Dec 16 11:00:59 rivoplus volumio[3283]: info: Setting HW Version info: 1.0 Dec 16 11:00:59 rivoplus volumio[3283]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Dec 16 11:00:59 rivoplus volumio[3283]: info: MCU Signalled Headphone Mode Disabled Dec 16 11:00:59 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Dec 16 11:00:59 rivoplus volumio[3283]: info: Reporting Headphone State: false Dec 16 11:00:59 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 11:00:59 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 11:00:59 rivoplus volumio[3283]: info: Updating RAAT Signal Path Dec 16 11:00:59 rivoplus volumio[3283]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Dec 16 11:00:59 rivoplus volumio[3283]: info: MCU Signalled Sleep Mode Disabled Dec 16 11:00:59 rivoplus volumio[3283]: info: Enabling Advanced system settings configuration Dec 16 11:00:59 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: system , addAdditionalUISections Dec 16 11:00:59 rivoplus volumio[3283]: info: Additional UI Settings Added for plugin music_service/inputs Dec 16 11:00:59 rivoplus volumio[3283]: info: MCU Signalled Auto Boot Mode On Power Disabled Dec 16 11:00:59 rivoplus volumio[3283]: info: CoreStateMachine::pushState Dec 16 11:00:59 rivoplus volumio[3283]: info: CorePlayQueue::getTrack 0 Dec 16 11:00:59 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 11:00:59 rivoplus volumio[3283]: info: CoreCommandRouter::volumioPushState Dec 16 11:00:59 rivoplus volumio[3283]: info: CoreCommandRouter::volumioGetState Dec 16 11:00:59 rivoplus volumio[3283]: info: CorePlayQueue::getTrack 0 Dec 16 11:00:59 rivoplus volumio[3283]: info: MP1 GPIO: Signalled system ready via GPIO Dec 16 11:00:59 rivoplus sudo[4292]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms force on Dec 16 11:00:59 rivoplus sudo[4292]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 11:00:59 rivoplus kernel: fb: osd[0] enable: 1 (Xorg) Dec 16 11:00:59 rivoplus sudo[4292]: pam_unix(sudo:session): session closed for user root Dec 16 11:00:59 rivoplus go-librespot[4218]: time="2025-12-16T11:00:59+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Dec 16 11:00:59 rivoplus go-librespot[4218]: time="2025-12-16T11:00:59+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Dec 16 11:00:59 rivoplus go-librespot[4218]: time="2025-12-16T11:00:59+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Dec 16 11:00:59 rivoplus go-librespot[4218]: time="2025-12-16T11:00:59+01:00" level=info msg="zeroconf server listening on port 36911" Dec 16 11:00:59 rivoplus volumio[3283]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Dec 16 11:00:59 rivoplus volumio[3283]: info: CoreCommandRouter::volumioGetState Dec 16 11:00:59 rivoplus volumio[3283]: info: CorePlayQueue::getTrack 0 Dec 16 11:00:59 rivoplus volumio[3283]: info: camilladsp spawned new process with pid 4300, instance 1, run: true Dec 16 11:00:59 rivoplus volumio[3283]: info: camilladsp service started and running in background, instance 1 Dec 16 11:00:59 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 11:00:59 rivoplus volumio[3283]: No protocol specified Dec 16 11:00:59 rivoplus volumio[3283]: xcb_connection_has_error() returned true Dec 16 11:00:59 rivoplus volumio[3283]: info: FusionDsp - AAAAAAAAAAAAAA-> 44100 48000 88200 96000 176400 192000 384000 768000 <-AAAAAAAAAAAAA Dec 16 11:00:59 rivoplus volumio[3283]: info: FusionDsp loaded Dec 16 11:00:59 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 16 11:00:59 rivoplus sudo[4310]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Dec 16 11:00:59 rivoplus sudo[4310]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 11:00:59 rivoplus volumio[3283]: info: FusionDsp - Reporting Fusion DSP Enabled Dec 16 11:00:59 rivoplus volumio[3283]: info: Adding Signal Path Element [object Object] Dec 16 11:00:59 rivoplus volumio[3283]: info: Adding fusiondspeq DSP Signal Path Element Dec 16 11:00:59 rivoplus volumio[3283]: info: Additional DSP elements updated Dec 16 11:00:59 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 11:00:59 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 11:00:59 rivoplus volumio[3283]: info: Updating RAAT Signal Path Dec 16 11:00:59 rivoplus volumio[3283]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Dec 16 11:00:59 rivoplus volumio[3283]: info: FusionDsp - ---- installed callbackRead Dec 16 11:00:59 rivoplus volumio[3283]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 16 11:00:59 rivoplus volumio[3283]: info: FusionDsp - Effects disabled Dec 16 11:00:59 rivoplus mpd[4049]: decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 16 11:00:59 rivoplus systemd[1]: Started FusionDsp Daemon. Dec 16 11:00:59 rivoplus mpd[4049]: output: No 'audio_output' defined in config file Dec 16 11:00:59 rivoplus sudo[4310]: pam_unix(sudo:session): session closed for user root Dec 16 11:00:59 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 16 11:00:59 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 16 11:00:59 rivoplus volumio[3283]: info: Discovery: Getting this device information Dec 16 11:00:59 rivoplus volumio[3283]: info: CoreCommandRouter::volumioGetState Dec 16 11:00:59 rivoplus volumio[3283]: info: CorePlayQueue::getTrack 0 Dec 16 11:00:59 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 16 11:00:59 rivoplus kernel: aml_tdm_open Dec 16 11:00:59 rivoplus kernel: Not init audio effects Dec 16 11:00:59 rivoplus kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:tdm@1 Dec 16 11:00:59 rivoplus kernel: aml_dai_set_tdm_sysclk(), mpll no change, keep clk Dec 16 11:00:59 rivoplus kernel: aml_dai_set_tdm_sysclk(), mclk no change, keep clk Dec 16 11:00:59 rivoplus kernel: set mclk:12288000, mpll:24576000, get mclk:12287994, mpll:24575987 Dec 16 11:00:59 rivoplus kernel: asoc aml_dai_set_tdm_fmt, 0x4001, ffffffc050351818, id(1), clksel(1) Dec 16 11:00:59 rivoplus kernel: aml_dai_set_tdm_fmt(), fmt not change Dec 16 11:00:59 rivoplus kernel: dump_pcm_setting(ffffffc050351818) Dec 16 11:00:59 rivoplus kernel: pcm_mode(1) Dec 16 11:00:59 rivoplus kernel: sysclk(12288000) Dec 16 11:00:59 rivoplus kernel: sysclk_bclk_ratio(4) Dec 16 11:00:59 rivoplus kernel: bclk(3072000) Dec 16 11:00:59 rivoplus kernel: bclk_lrclk_ratio(64) Dec 16 11:00:59 rivoplus kernel: lrclk(48000) Dec 16 11:00:59 rivoplus kernel: tx_mask(0x3) Dec 16 11:00:59 rivoplus kernel: rx_mask(0x3) Dec 16 11:00:59 rivoplus kernel: slots(2) Dec 16 11:00:59 rivoplus kernel: slot_width(32) Dec 16 11:00:59 rivoplus kernel: lane_mask_in(0x2) Dec 16 11:00:59 rivoplus kernel: lane_mask_out(0x1) Dec 16 11:00:59 rivoplus kernel: lane_oe_mask_in(0x0) Dec 16 11:00:59 rivoplus kernel: lane_oe_mask_out(0x0) Dec 16 11:00:59 rivoplus kernel: lane_lb_mask_in(0x0) Dec 16 11:00:59 rivoplus kernel: aml_dai_set_tdm_sysclk(), mpll no change, keep clk Dec 16 11:00:59 rivoplus kernel: aml_dai_set_tdm_sysclk(), mclk no change, keep clk Dec 16 11:00:59 rivoplus kernel: set mclk:12288000, mpll:24576000, get mclk:12287994, mpll:24575987 Dec 16 11:00:59 rivoplus kernel: aml_dai_set_clkdiv, div 4, clksel(1) Dec 16 11:00:59 rivoplus kernel: aml_dai_set_bclk_ratio, select I2S mode Dec 16 11:00:59 rivoplus kernel: aml_dai_tdm_hw_params(), enable mclk for TDM-B Dec 16 11:00:59 rivoplus kernel: aml_tdm_prepare(), reset fddr Dec 16 11:00:59 rivoplus kernel: spdif_a fifo ctrl, frddr:0 type:1, 16 bits, chmask 0x3, swap 0x10 Dec 16 11:00:59 rivoplus kernel: spdif_info: rate: 48000, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x200, ch1_r:0x200 Dec 16 11:00:59 rivoplus kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Dec 16 11:00:59 rivoplus kernel: tdm playback mute: 0, lane_cnt = 8 Dec 16 11:00:59 rivoplus kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Dec 16 11:00:59 rivoplus kernel: aml_dai_tdm_hw_free(), disable mclk for TDM-B Dec 16 11:00:59 rivoplus kernel: tdm playback mute: 1, lane_cnt = 8 Dec 16 11:00:59 rivoplus kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:tdm@1 Dec 16 11:00:59 rivoplus mpd[4049]: output: Successfully detected a sndio audio device Dec 16 11:00:59 rivoplus mpd[4049]: zeroconf: No global port, disabling zeroconf Dec 16 11:00:59 rivoplus systemd[1]: Started Music Player Daemon. Dec 16 11:00:59 rivoplus sudo[4028]: pam_unix(sudo:session): session closed for user root Dec 16 11:00:59 rivoplus sudo[4018]: pam_unix(sudo:session): session closed for user root Dec 16 11:00:59 rivoplus volumio[3283]: info: TidalConnect service stoped! Dec 16 11:00:59 rivoplus sudo[4318]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms 0 0 0 Dec 16 11:00:59 rivoplus sudo[4318]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 11:00:59 rivoplus volumio[3283]: info: Volumio BT Module successfully started Dec 16 11:00:59 rivoplus volumio[3283]: info: Turning Off Onboard LEDs Dec 16 11:00:59 rivoplus sudo[4320]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Dec 16 11:00:59 rivoplus sudo[4320]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 11:00:59 rivoplus sudo[4318]: pam_unix(sudo:session): session closed for user root Dec 16 11:00:59 rivoplus volumiobt[4264]: pulseaudio: no process found Dec 16 11:00:59 rivoplus sudo[4320]: pam_unix(sudo:session): session closed for user root Dec 16 11:00:59 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 16 11:00:59 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 16 11:00:59 rivoplus volumio[3283]: info: Discovery: Getting this device information Dec 16 11:00:59 rivoplus volumio[3283]: info: CoreCommandRouter::volumioGetState Dec 16 11:00:59 rivoplus volumio[3283]: info: CorePlayQueue::getTrack 0 Dec 16 11:00:59 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 16 11:01:00 rivoplus ntpd[4284]: Soliciting pool server 217.147.223.78 Dec 16 11:01:00 rivoplus sudo[4328]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/sys_led/trigger Dec 16 11:01:00 rivoplus sudo[4328]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 11:01:00 rivoplus volumio[3283]: Upnp client error: Error: This socket has been ended by the other party Dec 16 11:01:00 rivoplus sudo[4328]: pam_unix(sudo:session): session closed for user root Dec 16 11:01:00 rivoplus volumio-remote-updater[2827]: [2025-12-16 11:01:00] [connect] Successful connection Dec 16 11:01:00 rivoplus dbus-daemon[2837]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.20' (uid=1000 pid=4335 comm="/usr/bin/pulseaudio --high-priority=true " label="kernel") Dec 16 11:01:00 rivoplus sudo[4333]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/red_red/trigger Dec 16 11:01:00 rivoplus sudo[4333]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 11:01:00 rivoplus sudo[4333]: pam_unix(sudo:session): session closed for user root Dec 16 11:01:00 rivoplus systemd[1]: Starting RealtimeKit Scheduling Policy Service... Dec 16 11:01:00 rivoplus volumio[3283]: info: Starting Shairport Sync Dec 16 11:01:00 rivoplus kernel: IPVS: Creating netns size=1928 id=2 Dec 16 11:01:00 rivoplus dbus-daemon[2837]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' Dec 16 11:01:00 rivoplus systemd[1]: Started RealtimeKit Scheduling Policy Service. Dec 16 11:01:00 rivoplus rtkit-daemon[4342]: Successfully called chroot. Dec 16 11:01:00 rivoplus rtkit-daemon[4342]: Successfully dropped privileges. Dec 16 11:01:00 rivoplus rtkit-daemon[4342]: Successfully limited resources. Dec 16 11:01:00 rivoplus rtkit-daemon[4342]: Running. Dec 16 11:01:00 rivoplus rtkit-daemon[4342]: Canary thread running. Dec 16 11:01:00 rivoplus rtkit-daemon[4342]: Watchdog thread running. Dec 16 11:01:00 rivoplus dbus-daemon[2837]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.21' (uid=0 pid=4342 comm="/usr/lib/rtkit/rtkit-daemon " label="kernel") Dec 16 11:01:00 rivoplus volumio[3283]: info: Starting Shairport Sync Dec 16 11:01:00 rivoplus systemd[1]: Starting Authorization Manager... Dec 16 11:01:00 rivoplus sudo[4347]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 16 11:01:00 rivoplus sudo[4347]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 11:01:00 rivoplus volumio[3283]: info: Starting Shairport Sync Dec 16 11:01:00 rivoplus systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 16 11:01:00 rivoplus systemd[1]: shairport-sync.service: Succeeded. Dec 16 11:01:00 rivoplus systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 16 11:01:00 rivoplus sudo[4353]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 16 11:01:00 rivoplus sudo[4353]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 11:01:00 rivoplus volumio[3283]: error: Serial API: Failed to decode command: MAXVOL, message: 100 Dec 16 11:01:00 rivoplus systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 16 11:01:00 rivoplus sudo[4347]: pam_unix(sudo:session): session closed for user root Dec 16 11:01:00 rivoplus volumio[3283]: info: CoreStateMachine::pushState Dec 16 11:01:00 rivoplus volumio[3283]: info: CorePlayQueue::getTrack 0 Dec 16 11:01:00 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 11:01:00 rivoplus volumio[3283]: info: CoreCommandRouter::volumioPushState Dec 16 11:01:00 rivoplus volumio[3283]: info: CoreCommandRouter::volumioGetState Dec 16 11:01:00 rivoplus volumio[3283]: info: CorePlayQueue::getTrack 0 Dec 16 11:01:00 rivoplus sudo[4358]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 16 11:01:00 rivoplus volumio[3283]: info: Discovery: Browse raised the following error Error: dns service error: unknown Dec 16 11:01:00 rivoplus systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 16 11:01:00 rivoplus systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Dec 16 11:01:00 rivoplus systemd[1]: shairport-sync.service: Succeeded. Dec 16 11:01:00 rivoplus sudo[4358]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 11:01:00 rivoplus systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 16 11:01:00 rivoplus systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 16 11:01:00 rivoplus volumio[3283]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 16 11:01:00 rivoplus sudo[4353]: pam_unix(sudo:session): session closed for user root Dec 16 11:01:00 rivoplus polkitd[4349]: started daemon version 0.105 using authority implementation `local' version `0.105' Dec 16 11:01:00 rivoplus dbus-daemon[2837]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Dec 16 11:01:00 rivoplus systemd[1]: Started Authorization Manager. Dec 16 11:01:00 rivoplus volumio[3283]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 16 11:01:00 rivoplus systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 16 11:01:00 rivoplus systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Dec 16 11:01:00 rivoplus systemd[1]: shairport-sync.service: Succeeded. Dec 16 11:01:00 rivoplus systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 16 11:01:00 rivoplus volumio[3283]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 16 11:01:00 rivoplus systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 16 11:01:00 rivoplus volumio[3283]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 16 11:01:00 rivoplus sudo[4358]: pam_unix(sudo:session): session closed for user root Dec 16 11:01:00 rivoplus volumio[3283]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 16 11:01:00 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 16 11:01:00 rivoplus volumio[3283]: info: CoreCommandRouter::volumioGetState Dec 16 11:01:00 rivoplus volumio[3283]: info: CorePlayQueue::getTrack 0 Dec 16 11:01:00 rivoplus volumio[3283]: verbose: New Socket.io Connection to 192.168.178.104:3000 from 192.168.178.157 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 5 Dec 16 11:01:00 rivoplus volumio[3283]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Dec 16 11:01:00 rivoplus volumio[3283]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 16 11:01:00 rivoplus volumio[3283]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 16 11:01:00 rivoplus volumio[3283]: info: Onboard LEDs initialized Dec 16 11:01:00 rivoplus volumio[3283]: info: Adding Inputs via Serial API Dec 16 11:01:00 rivoplus volumio[3283]: info: Adding Advanced Audio Settings via Serial API Dec 16 11:01:00 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , addAdditionalUISections Dec 16 11:01:00 rivoplus volumio[3283]: info: Additional UI Settings Added for plugin music_service/inputs Dec 16 11:01:00 rivoplus sudo[4366]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 16 11:01:00 rivoplus sudo[4366]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 11:01:00 rivoplus volumio[3283]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 16 11:01:00 rivoplus systemd[1]: Started Volumio Tidal Connect Service. Dec 16 11:01:00 rivoplus sudo[4366]: pam_unix(sudo:session): session closed for user root Dec 16 11:01:01 rivoplus ntpd[4284]: Soliciting pool server 212.51.144.46 Dec 16 11:01:01 rivoplus volumio[3283]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 16 11:01:01 rivoplus ntpd[4284]: Soliciting pool server 193.134.29.12 Dec 16 11:01:01 rivoplus volumio[3283]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 16 11:01:01 rivoplus volumio[3283]: info: FusionDsp - {"Reload":{"result":"Ok"}} Dec 16 11:01:01 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Dec 16 11:01:01 rivoplus volumio[3283]: error: MPD error: The expression evaluated to a falsy value: Dec 16 11:01:01 rivoplus volumio[3283]: assert.ok(self.idling) Dec 16 11:01:01 rivoplus volumio[3283]: error: The expression evaluated to a falsy value: Dec 16 11:01:01 rivoplus volumio[3283]: assert.ok(self.idling) Dec 16 11:01:01 rivoplus volumio[3283]: info: CoreCommandRouter::volumioGetState Dec 16 11:01:01 rivoplus volumio[3283]: info: CorePlayQueue::getTrack 0 Dec 16 11:01:01 rivoplus pulseaudio[4335]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Dec 16 11:01:01 rivoplus pulseaudio[4335]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Dec 16 11:01:01 rivoplus volumiobt[4264]: Applying permissions Dec 16 11:01:01 rivoplus volumiobt[4264]: Setting BT discoverable and pairable Dec 16 11:01:01 rivoplus bluetoothd[3207]: Endpoint registered: sender=:1.42 path=/MediaEndpoint/A2DPSource Dec 16 11:01:01 rivoplus bluetoothd[3207]: Endpoint registered: sender=:1.42 path=/MediaEndpoint/A2DPSink Dec 16 11:01:01 rivoplus pulseaudio[4335]: Failed to register as a handsfree audio agent with ofono: org.freedesktop.DBus.Error.ServiceUnknown: The name org.ofono was not provided by any .service files Dec 16 11:01:01 rivoplus volumio-remote-updater[2827]: [2025-12-16 11:01:01] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1765879260 101 Dec 16 11:01:01 rivoplus volumio[3283]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 7 Dec 16 11:01:01 rivoplus volumiobt[4264]: [96B blob data] Dec 16 11:01:01 rivoplus volumiobt[4264]: [77B blob data] Dec 16 11:01:01 rivoplus volumiobt[4264]: [77B blob data] Dec 16 11:01:01 rivoplus volumiobt[4264]: [33B blob data] Dec 16 11:01:01 rivoplus volumiobt[4264]: [65B blob data] Dec 16 11:01:01 rivoplus volumiobt[4264]: [103B blob data] Dec 16 11:01:01 rivoplus volumiobt[4264]: [bluetoothctl]> -e power on Dec 16 11:01:01 rivoplus volumiobt[4264]: Invalid command in menu main: -e Dec 16 11:01:01 rivoplus volumiobt[4264]: Dec 16 11:01:01 rivoplus volumiobt[4264]: Use "help" for a list of available commands in a menu. Dec 16 11:01:01 rivoplus volumiobt[4264]: Use "menu " if you want to enter any submenu. Dec 16 11:01:01 rivoplus volumiobt[4264]: Use "back" if you want to return to menu main. Dec 16 11:01:01 rivoplus volumiobt[4264]: [bluetoothctl]> agent on Dec 16 11:01:01 rivoplus volumiobt[4264]: Agent is already registered Dec 16 11:01:01 rivoplus volumiobt[4264]: [bluetoothctl]> discoverable on Dec 16 11:01:01 rivoplus volumiobt[4264]: Warning: setting discoverable while discoverable-timeout not set(0) is not recommended Dec 16 11:01:01 rivoplus volumiobt[4264]: [bluetoothctl]> pairable on Dec 16 11:01:01 rivoplus volumiobt[4264]: [bluetoothctl]> agent NoInputNoOutput Dec 16 11:01:01 rivoplus volumiobt[4264]: Agent is already registered Dec 16 11:01:01 rivoplus volumiobt[4264]: [bluetoothctl]> default-agent Dec 16 11:01:01 rivoplus volumiobt[4264]: [bluetoothctl]> quit Dec 16 11:01:01 rivoplus volumiobt[4264]: [65B blob data] Dec 16 11:01:01 rivoplus volumio[3283]: info: Shairport-Sync Started Dec 16 11:01:01 rivoplus volumio[3283]: Error adding Membership: Error: addMembership EINVAL Dec 16 11:01:01 rivoplus volumio[3283]: info: Shairport-Sync Started Dec 16 11:01:01 rivoplus volumio[3283]: info: Shairport-Sync Started Dec 16 11:01:01 rivoplus volumio[3283]: info: MPD running with PID4049 Dec 16 11:01:01 rivoplus volumio[3283]: ,establishing connection Dec 16 11:01:01 rivoplus volumio[3283]: info: Asound.conf file unchanged, so no further update is needed Dec 16 11:01:01 rivoplus volumio[3283]: info: Output device has changed, restarting MPD Dec 16 11:01:01 rivoplus sudo[4376]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 16 11:01:01 rivoplus sudo[4376]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 11:01:01 rivoplus volumio[3283]: info: Output device has changed, restarting Shairport Sync Dec 16 11:01:01 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 11:01:01 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 11:01:01 rivoplus sudo[4376]: pam_unix(sudo:session): session closed for user root Dec 16 11:01:01 rivoplus sudo[4379]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 16 11:01:01 rivoplus sudo[4379]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 11:01:01 rivoplus systemd[1]: Stopping Music Player Daemon... Dec 16 11:01:01 rivoplus volumio[3283]: No protocol specified Dec 16 11:01:01 rivoplus volumio[3283]: xcb_connection_has_error() returned true Dec 16 11:01:01 rivoplus volumio[3283]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 16 11:01:01 rivoplus volumio[3283]: No protocol specified Dec 16 11:01:01 rivoplus volumio[3283]: xcb_connection_has_error() returned true Dec 16 11:01:01 rivoplus volumio[3283]: info: QobuzConnect: setDeactiveState invoked Dec 16 11:01:01 rivoplus volumio[3283]: info: CoreCommandRouter::volumioGetState Dec 16 11:01:01 rivoplus volumio[3283]: info: CorePlayQueue::getTrack 0 Dec 16 11:01:01 rivoplus volumio[3283]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Dec 16 11:01:01 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 11:01:01 rivoplus volumio[3283]: error: Error starting TidalConnect Cannot read property 'sendRevoke' of undefined {"stack":"TypeError: Cannot read property 'sendRevoke' of undefined\n at ControllerTidalconnect.stopTidalConnect (/volumio/app/plugins/music_service/tidalconnect/tidalconnect_real:1:8526)\n at ControllerTidalconnect.restartService (/volumio/app/plugins/music_service/tidalconnect/tidalconnect_real:1:8818)\n at /volumio/node_modules/v-conf/index.js:165:13\n at /volumio/node_modules/multimap/index.js:150:9\n at Array.forEach ()\n at /volumio/node_modules/multimap/index.js:149:13\n at mapEach (/volumio/node_modules/multimap/index.js:9:5)\n at Multimap.forEachEntry (/volumio/node_modules/multimap/index.js:143:5)\n at Multimap.forEach (/volumio/node_modules/multimap/index.js:148:10)\n at Config.set (/volumio/node_modules/v-conf/index.js:162:20)\n at Promise._successFn (/volumio/app/plugins/audio_interface/alsa_controller/index.js:2112:39)\n at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28)\n at processTicksAndRejections (internal/process/task_queues.js:77:11)"} Dec 16 11:01:02 rivoplus ntpd[4284]: Soliciting pool server 217.147.208.1 Dec 16 11:01:02 rivoplus ntpd[4284]: Soliciting pool server 185.125.24.59 Dec 16 11:01:02 rivoplus ntpd[4284]: Soliciting pool server 195.141.190.190 Dec 16 11:01:02 rivoplus sudo[4386]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 16 11:01:02 rivoplus sudo[4386]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 11:01:02 rivoplus systemd[1]: Stopping Volumio Tidal Connect Service... Dec 16 11:01:02 rivoplus systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Dec 16 11:01:02 rivoplus systemd[1]: vtcs.service: Succeeded. Dec 16 11:01:02 rivoplus systemd[1]: Stopped Volumio Tidal Connect Service. Dec 16 11:01:02 rivoplus systemd[1]: mpd.service: Succeeded. Dec 16 11:01:02 rivoplus systemd[1]: Stopped Music Player Daemon. Dec 16 11:01:02 rivoplus volumio[3283]: info: Discovery: A device disappeared from network Dec 16 11:01:02 rivoplus volumio[3283]: info: Discovery: Browse raised the following error TypeError: Cannot read property 'toLowerCase' of undefined Dec 16 11:01:02 rivoplus sudo[4386]: pam_unix(sudo:session): session closed for user root Dec 16 11:01:02 rivoplus sudo[4392]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 16 11:01:02 rivoplus sudo[4392]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 11:01:02 rivoplus systemd[1]: Starting Music Player Daemon... Dec 16 11:01:02 rivoplus sudo[4392]: pam_unix(sudo:session): session closed for user root Dec 16 11:01:02 rivoplus volumio[3283]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 16 11:01:02 rivoplus dhcpcd[3355]: eth0: using IPv4LL address 169.254.62.135 Dec 16 11:01:02 rivoplus dhcpcd[3355]: eth0: adding route to 169.254.0.0/16 Dec 16 11:01:02 rivoplus avahi-daemon[2829]: Registering new address record for 169.254.62.135 on eth0.IPv4. Dec 16 11:01:02 rivoplus volumio[3283]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 16 11:01:02 rivoplus sudo[4396]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 16 11:01:02 rivoplus sudo[4396]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 11:01:02 rivoplus volumio[3283]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 16 11:01:02 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Dec 16 11:01:02 rivoplus volumio[3283]: error: updateQueue error: null Dec 16 11:01:02 rivoplus qobuz-connect[4064]: 20251216 11:01:02.470 [4064.4064] INFO SampleApp: Stopping Local configuration server Dec 16 11:01:02 rivoplus qobuz-connect[4064]: 20251216 11:01:02.486 [4064.4064] INFO SampleApp: shat down connection on UNIX socket Dec 16 11:01:02 rivoplus systemd[1]: Stopping Volumio Qobuz Connect Service... Dec 16 11:01:02 rivoplus systemd[1]: qobuz-connect.service: Succeeded. Dec 16 11:01:02 rivoplus systemd[1]: Stopped Volumio Qobuz Connect Service. Dec 16 11:01:02 rivoplus systemd[1]: Started Volumio Qobuz Connect Service. Dec 16 11:01:02 rivoplus sudo[4396]: pam_unix(sudo:session): session closed for user root Dec 16 11:01:02 rivoplus volumio[3283]: info: go-librespot daemon successfully initialized Dec 16 11:01:02 rivoplus sudo[4394]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 16 11:01:02 rivoplus sudo[4394]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 11:01:02 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 11:01:02 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 11:01:02 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 11:01:02 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 11:01:02 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 11:01:02 rivoplus volumio[3283]: info: Not Reporting Auto name since its the default one Dec 16 11:01:02 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 16 11:01:02 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 16 11:01:02 rivoplus volumio[3283]: info: Discovery: Getting this device information Dec 16 11:01:02 rivoplus volumio[3283]: info: CoreCommandRouter::volumioGetState Dec 16 11:01:02 rivoplus volumio[3283]: info: CorePlayQueue::getTrack 0 Dec 16 11:01:02 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 16 11:01:02 rivoplus sudo[4394]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Dec 16 11:01:02 rivoplus sudo[4394]: pam_unix(sudo:session): session closed for user root Dec 16 11:01:02 rivoplus volumio[3283]: info: CoreCommandRouter::servicePushState Dec 16 11:01:02 rivoplus volumio[3283]: info: CoreStateMachine::pushState Dec 16 11:01:02 rivoplus volumio[3283]: info: CorePlayQueue::getTrack 0 Dec 16 11:01:02 rivoplus volumio[3283]: info: CoreCommandRouter::volumioPushState Dec 16 11:01:02 rivoplus volumio[3283]: info: CoreCommandRouter::volumioGetState Dec 16 11:01:02 rivoplus volumio[3283]: info: CorePlayQueue::getTrack 0 Dec 16 11:01:02 rivoplus volumio[3283]: error: Help! Some callbacks for volumioPushState are crashing! Dec 16 11:01:02 rivoplus volumio[3283]: error: Cannot read property 'sendVolumeMute' of undefined Dec 16 11:01:02 rivoplus volumio[3283]: info: CorePlayQueue::getTrack 0 Dec 16 11:01:02 rivoplus volumio[3283]: 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} Dec 16 11:01:02 rivoplus volumio[3283]: verbose: CURRENT POSITION 0 Dec 16 11:01:02 rivoplus volumio[3283]: info: CoreStateMachine::syncState stateService stop Dec 16 11:01:02 rivoplus volumio[3283]: info: CoreStateMachine::syncState currentStatus stop Dec 16 11:01:02 rivoplus volumio[3283]: info: CoreStateMachine::pushState Dec 16 11:01:02 rivoplus volumio[3283]: info: CorePlayQueue::getTrack 0 Dec 16 11:01:02 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 11:01:02 rivoplus volumio[3283]: info: CoreCommandRouter::volumioPushState Dec 16 11:01:02 rivoplus volumio[3283]: info: CoreCommandRouter::volumioGetState Dec 16 11:01:02 rivoplus volumio[3283]: info: CorePlayQueue::getTrack 0 Dec 16 11:01:02 rivoplus volumio[3283]: error: Help! Some callbacks for volumioPushState are crashing! Dec 16 11:01:02 rivoplus volumio[3283]: error: Cannot read property 'sendVolumeMute' of undefined Dec 16 11:01:02 rivoplus volumio[3283]: info: No code Dec 16 11:01:02 rivoplus volumio[3283]: info: CoreStateMachine::pushState Dec 16 11:01:02 rivoplus volumio[3283]: info: CorePlayQueue::getTrack 0 Dec 16 11:01:02 rivoplus volumio[3283]: info: CoreCommandRouter::volumioPushState Dec 16 11:01:02 rivoplus volumio[3283]: info: CoreCommandRouter::volumioGetState Dec 16 11:01:02 rivoplus volumio[3283]: info: CorePlayQueue::getTrack 0 Dec 16 11:01:02 rivoplus volumio[3283]: error: Help! Some callbacks for volumioPushState are crashing! Dec 16 11:01:02 rivoplus volumio[3283]: error: Cannot read property 'sendVolumeMute' of undefined Dec 16 11:01:02 rivoplus volumio[3283]: info: CoreCommandRouter::volumiosetSourceActiveno-source Dec 16 11:01:02 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 11:01:02 rivoplus volumio[3283]: Cannot find translation for source Mixcloud Dec 16 11:01:02 rivoplus volumio[3283]: Cannot find translation for source SoundCloud Dec 16 11:01:02 rivoplus volumio[3283]: Cannot find translation for source YouTube Music Dec 16 11:01:02 rivoplus volumio[3283]: Cannot find translation for source 80s80s Radio Dec 16 11:01:02 rivoplus volumio[3283]: Cannot find translation for source Randomizer Dec 16 11:01:02 rivoplus volumio[3283]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 16 11:01:02 rivoplus volumio[3283]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 16 11:01:02 rivoplus volumio[3283]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 16 11:01:02 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Dec 16 11:01:03 rivoplus ntpd[4284]: Soliciting pool server 156.106.214.52 Dec 16 11:01:03 rivoplus ntpd[4284]: Soliciting pool server 85.195.227.65 Dec 16 11:01:03 rivoplus ntpd[4284]: Soliciting pool server 85.195.210.125 Dec 16 11:01:03 rivoplus ntpd[4284]: Soliciting pool server 193.33.30.39 Dec 16 11:01:03 rivoplus sudo[4424]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Dec 16 11:01:03 rivoplus sudo[4424]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 11:01:03 rivoplus sudo[4429]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 16 11:01:03 rivoplus sudo[4429]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 11:01:03 rivoplus sudo[4424]: pam_unix(sudo:session): session closed for user root Dec 16 11:01:03 rivoplus sudo[4429]: pam_unix(sudo:session): session closed for user root Dec 16 11:01:03 rivoplus sudo[4436]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 16 11:01:03 rivoplus sudo[4436]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 11:01:03 rivoplus sudo[4441]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 16 11:01:03 rivoplus sudo[4441]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 11:01:03 rivoplus sudo[4441]: pam_unix(sudo:session): session closed for user root Dec 16 11:01:03 rivoplus sudo[4436]: pam_unix(sudo:session): session closed for user root Dec 16 11:01:03 rivoplus sudo[4450]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 16 11:01:03 rivoplus volumio[3283]: error: MPD error: Error: write EPIPE Dec 16 11:01:03 rivoplus sudo[4450]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 11:01:03 rivoplus volumio[3283]: error: write EPIPE {"errno":-32,"code":"EPIPE","syscall":"write"} Dec 16 11:01:03 rivoplus volumio[3283]: error: Upnp client error: Error: This socket has been ended by the other party Dec 16 11:01:03 rivoplus sudo[4450]: pam_unix(sudo:session): session closed for user root Dec 16 11:01:03 rivoplus sudo[4452]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 16 11:01:03 rivoplus sudo[4452]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 11:01:03 rivoplus volumio[3283]: info: MPD Permissions set Dec 16 11:01:03 rivoplus volumio[3283]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Dec 16 11:01:03 rivoplus volumio[3283]: info: QobuzConnect: setDeactiveState invoked Dec 16 11:01:03 rivoplus volumio[3283]: info: CoreCommandRouter::volumioGetState Dec 16 11:01:03 rivoplus volumio[3283]: info: CorePlayQueue::getTrack 0 Dec 16 11:01:03 rivoplus sudo[4452]: pam_unix(sudo:session): session closed for user root Dec 16 11:01:03 rivoplus sudo[4458]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 16 11:01:03 rivoplus sudo[4458]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 11:01:03 rivoplus sudo[4461]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 16 11:01:03 rivoplus sudo[4461]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 11:01:03 rivoplus volumio[3283]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 16 11:01:03 rivoplus volumio[3283]: info: Executing endpoint tc_getconfig Dec 16 11:01:03 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Dec 16 11:01:03 rivoplus volumio[3283]: info: Executing endpoint qc_getconfig Dec 16 11:01:03 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 16 11:01:03 rivoplus qobuz-connect[4408]: 20251216 11:01:03.792 [4408.4408] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Dec 16 11:01:03 rivoplus qobuz-connect[4408]: 20251216 11:01:03.820 [4408.4408] INFO VolumeManager: [0xac7b7700]: Setting new playback volume: 75 Dec 16 11:01:03 rivoplus qobuz-connect[4408]: 20251216 11:01:03.821 [4408.4408] INFO VolumeManager: [0xac7b7700]: Setting new mute state: 0 Dec 16 11:01:03 rivoplus qobuz-connect[4408]: 20251216 11:01:03.821 [4408.4408] INFO QobuzConnect: [0xac7b80d0]: Client initialized! Dec 16 11:01:03 rivoplus qobuz-connect[4408]: 20251216 11:01:03.821 [4408.4408] INFO SampleApp: Starting Avahi advertising, name: Rivoplus, service name: _qobuz-connect._tcp Dec 16 11:01:03 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 16 11:01:03 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 16 11:01:03 rivoplus volumio[3283]: info: Discovery: Getting this device information Dec 16 11:01:03 rivoplus volumio[3283]: info: CoreCommandRouter::volumioGetState Dec 16 11:01:03 rivoplus volumio[3283]: info: CorePlayQueue::getTrack 0 Dec 16 11:01:03 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 16 11:01:03 rivoplus qobuz-connect[4408]: 20251216 11:01:03.843 [4408.4408] INFO LocalConfigManager: [0xac7b6fe0]: Starting Local Configuration server Dec 16 11:01:03 rivoplus qobuz-connect[4408]: 20251216 11:01:03.843 [4408.4408] INFO SampleApp: Starting Local configuration server Dec 16 11:01:03 rivoplus qobuz-connect[4408]: 20251216 11:01:03.844 [4408.4408] INFO SampleApp: Connected to UNIX socket client 0xac7aced8 Dec 16 11:01:03 rivoplus volumio[3283]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 16 11:01:03 rivoplus volumio[3283]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 16 11:01:03 rivoplus volumio[3283]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Dec 16 11:01:03 rivoplus volumio[3283]: info: QobuzConnect: QOBUZ Connect daemon connected Dec 16 11:01:03 rivoplus qobuz-connect[4408]: 20251216 11:01:03.942 [4408.4408] INFO SampleApp: Playback volume changed: 75 Dec 16 11:01:03 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 11:01:03 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 11:01:03 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 11:01:03 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 11:01:03 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 11:01:03 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 11:01:03 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 11:01:03 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 11:01:04 rivoplus ntpd[4284]: Soliciting pool server 109.233.182.115 Dec 16 11:01:04 rivoplus ntpd[4284]: Soliciting pool server 82.197.164.46 Dec 16 11:01:04 rivoplus ntpd[4284]: Soliciting pool server 82.197.162.18 Dec 16 11:01:04 rivoplus ntpd[4284]: Listen normally on 5 eth0 169.254.62.135:123 Dec 16 11:01:04 rivoplus ntpd[4284]: new interface(s) found: waking up resolver Dec 16 11:01:04 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 11:01:04 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 16 11:01:04 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 11:01:04 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Dec 16 11:01:04 rivoplus volumio[3283]: No protocol specified Dec 16 11:01:04 rivoplus volumio[3283]: xcb_connection_has_error() returned true Dec 16 11:01:04 rivoplus sudo[4476]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Dec 16 11:01:04 rivoplus sudo[4476]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 11:01:04 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 16 11:01:04 rivoplus volumio[3283]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Dec 16 11:01:04 rivoplus volumio[3283]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Dec 16 11:01:04 rivoplus volumio[3283]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: volumiokiosk-touch Engine version: 3 Transport: polling Total Clients: 8 Dec 16 11:01:04 rivoplus volumio[3283]: info: CoreCommandRouter::volumioGetState Dec 16 11:01:04 rivoplus sudo[4476]: pam_unix(sudo:session): session closed for user root Dec 16 11:01:04 rivoplus volumio[3283]: info: CorePlayQueue::getTrack 0 Dec 16 11:01:04 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 16 11:01:04 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 16 11:01:04 rivoplus volumio[3283]: info: Discovery: Getting this device information Dec 16 11:01:04 rivoplus volumio[3283]: info: CoreCommandRouter::volumioGetState Dec 16 11:01:04 rivoplus volumio[3283]: info: CorePlayQueue::getTrack 0 Dec 16 11:01:04 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 16 11:01:04 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Dec 16 11:01:04 rivoplus sudo[4479]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Dec 16 11:01:04 rivoplus sudo[4479]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 11:01:04 rivoplus volumio[3283]: info: TidalConnect service started! Dec 16 11:01:04 rivoplus volumio[3283]: info: Completed starting Core Plugins Dec 16 11:01:04 rivoplus volumio[3283]: info: ------------------------------------------- Dec 16 11:01:04 rivoplus volumio[3283]: info: ----- MyVolumio plugins startup ---- Dec 16 11:01:04 rivoplus volumio[3283]: info: ------------------------------------------- Dec 16 11:01:04 rivoplus volumio[3283]: info: [MyVolumio PluginManager] Fetching plans data.... Dec 16 11:01:04 rivoplus systemd[1]: Started RAAT DAEMON. Dec 16 11:01:04 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Dec 16 11:01:04 rivoplus sudo[4479]: pam_unix(sudo:session): session closed for user root Dec 16 11:01:04 rivoplus volumio[3283]: info: CoreCommandRouter::volumioGetState Dec 16 11:01:04 rivoplus volumio[3283]: info: CorePlayQueue::getTrack 0 Dec 16 11:01:04 rivoplus volumio[3283]: info: Starting Shairport Sync Dec 16 11:01:04 rivoplus volumio[3283]: info: Discovery: adding b4c43cc9-f094-4c1b-a695-98c2a44972ac Dec 16 11:01:04 rivoplus volumio[3283]: info: Discovery: Found device Rivoplus Dec 16 11:01:04 rivoplus volumio[3283]: info: CoreCommandRouter::volumioGetState Dec 16 11:01:04 rivoplus volumio[3283]: info: CorePlayQueue::getTrack 0 Dec 16 11:01:04 rivoplus volumio[3283]: info: Discovery: this is already registered, b4c43cc9-f094-4c1b-a695-98c2a44972ac Dec 16 11:01:04 rivoplus volumio[3283]: info: Discovery: Found device Rivoplus Dec 16 11:01:04 rivoplus volumio[3283]: info: CoreCommandRouter::volumioGetState Dec 16 11:01:04 rivoplus volumio[3283]: info: CorePlayQueue::getTrack 0 Dec 16 11:01:04 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Dec 16 11:01:04 rivoplus sudo[4491]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 16 11:01:04 rivoplus sudo[4491]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 11:01:04 rivoplus systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 16 11:01:04 rivoplus systemd[1]: shairport-sync.service: Succeeded. Dec 16 11:01:04 rivoplus systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 16 11:01:04 rivoplus sudo[4495]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Dec 16 11:01:04 rivoplus sudo[4495]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 11:01:04 rivoplus sudo[4495]: pam_unix(sudo:session): session closed for user root Dec 16 11:01:04 rivoplus systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 16 11:01:04 rivoplus sudo[4501]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 16 11:01:04 rivoplus sudo[4491]: pam_unix(sudo:session): session closed for user root Dec 16 11:01:04 rivoplus sudo[4501]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 11:01:04 rivoplus sudo[4501]: pam_unix(sudo:session): session closed for user root Dec 16 11:01:05 rivoplus ntpd[4284]: Soliciting pool server 84.16.73.33 Dec 16 11:01:05 rivoplus ntpd[4284]: Soliciting pool server 2001:1680:1:1::10 Dec 16 11:01:05 rivoplus sudo[4516]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 16 11:01:05 rivoplus sudo[4516]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 11:01:05 rivoplus sudo[4516]: pam_unix(sudo:session): session closed for user root Dec 16 11:01:05 rivoplus sudo[4520]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 16 11:01:05 rivoplus sudo[4520]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 11:01:05 rivoplus sudo[4520]: pam_unix(sudo:session): session closed for user root Dec 16 11:01:05 rivoplus sudo[4527]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 16 11:01:05 rivoplus sudo[4527]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 11:01:05 rivoplus volumio[3283]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 16 11:01:05 rivoplus volumio[3283]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 16 11:01:05 rivoplus sudo[4527]: pam_unix(sudo:session): session closed for user root Dec 16 11:01:05 rivoplus sudo[4530]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 16 11:01:05 rivoplus sudo[4530]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 11:01:05 rivoplus volumio[3283]: info: Raat Daemon started successfully Dec 16 11:01:05 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 16 11:01:05 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 16 11:01:05 rivoplus sudo[4530]: pam_unix(sudo:session): session closed for user root Dec 16 11:01:05 rivoplus volumio[3283]: info: CoreCommandRouter::volumioGetState Dec 16 11:01:05 rivoplus volumio[3283]: info: CorePlayQueue::getTrack 0 Dec 16 11:01:05 rivoplus volumio[3283]: info: Executing endpoint restartRAATSocket Dec 16 11:01:05 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Dec 16 11:01:05 rivoplus volumio[3283]: info: Shairport-Sync Started Dec 16 11:01:05 rivoplus volumio[3283]: info: TidalConnect service stoped! Dec 16 11:01:05 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 16 11:01:05 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 16 11:01:05 rivoplus volumio[3283]: info: Discovery: Getting this device information Dec 16 11:01:05 rivoplus volumio[3283]: info: CoreCommandRouter::volumioGetState Dec 16 11:01:05 rivoplus volumio[3283]: info: CorePlayQueue::getTrack 0 Dec 16 11:01:05 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 16 11:01:05 rivoplus volumio[3283]: info: CorePlayQueue::getTrack 0 Dec 16 11:01:05 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 16 11:01:05 rivoplus volumio[3283]: info: Initializing connection to go-librespot Websocket Dec 16 11:01:05 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 16 11:01:05 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 16 11:01:05 rivoplus volumio[3283]: info: Discovery: Getting this device information Dec 16 11:01:05 rivoplus volumio[3283]: info: CoreCommandRouter::volumioGetState Dec 16 11:01:05 rivoplus volumio[3283]: info: CorePlayQueue::getTrack 0 Dec 16 11:01:05 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 16 11:01:05 rivoplus volumio[3283]: info: MCU Signalled Playback Inactive Dec 16 11:01:05 rivoplus volumio[3283]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Dec 16 11:01:05 rivoplus go-librespot[4218]: time="2025-12-16T11:01:05+01:00" level=debug msg="new websocket client" Dec 16 11:01:05 rivoplus volumio[3283]: verbose: New Socket.io Connection to 192.168.178.104:3000 from 192.168.178.157 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 10 Dec 16 11:01:05 rivoplus volumio[3283]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 16 11:01:05 rivoplus volumio[3283]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 16 11:01:05 rivoplus sudo[4578]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 16 11:01:05 rivoplus sudo[4578]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 11:01:05 rivoplus volumio[3283]: info: Connection to go-librespot Websocket established Dec 16 11:01:05 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 16 11:01:05 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 16 11:01:05 rivoplus volumio[3283]: info: CoreCommandRouter::volumioGetState Dec 16 11:01:05 rivoplus volumio[3283]: info: CorePlayQueue::getTrack 0 Dec 16 11:01:06 rivoplus systemd[1]: Started Volumio Tidal Connect Service. Dec 16 11:01:06 rivoplus sudo[4578]: pam_unix(sudo:session): session closed for user root Dec 16 11:01:06 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 16 11:01:06 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 16 11:01:06 rivoplus sudo[4594]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 16 11:01:06 rivoplus sudo[4594]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 11:01:06 rivoplus sudo[4596]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 16 11:01:06 rivoplus sudo[4596]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 11:01:06 rivoplus sudo[4594]: pam_unix(sudo:session): session closed for user root Dec 16 11:01:06 rivoplus sudo[4596]: pam_unix(sudo:session): session closed for user root Dec 16 11:01:06 rivoplus volumio[3283]: info: Executing endpoint tc_getconfig Dec 16 11:01:06 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Dec 16 11:01:06 rivoplus vtcs[4586]: STARTING TidalConnect services, version: 1.5.1-beta1 Dec 16 11:01:06 rivoplus vtcs[4586]: STARTED TidalConnect services. Dec 16 11:01:06 rivoplus volumio[3283]: info: Executing endpoint tc_connect Dec 16 11:01:06 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Dec 16 11:01:06 rivoplus volumio[3283]: info: Connecting to TidalConnect Dec 16 11:01:06 rivoplus volumio[3283]: verbose: New Socket.io Connection to 192.168.178.104 from 192.168.178.157 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 10 Dec 16 11:01:06 rivoplus volumio[3283]: info: CoreCommandRouter::servicePushState Dec 16 11:01:06 rivoplus volumio[3283]: info: CoreStateMachine::pushState Dec 16 11:01:06 rivoplus volumio[3283]: info: CorePlayQueue::getTrack 0 Dec 16 11:01:06 rivoplus volumio[3283]: info: CoreCommandRouter::volumioPushState Dec 16 11:01:06 rivoplus volumio[3283]: info: CoreCommandRouter::volumioGetState Dec 16 11:01:06 rivoplus volumio[3283]: info: CorePlayQueue::getTrack 0 Dec 16 11:01:06 rivoplus volumio[3283]: info: CorePlayQueue::getTrack 0 Dec 16 11:01:06 rivoplus volumio[3283]: 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} Dec 16 11:01:06 rivoplus volumio[3283]: verbose: CURRENT POSITION 0 Dec 16 11:01:06 rivoplus volumio[3283]: info: CoreStateMachine::syncState stateService stop Dec 16 11:01:06 rivoplus volumio[3283]: info: CoreStateMachine::syncState currentStatus stop Dec 16 11:01:06 rivoplus volumio[3283]: info: CoreStateMachine::pushState Dec 16 11:01:06 rivoplus volumio[3283]: info: CorePlayQueue::getTrack 0 Dec 16 11:01:06 rivoplus volumio[3283]: info: CoreCommandRouter::volumioPushState Dec 16 11:01:06 rivoplus volumio[3283]: info: CoreCommandRouter::volumioGetState Dec 16 11:01:06 rivoplus volumio[3283]: info: CorePlayQueue::getTrack 0 Dec 16 11:01:06 rivoplus volumio[3283]: info: No code Dec 16 11:01:06 rivoplus volumio[3283]: info: CoreStateMachine::pushState Dec 16 11:01:06 rivoplus volumio[3283]: info: CorePlayQueue::getTrack 0 Dec 16 11:01:06 rivoplus volumio[3283]: info: CoreCommandRouter::volumioPushState Dec 16 11:01:06 rivoplus volumio[3283]: info: CoreCommandRouter::volumioGetState Dec 16 11:01:06 rivoplus volumio[3283]: info: CorePlayQueue::getTrack 0 Dec 16 11:01:06 rivoplus volumio[3283]: info: CoreCommandRouter::servicePushState Dec 16 11:01:06 rivoplus volumio[3283]: info: CoreStateMachine::pushState Dec 16 11:01:06 rivoplus volumio[3283]: info: CorePlayQueue::getTrack 0 Dec 16 11:01:06 rivoplus volumio[3283]: info: CoreCommandRouter::volumioPushState Dec 16 11:01:06 rivoplus volumio[3283]: info: CoreCommandRouter::volumioGetState Dec 16 11:01:06 rivoplus volumio[3283]: info: CorePlayQueue::getTrack 0 Dec 16 11:01:06 rivoplus volumio[3283]: info: CorePlayQueue::getTrack 0 Dec 16 11:01:06 rivoplus volumio[3283]: 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} Dec 16 11:01:06 rivoplus volumio[3283]: verbose: CURRENT POSITION 0 Dec 16 11:01:06 rivoplus volumio[3283]: info: CoreStateMachine::syncState stateService stop Dec 16 11:01:06 rivoplus volumio[3283]: info: CoreStateMachine::syncState currentStatus stop Dec 16 11:01:06 rivoplus volumio[3283]: info: CoreStateMachine::pushState Dec 16 11:01:06 rivoplus volumio[3283]: info: CorePlayQueue::getTrack 0 Dec 16 11:01:06 rivoplus volumio[3283]: info: CoreCommandRouter::volumioPushState Dec 16 11:01:06 rivoplus volumio[3283]: info: CoreCommandRouter::volumioGetState Dec 16 11:01:06 rivoplus volumio[3283]: info: CorePlayQueue::getTrack 0 Dec 16 11:01:06 rivoplus volumio[3283]: info: No code Dec 16 11:01:06 rivoplus volumio[3283]: info: CoreStateMachine::pushState Dec 16 11:01:06 rivoplus volumio[3283]: info: CorePlayQueue::getTrack 0 Dec 16 11:01:06 rivoplus volumio[3283]: info: CoreCommandRouter::volumioPushState Dec 16 11:01:06 rivoplus volumio[3283]: info: CoreCommandRouter::volumioGetState Dec 16 11:01:06 rivoplus volumio[3283]: info: CorePlayQueue::getTrack 0 Dec 16 11:01:06 rivoplus mpd[4422]: Dec 16 11:01 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 16 11:01:06 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 16 11:01:06 rivoplus systemd[1]: Started Music Player Daemon. Dec 16 11:01:06 rivoplus sudo[4379]: pam_unix(sudo:session): session closed for user root Dec 16 11:01:06 rivoplus systemd[1]: Started MPD Monitor Service. Dec 16 11:01:06 rivoplus sudo[4461]: pam_unix(sudo:session): session closed for user root Dec 16 11:01:06 rivoplus sudo[4458]: pam_unix(sudo:session): session closed for user root Dec 16 11:01:06 rivoplus volumio[3283]: Upnp client error: Error: This socket has been ended by the other party Dec 16 11:01:06 rivoplus mpd_monitor.sh[4619]: MPD Monitor Service: Starting MPD Monitor Service Dec 16 11:01:06 rivoplus volumio[3283]: info: Checking for updated MCU Firmware Dec 16 11:01:06 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 16 11:01:06 rivoplus volumio[3283]: info: Firware on device is on latest version, no need to update Dec 16 11:01:06 rivoplus volumio[3283]: info: Successfully started MPD Monitor Dec 16 11:01:06 rivoplus volumio[3283]: info: Successfully started MPD Monitor Dec 16 11:01:06 rivoplus volumio[3283]: error: updateQueue error: null Dec 16 11:01:06 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 16 11:01:06 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 16 11:01:06 rivoplus volumio[3283]: info: Discovery: Getting this device information Dec 16 11:01:06 rivoplus volumio[3283]: info: CoreCommandRouter::volumioGetState Dec 16 11:01:06 rivoplus volumio[3283]: info: CorePlayQueue::getTrack 0 Dec 16 11:01:06 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 16 11:01:07 rivoplus sudo[4624]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 16 11:01:07 rivoplus sudo[4624]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 11:01:07 rivoplus volumio[3283]: verbose: New Socket.io Connection to 192.168.178.40:3000 from 192.168.178.157 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 11 Dec 16 11:01:07 rivoplus sudo[4624]: pam_unix(sudo:session): session closed for user root Dec 16 11:01:07 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 16 11:01:07 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 16 11:01:07 rivoplus sudo[4629]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 16 11:01:07 rivoplus sudo[4629]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 11:01:07 rivoplus sudo[4629]: pam_unix(sudo:session): session closed for user root Dec 16 11:01:07 rivoplus volumio[3283]: verbose: New Socket.io Connection to 192.168.178.104 from 192.168.178.157 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 11 Dec 16 11:01:07 rivoplus volumio[3283]: info: RAAT: Requesting Headphone Status Dec 16 11:01:07 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Dec 16 11:01:07 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 16 11:01:07 rivoplus volumio[3283]: info: CoreCommandRouter::volumioGetVisibleSources Dec 16 11:01:07 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 11:01:07 rivoplus volumio[3283]: info: CoreCommandRouter::volumioGetState Dec 16 11:01:07 rivoplus volumio[3283]: info: CorePlayQueue::getTrack 0 Dec 16 11:01:07 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 16 11:01:07 rivoplus volumio[3283]: info: CoreCommandRouter::volumioGetQueue Dec 16 11:01:07 rivoplus volumio[3283]: info: CoreStateMachine::getQueue Dec 16 11:01:07 rivoplus volumio[3283]: info: CorePlayQueue::getQueue Dec 16 11:01:07 rivoplus volumio[3283]: info: Listing playlists Dec 16 11:01:07 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 16 11:01:07 rivoplus volumio[3283]: info: Received Get System Info Dec 16 11:01:07 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 16 11:01:07 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 16 11:01:07 rivoplus volumio[3283]: info: Discovery: Getting this device information Dec 16 11:01:07 rivoplus volumio[3283]: info: CoreCommandRouter::volumioGetState Dec 16 11:01:07 rivoplus volumio[3283]: info: CorePlayQueue::getTrack 0 Dec 16 11:01:07 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 16 11:01:07 rivoplus volumio[3283]: info: CoreCommandRouter::volumioGetState Dec 16 11:01:07 rivoplus volumio[3283]: info: CorePlayQueue::getTrack 0 Dec 16 11:01:07 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 16 11:01:07 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 16 11:01:07 rivoplus volumio[3283]: info: MCU Signalled Headphone Mode Disabled Dec 16 11:01:07 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Dec 16 11:01:07 rivoplus volumio[3283]: info: Reporting Headphone State: false Dec 16 11:01:07 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 11:01:07 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 11:01:07 rivoplus volumio[3283]: info: Updating RAAT Signal Path Dec 16 11:01:07 rivoplus volumio[3283]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 16 11:01:07 rivoplus volumio[3283]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: volumiokiosk-touch Engine version: 3 Transport: polling Total Clients: 12 Dec 16 11:01:09 rivoplus ntpd[4284]: receive: Unexpected origin timestamp 0xecebac64.00f83f16 does not match aorg 0000000000.00000000 from server@195.141.190.190 xmt 0xecebac65.0f9533ee Dec 16 11:01:09 rivoplus ntpd[4284]: receive: Unexpected origin timestamp 0xecebac64.00fac91b does not match aorg 0000000000.00000000 from server@185.125.24.59 xmt 0xecebac65.0fd37dbe Dec 16 11:01:09 rivoplus ntpd[4284]: receive: Unexpected origin timestamp 0xecebac64.00fd0e08 does not match aorg 0000000000.00000000 from server@212.51.144.46 xmt 0xecebac65.10d46a25 Dec 16 11:01:09 rivoplus volumio[3283]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=mp1&variante=rivoplus&version=3.874&uuid=b4eb889311ffdd98e5bc6b0a9898118c" http://updates.volumio.org/downloader-v1/track-device Dec 16 11:01:09 rivoplus volumio[3283]: % Total % Received % Xferd Average Speed Time Time Time Current Dec 16 11:01:09 rivoplus volumio[3283]: Dload Upload Total Spent Left Speed Dec 16 11:01:09 rivoplus volumio[3283]: [132B blob data] Dec 16 11:01:09 rivoplus volumio[3283]: retrying in 5 seconds, trial 0 Dec 16 11:01:09 rivoplus volumio[3283]: info: Volumio Calling Home Dec 16 11:01:09 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 16 11:01:09 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 16 11:01:09 rivoplus volumio[3283]: info: Discovery: Getting this device information Dec 16 11:01:09 rivoplus volumio[3283]: info: CoreCommandRouter::volumioGetState Dec 16 11:01:09 rivoplus volumio[3283]: info: CorePlayQueue::getTrack 0 Dec 16 11:01:09 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 16 11:01:09 rivoplus volumio[3283]: verbose: New Socket.io Connection to 192.168.178.104:3000 from 192.168.178.157 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 13 Dec 16 11:01:09 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 16 11:01:09 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 16 11:01:09 rivoplus volumio[3283]: info: Volumio called home Dec 16 11:01:09 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Dec 16 11:01:10 rivoplus volumio[3283]: info: Getting Spotify volume Dec 16 11:01:10 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 16 11:01:10 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 16 11:01:10 rivoplus volumio[3283]: info: Received Get System Info Dec 16 11:01:10 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 16 11:01:10 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 16 11:01:10 rivoplus volumio[3283]: info: Discovery: Getting this device information Dec 16 11:01:10 rivoplus volumio[3283]: info: CoreCommandRouter::volumioGetState Dec 16 11:01:10 rivoplus volumio[3283]: info: CorePlayQueue::getTrack 0 Dec 16 11:01:10 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 16 11:01:10 rivoplus volumio[3283]: info: TidalConnect service started! Dec 16 11:01:10 rivoplus volumio[3283]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 13 Dec 16 11:01:10 rivoplus volumio[3283]: info: CoreCommandRouter::volumioGetState Dec 16 11:01:10 rivoplus volumio[3283]: info: CorePlayQueue::getTrack 0 Dec 16 11:01:10 rivoplus volumio[3283]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 16 11:01:10 rivoplus volumio[3283]: SPOTIFY: SPOTIFY VOLUME undefined Dec 16 11:01:10 rivoplus volumio[3283]: SPOTIFY: VOLUMIO VOLUME 100 Dec 16 11:01:10 rivoplus volumio[3283]: info: Aligning Spotify Volume to Volumio Volume Dec 16 11:01:10 rivoplus volumio[3283]: info: CoreCommandRouter::volumioGetState Dec 16 11:01:10 rivoplus volumio[3283]: info: CorePlayQueue::getTrack 0 Dec 16 11:01:10 rivoplus volumio[3283]: info: Setting Spotify Volume from Volumio: 100 Dec 16 11:01:10 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Dec 16 11:01:10 rivoplus volumio[3283]: info: CoreCommandRouter::volumioGetVisibleSources Dec 16 11:01:10 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 11:01:10 rivoplus volumio[3283]: info: CoreCommandRouter::volumioGetState Dec 16 11:01:10 rivoplus volumio[3283]: info: CorePlayQueue::getTrack 0 Dec 16 11:01:10 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 16 11:01:10 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 16 11:01:10 rivoplus volumio[3283]: info: Received Get System Info Dec 16 11:01:10 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 16 11:01:10 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 16 11:01:10 rivoplus volumio[3283]: info: Discovery: Getting this device information Dec 16 11:01:10 rivoplus volumio[3283]: info: CoreCommandRouter::volumioGetState Dec 16 11:01:10 rivoplus volumio[3283]: info: CorePlayQueue::getTrack 0 Dec 16 11:01:10 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 16 11:01:10 rivoplus volumio[3283]: info: CoreCommandRouter::volumioGetState Dec 16 11:01:10 rivoplus volumio[3283]: info: CorePlayQueue::getTrack 0 Dec 16 11:01:10 rivoplus volumio[3283]: info: Listing playlists Dec 16 11:01:10 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 16 11:01:10 rivoplus volumio[3283]: info: Received Get System Info Dec 16 11:01:10 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 16 11:01:10 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 16 11:01:10 rivoplus volumio[3283]: info: Discovery: Getting this device information Dec 16 11:01:10 rivoplus volumio[3283]: info: CoreCommandRouter::volumioGetState Dec 16 11:01:10 rivoplus volumio[3283]: info: CorePlayQueue::getTrack 0 Dec 16 11:01:10 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 16 11:01:10 rivoplus volumio[3283]: info: CoreCommandRouter::volumioGetState Dec 16 11:01:10 rivoplus volumio[3283]: info: CorePlayQueue::getTrack 0 Dec 16 11:01:10 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 16 11:01:10 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 16 11:01:11 rivoplus sudo[4654]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 16 11:01:11 rivoplus sudo[4654]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 11:01:11 rivoplus systemd[1]: Stopping MPD Monitor Service... Dec 16 11:01:11 rivoplus systemd[1]: mpd_monitor.service: Main process exited, code=killed, status=15/TERM Dec 16 11:01:11 rivoplus systemd[1]: mpd_monitor.service: Succeeded. Dec 16 11:01:11 rivoplus systemd[1]: Stopped MPD Monitor Service. Dec 16 11:01:11 rivoplus volumio[3283]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 16 11:01:11 rivoplus volumio[3283]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 16 11:01:11 rivoplus systemd[1]: Started MPD Monitor Service. Dec 16 11:01:11 rivoplus mpd_monitor.sh[4656]: MPD Monitor Service: Starting MPD Monitor Service Dec 16 11:01:11 rivoplus volumio[3283]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 16 11:01:11 rivoplus sudo[4654]: pam_unix(sudo:session): session closed for user root Dec 16 11:01:11 rivoplus volumio[3283]: info: Successfully started MPD Monitor Dec 16 11:01:11 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 16 11:01:11 rivoplus volumio[3283]: info: Received Get System Info Dec 16 11:01:11 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 16 11:01:11 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 16 11:01:11 rivoplus volumio[3283]: info: Discovery: Getting this device information Dec 16 11:01:11 rivoplus volumio[3283]: info: CoreCommandRouter::volumioGetState Dec 16 11:01:11 rivoplus volumio[3283]: info: CorePlayQueue::getTrack 0 Dec 16 11:01:11 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 16 11:01:11 rivoplus volumio[3283]: SPOTIFY: SETTING SPOTIFY VOLUME 100 Dec 16 11:01:11 rivoplus volumio[3283]: info: Sending Spotify command with payload to local API: /player/volume Dec 16 11:01:11 rivoplus volumio[3283]: info: Preload queue cleared Dec 16 11:01:11 rivoplus volumio[3283]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 16 11:01:11 rivoplus volumio[3283]: info: CoreStateMachine::ClearQueue Dec 16 11:01:11 rivoplus volumio[3283]: info: CoreStateMachine::stop Dec 16 11:01:11 rivoplus volumio[3283]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 16 11:01:11 rivoplus volumio[3283]: info: CorePlayQueue::clearPlayQueue Dec 16 11:01:11 rivoplus volumio[3283]: info: CorePlayQueue::saveQueue Dec 16 11:01:11 rivoplus volumio[3283]: info: CoreCommandRouter::volumioPushQueue Dec 16 11:01:11 rivoplus volumio[3283]: info: CoreStateMachine::addQueueItems Dec 16 11:01:11 rivoplus volumio[3283]: info: CorePlayQueue::addQueueItems Dec 16 11:01:11 rivoplus volumio[3283]: info: Preload queue cleared Dec 16 11:01:11 rivoplus volumio[3283]: info: Adding Item to queue: webnineties/3 Dec 16 11:01:11 rivoplus volumio[3283]: info: Exploding uri webnineties/3 in service 80s80s Dec 16 11:01:11 rivoplus volumio[3283]: info: [1765879271979] [80s80s] explodeUri: webnineties/3 Dec 16 11:01:11 rivoplus volumio[3283]: info: CoreCommandRouter::volumioPushQueue Dec 16 11:01:11 rivoplus volumio[3283]: info: CorePlayQueue::saveQueue Dec 16 11:01:11 rivoplus volumio[3283]: info: CoreStateMachine::updateTrackBlock Dec 16 11:01:11 rivoplus volumio[3283]: info: CorePlayQueue::getTrackBlock Dec 16 11:01:11 rivoplus volumio[3283]: info: CoreCommandRouter::volumioPlay Dec 16 11:01:11 rivoplus volumio[3283]: info: CoreStateMachine::play index 0 Dec 16 11:01:11 rivoplus volumio[3283]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 16 11:01:12 rivoplus volumio[3283]: info: CoreStateMachine::stop Dec 16 11:01:12 rivoplus volumio[3283]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 16 11:01:12 rivoplus volumio[3283]: info: CoreStateMachine::play index undefined Dec 16 11:01:12 rivoplus volumio[3283]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 16 11:01:12 rivoplus volumio[3283]: info: CorePlayQueue::getTrack 0 Dec 16 11:01:12 rivoplus volumio[3283]: info: CoreStateMachine::startPlaybackTimer Dec 16 11:01:12 rivoplus volumio[3283]: info: CorePlayQueue::getTrack 0 Dec 16 11:01:12 rivoplus volumio[3283]: info: CoreCommandRouter::volumioGetVisibleSources Dec 16 11:01:12 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 11:01:12 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Dec 16 11:01:12 rivoplus volumio[3283]: info: [1765879272009] [80s80s] clearAddPlayTrack url: http://streams.90s90s.de/beat/mp3-192/volumio Dec 16 11:01:12 rivoplus volumio[3283]: info: [1765879272011] [80s80s] getContentOfUrl started with url http://iris-90s90s.loverad.io/flow.json?station=140&count=2 Dec 16 11:01:12 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Dec 16 11:01:12 rivoplus volumio[3283]: info: [1765879272236] [80s80s] received new event containing 2 songs. Dec 16 11:01:12 rivoplus volumio[3283]: verbose: ControllerMpd::sendMpdCommand stop Dec 16 11:01:12 rivoplus volumio[3283]: info: sendMpdCommand stop took 8 milliseconds Dec 16 11:01:12 rivoplus volumio[3283]: verbose: ControllerMpd::sendMpdCommand clear Dec 16 11:01:12 rivoplus volumio[3283]: info: Dec 16 11:01:12 rivoplus volumio[3283]: ---------------------------- MPD announces system playlist update Dec 16 11:01:12 rivoplus volumio[3283]: info: Ignoring MPD Status Update Dec 16 11:01:12 rivoplus volumio[3283]: info: sendMpdCommand clear took 6 milliseconds Dec 16 11:01:12 rivoplus volumio[3283]: verbose: ControllerMpd::sendMpdCommand consume 1 Dec 16 11:01:12 rivoplus volumio[3283]: error: updateQueue error: null Dec 16 11:01:12 rivoplus volumio[3283]: info: Dec 16 11:01:12 rivoplus volumio[3283]: ---------------------------- MPD announces state update: options Dec 16 11:01:12 rivoplus volumio[3283]: info: ------------------------------ 26ms Dec 16 11:01:12 rivoplus volumio[3283]: info: sendMpdCommand consume 1 took 12 milliseconds Dec 16 11:01:12 rivoplus volumio[3283]: info: ControllerMpd::getState Dec 16 11:01:12 rivoplus volumio[3283]: verbose: ControllerMpd::sendMpdCommand status Dec 16 11:01:12 rivoplus volumio[3283]: info: [1765879272291] [80s80s] adding url: http://streams.90s90s.de/beat/mp3-192/volumio Dec 16 11:01:12 rivoplus volumio[3283]: verbose: ControllerMpd::sendMpdCommand add "http://streams.90s90s.de/beat/mp3-192/volumio" Dec 16 11:01:12 rivoplus volumio[3283]: info: Dec 16 11:01:12 rivoplus volumio[3283]: ---------------------------- MPD announces system playlist update Dec 16 11:01:12 rivoplus volumio[3283]: info: Ignoring MPD Status Update Dec 16 11:01:12 rivoplus volumio[3283]: info: sendMpdCommand status took 12 milliseconds Dec 16 11:01:12 rivoplus volumio[3283]: info: sendMpdCommand add "http://streams.90s90s.de/beat/mp3-192/volumio" took 9 milliseconds Dec 16 11:01:12 rivoplus volumio[3283]: verbose: ControllerMpd::parseState Dec 16 11:01:12 rivoplus volumio[3283]: verbose: ControllerMpd::sendMpdCommand play Dec 16 11:01:12 rivoplus volumio[3283]: info: ControllerMpd::pushState Dec 16 11:01:12 rivoplus volumio[3283]: info: CoreCommandRouter::servicePushState Dec 16 11:01:12 rivoplus volumio[3283]: info: CoreStateMachine::pushState Dec 16 11:01:12 rivoplus volumio[3283]: info: CorePlayQueue::getTrack 0 Dec 16 11:01:12 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 11:01:12 rivoplus volumio[3283]: info: CoreCommandRouter::volumioPushState Dec 16 11:01:12 rivoplus volumio[3283]: info: CoreCommandRouter::volumioGetState Dec 16 11:01:12 rivoplus volumio[3283]: info: CorePlayQueue::getTrack 0 Dec 16 11:01:12 rivoplus volumio[3283]: info: CorePlayQueue::getTrack 0 Dec 16 11:01:12 rivoplus volumio[3283]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Dec 16 11:01:12 rivoplus volumio[3283]: info: ------------------------------ 82ms Dec 16 11:01:12 rivoplus volumio[3283]: info: ------------------------------ 72ms Dec 16 11:01:12 rivoplus volumio[3283]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 16 11:01:12 rivoplus volumio[3283]: info: sendMpdCommand play took 75 milliseconds Dec 16 11:01:12 rivoplus volumio[3283]: info: [1765879272399] [80s80s] Pushing the next song state: Die Fantastischen Vier - Mit freundlichen Grüßen Dec 16 11:01:12 rivoplus volumio[3283]: info: CorePlayQueue::getTrack 0 Dec 16 11:01:12 rivoplus volumio[3283]: info: CoreCommandRouter::servicePushState Dec 16 11:01:12 rivoplus volumio[3283]: info: CorePlayQueue::getTrack 0 Dec 16 11:01:12 rivoplus volumio[3283]: verbose: STATE SERVICE {"status":"play","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is1-ssl.mzstatic.com/image/thumb/Music1/v4/5e/ad/ec/5eadecd0-fed6-5b00-7b60-ecd4a10d86a3/source/600x600bb.jpg","name":"Die Fantastischen Vier - Mit freundlichen Grüßen","title":"Mit freundlichen Grüßen","artist":"90s90s Clubhits","album":"","streaming":true,"disableUiControls":true,"duration":"204","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Dec 16 11:01:12 rivoplus volumio[3283]: verbose: CURRENT POSITION 0 Dec 16 11:01:12 rivoplus volumio[3283]: info: CoreStateMachine::syncState stateService play Dec 16 11:01:12 rivoplus volumio[3283]: info: CoreStateMachine::syncState currentStatus stop Dec 16 11:01:12 rivoplus volumio[3283]: info: [1765879272415] [80s80s] PlayNextTrack API delay: 30 Dec 16 11:01:12 rivoplus volumio[3283]: info: [1765879272445] [80s80s] Setting timer to: 204000 milliseconds. Dec 16 11:01:12 rivoplus volumio[3283]: info: CorePlayQueue::getTrack 0 Dec 16 11:01:12 rivoplus volumio[3283]: info: CorePlayQueue::getTrack 1 Dec 16 11:01:13 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Dec 16 11:01:13 rivoplus volumio[3283]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Dec 16 11:01:13 rivoplus volumio[3283]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Dec 16 11:01:13 rivoplus volumio[3283]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Dec 16 11:01:13 rivoplus volumio[3283]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Dec 16 11:01:13 rivoplus volumio[3283]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Dec 16 11:01:13 rivoplus volumio[3283]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Dec 16 11:01:13 rivoplus volumio[3283]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Dec 16 11:01:13 rivoplus volumio[3283]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Dec 16 11:01:13 rivoplus volumio[3283]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Dec 16 11:01:13 rivoplus volumio[3283]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Dec 16 11:01:13 rivoplus volumio[3283]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Dec 16 11:01:13 rivoplus volumio[3283]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Dec 16 11:01:13 rivoplus volumio[3283]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Dec 16 11:01:13 rivoplus volumio[3283]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Dec 16 11:01:13 rivoplus volumio[3283]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Dec 16 11:01:13 rivoplus volumio[3283]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Dec 16 11:01:13 rivoplus volumio[3283]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Dec 16 11:01:13 rivoplus volumio[3283]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Dec 16 11:01:13 rivoplus volumio[3283]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Dec 16 11:01:13 rivoplus volumio[3283]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Dec 16 11:01:13 rivoplus volumio[3283]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Dec 16 11:01:13 rivoplus volumio[3283]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Dec 16 11:01:13 rivoplus volumio[3283]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Dec 16 11:01:13 rivoplus volumio[3283]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Dec 16 11:01:13 rivoplus volumio[3283]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Dec 16 11:01:13 rivoplus volumio[3283]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Dec 16 11:01:13 rivoplus volumio[3283]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Dec 16 11:01:13 rivoplus volumio[3283]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Dec 16 11:01:13 rivoplus volumio[3283]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Dec 16 11:01:15 rivoplus volumio[3283]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Dec 16 11:01:15 rivoplus volumio[3283]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Dec 16 11:01:15 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 11:01:15 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 11:01:15 rivoplus volumio[3283]: info: Starting MyVolumio Remote Streaming Endpoints Dec 16 11:01:15 rivoplus volumio[3283]: info: MyVolumio login type: Token Dec 16 11:01:15 rivoplus volumio[3283]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Dec 16 11:01:15 rivoplus volumio[3283]: 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' Dec 16 11:01:15 rivoplus volumio[3283]: info: Dec 16 11:01:15 rivoplus volumio[3283]: ---------------------------- MPD announces state update: player Dec 16 11:01:15 rivoplus volumio[3283]: info: ControllerMpd::getState Dec 16 11:01:15 rivoplus volumio[3283]: verbose: ControllerMpd::sendMpdCommand status Dec 16 11:01:15 rivoplus volumio[3283]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Dec 16 11:01:15 rivoplus volumio[3283]: info: FusionDsp - ---- read samplerate from file: 44100 Dec 16 11:01:15 rivoplus volumio[3283]: info: camilladsp stopping service pid 4300... Dec 16 11:01:15 rivoplus volumio[3283]: info: camilladsp service terminated, instance 1 Dec 16 11:01:15 rivoplus volumio[3283]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 16 11:01:15 rivoplus volumio[3283]: info: FusionDsp - Effects disabled Dec 16 11:01:15 rivoplus volumio[3283]: info: camilladsp service started and running in background, instance 1 Dec 16 11:01:15 rivoplus volumio[3283]: info: Dec 16 11:01:15 rivoplus volumio[3283]: ---------------------------- MPD announces system playlist update Dec 16 11:01:15 rivoplus volumio[3283]: info: Ignoring MPD Status Update Dec 16 11:01:15 rivoplus volumio[3283]: info: Dec 16 11:01:15 rivoplus volumio[3283]: ---------------------------- MPD announces state update: player Dec 16 11:01:15 rivoplus volumio[3283]: info: ControllerMpd::getState Dec 16 11:01:15 rivoplus volumio[3283]: verbose: ControllerMpd::sendMpdCommand status Dec 16 11:01:15 rivoplus volumio[3283]: info: sendMpdCommand status took 74 milliseconds Dec 16 11:01:15 rivoplus volumio[3283]: verbose: ControllerMpd::parseState Dec 16 11:01:15 rivoplus volumio[3283]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 11:01:15 rivoplus volumio[3283]: info: ------------------------------ 20ms Dec 16 11:01:15 rivoplus volumio[3283]: info: sendMpdCommand status took 18 milliseconds Dec 16 11:01:15 rivoplus volumio[3283]: info: sendMpdCommand playlistinfo took 16 milliseconds Dec 16 11:01:15 rivoplus volumio[3283]: verbose: ControllerMpd::parseState Dec 16 11:01:15 rivoplus volumio[3283]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 11:01:15 rivoplus volumio[3283]: verbose: ControllerMpd::parseTrackInfo Dec 16 11:01:15 rivoplus volumio[3283]: info: ControllerMpd::pushState Dec 16 11:01:15 rivoplus volumio[3283]: info: CoreCommandRouter::servicePushState Dec 16 11:01:15 rivoplus volumio[3283]: info: CorePlayQueue::getTrack 0 Dec 16 11:01:15 rivoplus volumio[3283]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Dec 16 11:01:15 rivoplus volumio[3283]: info: ------------------------------ 101ms Dec 16 11:01:15 rivoplus volumio[3283]: info: camilladsp spawned new process with pid 4678, instance 1, run: true Dec 16 11:01:15 rivoplus volumio[3283]: info: sendMpdCommand playlistinfo took 45 milliseconds Dec 16 11:01:15 rivoplus volumio[3283]: verbose: ControllerMpd::parseTrackInfo Dec 16 11:01:15 rivoplus volumio[3283]: info: ControllerMpd::pushState Dec 16 11:01:15 rivoplus volumio[3283]: info: CoreCommandRouter::servicePushState Dec 16 11:01:15 rivoplus volumio[3283]: info: CorePlayQueue::getTrack 0 Dec 16 11:01:15 rivoplus volumio[3283]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Dec 16 11:01:15 rivoplus volumio[3283]: info: ------------------------------ 70ms Dec 16 11:01:15 rivoplus volumio[3283]: info: Executing endpoint metavolumio Dec 16 11:01:15 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 16 11:01:15 rivoplus volumio[3283]: error: MyVolumio Custom Token format not valid, refreshing it Dec 16 11:01:16 rivoplus volumio[3283]: info: MyVolumio login type: Token Dec 16 11:01:17 rivoplus volumio[3283]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Dec 16 11:01:17 rivoplus volumio[3283]: info: MyVolumio token set successfully Dec 16 11:01:17 rivoplus volumio[3283]: info: MYVOLUMIO: Adding device Dec 16 11:01:17 rivoplus volumio[3283]: info: MYVOLUMIO: Evaluating Server Dec 16 11:01:18 rivoplus volumio[3283]: info: MyVolumio status changed Dec 16 11:01:18 rivoplus volumio[3283]: info: Streaming services startup Dec 16 11:01:18 rivoplus volumio[3283]: info: Starting Streaming Daemon Dec 16 11:01:18 rivoplus volumio[3283]: info: Removing browser output: myVolumio user plan is not superstar Dec 16 11:01:18 rivoplus volumio[3283]: info: Removing audio output: Dec 16 11:01:18 rivoplus volumio[3283]: info: Stoppping Tunnel 1 Dec 16 11:01:18 rivoplus sudo[4713]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 16 11:01:18 rivoplus sudo[4713]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 11:01:18 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 16 11:01:18 rivoplus sudo[4713]: pam_unix(sudo:session): session closed for user root Dec 16 11:01:18 rivoplus volumio[3283]: error: Cannot start Volumio Streaming Daemon Dec 16 11:01:18 rivoplus volumio[3283]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 16 11:01:18 rivoplus volumio[3283]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 16 11:01:18 rivoplus sudo[4716]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Dec 16 11:01:18 rivoplus sudo[4716]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 11:01:18 rivoplus sudo[4716]: pam_unix(sudo:session): session closed for user root Dec 16 11:01:18 rivoplus volumio[3283]: info: Remote SSH Stopped Dec 16 11:01:18 rivoplus volumio[3283]: info: Setting Geolocation for MyVolumio to eu4 Dec 16 11:01:18 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 11:01:18 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 11:01:18 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 11:01:18 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 16 11:01:19 rivoplus volumio[3283]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Dec 16 11:01:19 rivoplus volumio[3283]: info: Updating MyVolumio device info Dec 16 11:01:19 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 11:01:20 rivoplus volumio[3283]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Dec 16 11:01:23 rivoplus volumio[3283]: info: MYVOLUMIO: Adding device Dec 16 11:01:23 rivoplus volumio[3283]: info: MYVOLUMIO: Evaluating Server Dec 16 11:01:24 rivoplus volumio[3283]: info: Setting Geolocation for MyVolumio to eu10 Dec 16 11:01:24 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 11:01:24 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 11:01:24 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 11:01:24 rivoplus volumio[3283]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Dec 16 11:01:25 rivoplus volumio[3283]: info: Updating MyVolumio device info Dec 16 11:01:25 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 11:01:25 rivoplus volumio[3283]: error: MyVolumio Plugin failed to start in a timely fashion Dec 16 11:01:25 rivoplus volumio[3283]: [Metrics] CommandRouter: 60s 437.40ms Dec 16 11:01:25 rivoplus volumio[3283]: info: CoreCommandRouter::volumiosetStartupVolume Dec 16 11:01:25 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 11:01:25 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 11:01:25 rivoplus volumio[3283]: info: CoreCommandRouter::Close All Modals sent Dec 16 11:01:25 rivoplus volumio[3283]: info: CoreCommandRouter::Close All Modals sent Dec 16 11:01:25 rivoplus volumio[3283]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Dec 16 11:01:25 rivoplus volumio[3283]: info: FusionDsp - ---- read samplerate from file: 44100 Dec 16 11:01:25 rivoplus volumio[3283]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 16 11:01:25 rivoplus volumio[3283]: info: FusionDsp - Effects disabled Dec 16 11:01:25 rivoplus volumio[3283]: info: FusionDsp - {"Reload":{"result":"Ok"}} Dec 16 11:01:25 rivoplus volumio[3283]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Dec 16 11:01:26 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 16 11:01:26 rivoplus volumio[3283]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Dec 16 11:01:26 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Dec 16 11:01:26 rivoplus volumio[3283]: info: Received Get System Version Dec 16 11:01:26 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 16 11:01:26 rivoplus volumio[3283]: info: Received Get System Info Dec 16 11:01:26 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 16 11:01:26 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 16 11:01:26 rivoplus volumio[3283]: info: Discovery: Getting this device information Dec 16 11:01:26 rivoplus volumio[3283]: info: CoreCommandRouter::volumioGetState Dec 16 11:01:26 rivoplus volumio[3283]: info: CorePlayQueue::getTrack 0 Dec 16 11:01:26 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 16 11:01:26 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 16 11:01:26 rivoplus volumio[3283]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Dec 16 11:01:26 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Dec 16 11:01:26 rivoplus volumio[3283]: info: Received Get System Version Dec 16 11:01:26 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 16 11:01:26 rivoplus volumio[3283]: info: Received Get System Info Dec 16 11:01:26 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 16 11:01:26 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 16 11:01:26 rivoplus volumio[3283]: info: Discovery: Getting this device information Dec 16 11:01:26 rivoplus volumio[3283]: info: CoreCommandRouter::volumioGetState Dec 16 11:01:26 rivoplus volumio[3283]: info: CorePlayQueue::getTrack 0 Dec 16 11:01:26 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 16 11:01:26 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Dec 16 11:01:26 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 16 11:01:26 rivoplus volumio[3283]: No protocol specified Dec 16 11:01:26 rivoplus volumio[3283]: xcb_connection_has_error() returned true Dec 16 11:01:26 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Dec 16 11:01:27 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 16 11:01:27 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 11:01:27 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 16 11:01:28 rivoplus volumio-remote-updater[2827]: No test mode Dec 16 11:01:28 rivoplus volumio-remote-updater[2827]: No alpha test mode Dec 16 11:01:28 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Dec 16 11:01:28 rivoplus volumio[3283]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Dec 16 11:01:28 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Dec 16 11:01:30 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 16 11:01:30 rivoplus volumio[3283]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 14 Dec 16 11:01:30 rivoplus volumio[3283]: info: CoreCommandRouter::volumioGetState Dec 16 11:01:30 rivoplus volumio[3283]: info: CorePlayQueue::getTrack 0 Dec 16 11:01:32 rivoplus volumio[3283]: info: BOOT COMPLETED Dec 16 11:01:34 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 11:01:34 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 11:01:34 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 11:01:34 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 11:01:34 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 11:01:34 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 11:01:34 rivoplus volumio[3283]: info: Not Reporting Auto name since its the default one Dec 16 11:01:34 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Dec 16 11:01:34 rivoplus volumio[3283]: No protocol specified Dec 16 11:01:34 rivoplus volumio[3283]: xcb_connection_has_error() returned true Dec 16 11:01:34 rivoplus sudo[4798]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Dec 16 11:01:34 rivoplus sudo[4798]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 11:01:34 rivoplus sudo[4798]: pam_unix(sudo:session): session closed for user root Dec 16 11:01:34 rivoplus sudo[4800]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Dec 16 11:01:34 rivoplus sudo[4800]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 11:01:34 rivoplus systemd[1]: Stopping RAAT DAEMON... Dec 16 11:01:34 rivoplus systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Dec 16 11:01:34 rivoplus systemd[1]: raat-daemon.service: Succeeded. Dec 16 11:01:34 rivoplus systemd[1]: Stopped RAAT DAEMON. Dec 16 11:01:34 rivoplus volumio[3283]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 16 11:01:34 rivoplus volumio[3283]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 16 11:01:34 rivoplus systemd[1]: Started RAAT DAEMON. Dec 16 11:01:34 rivoplus sudo[4800]: pam_unix(sudo:session): session closed for user root Dec 16 11:01:34 rivoplus volumio[3283]: info: Raat Daemon started successfully Dec 16 11:01:34 rivoplus volumio[3283]: info: Executing endpoint restartRAATSocket Dec 16 11:01:34 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Dec 16 11:01:34 rivoplus volumio[3283]: info: CorePlayQueue::getTrack 0 Dec 16 11:01:34 rivoplus volumio[3283]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 15 Dec 16 11:01:35 rivoplus volumio[3283]: info: Dec 16 11:01:35 rivoplus volumio[3283]: ---------------------------- MPD announces system playlist update Dec 16 11:01:35 rivoplus volumio[3283]: info: Ignoring MPD Status Update Dec 16 11:01:35 rivoplus volumio[3283]: info: Dec 16 11:01:35 rivoplus volumio[3283]: ---------------------------- MPD announces state update: player Dec 16 11:01:35 rivoplus volumio[3283]: info: ControllerMpd::getState Dec 16 11:01:35 rivoplus volumio[3283]: verbose: ControllerMpd::sendMpdCommand status Dec 16 11:01:35 rivoplus volumio[3283]: info: ------------------------------ 5ms Dec 16 11:01:35 rivoplus volumio[3283]: info: sendMpdCommand status took 3 milliseconds Dec 16 11:01:35 rivoplus volumio[3283]: verbose: ControllerMpd::parseState Dec 16 11:01:35 rivoplus volumio[3283]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 11:01:35 rivoplus volumio[3283]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 16 11:01:35 rivoplus volumio[3283]: verbose: ControllerMpd::parseTrackInfo Dec 16 11:01:35 rivoplus volumio[3283]: info: ControllerMpd::pushState Dec 16 11:01:35 rivoplus volumio[3283]: info: CoreCommandRouter::servicePushState Dec 16 11:01:35 rivoplus volumio[3283]: info: CorePlayQueue::getTrack 0 Dec 16 11:01:35 rivoplus volumio[3283]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Dec 16 11:01:35 rivoplus volumio[3283]: info: ------------------------------ 11ms Dec 16 11:01:35 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 11:01:35 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 11:01:35 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Dec 16 11:01:35 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 16 11:01:35 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Dec 16 11:01:35 rivoplus volumio[3283]: info: CoreCommandRouter::volumioGetBrowseSources Dec 16 11:01:35 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 16 11:01:36 rivoplus volumio[3283]: info: Preload queue cleared Dec 16 11:01:36 rivoplus volumio[3283]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 16 11:01:36 rivoplus volumio[3283]: info: CoreStateMachine::ClearQueue Dec 16 11:01:36 rivoplus volumio[3283]: info: CoreStateMachine::stop Dec 16 11:01:36 rivoplus volumio[3283]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 16 11:01:36 rivoplus volumio[3283]: info: CoreStateMachine::stPlaybackTimer Dec 16 11:01:36 rivoplus volumio[3283]: info: CoreStateMachine::updateTrackBlock Dec 16 11:01:36 rivoplus volumio[3283]: info: CorePlayQueue::getTrackBlock Dec 16 11:01:36 rivoplus volumio[3283]: info: CoreStateMachine::pushState Dec 16 11:01:36 rivoplus volumio[3283]: info: CorePlayQueue::getTrack 0 Dec 16 11:01:36 rivoplus volumio[3283]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 11:01:36 rivoplus volumio[3283]: info: CoreCommandRouter::volumioPushState Dec 16 11:01:36 rivoplus volumio[3283]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 16 11:01:36 rivoplus volumio[3283]: Error: Failed to convert napi value String into rust type `f64` on TimelineInfo.duration Dec 16 11:01:36 rivoplus volumio[3283]: at FerrumStreamingControlTechnology.updateStateOnPlayer (/data/plugins/user_interface/ferrum_streaming_control_technology/index.js:72:12) Dec 16 11:01:36 rivoplus volumio[3283]: at FerrumStreamingControlTechnology.pushState (/data/plugins/user_interface/ferrum_streaming_control_technology/index.js:183:10) Dec 16 11:01:36 rivoplus volumio[3283]: at /volumio/app/index.js:326:81 Dec 16 11:01:36 rivoplus volumio[3283]: at fastMap (/volumio/node_modules/fast.js/array/map.js:21:17) Dec 16 11:01:36 rivoplus volumio[3283]: at Function.fastMap [as map] (/volumio/node_modules/fast.js/map.js:18:12) Dec 16 11:01:36 rivoplus volumio[3283]: at CoreCommandRouter.volumioPushState (/volumio/app/index.js:324:13) Dec 16 11:01:36 rivoplus volumio[3283]: at CoreStateMachine.pushState (/volumio/app/statemachine.js:534:22) Dec 16 11:01:36 rivoplus volumio[3283]: at CoreStateMachine.stop (/volumio/app/statemachine.js:1247:12) Dec 16 11:01:36 rivoplus volumio[3283]: at CoreStateMachine.clearQueue (/volumio/app/statemachine.js:277:8) Dec 16 11:01:36 rivoplus volumio[3283]: at CoreCommandRouter.replaceAndPlay (/volumio/app/index.js:494:21) Dec 16 11:01:36 rivoplus volumio[3283]: at CoreCommandRouter.playItemsList (/volumio/app/index.js:484:17) Dec 16 11:01:36 rivoplus volumio[3283]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:142:33) Dec 16 11:01:36 rivoplus volumio[3283]: at Socket.emit (events.js:400:28) Dec 16 11:01:36 rivoplus volumio[3283]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Dec 16 11:01:36 rivoplus volumio[3283]: at processTicksAndRejections (internal/process/task_queues.js:77:11) { Dec 16 11:01:36 rivoplus volumio[3283]: code: 'NumberExpected' Dec 16 11:01:36 rivoplus volumio[3283]: } Dec 16 11:01:36 rivoplus volumio[3283]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 16 11:01:36 rivoplus nmbd[3050]: [2025/12/16 11:01:36.938692, 0] ../source3/libsmb/nmblib.c:917(send_udp) Dec 16 11:01:36 rivoplus nmbd[3050]: Packet send failed to 192.168.178.255(138) ERRNO=Network is unreachable Dec 16 11:01:37 rivoplus sudo[4829]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-16 11:00 Dec 16 11:01:37 rivoplus sudo[4829]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="cc50ad4a2058d01de272214eb33827883bd2b7d8" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="502750e5d54ecf269d25125b622bfabc030fb553" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivoplus" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 12 Nov 2025 07:44:55 PM CET" VOLUMIO_VERSION="3.874" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo Plus" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo Plus" VOLUMIO_HASH="af8df74278231e4f9fb9f04db56c320f"