-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Sun 2025-05-18 04:35:38 CEST. -- May 18 04:34:56 rivo systemd-timedated[3181]: Changed local time to Sun May 18 04:34:56 2025 May 18 04:34:56 rivo sudo[3179]: pam_unix(sudo:session): session closed for user root May 18 04:34:56 rivo volumio-time-update[2820]: volumio-time-update-util: System time updated successfully. May 18 04:34:56 rivo systemd[1]: Started Volumio Time Update Utility. May 18 04:34:56 rivo kernel: meson_uart ffd24000.serial: ttyS1 use xtal(24M) 24000000 change 115200 to 2000000 May 18 04:34:56 rivo systemd[1]: Starting Bluetooth service... May 18 04:34:56 rivo systemd[1]: Starting Load/Save RF Kill Switch Status... May 18 04:34:56 rivo bluetoothd[3184]: Bluetooth daemon 5.50 May 18 04:34:56 rivo systemd[1]: Started Load/Save RF Kill Switch Status. May 18 04:34:56 rivo bluetoothd[3184]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf May 18 04:34:56 rivo systemd[1]: Started Bluetooth service. May 18 04:34:56 rivo systemd[1]: Reached target Bluetooth. May 18 04:34:56 rivo bluetoothd[3184]: Starting SDP server May 18 04:34:56 rivo bluetoothd[3184]: Excluding (cli) sap May 18 04:34:56 rivo dbus-daemon[2799]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.6' (uid=0 pid=3184 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap " label="kernel") May 18 04:34:56 rivo bluetoothd[3184]: Bluetooth management interface 1.14 initialized May 18 04:34:56 rivo systemd[1]: Starting Hostname Service... May 18 04:34:56 rivo kernel: IPVS: Creating netns size=1928 id=2 May 18 04:34:56 rivo dbus-daemon[2799]: [system] Successfully activated service 'org.freedesktop.hostname1' May 18 04:34:56 rivo systemd[1]: Started Hostname Service. May 18 04:34:56 rivo wireless.js[2825]: trying... May 18 04:34:56 rivo sudo[3192]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 18 04:34:56 rivo sudo[3192]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 04:34:56 rivo sudo[3192]: pam_unix(sudo:session): session closed for user root May 18 04:34:57 rivo kernel: usb 1-1.1: device descriptor read/64, error -110 May 18 04:34:57 rivo kernel: usb 1-1.1: Device no response May 18 04:34:57 rivo wireless.js[2825]: trying... May 18 04:34:57 rivo sudo[3195]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 18 04:34:57 rivo sudo[3195]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 04:34:57 rivo sudo[3195]: pam_unix(sudo:session): session closed for user root May 18 04:34:58 rivo wireless.js[2825]: trying... May 18 04:34:58 rivo sudo[3198]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 18 04:34:58 rivo sudo[3198]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 04:34:58 rivo sudo[3198]: pam_unix(sudo:session): session closed for user root May 18 04:34:58 rivo dhcpcd[3113]: wlan0: leased 192.168.2.179 for infinity May 18 04:34:58 rivo avahi-daemon[2803]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.2.179. May 18 04:34:58 rivo dhcpcd[3113]: wlan0: adding route to 192.168.2.0/24 May 18 04:34:58 rivo dhcpcd[3113]: wlan0: adding default route via 192.168.2.1 May 18 04:34:58 rivo avahi-daemon[2803]: New relevant interface wlan0.IPv4 for mDNS. May 18 04:34:58 rivo avahi-daemon[2803]: Registering new address record for 192.168.2.179 on wlan0.IPv4. May 18 04:34:59 rivo volumio-remote-updater[2818]: [2025-05-18 04:34:59] [info] asio async_connect error: system:111 (Connection refused) May 18 04:34:59 rivo volumio-remote-updater[2818]: [2025-05-18 04:34:59] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) May 18 04:34:59 rivo volumio-remote-updater[2818]: [2025-05-18 04:34:59] [error] handle_connect error: Underlying Transport Error May 18 04:34:59 rivo wireless.js[2825]: trying... May 18 04:34:59 rivo sudo[3232]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 18 04:34:59 rivo sudo[3232]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 04:34:59 rivo sudo[3232]: pam_unix(sudo:session): session closed for user root May 18 04:34:59 rivo wireless.js[2825]: Connected to: ----EasyBox-0B4610_EXT May 18 04:34:59 rivo wireless.js[2825]: ---- May 18 04:34:59 rivo sudo[3235]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 18 04:34:59 rivo sudo[3235]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 04:34:59 rivo sudo[3235]: pam_unix(sudo:session): session closed for user root May 18 04:34:59 rivo wireless.js[2825]: ... joined AP, wlan0 IPv4 is 192.168.2.179, ipV6 is undefined May 18 04:34:59 rivo wireless.js[2825]: It's done! AP May 18 04:34:59 rivo systemd[1]: Started Wireless Services. May 18 04:34:59 rivo systemd[1]: Started Volumio Backend Module. May 18 04:34:59 rivo systemd[1]: Started Start Volumio Kiosk. May 18 04:34:59 rivo systemd[1]: Started Volumio Cpu Tweaker. May 18 04:34:59 rivo startkiosk.sh[3242]: Starting Volumio Kiosk with arguments for cursor display May 18 04:34:59 rivo volumio-cpu-tweak[3243]: Setting RT Priority for mpd May 18 04:34:59 rivo volumio-cpu-tweak[3243]: chrt: cannot obtain the list of tasks: No such file or directory May 18 04:34:59 rivo volumio-cpu-tweak[3243]: Setting MPD Affinity May 18 04:34:59 rivo volumio-cpu-tweak[3243]: pid 3's current affinity mask: 1 May 18 04:35:00 rivo volumio-cpu-tweak[3243]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input May 18 04:35:00 rivo volumio-cpu-tweak[3243]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance May 18 04:35:00 rivo systemd[1]: volumio_cpu_tweak.service: Succeeded. May 18 04:35:00 rivo startkiosk.sh[3242]: X.Org X Server 1.20.4 May 18 04:35:00 rivo startkiosk.sh[3242]: X Protocol Version 11, Revision 0 May 18 04:35:00 rivo startkiosk.sh[3242]: Build Operating System: Linux 4.19.0-18-armmp-lpae armv7l Debian May 18 04:35:00 rivo startkiosk.sh[3242]: Current Operating System: Linux rivo 4.9.241 #2 SMP PREEMPT Sat Dec 14 12:27:06 CET 2024 aarch64 May 18 04:35:00 rivo startkiosk.sh[3242]: Kernel command line: imgpart=UUID=4d529f1a-ec2d-41b4-983f-cd6add012c49 imgfile=/volumio_current.sqsh bootpart=UUID=0055-6DBD datapart=UUID=65621ea7-49f0-4a78-823b-f13c068afb28 uuidconfig=env.system.txt hwdevice=VIMX console=tty0 console=ttyS0,115200n8 no_console_suspend consoleblank=0 logo=osd0,loaded,0x3d800000,panel vout=panel,enable hdmimode=1080p60hz fsck.repair=yes net.ifnames=0 ddr_size= wol_enable= mac=02:00:00:08:20:01 fan=auto hwver=Unknow coherent_pool=2M quiet loglevel=0 splash plymouth.ignore-serial-consoles initramfs.clear splash bootdelay=7 May 18 04:35:00 rivo startkiosk.sh[3242]: Build Date: 18 December 2021 09:05:36AM May 18 04:35:00 rivo startkiosk.sh[3242]: xorg-server 2:1.20.4-1+deb10u4 (https://www.debian.org/support) May 18 04:35:00 rivo startkiosk.sh[3242]: Current version of pixman: 0.36.0 May 18 04:35:00 rivo startkiosk.sh[3242]: Before reporting problems, check http://wiki.x.org May 18 04:35:00 rivo startkiosk.sh[3242]: to make sure that you have the latest version. May 18 04:35:00 rivo startkiosk.sh[3242]: Markers: (--) probed, (**) from config file, (==) default setting, May 18 04:35:00 rivo startkiosk.sh[3242]: (++) from command line, (!!) notice, (II) informational, May 18 04:35:00 rivo startkiosk.sh[3242]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. May 18 04:35:00 rivo startkiosk.sh[3242]: (==) Log file: "/var/log/Xorg.0.log", Time: Sun May 18 04:35:00 2025 May 18 04:35:00 rivo startkiosk.sh[3242]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" May 18 04:35:00 rivo kernel: fb: osd[0] canvas.idx =0x40 May 18 04:35:00 rivo kernel: fb: osd[0] canvas.addr=0x70000000 May 18 04:35:00 rivo kernel: fb: osd[0] canvas.width=7680 May 18 04:35:00 rivo kernel: fb: osd[0] canvas.height=1080 May 18 04:35:00 rivo kernel: fb: osd[0] frame.width=1920 May 18 04:35:00 rivo kernel: fb: osd[0] frame.height=1080 May 18 04:35:00 rivo kernel: fb: osd[0] out_addr_id =0x1 May 18 04:35:00 rivo kernel: fb: osd[0] enable: 0 (Xorg) May 18 04:35:01 rivo kernel: fb: osd[0] enable: 1 (Xorg) May 18 04:35:01 rivo systemd[1]: systemd-rfkill.service: Succeeded. May 18 04:35:01 rivo kernel: fb: osd[0] enable: 1 (Xorg) May 18 04:35:02 rivo volumio[3241]: info: ------------------------------------------- May 18 04:35:02 rivo volumio[3241]: info: ----- Volumio3 ---- May 18 04:35:02 rivo volumio[3241]: info: ------------------------------------------- May 18 04:35:02 rivo volumio[3241]: info: ----- System startup ---- May 18 04:35:02 rivo volumio[3241]: info: ------------------------------------------- May 18 04:35:03 rivo volumio[3241]: info: MYVOLUMIO Environment detected May 18 04:35:03 rivo volumio[3241]: info: Plugin folders cleanup May 18 04:35:03 rivo volumio[3241]: info: Scanning into folder /volumio/app/plugins/ May 18 04:35:03 rivo volumio[3241]: info: Scanning category audio_interface May 18 04:35:03 rivo volumio[3241]: info: Scanning category miscellanea May 18 04:35:03 rivo volumio[3241]: info: Scanning category music_service May 18 04:35:03 rivo volumio[3241]: info: Scanning category plugins.json May 18 04:35:03 rivo volumio[3241]: info: Scanning category system_controller May 18 04:35:03 rivo volumio[3241]: info: Scanning category user_interface May 18 04:35:03 rivo volumio[3241]: info: Scanning into folder /data/plugins/ May 18 04:35:03 rivo volumio[3241]: info: Plugin folders cleanup completed May 18 04:35:03 rivo volumio[3241]: info: ------------------------------------------- May 18 04:35:03 rivo volumio[3241]: info: ----- Core plugins startup ---- May 18 04:35:03 rivo volumio[3241]: info: ------------------------------------------- May 18 04:35:03 rivo volumio[3241]: info: Loading plugins from folder /volumio/app/plugins/ May 18 04:35:03 rivo volumio[3241]: info: Adding plugin bluetooth to MyMusic Plugins May 18 04:35:03 rivo volumio[3241]: info: Adding plugin multiroom to MyMusic Plugins May 18 04:35:03 rivo volumio[3241]: info: Adding plugin upnp to MyMusic Plugins May 18 04:35:03 rivo volumio[3241]: info: Adding plugin metavolumio to MyMusic Plugins May 18 04:35:03 rivo volumio[3241]: info: Adding plugin airplay_emulation to MyMusic Plugins May 18 04:35:03 rivo volumio[3241]: info: Adding plugin cd_controller to MyMusic Plugins May 18 04:35:03 rivo volumio[3241]: info: Adding plugin qobuzconnect to MyMusic Plugins May 18 04:35:03 rivo volumio[3241]: info: Adding plugin raat to MyMusic Plugins May 18 04:35:03 rivo volumio[3241]: info: Adding plugin smart_inputs to MyMusic Plugins May 18 04:35:03 rivo volumio[3241]: info: Adding plugin tidalconnect to MyMusic Plugins May 18 04:35:03 rivo volumio[3241]: info: Adding plugin upnp_browser to MyMusic Plugins May 18 04:35:03 rivo volumio[3241]: info: Loading plugins from folder /data/plugins/ May 18 04:35:03 rivo volumio[3241]: info: Loading plugin "system"... May 18 04:35:03 rivo volumio[3241]: info: Loading plugin "appearance"... May 18 04:35:04 rivo volumio[3241]: info: Loading plugin "network"... May 18 04:35:04 rivo volumio[3241]: info: Refreshing Cached IP Addresses May 18 04:35:04 rivo sudo[3350]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 18 04:35:04 rivo sudo[3350]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 04:35:04 rivo sudo[3352]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 18 04:35:04 rivo sudo[3352]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 04:35:04 rivo sudo[3350]: pam_unix(sudo:session): session closed for user root May 18 04:35:04 rivo volumio[3241]: info: Loading plugin "services"... May 18 04:35:04 rivo sudo[3352]: pam_unix(sudo:session): session closed for user root May 18 04:35:04 rivo volumio[3241]: info: Loading plugin "alsa_controller"... May 18 04:35:04 rivo sudo[3363]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 18 04:35:04 rivo sudo[3363]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 04:35:04 rivo kernel: wl_escan_set_scan: ioctl_ver=2 May 18 04:35:04 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f46a000 drv_status 0x0 e_type 69 e_states 8 May 18 04:35:04 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f46a000 drv_status 0x0 e_type 69 e_states 8 May 18 04:35:04 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f46a000 drv_status 0x0 e_type 69 e_states 8 May 18 04:35:04 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f46a000 drv_status 0x0 e_type 69 e_states 8 May 18 04:35:04 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f46a000 drv_status 0x0 e_type 69 e_states 8 May 18 04:35:04 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f46a000 drv_status 0x0 e_type 69 e_states 8 May 18 04:35:04 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f46a000 drv_status 0x0 e_type 69 e_states 8 May 18 04:35:04 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f46a000 drv_status 0x0 e_type 69 e_states 8 May 18 04:35:04 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f46a000 drv_status 0x0 e_type 69 e_states 8 May 18 04:35:04 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f46a000 drv_status 0x0 e_type 69 e_states 8 May 18 04:35:04 rivo volumio[3241]: warn: Unable to locate the audio output device Combo384 Amanero. Please configure a valid output device. May 18 04:35:04 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f46a000 drv_status 0x0 e_type 69 e_states 8 May 18 04:35:04 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f46a000 drv_status 0x0 e_type 69 e_states 8 May 18 04:35:04 rivo volumio[3241]: Invalid card number. May 18 04:35:04 rivo volumio[3241]: info: Hardware Mixer selected but no Hardware mixer available, detecting default mixer May 18 04:35:04 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f46a000 drv_status 0x0 e_type 69 e_states 8 May 18 04:35:04 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f46a000 drv_status 0x0 e_type 69 e_states 8 May 18 04:35:04 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 18 04:35:04 rivo volumio[3241]: Invalid card number. May 18 04:35:04 rivo volumio[3241]: info: CoreCommandRouter::volumioUpdateVolumeSettings May 18 04:35:04 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 18 04:35:04 rivo volumio[3241]: info: Loading plugin "wizard"... May 18 04:35:04 rivo volumio[3241]: info: Loading plugin "networkfs"... May 18 04:35:04 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f46a000 drv_status 0x0 e_type 69 e_states 8 May 18 04:35:04 rivo volumio[3241]: info: Starting Udev Watcher for removable devices May 18 04:35:04 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f46a000 drv_status 0x0 e_type 69 e_states 8 May 18 04:35:04 rivo volumio[3241]: info: Ignoring mount for partition: BOOT May 18 04:35:04 rivo volumio[3241]: info: Ignoring mount for partition: volumio May 18 04:35:04 rivo volumio[3241]: info: Ignoring mount for partition: volumio_data May 18 04:35:04 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 18 04:35:04 rivo volumio[3241]: info: Loading plugin "volumio_command_line_client"... May 18 04:35:04 rivo volumio[3241]: info: Loading plugin "upnp"... May 18 04:35:04 rivo volumio[3241]: info: [1747535704976] Starting Upmpd Daemon May 18 04:35:04 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 18 04:35:04 rivo volumio[3241]: info: Loading plugin "my_music"... May 18 04:35:04 rivo volumio[3241]: info: Loading plugin "mpd"... May 18 04:35:05 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f46a000 drv_status 0x0 e_type 69 e_states 8 May 18 04:35:05 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f46a000 drv_status 0x0 e_type 69 e_states 8 May 18 04:35:05 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f46a000 drv_status 0x0 e_type 69 e_states 8 May 18 04:35:05 rivo volumio[3241]: info: Loading plugin "upnp_browser"... May 18 04:35:05 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f46a000 drv_status 0x0 e_type 69 e_states 8 May 18 04:35:05 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f46a000 drv_status 0x0 e_type 69 e_states 8 May 18 04:35:05 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f46a000 drv_status 0x0 e_type 69 e_states 8 May 18 04:35:05 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f46a000 drv_status 0x0 e_type 69 e_states 8 May 18 04:35:05 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f46a000 drv_status 0x0 e_type 69 e_states 8 May 18 04:35:05 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f46a000 drv_status 0x0 e_type 69 e_states 8 May 18 04:35:05 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f46a000 drv_status 0x0 e_type 69 e_states 8 May 18 04:35:05 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f46a000 drv_status 0x0 e_type 69 e_states 8 May 18 04:35:05 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f46a000 drv_status 0x0 e_type 69 e_states 8 May 18 04:35:05 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f46a000 drv_status 0x0 e_type 69 e_states 8 May 18 04:35:05 rivo volumio[3241]: info: Loading plugin "bluetooth"... May 18 04:35:05 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f46a000 drv_status 0x0 e_type 69 e_states 8 May 18 04:35:05 rivo volumio[3241]: info: [1747535705912] Starting BluetoothController May 18 04:35:06 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f46a000 drv_status 0x0 e_type 69 e_states 8 May 18 04:35:06 rivo volumio[3241]: info: Loading plugin "alarm-clock"... May 18 04:35:06 rivo volumio[3241]: info: Loading plugin "manifestui"... May 18 04:35:06 rivo volumio[3241]: info: Loading plugin "metavolumio"... May 18 04:35:06 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f46a000 drv_status 0x0 e_type 69 e_states 8 May 18 04:35:06 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f46a000 drv_status 0x0 e_type 69 e_states 8 May 18 04:35:06 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f46a000 drv_status 0x0 e_type 69 e_states 8 May 18 04:35:07 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f46a000 drv_status 0x0 e_type 69 e_states 8 May 18 04:35:07 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f46a000 drv_status 0x0 e_type 69 e_states 8 May 18 04:35:07 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f46a000 drv_status 0x0 e_type 69 e_states 8 May 18 04:35:08 rivo volumio[3241]: info: Loading plugin "airplay_emulation"... May 18 04:35:08 rivo volumio[3241]: info: Starting Shairport Sync May 18 04:35:08 rivo volumio[3241]: info: Loading plugin "cd_controller"... May 18 04:35:08 rivo volumio[3241]: info: Loading plugin "last_100"... May 18 04:35:08 rivo volumio[3241]: info: Loading plugin "qobuzconnect"... May 18 04:35:08 rivo volumio[3241]: info: Loading plugin "raat"... May 18 04:35:08 rivo volumio[3241]: info: RAAT Plugin loaded May 18 04:35:08 rivo volumio[3241]: info: Adding restartRAATSocket REST API Endpoint May 18 04:35:08 rivo volumio[3241]: info: Adding restartRAATSocket REST Endpoint for plugin: music_service/raat May 18 04:35:08 rivo volumio[3241]: info: Loading plugin "streaming_services"... May 18 04:35:08 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f46a000 drv_status 0x0 e_type 69 e_states 8 May 18 04:35:09 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f46a000 drv_status 0x0 e_type 69 e_states 0 May 18 04:35:09 rivo sudo[3363]: pam_unix(sudo:session): session closed for user root May 18 04:35:09 rivo volumio-remote-updater[2818]: [2025-05-18 04:35:09] [connect] Successful connection May 18 04:35:10 rivo volumio[3241]: info: Starting Streaming Service Transparent Proxy May 18 04:35:10 rivo volumio[3241]: info: Loading plugin "tidalconnect"... May 18 04:35:10 rivo volumio[3241]: info: Loading plugin "webradio"... May 18 04:35:10 rivo volumio[3241]: info: Loading plugin "i2s_dacs"... May 18 04:35:10 rivo volumio[3241]: info: I2S DAC not set, start Auto-detection May 18 04:35:10 rivo volumio[3241]: info: Loading plugin "volumiodiscovery"... May 18 04:35:10 rivo volumio[3241]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 18 04:35:10 rivo volumio[3241]: *** WARNING *** Please fix your application to use the native API of Avahi! May 18 04:35:10 rivo volumio[3241]: *** WARNING *** For more information see May 18 04:35:10 rivo volumio[3241]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 18 04:35:10 rivo volumio[3241]: *** WARNING *** Please fix your application to use the native API of Avahi! May 18 04:35:10 rivo volumio[3241]: *** WARNING *** For more information see May 18 04:35:10 rivo node[3241]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 18 04:35:10 rivo node[3241]: *** WARNING *** Please fix your application to use the native API of Avahi! May 18 04:35:10 rivo node[3241]: *** WARNING *** For more information see May 18 04:35:10 rivo node[3241]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 18 04:35:10 rivo node[3241]: *** WARNING *** Please fix your application to use the native API of Avahi! May 18 04:35:10 rivo node[3241]: *** WARNING *** For more information see May 18 04:35:10 rivo volumio[3241]: info: Applying required configuration parameters for plugin volumiodiscovery May 18 04:35:10 rivo volumio[3241]: info: Discovery: Started advertising with name: Rivo May 18 04:35:10 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 18 04:35:10 rivo volumio[3241]: info: Loading plugin "multiroom"... May 18 04:35:11 rivo volumio[3241]: STREAMING PROXY: Starting server on port 3245 May 18 04:35:11 rivo volumio[3241]: Node JS runtime: 14 May 18 04:35:12 rivo volumio[3241]: info: Applying required configuration parameters for plugin multiroom May 18 04:35:13 rivo sudo[3556]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom May 18 04:35:13 rivo sudo[3556]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 04:35:13 rivo sudo[3556]: pam_unix(sudo:session): session closed for user root May 18 04:35:13 rivo volumio[3241]: info: MRS: MultiRoom plugin initialized May 18 04:35:13 rivo volumio[3241]: info: MRS: STOPPING SNAPCLIENT May 18 04:35:13 rivo volumio[3241]: info: MRS: Snap server stop May 18 04:35:13 rivo volumio[3241]: info: MRS: STOPPING volumioStreaming May 18 04:35:13 rivo sudo[3572]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient May 18 04:35:13 rivo sudo[3572]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 04:35:13 rivo sudo[3574]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver May 18 04:35:13 rivo sudo[3574]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 04:35:13 rivo volumio[3241]: info: Loading plugin "outputs"... May 18 04:35:13 rivo volumio[3241]: info: Loading plugin "albumart"... May 18 04:35:13 rivo sudo[3579]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming May 18 04:35:13 rivo sudo[3579]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 04:35:13 rivo sudo[3572]: pam_unix(sudo:session): session closed for user root May 18 04:35:13 rivo sudo[3580]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* May 18 04:35:13 rivo sudo[3580]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 04:35:13 rivo sudo[3580]: pam_unix(sudo:session): session closed for user root May 18 04:35:13 rivo volumio[3241]: info: Plugin example_plugin is not enabled May 18 04:35:13 rivo volumio[3241]: info: Loading plugin "hi_res_audio"... May 18 04:35:13 rivo sudo[3574]: pam_unix(sudo:session): session closed for user root May 18 04:35:13 rivo sudo[3579]: pam_unix(sudo:session): session closed for user root May 18 04:35:13 rivo kernel: usb 1-1.1: device descriptor read/64, error -110 May 18 04:35:13 rivo kernel: usb 1-1.1: Device no response May 18 04:35:13 rivo kernel: usb 1-1-port1: attempt power cycle May 18 04:35:13 rivo volumio[3241]: Forking 3 albumart workers May 18 04:35:13 rivo kernel: usb 1-1.1: new high-speed USB device number 5 using xhci-hcd May 18 04:35:14 rivo systemd[1]: systemd-fsckd.service: Succeeded. May 18 04:35:14 rivo volumio[3241]: Starting albumart workers May 18 04:35:14 rivo volumio[3241]: Starting albumart workers May 18 04:35:14 rivo volumio[3241]: Starting albumart workers May 18 04:35:15 rivo volumio[3241]: info: Applying required configuration parameters for plugin hi_res_audio May 18 04:35:15 rivo volumio[3241]: info: Loading plugin "inputs"... May 18 04:35:16 rivo volumio[3241]: info: Loading plugin "qobuz"... May 18 04:35:17 rivo volumio[3241]: info: Loading plugin "smart_inputs"... May 18 04:35:17 rivo volumio[3241]: info: Loading plugin "tidal"... May 18 04:35:19 rivo kernel: xhci-hcd xhci-hcd.0.auto: Timeout while waiting for setup device command May 18 04:35:19 rivo volumio[3241]: info: Loading plugin "rivocontrol"... May 18 04:35:19 rivo volumio[3241]: info: Adding this device properties May 18 04:35:19 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: system , setThisDeviceVolumioProperties May 18 04:35:19 rivo volumio[3241]: info: Setting Additional Device Volumio Properties: [object Object] May 18 04:35:19 rivo volumio[3241]: info: Loading plugin "updater_comm"... May 18 04:35:19 rivo volumio[3241]: info: Loading plugin "cec_controller"... May 18 04:35:20 rivo volumio[3241]: info: Applying required configuration parameters for plugin cec_controller May 18 04:35:20 rivo volumio[3241]: info: Plugin mpdemulation is not enabled May 18 04:35:20 rivo volumio[3241]: info: Loading plugin "rest_api"... May 18 04:35:20 rivo volumio[3241]: info: Loading plugin "websocket"... May 18 04:35:20 rivo volumio[3241]: info: Starting Socket.io Server version 2.3.0 May 18 04:35:20 rivo volumio[3241]: info: Loading i18n strings for locale de May 18 04:35:20 rivo volumio[3241]: Updating browse sources language May 18 04:35:20 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 18 04:35:20 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 18 04:35:20 rivo volumio[3241]: info: CoreCommandRouter::initPlayerControls May 18 04:35:20 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 04:35:20 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 04:35:20 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 04:35:20 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 04:35:20 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 04:35:20 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 04:35:20 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 04:35:20 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 04:35:20 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 18 04:35:20 rivo volumio[3241]: Express server listening on port 3000 May 18 04:35:20 rivo volumio[3241]: [Metrics] WebUI: 18s 961.15ms May 18 04:35:20 rivo volumio[3241]: info: CoreStateMachine::resetVolumioState May 18 04:35:20 rivo volumio[3241]: info: CoreStateMachine::getcurrentVolume May 18 04:35:20 rivo volumio[3241]: info: CoreCommandRouter::volumioRetrievevolume May 18 04:35:20 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 18 04:35:20 rivo volumio[3241]: info: CoreCommandRouter::volumioRetrieveVolumeLevels May 18 04:35:20 rivo volumio[3241]: info: CoreStateMachine::getcurrentVolume May 18 04:35:20 rivo volumio[3241]: info: CoreCommandRouter::volumioRetrievevolume May 18 04:35:20 rivo sudo[3661]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 18 04:35:20 rivo sudo[3661]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 04:35:20 rivo sudo[3661]: pam_unix(sudo:session): session closed for user root May 18 04:35:20 rivo volumio[3241]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' May 18 04:35:20 rivo volumio[3241]: info: Completed loading Core Plugins May 18 04:35:20 rivo volumio[3241]: info: Preparing to generate the ALSA configuration file May 18 04:35:20 rivo sudo[3665]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 18 04:35:20 rivo sudo[3665]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 04:35:20 rivo sudo[3665]: pam_unix(sudo:session): session closed for user root May 18 04:35:20 rivo volumio[3241]: info: Volumio Network Manager: Network status updated: 3 May 18 04:35:20 rivo volumio[3241]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. May 18 04:35:20 rivo volumio[3241]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. May 18 04:35:20 rivo volumio[3241]: info: MRS: Removed streaming files May 18 04:35:20 rivo volumio[3241]: info: MRS: volumioStreaming STOPPED May 18 04:35:20 rivo volumio[3241]: info: MRS: SNAPSERVER STOPPED May 18 04:35:20 rivo volumio[3241]: info: MRS: SNAPCLIENT STOPPED May 18 04:35:20 rivo volumio[3241]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision May 18 04:35:20 rivo volumio[3241]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf May 18 04:35:20 rivo volumio[3241]: info: Reading ALSA contributions from plugins. May 18 04:35:20 rivo volumio[3241]: info: VolumeController:: Volume=undefined Mute =false May 18 04:35:20 rivo volumio[3241]: info: CoreStateMachine::pushState May 18 04:35:20 rivo volumio[3241]: info: CorePlayQueue::getTrack 0 May 18 04:35:20 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 04:35:20 rivo volumio[3241]: info: CoreCommandRouter::volumioPushState May 18 04:35:20 rivo volumio[3241]: info: CoreStateMachine::updateTrackBlock May 18 04:35:20 rivo volumio[3241]: info: CorePlayQueue::getTrackBlock May 18 04:35:20 rivo volumio[3241]: info: CoreCommandRouter::volumioRetrievevolume May 18 04:35:20 rivo volumio[3241]: info: VolumeController:: Volume=undefined Mute =false May 18 04:35:20 rivo volumio[3241]: info: CoreStateMachine::pushState May 18 04:35:20 rivo volumio[3241]: info: CorePlayQueue::getTrack 0 May 18 04:35:20 rivo volumio[3241]: info: CoreCommandRouter::volumioPushState May 18 04:35:20 rivo volumio[3241]: info: CoreStateMachine::updateTrackBlock May 18 04:35:20 rivo volumio[3241]: info: CorePlayQueue::getTrackBlock May 18 04:35:20 rivo volumio[3241]: info: CoreCommandRouter::volumioRetrievevolume May 18 04:35:20 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 18 04:35:20 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 18 04:35:20 rivo volumio[3241]: info: Discovery: Getting this device information May 18 04:35:20 rivo volumio[3241]: info: CoreCommandRouter::volumioGetState May 18 04:35:20 rivo volumio[3241]: info: CorePlayQueue::getTrack 0 May 18 04:35:20 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 18 04:35:20 rivo volumio[3241]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. May 18 04:35:20 rivo volumio[3241]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. May 18 04:35:21 rivo volumio[3241]: info: Reloading queue from file May 18 04:35:21 rivo volumio[3241]: info: VolumeController:: Volume=undefined Mute =false May 18 04:35:21 rivo volumio[3241]: info: CoreStateMachine::pushState May 18 04:35:21 rivo volumio[3241]: info: CorePlayQueue::getTrack 0 May 18 04:35:21 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 04:35:21 rivo volumio[3241]: info: CoreCommandRouter::volumioPushState May 18 04:35:21 rivo volumio[3241]: info: CoreStateMachine::setRepeat null single undefined May 18 04:35:21 rivo volumio[3241]: info: CoreStateMachine::pushState May 18 04:35:21 rivo volumio[3241]: info: CorePlayQueue::getTrack 0 May 18 04:35:21 rivo volumio[3241]: info: CoreCommandRouter::volumioPushState May 18 04:35:21 rivo volumio[3241]: info: CoreStateMachine::setRandom null May 18 04:35:21 rivo volumio[3241]: info: CoreStateMachine::pushState May 18 04:35:21 rivo volumio[3241]: info: CorePlayQueue::getTrack 0 May 18 04:35:21 rivo volumio[3241]: info: CoreCommandRouter::volumioPushState May 18 04:35:21 rivo volumio[3241]: info: VolumeController:: Volume=undefined Mute =false May 18 04:35:21 rivo volumio[3241]: info: CoreStateMachine::pushState May 18 04:35:21 rivo volumio[3241]: info: CorePlayQueue::getTrack 0 May 18 04:35:21 rivo volumio[3241]: info: CoreCommandRouter::volumioPushState May 18 04:35:21 rivo volumio[3241]: info: Discovery: adding b34a40c3-de2c-433e-82f2-b63f973eaa47 May 18 04:35:21 rivo volumio[3241]: info: Discovery: Found device Rivo May 18 04:35:21 rivo volumio[3241]: info: CoreCommandRouter::volumioGetState May 18 04:35:21 rivo volumio[3241]: info: CorePlayQueue::getTrack 0 May 18 04:35:21 rivo volumio[3241]: info: MRS: Pushing multiroomSync output for this device May 18 04:35:21 rivo volumio[3241]: info: MRS: Pushing multiroomSync output May 18 04:35:21 rivo volumio[3241]: info: Adding audio output: May 18 04:35:21 rivo sudo[3684]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 18 04:35:21 rivo sudo[3684]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 04:35:21 rivo volumio[3241]: info: Adding audio output: May 18 04:35:21 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 18 04:35:21 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 18 04:35:21 rivo volumio[3241]: info: Discovery: Getting this device information May 18 04:35:21 rivo volumio[3241]: info: CoreCommandRouter::volumioGetState May 18 04:35:21 rivo volumio[3241]: info: CorePlayQueue::getTrack 0 May 18 04:35:21 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 18 04:35:21 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 18 04:35:21 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 18 04:35:21 rivo volumio[3241]: info: Discovery: Getting this device information May 18 04:35:21 rivo volumio[3241]: info: CoreCommandRouter::volumioGetState May 18 04:35:21 rivo volumio[3241]: info: CorePlayQueue::getTrack 0 May 18 04:35:21 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 18 04:35:21 rivo volumio[3241]: info: Asound.conf file unchanged, so no further update is needed May 18 04:35:21 rivo volumio[3241]: info: Output device has changed, restarting MPD May 18 04:35:21 rivo systemd[1]: Started UPnP Renderer front-end to MPD. May 18 04:35:21 rivo sudo[3684]: pam_unix(sudo:session): session closed for user root May 18 04:35:21 rivo volumio[3241]: info: Output device has changed, restarting Shairport Sync May 18 04:35:21 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 04:35:21 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 18 04:35:21 rivo sudo[3688]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 18 04:35:21 rivo sudo[3688]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 04:35:21 rivo sudo[3688]: pam_unix(sudo:session): session closed for user root May 18 04:35:21 rivo sudo[3692]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 18 04:35:21 rivo sudo[3692]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 04:35:21 rivo volumio[3241]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 18 04:35:21 rivo volumio[3241]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 18 04:35:21 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 04:35:21 rivo volumio[3241]: info: ___________ START PLUGINS ___________ May 18 04:35:21 rivo volumio[3241]: info: ControllerMpd::onStart: Initializing MPD May 18 04:35:21 rivo volumio[3241]: info: Creating MPD Configuration file May 18 04:35:21 rivo sudo[3699]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 18 04:35:21 rivo sudo[3699]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 04:35:21 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 18 04:35:21 rivo sudo[3699]: pam_unix(sudo:session): session closed for user root May 18 04:35:21 rivo volumio[3241]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 18 04:35:21 rivo volumio[3241]: info: [1747535721442] CoreMusicLibrary::Adding element Medienserver May 18 04:35:21 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 18 04:35:21 rivo sudo[3701]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 18 04:35:21 rivo sudo[3701]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 04:35:21 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 18 04:35:21 rivo volumio[3241]: info: Adding METAVOLUMIO REST API Endpoints May 18 04:35:21 rivo volumio[3241]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio May 18 04:35:21 rivo volumio[3241]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio May 18 04:35:21 rivo volumio[3241]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio May 18 04:35:21 rivo volumio[3241]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio May 18 04:35:21 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 04:35:21 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 18 04:35:21 rivo sudo[3705]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name Rivo May 18 04:35:21 rivo sudo[3705]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 04:35:21 rivo sudo[3705]: pam_unix(sudo:session): session closed for user root May 18 04:35:21 rivo volumio[3241]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 18 04:35:21 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 04:35:21 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 18 04:35:21 rivo volumio[3241]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 18 04:35:21 rivo volumio[3241]: info: Preparing CD Folders May 18 04:35:21 rivo volumio[3241]: info: Adding CD REST API Endpoints May 18 04:35:21 rivo volumio[3241]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller May 18 04:35:21 rivo volumio[3241]: info: Starting UDEV Watcher for CD May 18 04:35:21 rivo volumio[3241]: info: Detecting CD presence with UDEV May 18 04:35:21 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices May 18 04:35:21 rivo volumio[3241]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 18 04:35:21 rivo volumio[3241]: info: [1747535721639] CoreMusicLibrary::Adding element Last_100 May 18 04:35:21 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 18 04:35:21 rivo volumio[3241]: info: Adding qc_getconfig REST Endpoint for plugin: music_service/qobuzconnect May 18 04:35:21 rivo volumio[3241]: info: QobuzConnect: Starting Qobuz Connect socket and service May 18 04:35:21 rivo volumio[3241]: info: Starting RAAT Plugin May 18 04:35:21 rivo sudo[3717]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service May 18 04:35:21 rivo sudo[3717]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 04:35:21 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , addAdditionalUISections May 18 04:35:21 rivo volumio[3241]: info: Additional UI Settings Added for plugin music_service/raat May 18 04:35:21 rivo volumio[3241]: info: Registering DSP Elements listener and retrieving current ones May 18 04:35:21 rivo volumio[3241]: info: Additional DSP elements updated May 18 04:35:21 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 04:35:21 rivo volumio[3241]: info: Updating RAAT Signal Path May 18 04:35:21 rivo volumio[3241]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined May 18 04:35:21 rivo volumio[3241]: info: Streaming services startup May 18 04:35:21 rivo volumio[3241]: info: Starting Streaming Daemon May 18 04:35:21 rivo sudo[3717]: pam_unix(sudo:session): session closed for user root May 18 04:35:21 rivo sudo[3722]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service May 18 04:35:21 rivo sudo[3722]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 04:35:21 rivo sudo[3725]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 18 04:35:21 rivo sudo[3725]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 04:35:21 rivo systemd[1]: Started Volumio Qobuz Connect Service. May 18 04:35:21 rivo sudo[3722]: pam_unix(sudo:session): session closed for user root May 18 04:35:21 rivo sudo[3725]: pam_unix(sudo:session): session closed for user root May 18 04:35:21 rivo volumio[3241]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 18 04:35:21 rivo volumio[3241]: info: [1747535721876] CoreMusicLibrary::Adding element Webradio May 18 04:35:21 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 18 04:35:21 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 18 04:35:21 rivo volumio[3241]: info: Initializing BBC Radios May 18 04:35:21 rivo sudo[3733]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 18 04:35:21 rivo sudo[3733]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 04:35:21 rivo sudo[3733]: pam_unix(sudo:session): session closed for user root May 18 04:35:21 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 18 04:35:21 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 18 04:35:21 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus May 18 04:35:22 rivo volumio[3241]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 18 04:35:22 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 04:35:22 rivo volumio[3241]: error: Hi Res Audio Failed Login: Missing Login Data May 18 04:35:22 rivo volumio[3241]: info: Adding HIGHRESAUDIO REST API Endpoints May 18 04:35:22 rivo volumio[3241]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio May 18 04:35:22 rivo volumio[3241]: info: Initializing Serial Communication on port /dev/ttyS3 May 18 04:35:22 rivo kernel: meson_uart ffd22000.serial: ttyS3 use xtal(24M) 24000000 change 0 to 9600 May 18 04:35:22 rivo kernel: meson_uart ffd22000.serial: ttyS3 use xtal(24M) 24000000 change 9600 to 9600 May 18 04:35:22 rivo kernel: meson_uart ffd22000.serial: ttyS3 use xtal(24M) 24000000 change 9600 to 115200 May 18 04:35:22 rivo volumio[3241]: info: Touch Event Listener Process Starting May 18 04:35:22 rivo volumio[3241]: info: Refreshing QOBUZ token May 18 04:35:22 rivo sudo[3751]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xinput --test-xi2 --root May 18 04:35:22 rivo sudo[3751]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 04:35:22 rivo volumio[3241]: info: Adding inputs REST Endpoints May 18 04:35:22 rivo volumio[3241]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs May 18 04:35:22 rivo volumio[3241]: info: Scanning Audio Inputs May 18 04:35:22 rivo volumio[3241]: info: Checking against Known Cards name May 18 04:35:22 rivo volumio[3241]: info: Checking against Known Cards name May 18 04:35:22 rivo volumio[3241]: info: Checking against Known Cards name May 18 04:35:22 rivo volumio[3241]: info: Checking against Known Cards name May 18 04:35:22 rivo volumio[3241]: info: Checking against Known Cards name May 18 04:35:22 rivo volumio[3241]: info: Adding Server instance for streaming May 18 04:35:22 rivo volumio[3241]: info: Refreshing TIDAL token May 18 04:35:22 rivo sudo[3762]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x25 0x0 May 18 04:35:22 rivo sudo[3762]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 04:35:22 rivo sudo[3762]: pam_unix(sudo:session): session closed for user root May 18 04:35:22 rivo sudo[3765]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x28 0x0 May 18 04:35:22 rivo sudo[3765]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 04:35:22 rivo sudo[3765]: pam_unix(sudo:session): session closed for user root May 18 04:35:22 rivo sudo[3768]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x29 0x1 May 18 04:35:22 rivo sudo[3768]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 04:35:22 rivo sudo[3768]: pam_unix(sudo:session): session closed for user root May 18 04:35:22 rivo sudo[3771]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x33 0x1 May 18 04:35:22 rivo sudo[3771]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 04:35:22 rivo sudo[3771]: pam_unix(sudo:session): session closed for user root May 18 04:35:22 rivo volumio[3241]: info: Apply VIM3L Onboard LEDs Settings May 18 04:35:22 rivo volumio[3241]: info: Turning On Onboard LEDs May 18 04:35:22 rivo sudo[3775]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/sys_led/trigger May 18 04:35:22 rivo sudo[3775]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 04:35:22 rivo volumio[3241]: info: Starting CEC Event listener May 18 04:35:22 rivo volumio[3241]: info: Volumio Calling Home May 18 04:35:22 rivo sudo[3775]: pam_unix(sudo:session): session closed for user root May 18 04:35:22 rivo sudo[3779]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/red_red/trigger May 18 04:35:22 rivo sudo[3779]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 04:35:22 rivo sudo[3779]: pam_unix(sudo:session): session closed for user root May 18 04:35:22 rivo volumio[3241]: info: QobuzConnect: Opened /tmp/qbz-connect.socket socket, listening for connections May 18 04:35:22 rivo volumio[3241]: (node:3241) [DEP0005] DeprecationWarning: Buffer() is deprecated due to security and usability issues. Please use the Buffer.alloc(), Buffer.allocUnsafe(), or Buffer.from() methods instead. May 18 04:35:22 rivo volumio[3241]: (Use `node --trace-deprecation ...` to show where the warning was created) May 18 04:35:22 rivo volumio[3241]: info: Discovery: this is already registered, b34a40c3-de2c-433e-82f2-b63f973eaa47 May 18 04:35:22 rivo volumio[3241]: info: Discovery: Found device Rivo May 18 04:35:22 rivo volumio[3241]: info: CoreCommandRouter::volumioGetState May 18 04:35:22 rivo volumio[3241]: info: CorePlayQueue::getTrack 0 May 18 04:35:22 rivo volumio[3241]: info: Serial port opened successfully May 18 04:35:22 rivo volumio[3241]: info: Sending serial start messages May 18 04:35:22 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 04:35:22 rivo volumio[3241]: info: Reporting MCU Network Status: 3 May 18 04:35:22 rivo volumio[3241]: info: MP1 GPIO: Signalled system ready via GPIO May 18 04:35:22 rivo volumio[3241]: verbose: New Socket.io Connection to 192.168.2.186:3000 from 192.168.2.156 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 1 May 18 04:35:22 rivo volumio[3241]: info: Executing endpoint qc_getconfig May 18 04:35:22 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig May 18 04:35:22 rivo qobuz-connect[3727]: 20250518 04:35:22.963 [3727.3727] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket May 18 04:35:22 rivo volumio[3241]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined May 18 04:35:22 rivo volumio[3241]: error: Cannot start Volumio Streaming Daemon May 18 04:35:22 rivo volumio[3241]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 18 04:35:22 rivo volumio[3241]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 18 04:35:22 rivo volumio[3241]: info: RAAT Albumart path created successfully May 18 04:35:22 rivo volumio[3241]: info: Bluetooth name changed to Rivo May 18 04:35:22 rivo volumio[3241]: info: MPD Permissions set May 18 04:35:22 rivo volumio[3241]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined May 18 04:35:22 rivo volumio[3241]: info: MPD Permissions set May 18 04:35:22 rivo volumio[3241]: info: Upmpdcli Daemon Started May 18 04:35:23 rivo volumio[3241]: info: CoreCommandRouter::volumioGetState May 18 04:35:23 rivo volumio[3241]: info: CorePlayQueue::getTrack 0 May 18 04:35:23 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 18 04:35:23 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 18 04:35:23 rivo volumio[3241]: info: CoreCommandRouter::volumioGetState May 18 04:35:23 rivo volumio[3241]: info: CorePlayQueue::getTrack 0 May 18 04:35:23 rivo volumio[3241]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] May 18 04:35:23 rivo volumio[3241]: info: QobuzConnect: QOBUZ Connect daemon connected May 18 04:35:23 rivo volumio[3241]: info: Volumio called home May 18 04:35:23 rivo qobuz-connect[3727]: 20250518 04:35:23.063 [3727.3727] INFO VolumeManager: [0xabf9e800]: Setting new playback volume: 75 May 18 04:35:23 rivo qobuz-connect[3727]: 20250518 04:35:23.063 [3727.3727] INFO VolumeManager: [0xabf9e800]: Setting new mute state: 0 May 18 04:35:23 rivo qobuz-connect[3727]: 20250518 04:35:23.063 [3727.3727] INFO QobuzConnect: [0xabf9f1d0]: Client initialized! May 18 04:35:23 rivo qobuz-connect[3727]: 20250518 04:35:23.063 [3727.3727] INFO SampleApp: Starting Avahi advertising, name: Rivo, service name: _qobuz-connect._tcp May 18 04:35:23 rivo volumio[3241]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 May 18 04:35:23 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 04:35:23 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 04:35:23 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 04:35:23 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 04:35:23 rivo qobuz-connect[3727]: 20250518 04:35:23.094 [3727.3727] INFO LocalConfigManager: [0xabf9e0e0]: Starting Local Configuration server May 18 04:35:23 rivo qobuz-connect[3727]: 20250518 04:35:23.094 [3727.3727] INFO SampleApp: Starting Local configuration server May 18 04:35:23 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 04:35:23 rivo qobuz-connect[3727]: 20250518 04:35:23.094 [3727.3727] INFO SampleApp: Connected to UNIX socket client 0xabf93ed8 May 18 04:35:23 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 04:35:23 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 04:35:23 rivo qobuz-connect[3727]: 20250518 04:35:23.114 [3727.3727] INFO SampleApp: Playback volume changed: 75 May 18 04:35:23 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 18 04:35:23 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 18 04:35:23 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 04:35:23 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 04:35:23 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 04:35:23 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 04:35:23 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 04:35:23 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 04:35:23 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 04:35:23 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 04:35:23 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 04:35:23 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 18 04:35:23 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 18 04:35:23 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 04:35:23 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setDeviceVolumeOverride May 18 04:35:23 rivo volumio[3241]: info: Setting Device Volume Override May 18 04:35:23 rivo volumio[3241]: info: CoreCommandRouter::volumioUpdateVolumeSettings May 18 04:35:23 rivo volumio[3241]: info: Updating Volume Controller Parameters: Device: 5 Name: Combo384 Amanero Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 May 18 04:35:23 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume May 18 04:35:23 rivo volumio[3241]: info: Disabling external Volume Control May 18 04:35:23 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 04:35:23 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 04:35:23 rivo volumio[3241]: info: CoreStateMachine::pushState May 18 04:35:23 rivo volumio[3241]: info: CorePlayQueue::getTrack 0 May 18 04:35:23 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 04:35:23 rivo volumio[3241]: info: CoreCommandRouter::volumioPushState May 18 04:35:23 rivo volumio[3241]: info: CoreCommandRouter::volumioGetState May 18 04:35:23 rivo volumio[3241]: info: CorePlayQueue::getTrack 0 May 18 04:35:23 rivo volumio[3241]: info: MRS: Pushing multiroomSync output update for this device May 18 04:35:23 rivo volumio[3241]: info: MRS: Pushing multiroomSync output May 18 04:35:23 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: system , setAdditionalSVInfo May 18 04:35:23 rivo volumio[3241]: info: Setting Additional System Software info: Hardware Revision: 2.1 May 18 04:35:23 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: system , setHwFwVersion May 18 04:35:23 rivo volumio[3241]: info: Setting HW Firmware info: undefined May 18 04:35:23 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: system , setHwVersion May 18 04:35:23 rivo volumio[3241]: info: Setting HW Version info: 2.1 May 18 04:35:23 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: system , setAdditionalSVInfo May 18 04:35:23 rivo volumio[3241]: info: Setting Additional System Software info: Hardware Revision: 2.1, Firmware Version: 0.3.4 May 18 04:35:23 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: system , setHwFwVersion May 18 04:35:23 rivo volumio[3241]: info: Setting HW Firmware info: 0.3.4 May 18 04:35:23 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: system , setHwVersion May 18 04:35:23 rivo volumio[3241]: info: Setting HW Version info: 2.1 May 18 04:35:23 rivo volumio[3241]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff May 18 04:35:23 rivo volumio[3241]: info: MCU Signalled Headphone Mode Disabled May 18 04:35:23 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState May 18 04:35:23 rivo volumio[3241]: info: Reporting Headphone State: false May 18 04:35:23 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 04:35:23 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 04:35:23 rivo volumio[3241]: info: Updating RAAT Signal Path May 18 04:35:23 rivo volumio[3241]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined May 18 04:35:23 rivo volumio[3241]: info: MCU Signalled Sleep Mode Disabled May 18 04:35:23 rivo volumio[3241]: info: Enabling Advanced system settings configuration May 18 04:35:23 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: system , addAdditionalUISections May 18 04:35:23 rivo volumio[3241]: info: Additional UI Settings Added for plugin music_service/inputs May 18 04:35:23 rivo volumio[3241]: info: MCU Signalled Auto Boot Mode On Power Disabled May 18 04:35:23 rivo sudo[3815]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms force on May 18 04:35:23 rivo sudo[3815]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 04:35:23 rivo kernel: fb: osd[0] enable: 1 (Xorg) May 18 04:35:23 rivo sudo[3815]: pam_unix(sudo:session): session closed for user root May 18 04:35:23 rivo volumio[3241]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 May 18 04:35:23 rivo volumio[3241]: error: Serial API: Failed to decode command: MAXVOL, message: 100 May 18 04:35:23 rivo volumio[3241]: info: CoreCommandRouter::volumioGetState May 18 04:35:23 rivo volumio[3241]: info: CorePlayQueue::getTrack 0 May 18 04:35:23 rivo volumio[3241]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. May 18 04:35:23 rivo sudo[3818]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms 0 0 0 May 18 04:35:23 rivo sudo[3818]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 04:35:23 rivo sudo[3818]: pam_unix(sudo:session): session closed for user root May 18 04:35:23 rivo volumio[3241]: info: Access Token successfully retrieved May 18 04:35:23 rivo volumio[3241]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 18 04:35:23 rivo volumio[3241]: info: [1747535723640] CoreMusicLibrary::Adding element QOBUZ May 18 04:35:23 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 18 04:35:23 rivo volumio[3241]: Cannot find translation for source QOBUZ May 18 04:35:23 rivo volumio[3241]: info: Stopping AccessToken refresher cron for QOBUZ May 18 04:35:23 rivo volumio[3241]: info: AccessToken refresher cron started for QOBUZ May 18 04:35:23 rivo volumio[3241]: info: Adding QOBUZ REST API Endpoints May 18 04:35:23 rivo volumio[3241]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz May 18 04:35:23 rivo volumio[3241]: verbose: New Socket.io Connection to 192.168.2.186:3000 from 192.168.2.156 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 4 May 18 04:35:23 rivo volumio[3241]: info: VolumeController:: Volume=undefined Mute =false May 18 04:35:23 rivo volumio[3241]: info: CoreStateMachine::pushState May 18 04:35:23 rivo volumio[3241]: info: CorePlayQueue::getTrack 0 May 18 04:35:23 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 04:35:23 rivo volumio[3241]: info: CoreCommandRouter::volumioPushState May 18 04:35:23 rivo volumio[3241]: info: CoreCommandRouter::volumioGetState May 18 04:35:23 rivo volumio[3241]: info: CorePlayQueue::getTrack 0 May 18 04:35:23 rivo volumio[3241]: info: MRS: Pushing multiroomSync output update for this device May 18 04:35:23 rivo volumio[3241]: info: MRS: Pushing multiroomSync output May 18 04:35:23 rivo volumio[3241]: info: Starting Shairport Sync May 18 04:35:23 rivo volumio[3241]: info: Starting Shairport Sync May 18 04:35:23 rivo sudo[3822]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 18 04:35:23 rivo sudo[3822]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 04:35:23 rivo volumio[3241]: info: Starting Shairport Sync May 18 04:35:23 rivo sudo[3825]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 18 04:35:23 rivo sudo[3825]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 04:35:23 rivo volumio[3241]: info: CoreCommandRouter::volumioGetState May 18 04:35:23 rivo volumio[3241]: info: CorePlayQueue::getTrack 0 May 18 04:35:23 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 18 04:35:23 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 18 04:35:23 rivo volumio[3241]: info: CoreCommandRouter::volumioGetState May 18 04:35:23 rivo volumio[3241]: info: CorePlayQueue::getTrack 0 May 18 04:35:23 rivo sudo[3827]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 18 04:35:23 rivo sudo[3827]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 04:35:23 rivo volumio[3241]: info: CoreCommandRouter::volumioGetState May 18 04:35:23 rivo volumio[3241]: info: CorePlayQueue::getTrack 0 May 18 04:35:23 rivo volumio[3241]: info: Preparing to generate the ALSA configuration file May 18 04:35:23 rivo volumio[3241]: info: Adding Inputs via Serial API May 18 04:35:23 rivo volumio[3241]: info: Access Token successfully retrieved May 18 04:35:23 rivo volumio[3241]: info: CoreCommandRouter::volumioGetState May 18 04:35:23 rivo volumio[3241]: info: CorePlayQueue::getTrack 0 May 18 04:35:23 rivo volumio[3241]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf May 18 04:35:23 rivo volumio[3241]: info: Reading ALSA contributions from plugins. May 18 04:35:24 rivo volumio[3241]: info: CoreStateMachine::pushState May 18 04:35:24 rivo volumio[3241]: info: CorePlayQueue::getTrack 0 May 18 04:35:24 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 04:35:24 rivo volumio[3241]: info: CoreCommandRouter::volumioPushState May 18 04:35:24 rivo volumio[3241]: info: CoreCommandRouter::volumioGetState May 18 04:35:24 rivo volumio[3241]: info: CorePlayQueue::getTrack 0 May 18 04:35:24 rivo volumio[3241]: info: MRS: Pushing multiroomSync output update for this device May 18 04:35:24 rivo volumio[3241]: info: MRS: Pushing multiroomSync output May 18 04:35:24 rivo volumio[3241]: info: CoreCommandRouter::servicePushState May 18 04:35:24 rivo volumio[3241]: info: CorePlayQueue::getTrack 0 May 18 04:35:24 rivo volumio[3241]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received inputs May 18 04:35:24 rivo volumio[3241]: info: CoreCommandRouter::volumiosetSourceActiveno-source May 18 04:35:24 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 18 04:35:24 rivo volumio[3241]: Cannot find translation for source QOBUZ May 18 04:35:24 rivo volumio[3241]: info: MCU Signalled Playback Inactive May 18 04:35:24 rivo volumio[3241]: info: Asound.conf file written May 18 04:35:24 rivo sudo[3832]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf May 18 04:35:24 rivo sudo[3832]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 04:35:24 rivo sudo[3832]: pam_unix(sudo:session): session closed for user root May 18 04:35:24 rivo kernel: Fine tdm clk setting range (0~2000000), 12287994 May 18 04:35:24 rivo kernel: Err: can not set rate to 4278967296! May 18 04:35:24 rivo kernel: Range[3920000 - 500000000] May 18 04:35:24 rivo kernel: out of value, fixed it May 18 04:35:24 rivo kernel: id=0 set inskew=0 May 18 04:35:24 rivo volumio[3241]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:eARC_TX CDS:0' : Operation not permitted May 18 04:35:24 rivo volumio[3241]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:I2SIn CLK:0' : Operation not permitted May 18 04:35:24 rivo volumio[3241]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:SPDIFIN audio samplerate:0' : Operation not permitted May 18 04:35:24 rivo volumio[3241]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:SPDIFIN Audio Type:0' : Operation not permitted May 18 04:35:24 rivo volumio[3241]: info: Output device has changed, restarting MPD May 18 04:35:24 rivo sudo[3837]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 18 04:35:24 rivo sudo[3837]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 04:35:24 rivo volumio[3241]: info: Output device has changed, restarting Shairport Sync May 18 04:35:24 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 04:35:24 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 18 04:35:24 rivo sudo[3837]: pam_unix(sudo:session): session closed for user root May 18 04:35:24 rivo kernel: xhci-hcd xhci-hcd.0.auto: Timeout while waiting for setup device command May 18 04:35:24 rivo volumio-remote-updater[2818]: [2025-05-18 04:35:24] [connect] Successful connection May 18 04:35:24 rivo sudo[3841]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 18 04:35:24 rivo sudo[3841]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 04:35:24 rivo volumio[3241]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 18 04:35:24 rivo volumio[3241]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 18 04:35:24 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 04:35:24 rivo volumio[3241]: info: QobuzConnect: setDeactiveState invoked May 18 04:35:24 rivo volumio[3241]: info: CoreCommandRouter::volumioGetState May 18 04:35:24 rivo volumio[3241]: info: CorePlayQueue::getTrack 0 May 18 04:35:24 rivo volumio[3241]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes May 18 04:35:24 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 04:35:24 rivo volumio[3241]: info: Preparing to generate the ALSA configuration file May 18 04:35:24 rivo kernel: usb 1-1.1: device not accepting address 5, error -62 May 18 04:35:24 rivo kernel: usb 1-1.1: new high-speed USB device number 6 using xhci-hcd May 18 04:35:24 rivo sudo[3853]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service May 18 04:35:24 rivo sudo[3853]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 04:35:24 rivo volumio[3241]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf May 18 04:35:24 rivo volumio[3241]: info: Reading ALSA contributions from plugins. May 18 04:35:24 rivo volumio[3241]: info: Successfully retrieved User Session From TIDAL May 18 04:35:24 rivo sudo[3853]: pam_unix(sudo:session): session closed for user root May 18 04:35:24 rivo sudo[3855]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service May 18 04:35:24 rivo sudo[3855]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 04:35:24 rivo volumio[3241]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined May 18 04:35:24 rivo volumio[3241]: info: MPD Permissions set May 18 04:35:24 rivo sudo[3857]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service May 18 04:35:24 rivo sudo[3857]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 04:35:24 rivo volumio-remote-updater[2818]: [2025-05-18 04:35:24] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1747535724 101 May 18 04:35:24 rivo volumio[3241]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 5 May 18 04:35:24 rivo systemd[1]: Stopping Volumio Qobuz Connect Service... May 18 04:35:24 rivo qobuz-connect[3727]: 20250518 04:35:24.892 [3727.3727] INFO SampleApp: Stopping Local configuration server May 18 04:35:24 rivo systemd[1]: Started Volumio Bluetooth Module. May 18 04:35:24 rivo qobuz-connect[3727]: 20250518 04:35:24.902 [3727.3727] INFO SampleApp: shat down connection on UNIX socket May 18 04:35:24 rivo systemd[1]: qobuz-connect.service: Succeeded. May 18 04:35:24 rivo systemd[1]: Stopped Volumio Qobuz Connect Service. May 18 04:35:24 rivo sudo[3855]: pam_unix(sudo:session): session closed for user root May 18 04:35:24 rivo systemd[1]: Started Volumio Qobuz Connect Service. May 18 04:35:24 rivo sudo[3866]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 18 04:35:24 rivo sudo[3857]: pam_unix(sudo:session): session closed for user root May 18 04:35:25 rivo sudo[3869]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* May 18 04:35:25 rivo sudo[3869]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 04:35:25 rivo volumiobt[3862]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory May 18 04:35:25 rivo sudo[3866]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 04:35:25 rivo sudo[3869]: pam_unix(sudo:session): session closed for user root May 18 04:35:25 rivo volumio[3241]: ------------------------------------ BT MESSAGE: BT STATUS: running May 18 04:35:25 rivo sudo[3872]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 18 04:35:25 rivo sudo[3872]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 04:35:25 rivo volumio[3241]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 18 04:35:25 rivo sudo[3866]: pam_unix(sudo:session): session closed for user root May 18 04:35:25 rivo sudo[3872]: pam_unix(sudo:session): session closed for user root May 18 04:35:25 rivo volumio[3241]: info: Turning Off Onboard LEDs May 18 04:35:25 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 04:35:25 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 04:35:25 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 04:35:25 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 04:35:25 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 04:35:25 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 04:35:25 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 04:35:25 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 04:35:25 rivo sudo[3893]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/sys_led/trigger May 18 04:35:25 rivo sudo[3893]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 04:35:25 rivo sudo[3893]: pam_unix(sudo:session): session closed for user root May 18 04:35:25 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 18 04:35:25 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 18 04:35:25 rivo sudo[3900]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/red_red/trigger May 18 04:35:25 rivo sudo[3900]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 04:35:25 rivo sudo[3900]: pam_unix(sudo:session): session closed for user root May 18 04:35:25 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 04:35:25 rivo volumio[3241]: info: TidalConnect service stoped! May 18 04:35:25 rivo volumio[3241]: info: Volumio BT Module successfully started May 18 04:35:25 rivo volumio[3241]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected May 18 04:35:25 rivo volumio[3241]: info: QobuzConnect: setDeactiveState invoked May 18 04:35:25 rivo volumio[3241]: info: CoreCommandRouter::volumioGetState May 18 04:35:25 rivo volumio[3241]: info: CorePlayQueue::getTrack 0 May 18 04:35:25 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 04:35:25 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 04:35:25 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 04:35:25 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 04:35:25 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 04:35:25 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 04:35:25 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 04:35:25 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 04:35:25 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 18 04:35:25 rivo volumio[3241]: info: Not Reporting Auto name since its the default one May 18 04:35:25 rivo volumio[3241]: info: Executing endpoint qc_getconfig May 18 04:35:25 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig May 18 04:35:25 rivo qobuz-connect[3870]: 20250518 04:35:25.280 [3870.3870] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket May 18 04:35:25 rivo qobuz-connect[3870]: 20250518 04:35:25.284 [3870.3870] INFO VolumeManager: [0xac3fc800]: Setting new playback volume: 75 May 18 04:35:25 rivo qobuz-connect[3870]: 20250518 04:35:25.285 [3870.3870] INFO VolumeManager: [0xac3fc800]: Setting new mute state: 0 May 18 04:35:25 rivo qobuz-connect[3870]: 20250518 04:35:25.285 [3870.3870] INFO QobuzConnect: [0xac3fd1d0]: Client initialized! May 18 04:35:25 rivo qobuz-connect[3870]: 20250518 04:35:25.285 [3870.3870] INFO SampleApp: Starting Avahi advertising, name: Rivo, service name: _qobuz-connect._tcp May 18 04:35:25 rivo qobuz-connect[3870]: 20250518 04:35:25.300 [3870.3870] INFO LocalConfigManager: [0xac3fc0e0]: Starting Local Configuration server May 18 04:35:25 rivo qobuz-connect[3870]: 20250518 04:35:25.300 [3870.3870] INFO SampleApp: Starting Local configuration server May 18 04:35:25 rivo qobuz-connect[3870]: 20250518 04:35:25.301 [3870.3870] INFO SampleApp: Connected to UNIX socket client 0xac3f1ed8 May 18 04:35:25 rivo volumio[3241]: info: Onboard LEDs initialized May 18 04:35:25 rivo volumio[3241]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] May 18 04:35:25 rivo volumio[3241]: info: QobuzConnect: QOBUZ Connect daemon connected May 18 04:35:25 rivo volumio[3241]: info: Starting Shairport Sync May 18 04:35:25 rivo volumio[3241]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 18 04:35:25 rivo volumio[3241]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect May 18 04:35:25 rivo sudo[3919]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 18 04:35:25 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable May 18 04:35:25 rivo sudo[3919]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 04:35:25 rivo qobuz-connect[3870]: 20250518 04:35:25.430 [3870.3870] INFO SampleApp: Playback volume changed: 75 May 18 04:35:25 rivo sudo[3921]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 18 04:35:25 rivo sudo[3921]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 04:35:25 rivo systemd[1]: Started Volumio Tidal Connect Service. May 18 04:35:25 rivo volumio[3241]: No protocol specified May 18 04:35:25 rivo volumio[3241]: xcb_connection_has_error() returned true May 18 04:35:25 rivo volumio[3241]: info: Configured Audio card not found, not starting RAAT May 18 04:35:25 rivo sudo[3921]: pam_unix(sudo:session): session closed for user root May 18 04:35:25 rivo volumio[3241]: verbose: New Socket.io Connection to 192.168.2.179 from 192.168.2.156 UA: Mozilla/5.0 (Linux; Android 15; SM-S928B Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/136.0.7103.60 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 May 18 04:35:25 rivo volumio[3241]: info: Asound.conf file unchanged, so no further update is needed May 18 04:35:25 rivo volumio[3241]: info: Output device has changed, restarting MPD May 18 04:35:25 rivo sudo[3930]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 18 04:35:25 rivo volumio[3241]: info: Output device has changed, restarting Shairport Sync May 18 04:35:25 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 04:35:25 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 18 04:35:25 rivo sudo[3930]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 04:35:25 rivo sudo[3930]: pam_unix(sudo:session): session closed for user root May 18 04:35:25 rivo sudo[3934]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 18 04:35:25 rivo sudo[3934]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 04:35:25 rivo volumio[3241]: No protocol specified May 18 04:35:25 rivo volumio[3241]: xcb_connection_has_error() returned true May 18 04:35:25 rivo volumio[3241]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 18 04:35:25 rivo volumio[3241]: No protocol specified May 18 04:35:25 rivo volumio[3241]: xcb_connection_has_error() returned true May 18 04:35:25 rivo volumio[3241]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 18 04:35:25 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 04:35:25 rivo volumio[3241]: info: QobuzConnect: setDeactiveState invoked May 18 04:35:25 rivo volumio[3241]: info: CoreCommandRouter::volumioGetState May 18 04:35:25 rivo volumio[3241]: info: CorePlayQueue::getTrack 0 May 18 04:35:25 rivo volumio[3241]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes May 18 04:35:25 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 04:35:25 rivo volumio[3241]: 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:2107:39)\n at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28)\n at processTicksAndRejections (internal/process/task_queues.js:77:11)"} May 18 04:35:25 rivo sudo[3941]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 18 04:35:25 rivo sudo[3941]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 04:35:25 rivo systemd[1]: Stopping Volumio Tidal Connect Service... May 18 04:35:25 rivo systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM May 18 04:35:25 rivo systemd[1]: vtcs.service: Succeeded. May 18 04:35:25 rivo systemd[1]: Stopped Volumio Tidal Connect Service. May 18 04:35:25 rivo volumio[3241]: info: CoreCommandRouter::volumioGetState May 18 04:35:25 rivo volumio[3241]: info: CorePlayQueue::getTrack 0 May 18 04:35:25 rivo volumio[3241]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 18 04:35:25 rivo volumio[3241]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 18 04:35:25 rivo volumio[3241]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 18 04:35:25 rivo sudo[3941]: pam_unix(sudo:session): session closed for user root May 18 04:35:25 rivo volumio[3241]: ------------------------------------ BT MESSAGE: BT STATUS: running May 18 04:35:25 rivo volumio[3241]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 18 04:35:25 rivo volumio[3241]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 18 04:35:25 rivo volumio[3241]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 18 04:35:25 rivo sudo[3947]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service May 18 04:35:25 rivo sudo[3947]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 04:35:25 rivo volumio[3241]: ------------------------------------ BT MESSAGE: BT STATUS: running May 18 04:35:25 rivo sudo[3947]: pam_unix(sudo:session): session closed for user root May 18 04:35:25 rivo volumio[3241]: 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: 7 May 18 04:35:25 rivo volumio[3241]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined May 18 04:35:25 rivo volumio[3241]: info: MPD Permissions set May 18 04:35:25 rivo sudo[3950]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service May 18 04:35:25 rivo sudo[3950]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 04:35:25 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 04:35:25 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 04:35:25 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 04:35:25 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 04:35:25 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 04:35:25 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 04:35:25 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 04:35:25 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 04:35:25 rivo qobuz-connect[3870]: 20250518 04:35:25.928 [3870.3870] INFO SampleApp: Stopping Local configuration server May 18 04:35:25 rivo systemd[1]: Stopping Volumio Qobuz Connect Service... May 18 04:35:25 rivo qobuz-connect[3870]: 20250518 04:35:25.939 [3870.3870] INFO SampleApp: shat down connection on UNIX socket May 18 04:35:25 rivo systemd[1]: qobuz-connect.service: Succeeded. May 18 04:35:25 rivo systemd[1]: Stopped Volumio Qobuz Connect Service. May 18 04:35:25 rivo systemd[1]: Started Volumio Qobuz Connect Service. May 18 04:35:25 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 18 04:35:25 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 18 04:35:25 rivo sudo[3950]: pam_unix(sudo:session): session closed for user root May 18 04:35:26 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 04:35:26 rivo volumio[3241]: ------------------------------------ BT MESSAGE: BT STATUS: running May 18 04:35:26 rivo volumio[3241]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 18 04:35:26 rivo sudo[3961]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio May 18 04:35:26 rivo sudo[3961]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 04:35:26 rivo systemd[1]: systemd-timedated.service: Succeeded. May 18 04:35:26 rivo volumiobt[3862]: pulseaudio: no process found May 18 04:35:26 rivo sudo[3961]: pam_unix(sudo:session): session closed for user root May 18 04:35:26 rivo volumio[3241]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected May 18 04:35:26 rivo volumio[3241]: info: QobuzConnect: setDeactiveState invoked May 18 04:35:26 rivo volumio[3241]: info: CoreCommandRouter::volumioGetState May 18 04:35:26 rivo volumio[3241]: info: CorePlayQueue::getTrack 0 May 18 04:35:26 rivo volumio[3241]: info: Successfully retrieved User Subscription From TIDAL May 18 04:35:26 rivo volumio[3241]: info: Adding TIDAL to Browse Sources May 18 04:35:26 rivo volumio[3241]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 18 04:35:26 rivo volumio[3241]: info: [1747535726177] CoreMusicLibrary::Adding element TIDAL May 18 04:35:26 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 18 04:35:26 rivo volumio[3241]: Cannot find translation for source QOBUZ May 18 04:35:26 rivo volumio[3241]: Cannot find translation for source TIDAL May 18 04:35:26 rivo volumio[3241]: info: Stopping AccessToken refresher cron May 18 04:35:26 rivo dbus-daemon[2799]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.21' (uid=1000 pid=3967 comm="/usr/bin/pulseaudio --high-priority=true " label="kernel") May 18 04:35:26 rivo systemd[1]: Starting RealtimeKit Scheduling Policy Service... May 18 04:35:26 rivo kernel: IPVS: Creating netns size=1928 id=3 May 18 04:35:26 rivo volumio[3241]: info: AccessToken refresher cron started May 18 04:35:26 rivo volumio[3241]: info: Adding TIDAL REST API Endpoints May 18 04:35:26 rivo volumio[3241]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal May 18 04:35:26 rivo dbus-daemon[2799]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' May 18 04:35:26 rivo systemd[1]: Started RealtimeKit Scheduling Policy Service. May 18 04:35:26 rivo rtkit-daemon[3968]: Successfully called chroot. May 18 04:35:26 rivo rtkit-daemon[3968]: Successfully dropped privileges. May 18 04:35:26 rivo rtkit-daemon[3968]: Successfully limited resources. May 18 04:35:26 rivo rtkit-daemon[3968]: Running. May 18 04:35:26 rivo dbus-daemon[2799]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.22' (uid=0 pid=3968 comm="/usr/lib/rtkit/rtkit-daemon " label="kernel") May 18 04:35:26 rivo rtkit-daemon[3968]: Watchdog thread running. May 18 04:35:26 rivo rtkit-daemon[3968]: Canary thread running. May 18 04:35:26 rivo systemd[1]: Starting Authorization Manager... May 18 04:35:26 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 04:35:26 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 04:35:26 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 04:35:26 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 04:35:26 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 04:35:26 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 18 04:35:26 rivo volumio[3241]: info: Not Reporting Auto name since its the default one May 18 04:35:26 rivo volumio[3241]: info: Starting Shairport Sync May 18 04:35:26 rivo polkitd[3975]: started daemon version 0.105 using authority implementation `local' version `0.105' May 18 04:35:26 rivo dbus-daemon[2799]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' May 18 04:35:26 rivo systemd[1]: Started Authorization Manager. May 18 04:35:26 rivo sudo[3980]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 18 04:35:26 rivo sudo[3980]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 04:35:26 rivo sudo[3983]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 18 04:35:26 rivo sudo[3983]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 04:35:26 rivo volumio[3241]: ------------------------------------ BT MESSAGE: BT STATUS: running May 18 04:35:26 rivo sudo[3985]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 18 04:35:26 rivo volumio[3241]: ------------------------------------ BT MESSAGE: BT STATUS: running May 18 04:35:26 rivo sudo[3983]: pam_unix(sudo:session): session closed for user root May 18 04:35:26 rivo sudo[3985]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 04:35:26 rivo volumio[3241]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 18 04:35:26 rivo volumio[3241]: info: Executing endpoint qc_getconfig May 18 04:35:26 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig May 18 04:35:26 rivo qobuz-connect[3956]: 20250518 04:35:26.535 [3956.3956] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket May 18 04:35:26 rivo qobuz-connect[3956]: 20250518 04:35:26.540 [3956.3956] INFO VolumeManager: [0xab7af800]: Setting new playback volume: 75 May 18 04:35:26 rivo qobuz-connect[3956]: 20250518 04:35:26.540 [3956.3956] INFO VolumeManager: [0xab7af800]: Setting new mute state: 0 May 18 04:35:26 rivo qobuz-connect[3956]: 20250518 04:35:26.540 [3956.3956] INFO QobuzConnect: [0xab7b01d0]: Client initialized! May 18 04:35:26 rivo qobuz-connect[3956]: 20250518 04:35:26.540 [3956.3956] INFO SampleApp: Starting Avahi advertising, name: Rivo, service name: _qobuz-connect._tcp May 18 04:35:26 rivo sudo[3985]: pam_unix(sudo:session): session closed for user root May 18 04:35:26 rivo volumio[3241]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 18 04:35:26 rivo volumio[3241]: ------------------------------------ BT MESSAGE: BT STATUS: running May 18 04:35:26 rivo volumio[3241]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 18 04:35:26 rivo volumio[3241]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] May 18 04:35:26 rivo volumio[3241]: info: QobuzConnect: QOBUZ Connect daemon connected May 18 04:35:26 rivo qobuz-connect[3956]: 20250518 04:35:26.568 [3956.3956] INFO LocalConfigManager: [0xab7af0e0]: Starting Local Configuration server May 18 04:35:26 rivo qobuz-connect[3956]: 20250518 04:35:26.569 [3956.3956] INFO SampleApp: Starting Local configuration server May 18 04:35:26 rivo qobuz-connect[3956]: 20250518 04:35:26.572 [3956.3956] INFO SampleApp: Connected to UNIX socket client 0xab7a4ed8 May 18 04:35:26 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable May 18 04:35:26 rivo volumio[3241]: No protocol specified May 18 04:35:26 rivo volumio[3241]: xcb_connection_has_error() returned true May 18 04:35:26 rivo volumio[3241]: info: Configured Audio card not found, not starting RAAT May 18 04:35:26 rivo qobuz-connect[3956]: 20250518 04:35:26.684 [3956.3956] INFO SampleApp: Playback volume changed: 75 May 18 04:35:26 rivo volumio[3241]: info: CoreCommandRouter::volumioGetState May 18 04:35:26 rivo volumio[3241]: info: CorePlayQueue::getTrack 0 May 18 04:35:26 rivo volumio[3241]: verbose: New Socket.io Connection to 192.168.2.186 from 192.168.2.156 UA: Mozilla/5.0 (Linux; Android 15; SM-S928B Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/136.0.7103.60 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 May 18 04:35:26 rivo systemd[1]: systemd-hostnamed.service: Succeeded. May 18 04:35:26 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 18 04:35:26 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 18 04:35:26 rivo volumio[3241]: info: CoreCommandRouter::volumioGetVisibleSources May 18 04:35:26 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 18 04:35:26 rivo volumio[3241]: info: CoreCommandRouter::volumioGetState May 18 04:35:26 rivo volumio[3241]: info: CorePlayQueue::getTrack 0 May 18 04:35:26 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 18 04:35:26 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 18 04:35:26 rivo volumio[3241]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom May 18 04:35:26 rivo volumio[3241]: info: Received Get System Info May 18 04:35:26 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 18 04:35:26 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 18 04:35:26 rivo volumio[3241]: info: Discovery: Getting this device information May 18 04:35:26 rivo volumio[3241]: info: CoreCommandRouter::volumioGetState May 18 04:35:26 rivo volumio[3241]: info: CorePlayQueue::getTrack 0 May 18 04:35:26 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 18 04:35:26 rivo volumio[3241]: info: CoreCommandRouter::volumioGetState May 18 04:35:26 rivo volumio[3241]: info: CorePlayQueue::getTrack 0 May 18 04:35:26 rivo volumio[3241]: info: Listing playlists May 18 04:35:26 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 18 04:35:26 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 18 04:35:26 rivo volumio[3241]: info: MRS: Getting audio outputs on start May 18 04:35:26 rivo volumio[3241]: info: MRS: Requesting all other devices output May 18 04:35:27 rivo pulseaudio[3967]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 May 18 04:35:27 rivo pulseaudio[3967]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 May 18 04:35:27 rivo volumiobt[3862]: Applying permissions May 18 04:35:27 rivo volumiobt[3862]: Setting BT discoverable and pairable May 18 04:35:27 rivo pulseaudio[3967]: 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 May 18 04:35:27 rivo bluetoothd[3184]: Endpoint registered: sender=:1.43 path=/MediaEndpoint/A2DPSource May 18 04:35:27 rivo bluetoothd[3184]: Endpoint registered: sender=:1.43 path=/MediaEndpoint/A2DPSink May 18 04:35:27 rivo volumiobt[3862]: Agent registered May 18 04:35:27 rivo volumiobt[3862]: [bluetooth]# -e power on May 18 04:35:27 rivo volumiobt[3862]: Invalid command in menu main: -e May 18 04:35:27 rivo volumiobt[3862]: May 18 04:35:27 rivo volumiobt[3862]: Use "help" for a list of available commands in a menu. May 18 04:35:27 rivo volumiobt[3862]: Use "menu " if you want to enter any submenu. May 18 04:35:27 rivo volumiobt[3862]: Use "back" if you want to return to menu main. May 18 04:35:27 rivo volumiobt[3862]: [bluetooth]# agent on May 18 04:35:27 rivo volumiobt[3862]: Agent is already registered May 18 04:35:27 rivo volumiobt[3862]: [bluetooth]# discoverable on May 18 04:35:27 rivo volumiobt[3862]: [bluetooth]# pairable on May 18 04:35:27 rivo volumiobt[3862]: [bluetooth]# agent NoInputNoOutput May 18 04:35:27 rivo volumiobt[3862]: Agent is already registered May 18 04:35:27 rivo volumiobt[3862]: [bluetooth]# default-agent May 18 04:35:27 rivo volumiobt[3862]: [bluetooth]# quit May 18 04:35:27 rivo volumiobt[3862]: [59B blob data] May 18 04:35:28 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 18 04:35:28 rivo volumio[3241]: info: Received Get System Info May 18 04:35:28 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 18 04:35:28 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 18 04:35:28 rivo volumio[3241]: info: Discovery: Getting this device information May 18 04:35:28 rivo volumio[3241]: info: CoreCommandRouter::volumioGetState May 18 04:35:28 rivo volumio[3241]: info: CorePlayQueue::getTrack 0 May 18 04:35:28 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 18 04:35:28 rivo volumio[3241]: 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 May 18 04:35:28 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken May 18 04:35:28 rivo volumio[3241]: info: RAAT: Requesting Headphone Status May 18 04:35:28 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus May 18 04:35:28 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard May 18 04:35:28 rivo volumio[3241]: info: TidalConnect service started! May 18 04:35:28 rivo volumio[3241]: info: MCU Signalled Headphone Mode Disabled May 18 04:35:28 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState May 18 04:35:28 rivo volumio[3241]: info: Reporting Headphone State: false May 18 04:35:28 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 04:35:28 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 04:35:28 rivo volumio[3241]: info: Updating RAAT Signal Path May 18 04:35:28 rivo volumio[3241]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined May 18 04:35:28 rivo volumio[3241]: info: TidalConnect service stoped! May 18 04:35:28 rivo volumio[3241]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 18 04:35:28 rivo volumio[3241]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect May 18 04:35:28 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 18 04:35:28 rivo sudo[4008]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 18 04:35:28 rivo sudo[4008]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 04:35:29 rivo volumio[3241]: info: CoreCommandRouter::volumioGetVisibleSources May 18 04:35:29 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 18 04:35:29 rivo volumio[3241]: info: CoreCommandRouter::volumioGetState May 18 04:35:29 rivo volumio[3241]: info: CorePlayQueue::getTrack 0 May 18 04:35:29 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 18 04:35:29 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 18 04:35:29 rivo volumio[3241]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom May 18 04:35:29 rivo volumio[3241]: info: Received Get System Info May 18 04:35:29 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 18 04:35:29 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 18 04:35:29 rivo volumio[3241]: info: Discovery: Getting this device information May 18 04:35:29 rivo volumio[3241]: info: CoreCommandRouter::volumioGetState May 18 04:35:29 rivo volumio[3241]: info: CorePlayQueue::getTrack 0 May 18 04:35:29 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 18 04:35:29 rivo volumio[3241]: info: CoreCommandRouter::volumioGetState May 18 04:35:29 rivo volumio[3241]: info: CorePlayQueue::getTrack 0 May 18 04:35:29 rivo systemd[1]: Started Volumio Tidal Connect Service. May 18 04:35:29 rivo volumio[3241]: info: Listing playlists May 18 04:35:29 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 18 04:35:29 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 18 04:35:29 rivo sudo[4008]: pam_unix(sudo:session): session closed for user root May 18 04:35:29 rivo volumio[3241]: info: Executing endpoint tc_getconfig May 18 04:35:29 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig May 18 04:35:29 rivo vtcs[4010]: STARTING TidalConnect services, version: 1.4.0.34 May 18 04:35:29 rivo vtcs[4010]: STARTED TidalConnect services. May 18 04:35:29 rivo volumio[3241]: info: Executing endpoint tc_connect May 18 04:35:29 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect May 18 04:35:29 rivo volumio[3241]: info: Connecting to TidalConnect May 18 04:35:29 rivo volumio[3241]: info: CoreCommandRouter::servicePushState May 18 04:35:29 rivo volumio[3241]: info: CoreStateMachine::pushState May 18 04:35:29 rivo volumio[3241]: info: CorePlayQueue::getTrack 0 May 18 04:35:29 rivo volumio[3241]: info: CoreCommandRouter::volumioPushState May 18 04:35:29 rivo volumio[3241]: info: CoreCommandRouter::volumioGetState May 18 04:35:29 rivo volumio[3241]: info: CorePlayQueue::getTrack 0 May 18 04:35:29 rivo volumio[3241]: info: MRS: Pushing multiroomSync output update for this device May 18 04:35:29 rivo volumio[3241]: info: MRS: Pushing multiroomSync output May 18 04:35:29 rivo volumio[3241]: info: CorePlayQueue::getTrack 0 May 18 04:35:29 rivo volumio[3241]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received tidalconnect May 18 04:35:29 rivo volumio[3241]: info: CoreCommandRouter::servicePushState May 18 04:35:29 rivo volumio[3241]: info: CoreStateMachine::pushState May 18 04:35:29 rivo volumio[3241]: info: CorePlayQueue::getTrack 0 May 18 04:35:29 rivo volumio[3241]: info: CoreCommandRouter::volumioPushState May 18 04:35:29 rivo volumio[3241]: info: CoreCommandRouter::volumioGetState May 18 04:35:29 rivo volumio[3241]: info: CorePlayQueue::getTrack 0 May 18 04:35:29 rivo volumio[3241]: info: MRS: Pushing multiroomSync output update for this device May 18 04:35:29 rivo volumio[3241]: info: MRS: Pushing multiroomSync output May 18 04:35:29 rivo volumio[3241]: info: CorePlayQueue::getTrack 0 May 18 04:35:29 rivo volumio[3241]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received tidalconnect May 18 04:35:29 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 18 04:35:29 rivo volumio[3241]: info: Received Get System Info May 18 04:35:29 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 18 04:35:29 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 18 04:35:29 rivo volumio[3241]: info: Discovery: Getting this device information May 18 04:35:29 rivo volumio[3241]: info: CoreCommandRouter::volumioGetState May 18 04:35:29 rivo volumio[3241]: info: CorePlayQueue::getTrack 0 May 18 04:35:29 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 18 04:35:29 rivo volumio[3241]: info: CoreCommandRouter::getUIConfigOnPlugin May 18 04:35:29 rivo volumio[3241]: info: CoreCommandRouter::getUIConfigOnPlugin May 18 04:35:29 rivo volumio[3241]: info: Retrieving Cloud Streaming UI May 18 04:35:29 rivo volumio[3241]: info: RAAT: Requesting Headphone Status May 18 04:35:29 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus May 18 04:35:29 rivo volumio[3241]: info: CoreCommandRouter::volumioGetState May 18 04:35:29 rivo volumio[3241]: info: CorePlayQueue::getTrack 0 May 18 04:35:29 rivo volumio[3241]: info: Getting Tidal Cloud Configuration May 18 04:35:29 rivo volumio[3241]: info: CoreCommandRouter::getUIConfigOnPlugin May 18 04:35:29 rivo volumio[3241]: info: Getting Qobuz Cloud Configuration May 18 04:35:29 rivo volumio[3241]: info: Asking plugin for UI Config May 18 04:35:29 rivo volumio[3241]: info: CoreCommandRouter::getUIConfigOnPlugin May 18 04:35:29 rivo volumio[3241]: info: Getting Spotify Cloud Configuration May 18 04:35:29 rivo volumio[3241]: info: Asking plugin for UI Config May 18 04:35:29 rivo volumio[3241]: info: CoreCommandRouter::getUIConfigOnPlugin May 18 04:35:29 rivo volumio[3241]: info: Saving Spotify Acccount May 18 04:35:29 rivo volumio[3241]: info: Got it May 18 04:35:29 rivo volumio[3241]: error: Could not retrieve Spotify Config from plugin Spotify: no section found May 18 04:35:29 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 18 04:35:29 rivo volumio[3241]: info: Received Get System Info May 18 04:35:29 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 18 04:35:29 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 18 04:35:29 rivo volumio[3241]: info: Discovery: Getting this device information May 18 04:35:29 rivo volumio[3241]: info: CoreCommandRouter::volumioGetState May 18 04:35:29 rivo volumio[3241]: info: CorePlayQueue::getTrack 0 May 18 04:35:29 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 18 04:35:29 rivo volumio[3241]: info: MCU Signalled Headphone Mode Disabled May 18 04:35:29 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState May 18 04:35:29 rivo volumio[3241]: info: Reporting Headphone State: false May 18 04:35:29 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 04:35:29 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 04:35:29 rivo volumio[3241]: info: Updating RAAT Signal Path May 18 04:35:29 rivo volumio[3241]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined May 18 04:35:29 rivo volumio[3241]: info: Got Tidal Cloud Configuration May 18 04:35:29 rivo volumio[3241]: info: Got it May 18 04:35:29 rivo volumio[3241]: info: CoreCommandRouter::getUIConfigOnPlugin May 18 04:35:29 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 18 04:35:29 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 18 04:35:29 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 18 04:35:29 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 18 04:35:29 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 18 04:35:29 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 18 04:35:29 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 18 04:35:29 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 18 04:35:29 rivo volumio[3241]: info: CoreCommandRouter::volumioGetBrowseSources May 18 04:35:29 rivo volumio[3241]: info: CoreCommandRouter::volumioGetBrowseSources May 18 04:35:29 rivo volumio[3241]: info: CoreCommandRouter::volumioGetBrowseSources May 18 04:35:29 rivo kernel: xhci-hcd xhci-hcd.0.auto: Timeout while waiting for setup device command May 18 04:35:29 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 18 04:35:29 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares May 18 04:35:30 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken May 18 04:35:30 rivo kernel: fb: mem_free_work, free memory: addr:800000 May 18 04:35:31 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard May 18 04:35:32 rivo volumio[3241]: info: TidalConnect service started! May 18 04:35:32 rivo volumio[3241]: info: Checking for updated MCU Firmware May 18 04:35:32 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 18 04:35:32 rivo volumio[3241]: info: Firware on device is on latest version, no need to update May 18 04:35:32 rivo volumio[3241]: info: Executing endpoint metavolumio May 18 04:35:32 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 18 04:35:32 rivo volumio[3241]: info: Executing endpoint metavolumio May 18 04:35:32 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 18 04:35:32 rivo volumio[3241]: info: Executing endpoint metavolumio May 18 04:35:32 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 18 04:35:33 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 18 04:35:34 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 18 04:35:34 rivo volumio[3241]: info: Received Get System Info May 18 04:35:34 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 18 04:35:34 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 18 04:35:34 rivo volumio[3241]: info: Discovery: Getting this device information May 18 04:35:34 rivo volumio[3241]: info: CoreCommandRouter::volumioGetState May 18 04:35:34 rivo volumio[3241]: info: CorePlayQueue::getTrack 0 May 18 04:35:34 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 18 04:35:34 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken May 18 04:35:35 rivo kernel: xhci-hcd xhci-hcd.0.auto: Timeout while waiting for setup device command May 18 04:35:35 rivo volumio[3241]: error: Failed request for metavolumio API May 18 04:35:35 rivo kernel: usb 1-1.1: device not accepting address 6, error -62 May 18 04:35:35 rivo kernel: usb 1-1.1: the parent's name is 1-1 May 18 04:35:35 rivo kernel: usb 1-1-port1: Device no response May 18 04:35:35 rivo kernel: usb 1-1-port1: unable to enumerate USB device May 18 04:35:35 rivo kernel: usb 1-1.4: new high-speed USB device number 7 using xhci-hcd May 18 04:35:35 rivo kernel: usb 1-1.4: New USB device found, idVendor=0424, idProduct=2514 May 18 04:35:35 rivo kernel: usb 1-1.4: New USB device strings: Mfr=0, Product=0, SerialNumber=0 May 18 04:35:35 rivo kernel: hub 1-1.4:1.0: USB hub found May 18 04:35:35 rivo kernel: hub 1-1.4:1.0: 2 ports detected May 18 04:35:35 rivo systemd[1]: Started Helper to synchronize boot up for ifupdown. May 18 04:35:35 rivo systemd[1]: Starting Raise network interfaces... May 18 04:35:35 rivo volumio[3241]: ------------------------------------ BT MESSAGE: BT STATUS: running May 18 04:35:35 rivo systemd[1]: Started Raise network interfaces. May 18 04:35:35 rivo systemd[1]: Reached target Network. May 18 04:35:35 rivo systemd[1]: Starting Network Time Service... May 18 04:35:35 rivo systemd[1]: Starting Music Player Daemon... May 18 04:35:35 rivo systemd[1]: Starting Flexible IR remote input/output application support... May 18 04:35:35 rivo kernel: usb 1-1.4.1: new full-speed USB device number 8 using xhci-hcd May 18 04:35:35 rivo systemd[1]: Starting Permit User Sessions... May 18 04:35:35 rivo systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. May 18 04:35:35 rivo systemd[1]: Reached target Network is Online. May 18 04:35:36 rivo lircd-0.10.1[4116]: Info: lircd: Opening log, level: Info May 18 04:35:36 rivo lircd-0.10.1[4116]: Notice: Version: lircd 0.10.1 May 18 04:35:36 rivo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 18 04:35:36 rivo sudo[3980]: pam_unix(sudo:session): session closed for user root May 18 04:35:36 rivo sudo[3825]: pam_unix(sudo:session): session closed for user root May 18 04:35:36 rivo sudo[3822]: pam_unix(sudo:session): session closed for user root May 18 04:35:36 rivo sudo[3919]: pam_unix(sudo:session): session closed for user root May 18 04:35:36 rivo sudo[3827]: pam_unix(sudo:session): session closed for user root May 18 04:35:36 rivo lircd-0.10.1[4116]: Notice: System info: Linux rivo 4.9.241 #2 SMP PREEMPT Sat Dec 14 12:27:06 CET 2024 aarch64 GNU/Linux May 18 04:35:36 rivo volumio[3241]: info: Shairport-Sync Started May 18 04:35:36 rivo volumio[3241]: Error adding Membership: Error: addMembership EINVAL May 18 04:35:36 rivo volumio[3241]: info: Shairport-Sync Started May 18 04:35:36 rivo systemd[1]: Starting /etc/rc.local Compatibility... May 18 04:35:36 rivo ntpd[4114]: ntpd 4.2.8p12@1.3728-o (1): Starting May 18 04:35:36 rivo ntpd[4114]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 May 18 04:35:36 rivo kernel: usb 1-1.4.1: New USB device found, idVendor=2e8a, idProduct=0005 May 18 04:35:36 rivo kernel: usb 1-1.4.1: New USB device strings: Mfr=1, Product=2, SerialNumber=3 May 18 04:35:36 rivo kernel: usb 1-1.4.1: Product: Board in FS mode May 18 04:35:36 rivo kernel: usb 1-1.4.1: Manufacturer: MicroPython May 18 04:35:36 rivo kernel: usb 1-1.4.1: SerialNumber: 1120022408010013 May 18 04:35:36 rivo kernel: cdc_acm 1-1.4.1:1.0: ttyACM0: USB ACM device May 18 04:35:36 rivo volumio[3241]: info: Shairport-Sync Started May 18 04:35:36 rivo systemd[1]: Starting Samba NMB Daemon... May 18 04:35:36 rivo rc.local[4125]: [i] set fix freq 1200000 May 18 04:35:36 rivo ntpd[4127]: proto: precision = 1.250 usec (-20) May 18 04:35:36 rivo systemd[1]: Starting LSB: Brings up/down network automatically... May 18 04:35:36 rivo ntpd[4127]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature May 18 04:35:36 rivo kernel: usb 1-1.4.2: new high-speed USB device number 9 using xhci-hcd May 18 04:35:36 rivo ntpd[4127]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 May 18 04:35:36 rivo ntpd[4127]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 873 days ago May 18 04:35:36 rivo ntpd[4127]: Listen and drop on 0 v6wildcard [::]:123 May 18 04:35:36 rivo ntpd[4127]: Listen and drop on 1 v4wildcard 0.0.0.0:123 May 18 04:35:36 rivo ntpd[4127]: Listen normally on 2 lo 127.0.0.1:123 May 18 04:35:36 rivo ntpd[4127]: Listen normally on 3 eth0 192.168.2.186:123 May 18 04:35:36 rivo ntpd[4127]: Listen normally on 4 wlan0 192.168.2.179:123 May 18 04:35:36 rivo ntpd[4127]: Listening on routing socket on fd #21 for interface updates May 18 04:35:36 rivo ntpd[4127]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized May 18 04:35:36 rivo ntpd[4127]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized May 18 04:35:36 rivo rc.local[4125]: /etc/rc.local: 29: /etc/rc.local: cannot create /proc/irq/42/smp_affinity: Directory nonexistent May 18 04:35:36 rivo systemd[1]: Started Network Time Service. May 18 04:35:36 rivo volumio[3241]: ------------------------------------ BT MESSAGE: BT STATUS: running May 18 04:35:36 rivo systemd[1]: Started Permit User Sessions. May 18 04:35:36 rivo volumio[3241]: ------------------------------------ BT MESSAGE: BT STATUS: running May 18 04:35:36 rivo volumio[3241]: ------------------------------------ BT MESSAGE: BT STATUS: running May 18 04:35:36 rivo volumio[3241]: ------------------------------------ BT MESSAGE: BT STATUS: running May 18 04:35:36 rivo volumio[3241]: ------------------------------------ BT MESSAGE: BT STATUS: running May 18 04:35:36 rivo volumio[3241]: ------------------------------------ BT MESSAGE: BT STATUS: running May 18 04:35:36 rivo volumio[3241]: ------------------------------------ BT MESSAGE: BT STATUS: running May 18 04:35:36 rivo lircd-0.10.1[4116]: Info: Initial device: auto May 18 04:35:36 rivo lircd-0.10.1[4116]: Info: Initial device: auto May 18 04:35:36 rivo lircd-0.10.1[4116]: Notice: Options: driver: devinput May 18 04:35:36 rivo lircd[4116]: lircd-0.10.1[4116]: Info: lircd: Opening log, level: Info May 18 04:35:36 rivo lircd[4116]: lircd-0.10.1[4116]: Notice: Using systemd fd May 18 04:35:36 rivo lircd[4116]: lircd-0.10.1[4116]: Warning: Running as root May 18 04:35:36 rivo lircd-0.10.1[4116]: Notice: Options: output: /var/run/lirc/lircd May 18 04:35:36 rivo lircd-0.10.1[4116]: Notice: Options: nodaemon: 1 May 18 04:35:36 rivo systemd[1]: rc-local.service: Control process exited, code=exited, status=2/INVALIDARGUMENT May 18 04:35:36 rivo lircd-0.10.1[4116]: Notice: Options: plugindir: /usr/lib/arm-linux-gnueabihf/lirc/plugins May 18 04:35:36 rivo systemd[1]: rc-local.service: Failed with result 'exit-code'. May 18 04:35:36 rivo lircd-0.10.1[4116]: Notice: Options: logfile: syslog May 18 04:35:36 rivo lircd-0.10.1[4116]: Notice: Options: immediate-init: 0 May 18 04:35:36 rivo lircd-0.10.1[4116]: Notice: Options: permission: 666 May 18 04:35:36 rivo lircd[4116]: lircd-0.10.1[4116]: Info: Using remote: devinput-64. May 18 04:35:36 rivo lircd-0.10.1[4116]: Notice: Options: driver-options: May 18 04:35:36 rivo lircd-0.10.1[4116]: Notice: Options: pidfile: /var/run/lirc/lircd.pid May 18 04:35:36 rivo systemd[1]: Failed to start /etc/rc.local Compatibility. May 18 04:35:36 rivo lircd-0.10.1[4116]: Notice: Options: listen: 0 May 18 04:35:36 rivo lircd[4116]: lircd-0.10.1[4116]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-64: Multiple values for same code: BTN_MISC May 18 04:35:36 rivo lircd[4116]: lircd-0.10.1[4116]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-64: Multiple values for same code: BTN_MOUSE May 18 04:35:36 rivo lircd[4116]: lircd-0.10.1[4116]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-64: Multiple values for same code: BTN_SOUTH May 18 04:35:36 rivo lircd[4116]: lircd-0.10.1[4116]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-64: Multiple values for same code: BTN_TOOL_PEN May 18 04:35:36 rivo lircd[4116]: lircd-0.10.1[4116]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-64: Multiple values for same code: BTN_TRIGGER May 18 04:35:36 rivo lircd-0.10.1[4116]: Notice: Options: connect: (null) May 18 04:35:36 rivo lircd[4116]: lircd-0.10.1[4116]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-64: Multiple values for same code: BTN_TRIGGER_HAPPY1 May 18 04:35:36 rivo lircd[4116]: lircd-0.10.1[4116]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-64: Multiple values for same code: BTN_WHEEL May 18 04:35:36 rivo lircd-0.10.1[4116]: Notice: Options: userelease: 0 May 18 04:35:36 rivo lircd[4116]: lircd-0.10.1[4116]: Info: Using remote: devinput-32. May 18 04:35:36 rivo lircd-0.10.1[4116]: Notice: Options: effective_user: (null) May 18 04:35:36 rivo lircd-0.10.1[4116]: Notice: Options: release_suffix: _EVUP May 18 04:35:36 rivo lircd-0.10.1[4116]: Notice: Options: allow_simulate: 0 May 18 04:35:36 rivo lircd-0.10.1[4116]: Notice: Options: repeat_max: 600 May 18 04:35:36 rivo lircd-0.10.1[4116]: Notice: Options: configfile: /etc/lirc/lircd.conf May 18 04:35:36 rivo lircd-0.10.1[4116]: Notice: Options: dynamic_codes: (null) May 18 04:35:36 rivo lircd[4116]: lircd-0.10.1[4116]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-32: Multiple values for same code: BTN_MISC May 18 04:35:36 rivo lircd[4116]: lircd-0.10.1[4116]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-32: Multiple values for same code: BTN_MOUSE May 18 04:35:36 rivo lircd[4116]: lircd-0.10.1[4116]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-32: Multiple values for same code: BTN_SOUTH May 18 04:35:36 rivo lircd[4116]: lircd-0.10.1[4116]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-32: Multiple values for same code: BTN_TOOL_PEN May 18 04:35:36 rivo lircd[4116]: lircd-0.10.1[4116]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-32: Multiple values for same code: BTN_TRIGGER May 18 04:35:36 rivo lircd[4116]: lircd-0.10.1[4116]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-32: Multiple values for same code: BTN_TRIGGER_HAPPY1 May 18 04:35:36 rivo lircd[4116]: lircd-0.10.1[4116]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-32: Multiple values for same code: BTN_WHEEL May 18 04:35:36 rivo lircd-0.10.1[4116]: Notice: Current driver: devinput May 18 04:35:36 rivo lircd-0.10.1[4116]: Notice: Driver API version: 4 May 18 04:35:36 rivo lircd-0.10.1[4116]: Notice: Driver version: 0.10.0 May 18 04:35:36 rivo lircd-0.10.1[4116]: Notice: Driver info: See file:///usr/share/doc/lirc/plugindocs/devinput.html May 18 04:35:36 rivo lircd-0.10.1[4116]: Info: lircd: Opening log, level: Info May 18 04:35:36 rivo lircd-0.10.1[4116]: Notice: Using systemd fd May 18 04:35:36 rivo lircd-0.10.1[4116]: Warning: Running as root May 18 04:35:36 rivo lircd-0.10.1[4116]: Info: Using remote: devinput-64. May 18 04:35:36 rivo sudo[4115]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 18 04:35:36 rivo kernel: usb 1-1.4.2: New USB device found, idVendor=16d0, idProduct=0a23 May 18 04:35:36 rivo kernel: usb 1-1.4.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 May 18 04:35:36 rivo kernel: usb 1-1.4.2: Product: Combo384 Amanero May 18 04:35:36 rivo kernel: usb 1-1.4.2: Manufacturer: Amanero Technologies May 18 04:35:36 rivo kernel: usb 1-1.4.2: SerialNumber: 415-001 May 18 04:35:36 rivo kernel: usb 1-1.4.2: Unsupported device May 18 04:35:36 rivo kernel: usb 1-1.4.2: Unsupported device May 18 04:35:36 rivo lircd[4116]: lircd-0.10.1[4116]: Notice: lircd(devinput) ready, using /var/run/lirc/lircd May 18 04:35:36 rivo lircd-0.10.1[4116]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-64: Multiple values for same code: BTN_MISC May 18 04:35:36 rivo sudo[4115]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory May 18 04:35:36 rivo systemd[1]: Started Flexible IR remote input/output application support. May 18 04:35:36 rivo lircd-0.10.1[4116]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-64: Multiple values for same code: BTN_MOUSE May 18 04:35:36 rivo lircd-0.10.1[4116]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-64: Multiple values for same code: BTN_SOUTH May 18 04:35:36 rivo lircd-0.10.1[4116]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-64: Multiple values for same code: BTN_TOOL_PEN May 18 04:35:36 rivo lircd-0.10.1[4116]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-64: Multiple values for same code: BTN_TRIGGER May 18 04:35:36 rivo lircd-0.10.1[4116]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-64: Multiple values for same code: BTN_TRIGGER_HAPPY1 May 18 04:35:36 rivo sudo[4115]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 04:35:36 rivo lircd-0.10.1[4116]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-64: Multiple values for same code: BTN_WHEEL May 18 04:35:36 rivo lircd-0.10.1[4116]: Info: Using remote: devinput-32. May 18 04:35:36 rivo lircd-0.10.1[4116]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-32: Multiple values for same code: BTN_MISC May 18 04:35:36 rivo lircd-0.10.1[4116]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-32: Multiple values for same code: BTN_MOUSE May 18 04:35:36 rivo lircd-0.10.1[4116]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-32: Multiple values for same code: BTN_SOUTH May 18 04:35:36 rivo lircd-0.10.1[4116]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-32: Multiple values for same code: BTN_TOOL_PEN May 18 04:35:36 rivo lircd-0.10.1[4116]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-32: Multiple values for same code: BTN_TRIGGER May 18 04:35:36 rivo lircd-0.10.1[4116]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-32: Multiple values for same code: BTN_TRIGGER_HAPPY1 May 18 04:35:36 rivo lircd-0.10.1[4116]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-32: Multiple values for same code: BTN_WHEEL May 18 04:35:36 rivo sudo[4115]: pam_unix(sudo:session): session closed for user root May 18 04:35:36 rivo lircd-0.10.1[4116]: Notice: lircd(devinput) ready, using /var/run/lirc/lircd May 18 04:35:36 rivo systemd[1]: Starting Terminate Plymouth Boot Screen... May 18 04:35:36 rivo systemd[1]: Starting Hold until boot process finishes up... May 18 04:35:36 rivo volumio[3241]: ------------------------------------ BT MESSAGE: BT STATUS: running May 18 04:35:36 rivo volumio[3241]: ------------------------------------ BT MESSAGE: BT STATUS: running May 18 04:35:36 rivo systemd[1]: Received SIGRTMIN+21 from PID 2450 (plymouthd). May 18 04:35:36 rivo systemd[1]: Received SIGRTMIN+21 from PID 2450 (plymouthd). May 18 04:35:36 rivo systemd[1]: plymouth-quit.service: Succeeded. May 18 04:35:36 rivo systemd[1]: Started Terminate Plymouth Boot Screen. May 18 04:35:36 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 18 04:35:36 rivo systemd[1]: plymouth-quit-wait.service: Succeeded. May 18 04:35:36 rivo kernel: usb 1-1.4.2: Warning! Unlikely big volume range (=4294967295), cval->res is probably wrong. May 18 04:35:36 rivo kernel: usb 1-1.4.2: [10] FU [PCM Playback Volume] ch = 2, val = 1/0/1 May 18 04:35:36 rivo kernel: usb 1-1.4.2: Warning! Unlikely big volume range (=4294967295), cval->res is probably wrong. May 18 04:35:36 rivo kernel: usb 1-1.4.2: [10] FU [PCM Playback Volume] ch = 1, val = 1/0/1 May 18 04:35:36 rivo kernel: usbcore: registered new interface driver snd-usb-audio May 18 04:35:36 rivo systemd[1]: Started Hold until boot process finishes up. May 18 04:35:36 rivo kernel: fb: osd_release now.index=3,open_count=1 May 18 04:35:36 rivo kernel: fb: osd_release now.index=2,open_count=1 May 18 04:35:36 rivo kernel: fb: osd_release now.index=1,open_count=1 May 18 04:35:36 rivo ifplugd(eth0)[4155]: ifplugd 0.28 initializing. May 18 04:35:36 rivo ifplugd(eth0)[4155]: Using interface eth0/02:00:00:08:20:01 with driver (version: Jan_2016) May 18 04:35:36 rivo ifplugd(eth0)[4155]: Using detection mode: SIOCETHTOOL May 18 04:35:36 rivo ifplugd(eth0)[4155]: Initialization complete, link beat detected. May 18 04:35:36 rivo systemd[1]: Started Serial Getty on ttyS0. May 18 04:35:36 rivo systemd[1]: Reached target Login Prompts. May 18 04:35:36 rivo volumio[3241]: info: May 18 04:35:36 rivo volumio[3241]: ---------------------------- USB Audio Device Attached May 18 04:35:36 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach May 18 04:35:36 rivo ifplugd(eth0)[4155]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. May 18 04:35:36 rivo volumio[3241]: No protocol specified May 18 04:35:36 rivo volumio[3241]: xcb_connection_has_error() returned true May 18 04:35:36 rivo ifplugd(eth0)[4155]: client: ifup: interface eth0 already configured May 18 04:35:36 rivo volumio[3241]: info: CoreCommandRouter::Close All Modals sent May 18 04:35:36 rivo volumio[3241]: info: Preparing to save Alsa Options, stopping services first May 18 04:35:36 rivo volumio[3241]: info: CoreCommandRouter::volumioGetState May 18 04:35:36 rivo volumio[3241]: info: CorePlayQueue::getTrack 0 May 18 04:35:36 rivo volumio[3241]: info: CoreCommandRouter::volumioPause May 18 04:35:36 rivo volumio[3241]: info: CoreStateMachine::pause May 18 04:35:36 rivo volumio[3241]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"Combo384 Amanero","alsacard":"Amanero"},"i2s":false} May 18 04:35:36 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 18 04:35:36 rivo volumio[3241]: No protocol specified May 18 04:35:36 rivo volumio[3241]: xcb_connection_has_error() returned true May 18 04:35:36 rivo nmbd[4139]: [2025/05/18 04:35:36.957779, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) May 18 04:35:36 rivo kernel: nf_conntrack: default automatic helper assignment has been turned off for security reasons and CT-based firewall rule not found. Use the iptables CT target to attach helpers instead. May 18 04:35:36 rivo ifplugd[4130]: Network Interface Plugging Daemon...start eth0...done. May 18 04:35:36 rivo systemd[1]: Started LSB: Brings up/down network automatically. May 18 04:35:36 rivo nmbd[4139]: started asyncdns process 4169 May 18 04:35:36 rivo ifplugd(eth0)[4155]: Program executed successfully. May 18 04:35:36 rivo nmbd[4139]: [2025/05/18 04:35:36.965750, 0] ../lib/util/become_daemon.c:138(daemon_ready) May 18 04:35:36 rivo nmbd[4139]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections May 18 04:35:36 rivo systemd[1]: Started Samba NMB Daemon. May 18 04:35:36 rivo systemd[1]: Starting Samba Winbind Daemon... May 18 04:35:37 rivo volumio[3241]: No protocol specified May 18 04:35:37 rivo volumio[3241]: xcb_connection_has_error() returned true May 18 04:35:37 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 18 04:35:37 rivo ntpd[4127]: Soliciting pool server 185.41.106.152 May 18 04:35:37 rivo volumio[3241]: No protocol specified May 18 04:35:37 rivo volumio[3241]: xcb_connection_has_error() returned true May 18 04:35:37 rivo volumio[3241]: info: Setting mixer PCM for card Combo384 Amanero May 18 04:35:37 rivo volumio[3241]: info: QobuzConnect: setDeactiveState invoked May 18 04:35:37 rivo volumio[3241]: info: CoreCommandRouter::volumioGetState May 18 04:35:37 rivo volumio[3241]: info: CorePlayQueue::getTrack 0 May 18 04:35:37 rivo volumio[3241]: info: Reconfiguring and Restarting RAAT Plugin due to audio path changes May 18 04:35:37 rivo vtcs[4010]: [2025-05-18 04:35:37.158] [tisoc] [warning] [SessionManagerImpl.cpp:243] Illegal State: IDLE May 18 04:35:37 rivo vtcs[4010]: [2025-05-18 04:35:37.160] [tisoc] [error] [SpkconServer.cpp:381] recv error. client fd=8 errorno=104 error=Connection reset by peer May 18 04:35:37 rivo vtcs[4010]: [2025-05-18 04:35:37.161] [tisoc] [error] [SpkconServer.cpp:376] recv error. socket disconnected May 18 04:35:37 rivo sudo[4177]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 18 04:35:37 rivo sudo[4177]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 04:35:37 rivo systemd[1]: Stopping Volumio Tidal Connect Service... May 18 04:35:37 rivo systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM May 18 04:35:37 rivo systemd[1]: vtcs.service: Succeeded. May 18 04:35:37 rivo systemd[1]: Stopped Volumio Tidal Connect Service. May 18 04:35:37 rivo volumio[3241]: No protocol specified May 18 04:35:37 rivo volumio[3241]: xcb_connection_has_error() returned true May 18 04:35:37 rivo volumio[3241]: info: CoreCommandRouter::volumioUpdateVolumeSettings May 18 04:35:37 rivo volumio[3241]: info: Updating Volume Controller Parameters: Device: 5 Name: Combo384 Amanero Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 May 18 04:35:37 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume May 18 04:35:37 rivo volumio[3241]: info: Disabling external Volume Control May 18 04:35:37 rivo sudo[4177]: pam_unix(sudo:session): session closed for user root May 18 04:35:37 rivo volumio[3241]: info: CoreCommandRouter::getUIConfigOnPlugin May 18 04:35:37 rivo volumio[3241]: info: Preparing to generate the ALSA configuration file May 18 04:35:37 rivo winbindd[4172]: [2025/05/18 04:35:37.430882, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) May 18 04:35:37 rivo winbindd[4172]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 May 18 04:35:37 rivo winbindd[4172]: [2025/05/18 04:35:37.448153, 0] ../lib/util/become_daemon.c:138(daemon_ready) May 18 04:35:37 rivo systemd[1]: Started Samba Winbind Daemon. May 18 04:35:37 rivo winbindd[4172]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections May 18 04:35:37 rivo systemd[1]: Starting Samba SMB Daemon... May 18 04:35:37 rivo sudo[4185]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 18 04:35:37 rivo sudo[4185]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 04:35:37 rivo sudo[4185]: pam_unix(sudo:session): session closed for user root May 18 04:35:37 rivo volumio[3241]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf May 18 04:35:37 rivo volumio[3241]: info: Reading ALSA contributions from plugins. May 18 04:35:37 rivo volumio[3241]: ------------------------------------ BT MESSAGE: BT STATUS: running May 18 04:35:37 rivo sudo[4195]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service May 18 04:35:37 rivo sudo[4195]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 04:35:37 rivo volumio[3241]: ------------------------------------ BT MESSAGE: BT STATUS: running May 18 04:35:37 rivo volumio[3241]: ------------------------------------ BT MESSAGE: BT STATUS: running May 18 04:35:37 rivo sudo[4195]: pam_unix(sudo:session): session closed for user root May 18 04:35:37 rivo volumio[3241]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb May 18 04:35:37 rivo volumio[3241]: verbose: ControllerMpd::sendMpdCommand rescan May 18 04:35:37 rivo volumio[3241]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 18 04:35:37 rivo volumio[3241]: TypeError: Cannot read property 'then' of undefined May 18 04:35:37 rivo volumio[3241]: at ControllerMpd.sendMpdCommand (/volumio/app/plugins/music_service/mpd/index.js:332:6) May 18 04:35:37 rivo volumio[3241]: at ControllerMpd.rescanDb (/volumio/app/plugins/music_service/mpd/index.js:1886:15) May 18 04:35:37 rivo volumio[3241]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1096:32) May 18 04:35:37 rivo volumio[3241]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1087:26) May 18 04:35:37 rivo volumio[3241]: at Socket.emit (events.js:400:28) May 18 04:35:37 rivo volumio[3241]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 May 18 04:35:37 rivo volumio[3241]: at processTicksAndRejections (internal/process/task_queues.js:77:11) May 18 04:35:37 rivo volumio[3241]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 18 04:35:37 rivo sudo[4197]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service May 18 04:35:37 rivo sudo[4197]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 04:35:37 rivo systemd[1]: Stopping Volumio Qobuz Connect Service... May 18 04:35:37 rivo qobuz-connect[3956]: 20250518 04:35:37.714 [3956.3956] INFO SampleApp: Stopping Local configuration server May 18 04:35:37 rivo qobuz-connect[3956]: 20250518 04:35:37.726 [3956.3956] INFO SampleApp: shat down connection on UNIX socket May 18 04:35:37 rivo systemd[1]: qobuz-connect.service: Succeeded. May 18 04:35:37 rivo systemd[1]: Stopped Volumio Qobuz Connect Service. May 18 04:35:37 rivo systemd[1]: Started Volumio Qobuz Connect Service. May 18 04:35:37 rivo sudo[4197]: pam_unix(sudo:session): session closed for user root May 18 04:35:38 rivo ntpd[4127]: Soliciting pool server 148.251.5.46 May 18 04:35:38 rivo ntpd[4127]: Soliciting pool server 49.13.14.46 May 18 04:35:38 rivo smbd[4192]: [2025/05/18 04:35:38.224455, 0] ../lib/util/become_daemon.c:138(daemon_ready) May 18 04:35:38 rivo systemd[1]: Started Samba SMB Daemon. May 18 04:35:38 rivo smbd[4192]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections May 18 04:35:38 rivo systemd[1]: Reached target Multi-User System. May 18 04:35:38 rivo systemd[1]: Reached target Graphical Interface. May 18 04:35:38 rivo systemd[1]: Starting Update UTMP about System Runlevel Changes... May 18 04:35:38 rivo systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. May 18 04:35:38 rivo systemd[1]: Started Update UTMP about System Runlevel Changes. May 18 04:35:38 rivo sudo[4218]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-18 04:34 May 18 04:35:38 rivo sudo[4218]: 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="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 06:24:39 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="c8a59422b1bfedffc72f72cef58ad20b"