-- Logs begin at Wed 2024-10-30 02:31:42 EDT, end at Wed 2024-10-30 02:33:35 EDT. -- Oct 30 02:32:00 rivo wireless.js[2850]: trying... Oct 30 02:32:00 rivo sudo[3210]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 30 02:32:00 rivo sudo[3210]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 02:32:00 rivo sudo[3210]: pam_unix(sudo:session): session closed for user root Oct 30 02:32:01 rivo volumio-remote-updater[2840]: [2024-10-30 02:32:01] [info] asio async_connect error: system:111 (Connection refused) Oct 30 02:32:01 rivo volumio-remote-updater[2840]: [2024-10-30 02:32:01] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Oct 30 02:32:01 rivo volumio-remote-updater[2840]: [2024-10-30 02:32:01] [error] handle_connect error: Underlying Transport Error Oct 30 02:32:01 rivo wireless.js[2850]: trying... Oct 30 02:32:01 rivo sudo[3213]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 30 02:32:01 rivo sudo[3213]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 02:32:01 rivo sudo[3213]: pam_unix(sudo:session): session closed for user root Oct 30 02:32:02 rivo dhcpcd[2845]: wlan0: leased 192.168.0.118 for 7200 seconds Oct 30 02:32:02 rivo dhcpcd[2845]: wlan0: leased 192.168.0.118 for 7200 seconds Oct 30 02:32:02 rivo avahi-daemon[2843]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.118. Oct 30 02:32:02 rivo dhcpcd[2845]: wlan0: adding route to 192.168.0.0/24 Oct 30 02:32:02 rivo dhcpcd[2845]: wlan0: adding default route via 192.168.0.1 Oct 30 02:32:02 rivo dhcpcd[2845]: wlan0: adding route to 192.168.0.0/24 Oct 30 02:32:02 rivo dhcpcd[2845]: wlan0: adding default route via 192.168.0.1 Oct 30 02:32:02 rivo avahi-daemon[2843]: New relevant interface wlan0.IPv4 for mDNS. Oct 30 02:32:02 rivo avahi-daemon[2843]: Registering new address record for 192.168.0.118 on wlan0.IPv4. Oct 30 02:32:02 rivo ntpd[3061]: ntpd exiting on signal 15 (Terminated) Oct 30 02:32:02 rivo systemd[1]: Stopping Network Time Service... Oct 30 02:32:02 rivo systemd[1]: ntp.service: Succeeded. Oct 30 02:32:02 rivo systemd[1]: Stopped Network Time Service. Oct 30 02:32:02 rivo systemd[1]: Starting Network Time Service... Oct 30 02:32:02 rivo ntpd[3249]: ntpd 4.2.8p12@1.3728-o (1): Starting Oct 30 02:32:02 rivo ntpd[3249]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Oct 30 02:32:02 rivo systemd[1]: Started Network Time Service. Oct 30 02:32:02 rivo ntpd[3260]: proto: precision = 1.250 usec (-20) Oct 30 02:32:02 rivo ntpd[3260]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Oct 30 02:32:02 rivo ntpd[3260]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Oct 30 02:32:02 rivo ntpd[3260]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 673 days ago Oct 30 02:32:02 rivo ntpd[3260]: Listen and drop on 0 v6wildcard [::]:123 Oct 30 02:32:02 rivo ntpd[3260]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Oct 30 02:32:02 rivo ntpd[3260]: Listen normally on 2 lo 127.0.0.1:123 Oct 30 02:32:02 rivo ntpd[3260]: Listen normally on 3 wlan0 192.168.0.118:123 Oct 30 02:32:02 rivo ntpd[3260]: Listening on routing socket on fd #20 for interface updates Oct 30 02:32:02 rivo ntpd[3260]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Oct 30 02:32:02 rivo ntpd[3260]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Oct 30 02:32:02 rivo dhcpcd[2845]: forked to background, child pid 3261 Oct 30 02:32:02 rivo dhcpcd[2845]: forked to background, child pid 3261 Oct 30 02:32:02 rivo wireless.js[2850]: trying... Oct 30 02:32:02 rivo systemd[1]: Started DHCP Client Daemon. Oct 30 02:32:02 rivo sudo[3264]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 30 02:32:02 rivo sudo[3264]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 02:32:02 rivo sudo[3264]: pam_unix(sudo:session): session closed for user root Oct 30 02:32:02 rivo wireless.js[2850]: Connected to: ----GENESIS 5G Oct 30 02:32:02 rivo wireless.js[2850]: ---- Oct 30 02:32:02 rivo sudo[3267]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 30 02:32:02 rivo sudo[3267]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 02:32:02 rivo sudo[3267]: pam_unix(sudo:session): session closed for user root Oct 30 02:32:02 rivo wireless.js[2850]: ... joined AP, wlan0 IPv4 is 192.168.0.118, ipV6 is undefined Oct 30 02:32:02 rivo wireless.js[2850]: It's done! AP Oct 30 02:32:02 rivo systemd[1]: systemd-rfkill.service: Succeeded. Oct 30 02:32:02 rivo systemd[1]: Started Wireless Services. Oct 30 02:32:02 rivo systemd[1]: Started Volumio Backend Module. Oct 30 02:32:02 rivo systemd[1]: Started Volumio Cpu Tweaker. Oct 30 02:32:02 rivo systemd[1]: Started Start Volumio Kiosk. Oct 30 02:32:03 rivo startkiosk.sh[3275]: Starting Volumio Kiosk with arguments for cursor display Oct 30 02:32:03 rivo volumio-cpu-tweak[3274]: Setting RT Priority for mpd Oct 30 02:32:03 rivo volumio-cpu-tweak[3274]: chrt: cannot obtain the list of tasks: No such file or directory Oct 30 02:32:03 rivo volumio-cpu-tweak[3274]: Setting MPD Affinity Oct 30 02:32:03 rivo volumio-cpu-tweak[3274]: pid 3's current affinity mask: 1 Oct 30 02:32:03 rivo volumio-cpu-tweak[3274]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Oct 30 02:32:03 rivo volumio-cpu-tweak[3274]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Oct 30 02:32:03 rivo systemd[1]: volumio_cpu_tweak.service: Succeeded. Oct 30 02:32:03 rivo startkiosk.sh[3275]: X.Org X Server 1.20.4 Oct 30 02:32:03 rivo startkiosk.sh[3275]: X Protocol Version 11, Revision 0 Oct 30 02:32:03 rivo startkiosk.sh[3275]: Build Operating System: Linux 4.19.0-18-armmp-lpae armv7l Debian Oct 30 02:32:03 rivo startkiosk.sh[3275]: Current Operating System: Linux rivo 4.9.241 #27 SMP PREEMPT Mon Sep 16 18:15:31 CEST 2024 aarch64 Oct 30 02:32:03 rivo startkiosk.sh[3275]: Kernel command line: imgpart=UUID=a14debb3-ecf2-4f34-b3d3-9f420cb24429 imgfile=/volumio_current.sqsh bootpart=UUID=0051-596A datapart=UUID=87b75d6c-c3c7-4e6a-a19c-b2d8021957da uuidconfig=env.system.txt hwdevice=VIMX console=tty0 console=ttyS0,115200n8 no_console_suspend consoleblank=0 logo=osd0,loaded,0x3d800000,1080p60hz vout=1080p60hz,enable hdmimode=1080p60hz fsck.repair=yes net.ifnames=0 ddr_size= wol_enable= mac=02:00:00:1e:36:01 fan=auto hwver=Unknow coherent_pool=2M quiet loglevel=0 splash plymouth.ignore-serial-consoles initramfs.clear splash bootdelay=7 Oct 30 02:32:03 rivo startkiosk.sh[3275]: Build Date: 18 December 2021 09:05:36AM Oct 30 02:32:03 rivo startkiosk.sh[3275]: xorg-server 2:1.20.4-1+deb10u4 (https://www.debian.org/support) Oct 30 02:32:03 rivo startkiosk.sh[3275]: Current version of pixman: 0.36.0 Oct 30 02:32:03 rivo startkiosk.sh[3275]: Before reporting problems, check http://wiki.x.org Oct 30 02:32:03 rivo startkiosk.sh[3275]: to make sure that you have the latest version. Oct 30 02:32:03 rivo startkiosk.sh[3275]: Markers: (--) probed, (**) from config file, (==) default setting, Oct 30 02:32:03 rivo startkiosk.sh[3275]: (++) from command line, (!!) notice, (II) informational, Oct 30 02:32:03 rivo startkiosk.sh[3275]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Oct 30 02:32:03 rivo startkiosk.sh[3275]: (==) Log file: "/var/log/Xorg.0.log", Time: Wed Oct 30 02:32:03 2024 Oct 30 02:32:03 rivo startkiosk.sh[3275]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Oct 30 02:32:03 rivo kernel: fb: osd[0] canvas.idx =0x40 Oct 30 02:32:03 rivo kernel: fb: osd[0] canvas.addr=0x70000000 Oct 30 02:32:03 rivo kernel: fb: osd[0] canvas.width=7680 Oct 30 02:32:03 rivo kernel: fb: osd[0] canvas.height=1080 Oct 30 02:32:03 rivo kernel: fb: osd[0] frame.width=1920 Oct 30 02:32:03 rivo kernel: fb: osd[0] frame.height=1080 Oct 30 02:32:03 rivo kernel: fb: osd[0] out_addr_id =0x1 Oct 30 02:32:03 rivo kernel: fb: osd[0] enable: 0 (Xorg) Oct 30 02:32:03 rivo ntpd[3260]: Soliciting pool server 162.159.200.123 Oct 30 02:32:04 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. Oct 30 02:32:04 rivo systemd[1]: Started Samba NMB Daemon. Oct 30 02:32:04 rivo nmbd[3052]: [2024/10/30 02:32:04.036110, 0] ../lib/util/become_daemon.c:138(daemon_ready) Oct 30 02:32:04 rivo nmbd[3052]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Oct 30 02:32:04 rivo systemd[1]: Starting Samba Winbind Daemon... Oct 30 02:32:04 rivo winbindd[3317]: [2024/10/30 02:32:04.447814, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Oct 30 02:32:04 rivo winbindd[3317]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Oct 30 02:32:04 rivo winbindd[3317]: [2024/10/30 02:32:04.463512, 0] ../lib/util/become_daemon.c:138(daemon_ready) Oct 30 02:32:04 rivo systemd[1]: Started Samba Winbind Daemon. Oct 30 02:32:04 rivo winbindd[3317]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Oct 30 02:32:04 rivo systemd[1]: Starting Samba SMB Daemon... Oct 30 02:32:04 rivo ntpd[3260]: Soliciting pool server 142.202.190.19 Oct 30 02:32:04 rivo ntpd[3260]: Soliciting pool server 199.101.96.52 Oct 30 02:32:04 rivo kernel: fb: osd[0] enable: 1 (Xorg) Oct 30 02:32:05 rivo systemd[1]: Started Samba SMB Daemon. Oct 30 02:32:05 rivo systemd[1]: Reached target Multi-User System. Oct 30 02:32:05 rivo systemd[1]: Reached target Graphical Interface. Oct 30 02:32:05 rivo systemd[1]: Starting Update UTMP about System Runlevel Changes... Oct 30 02:32:05 rivo smbd[3328]: [2024/10/30 02:32:05.100952, 0] ../lib/util/become_daemon.c:138(daemon_ready) Oct 30 02:32:05 rivo smbd[3328]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Oct 30 02:32:05 rivo systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Oct 30 02:32:05 rivo systemd[1]: Started Update UTMP about System Runlevel Changes. Oct 30 02:32:05 rivo systemd[1]: Startup finished in 13.110s (kernel) + 22.842s (userspace) = 35.953s. Oct 30 02:32:05 rivo kernel: fb: osd[0] enable: 1 (Xorg) Oct 30 02:32:05 rivo ntpd[3260]: Soliciting pool server 217.180.209.214 Oct 30 02:32:05 rivo ntpd[3260]: Soliciting pool server 23.131.160.7 Oct 30 02:32:05 rivo ntpd[3260]: Soliciting pool server 167.160.92.107 Oct 30 02:32:06 rivo volumio[3273]: info: ------------------------------------------- Oct 30 02:32:06 rivo volumio[3273]: info: ----- Volumio3 ---- Oct 30 02:32:06 rivo volumio[3273]: info: ------------------------------------------- Oct 30 02:32:06 rivo volumio[3273]: info: ----- System startup ---- Oct 30 02:32:06 rivo volumio[3273]: info: ------------------------------------------- Oct 30 02:32:06 rivo ntpd[3260]: Soliciting pool server 50.205.57.38 Oct 30 02:32:06 rivo ntpd[3260]: Soliciting pool server 51.81.226.229 Oct 30 02:32:06 rivo ntpd[3260]: Soliciting pool server 64.142.54.12 Oct 30 02:32:06 rivo ntpd[3260]: Soliciting pool server 198.71.50.75 Oct 30 02:32:07 rivo volumio[3273]: info: MYVOLUMIO Environment detected Oct 30 02:32:07 rivo ntpd[3260]: Soliciting pool server 129.146.193.200 Oct 30 02:32:07 rivo ntpd[3260]: Soliciting pool server 66.118.230.14 Oct 30 02:32:07 rivo ntpd[3260]: Soliciting pool server 71.123.46.185 Oct 30 02:32:07 rivo volumio[3273]: info: Plugin folders cleanup Oct 30 02:32:07 rivo volumio[3273]: info: Scanning into folder /volumio/app/plugins/ Oct 30 02:32:07 rivo volumio[3273]: info: Scanning category audio_interface Oct 30 02:32:07 rivo volumio[3273]: info: Scanning category miscellanea Oct 30 02:32:07 rivo volumio[3273]: info: Scanning category music_service Oct 30 02:32:07 rivo volumio[3273]: info: Scanning category plugins.json Oct 30 02:32:07 rivo volumio[3273]: info: Scanning category system_controller Oct 30 02:32:07 rivo volumio[3273]: info: Scanning category user_interface Oct 30 02:32:07 rivo volumio[3273]: info: Scanning into folder /data/plugins/ Oct 30 02:32:07 rivo volumio[3273]: info: Scanning category music_service Oct 30 02:32:07 rivo volumio[3273]: info: Plugin folders cleanup completed Oct 30 02:32:07 rivo volumio[3273]: info: ------------------------------------------- Oct 30 02:32:07 rivo volumio[3273]: info: ----- Core plugins startup ---- Oct 30 02:32:07 rivo volumio[3273]: info: ------------------------------------------- Oct 30 02:32:07 rivo volumio[3273]: info: Loading plugins from folder /volumio/app/plugins/ Oct 30 02:32:07 rivo volumio[3273]: info: Adding plugin bluetooth to MyMusic Plugins Oct 30 02:32:07 rivo volumio[3273]: info: Adding plugin multiroom to MyMusic Plugins Oct 30 02:32:07 rivo volumio[3273]: info: Adding plugin upnp to MyMusic Plugins Oct 30 02:32:07 rivo volumio[3273]: info: Adding plugin metavolumio to MyMusic Plugins Oct 30 02:32:07 rivo volumio[3273]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 30 02:32:07 rivo volumio[3273]: info: Adding plugin cd_controller to MyMusic Plugins Oct 30 02:32:07 rivo volumio[3273]: info: Adding plugin raat to MyMusic Plugins Oct 30 02:32:07 rivo volumio[3273]: info: Adding plugin smart_inputs to MyMusic Plugins Oct 30 02:32:07 rivo volumio[3273]: info: Adding plugin tidalconnect to MyMusic Plugins Oct 30 02:32:07 rivo volumio[3273]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 30 02:32:07 rivo ntpd[3260]: Soliciting pool server 167.248.62.201 Oct 30 02:32:07 rivo volumio[3273]: info: Loading plugins from folder /data/plugins/ Oct 30 02:32:07 rivo volumio[3273]: info: Loading plugin "system"... Oct 30 02:32:07 rivo volumio[3273]: info: Loading plugin "appearance"... Oct 30 02:32:08 rivo ntpd[3260]: Soliciting pool server 173.71.68.71 Oct 30 02:32:08 rivo ntpd[3260]: Soliciting pool server 23.94.221.138 Oct 30 02:32:08 rivo ntpd[3260]: Soliciting pool server 15.204.87.223 Oct 30 02:32:08 rivo ntpd[3260]: Soliciting pool server 216.229.0.50 Oct 30 02:32:09 rivo volumio[3273]: info: Loading plugin "network"... Oct 30 02:32:09 rivo volumio[3273]: info: Refreshing Cached IP Addresses Oct 30 02:32:09 rivo sudo[3405]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 30 02:32:09 rivo sudo[3405]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 02:32:09 rivo volumio[3273]: info: Loading plugin "services"... Oct 30 02:32:09 rivo sudo[3407]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 30 02:32:09 rivo sudo[3407]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 02:32:09 rivo volumio[3273]: info: Loading plugin "alsa_controller"... Oct 30 02:32:09 rivo sudo[3405]: pam_unix(sudo:session): session closed for user root Oct 30 02:32:09 rivo sudo[3407]: pam_unix(sudo:session): session closed for user root Oct 30 02:32:09 rivo sudo[3412]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 30 02:32:09 rivo sudo[3412]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 02:32:09 rivo kernel: wl_escan_set_scan: ioctl_ver=2 Oct 30 02:32:09 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f76f000 drv_status 0x0 e_type 69 e_states 8 Oct 30 02:32:09 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f76f000 drv_status 0x0 e_type 69 e_states 8 Oct 30 02:32:09 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f76f000 drv_status 0x0 e_type 69 e_states 8 Oct 30 02:32:09 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f76f000 drv_status 0x0 e_type 69 e_states 8 Oct 30 02:32:09 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 30 02:32:09 rivo volumio[3273]: info: Loading plugin "wizard"... Oct 30 02:32:09 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f76f000 drv_status 0x0 e_type 69 e_states 8 Oct 30 02:32:09 rivo volumio[3273]: info: Loading plugin "networkfs"... Oct 30 02:32:09 rivo volumio[3273]: info: Starting Udev Watcher for removable devices Oct 30 02:32:09 rivo sudo[3421]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.153/NAS /mnt/NAS/NAS Oct 30 02:32:09 rivo sudo[3421]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 02:32:10 rivo sudo[3423]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t nfs -o ro,soft,noauto 192.168.0.223:Dell-XPS\Tidabie Tidal Music /mnt/NAS/Dell Oct 30 02:32:10 rivo sudo[3423]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 02:32:10 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f76f000 drv_status 0x0 e_type 69 e_states 8 Oct 30 02:32:10 rivo volumio[3273]: info: Mounting Device C8CD-1239 Oct 30 02:32:10 rivo kernel: FS-Cache: Netfs 'nfs' registered for caching Oct 30 02:32:10 rivo sudo[3445]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/C8CD-1239 -o noatime Oct 30 02:32:10 rivo sudo[3445]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 02:32:10 rivo kernel: NFS: Registering the id_resolver key type Oct 30 02:32:10 rivo kernel: Key type id_resolver registered Oct 30 02:32:10 rivo kernel: Key type id_legacy registered Oct 30 02:32:10 rivo sudo[3421]: pam_unix(sudo:session): session closed for user root Oct 30 02:32:10 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f76f000 drv_status 0x0 e_type 69 e_states 8 Oct 30 02:32:10 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f76f000 drv_status 0x0 e_type 69 e_states 8 Oct 30 02:32:10 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f76f000 drv_status 0x0 e_type 69 e_states 8 Oct 30 02:32:10 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f76f000 drv_status 0x0 e_type 69 e_states 8 Oct 30 02:32:10 rivo mount.exfat[3450]: volume was not unmounted cleanly Oct 30 02:32:10 rivo sudo[3445]: pam_unix(sudo:session): session closed for user root Oct 30 02:32:10 rivo volumio[3273]: WARN: volume was not unmounted cleanly. Oct 30 02:32:10 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f76f000 drv_status 0x0 e_type 69 e_states 8 Oct 30 02:32:10 rivo volumio[3273]: info: Ignoring mount for partition: BOOT Oct 30 02:32:10 rivo volumio[3273]: info: Ignoring mount for partition: volumio Oct 30 02:32:10 rivo volumio[3273]: info: Ignoring mount for partition: volumio_data Oct 30 02:32:10 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 30 02:32:10 rivo volumio[3273]: info: Loading plugin "volumio_command_line_client"... Oct 30 02:32:10 rivo volumio[3273]: info: Loading plugin "upnp"... Oct 30 02:32:10 rivo volumio[3273]: info: [1730269930596] Starting Upmpd Daemon Oct 30 02:32:10 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 30 02:32:10 rivo volumio[3273]: info: Loading plugin "my_music"... Oct 30 02:32:10 rivo volumio[3273]: info: Loading plugin "mpd"... Oct 30 02:32:10 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f76f000 drv_status 0x0 e_type 69 e_states 8 Oct 30 02:32:10 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f76f000 drv_status 0x0 e_type 69 e_states 8 Oct 30 02:32:10 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f76f000 drv_status 0x0 e_type 69 e_states 8 Oct 30 02:32:10 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f76f000 drv_status 0x0 e_type 69 e_states 8 Oct 30 02:32:10 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f76f000 drv_status 0x0 e_type 69 e_states 8 Oct 30 02:32:10 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f76f000 drv_status 0x0 e_type 69 e_states 8 Oct 30 02:32:10 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f76f000 drv_status 0x0 e_type 69 e_states 8 Oct 30 02:32:10 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f76f000 drv_status 0x0 e_type 69 e_states 8 Oct 30 02:32:10 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f76f000 drv_status 0x0 e_type 69 e_states 8 Oct 30 02:32:10 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f76f000 drv_status 0x0 e_type 69 e_states 8 Oct 30 02:32:10 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f76f000 drv_status 0x0 e_type 69 e_states 8 Oct 30 02:32:11 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f76f000 drv_status 0x0 e_type 69 e_states 8 Oct 30 02:32:11 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f76f000 drv_status 0x0 e_type 69 e_states 8 Oct 30 02:32:11 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f76f000 drv_status 0x0 e_type 69 e_states 8 Oct 30 02:32:11 rivo volumio[3273]: info: Loading plugin "upnp_browser"... Oct 30 02:32:11 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f76f000 drv_status 0x0 e_type 69 e_states 8 Oct 30 02:32:11 rivo volumio-remote-updater[2840]: [2024-10-30 02:32:11] [connect] Successful connection Oct 30 02:32:11 rivo volumio[3273]: info: Loading plugin "bluetooth"... Oct 30 02:32:11 rivo ntpd[3260]: receive: Unexpected origin timestamp 0xeacc516b.c44a6b32 does not match aorg 0000000000.00000000 from server@167.160.92.107 xmt 0xeacc516b.aca87900 Oct 30 02:32:11 rivo ntpd[3260]: receive: Unexpected origin timestamp 0xeacc516b.c44cc2a1 does not match aorg 0000000000.00000000 from server@217.180.209.214 xmt 0xeacc516b.adb3c4a5 Oct 30 02:32:11 rivo volumio[3273]: info: [1730269931811] Starting BluetoothController Oct 30 02:32:11 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f76f000 drv_status 0x0 e_type 69 e_states 8 Oct 30 02:32:11 rivo ntpd[3260]: receive: Unexpected origin timestamp 0xeacc516b.c4436c0e does not match aorg 0000000000.00000000 from server@198.71.50.75 xmt 0xeacc516b.b04f10e8 Oct 30 02:32:11 rivo ntpd[3260]: receive: Unexpected origin timestamp 0xeacc516b.c42ec59b does not match aorg 0000000000.00000000 from server@216.229.0.50 xmt 0xeacc516b.b11ec062 Oct 30 02:32:11 rivo ntpd[3260]: receive: Unexpected origin timestamp 0xeacc516b.c4390ee5 does not match aorg 0000000000.00000000 from server@15.204.87.223 xmt 0xeacc516b.b0d5e4d6 Oct 30 02:32:11 rivo ntpd[3260]: receive: Unexpected origin timestamp 0xeacc516b.c4480b96 does not match aorg 0000000000.00000000 from server@64.142.54.12 xmt 0xeacc516b.b6a1b268 Oct 30 02:32:11 rivo ntpd[3260]: receive: Unexpected origin timestamp 0xeacc516b.c445b30e does not match aorg 0000000000.00000000 from server@51.81.226.229 xmt 0xeacc516b.b523cfcf Oct 30 02:32:11 rivo ntpd[3260]: receive: Unexpected origin timestamp 0xeacc516b.c44091bc does not match aorg 0000000000.00000000 from server@129.146.193.200 xmt 0xeacc516b.b6180f1f Oct 30 02:32:11 rivo ntpd[3260]: receive: Unexpected origin timestamp 0xeacc516b.c43c76c9 does not match aorg 0000000000.00000000 from server@66.118.230.14 xmt 0xeacc516b.c4907fa8 Oct 30 02:32:11 rivo volumio[3273]: info: Loading plugin "alarm-clock"... Oct 30 02:32:12 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f76f000 drv_status 0x0 e_type 69 e_states 8 Oct 30 02:32:12 rivo volumio[3273]: info: Loading plugin "manifestui"... Oct 30 02:32:12 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f76f000 drv_status 0x0 e_type 69 e_states 8 Oct 30 02:32:12 rivo volumio[3273]: info: Loading plugin "metavolumio"... Oct 30 02:32:13 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f76f000 drv_status 0x0 e_type 69 e_states 8 Oct 30 02:32:13 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f76f000 drv_status 0x0 e_type 69 e_states 8 Oct 30 02:32:13 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f76f000 drv_status 0x0 e_type 69 e_states 8 Oct 30 02:32:13 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f76f000 drv_status 0x0 e_type 69 e_states 0 Oct 30 02:32:14 rivo sudo[3412]: pam_unix(sudo:session): session closed for user root Oct 30 02:32:14 rivo volumio[3273]: info: Loading plugin "airplay_emulation"... Oct 30 02:32:14 rivo volumio[3273]: info: Starting Shairport Sync Oct 30 02:32:14 rivo volumio[3273]: info: Loading plugin "cd_controller"... Oct 30 02:32:14 rivo volumio[3273]: info: Loading plugin "last_100"... Oct 30 02:32:14 rivo volumio[3273]: info: Loading plugin "raat"... Oct 30 02:32:15 rivo volumio[3273]: info: RAAT Plugin loaded Oct 30 02:32:15 rivo volumio[3273]: info: Adding restartRAATSocket REST API Endpoint Oct 30 02:32:15 rivo volumio[3273]: info: Adding restartRAATSocket REST Endpoint for plugin: music_service/raat Oct 30 02:32:15 rivo volumio[3273]: info: Loading plugin "streaming_services"... Oct 30 02:32:15 rivo systemd[1]: systemd-fsckd.service: Succeeded. Oct 30 02:32:17 rivo volumio[3273]: info: Starting Streaming Service Transparent Proxy Oct 30 02:32:17 rivo volumio[3273]: info: Loading plugin "tidalconnect"... Oct 30 02:32:17 rivo sh[2773]: timed out Oct 30 02:32:17 rivo sh[2773]: dhcpcd exited Oct 30 02:32:17 rivo dhcpcd[2822]: timed out Oct 30 02:32:17 rivo dhcpcd[2822]: dhcpcd exited Oct 30 02:32:17 rivo sh[2773]: ifup: failed to bring up eth0 Oct 30 02:32:17 rivo systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Oct 30 02:32:17 rivo systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Oct 30 02:32:18 rivo volumio[3273]: info: Loading plugin "webradio"... Oct 30 02:32:18 rivo volumio[3273]: info: Loading plugin "i2s_dacs"... Oct 30 02:32:18 rivo volumio[3273]: info: I2S DAC not set, start Auto-detection Oct 30 02:32:18 rivo volumio[3273]: info: Loading plugin "volumiodiscovery"... Oct 30 02:32:18 rivo volumio[3273]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 30 02:32:18 rivo node[3273]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 30 02:32:18 rivo volumio[3273]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 30 02:32:18 rivo node[3273]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 30 02:32:18 rivo volumio[3273]: *** WARNING *** For more information see Oct 30 02:32:18 rivo volumio[3273]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 30 02:32:18 rivo volumio[3273]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 30 02:32:18 rivo node[3273]: *** WARNING *** For more information see Oct 30 02:32:18 rivo volumio[3273]: *** WARNING *** For more information see Oct 30 02:32:18 rivo node[3273]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 30 02:32:18 rivo node[3273]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 30 02:32:18 rivo node[3273]: *** WARNING *** For more information see Oct 30 02:32:18 rivo volumio[3273]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 30 02:32:18 rivo volumio[3273]: info: Discovery: Started advertising with name: Rivo Oct 30 02:32:18 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 30 02:32:18 rivo volumio[3273]: info: Loading plugin "youtube2"... Oct 30 02:32:19 rivo volumio[3273]: STREAMING PROXY: Starting server on port 3245 Oct 30 02:32:19 rivo volumio[3273]: Node JS runtime: 14 Oct 30 02:32:20 rivo volumio[3273]: info: Loading plugin "ytcr"... Oct 30 02:32:23 rivo volumio[3273]: info: Loading plugin "ytmusic"... Oct 30 02:32:25 rivo volumio[3273]: info: Loading plugin "multiroom"... Oct 30 02:32:26 rivo volumio-remote-updater[2840]: [2024-10-30 02:32:26] [connect] Successful connection Oct 30 02:32:27 rivo volumio[3273]: info: Applying required configuration parameters for plugin multiroom Oct 30 02:32:27 rivo sudo[3631]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Oct 30 02:32:27 rivo sudo[3631]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 02:32:27 rivo sudo[3631]: pam_unix(sudo:session): session closed for user root Oct 30 02:32:27 rivo volumio[3273]: info: MRS: MultiRoom plugin initialized Oct 30 02:32:27 rivo volumio[3273]: info: MRS: STOPPING SNAPCLIENT Oct 30 02:32:27 rivo volumio[3273]: info: MRS: Snap server stop Oct 30 02:32:27 rivo sudo[3647]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Oct 30 02:32:27 rivo sudo[3647]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 02:32:27 rivo volumio[3273]: info: MRS: STOPPING volumioStreaming Oct 30 02:32:27 rivo sudo[3650]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Oct 30 02:32:27 rivo sudo[3650]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 02:32:27 rivo sudo[3647]: pam_unix(sudo:session): session closed for user root Oct 30 02:32:27 rivo sudo[3653]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Oct 30 02:32:27 rivo sudo[3653]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 02:32:27 rivo volumio[3273]: info: Loading plugin "outputs"... Oct 30 02:32:27 rivo volumio[3273]: info: Loading plugin "albumart"... Oct 30 02:32:27 rivo sudo[3650]: pam_unix(sudo:session): session closed for user root Oct 30 02:32:27 rivo sudo[3655]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Oct 30 02:32:27 rivo sudo[3655]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 02:32:27 rivo sudo[3655]: pam_unix(sudo:session): session closed for user root Oct 30 02:32:27 rivo volumio[3273]: info: Plugin example_plugin is not enabled Oct 30 02:32:27 rivo sudo[3653]: pam_unix(sudo:session): session closed for user root Oct 30 02:32:27 rivo volumio[3273]: info: Loading plugin "hi_res_audio"... Oct 30 02:32:27 rivo volumio[3273]: Forking 3 albumart workers Oct 30 02:32:28 rivo systemd[1]: systemd-hostnamed.service: Succeeded. Oct 30 02:32:28 rivo volumio[3273]: Starting albumart workers Oct 30 02:32:28 rivo volumio[3273]: Starting albumart workers Oct 30 02:32:28 rivo volumio[3273]: Starting albumart workers Oct 30 02:32:29 rivo volumio[3273]: info: Applying required configuration parameters for plugin hi_res_audio Oct 30 02:32:29 rivo volumio[3273]: info: Loading plugin "inputs"... Oct 30 02:32:30 rivo volumio[3273]: info: Loading plugin "qobuz"... Oct 30 02:32:31 rivo kernel: fb: mem_free_work, free memory: addr:800000 Oct 30 02:32:31 rivo volumio[3273]: info: Loading plugin "smart_inputs"... Oct 30 02:32:32 rivo volumio[3273]: info: Loading plugin "tidal"... Oct 30 02:32:34 rivo volumio[3273]: info: Loading plugin "rivocontrol"... Oct 30 02:32:34 rivo volumio[3273]: info: Adding this device properties Oct 30 02:32:34 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: system , setThisDeviceVolumioProperties Oct 30 02:32:34 rivo volumio[3273]: info: Setting Additional Device Volumio Properties: [object Object] Oct 30 02:32:34 rivo volumio[3273]: info: Loading plugin "updater_comm"... Oct 30 02:32:34 rivo volumio[3273]: info: Loading plugin "cec_controller"... Oct 30 02:32:35 rivo volumio[3273]: info: Applying required configuration parameters for plugin cec_controller Oct 30 02:32:35 rivo volumio[3273]: info: Plugin mpdemulation is not enabled Oct 30 02:32:35 rivo volumio[3273]: info: Loading plugin "rest_api"... Oct 30 02:32:35 rivo volumio[3273]: info: Loading plugin "websocket"... Oct 30 02:32:35 rivo volumio[3273]: info: Starting Socket.io Server version 2.3.0 Oct 30 02:32:35 rivo volumio[3273]: info: Loading plugin "minidlna"... Oct 30 02:32:35 rivo volumio[3273]: info: Applying required configuration parameters for plugin minidlna Oct 30 02:32:35 rivo volumio[3273]: info: Loading i18n strings for locale en Oct 30 02:32:35 rivo volumio[3273]: Updating browse sources language Oct 30 02:32:35 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 30 02:32:35 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 30 02:32:35 rivo volumio[3273]: info: CoreCommandRouter::initPlayerControls Oct 30 02:32:35 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 02:32:35 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 02:32:35 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 02:32:35 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 02:32:35 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 02:32:35 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 02:32:35 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 02:32:35 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 02:32:35 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 30 02:32:35 rivo volumio[3273]: Express server listening on port 3000 Oct 30 02:32:35 rivo volumio[3273]: [Metrics] WebUI: 30s 765.63ms Oct 30 02:32:35 rivo volumio[3273]: info: CoreStateMachine::resetVolumioState Oct 30 02:32:35 rivo volumio[3273]: info: CoreStateMachine::getcurrentVolume Oct 30 02:32:35 rivo volumio[3273]: info: CoreCommandRouter::volumioRetrievevolume Oct 30 02:32:35 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 30 02:32:36 rivo sudo[3718]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 30 02:32:36 rivo sudo[3718]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 02:32:36 rivo volumio[3273]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Oct 30 02:32:36 rivo volumio[3273]: info: Completed loading Core Plugins Oct 30 02:32:36 rivo volumio[3273]: info: Preparing to generate the ALSA configuration file Oct 30 02:32:36 rivo sudo[3718]: pam_unix(sudo:session): session closed for user root Oct 30 02:32:36 rivo sudo[3721]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 30 02:32:36 rivo sudo[3721]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 02:32:36 rivo sudo[3721]: pam_unix(sudo:session): session closed for user root Oct 30 02:32:36 rivo volumio[3273]: info: MRS: Removed streaming files Oct 30 02:32:36 rivo volumio[3273]: info: MRS: volumioStreaming STOPPED Oct 30 02:32:36 rivo volumio[3273]: info: MRS: SNAPSERVER STOPPED Oct 30 02:32:36 rivo volumio[3273]: info: MRS: SNAPCLIENT STOPPED Oct 30 02:32:36 rivo volumio[3273]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Oct 30 02:32:36 rivo volumio[3273]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 30 02:32:36 rivo volumio[3273]: info: Reading ALSA contributions from plugins. Oct 30 02:32:36 rivo volumio[3273]: verbose: New Socket.io Connection to 192.168.0.118 from 192.168.0.113 UA: Mozilla/5.0 (iPad; CPU OS 15_8_3 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 1 Oct 30 02:32:36 rivo volumio[3273]: verbose: New Socket.io Connection to 192.168.0.118 from 192.168.0.113 UA: Mozilla/5.0 (iPad; CPU OS 15_8_3 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 2 Oct 30 02:32:36 rivo volumio[3273]: verbose: New Socket.io Connection to 192.168.0.118 from 192.168.0.113 UA: Mozilla/5.0 (iPad; CPU OS 15_8_3 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 3 Oct 30 02:32:36 rivo volumio[3273]: verbose: New Socket.io Connection to 192.168.0.118 from 192.168.0.113 UA: Mozilla/5.0 (iPad; CPU OS 15_8_3 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 4 Oct 30 02:32:36 rivo volumio[3273]: verbose: New Socket.io Connection to 192.168.0.118:3000 from 192.168.0.153 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Oct 30 02:32:36 rivo volumio[3273]: verbose: New Socket.io Connection to 192.168.0.118 from 192.168.0.113 UA: Mozilla/5.0 (iPad; CPU OS 15_8_3 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 6 Oct 30 02:32:36 rivo volumio[3273]: verbose: New Socket.io Connection to 192.168.0.118:3000 from 192.168.0.153 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Oct 30 02:32:36 rivo volumio[3273]: verbose: New Socket.io Connection to 192.168.0.118 from 192.168.0.113 UA: Mozilla/5.0 (iPad; CPU OS 15_8_3 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 8 Oct 30 02:32:36 rivo volumio[3273]: verbose: New Socket.io Connection to 192.168.0.118:3000 from 192.168.0.153 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Oct 30 02:32:36 rivo volumio[3273]: verbose: New Socket.io Connection to 192.168.0.118 from 192.168.0.113 UA: Mozilla/5.0 (iPad; CPU OS 15_8_3 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 10 Oct 30 02:32:36 rivo volumio[3273]: info: VolumeController:: Volume=99 Mute =false Oct 30 02:32:36 rivo volumio[3273]: info: CoreStateMachine::pushState Oct 30 02:32:36 rivo volumio[3273]: info: CorePlayQueue::getTrack 0 Oct 30 02:32:36 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 02:32:36 rivo volumio[3273]: info: CoreCommandRouter::volumioPushState Oct 30 02:32:36 rivo volumio[3273]: info: CoreStateMachine::updateTrackBlock Oct 30 02:32:36 rivo volumio[3273]: info: CorePlayQueue::getTrackBlock Oct 30 02:32:36 rivo volumio[3273]: info: CoreCommandRouter::volumioRetrievevolume Oct 30 02:32:36 rivo volumio[3273]: info: Reloading queue from file Oct 30 02:32:36 rivo volumio[3273]: info: CoreStateMachine::setRepeat false single undefined Oct 30 02:32:36 rivo volumio[3273]: info: CoreStateMachine::pushState Oct 30 02:32:36 rivo volumio[3273]: info: CorePlayQueue::getTrack 0 Oct 30 02:32:36 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 02:32:36 rivo volumio[3273]: info: CoreCommandRouter::volumioPushState Oct 30 02:32:36 rivo volumio[3273]: info: CoreStateMachine::setRandom true Oct 30 02:32:36 rivo volumio[3273]: info: CoreStateMachine::pushState Oct 30 02:32:36 rivo volumio[3273]: info: CorePlayQueue::getTrack 0 Oct 30 02:32:36 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 02:32:36 rivo volumio[3273]: info: CoreCommandRouter::volumioPushState Oct 30 02:32:36 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Oct 30 02:32:36 rivo volumio[3273]: info: CoreCommandRouter::volumioGetState Oct 30 02:32:36 rivo volumio[3273]: info: CorePlayQueue::getTrack 0 Oct 30 02:32:36 rivo volumio[3273]: info: VolumeController:: Volume=99 Mute =false Oct 30 02:32:36 rivo volumio[3273]: info: CoreStateMachine::pushState Oct 30 02:32:36 rivo volumio[3273]: info: CorePlayQueue::getTrack 0 Oct 30 02:32:36 rivo volumio[3273]: info: CoreCommandRouter::volumioPushState Oct 30 02:32:36 rivo volumio[3273]: info: Discovery: adding 8f86395d-d62e-4cc6-92f6-7e0acff9f44e Oct 30 02:32:36 rivo volumio[3273]: info: Discovery: Found device Rivo Oct 30 02:32:36 rivo volumio[3273]: info: CoreCommandRouter::volumioGetState Oct 30 02:32:36 rivo volumio[3273]: info: CorePlayQueue::getTrack 0 Oct 30 02:32:36 rivo volumio[3273]: info: MRS: Pushing multiroomSync output for this device Oct 30 02:32:36 rivo volumio[3273]: info: MRS: Pushing multiroomSync output Oct 30 02:32:36 rivo sudo[3737]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Oct 30 02:32:36 rivo sudo[3737]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 02:32:36 rivo volumio[3273]: info: Adding audio output: Oct 30 02:32:36 rivo volumio[3273]: info: Adding audio output: Oct 30 02:32:36 rivo volumio[3273]: info: Discovery: adding a00049b6-dd7c-4e55-9b21-ec215c890618 Oct 30 02:32:36 rivo volumio[3273]: info: Discovery: Found device Volumio Oct 30 02:32:36 rivo systemd[1]: Started UPnP Renderer front-end to MPD. Oct 30 02:32:36 rivo sudo[3737]: pam_unix(sudo:session): session closed for user root Oct 30 02:32:36 rivo volumio[3273]: info: Discovery: Connecting to remote: 192.168.0.153 Oct 30 02:32:37 rivo volumio[3273]: info: Upmpdcli Daemon Started Oct 30 02:32:37 rivo volumio[3273]: info: Asound.conf file unchanged, so no further update is needed Oct 30 02:32:37 rivo volumio[3273]: info: Output device has changed, restarting MPD Oct 30 02:32:37 rivo volumio[3273]: info: Output device has changed, restarting Shairport Sync Oct 30 02:32:37 rivo sudo[3749]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 30 02:32:37 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 02:32:37 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 30 02:32:37 rivo sudo[3749]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 02:32:37 rivo sudo[3749]: pam_unix(sudo:session): session closed for user root Oct 30 02:32:37 rivo sudo[3751]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 30 02:32:37 rivo sudo[3751]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 02:32:37 rivo systemd[1]: Listening on mpd.socket. Oct 30 02:32:37 rivo systemd[1]: Starting Music Player Daemon... Oct 30 02:32:37 rivo volumio[3273]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 30 02:32:37 rivo volumio[3273]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 30 02:32:37 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 02:32:37 rivo volumio[3273]: info: ___________ START PLUGINS ___________ Oct 30 02:32:37 rivo volumio[3273]: info: ControllerMpd::onStart: Initializing MPD Oct 30 02:32:37 rivo volumio[3273]: info: Creating MPD Configuration file Oct 30 02:32:37 rivo sudo[3762]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 30 02:32:37 rivo sudo[3762]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 02:32:37 rivo sudo[3762]: pam_unix(sudo:session): session closed for user root Oct 30 02:32:37 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 30 02:32:37 rivo volumio[3273]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 30 02:32:37 rivo volumio[3273]: info: [1730269957346] CoreMusicLibrary::Adding element Media Servers Oct 30 02:32:37 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 30 02:32:37 rivo sudo[3758]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 30 02:32:37 rivo sudo[3766]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 30 02:32:37 rivo sudo[3758]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 02:32:37 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 30 02:32:37 rivo sudo[3766]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 02:32:37 rivo sudo[3758]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Oct 30 02:32:37 rivo sudo[3758]: pam_unix(sudo:session): session closed for user root Oct 30 02:32:37 rivo volumio[3273]: info: Adding METAVOLUMIO REST API Endpoints Oct 30 02:32:37 rivo volumio[3273]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Oct 30 02:32:37 rivo volumio[3273]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Oct 30 02:32:37 rivo volumio[3273]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Oct 30 02:32:37 rivo volumio[3273]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Oct 30 02:32:37 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 02:32:37 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 30 02:32:37 rivo sudo[3770]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name Rivo Oct 30 02:32:37 rivo sudo[3770]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 02:32:37 rivo systemd[1]: mpd.service: Succeeded. Oct 30 02:32:37 rivo systemd[1]: Stopped Music Player Daemon. Oct 30 02:32:37 rivo sudo[3770]: pam_unix(sudo:session): session closed for user root Oct 30 02:32:37 rivo systemd[1]: Starting Music Player Daemon... Oct 30 02:32:37 rivo volumio[3273]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 30 02:32:37 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 02:32:37 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 30 02:32:37 rivo volumio[3273]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 30 02:32:37 rivo volumio[3273]: info: Preparing CD Folders Oct 30 02:32:37 rivo volumio[3273]: info: Adding CD REST API Endpoints Oct 30 02:32:37 rivo volumio[3273]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Oct 30 02:32:37 rivo volumio[3273]: info: Starting UDEV Watcher for CD Oct 30 02:32:37 rivo volumio[3273]: info: Detecting CD presence with UDEV Oct 30 02:32:37 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Oct 30 02:32:37 rivo sudo[3773]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 30 02:32:37 rivo sudo[3773]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 02:32:37 rivo sudo[3773]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Oct 30 02:32:37 rivo sudo[3773]: pam_unix(sudo:session): session closed for user root Oct 30 02:32:37 rivo volumio[3273]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 30 02:32:37 rivo volumio[3273]: info: [1730269957679] CoreMusicLibrary::Adding element Last_100 Oct 30 02:32:37 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 30 02:32:37 rivo volumio[3273]: info: Starting RAAT Plugin Oct 30 02:32:37 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , addAdditionalUISections Oct 30 02:32:37 rivo volumio[3273]: info: Additional UI Settings Added for plugin music_service/raat Oct 30 02:32:37 rivo volumio[3273]: info: Registering DSP Elements listener and retrieving current ones Oct 30 02:32:37 rivo volumio[3273]: info: Additional DSP elements updated Oct 30 02:32:37 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 02:32:37 rivo volumio[3273]: info: Updating RAAT Signal Path Oct 30 02:32:37 rivo volumio[3273]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Oct 30 02:32:37 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 02:32:37 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 02:32:37 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 02:32:37 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 02:32:37 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 02:32:37 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 30 02:32:37 rivo volumio[3273]: info: Not Reporting Auto name since its the default one Oct 30 02:32:37 rivo volumio[3273]: info: Streaming services startup Oct 30 02:32:37 rivo volumio[3273]: info: Starting Streaming Daemon Oct 30 02:32:37 rivo sudo[3790]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 30 02:32:37 rivo sudo[3790]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 02:32:37 rivo sudo[3790]: pam_unix(sudo:session): session closed for user root Oct 30 02:32:37 rivo volumio[3273]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 30 02:32:37 rivo volumio[3273]: info: [1730269957965] CoreMusicLibrary::Adding element Webradio Oct 30 02:32:37 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 30 02:32:37 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 30 02:32:37 rivo volumio[3273]: info: Initializing BBC Radios Oct 30 02:32:37 rivo sudo[3798]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 30 02:32:37 rivo sudo[3798]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 02:32:38 rivo sudo[3798]: pam_unix(sudo:session): session closed for user root Oct 30 02:32:38 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 30 02:32:38 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 30 02:32:38 rivo volumio[3273]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 30 02:32:38 rivo volumio[3273]: info: [1730269958140] CoreMusicLibrary::Adding element YouTube2 Oct 30 02:32:38 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 30 02:32:38 rivo volumio[3273]: Cannot find translation for source YouTube2 Oct 30 02:32:38 rivo volumio[3273]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 30 02:32:38 rivo volumio[3273]: info: [1730269958228] CoreMusicLibrary::Adding element YouTube Music Oct 30 02:32:38 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 30 02:32:38 rivo volumio[3273]: Cannot find translation for source YouTube2 Oct 30 02:32:38 rivo volumio[3273]: Cannot find translation for source YouTube Music Oct 30 02:32:38 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Oct 30 02:32:38 rivo volumio[3273]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 30 02:32:38 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 02:32:38 rivo volumio[3273]: error: Hi Res Audio Failed Login: Missing Login Data Oct 30 02:32:38 rivo volumio[3273]: info: Adding HIGHRESAUDIO REST API Endpoints Oct 30 02:32:38 rivo volumio[3273]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Oct 30 02:32:38 rivo volumio[3273]: info: Initializing Serial Communication on port /dev/ttyS3 Oct 30 02:32:38 rivo volumio[3273]: info: Touch Event Listener Process Starting Oct 30 02:32:38 rivo kernel: meson_uart ffd22000.serial: ttyS3 use xtal(24M) 24000000 change 0 to 9600 Oct 30 02:32:38 rivo kernel: meson_uart ffd22000.serial: ttyS3 use xtal(24M) 24000000 change 9600 to 9600 Oct 30 02:32:38 rivo kernel: meson_uart ffd22000.serial: ttyS3 use xtal(24M) 24000000 change 9600 to 115200 Oct 30 02:32:38 rivo volumio[3273]: info: Adding inputs REST Endpoints Oct 30 02:32:38 rivo volumio[3273]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Oct 30 02:32:38 rivo volumio[3273]: info: Scanning Audio Inputs Oct 30 02:32:38 rivo sudo[3815]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xinput --test-xi2 --root Oct 30 02:32:38 rivo sudo[3815]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 02:32:38 rivo volumio[3273]: info: Checking against Known Cards name Oct 30 02:32:38 rivo volumio[3273]: info: Checking against Known Cards name Oct 30 02:32:38 rivo volumio[3273]: info: Checking against Known Cards name Oct 30 02:32:38 rivo volumio[3273]: info: Checking against Known Cards name Oct 30 02:32:38 rivo volumio[3273]: info: Checking against Known Cards name Oct 30 02:32:38 rivo volumio[3273]: info: Adding Server instance for streaming Oct 30 02:32:38 rivo volumio[3273]: info: Refreshing TIDAL token Oct 30 02:32:38 rivo sudo[3826]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x25 0x0 Oct 30 02:32:38 rivo sudo[3826]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 02:32:38 rivo sudo[3826]: pam_unix(sudo:session): session closed for user root Oct 30 02:32:38 rivo sudo[3829]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x28 0x0 Oct 30 02:32:38 rivo sudo[3829]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 02:32:38 rivo sudo[3829]: pam_unix(sudo:session): session closed for user root Oct 30 02:32:38 rivo sudo[3832]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x29 0x1 Oct 30 02:32:38 rivo sudo[3832]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 02:32:38 rivo sudo[3832]: pam_unix(sudo:session): session closed for user root Oct 30 02:32:38 rivo sudo[3835]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x33 0x1 Oct 30 02:32:38 rivo sudo[3835]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 02:32:38 rivo sudo[3835]: pam_unix(sudo:session): session closed for user root Oct 30 02:32:38 rivo volumio[3273]: info: Apply VIM3L Onboard LEDs Settings Oct 30 02:32:38 rivo volumio[3273]: info: Turning On Onboard LEDs Oct 30 02:32:38 rivo sudo[3839]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/sys_led/trigger Oct 30 02:32:38 rivo sudo[3839]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 02:32:39 rivo sudo[3839]: pam_unix(sudo:session): session closed for user root Oct 30 02:32:39 rivo volumio[3273]: info: Starting CEC Event listener Oct 30 02:32:39 rivo volumio[3273]: info: Loading i18n strings for locale en Oct 30 02:32:39 rivo sudo[3844]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/red_red/trigger Oct 30 02:32:39 rivo sudo[3844]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 02:32:39 rivo sudo[3844]: pam_unix(sudo:session): session closed for user root Oct 30 02:32:39 rivo volumio[3273]: info: Volumio Calling Home Oct 30 02:32:39 rivo volumio[3273]: (node:3273) [DEP0005] DeprecationWarning: Buffer() is deprecated due to security and usability issues. Please use the Buffer.alloc(), Buffer.allocUnsafe(), or Buffer.from() methods instead. Oct 30 02:32:39 rivo volumio[3273]: (Use `node --trace-deprecation ...` to show where the warning was created) Oct 30 02:32:39 rivo volumio[3273]: info: Stopping AccessToken refresher cron for QOBUZ Oct 30 02:32:39 rivo volumio[3273]: info: AccessToken refresher cron started for QOBUZ Oct 30 02:32:39 rivo volumio[3273]: info: Adding QOBUZ REST API Endpoints Oct 30 02:32:39 rivo volumio[3273]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Oct 30 02:32:39 rivo volumio[3273]: info: Serial port opened successfully Oct 30 02:32:39 rivo volumio[3273]: info: Sending serial start messages Oct 30 02:32:39 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 02:32:39 rivo volumio[3273]: info: MP1 GPIO: Signalled system ready via GPIO Oct 30 02:32:39 rivo volumio[3273]: info: MRS: Pushing audio output on request Oct 30 02:32:39 rivo volumio[3273]: info: MRS: Pushing multiroomSync output Oct 30 02:32:39 rivo volumio[3273]: info: Discovery: Connected to remote: 192.168.0.153 Oct 30 02:32:40 rivo volumio[3273]: error: Cannot start Volumio Streaming Daemon Oct 30 02:32:40 rivo volumio[3273]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 30 02:32:40 rivo volumio[3273]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 30 02:32:40 rivo volumio[3273]: info: RAAT Albumart path created successfully Oct 30 02:32:40 rivo volumio[3273]: info: Bluetooth name changed to Rivo Oct 30 02:32:40 rivo volumio[3273]: info: MPD Permissions set Oct 30 02:32:40 rivo volumio[3273]: info: MPD Permissions set Oct 30 02:32:40 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setDeviceVolumeOverride Oct 30 02:32:40 rivo volumio[3273]: info: Setting Device Volume Override Oct 30 02:32:40 rivo volumio[3273]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 30 02:32:40 rivo volumio[3273]: info: Updating Volume Controller Parameters: Device: 5 Name: DENAFRIPS USB v3.12 | E1.3 Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Oct 30 02:32:40 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 30 02:32:40 rivo volumio[3273]: info: Disabling external Volume Control Oct 30 02:32:40 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 02:32:40 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 02:32:40 rivo volumio[3273]: info: CoreCommandRouter::volumioGetState Oct 30 02:32:40 rivo volumio[3273]: info: CorePlayQueue::getTrack 0 Oct 30 02:32:40 rivo volumio[3273]: info: CoreStateMachine::pushState Oct 30 02:32:40 rivo volumio[3273]: info: CorePlayQueue::getTrack 0 Oct 30 02:32:40 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 02:32:40 rivo volumio[3273]: info: CoreCommandRouter::volumioPushState Oct 30 02:32:40 rivo volumio[3273]: info: MRS: Pushing multiroomSync output update for this device Oct 30 02:32:40 rivo volumio[3273]: info: MRS: Pushing multiroomSync output Oct 30 02:32:40 rivo volumio[3273]: info: Volumio called home Oct 30 02:32:40 rivo volumio[3273]: info: minidlna: Found miniDLNA version 1.2.1 Oct 30 02:32:40 rivo volumio[3273]: info: minidlna: Starting minidlna.service Oct 30 02:32:40 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: system , setAdditionalSVInfo Oct 30 02:32:40 rivo volumio[3273]: info: Setting Additional System Software info: Hardware Revision: 2.0 Oct 30 02:32:40 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: system , setHwFwVersion Oct 30 02:32:40 rivo volumio[3273]: info: Setting HW Firmware info: undefined Oct 30 02:32:40 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: system , setHwVersion Oct 30 02:32:40 rivo volumio[3273]: info: Setting HW Version info: 2.0 Oct 30 02:32:40 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: system , setAdditionalSVInfo Oct 30 02:32:40 rivo volumio[3273]: info: Setting Additional System Software info: Hardware Revision: 2.0, Firmware Version: 0.3.3 Oct 30 02:32:40 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: system , setHwFwVersion Oct 30 02:32:40 rivo volumio[3273]: info: Setting HW Firmware info: 0.3.3 Oct 30 02:32:40 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: system , setHwVersion Oct 30 02:32:40 rivo volumio[3273]: info: Setting HW Version info: 2.0 Oct 30 02:32:40 rivo volumio[3273]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Oct 30 02:32:40 rivo volumio[3273]: info: MCU Signalled Headphone Mode Disabled Oct 30 02:32:40 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Oct 30 02:32:40 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 02:32:40 rivo volumio[3273]: info: Updating RAAT Signal Path Oct 30 02:32:40 rivo volumio[3273]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Oct 30 02:32:40 rivo volumio[3273]: info: MCU Signalled Sleep Mode Disabled Oct 30 02:32:40 rivo sudo[3869]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start minidlna.service Oct 30 02:32:40 rivo sudo[3869]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 02:32:40 rivo volumio[3273]: info: Enabling Advanced system settings configuration Oct 30 02:32:40 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: system , addAdditionalUISections Oct 30 02:32:40 rivo volumio[3273]: info: Additional UI Settings Added for plugin music_service/inputs Oct 30 02:32:40 rivo volumio[3273]: info: MCU Signalled Auto Boot Mode On Power Disabled Oct 30 02:32:40 rivo sudo[3872]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms force on Oct 30 02:32:40 rivo sudo[3872]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 02:32:40 rivo systemd[1]: Started MiniDLNA lightweight DLNA/UPnP-AV server. Oct 30 02:32:40 rivo sudo[3869]: pam_unix(sudo:session): session closed for user root Oct 30 02:32:40 rivo kernel: fb: osd[0] enable: 1 (Xorg) Oct 30 02:32:40 rivo sudo[3872]: pam_unix(sudo:session): session closed for user root Oct 30 02:32:40 rivo volumio[3273]: verbose: New Socket.io Connection to 192.168.0.118 from 192.168.0.113 UA: Mozilla/5.0 (iPad; CPU OS 15_8_3 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 11 Oct 30 02:32:40 rivo volumio[3273]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 12 Oct 30 02:32:40 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 30 02:32:40 rivo sudo[3878]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Oct 30 02:32:40 rivo sudo[3878]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 02:32:40 rivo systemd[1]: Started Volumio Bluetooth Module. Oct 30 02:32:40 rivo minidlnad[3874]: [2024/10/30 02:32:40] minidlna.c:572: error: Too many interfaces (max: 4), ignoring ip6_vti0@NONE Oct 30 02:32:40 rivo volumio[3273]: No protocol specified Oct 30 02:32:40 rivo volumio[3273]: xcb_connection_has_error() returned true Oct 30 02:32:40 rivo sudo[3878]: pam_unix(sudo:session): session closed for user root Oct 30 02:32:40 rivo sudo[3884]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Oct 30 02:32:40 rivo sudo[3884]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 02:32:40 rivo volumiobt[3882]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Oct 30 02:32:40 rivo sudo[3884]: pam_unix(sudo:session): session closed for user root Oct 30 02:32:41 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 02:32:41 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 02:32:41 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 02:32:41 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 02:32:41 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 02:32:41 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 02:32:41 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 02:32:41 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 02:32:41 rivo sudo[3891]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 30 02:32:41 rivo sudo[3891]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 02:32:41 rivo sudo[3891]: pam_unix(sudo:session): session closed for user root Oct 30 02:32:41 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 30 02:32:41 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 30 02:32:41 rivo sudo[3897]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 30 02:32:41 rivo sudo[3897]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 02:32:41 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 02:32:41 rivo systemd[1]: Started RAAT DAEMON. Oct 30 02:32:41 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 02:32:41 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 02:32:41 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 02:32:41 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 02:32:41 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 02:32:41 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 02:32:41 rivo sudo[3897]: pam_unix(sudo:session): session closed for user root Oct 30 02:32:41 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 02:32:41 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 30 02:32:41 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 30 02:32:41 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 02:32:41 rivo volumio[3273]: error: Serial API: Failed to decode command: MAXVOL, message: 100 Oct 30 02:32:41 rivo volumio-remote-updater[2840]: [2024-10-30 02:32:41] [connect] Successful connection Oct 30 02:32:41 rivo mpd[3782]: decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 30 02:32:41 rivo mpd[3782]: output: No 'audio_output' defined in config file Oct 30 02:32:41 rivo volumio[3273]: info: Access Token successfully retrieved Oct 30 02:32:41 rivo kernel: aml_tdm_open Oct 30 02:32:41 rivo kernel: Not init audio effects Oct 30 02:32:41 rivo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:tdm@1 Oct 30 02:32:41 rivo kernel: aml_dai_set_tdm_sysclk(), mpll no change, keep clk Oct 30 02:32:41 rivo kernel: aml_dai_set_tdm_sysclk(), mclk no change, keep clk Oct 30 02:32:41 rivo kernel: set mclk:12288000, mpll:24576000, get mclk:12287994, mpll:24575987 Oct 30 02:32:41 rivo kernel: asoc aml_dai_set_tdm_fmt, 0x4001, ffffffc03d28f818, id(1), clksel(1) Oct 30 02:32:41 rivo kernel: aml_dai_set_tdm_fmt(), fmt not change Oct 30 02:32:41 rivo kernel: dump_pcm_setting(ffffffc03d28f818) Oct 30 02:32:41 rivo kernel: pcm_mode(1) Oct 30 02:32:41 rivo kernel: sysclk(12288000) Oct 30 02:32:41 rivo kernel: sysclk_bclk_ratio(4) Oct 30 02:32:41 rivo kernel: bclk(3072000) Oct 30 02:32:41 rivo kernel: bclk_lrclk_ratio(64) Oct 30 02:32:41 rivo kernel: lrclk(48000) Oct 30 02:32:41 rivo kernel: tx_mask(0x3) Oct 30 02:32:41 rivo kernel: rx_mask(0x3) Oct 30 02:32:41 rivo kernel: slots(2) Oct 30 02:32:41 rivo kernel: slot_width(32) Oct 30 02:32:41 rivo kernel: lane_mask_in(0x2) Oct 30 02:32:41 rivo kernel: lane_mask_out(0x1) Oct 30 02:32:41 rivo kernel: lane_oe_mask_in(0x0) Oct 30 02:32:41 rivo kernel: lane_oe_mask_out(0x0) Oct 30 02:32:41 rivo kernel: lane_lb_mask_in(0x0) Oct 30 02:32:41 rivo kernel: aml_dai_set_tdm_sysclk(), mpll no change, keep clk Oct 30 02:32:41 rivo kernel: aml_dai_set_tdm_sysclk(), mclk no change, keep clk Oct 30 02:32:41 rivo kernel: set mclk:12288000, mpll:24576000, get mclk:12287994, mpll:24575987 Oct 30 02:32:41 rivo kernel: aml_dai_set_clkdiv, div 4, clksel(1) Oct 30 02:32:41 rivo kernel: aml_dai_set_bclk_ratio, select I2S mode Oct 30 02:32:41 rivo kernel: aml_dai_tdm_hw_params(), enable mclk for TDM-B Oct 30 02:32:41 rivo kernel: aml_tdm_prepare(), reset fddr Oct 30 02:32:41 rivo kernel: spdif_a fifo ctrl, frddr:0 type:1, 16 bits, chmask 0x3, swap 0x10 Oct 30 02:32:41 rivo kernel: spdif_info: rate: 48000, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x200, ch1_r:0x200 Oct 30 02:32:41 rivo kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Oct 30 02:32:41 rivo kernel: tdm playback mute: 0, lane_cnt = 8 Oct 30 02:32:41 rivo mpd[3782]: output: Successfully detected a sndio audio device Oct 30 02:32:41 rivo kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Oct 30 02:32:41 rivo kernel: aml_dai_tdm_hw_free(), disable mclk for TDM-B Oct 30 02:32:41 rivo kernel: tdm playback mute: 1, lane_cnt = 8 Oct 30 02:32:41 rivo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:tdm@1 Oct 30 02:32:41 rivo mpd[3782]: zeroconf: No global port, disabling zeroconf Oct 30 02:32:41 rivo systemd[1]: Started Music Player Daemon. Oct 30 02:32:41 rivo sudo[3751]: pam_unix(sudo:session): session closed for user root Oct 30 02:32:41 rivo sudo[3766]: pam_unix(sudo:session): session closed for user root Oct 30 02:32:41 rivo volumio[3273]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 13 Oct 30 02:32:41 rivo volumio[3273]: info: VolumeController:: Volume=99 Mute =false Oct 30 02:32:41 rivo volumio[3273]: info: CoreCommandRouter::volumioGetState Oct 30 02:32:41 rivo volumio[3273]: info: CorePlayQueue::getTrack 0 Oct 30 02:32:41 rivo volumio[3273]: info: CoreStateMachine::pushState Oct 30 02:32:41 rivo volumio[3273]: info: CorePlayQueue::getTrack 0 Oct 30 02:32:41 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 02:32:41 rivo volumio[3273]: info: CoreCommandRouter::volumioPushState Oct 30 02:32:41 rivo volumio[3273]: info: MRS: Pushing multiroomSync output update for this device Oct 30 02:32:41 rivo volumio[3273]: info: MRS: Pushing multiroomSync output Oct 30 02:32:41 rivo volumio[3273]: info: minidlna: systemctl start minidlna.service succeeded. Oct 30 02:32:41 rivo volumio[3273]: info: CoreCommandRouter::volumioGetState Oct 30 02:32:41 rivo volumio[3273]: info: CorePlayQueue::getTrack 0 Oct 30 02:32:41 rivo volumio[3273]: info: CoreStateMachine::pushState Oct 30 02:32:41 rivo volumio[3273]: info: CorePlayQueue::getTrack 0 Oct 30 02:32:41 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 02:32:41 rivo volumio[3273]: info: CoreCommandRouter::volumioPushState Oct 30 02:32:41 rivo sudo[3926]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms 0 0 0 Oct 30 02:32:41 rivo volumio[3273]: info: MRS: Pushing multiroomSync output update for this device Oct 30 02:32:41 rivo sudo[3926]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 02:32:41 rivo volumio[3273]: info: MRS: Pushing multiroomSync output Oct 30 02:32:41 rivo sudo[3926]: pam_unix(sudo:session): session closed for user root Oct 30 02:32:41 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 30 02:32:41 rivo volumio[3273]: Upnp client error: Error: This socket has been ended by the other party Oct 30 02:32:41 rivo volumio[3273]: info: Executing endpoint restartRAATSocket Oct 30 02:32:41 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Oct 30 02:32:41 rivo volumio-remote-updater[2840]: [2024-10-30 02:32:41] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1730269961 101 Oct 30 02:32:41 rivo volumio[3273]: 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: 14 Oct 30 02:32:41 rivo volumio[3273]: info: Raat Daemon started successfully Oct 30 02:32:41 rivo volumio[3273]: info: TidalConnect service stoped! Oct 30 02:32:42 rivo volumio[3273]: info: Volumio BT Module successfully started Oct 30 02:32:42 rivo sudo[3941]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Oct 30 02:32:42 rivo sudo[3941]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 02:32:42 rivo volumiobt[3882]: pulseaudio: no process found Oct 30 02:32:42 rivo sudo[3941]: pam_unix(sudo:session): session closed for user root Oct 30 02:32:42 rivo volumio[3273]: info: CoreCommandRouter::volumioGetVisibleSources Oct 30 02:32:42 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 30 02:32:42 rivo volumio[3273]: info: CoreCommandRouter::volumioGetState Oct 30 02:32:42 rivo volumio[3273]: info: CorePlayQueue::getTrack 0 Oct 30 02:32:42 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 30 02:32:42 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 30 02:32:42 rivo volumio[3273]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Oct 30 02:32:42 rivo volumio[3273]: info: Received Get System Info Oct 30 02:32:42 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 30 02:32:42 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 30 02:32:42 rivo volumio[3273]: info: Discovery: Getting this device information Oct 30 02:32:42 rivo volumio[3273]: info: CoreCommandRouter::volumioGetState Oct 30 02:32:42 rivo volumio[3273]: info: CorePlayQueue::getTrack 0 Oct 30 02:32:42 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 30 02:32:42 rivo volumio[3273]: info: CoreCommandRouter::volumioGetState Oct 30 02:32:42 rivo volumio[3273]: info: CorePlayQueue::getTrack 0 Oct 30 02:32:42 rivo volumio[3273]: info: Listing playlists Oct 30 02:32:42 rivo volumio[3273]: info: CoreCommandRouter::volumioGetQueue Oct 30 02:32:42 rivo volumio[3273]: info: CoreStateMachine::getQueue Oct 30 02:32:42 rivo volumio[3273]: info: CorePlayQueue::getQueue Oct 30 02:32:42 rivo dbus-daemon[2848]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.23' (uid=1000 pid=3949 comm="/usr/bin/pulseaudio --high-priority=true " label="kernel") Oct 30 02:32:42 rivo systemd[1]: Starting RealtimeKit Scheduling Policy Service... Oct 30 02:32:42 rivo kernel: IPVS: Creating netns size=1928 id=3 Oct 30 02:32:42 rivo volumio[3273]: info: MPD running with PID3782 Oct 30 02:32:42 rivo volumio[3273]: ,establishing connection Oct 30 02:32:42 rivo dbus-daemon[2848]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' Oct 30 02:32:42 rivo systemd[1]: Started RealtimeKit Scheduling Policy Service. Oct 30 02:32:42 rivo volumio[3273]: info: Turning Off Onboard LEDs Oct 30 02:32:42 rivo rtkit-daemon[3950]: Successfully called chroot. Oct 30 02:32:42 rivo rtkit-daemon[3950]: Successfully dropped privileges. Oct 30 02:32:42 rivo rtkit-daemon[3950]: Successfully limited resources. Oct 30 02:32:42 rivo rtkit-daemon[3950]: Running. Oct 30 02:32:42 rivo rtkit-daemon[3950]: Watchdog thread running. Oct 30 02:32:42 rivo rtkit-daemon[3950]: Canary thread running. Oct 30 02:32:42 rivo dbus-daemon[2848]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.24' (uid=0 pid=3950 comm="/usr/lib/rtkit/rtkit-daemon " label="kernel") Oct 30 02:32:42 rivo systemd[1]: Starting Authorization Manager... Oct 30 02:32:42 rivo volumio[3273]: info: Starting Shairport Sync Oct 30 02:32:42 rivo sudo[3956]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/sys_led/trigger Oct 30 02:32:42 rivo sudo[3956]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 02:32:42 rivo volumio[3273]: info: Starting Shairport Sync Oct 30 02:32:42 rivo sudo[3956]: pam_unix(sudo:session): session closed for user root Oct 30 02:32:42 rivo sudo[3964]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/red_red/trigger Oct 30 02:32:42 rivo sudo[3964]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 02:32:42 rivo sudo[3962]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 30 02:32:42 rivo polkitd[3954]: started daemon version 0.105 using authority implementation `local' version `0.105' Oct 30 02:32:42 rivo sudo[3962]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 02:32:42 rivo dbus-daemon[2848]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Oct 30 02:32:42 rivo systemd[1]: Started Authorization Manager. Oct 30 02:32:42 rivo volumio[3273]: info: Starting Shairport Sync Oct 30 02:32:42 rivo sudo[3964]: pam_unix(sudo:session): session closed for user root Oct 30 02:32:42 rivo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 30 02:32:42 rivo sudo[3968]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 30 02:32:42 rivo sudo[3968]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 02:32:42 rivo systemd[1]: shairport-sync.service: Succeeded. Oct 30 02:32:42 rivo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 30 02:32:42 rivo volumio[3273]: info: Adding Inputs via Serial API Oct 30 02:32:42 rivo volumio[3273]: info: MRS: Found cast device: Google-Home-Mini-ce9a56e2ffff64293beed64a9dcaefe3 Oct 30 02:32:42 rivo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 30 02:32:42 rivo volumio[3273]: info: Adding audio output: Oct 30 02:32:42 rivo sudo[3962]: pam_unix(sudo:session): session closed for user root Oct 30 02:32:42 rivo sudo[3971]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 30 02:32:42 rivo sudo[3971]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 02:32:42 rivo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 30 02:32:42 rivo systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Oct 30 02:32:42 rivo systemd[1]: shairport-sync.service: Succeeded. Oct 30 02:32:42 rivo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 30 02:32:42 rivo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 30 02:32:42 rivo sudo[3968]: pam_unix(sudo:session): session closed for user root Oct 30 02:32:42 rivo volumio[3273]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 30 02:32:42 rivo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 30 02:32:42 rivo systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Oct 30 02:32:42 rivo systemd[1]: shairport-sync.service: Succeeded. Oct 30 02:32:42 rivo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 30 02:32:42 rivo volumio[3273]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 30 02:32:42 rivo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 30 02:32:42 rivo sudo[3971]: pam_unix(sudo:session): session closed for user root Oct 30 02:32:42 rivo volumio[3273]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 30 02:32:42 rivo volumio[3273]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 30 02:32:42 rivo volumio[3273]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 30 02:32:42 rivo volumio[3273]: info: CorePlayQueue::getTrack 0 Oct 30 02:32:42 rivo volumio[3273]: info: CorePlayQueue::getTrack 0 Oct 30 02:32:42 rivo volumio[3273]: info: CorePlayQueue::getTrack 0 Oct 30 02:32:42 rivo volumio[3273]: info: CoreCommandRouter::servicePushState Oct 30 02:32:42 rivo volumio[3273]: info: CorePlayQueue::getTrack 0 Oct 30 02:32:42 rivo volumio[3273]: verbose: STATE SERVICE {"status":"play","service":"raat","title":"","artist":"","album":"","albumart":"/albumart?web=100/extralarge&path=/tmp/raat/albumart/76071b520092d0eb195a1ae078f5812e.jpg","uri":"","trackType":"","seek":0,"duration":0,"samplerate":"","bitdepth":"","channels":2,"disableUiControls":false,"push_time":0,"random":false,"repeat":false,"repeatSingle":false} Oct 30 02:32:42 rivo volumio[3273]: verbose: CURRENT POSITION 0 Oct 30 02:32:42 rivo volumio[3273]: info: CoreStateMachine::syncState stateService play Oct 30 02:32:42 rivo volumio[3273]: info: CoreStateMachine::syncState currentStatus stop Oct 30 02:32:42 rivo volumio[3273]: info: CoreCommandRouter::servicePushState Oct 30 02:32:42 rivo volumio[3273]: info: CorePlayQueue::getTrack 0 Oct 30 02:32:42 rivo volumio[3273]: verbose: STATE SERVICE {"status":"pause","service":"raat","title":"Ma Hele","artist":"Franco / OK Jazz","album":"Quatre boutons","albumart":"/albumart","uri":"","trackType":"","seek":40000,"duration":364,"samplerate":"","bitdepth":"","channels":2,"disableUiControls":false,"push_time":0,"random":false,"repeat":false,"repeatSingle":false} Oct 30 02:32:42 rivo volumio[3273]: verbose: CURRENT POSITION 0 Oct 30 02:32:42 rivo volumio[3273]: info: CoreStateMachine::syncState stateService pause Oct 30 02:32:42 rivo volumio[3273]: info: CoreStateMachine::syncState currentStatus play Oct 30 02:32:42 rivo volumio[3273]: info: CoreStateMachine::pushState Oct 30 02:32:42 rivo volumio[3273]: info: CorePlayQueue::getTrack 0 Oct 30 02:32:42 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 02:32:42 rivo volumio[3273]: info: CoreCommandRouter::volumioPushState Oct 30 02:32:42 rivo volumio[3273]: info: MRS: Pushing multiroomSync output update for this device Oct 30 02:32:43 rivo volumio[3273]: info: MRS: Pushing multiroomSync output Oct 30 02:32:43 rivo volumio[3273]: error: MPD error: The expression evaluated to a falsy value: Oct 30 02:32:43 rivo volumio[3273]: assert.ok(self.idling) Oct 30 02:32:43 rivo volumio[3273]: error: The expression evaluated to a falsy value: Oct 30 02:32:43 rivo volumio[3273]: assert.ok(self.idling) Oct 30 02:32:43 rivo volumio[3273]: error: MPD error: The expression evaluated to a falsy value: Oct 30 02:32:43 rivo volumio[3273]: assert.ok(self.idling) Oct 30 02:32:43 rivo volumio[3273]: error: The expression evaluated to a falsy value: Oct 30 02:32:43 rivo volumio[3273]: assert.ok(self.idling) Oct 30 02:32:43 rivo pulseaudio[3949]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Oct 30 02:32:43 rivo pulseaudio[3949]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Oct 30 02:32:43 rivo volumio[3273]: info: CoreCommandRouter::volumioGetState Oct 30 02:32:43 rivo bluetoothd[3197]: Endpoint registered: sender=:1.45 path=/MediaEndpoint/A2DPSource Oct 30 02:32:43 rivo volumio[3273]: info: CorePlayQueue::getTrack 0 Oct 30 02:32:43 rivo volumiobt[3882]: Applying permissions Oct 30 02:32:43 rivo volumiobt[3882]: Setting BT discoverable and pairable Oct 30 02:32:43 rivo bluetoothd[3197]: Endpoint registered: sender=:1.45 path=/MediaEndpoint/A2DPSink Oct 30 02:32:43 rivo pulseaudio[3949]: 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 Oct 30 02:32:43 rivo volumio[3273]: info: MRS: Getting audio outputs on start Oct 30 02:32:43 rivo volumio[3273]: info: MRS: Requesting all other devices output Oct 30 02:32:43 rivo volumio[3273]: info: MRS: Found cast device: Google-Nest-Mini-ad6ad6b70f63b7b1058e5b065eb70db8 Oct 30 02:32:43 rivo volumio[3273]: info: Adding audio output: Oct 30 02:32:43 rivo volumio[3273]: info: MRS: Found cast device: WiiM-Pro-Receiver-9d5e3b64fe399d2ab4ad6939bb84abfe Oct 30 02:32:43 rivo volumiobt[3882]: Agent registered Oct 30 02:32:43 rivo volumio[3273]: info: Adding audio output: Oct 30 02:32:43 rivo volumiobt[3882]: [bluetooth]# -e power on Oct 30 02:32:43 rivo volumiobt[3882]: Invalid command in menu main: -e Oct 30 02:32:43 rivo volumiobt[3882]: Oct 30 02:32:43 rivo volumiobt[3882]: Use "help" for a list of available commands in a menu. Oct 30 02:32:43 rivo volumiobt[3882]: Use "menu " if you want to enter any submenu. Oct 30 02:32:43 rivo volumiobt[3882]: Use "back" if you want to return to menu main. Oct 30 02:32:43 rivo volumiobt[3882]: [bluetooth]# agent on Oct 30 02:32:43 rivo volumiobt[3882]: Agent is already registered Oct 30 02:32:43 rivo volumiobt[3882]: [bluetooth]# discoverable on Oct 30 02:32:43 rivo volumiobt[3882]: [bluetooth]# pairable on Oct 30 02:32:43 rivo volumiobt[3882]: [bluetooth]# agent NoInputNoOutput Oct 30 02:32:43 rivo volumiobt[3882]: Agent is already registered Oct 30 02:32:43 rivo volumiobt[3882]: [bluetooth]# default-agent Oct 30 02:32:43 rivo volumiobt[3882]: [bluetooth]# quit Oct 30 02:32:43 rivo volumio[3273]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 30 02:32:43 rivo volumiobt[3882]: [59B blob data] Oct 30 02:32:43 rivo volumio[3273]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 30 02:32:43 rivo volumio[3273]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 30 02:32:43 rivo volumio[3273]: info: Successfully retrieved User Session From TIDAL Oct 30 02:32:43 rivo volumio[3273]: info: CoreCommandRouter::volumioGetState Oct 30 02:32:43 rivo volumio[3273]: info: CorePlayQueue::getTrack 0 Oct 30 02:32:43 rivo volumio[3273]: info: Shairport-Sync Started Oct 30 02:32:43 rivo volumio[3273]: Error adding Membership: Error: addMembership EINVAL Oct 30 02:32:43 rivo volumio[3273]: info: Shairport-Sync Started Oct 30 02:32:43 rivo volumio[3273]: info: Shairport-Sync Started Oct 30 02:32:43 rivo volumio[3273]: info: Onboard LEDs initialized Oct 30 02:32:43 rivo volumio[3273]: info: CoreCommandRouter::servicePushState Oct 30 02:32:43 rivo volumio[3273]: info: CorePlayQueue::getTrack 0 Oct 30 02:32:43 rivo volumio[3273]: verbose: STATE SERVICE {"status":"play","service":"inputs","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"","seek":0,"duration":0,"samplerate":"","bitdepth":"","stream":true,"disableUiControls":true,"channels":2} Oct 30 02:32:43 rivo volumio[3273]: verbose: CURRENT POSITION 0 Oct 30 02:32:43 rivo volumio[3273]: info: CoreStateMachine::syncState stateService play Oct 30 02:32:43 rivo volumio[3273]: info: CoreStateMachine::syncState currentStatus play Oct 30 02:32:43 rivo volumio[3273]: info: Received an update from plugin. extracting info from payload Oct 30 02:32:43 rivo volumio[3273]: info: CoreStateMachine::pushState Oct 30 02:32:43 rivo volumio[3273]: info: CorePlayQueue::getTrack 0 Oct 30 02:32:43 rivo volumio[3273]: info: CoreCommandRouter::volumioPushState Oct 30 02:32:43 rivo volumio[3273]: info: MRS: Pushing multiroomSync output update for this device Oct 30 02:32:43 rivo volumio[3273]: info: MRS: Pushing multiroomSync output Oct 30 02:32:43 rivo volumio[3273]: info: CoreStateMachine::pushState Oct 30 02:32:43 rivo volumio[3273]: info: CorePlayQueue::getTrack 0 Oct 30 02:32:43 rivo volumio[3273]: info: CoreCommandRouter::volumioPushState Oct 30 02:32:43 rivo volumio[3273]: info: MRS: Pushing multiroomSync output update for this device Oct 30 02:32:43 rivo volumio[3273]: info: MRS: Pushing multiroomSync output Oct 30 02:32:43 rivo volumio[3273]: info: CoreCommandRouter::volumiosetSourceActiveno-source Oct 30 02:32:43 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 30 02:32:43 rivo volumio[3273]: Cannot find translation for source YouTube2 Oct 30 02:32:43 rivo volumio[3273]: Cannot find translation for source YouTube Music Oct 30 02:32:43 rivo volumio[3273]: error: updateQueue error: null Oct 30 02:32:43 rivo volumio[3273]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 15 Oct 30 02:32:43 rivo volumio[3273]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 30 02:32:43 rivo volumio[3273]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 30 02:32:44 rivo sudo[3990]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 30 02:32:44 rivo sudo[3990]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 02:32:44 rivo systemd[1]: Started Volumio Tidal Connect Service. Oct 30 02:32:44 rivo sudo[3990]: pam_unix(sudo:session): session closed for user root Oct 30 02:32:44 rivo volumio[3273]: info: MRS: Found cast device: Chromecast-8bc1ccf93cbc942f819c094dca5caa5c Oct 30 02:32:44 rivo volumio[3273]: info: Adding audio output: Oct 30 02:32:44 rivo volumio[3273]: info: MCU Signalled Playback Inactive Oct 30 02:32:44 rivo volumio[3273]: info: MRS: Found cast device: Chromecast-Audio-2d095d495817f0ed63dc30a4bcc8af95 Oct 30 02:32:44 rivo volumio[3273]: info: Adding audio output: Oct 30 02:32:44 rivo volumio[3273]: info: Successfully retrieved User Subscription From TIDAL Oct 30 02:32:44 rivo volumio[3273]: info: Adding TIDAL to Browse Sources Oct 30 02:32:44 rivo volumio[3273]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 30 02:32:44 rivo volumio[3273]: info: [1730269964457] CoreMusicLibrary::Adding element TIDAL Oct 30 02:32:44 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 30 02:32:44 rivo volumio[3273]: Cannot find translation for source YouTube2 Oct 30 02:32:44 rivo volumio[3273]: Cannot find translation for source YouTube Music Oct 30 02:32:44 rivo volumio[3273]: Cannot find translation for source TIDAL Oct 30 02:32:44 rivo volumio[3273]: info: Stopping AccessToken refresher cron Oct 30 02:32:44 rivo volumio[3273]: info: AccessToken refresher cron started Oct 30 02:32:44 rivo volumio[3273]: info: Adding TIDAL REST API Endpoints Oct 30 02:32:44 rivo volumio[3273]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Oct 30 02:32:44 rivo volumio[3739]: Generating RSA private key, 4096 bit long modulus (2 primes) Oct 30 02:32:44 rivo volumio[3273]: 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: 16 Oct 30 02:32:44 rivo volumio[3273]: info: Executing endpoint tc_getconfig Oct 30 02:32:44 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Oct 30 02:32:44 rivo vtcs[3992]: STARTING TidalConnect services, version: 1.4.0.34 Oct 30 02:32:44 rivo vtcs[3992]: STARTED TidalConnect services. Oct 30 02:32:45 rivo volumio[3273]: info: Executing endpoint tc_connect Oct 30 02:32:45 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Oct 30 02:32:45 rivo volumio[3273]: info: Connecting to TidalConnect Oct 30 02:32:45 rivo volumio[3273]: info: CoreCommandRouter::servicePushState Oct 30 02:32:45 rivo volumio[3273]: info: CoreStateMachine::pushState Oct 30 02:32:45 rivo volumio[3273]: info: CorePlayQueue::getTrack 0 Oct 30 02:32:45 rivo volumio[3273]: info: CoreCommandRouter::volumioPushState Oct 30 02:32:45 rivo volumio[3273]: info: MRS: Pushing multiroomSync output update for this device Oct 30 02:32:45 rivo volumio[3273]: info: MRS: Pushing multiroomSync output Oct 30 02:32:45 rivo volumio[3273]: info: CorePlayQueue::getTrack 0 Oct 30 02:32:45 rivo volumio[3273]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Oct 30 02:32:45 rivo volumio[3273]: verbose: CURRENT POSITION 0 Oct 30 02:32:45 rivo volumio[3273]: info: CoreStateMachine::syncState stateService stop Oct 30 02:32:45 rivo volumio[3273]: info: CoreStateMachine::syncState currentStatus play Oct 30 02:32:45 rivo volumio[3273]: info: CoreCommandRouter::volumioPushState Oct 30 02:32:45 rivo volumio[3273]: info: MRS: Pushing multiroomSync output update for this device Oct 30 02:32:45 rivo volumio[3273]: info: MRS: Pushing multiroomSync output Oct 30 02:32:45 rivo volumio[3273]: info: CoreStateMachine::stPlaybackTimer Oct 30 02:32:45 rivo volumio[3273]: info: CoreCommandRouter::servicePushState Oct 30 02:32:45 rivo volumio[3273]: info: CoreStateMachine::pushState Oct 30 02:32:45 rivo volumio[3273]: info: CorePlayQueue::getTrack 0 Oct 30 02:32:45 rivo volumio[3273]: info: CoreCommandRouter::volumioPushState Oct 30 02:32:45 rivo volumio[3273]: info: MRS: Pushing multiroomSync output update for this device Oct 30 02:32:45 rivo volumio[3273]: info: MRS: Pushing multiroomSync output Oct 30 02:32:45 rivo volumio[3273]: info: CorePlayQueue::getTrack 0 Oct 30 02:32:45 rivo volumio[3273]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Oct 30 02:32:45 rivo volumio[3273]: verbose: CURRENT POSITION 0 Oct 30 02:32:45 rivo volumio[3273]: info: CoreStateMachine::syncState stateService stop Oct 30 02:32:45 rivo volumio[3273]: info: CoreStateMachine::syncState currentStatus stop Oct 30 02:32:45 rivo volumio[3273]: info: CoreStateMachine::pushState Oct 30 02:32:45 rivo volumio[3273]: info: CorePlayQueue::getTrack 0 Oct 30 02:32:45 rivo volumio[3273]: info: CoreCommandRouter::volumioPushState Oct 30 02:32:45 rivo volumio[3273]: info: MRS: Pushing multiroomSync output update for this device Oct 30 02:32:45 rivo volumio[3273]: info: MRS: Pushing multiroomSync output Oct 30 02:32:45 rivo volumio[3273]: info: No code Oct 30 02:32:45 rivo volumio[3273]: info: CoreStateMachine::pushState Oct 30 02:32:45 rivo volumio[3273]: info: CorePlayQueue::getTrack 0 Oct 30 02:32:45 rivo volumio[3273]: info: CoreCommandRouter::volumioPushState Oct 30 02:32:45 rivo volumio[3273]: info: MRS: Pushing multiroomSync output update for this device Oct 30 02:32:45 rivo volumio[3273]: info: MRS: Pushing multiroomSync output Oct 30 02:32:45 rivo volumio[3273]: info: [yt-cast-receiver] DIAL server listening on port 8098 Oct 30 02:32:47 rivo volumio[3273]: info: TidalConnect service started! Oct 30 02:32:47 rivo volumio[3273]: info: Completed starting Core Plugins Oct 30 02:32:47 rivo volumio[3273]: info: ------------------------------------------- Oct 30 02:32:47 rivo volumio[3273]: info: ----- MyVolumio plugins startup ---- Oct 30 02:32:47 rivo volumio[3273]: info: ------------------------------------------- Oct 30 02:32:47 rivo volumio[3273]: info: [MyVolumio PluginManager] Fetching plans data.... Oct 30 02:32:48 rivo mpd[3782]: exception: No database Oct 30 02:32:48 rivo volumio[3273]: error: Failed LSINFO: Error: [50@0] {lsinfo} No database Oct 30 02:32:48 rivo volumio[3273]: 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: 17 Oct 30 02:32:48 rivo volumio[3273]: info: Checking for updated MCU Firmware Oct 30 02:32:48 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 30 02:32:48 rivo volumio[3273]: info: Firware on device is on latest version, no need to update Oct 30 02:32:50 rivo volumio[3273]: 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: 18 Oct 30 02:32:50 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 30 02:32:50 rivo volumio[3273]: info: CoreCommandRouter::volumioGetVisibleSources Oct 30 02:32:50 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 30 02:32:50 rivo volumio[3273]: info: CoreCommandRouter::volumioGetState Oct 30 02:32:50 rivo volumio[3273]: info: CorePlayQueue::getTrack 0 Oct 30 02:32:50 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 30 02:32:50 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 30 02:32:50 rivo volumio[3273]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Oct 30 02:32:50 rivo volumio[3273]: info: Received Get System Info Oct 30 02:32:50 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 30 02:32:50 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 30 02:32:50 rivo volumio[3273]: info: Discovery: Getting this device information Oct 30 02:32:50 rivo volumio[3273]: info: CoreCommandRouter::volumioGetState Oct 30 02:32:50 rivo volumio[3273]: info: CorePlayQueue::getTrack 0 Oct 30 02:32:50 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 30 02:32:50 rivo volumio[3273]: info: CoreCommandRouter::volumioGetState Oct 30 02:32:50 rivo volumio[3273]: info: CorePlayQueue::getTrack 0 Oct 30 02:32:50 rivo volumio[3273]: info: Listing playlists Oct 30 02:32:50 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 30 02:32:50 rivo volumio[3273]: info: Received Get System Info Oct 30 02:32:50 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 30 02:32:50 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 30 02:32:50 rivo volumio[3273]: info: Discovery: Getting this device information Oct 30 02:32:50 rivo volumio[3273]: info: CoreCommandRouter::volumioGetState Oct 30 02:32:50 rivo volumio[3273]: info: CorePlayQueue::getTrack 0 Oct 30 02:32:50 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 30 02:32:50 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 30 02:32:50 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 30 02:32:50 rivo volumio[3273]: info: CoreCommandRouter::volumioGetState Oct 30 02:32:50 rivo volumio[3273]: info: CorePlayQueue::getTrack 0 Oct 30 02:32:50 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 30 02:32:50 rivo volumio[3273]: info: CoreCommandRouter::volumioGetVisibleSources Oct 30 02:32:50 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 30 02:32:50 rivo volumio[3273]: info: CoreCommandRouter::volumioGetState Oct 30 02:32:50 rivo volumio[3273]: info: CorePlayQueue::getTrack 0 Oct 30 02:32:50 rivo volumio[3273]: info: CoreCommandRouter::volumioGetState Oct 30 02:32:50 rivo volumio[3273]: info: CorePlayQueue::getTrack 0 Oct 30 02:32:50 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 30 02:32:50 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 30 02:32:50 rivo volumio[3273]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Oct 30 02:32:50 rivo volumio[3273]: info: Received Get System Info Oct 30 02:32:50 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 30 02:32:50 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 30 02:32:50 rivo volumio[3273]: info: Discovery: Getting this device information Oct 30 02:32:50 rivo volumio[3273]: info: CoreCommandRouter::volumioGetState Oct 30 02:32:50 rivo volumio[3273]: info: CorePlayQueue::getTrack 0 Oct 30 02:32:50 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 30 02:32:50 rivo volumio[3273]: info: CoreCommandRouter::volumioGetState Oct 30 02:32:50 rivo volumio[3273]: info: CorePlayQueue::getTrack 0 Oct 30 02:32:50 rivo volumio[3273]: info: Listing playlists Oct 30 02:32:50 rivo volumio[3739]: ................................................................++++ Oct 30 02:32:52 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Oct 30 02:32:52 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 30 02:32:52 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 30 02:32:52 rivo volumio[3273]: info: Received Get System Info Oct 30 02:32:52 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 30 02:32:52 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 30 02:32:52 rivo volumio[3273]: info: Discovery: Getting this device information Oct 30 02:32:52 rivo volumio[3273]: info: CoreCommandRouter::volumioGetState Oct 30 02:32:52 rivo volumio[3273]: info: CorePlayQueue::getTrack 0 Oct 30 02:32:52 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 30 02:32:52 rivo volumio[3739]: ..........................++++ Oct 30 02:32:52 rivo volumio[3739]: e is 65537 (0x010001) Oct 30 02:32:52 rivo volumio[3739]: writing RSA key Oct 30 02:32:53 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Oct 30 02:32:55 rivo volumio[3273]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Oct 30 02:32:55 rivo volumio[3273]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Oct 30 02:32:55 rivo volumio[3273]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Oct 30 02:32:55 rivo volumio[3273]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Oct 30 02:32:55 rivo volumio[3273]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Oct 30 02:32:55 rivo volumio[3273]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Oct 30 02:32:55 rivo volumio[3273]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Oct 30 02:32:55 rivo volumio[3273]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Oct 30 02:32:55 rivo volumio[3273]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Oct 30 02:32:55 rivo volumio[3273]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Oct 30 02:32:55 rivo volumio[3273]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Oct 30 02:32:55 rivo volumio[3273]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Oct 30 02:32:55 rivo volumio[3273]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Oct 30 02:32:55 rivo volumio[3273]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Oct 30 02:32:55 rivo volumio[3273]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Oct 30 02:32:55 rivo volumio[3273]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Oct 30 02:32:55 rivo volumio[3273]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Oct 30 02:32:55 rivo volumio[3273]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Oct 30 02:32:55 rivo volumio[3273]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Oct 30 02:32:55 rivo volumio[3273]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Oct 30 02:32:55 rivo volumio[3273]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Oct 30 02:32:55 rivo volumio[3273]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Oct 30 02:32:55 rivo volumio[3273]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Oct 30 02:32:55 rivo volumio[3273]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Oct 30 02:32:55 rivo volumio[3273]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Oct 30 02:32:55 rivo volumio[3273]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Oct 30 02:32:55 rivo volumio[3273]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Oct 30 02:32:57 rivo volumio[3273]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Oct 30 02:32:57 rivo volumio[3273]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Oct 30 02:32:57 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 30 02:32:57 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 30 02:32:57 rivo volumio[3273]: info: Starting MyVolumio Remote Streaming Endpoints Oct 30 02:32:57 rivo volumio[3273]: info: MyVolumio login type: Token Oct 30 02:32:57 rivo volumio[3273]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Oct 30 02:32:57 rivo volumio[3273]: error: [MyVolumio PluginManager] Could not read package.json file: Error: /myvolumio/plugins/music_service/streaming_services//package.json: ENOENT: no such file or directory, open '/myvolumio/plugins/music_service/streaming_services//package.json' Oct 30 02:32:57 rivo volumio[3273]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Oct 30 02:32:58 rivo volumio[3273]: info: MyVolumio token set successfully Oct 30 02:32:58 rivo volumio[3273]: info: MYVOLUMIO: Adding device Oct 30 02:32:58 rivo volumio[3273]: info: MYVOLUMIO: Evaluating Server Oct 30 02:32:58 rivo volumio[3273]: info: MyVolumio Plan changed: virtuoso Oct 30 02:32:58 rivo volumio[3273]: info: [MyVolumio PluginManager] Subscribed plan changed to virtuoso Oct 30 02:32:58 rivo volumio[3273]: info: Removing browser output: myVolumio user plan is not superstar Oct 30 02:32:58 rivo volumio[3273]: info: Removing audio output: Oct 30 02:32:58 rivo volumio[3273]: info: MYVOLUMIO: Adding device Oct 30 02:32:58 rivo volumio[3273]: info: MYVOLUMIO: Evaluating Server Oct 30 02:32:58 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 30 02:32:58 rivo volumio[3273]: info: Remote config written successfully Oct 30 02:32:58 rivo volumio[3273]: info: Starting Tunnel 1 Oct 30 02:32:58 rivo volumio[3273]: info: Starting Tunnel Connection Checker Oct 30 02:32:59 rivo volumio[3273]: info: MYVolumio Device disabled Oct 30 02:32:59 rivo volumio[3273]: info: MyVolumio status changed Oct 30 02:32:59 rivo volumio[3273]: info: Streaming services startup Oct 30 02:32:59 rivo volumio[3273]: info: Starting Streaming Daemon Oct 30 02:32:59 rivo volumio[3273]: info: Setting Geolocation for MyVolumio to us1 Oct 30 02:32:59 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 30 02:32:59 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 30 02:32:59 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 30 02:32:59 rivo sudo[4146]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 30 02:32:59 rivo sudo[4146]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 02:32:59 rivo sudo[4146]: pam_unix(sudo:session): session closed for user root Oct 30 02:32:59 rivo volumio[3273]: error: Cannot start Volumio Streaming Daemon Oct 30 02:32:59 rivo volumio[3273]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 30 02:32:59 rivo volumio[3273]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 30 02:32:59 rivo volumio[3273]: info: Setting Geolocation for MyVolumio to us1 Oct 30 02:32:59 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 30 02:32:59 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 30 02:32:59 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 30 02:33:00 rivo volumio[3273]: info: Successfully Added MyVolumio device Oct 30 02:33:00 rivo volumio[3273]: info: Successfully Added MyVolumio device Oct 30 02:33:00 rivo volumio[3273]: info: Updating MyVolumio device info Oct 30 02:33:00 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 30 02:33:00 rivo volumio[3273]: info: Updating MyVolumio device info Oct 30 02:33:00 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 30 02:33:00 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 30 02:33:00 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 30 02:33:00 rivo volumio[3273]: info: Successfully Updated MyVolumio device Oct 30 02:33:01 rivo volumio[3273]: info: Successfully Updated MyVolumio device Oct 30 02:33:04 rivo sudo[4153]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Oct 30 02:33:04 rivo sudo[4153]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 02:33:04 rivo systemd[1]: Started MyVolumio SSH Tunnel. Oct 30 02:33:04 rivo sudo[4153]: pam_unix(sudo:session): session closed for user root Oct 30 02:33:04 rivo volumio[3273]: info: Remote SSH Started Oct 30 02:33:04 rivo autossh[4163]: port set to 0, monitoring disabled Oct 30 02:33:04 rivo autossh[4163]: starting ssh (count 1) Oct 30 02:33:04 rivo autossh[4163]: ssh child pid is 4170 Oct 30 02:33:04 rivo volumiossh-tunnel[4155]: Warning: Permanently added '[us1.myvolumio.org]:2222,[162.243.167.188]:2222' (RSA) to the list of known hosts. Oct 30 02:33:04 rivo volumio[3273]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 19 Oct 30 02:33:04 rivo volumio[3273]: info: CoreCommandRouter::volumioGetState Oct 30 02:33:04 rivo volumio[3273]: info: CorePlayQueue::getTrack 0 Oct 30 02:33:04 rivo volumio[3273]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.TkXgyplqMWPbFjHvag5ReyVTURM2.0bad7f00814eec80dc91a304a0a56035.state.mute' Oct 30 02:33:05 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 30 02:33:05 rivo volumio[3273]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Oct 30 02:33:05 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Oct 30 02:33:05 rivo volumio[3273]: info: Received Get System Version Oct 30 02:33:05 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 30 02:33:05 rivo volumio[3273]: info: Received Get System Info Oct 30 02:33:05 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 30 02:33:05 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 30 02:33:05 rivo volumio[3273]: info: Discovery: Getting this device information Oct 30 02:33:05 rivo volumio[3273]: info: CoreCommandRouter::volumioGetState Oct 30 02:33:05 rivo volumio[3273]: info: CorePlayQueue::getTrack 0 Oct 30 02:33:05 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 30 02:33:07 rivo volumio[3273]: error: MyVolumio Plugin failed to start in a timely fashion Oct 30 02:33:07 rivo volumio[3273]: info: BOOT COMPLETED Oct 30 02:33:07 rivo volumio[3273]: [Metrics] CommandRouter: 61s 283.44ms Oct 30 02:33:07 rivo volumio[3273]: info: CoreCommandRouter::volumiosetStartupVolume Oct 30 02:33:07 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 02:33:07 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 30 02:33:07 rivo volumio[3273]: info: CoreCommandRouter::Close All Modals sent Oct 30 02:33:07 rivo volumio[3273]: info: CoreCommandRouter::Close All Modals sent Oct 30 02:33:08 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Oct 30 02:33:08 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 30 02:33:08 rivo volumio[3273]: No protocol specified Oct 30 02:33:08 rivo volumio[3273]: xcb_connection_has_error() returned true Oct 30 02:33:08 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Oct 30 02:33:08 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 30 02:33:08 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 30 02:33:08 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 30 02:33:09 rivo volumio-remote-updater[2840]: No test mode Oct 30 02:33:09 rivo volumio-remote-updater[2840]: No alpha test mode Oct 30 02:33:09 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Oct 30 02:33:09 rivo volumio[3273]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Oct 30 02:33:09 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Oct 30 02:33:09 rivo mount.exfat[3451]: unknown entry type 0x80 Oct 30 02:33:09 rivo mount.exfat[3451]: unknown entry type 0x80 Oct 30 02:33:09 rivo mount.exfat[3451]: unknown entry type 0x80 Oct 30 02:33:09 rivo mount.exfat[3451]: unknown entry type 0x80 Oct 30 02:33:09 rivo mount.exfat[3451]: unknown entry type 0x80 Oct 30 02:33:09 rivo mount.exfat[3451]: unknown entry type 0x80 Oct 30 02:33:09 rivo mount.exfat[3451]: unknown entry type 0x80 Oct 30 02:33:09 rivo mount.exfat[3451]: unknown entry type 0x80 Oct 30 02:33:09 rivo mount.exfat[3451]: unknown entry type 0x80 Oct 30 02:33:09 rivo mount.exfat[3451]: unknown entry type 0x80 Oct 30 02:33:09 rivo mount.exfat[3451]: unknown entry type 0x80 Oct 30 02:33:09 rivo mount.exfat[3451]: unknown entry type 0x80 Oct 30 02:33:09 rivo mount.exfat[3451]: unknown entry type 0x80 Oct 30 02:33:09 rivo mount.exfat[3451]: failed to open directory '/New Flac/Donnie McClurkin' Oct 30 02:33:12 rivo mount.exfat[3451]: unknown entry type 0x80 Oct 30 02:33:12 rivo mount.exfat[3451]: unknown entry type 0x80 Oct 30 02:33:12 rivo mount.exfat[3451]: unknown entry type 0x80 Oct 30 02:33:12 rivo mount.exfat[3451]: unknown entry type 0x80 Oct 30 02:33:12 rivo mount.exfat[3451]: unknown entry type 0x80 Oct 30 02:33:12 rivo mount.exfat[3451]: unknown entry type 0x80 Oct 30 02:33:12 rivo mount.exfat[3451]: unknown entry type 0x80 Oct 30 02:33:12 rivo mount.exfat[3451]: unknown entry type 0x80 Oct 30 02:33:12 rivo mount.exfat[3451]: unknown entry type 0x80 Oct 30 02:33:12 rivo mount.exfat[3451]: unknown entry type 0x80 Oct 30 02:33:12 rivo mount.exfat[3451]: unknown entry type 0x80 Oct 30 02:33:12 rivo mount.exfat[3451]: unknown entry type 0x80 Oct 30 02:33:12 rivo mount.exfat[3451]: unknown entry type 0x80 Oct 30 02:33:12 rivo mount.exfat[3451]: failed to open directory '/New Flac/Easy listening CD1' Oct 30 02:33:12 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 30 02:33:12 rivo volumio[3273]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Oct 30 02:33:12 rivo volumio[3273]: info: CoreCommandRouter::volumioGetState Oct 30 02:33:12 rivo volumio[3273]: info: CorePlayQueue::getTrack 0 Oct 30 02:33:14 rivo mount.exfat[3451]: unknown entry type 0x80 Oct 30 02:33:14 rivo mount.exfat[3451]: unknown entry type 0x80 Oct 30 02:33:14 rivo mount.exfat[3451]: unknown entry type 0x80 Oct 30 02:33:14 rivo mount.exfat[3451]: unknown entry type 0x80 Oct 30 02:33:14 rivo mount.exfat[3451]: unknown entry type 0x80 Oct 30 02:33:14 rivo mount.exfat[3451]: unknown entry type 0x80 Oct 30 02:33:14 rivo mount.exfat[3451]: unknown entry type 0x80 Oct 30 02:33:14 rivo mount.exfat[3451]: unknown entry type 0x80 Oct 30 02:33:14 rivo mount.exfat[3451]: unknown entry type 0x80 Oct 30 02:33:14 rivo mount.exfat[3451]: unknown entry type 0x80 Oct 30 02:33:14 rivo mount.exfat[3451]: unknown entry type 0x80 Oct 30 02:33:14 rivo mount.exfat[3451]: unknown entry type 0x80 Oct 30 02:33:14 rivo mount.exfat[3451]: unknown entry type 0x80 Oct 30 02:33:14 rivo mount.exfat[3451]: failed to open directory '/New Flac/Faith Hill' Oct 30 02:33:17 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 02:33:17 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 02:33:17 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Oct 30 02:33:17 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 30 02:33:17 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 30 02:33:17 rivo volumio[3273]: info: CoreCommandRouter::volumioGetBrowseSources Oct 30 02:33:17 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 30 02:33:18 rivo mount.exfat[3451]: unknown entry type 0x80 Oct 30 02:33:18 rivo mount.exfat[3451]: unknown entry type 0x80 Oct 30 02:33:18 rivo mount.exfat[3451]: unknown entry type 0x80 Oct 30 02:33:18 rivo mount.exfat[3451]: unknown entry type 0x80 Oct 30 02:33:18 rivo mount.exfat[3451]: unknown entry type 0x80 Oct 30 02:33:18 rivo mount.exfat[3451]: unknown entry type 0x80 Oct 30 02:33:18 rivo mount.exfat[3451]: unknown entry type 0x80 Oct 30 02:33:18 rivo mount.exfat[3451]: unknown entry type 0x80 Oct 30 02:33:18 rivo mount.exfat[3451]: unknown entry type 0x80 Oct 30 02:33:18 rivo mount.exfat[3451]: unknown entry type 0x80 Oct 30 02:33:18 rivo mount.exfat[3451]: unknown entry type 0x80 Oct 30 02:33:18 rivo mount.exfat[3451]: unknown entry type 0x80 Oct 30 02:33:18 rivo mount.exfat[3451]: unknown entry type 0x80 Oct 30 02:33:18 rivo mount.exfat[3451]: failed to open directory '/New Flac/Franco Luambo' Oct 30 02:33:22 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 30 02:33:22 rivo volumio[3273]: info: Preload queue cleared Oct 30 02:33:25 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 30 02:33:25 rivo volumio[3273]: info: Preload queue cleared Oct 30 02:33:26 rivo mount.exfat[3451]: unknown entry type 0x80 Oct 30 02:33:26 rivo mount.exfat[3451]: unknown entry type 0x80 Oct 30 02:33:26 rivo mount.exfat[3451]: unknown entry type 0x80 Oct 30 02:33:26 rivo mount.exfat[3451]: unknown entry type 0x80 Oct 30 02:33:26 rivo mount.exfat[3451]: unknown entry type 0x80 Oct 30 02:33:26 rivo mount.exfat[3451]: unknown entry type 0x80 Oct 30 02:33:26 rivo mount.exfat[3451]: unknown entry type 0x80 Oct 30 02:33:26 rivo mount.exfat[3451]: unknown entry type 0x80 Oct 30 02:33:26 rivo mount.exfat[3451]: unknown entry type 0x80 Oct 30 02:33:26 rivo mount.exfat[3451]: unknown entry type 0x80 Oct 30 02:33:26 rivo mount.exfat[3451]: unknown entry type 0x80 Oct 30 02:33:26 rivo mount.exfat[3451]: unknown entry type 0x80 Oct 30 02:33:26 rivo mount.exfat[3451]: unknown entry type 0x80 Oct 30 02:33:26 rivo mount.exfat[3451]: failed to open directory '/New Flac/Gaither Vocal band .. Everything good' Oct 30 02:33:27 rivo mount.exfat[3451]: unknown entry type 0x80 Oct 30 02:33:27 rivo mount.exfat[3451]: unknown entry type 0x80 Oct 30 02:33:27 rivo mount.exfat[3451]: unknown entry type 0x80 Oct 30 02:33:27 rivo mount.exfat[3451]: unknown entry type 0x80 Oct 30 02:33:27 rivo mount.exfat[3451]: unknown entry type 0x80 Oct 30 02:33:27 rivo mount.exfat[3451]: unknown entry type 0x80 Oct 30 02:33:27 rivo mount.exfat[3451]: unknown entry type 0x80 Oct 30 02:33:27 rivo mount.exfat[3451]: unknown entry type 0x80 Oct 30 02:33:27 rivo mount.exfat[3451]: unknown entry type 0x80 Oct 30 02:33:27 rivo mount.exfat[3451]: unknown entry type 0x80 Oct 30 02:33:27 rivo mount.exfat[3451]: unknown entry type 0x80 Oct 30 02:33:27 rivo mount.exfat[3451]: unknown entry type 0x80 Oct 30 02:33:27 rivo mount.exfat[3451]: unknown entry type 0x80 Oct 30 02:33:27 rivo mount.exfat[3451]: failed to open directory '/New Flac/Gloria Estefan' Oct 30 02:33:32 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 30 02:33:32 rivo volumio[3273]: info: Preload queue cleared Oct 30 02:33:35 rivo volumio[3273]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 30 02:33:35 rivo volumio[3273]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 30 02:33:35 rivo volumio[3273]: TypeError: Cannot read property 'length' of undefined Oct 30 02:33:35 rivo volumio[3273]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Oct 30 02:33:35 rivo volumio[3273]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Oct 30 02:33:35 rivo volumio[3273]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Oct 30 02:33:35 rivo volumio[3273]: at Parser.emit (events.js:400:28) Oct 30 02:33:35 rivo volumio[3273]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Oct 30 02:33:35 rivo volumio[3273]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Oct 30 02:33:35 rivo volumio[3273]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Oct 30 02:33:35 rivo volumio[3273]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Oct 30 02:33:35 rivo volumio[3273]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Oct 30 02:33:35 rivo volumio[3273]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Oct 30 02:33:35 rivo volumio[3273]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Oct 30 02:33:35 rivo volumio[3273]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Oct 30 02:33:35 rivo volumio[3273]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Oct 30 02:33:35 rivo volumio[3273]: at IncomingMessage.emit (events.js:412:35) Oct 30 02:33:35 rivo volumio[3273]: at endReadableNT (internal/streams/readable.js:1333:12) Oct 30 02:33:35 rivo volumio[3273]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Oct 30 02:33:35 rivo volumio[3273]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 30 02:33:35 rivo sudo[4195]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-30 02:32 Oct 30 02:33:35 rivo sudo[4195]: 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="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:47:06 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="b3e08c3e210a64867536255c2503fb09"