-- Logs begin at Sun 2024-07-14 07:45:47 KST, end at Sun 2024-07-14 07:47:09 KST. -- Jul 14 07:46:00 myrivo wpa_supplicant[3172]: wlan0: Trying to associate with 00:31:92:78:b3:15 (SSID='manim' freq=5220 MHz) Jul 14 07:46:00 myrivo kernel: Connecting with 00:31:92:78:b3:15 ssid "manim", len (5) channel=44 Jul 14 07:46:00 myrivo kernel: dhd_dbg_start_pkt_monitor, 1724 Jul 14 07:46:00 myrivo kernel: wl_iw_event: Link UP with 00:31:92:78:b3:15 Jul 14 07:46:00 myrivo kernel: wl_bss_connect_done succeeded with 00:31:92:78:b3:15 Jul 14 07:46:00 myrivo kernel: CFG80211-ERROR) wl_cfg80211_scan_abort : scan abort failed Jul 14 07:46:00 myrivo wpa_supplicant[3172]: wlan0: Associated with 00:31:92:78:b3:15 Jul 14 07:46:00 myrivo wpa_supplicant[3172]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Jul 14 07:46:00 myrivo wpa_supplicant[3172]: wlan0: WPA: Key negotiation completed with 00:31:92:78:b3:15 [PTK=CCMP GTK=CCMP] Jul 14 07:46:00 myrivo wpa_supplicant[3172]: wlan0: CTRL-EVENT-CONNECTED - Connection to 00:31:92:78:b3:15 completed [id=0 id_str=] Jul 14 07:46:00 myrivo dhcpcd[2833]: wlan0: carrier acquired Jul 14 07:46:00 myrivo dhcpcd[2833]: wlan0: carrier acquired Jul 14 07:46:00 myrivo dhcpcd[2833]: wlan0: IAID 32:e4:40:14 Jul 14 07:46:00 myrivo dhcpcd[2833]: wlan0: probing address 70.12.14.188/24 Jul 14 07:46:00 myrivo dhcpcd[2833]: wlan0: IAID 32:e4:40:14 Jul 14 07:46:00 myrivo dhcpcd[2833]: wlan0: probing address 70.12.14.188/24 Jul 14 07:46:00 myrivo kernel: wl_bss_connect_done succeeded with 00:31:92:78:b3:15 vndr_oui: 8C-FD-F0 Jul 14 07:46:00 myrivo wireless.js[2828]: trying... Jul 14 07:46:00 myrivo sudo[3188]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 14 07:46:00 myrivo sudo[3188]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 14 07:46:00 myrivo sudo[3188]: pam_unix(sudo:session): session closed for user root Jul 14 07:46:00 myrivo dhcpcd[2833]: wlan0: soliciting an IPv6 router Jul 14 07:46:00 myrivo dhcpcd[2833]: wlan0: soliciting an IPv6 router Jul 14 07:46:01 myrivo dhcpcd[2833]: eth0: using static address 70.12.14.88/24 Jul 14 07:46:01 myrivo dhcpcd[2833]: eth0: adding route to 70.12.14.0/24 Jul 14 07:46:01 myrivo dhcpcd[2833]: eth0: adding default route via 70.12.14.1 Jul 14 07:46:01 myrivo dhcpcd[2833]: eth0: using static address 70.12.14.88/24 Jul 14 07:46:01 myrivo dhcpcd[2833]: eth0: adding route to 70.12.14.0/24 Jul 14 07:46:01 myrivo dhcpcd[2833]: eth0: adding default route via 70.12.14.1 Jul 14 07:46:01 myrivo avahi-daemon[2834]: Joining mDNS multicast group on interface eth0.IPv4 with address 70.12.14.88. Jul 14 07:46:01 myrivo avahi-daemon[2834]: New relevant interface eth0.IPv4 for mDNS. Jul 14 07:46:01 myrivo avahi-daemon[2834]: Registering new address record for 70.12.14.88 on eth0.IPv4. Jul 14 07:46:01 myrivo dhcpcd[2833]: forked to background, child pid 3208 Jul 14 07:46:01 myrivo dhcpcd[2833]: forked to background, child pid 3208 Jul 14 07:46:01 myrivo systemd[1]: Started DHCP Client Daemon. Jul 14 07:46:01 myrivo wireless.js[2828]: trying... Jul 14 07:46:01 myrivo sudo[3210]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 14 07:46:01 myrivo sudo[3210]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 14 07:46:01 myrivo sudo[3210]: pam_unix(sudo:session): session closed for user root Jul 14 07:46:02 myrivo kernel: meson_uart ffd24000.serial: ttyS1 use xtal(24M) 24000000 change 115200 to 2000000 Jul 14 07:46:02 myrivo systemd[1]: Starting Bluetooth service... Jul 14 07:46:02 myrivo systemd[1]: Starting Load/Save RF Kill Switch Status... Jul 14 07:46:02 myrivo systemd[1]: Started Load/Save RF Kill Switch Status. Jul 14 07:46:02 myrivo bluetoothd[3214]: Bluetooth daemon 5.50 Jul 14 07:46:02 myrivo bluetoothd[3214]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Jul 14 07:46:02 myrivo systemd[1]: Started Bluetooth service. Jul 14 07:46:02 myrivo bluetoothd[3214]: Starting SDP server Jul 14 07:46:02 myrivo bluetoothd[3214]: Excluding (cli) sap Jul 14 07:46:02 myrivo systemd[1]: Reached target Bluetooth. Jul 14 07:46:02 myrivo dbus-daemon[2832]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.5' (uid=0 pid=3214 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap " label="kernel") Jul 14 07:46:02 myrivo bluetoothd[3214]: Bluetooth management interface 1.14 initialized Jul 14 07:46:02 myrivo systemd[1]: Starting Hostname Service... Jul 14 07:46:02 myrivo kernel: IPVS: Creating netns size=1928 id=2 Jul 14 07:46:02 myrivo dbus-daemon[2832]: [system] Successfully activated service 'org.freedesktop.hostname1' Jul 14 07:46:02 myrivo systemd[1]: Started Hostname Service. Jul 14 07:46:02 myrivo wireless.js[2828]: trying... Jul 14 07:46:02 myrivo sudo[3222]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 14 07:46:02 myrivo sudo[3222]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 14 07:46:02 myrivo sudo[3222]: pam_unix(sudo:session): session closed for user root Jul 14 07:46:02 myrivo ntpd[3046]: Listen normally on 3 eth0 70.12.14.88:123 Jul 14 07:46:02 myrivo ntpd[3046]: new interface(s) found: waking up resolver Jul 14 07:46:03 myrivo wireless.js[2828]: trying... Jul 14 07:46:03 myrivo sudo[3225]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 14 07:46:03 myrivo sudo[3225]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 14 07:46:03 myrivo sudo[3225]: pam_unix(sudo:session): session closed for user root Jul 14 07:46:04 myrivo 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. Jul 14 07:46:04 myrivo nmbd[3039]: [2024/07/14 07:46:04.271308, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jul 14 07:46:04 myrivo nmbd[3039]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Jul 14 07:46:04 myrivo systemd[1]: Started Samba NMB Daemon. Jul 14 07:46:04 myrivo systemd[1]: Starting Samba Winbind Daemon... Jul 14 07:46:04 myrivo winbindd[3228]: [2024/07/14 07:46:04.630113, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Jul 14 07:46:04 myrivo winbindd[3228]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Jul 14 07:46:04 myrivo winbindd[3228]: [2024/07/14 07:46:04.642393, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jul 14 07:46:04 myrivo winbindd[3228]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Jul 14 07:46:04 myrivo systemd[1]: Started Samba Winbind Daemon. Jul 14 07:46:04 myrivo systemd[1]: Starting Samba SMB Daemon... Jul 14 07:46:04 myrivo wireless.js[2828]: trying... Jul 14 07:46:04 myrivo sudo[3234]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 14 07:46:04 myrivo sudo[3234]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 14 07:46:04 myrivo sudo[3234]: pam_unix(sudo:session): session closed for user root Jul 14 07:46:05 myrivo dhcpcd[3208]: wlan0: using static address 70.12.14.188/24 Jul 14 07:46:05 myrivo avahi-daemon[2834]: Joining mDNS multicast group on interface wlan0.IPv4 with address 70.12.14.188. Jul 14 07:46:05 myrivo dhcpcd[3208]: wlan0: adding route to 70.12.14.0/24 Jul 14 07:46:05 myrivo dhcpcd[3208]: wlan0: adding default route via 70.12.14.1 Jul 14 07:46:05 myrivo avahi-daemon[2834]: New relevant interface wlan0.IPv4 for mDNS. Jul 14 07:46:05 myrivo avahi-daemon[2834]: Registering new address record for 70.12.14.188 on wlan0.IPv4. Jul 14 07:46:05 myrivo smbd[3232]: [2024/07/14 07:46:05.195330, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jul 14 07:46:05 myrivo smbd[3232]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Jul 14 07:46:05 myrivo systemd[1]: Started Samba SMB Daemon. Jul 14 07:46:05 myrivo volumio-remote-updater[2825]: [2024-07-14 07:46:05] [info] asio async_connect error: system:111 (Connection refused) Jul 14 07:46:05 myrivo volumio-remote-updater[2825]: [2024-07-14 07:46:05] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jul 14 07:46:05 myrivo volumio-remote-updater[2825]: [2024-07-14 07:46:05] [error] handle_connect error: Underlying Transport Error Jul 14 07:46:05 myrivo wireless.js[2828]: trying... Jul 14 07:46:05 myrivo sudo[3262]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 14 07:46:05 myrivo sudo[3262]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 14 07:46:05 myrivo sudo[3262]: pam_unix(sudo:session): session closed for user root Jul 14 07:46:05 myrivo wireless.js[2828]: Connected to: ----manim Jul 14 07:46:05 myrivo wireless.js[2828]: ---- Jul 14 07:46:05 myrivo sudo[3265]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 14 07:46:05 myrivo sudo[3265]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 14 07:46:05 myrivo sudo[3265]: pam_unix(sudo:session): session closed for user root Jul 14 07:46:05 myrivo wireless.js[2828]: ... joined AP, wlan0 IPv4 is 70.12.14.188, ipV6 is undefined Jul 14 07:46:05 myrivo wireless.js[2828]: It's done! AP Jul 14 07:46:05 myrivo systemd[1]: Started Wireless Services. Jul 14 07:46:05 myrivo systemd[1]: Started Volumio Backend Module. Jul 14 07:46:05 myrivo systemd[1]: Started Volumio Cpu Tweaker. Jul 14 07:46:05 myrivo systemd[1]: Reached target Multi-User System. Jul 14 07:46:05 myrivo systemd[1]: Reached target Graphical Interface. Jul 14 07:46:05 myrivo systemd[1]: Starting Update UTMP about System Runlevel Changes... Jul 14 07:46:06 myrivo systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Jul 14 07:46:06 myrivo systemd[1]: Started Update UTMP about System Runlevel Changes. Jul 14 07:46:06 myrivo systemd[1]: Startup finished in 12.805s (kernel) + 19.003s (userspace) = 31.808s. Jul 14 07:46:06 myrivo volumio-cpu-tweak[3272]: Setting RT Priority for mpd Jul 14 07:46:06 myrivo volumio-cpu-tweak[3272]: chrt: cannot obtain the list of tasks: No such file or directory Jul 14 07:46:06 myrivo volumio-cpu-tweak[3272]: Setting MPD Affinity Jul 14 07:46:06 myrivo volumio-cpu-tweak[3272]: pid 3's current affinity mask: 1 Jul 14 07:46:06 myrivo volumio-cpu-tweak[3272]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Jul 14 07:46:06 myrivo volumio-cpu-tweak[3272]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Jul 14 07:46:06 myrivo systemd[1]: volumio_cpu_tweak.service: Succeeded. Jul 14 07:46:06 myrivo ntpd[3046]: Listen normally on 4 wlan0 70.12.14.188:123 Jul 14 07:46:06 myrivo ntpd[3046]: new interface(s) found: waking up resolver Jul 14 07:46:07 myrivo systemd[1]: systemd-rfkill.service: Succeeded. Jul 14 07:46:09 myrivo volumio[3271]: info: ------------------------------------------- Jul 14 07:46:09 myrivo volumio[3271]: info: ----- Volumio3 ---- Jul 14 07:46:09 myrivo volumio[3271]: info: ------------------------------------------- Jul 14 07:46:09 myrivo volumio[3271]: info: ----- System startup ---- Jul 14 07:46:09 myrivo volumio[3271]: info: ------------------------------------------- Jul 14 07:46:10 myrivo volumio[3271]: info: MYVOLUMIO Environment detected Jul 14 07:46:10 myrivo volumio[3271]: info: Plugin folders cleanup Jul 14 07:46:10 myrivo volumio[3271]: info: Scanning into folder /volumio/app/plugins/ Jul 14 07:46:10 myrivo volumio[3271]: info: Scanning category audio_interface Jul 14 07:46:10 myrivo volumio[3271]: info: Scanning category miscellanea Jul 14 07:46:10 myrivo volumio[3271]: info: Scanning category music_service Jul 14 07:46:10 myrivo volumio[3271]: info: Scanning category plugins.json Jul 14 07:46:10 myrivo volumio[3271]: info: Scanning category system_controller Jul 14 07:46:10 myrivo volumio[3271]: info: Scanning category user_interface Jul 14 07:46:10 myrivo volumio[3271]: info: Scanning into folder /data/plugins/ Jul 14 07:46:10 myrivo volumio[3271]: info: Scanning category music_service Jul 14 07:46:10 myrivo volumio[3271]: info: Plugin folders cleanup completed Jul 14 07:46:10 myrivo volumio[3271]: info: ------------------------------------------- Jul 14 07:46:10 myrivo volumio[3271]: info: ----- Core plugins startup ---- Jul 14 07:46:10 myrivo volumio[3271]: info: ------------------------------------------- Jul 14 07:46:10 myrivo volumio[3271]: info: Loading plugins from folder /volumio/app/plugins/ Jul 14 07:46:10 myrivo volumio[3271]: info: Adding plugin bluetooth to MyMusic Plugins Jul 14 07:46:10 myrivo volumio[3271]: info: Adding plugin multiroom to MyMusic Plugins Jul 14 07:46:10 myrivo volumio[3271]: info: Adding plugin upnp to MyMusic Plugins Jul 14 07:46:10 myrivo volumio[3271]: info: Adding plugin metavolumio to MyMusic Plugins Jul 14 07:46:10 myrivo volumio[3271]: info: Adding plugin airplay_emulation to MyMusic Plugins Jul 14 07:46:10 myrivo volumio[3271]: info: Adding plugin cd_controller to MyMusic Plugins Jul 14 07:46:10 myrivo volumio[3271]: info: Adding plugin raat to MyMusic Plugins Jul 14 07:46:10 myrivo volumio[3271]: info: Adding plugin smart_inputs to MyMusic Plugins Jul 14 07:46:10 myrivo volumio[3271]: info: Adding plugin tidalconnect to MyMusic Plugins Jul 14 07:46:10 myrivo volumio[3271]: info: Adding plugin upnp_browser to MyMusic Plugins Jul 14 07:46:10 myrivo volumio[3271]: info: Loading plugins from folder /data/plugins/ Jul 14 07:46:10 myrivo volumio[3271]: info: Loading plugin "system"... Jul 14 07:46:10 myrivo volumio[3271]: info: Loading plugin "appearance"... Jul 14 07:46:12 myrivo volumio[3271]: info: Loading plugin "network"... Jul 14 07:46:12 myrivo volumio[3271]: info: Refreshing Cached IP Addresses Jul 14 07:46:12 myrivo sudo[3303]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 14 07:46:12 myrivo sudo[3303]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 14 07:46:12 myrivo volumio[3271]: info: Loading plugin "services"... Jul 14 07:46:12 myrivo sudo[3305]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 14 07:46:12 myrivo sudo[3305]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 14 07:46:12 myrivo sudo[3303]: pam_unix(sudo:session): session closed for user root Jul 14 07:46:12 myrivo volumio[3271]: info: Loading plugin "alsa_controller"... Jul 14 07:46:12 myrivo sudo[3308]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jul 14 07:46:12 myrivo sudo[3305]: pam_unix(sudo:session): session closed for user root Jul 14 07:46:12 myrivo sudo[3308]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 14 07:46:12 myrivo kernel: wl_escan_set_scan: ioctl_ver=2 Jul 14 07:46:12 myrivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03cf31000 drv_status 0x0 e_type 69 e_states 8 Jul 14 07:46:12 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 14 07:46:12 myrivo volumio[3271]: info: Loading plugin "wizard"... Jul 14 07:46:12 myrivo volumio[3271]: info: Loading plugin "networkfs"... Jul 14 07:46:12 myrivo volumio[3271]: info: Starting Udev Watcher for removable devices Jul 14 07:46:12 myrivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03cf31000 drv_status 0x0 e_type 69 e_states 8 Jul 14 07:46:12 myrivo sudo[3316]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=kkhayk,password=M@nim1005001,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //70.12.14.150/music /mnt/NAS/music Jul 14 07:46:12 myrivo sudo[3316]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 14 07:46:12 myrivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03cf31000 drv_status 0x0 e_type 69 e_states 8 Jul 14 07:46:12 myrivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03cf31000 drv_status 0x0 e_type 69 e_states 8 Jul 14 07:46:12 myrivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03cf31000 drv_status 0x0 e_type 69 e_states 8 Jul 14 07:46:12 myrivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03cf31000 drv_status 0x0 e_type 69 e_states 8 Jul 14 07:46:12 myrivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03cf31000 drv_status 0x0 e_type 69 e_states 8 Jul 14 07:46:12 myrivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03cf31000 drv_status 0x0 e_type 69 e_states 8 Jul 14 07:46:12 myrivo volumio[3271]: info: Ignoring mount for partition: BOOT Jul 14 07:46:12 myrivo volumio[3271]: info: Ignoring mount for partition: volumio Jul 14 07:46:12 myrivo volumio[3271]: info: Ignoring mount for partition: volumio_data Jul 14 07:46:12 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 14 07:46:12 myrivo volumio[3271]: info: Loading plugin "volumio_command_line_client"... Jul 14 07:46:12 myrivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03cf31000 drv_status 0x0 e_type 69 e_states 8 Jul 14 07:46:12 myrivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03cf31000 drv_status 0x0 e_type 69 e_states 8 Jul 14 07:46:12 myrivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03cf31000 drv_status 0x0 e_type 69 e_states 8 Jul 14 07:46:12 myrivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03cf31000 drv_status 0x0 e_type 69 e_states 8 Jul 14 07:46:12 myrivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03cf31000 drv_status 0x0 e_type 69 e_states 8 Jul 14 07:46:12 myrivo volumio[3271]: info: Plugin upnp is not enabled Jul 14 07:46:12 myrivo volumio[3271]: info: Loading plugin "my_music"... Jul 14 07:46:12 myrivo volumio[3271]: info: Loading plugin "mpd"... Jul 14 07:46:12 myrivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03cf31000 drv_status 0x0 e_type 69 e_states 8 Jul 14 07:46:12 myrivo sudo[3316]: pam_unix(sudo:session): session closed for user root Jul 14 07:46:12 myrivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03cf31000 drv_status 0x0 e_type 69 e_states 8 Jul 14 07:46:12 myrivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03cf31000 drv_status 0x0 e_type 69 e_states 8 Jul 14 07:46:12 myrivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03cf31000 drv_status 0x0 e_type 69 e_states 8 Jul 14 07:46:13 myrivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03cf31000 drv_status 0x0 e_type 69 e_states 8 Jul 14 07:46:13 myrivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03cf31000 drv_status 0x0 e_type 69 e_states 8 Jul 14 07:46:13 myrivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03cf31000 drv_status 0x0 e_type 69 e_states 8 Jul 14 07:46:13 myrivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03cf31000 drv_status 0x0 e_type 69 e_states 8 Jul 14 07:46:13 myrivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03cf31000 drv_status 0x0 e_type 69 e_states 8 Jul 14 07:46:13 myrivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03cf31000 drv_status 0x0 e_type 69 e_states 8 Jul 14 07:46:13 myrivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03cf31000 drv_status 0x0 e_type 69 e_states 8 Jul 14 07:46:13 myrivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03cf31000 drv_status 0x0 e_type 69 e_states 8 Jul 14 07:46:13 myrivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03cf31000 drv_status 0x0 e_type 69 e_states 8 Jul 14 07:46:13 myrivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03cf31000 drv_status 0x0 e_type 69 e_states 8 Jul 14 07:46:13 myrivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03cf31000 drv_status 0x0 e_type 69 e_states 8 Jul 14 07:46:13 myrivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03cf31000 drv_status 0x0 e_type 69 e_states 8 Jul 14 07:46:13 myrivo volumio[3271]: info: Plugin upnp_browser is not enabled Jul 14 07:46:13 myrivo volumio[3271]: info: Loading plugin "bluetooth"... Jul 14 07:46:13 myrivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03cf31000 drv_status 0x0 e_type 69 e_states 8 Jul 14 07:46:13 myrivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03cf31000 drv_status 0x0 e_type 69 e_states 8 Jul 14 07:46:13 myrivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03cf31000 drv_status 0x0 e_type 69 e_states 8 Jul 14 07:46:13 myrivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03cf31000 drv_status 0x0 e_type 69 e_states 8 Jul 14 07:46:13 myrivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03cf31000 drv_status 0x0 e_type 69 e_states 8 Jul 14 07:46:13 myrivo volumio[3271]: info: [1720910773451] Starting BluetoothController Jul 14 07:46:13 myrivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03cf31000 drv_status 0x0 e_type 69 e_states 8 Jul 14 07:46:13 myrivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03cf31000 drv_status 0x0 e_type 69 e_states 8 Jul 14 07:46:13 myrivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03cf31000 drv_status 0x0 e_type 69 e_states 8 Jul 14 07:46:13 myrivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03cf31000 drv_status 0x0 e_type 69 e_states 8 Jul 14 07:46:13 myrivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03cf31000 drv_status 0x0 e_type 69 e_states 8 Jul 14 07:46:13 myrivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03cf31000 drv_status 0x0 e_type 69 e_states 8 Jul 14 07:46:13 myrivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03cf31000 drv_status 0x0 e_type 69 e_states 8 Jul 14 07:46:13 myrivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03cf31000 drv_status 0x0 e_type 69 e_states 8 Jul 14 07:46:13 myrivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03cf31000 drv_status 0x0 e_type 69 e_states 8 Jul 14 07:46:13 myrivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03cf31000 drv_status 0x0 e_type 69 e_states 8 Jul 14 07:46:13 myrivo volumio[3271]: info: Loading plugin "alarm-clock"... Jul 14 07:46:13 myrivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03cf31000 drv_status 0x0 e_type 69 e_states 8 Jul 14 07:46:13 myrivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03cf31000 drv_status 0x0 e_type 69 e_states 8 Jul 14 07:46:13 myrivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03cf31000 drv_status 0x0 e_type 69 e_states 8 Jul 14 07:46:13 myrivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03cf31000 drv_status 0x0 e_type 69 e_states 8 Jul 14 07:46:13 myrivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03cf31000 drv_status 0x0 e_type 69 e_states 8 Jul 14 07:46:13 myrivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03cf31000 drv_status 0x0 e_type 69 e_states 8 Jul 14 07:46:13 myrivo volumio[3271]: info: Loading plugin "manifestui"... Jul 14 07:46:13 myrivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03cf31000 drv_status 0x0 e_type 69 e_states 8 Jul 14 07:46:14 myrivo volumio[3271]: info: Loading plugin "metavolumio"... Jul 14 07:46:14 myrivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03cf31000 drv_status 0x0 e_type 69 e_states 8 Jul 14 07:46:14 myrivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03cf31000 drv_status 0x0 e_type 69 e_states 8 Jul 14 07:46:15 myrivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03cf31000 drv_status 0x0 e_type 69 e_states 8 Jul 14 07:46:15 myrivo volumio-remote-updater[2825]: [2024-07-14 07:46:15] [connect] Successful connection Jul 14 07:46:15 myrivo volumio[3271]: info: Loading plugin "airplay_emulation"... Jul 14 07:46:15 myrivo volumio[3271]: info: Starting Shairport Sync Jul 14 07:46:15 myrivo volumio[3271]: info: Loading plugin "cd_controller"... Jul 14 07:46:15 myrivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03cf31000 drv_status 0x0 e_type 69 e_states 8 Jul 14 07:46:16 myrivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03cf31000 drv_status 0x0 e_type 69 e_states 8 Jul 14 07:46:16 myrivo volumio[3271]: info: Loading plugin "last_100"... Jul 14 07:46:16 myrivo volumio[3271]: info: Plugin raat is not enabled Jul 14 07:46:16 myrivo volumio[3271]: info: Loading plugin "streaming_services"... Jul 14 07:46:16 myrivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03cf31000 drv_status 0x0 e_type 69 e_states 8 Jul 14 07:46:16 myrivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03cf31000 drv_status 0x0 e_type 69 e_states 0 Jul 14 07:46:16 myrivo sudo[3308]: pam_unix(sudo:session): session closed for user root Jul 14 07:46:17 myrivo volumio[3271]: info: Starting Streaming Service Transparent Proxy Jul 14 07:46:17 myrivo volumio[3271]: info: Plugin tidalconnect is not enabled Jul 14 07:46:17 myrivo volumio[3271]: info: Loading plugin "webradio"... Jul 14 07:46:18 myrivo volumio[3271]: info: Loading plugin "i2s_dacs"... Jul 14 07:46:18 myrivo volumio[3271]: info: I2S DAC not set, start Auto-detection Jul 14 07:46:18 myrivo volumio[3271]: info: Loading plugin "volumiodiscovery"... Jul 14 07:46:18 myrivo volumio[3271]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 14 07:46:18 myrivo volumio[3271]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 14 07:46:18 myrivo node[3271]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 14 07:46:18 myrivo volumio[3271]: *** WARNING *** For more information see Jul 14 07:46:18 myrivo volumio[3271]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 14 07:46:18 myrivo volumio[3271]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 14 07:46:18 myrivo volumio[3271]: *** WARNING *** For more information see Jul 14 07:46:18 myrivo node[3271]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 14 07:46:18 myrivo node[3271]: *** WARNING *** For more information see Jul 14 07:46:18 myrivo node[3271]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 14 07:46:18 myrivo node[3271]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 14 07:46:18 myrivo node[3271]: *** WARNING *** For more information see Jul 14 07:46:18 myrivo volumio[3271]: info: Applying required configuration parameters for plugin volumiodiscovery Jul 14 07:46:18 myrivo volumio[3271]: info: Discovery: Started advertising with name: MyRivo Jul 14 07:46:18 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 14 07:46:18 myrivo volumio[3271]: info: Loading plugin "spop"... Jul 14 07:46:19 myrivo volumio[3271]: STREAMING PROXY: Starting server on port 3245 Jul 14 07:46:19 myrivo volumio[3271]: Node JS runtime: 14 Jul 14 07:46:19 myrivo volumio[3271]: info: Loading plugin "multiroom"... Jul 14 07:46:20 myrivo systemd[1]: systemd-fsckd.service: Succeeded. Jul 14 07:46:21 myrivo volumio[3271]: info: Applying required configuration parameters for plugin multiroom Jul 14 07:46:21 myrivo sudo[3341]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Jul 14 07:46:21 myrivo sudo[3341]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 14 07:46:21 myrivo sudo[3341]: pam_unix(sudo:session): session closed for user root Jul 14 07:46:21 myrivo volumio[3271]: info: MRS: MultiRoom plugin initialized Jul 14 07:46:21 myrivo volumio[3271]: info: MRS: STOPPING SNAPCLIENT Jul 14 07:46:21 myrivo volumio[3271]: info: MRS: Snap server stop Jul 14 07:46:22 myrivo sudo[3357]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Jul 14 07:46:22 myrivo sudo[3357]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 14 07:46:22 myrivo volumio[3271]: info: MRS: STOPPING volumioStreaming Jul 14 07:46:22 myrivo sudo[3359]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Jul 14 07:46:22 myrivo sudo[3359]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 14 07:46:22 myrivo sudo[3357]: pam_unix(sudo:session): session closed for user root Jul 14 07:46:22 myrivo sudo[3363]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Jul 14 07:46:22 myrivo volumio[3271]: info: Loading plugin "outputs"... Jul 14 07:46:22 myrivo sudo[3363]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 14 07:46:22 myrivo volumio[3271]: info: Loading plugin "albumart"... Jul 14 07:46:22 myrivo sudo[3365]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Jul 14 07:46:22 myrivo sudo[3365]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 14 07:46:22 myrivo sudo[3365]: pam_unix(sudo:session): session closed for user root Jul 14 07:46:22 myrivo sudo[3359]: pam_unix(sudo:session): session closed for user root Jul 14 07:46:22 myrivo volumio[3271]: info: Plugin example_plugin is not enabled Jul 14 07:46:22 myrivo volumio[3271]: info: Loading plugin "hi_res_audio"... Jul 14 07:46:22 myrivo sudo[3363]: pam_unix(sudo:session): session closed for user root Jul 14 07:46:22 myrivo volumio[3271]: Forking 3 albumart workers Jul 14 07:46:23 myrivo volumio[3271]: Starting albumart workers Jul 14 07:46:23 myrivo volumio[3271]: Starting albumart workers Jul 14 07:46:23 myrivo volumio[3271]: Starting albumart workers Jul 14 07:46:23 myrivo volumio[3271]: info: Applying required configuration parameters for plugin hi_res_audio Jul 14 07:46:23 myrivo volumio[3271]: info: Loading plugin "inputs"... Jul 14 07:46:24 myrivo volumio[3271]: info: Loading plugin "qobuz"... Jul 14 07:46:26 myrivo volumio[3271]: info: Plugin smart_inputs is not enabled Jul 14 07:46:26 myrivo volumio[3271]: info: Loading plugin "tidal"... Jul 14 07:46:28 myrivo volumio[3271]: info: Loading plugin "rivocontrol"... Jul 14 07:46:28 myrivo volumio[3271]: info: Adding this device properties Jul 14 07:46:28 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: system , setThisDeviceVolumioProperties Jul 14 07:46:28 myrivo volumio[3271]: info: Setting Additional Device Volumio Properties: [object Object] Jul 14 07:46:28 myrivo volumio[3271]: info: Loading plugin "updater_comm"... Jul 14 07:46:29 myrivo volumio[3271]: info: Loading plugin "cec_controller"... Jul 14 07:46:29 myrivo volumio[3271]: info: Applying required configuration parameters for plugin cec_controller Jul 14 07:46:29 myrivo volumio[3271]: info: Plugin mpdemulation is not enabled Jul 14 07:46:29 myrivo volumio[3271]: info: Loading plugin "rest_api"... Jul 14 07:46:29 myrivo volumio[3271]: info: Loading plugin "websocket"... Jul 14 07:46:29 myrivo volumio[3271]: info: Starting Socket.io Server version 2.3.0 Jul 14 07:46:29 myrivo volumio[3271]: info: Loading i18n strings for locale ja Jul 14 07:46:29 myrivo volumio[3271]: Updating browse sources language Jul 14 07:46:29 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 14 07:46:30 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 14 07:46:30 myrivo volumio[3271]: info: CoreCommandRouter::initPlayerControls Jul 14 07:46:30 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 14 07:46:30 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 14 07:46:30 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 14 07:46:30 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 14 07:46:30 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 14 07:46:30 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 14 07:46:30 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 14 07:46:30 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 14 07:46:30 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 14 07:46:30 myrivo volumio[3271]: Express server listening on port 3000 Jul 14 07:46:30 myrivo volumio[3271]: [Metrics] WebUI: 21s 971.99ms Jul 14 07:46:30 myrivo volumio[3271]: info: CoreStateMachine::resetVolumioState Jul 14 07:46:30 myrivo volumio[3271]: info: CoreStateMachine::getcurrentVolume Jul 14 07:46:30 myrivo volumio[3271]: info: CoreCommandRouter::volumioRetrievevolume Jul 14 07:46:30 myrivo volumio[3271]: info: CoreStateMachine::pushState Jul 14 07:46:30 myrivo volumio[3271]: info: CorePlayQueue::getTrack 0 Jul 14 07:46:30 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 14 07:46:30 myrivo volumio[3271]: info: CoreCommandRouter::volumioPushState Jul 14 07:46:30 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 14 07:46:30 myrivo volumio[3271]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Jul 14 07:46:30 myrivo volumio[3271]: info: Completed loading Core Plugins Jul 14 07:46:30 myrivo volumio[3271]: info: Preparing to generate the ALSA configuration file Jul 14 07:46:30 myrivo volumio[3271]: info: MRS: Removed streaming files Jul 14 07:46:30 myrivo volumio[3271]: info: MRS: volumioStreaming STOPPED Jul 14 07:46:30 myrivo volumio[3271]: info: MRS: SNAPSERVER STOPPED Jul 14 07:46:30 myrivo volumio[3271]: info: MRS: SNAPCLIENT STOPPED Jul 14 07:46:30 myrivo volumio[3271]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Jul 14 07:46:30 myrivo volumio[3271]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jul 14 07:46:30 myrivo volumio[3271]: info: Reading ALSA contributions from plugins. Jul 14 07:46:30 myrivo volumio[3271]: verbose: New Socket.io Connection to 70.12.14.188 from 70.12.14.228 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_5_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 1 Jul 14 07:46:30 myrivo volumio[3271]: verbose: New Socket.io Connection to 70.12.14.88 from 70.12.14.228 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_5_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 2 Jul 14 07:46:30 myrivo volumio[3271]: verbose: New Socket.io Connection to 70.12.14.188 from 70.12.14.228 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_5_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 3 Jul 14 07:46:30 myrivo volumio[3271]: verbose: New Socket.io Connection to 70.12.14.188 from 70.12.14.228 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_5_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 4 Jul 14 07:46:30 myrivo volumio[3271]: verbose: New Socket.io Connection to 70.12.14.88 from 70.12.14.228 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_5_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 5 Jul 14 07:46:30 myrivo volumio[3271]: verbose: New Socket.io Connection to 70.12.14.188 from 70.12.14.228 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_5_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 6 Jul 14 07:46:30 myrivo volumio[3271]: verbose: New Socket.io Connection to 70.12.14.188 from 70.12.14.228 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_5_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 7 Jul 14 07:46:30 myrivo volumio-remote-updater[2825]: [2024-07-14 07:46:30] [connect] Successful connection Jul 14 07:46:30 myrivo volumio[3271]: info: Reloading queue from file Jul 14 07:46:30 myrivo volumio[3271]: info: CoreStateMachine::setRepeat null single undefined Jul 14 07:46:30 myrivo volumio[3271]: info: CoreStateMachine::pushState Jul 14 07:46:30 myrivo volumio[3271]: info: CorePlayQueue::getTrack 0 Jul 14 07:46:30 myrivo volumio[3271]: info: CoreCommandRouter::volumioPushState Jul 14 07:46:30 myrivo volumio[3271]: info: CoreStateMachine::setRandom false Jul 14 07:46:30 myrivo volumio[3271]: info: CoreStateMachine::pushState Jul 14 07:46:30 myrivo volumio[3271]: info: CorePlayQueue::getTrack 0 Jul 14 07:46:30 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 14 07:46:30 myrivo volumio[3271]: info: CoreCommandRouter::volumioPushState Jul 14 07:46:30 myrivo volumio[3271]: verbose: New Socket.io Connection to 70.12.14.188 from 70.12.14.228 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_5_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 8 Jul 14 07:46:30 myrivo volumio[3271]: verbose: New Socket.io Connection to 70.12.14.188 from 70.12.14.228 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_5_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 9 Jul 14 07:46:30 myrivo volumio[3271]: verbose: New Socket.io Connection to 70.12.14.188 from 70.12.14.228 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_5_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 10 Jul 14 07:46:30 myrivo volumio[3271]: verbose: New Socket.io Connection to 70.12.14.188 from 70.12.14.228 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_5_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 11 Jul 14 07:46:30 myrivo volumio[3271]: verbose: New Socket.io Connection to 70.12.14.188 from 70.12.14.228 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_5_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 12 Jul 14 07:46:30 myrivo volumio[3271]: verbose: New Socket.io Connection to 70.12.14.88 from 70.12.14.228 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_5_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 13 Jul 14 07:46:30 myrivo volumio[3271]: verbose: New Socket.io Connection to 70.12.14.88 from 70.12.14.228 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_5_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 14 Jul 14 07:46:30 myrivo volumio[3271]: verbose: New Socket.io Connection to 70.12.14.188 from 70.12.14.228 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_5_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 15 Jul 14 07:46:30 myrivo volumio[3271]: verbose: New Socket.io Connection to 70.12.14.188 from 70.12.14.228 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_5_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 16 Jul 14 07:46:30 myrivo volumio[3271]: verbose: New Socket.io Connection to 70.12.14.188 from 70.12.14.228 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_5_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 17 Jul 14 07:46:30 myrivo volumio[3271]: verbose: New Socket.io Connection to 70.12.14.188 from 70.12.14.228 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_5_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 18 Jul 14 07:46:30 myrivo volumio[3271]: verbose: New Socket.io Connection to 70.12.14.188 from 70.12.14.228 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_5_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 19 Jul 14 07:46:30 myrivo volumio[3271]: verbose: New Socket.io Connection to 70.12.14.88 from 70.12.14.228 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_5_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 20 Jul 14 07:46:30 myrivo volumio-remote-updater[2825]: [2024-07-14 07:46:30] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1720910790 101 Jul 14 07:46:30 myrivo volumio[3271]: 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: 21 Jul 14 07:46:30 myrivo volumio[3271]: info: Discovery: adding 4427db92-ab9f-4602-ab0e-96ed1780744d Jul 14 07:46:30 myrivo volumio[3271]: info: Discovery: Found device MyRivo Jul 14 07:46:30 myrivo volumio[3271]: info: CoreCommandRouter::volumioGetState Jul 14 07:46:30 myrivo volumio[3271]: info: CorePlayQueue::getTrack 0 Jul 14 07:46:30 myrivo volumio[3271]: info: MRS: Pushing multiroomSync output for this device Jul 14 07:46:30 myrivo volumio[3271]: info: MRS: Pushing multiroomSync output Jul 14 07:46:30 myrivo volumio[3271]: info: Adding audio output: Jul 14 07:46:30 myrivo volumio[3271]: info: Adding audio output: Jul 14 07:46:30 myrivo volumio[3271]: verbose: New Socket.io Connection to 70.12.14.188 from 70.12.14.228 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_5_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 22 Jul 14 07:46:30 myrivo volumio[3271]: verbose: New Socket.io Connection to 70.12.14.188 from 70.12.14.228 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_5_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 23 Jul 14 07:46:30 myrivo volumio[3271]: verbose: New Socket.io Connection to 70.12.14.188 from 70.12.14.228 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_5_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 24 Jul 14 07:46:30 myrivo volumio[3271]: verbose: New Socket.io Connection to 70.12.14.188 from 70.12.14.228 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_5_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 25 Jul 14 07:46:30 myrivo volumio[3271]: verbose: New Socket.io Connection to 70.12.14.188 from 70.12.14.228 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_5_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 26 Jul 14 07:46:30 myrivo volumio[3271]: verbose: New Socket.io Connection to 70.12.14.88 from 70.12.14.228 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_5_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 27 Jul 14 07:46:30 myrivo volumio[3271]: verbose: New Socket.io Connection to 70.12.14.188 from 70.12.14.228 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_5_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 28 Jul 14 07:46:30 myrivo volumio[3271]: verbose: New Socket.io Connection to 70.12.14.188 from 70.12.14.228 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_5_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 29 Jul 14 07:46:30 myrivo volumio[3271]: verbose: New Socket.io Connection to 70.12.14.188 from 70.12.14.228 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_5_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 30 Jul 14 07:46:30 myrivo volumio[3271]: verbose: New Socket.io Connection to 70.12.14.188 from 70.12.14.228 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_5_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 31 Jul 14 07:46:30 myrivo volumio[3271]: verbose: New Socket.io Connection to 70.12.14.188 from 70.12.14.228 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_5_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 32 Jul 14 07:46:30 myrivo volumio[3271]: info: Discovery: this is already registered, 4427db92-ab9f-4602-ab0e-96ed1780744d Jul 14 07:46:30 myrivo volumio[3271]: info: Discovery: Found device MyRivo Jul 14 07:46:30 myrivo volumio[3271]: info: CoreCommandRouter::volumioGetState Jul 14 07:46:30 myrivo volumio[3271]: info: CorePlayQueue::getTrack 0 Jul 14 07:46:30 myrivo volumio[3271]: verbose: New Socket.io Connection to 70.12.14.88 from 70.12.14.228 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_5_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 33 Jul 14 07:46:30 myrivo volumio[3271]: verbose: New Socket.io Connection to 70.12.14.88 from 70.12.14.228 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_5_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 34 Jul 14 07:46:30 myrivo volumio[3271]: info: Asound.conf file unchanged, so no further update is needed Jul 14 07:46:30 myrivo volumio[3271]: info: Output device has changed, restarting MPD Jul 14 07:46:30 myrivo sudo[3432]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 14 07:46:30 myrivo sudo[3432]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 14 07:46:30 myrivo volumio[3271]: info: Output device has changed, restarting Shairport Sync Jul 14 07:46:30 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 14 07:46:30 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 14 07:46:30 myrivo sudo[3432]: pam_unix(sudo:session): session closed for user root Jul 14 07:46:30 myrivo sudo[3435]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 14 07:46:30 myrivo sudo[3435]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 14 07:46:30 myrivo systemd[1]: Listening on mpd.socket. Jul 14 07:46:30 myrivo systemd[1]: Starting Music Player Daemon... Jul 14 07:46:31 myrivo volumio[3271]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 14 07:46:31 myrivo volumio[3271]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jul 14 07:46:31 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 14 07:46:31 myrivo volumio[3271]: info: ___________ START PLUGINS ___________ Jul 14 07:46:31 myrivo volumio[3271]: info: ControllerMpd::onStart: Initializing MPD Jul 14 07:46:31 myrivo volumio[3271]: info: Creating MPD Configuration file Jul 14 07:46:31 myrivo sudo[3451]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 14 07:46:31 myrivo sudo[3451]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 14 07:46:31 myrivo sudo[3451]: pam_unix(sudo:session): session closed for user root Jul 14 07:46:31 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 14 07:46:31 myrivo sudo[3441]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 14 07:46:31 myrivo sudo[3441]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 14 07:46:31 myrivo sudo[3456]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 14 07:46:31 myrivo sudo[3456]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 14 07:46:31 myrivo sudo[3441]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Jul 14 07:46:31 myrivo sudo[3441]: pam_unix(sudo:session): session closed for user root Jul 14 07:46:31 myrivo volumio[3271]: info: Adding Manifest REST API Endpoints Jul 14 07:46:31 myrivo volumio[3271]: info: Adding manifestui REST Endpoint for plugin: miscellanea/manifestui Jul 14 07:46:31 myrivo volumio[3271]: info: Adding METAVOLUMIO REST API Endpoints Jul 14 07:46:31 myrivo volumio[3271]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Jul 14 07:46:31 myrivo volumio[3271]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Jul 14 07:46:31 myrivo volumio[3271]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Jul 14 07:46:31 myrivo volumio[3271]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Jul 14 07:46:31 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 14 07:46:31 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 14 07:46:31 myrivo sudo[3461]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name MyRivo Jul 14 07:46:31 myrivo systemd[1]: mpd.service: Succeeded. Jul 14 07:46:31 myrivo sudo[3461]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 14 07:46:31 myrivo systemd[1]: Stopped Music Player Daemon. Jul 14 07:46:31 myrivo systemd[1]: Starting Music Player Daemon... Jul 14 07:46:31 myrivo sudo[3461]: pam_unix(sudo:session): session closed for user root Jul 14 07:46:31 myrivo volumio[3271]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 14 07:46:31 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 14 07:46:31 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 14 07:46:31 myrivo volumio[3271]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 14 07:46:31 myrivo volumio[3271]: info: Preparing CD Folders Jul 14 07:46:31 myrivo volumio[3271]: info: Adding CD REST API Endpoints Jul 14 07:46:31 myrivo volumio[3271]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Jul 14 07:46:31 myrivo volumio[3271]: info: Starting UDEV Watcher for CD Jul 14 07:46:31 myrivo volumio[3271]: info: Detecting CD presence with UDEV Jul 14 07:46:31 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Jul 14 07:46:31 myrivo sudo[3464]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 14 07:46:31 myrivo sudo[3464]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 14 07:46:31 myrivo volumio[3271]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 14 07:46:31 myrivo volumio[3271]: info: [1720910791365] CoreMusicLibrary::Adding element Last_100 Jul 14 07:46:31 myrivo sudo[3464]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Jul 14 07:46:31 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 14 07:46:31 myrivo sudo[3464]: pam_unix(sudo:session): session closed for user root Jul 14 07:46:31 myrivo volumio[3271]: info: Streaming services startup Jul 14 07:46:31 myrivo volumio[3271]: info: Starting Streaming Daemon Jul 14 07:46:31 myrivo sudo[3473]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jul 14 07:46:31 myrivo sudo[3473]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 14 07:46:31 myrivo sudo[3473]: pam_unix(sudo:session): session closed for user root Jul 14 07:46:31 myrivo volumio[3271]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 14 07:46:31 myrivo volumio[3271]: info: [1720910791488] CoreMusicLibrary::Adding element Webradio Jul 14 07:46:31 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 14 07:46:31 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 14 07:46:31 myrivo volumio[3271]: info: Initializing BBC Radios Jul 14 07:46:31 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 14 07:46:31 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 14 07:46:31 myrivo volumio[3271]: info: Creating Spotify config file Jul 14 07:46:31 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 14 07:46:31 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Jul 14 07:46:31 myrivo volumio[3271]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jul 14 07:46:31 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 14 07:46:31 myrivo volumio[3271]: error: Hi Res Audio Failed Login: Missing Login Data Jul 14 07:46:31 myrivo volumio[3271]: info: Adding HIGHRESAUDIO REST API Endpoints Jul 14 07:46:31 myrivo volumio[3271]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Jul 14 07:46:31 myrivo volumio[3271]: info: Initializing Serial Communication on port /dev/ttyS3 Jul 14 07:46:31 myrivo volumio[3271]: info: Touch Event Listener Process Starting Jul 14 07:46:31 myrivo kernel: meson_uart ffd22000.serial: ttyS3 use xtal(24M) 24000000 change 0 to 9600 Jul 14 07:46:31 myrivo kernel: meson_uart ffd22000.serial: ttyS3 use xtal(24M) 24000000 change 9600 to 9600 Jul 14 07:46:31 myrivo kernel: meson_uart ffd22000.serial: ttyS3 use xtal(24M) 24000000 change 9600 to 115200 Jul 14 07:46:31 myrivo sudo[3495]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xinput --test-xi2 --root Jul 14 07:46:31 myrivo sudo[3495]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 14 07:46:31 myrivo sudo[3495]: pam_unix(sudo:session): session closed for user root Jul 14 07:46:31 myrivo sudo[3504]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x25 0x0 Jul 14 07:46:31 myrivo sudo[3504]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 14 07:46:31 myrivo sudo[3504]: pam_unix(sudo:session): session closed for user root Jul 14 07:46:31 myrivo sudo[3507]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x28 0x0 Jul 14 07:46:31 myrivo sudo[3507]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 14 07:46:31 myrivo sudo[3507]: pam_unix(sudo:session): session closed for user root Jul 14 07:46:32 myrivo sudo[3510]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x29 0x1 Jul 14 07:46:32 myrivo sudo[3510]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 14 07:46:32 myrivo sudo[3510]: pam_unix(sudo:session): session closed for user root Jul 14 07:46:32 myrivo sudo[3513]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x33 0x1 Jul 14 07:46:32 myrivo sudo[3513]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 14 07:46:32 myrivo sudo[3513]: pam_unix(sudo:session): session closed for user root Jul 14 07:46:32 myrivo volumio[3271]: info: Apply VIM3L Onboard LEDs Settings Jul 14 07:46:32 myrivo volumio[3271]: info: Turning On Onboard LEDs Jul 14 07:46:32 myrivo sudo[3517]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/sys_led/trigger Jul 14 07:46:32 myrivo sudo[3517]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 14 07:46:32 myrivo volumio[3271]: info: Starting CEC Event listener Jul 14 07:46:32 myrivo sudo[3517]: pam_unix(sudo:session): session closed for user root Jul 14 07:46:32 myrivo volumio[3271]: info: Volumio Calling Home Jul 14 07:46:32 myrivo sudo[3521]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/red_red/trigger Jul 14 07:46:32 myrivo sudo[3521]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 14 07:46:32 myrivo sudo[3521]: pam_unix(sudo:session): session closed for user root Jul 14 07:46:32 myrivo volumio[3271]: info: Stopping AccessToken refresher cron for QOBUZ Jul 14 07:46:32 myrivo volumio[3271]: info: AccessToken refresher cron started for QOBUZ Jul 14 07:46:32 myrivo volumio[3271]: info: Stopping AccessToken refresher cron Jul 14 07:46:32 myrivo volumio[3271]: info: AccessToken refresher cron started Jul 14 07:46:32 myrivo volumio[3271]: info: Adding TIDAL REST API Endpoints Jul 14 07:46:32 myrivo volumio[3271]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Jul 14 07:46:32 myrivo volumio[3271]: info: Adding QOBUZ REST API Endpoints Jul 14 07:46:32 myrivo volumio[3271]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Jul 14 07:46:32 myrivo volumio[3271]: info: Serial port opened successfully Jul 14 07:46:32 myrivo volumio[3271]: info: Sending serial start messages Jul 14 07:46:32 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 14 07:46:32 myrivo volumio[3271]: info: MP1 GPIO: Signalled system ready via GPIO Jul 14 07:46:32 myrivo volumio[3271]: verbose: New Socket.io Connection to 70.12.14.188 from 70.12.14.228 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_5_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 35 Jul 14 07:46:32 myrivo volumio[3271]: verbose: New Socket.io Connection to 70.12.14.188 from 70.12.14.228 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_5_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 36 Jul 14 07:46:32 myrivo volumio[3271]: info: Touch Event Listener Process Closed Jul 14 07:46:32 myrivo volumio[3271]: error: Cannot start Volumio Streaming Daemon Jul 14 07:46:32 myrivo volumio[3271]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jul 14 07:46:32 myrivo volumio[3271]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jul 14 07:46:32 myrivo volumio[3271]: info: Bluetooth name changed to MyRivo Jul 14 07:46:32 myrivo volumio[3271]: info: MPD Permissions set Jul 14 07:46:32 myrivo systemd[1]: systemd-hostnamed.service: Succeeded. Jul 14 07:46:32 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setDeviceVolumeOverride Jul 14 07:46:32 myrivo volumio[3271]: info: Setting Device Volume Override Jul 14 07:46:32 myrivo volumio[3271]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jul 14 07:46:32 myrivo volumio[3271]: info: Updating Volume Controller Parameters: Device: 5 Name: X6-DDC Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jul 14 07:46:32 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jul 14 07:46:32 myrivo volumio[3271]: info: Disabling external Volume Control Jul 14 07:46:32 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 14 07:46:32 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 14 07:46:32 myrivo volumio[3271]: info: CoreStateMachine::pushState Jul 14 07:46:32 myrivo volumio[3271]: info: CorePlayQueue::getTrack 0 Jul 14 07:46:32 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 14 07:46:32 myrivo volumio[3271]: info: CoreCommandRouter::volumioPushState Jul 14 07:46:32 myrivo volumio[3271]: info: MRS: Pushing multiroomSync output update for this device Jul 14 07:46:32 myrivo volumio[3271]: info: MRS: Pushing multiroomSync output Jul 14 07:46:32 myrivo volumio[3271]: info: CoreStateMachine::pushState Jul 14 07:46:32 myrivo volumio[3271]: info: CorePlayQueue::getTrack 0 Jul 14 07:46:32 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 14 07:46:32 myrivo volumio[3271]: info: CoreCommandRouter::volumioPushState Jul 14 07:46:32 myrivo volumio[3271]: info: MRS: Pushing multiroomSync output update for this device Jul 14 07:46:32 myrivo volumio[3271]: info: MRS: Pushing multiroomSync output Jul 14 07:46:32 myrivo volumio[3271]: verbose: New Socket.io Connection to 70.12.14.88 from 70.12.14.228 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_5_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 37 Jul 14 07:46:32 myrivo volumio[3271]: verbose: New Socket.io Connection to 70.12.14.88 from 70.12.14.228 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_5_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 38 Jul 14 07:46:32 myrivo volumio[3271]: info: Spotify config file written Jul 14 07:46:32 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: system , setAdditionalSVInfo Jul 14 07:46:32 myrivo volumio[3271]: info: Setting Additional System Software info: Hardware Revision: 2.0 Jul 14 07:46:32 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: system , setHwFwVersion Jul 14 07:46:32 myrivo volumio[3271]: info: Setting HW Firmware info: undefined Jul 14 07:46:32 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: system , setHwVersion Jul 14 07:46:32 myrivo volumio[3271]: info: Setting HW Version info: 2.0 Jul 14 07:46:32 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: system , setAdditionalSVInfo Jul 14 07:46:32 myrivo volumio[3271]: info: Setting Additional System Software info: Hardware Revision: 2.0, Firmware Version: 0.3.3 Jul 14 07:46:32 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: system , setHwFwVersion Jul 14 07:46:32 myrivo volumio[3271]: info: Setting HW Firmware info: 0.3.3 Jul 14 07:46:32 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: system , setHwVersion Jul 14 07:46:32 myrivo volumio[3271]: info: Setting HW Version info: 2.0 Jul 14 07:46:32 myrivo volumio[3271]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Jul 14 07:46:32 myrivo volumio[3271]: info: MCU Signalled Headphone Mode Disabled Jul 14 07:46:32 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Jul 14 07:46:32 myrivo volumio[3271]: info: MCU Signalled Sleep Mode Disabled Jul 14 07:46:32 myrivo sudo[3532]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Jul 14 07:46:32 myrivo sudo[3532]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 14 07:46:32 myrivo volumio[3271]: info: Enabling Advanced system settings configuration Jul 14 07:46:32 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: system , addAdditionalUISections Jul 14 07:46:32 myrivo volumio[3271]: info: Additional UI Settings Added for plugin music_service/inputs Jul 14 07:46:32 myrivo volumio[3271]: info: MCU Signalled Auto Boot Mode On Power Disabled Jul 14 07:46:32 myrivo volumio[3271]: verbose: New Socket.io Connection to 70.12.14.88 from 70.12.14.228 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_5_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 39 Jul 14 07:46:32 myrivo systemd[1]: Started go-librespot Daemon. Jul 14 07:46:32 myrivo sudo[3538]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms force on Jul 14 07:46:32 myrivo sudo[3538]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 14 07:46:32 myrivo sudo[3532]: pam_unix(sudo:session): session closed for user root Jul 14 07:46:32 myrivo go-librespot[3539]: Librespot-go daemon starting... Jul 14 07:46:33 myrivo sudo[3538]: pam_unix(sudo:session): session closed for user root Jul 14 07:46:33 myrivo volumio[3271]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 40 Jul 14 07:46:33 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 14 07:46:33 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 14 07:46:33 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 14 07:46:33 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 14 07:46:33 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 14 07:46:33 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 14 07:46:33 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 14 07:46:33 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 14 07:46:33 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 14 07:46:33 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jul 14 07:46:33 myrivo go-librespot[3539]: time="2024-07-14T07:46:33+09:00" level=info msg="generated new device id: e1450a2931b0d8858655c8c0c34f59d2aa1d635b" Jul 14 07:46:33 myrivo go-librespot[3539]: time="2024-07-14T07:46:33+09:00" level=debug msg="stored credentials found for 31ujqcb6wrducqtn4fjeo5v5r6eq" Jul 14 07:46:33 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 14 07:46:33 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 14 07:46:33 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 14 07:46:33 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 14 07:46:33 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 14 07:46:33 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 14 07:46:33 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 14 07:46:33 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 14 07:46:33 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 14 07:46:33 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 14 07:46:33 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jul 14 07:46:33 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 14 07:46:33 myrivo volumio[3271]: error: Serial API: Failed to decode command: MAXVOL, message: 100 Jul 14 07:46:33 myrivo volumio[3271]: info: Received Get System Info Jul 14 07:46:33 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 14 07:46:33 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 14 07:46:33 myrivo volumio[3271]: info: Discovery: Getting this device information Jul 14 07:46:33 myrivo volumio[3271]: info: CoreCommandRouter::volumioGetState Jul 14 07:46:33 myrivo volumio[3271]: info: CorePlayQueue::getTrack 0 Jul 14 07:46:33 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 14 07:46:33 myrivo volumio[3271]: info: CoreCommandRouter::volumioGetState Jul 14 07:46:33 myrivo volumio[3271]: info: CorePlayQueue::getTrack 0 Jul 14 07:46:33 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 14 07:46:33 myrivo volumio[3271]: info: CoreCommandRouter::volumioGetVisibleSources Jul 14 07:46:33 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 14 07:46:33 myrivo volumio[3271]: info: CoreCommandRouter::volumioGetState Jul 14 07:46:33 myrivo volumio[3271]: info: CorePlayQueue::getTrack 0 Jul 14 07:46:33 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 14 07:46:33 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 14 07:46:33 myrivo volumio[3271]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jul 14 07:46:33 myrivo volumio[3271]: info: Received Get System Info Jul 14 07:46:33 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 14 07:46:33 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 14 07:46:33 myrivo volumio[3271]: info: Discovery: Getting this device information Jul 14 07:46:33 myrivo volumio[3271]: info: CoreCommandRouter::volumioGetState Jul 14 07:46:33 myrivo volumio[3271]: info: CorePlayQueue::getTrack 0 Jul 14 07:46:33 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 14 07:46:33 myrivo volumio[3271]: info: CoreCommandRouter::volumioGetState Jul 14 07:46:33 myrivo volumio[3271]: info: CorePlayQueue::getTrack 0 Jul 14 07:46:33 myrivo volumio[3271]: info: Listing playlists Jul 14 07:46:33 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 14 07:46:33 myrivo volumio[3271]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 41 Jul 14 07:46:33 myrivo volumio[3271]: info: Volumio called home Jul 14 07:46:33 myrivo sudo[3561]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms 0 0 0 Jul 14 07:46:33 myrivo sudo[3561]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 14 07:46:33 myrivo sudo[3561]: pam_unix(sudo:session): session closed for user root Jul 14 07:46:33 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 14 07:46:33 myrivo volumio[3271]: verbose: New Socket.io Connection to 70.12.14.88 from 70.12.14.228 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_5_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 42 Jul 14 07:46:33 myrivo volumio[3271]: verbose: New Socket.io Connection to 70.12.14.88 from 70.12.14.228 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_5_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 43 Jul 14 07:46:33 myrivo go-librespot[3539]: time="2024-07-14T07:46:33+09:00" level=debug msg="obtained new client token: AABsuwOeuSU9RzbetGaMhdMKTxyPDuYcn0JAh2oLV8G8WlPKqttCh9Y+zNQowhOgv7p6wADD2M9okYKLtxafo7A1wq41MvNhiMMNPH7W8xH7x8i/toOLlArthzBp+MweWm6f87nHt32hfMQi4K5rHKdkicVHxj8rZ7PJ55SOo2GZVl5UecbffExN6kIjmhYy6HhIov3CNzhphAqip4KvKvYqJ29lFqa3V/ri/0MQ7KyoInQiHkbvBUW9sYK0vvZ5Rw==" Jul 14 07:46:33 myrivo volumio[3271]: info: Starting Shairport Sync Jul 14 07:46:33 myrivo volumio[3271]: info: Starting Shairport Sync Jul 14 07:46:33 myrivo sudo[3565]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 14 07:46:33 myrivo volumio[3271]: info: Starting Shairport Sync Jul 14 07:46:33 myrivo sudo[3565]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 14 07:46:33 myrivo sudo[3567]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 14 07:46:33 myrivo sudo[3567]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 14 07:46:33 myrivo volumio[3271]: info: Adding Inputs via Serial API Jul 14 07:46:33 myrivo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 14 07:46:33 myrivo systemd[1]: shairport-sync.service: Succeeded. Jul 14 07:46:33 myrivo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 14 07:46:33 myrivo volumio[3271]: info: CoreStateMachine::pushState Jul 14 07:46:33 myrivo volumio[3271]: info: CorePlayQueue::getTrack 0 Jul 14 07:46:33 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 14 07:46:33 myrivo volumio[3271]: info: CoreCommandRouter::volumioPushState Jul 14 07:46:33 myrivo volumio[3271]: info: MRS: Pushing multiroomSync output update for this device Jul 14 07:46:33 myrivo volumio[3271]: info: MRS: Pushing multiroomSync output Jul 14 07:46:33 myrivo sudo[3571]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 14 07:46:33 myrivo sudo[3571]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 14 07:46:33 myrivo volumio[3271]: verbose: New Socket.io Connection to 70.12.14.88 from 70.12.14.228 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_5_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 44 Jul 14 07:46:33 myrivo volumio[3271]: info: CoreCommandRouter::volumioGetState Jul 14 07:46:33 myrivo volumio[3271]: info: CorePlayQueue::getTrack 0 Jul 14 07:46:33 myrivo go-librespot[3539]: time="2024-07-14T07:46:33+09:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Jul 14 07:46:33 myrivo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 14 07:46:33 myrivo sudo[3567]: pam_unix(sudo:session): session closed for user root Jul 14 07:46:33 myrivo sudo[3565]: pam_unix(sudo:session): session closed for user root Jul 14 07:46:33 myrivo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 14 07:46:33 myrivo volumio[3271]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Jul 14 07:46:33 myrivo volumio[3271]: SPOTIFY: BQBxgCyFLw0M0MaAqK7eBO7l-BdW8VMR529e_gHqTyiCuQQXWekWyi6Bdyy_O97bJIVe1zvFb3qKRUGLWYxI30ozblLQgN6zPSOKZo8sVqWvNkYK_85aBuJ6b-jx1mLCfcnrkER-E8I_-bk_qDzmUMSRJWIJTMTTLnaRbznFpHPia273qNg3qPPW09dD0eg24eP1pHZyh0ieuIsecHgFElxEiTVHpuwTuPDDkBkUKkNkMWV_OZbtyaw7KwBD7WH4PFnN63TQkyg Jul 14 07:46:33 myrivo volumio[3271]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Jul 14 07:46:33 myrivo systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Jul 14 07:46:33 myrivo systemd[1]: shairport-sync.service: Succeeded. Jul 14 07:46:33 myrivo volumio[3271]: info: New Spotify access token = BQBxgCyFLw0M0MaAqK7eBO7l-BdW8VMR529e_gHqTyiCuQQXWekWyi6Bdyy_O97bJIVe1zvFb3qKRUGLWYxI30ozblLQgN6zPSOKZo8sVqWvNkYK_85aBuJ6b-jx1mLCfcnrkER-E8I_-bk_qDzmUMSRJWIJTMTTLnaRbznFpHPia273qNg3qPPW09dD0eg24eP1pHZyh0ieuIsecHgFElxEiTVHpuwTuPDDkBkUKkNkMWV_OZbtyaw7KwBD7WH4PFnN63TQkyg Jul 14 07:46:33 myrivo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 14 07:46:33 myrivo volumio[3271]: info: Spotify credentials grant success - running version from March 24, 2019 Jul 14 07:46:33 myrivo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 14 07:46:33 myrivo sudo[3571]: pam_unix(sudo:session): session closed for user root Jul 14 07:46:33 myrivo volumio[3271]: info: CoreCommandRouter::servicePushState Jul 14 07:46:33 myrivo volumio[3271]: info: CorePlayQueue::getTrack 0 Jul 14 07:46:33 myrivo volumio[3271]: 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} Jul 14 07:46:33 myrivo volumio[3271]: verbose: CURRENT POSITION 0 Jul 14 07:46:33 myrivo volumio[3271]: info: CoreStateMachine::syncState stateService play Jul 14 07:46:33 myrivo volumio[3271]: info: CoreStateMachine::syncState currentStatus stop Jul 14 07:46:33 myrivo volumio[3271]: info: CoreCommandRouter::volumiosetSourceActiveno-source Jul 14 07:46:33 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 14 07:46:33 myrivo volumio[3271]: verbose: New Socket.io Connection to 70.12.14.88 from 70.12.14.228 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_5_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 45 Jul 14 07:46:33 myrivo volumio[3271]: info: CoreCommandRouter::volumioGetState Jul 14 07:46:33 myrivo volumio[3271]: info: CorePlayQueue::getTrack 0 Jul 14 07:46:34 myrivo volumio[3271]: verbose: New Socket.io Connection to 70.12.14.88 from 70.12.14.228 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_5_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 46 Jul 14 07:46:34 myrivo go-librespot[3539]: time="2024-07-14T07:46:34+09:00" level=debug msg="completed keyexchange" Jul 14 07:46:34 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 14 07:46:34 myrivo volumio[3271]: info: Shairport-Sync Started Jul 14 07:46:34 myrivo volumio[3271]: Error adding Membership: Error: addMembership EINVAL Jul 14 07:46:34 myrivo volumio[3271]: info: Shairport-Sync Started Jul 14 07:46:34 myrivo volumio[3271]: info: Shairport-Sync Started Jul 14 07:46:34 myrivo volumio[3271]: verbose: New Socket.io Connection to 70.12.14.88 from 70.12.14.228 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_5_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 47 Jul 14 07:46:34 myrivo sudo[3578]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Jul 14 07:46:34 myrivo sudo[3578]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 14 07:46:34 myrivo volumio[3271]: verbose: New Socket.io Connection to 70.12.14.88 from 70.12.14.228 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_5_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 48 Jul 14 07:46:34 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 14 07:46:34 myrivo systemd[1]: Started Volumio Bluetooth Module. Jul 14 07:46:34 myrivo volumio[3271]: info: MCU Signalled Playback Inactive Jul 14 07:46:34 myrivo sudo[3578]: pam_unix(sudo:session): session closed for user root Jul 14 07:46:34 myrivo volumio[3271]: verbose: New Socket.io Connection to 70.12.14.88 from 70.12.14.228 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_5_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 48 Jul 14 07:46:34 myrivo sudo[3581]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Jul 14 07:46:34 myrivo sudo[3581]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 14 07:46:34 myrivo volumiobt[3580]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Jul 14 07:46:34 myrivo sudo[3581]: pam_unix(sudo:session): session closed for user root Jul 14 07:46:34 myrivo volumio[3271]: verbose: New Socket.io Connection to 70.12.14.88 from 70.12.14.228 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_5_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 49 Jul 14 07:46:34 myrivo volumio[3271]: info: Volumio BT Module successfully started Jul 14 07:46:34 myrivo volumio[3271]: verbose: New Socket.io Connection to 70.12.14.88 from 70.12.14.228 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_5_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 50 Jul 14 07:46:34 myrivo volumio[3271]: verbose: New Socket.io Connection to 70.12.14.88 from 70.12.14.228 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_5_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 51 Jul 14 07:46:34 myrivo volumio[3271]: verbose: New Socket.io Connection to 70.12.14.88 from 70.12.14.228 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_5_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 52 Jul 14 07:46:34 myrivo volumio[3271]: verbose: New Socket.io Connection to 70.12.14.88 from 70.12.14.228 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_5_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 53 Jul 14 07:46:34 myrivo mpd[3472]: Jul 14 07:46 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 14 07:46:34 myrivo volumio[3271]: SPOTIFY: User informations: {"display_name":"Kim Kye Hwan","external_urls":{"spotify":"https://open.spotify.com/user/31ujqcb6wrducqtn4fjeo5v5r6eq"},"href":"https://api.spotify.com/v1/users/31ujqcb6wrducqtn4fjeo5v5r6eq","id":"31ujqcb6wrducqtn4fjeo5v5r6eq","images":[{"url":"https://i.scdn.co/image/ab67757000003b82e5b9fd2f9b073e890c833e55","height":64,"width":64},{"url":"https://i.scdn.co/image/ab6775700000ee85e5b9fd2f9b073e890c833e55","height":300,"width":300}],"type":"user","uri":"spotify:user:31ujqcb6wrducqtn4fjeo5v5r6eq","followers":{"href":null,"total":0},"country":"KR","product":"premium","explicit_content":{"filter_enabled":false,"filter_locked":false},"email":"kkhayk@gmail.com"} Jul 14 07:46:34 myrivo volumio[3271]: info: Spotify Successfully logged in Jul 14 07:46:34 myrivo volumio[3271]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 14 07:46:34 myrivo volumio[3271]: info: [1720910794523] CoreMusicLibrary::Adding element Spotify Jul 14 07:46:34 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 14 07:46:34 myrivo volumio[3271]: Cannot find translation for source Spotify Jul 14 07:46:34 myrivo go-librespot[3539]: time="2024-07-14T07:46:34+09:00" level=debug msg="completed challenge" Jul 14 07:46:34 myrivo go-librespot[3539]: time="2024-07-14T07:46:34+09:00" level=debug msg="authenticated as 31ujqcb6wrducqtn4fjeo5v5r6eq" Jul 14 07:46:34 myrivo volumio[3271]: info: Turning Off Onboard LEDs Jul 14 07:46:34 myrivo sudo[3587]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/sys_led/trigger Jul 14 07:46:34 myrivo sudo[3587]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 14 07:46:34 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 14 07:46:34 myrivo sudo[3587]: pam_unix(sudo:session): session closed for user root Jul 14 07:46:34 myrivo sudo[3590]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/red_red/trigger Jul 14 07:46:34 myrivo sudo[3590]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 14 07:46:34 myrivo sudo[3590]: pam_unix(sudo:session): session closed for user root Jul 14 07:46:34 myrivo volumio[3271]: info: Onboard LEDs initialized Jul 14 07:46:34 myrivo go-librespot[3539]: time="2024-07-14T07:46:34+09:00" level=debug msg="authenticated as 31ujqcb6wrducqtn4fjeo5v5r6eq" Jul 14 07:46:34 myrivo go-librespot[3539]: time="2024-07-14T07:46:34+09:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Jul 14 07:46:34 myrivo go-librespot[3539]: time="2024-07-14T07:46:34+09:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Jul 14 07:46:35 myrivo go-librespot[3539]: time="2024-07-14T07:46:35+09:00" level=debug msg="dealer connection opened" Jul 14 07:46:35 myrivo go-librespot[3539]: time="2024-07-14T07:46:35+09:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Jul 14 07:46:35 myrivo go-librespot[3539]: time="2024-07-14T07:46:35+09:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Jul 14 07:46:35 myrivo go-librespot[3539]: time="2024-07-14T07:46:35+09:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Jul 14 07:46:35 myrivo go-librespot[3539]: time="2024-07-14T07:46:35+09:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Jul 14 07:46:35 myrivo go-librespot[3539]: time="2024-07-14T07:46:35+09:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 513" Jul 14 07:46:35 myrivo go-librespot[3539]: time="2024-07-14T07:46:35+09:00" level=debug msg="received connection id: ZmUzMzg5MmMtYmY5Yi00YWYwLTg2NmEtZDBkNjg4ZmJhOTQ2K2RlYWxlcit0Y3A6Ly9nYWUyLWRlYWxlci1hLXFzcXouZ2FlMi5zcG90aWZ5Lm5ldDo1NzAwKzU0MjgzRTRFMzE0MjdCRDg4QjNCM0JFODJGNTMyOTk5RkQyMEFFQjEzNURCQkMzMzg0RkY3NjE3NTQ0MzM0QTY=" Jul 14 07:46:35 myrivo volumio[3271]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 54 Jul 14 07:46:35 myrivo volumio[3271]: info: CoreCommandRouter::volumioGetState Jul 14 07:46:35 myrivo volumio[3271]: info: CorePlayQueue::getTrack 0 Jul 14 07:46:35 myrivo go-librespot[3539]: time="2024-07-14T07:46:35+09:00" level=debug msg="put connect state because NEW_DEVICE" Jul 14 07:46:35 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 14 07:46:35 myrivo systemd[1]: Started Music Player Daemon. Jul 14 07:46:35 myrivo sudo[3435]: pam_unix(sudo:session): session closed for user root Jul 14 07:46:35 myrivo sudo[3456]: pam_unix(sudo:session): session closed for user root Jul 14 07:46:35 myrivo volumio[3271]: info: Completed starting Core Plugins Jul 14 07:46:35 myrivo volumio[3271]: info: ------------------------------------------- Jul 14 07:46:35 myrivo volumio[3271]: info: ----- MyVolumio plugins startup ---- Jul 14 07:46:35 myrivo volumio[3271]: info: ------------------------------------------- Jul 14 07:46:35 myrivo volumio[3271]: info: [MyVolumio PluginManager] Fetching plans data.... Jul 14 07:46:35 myrivo sudo[3595]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Jul 14 07:46:35 myrivo sudo[3595]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 14 07:46:35 myrivo volumiobt[3580]: pulseaudio: no process found Jul 14 07:46:35 myrivo sudo[3595]: pam_unix(sudo:session): session closed for user root Jul 14 07:46:35 myrivo dbus-daemon[2832]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.16' (uid=1000 pid=3600 comm="/usr/bin/pulseaudio --high-priority=true " label="kernel") Jul 14 07:46:35 myrivo volumio[3271]: error: MPD error: The expression evaluated to a falsy value: Jul 14 07:46:35 myrivo volumio[3271]: assert.ok(self.idling) Jul 14 07:46:35 myrivo volumio[3271]: error: The expression evaluated to a falsy value: Jul 14 07:46:35 myrivo volumio[3271]: assert.ok(self.idling) Jul 14 07:46:35 myrivo volumio[3271]: error: updateQueue error: null Jul 14 07:46:35 myrivo volumio[3271]: info: MPD running with PID3472 Jul 14 07:46:35 myrivo volumio[3271]: ,establishing connection Jul 14 07:46:35 myrivo systemd[1]: Starting RealtimeKit Scheduling Policy Service... Jul 14 07:46:35 myrivo kernel: IPVS: Creating netns size=1928 id=3 Jul 14 07:46:35 myrivo volumio[3271]: error: updateQueue error: null Jul 14 07:46:35 myrivo volumio[3271]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 14 07:46:35 myrivo dbus-daemon[2832]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' Jul 14 07:46:35 myrivo systemd[1]: Started RealtimeKit Scheduling Policy Service. Jul 14 07:46:35 myrivo rtkit-daemon[3601]: Successfully called chroot. Jul 14 07:46:35 myrivo rtkit-daemon[3601]: Successfully dropped privileges. Jul 14 07:46:35 myrivo rtkit-daemon[3601]: Successfully limited resources. Jul 14 07:46:35 myrivo rtkit-daemon[3601]: Running. Jul 14 07:46:35 myrivo rtkit-daemon[3601]: Watchdog thread running. Jul 14 07:46:35 myrivo rtkit-daemon[3601]: Canary thread running. Jul 14 07:46:35 myrivo dbus-daemon[2832]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.17' (uid=0 pid=3601 comm="/usr/lib/rtkit/rtkit-daemon " label="kernel") Jul 14 07:46:35 myrivo systemd[1]: Starting Authorization Manager... Jul 14 07:46:35 myrivo volumio[3271]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 14 07:46:35 myrivo polkitd[3604]: started daemon version 0.105 using authority implementation `local' version `0.105' Jul 14 07:46:35 myrivo dbus-daemon[2832]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Jul 14 07:46:35 myrivo systemd[1]: Started Authorization Manager. Jul 14 07:46:35 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 14 07:46:36 myrivo pulseaudio[3600]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Jul 14 07:46:36 myrivo pulseaudio[3600]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Jul 14 07:46:36 myrivo volumiobt[3580]: Applying permissions Jul 14 07:46:36 myrivo volumiobt[3580]: Setting BT discoverable and pairable Jul 14 07:46:36 myrivo bluetoothd[3214]: Endpoint registered: sender=:1.37 path=/MediaEndpoint/A2DPSource Jul 14 07:46:36 myrivo bluetoothd[3214]: Endpoint registered: sender=:1.37 path=/MediaEndpoint/A2DPSink Jul 14 07:46:36 myrivo pulseaudio[3600]: 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 Jul 14 07:46:36 myrivo volumiobt[3580]: Agent registered Jul 14 07:46:36 myrivo volumiobt[3580]: [bluetooth]# -e power on Jul 14 07:46:36 myrivo volumiobt[3580]: Invalid command in menu main: -e Jul 14 07:46:36 myrivo volumiobt[3580]: Jul 14 07:46:36 myrivo volumiobt[3580]: Use "help" for a list of available commands in a menu. Jul 14 07:46:36 myrivo volumiobt[3580]: Use "menu " if you want to enter any submenu. Jul 14 07:46:36 myrivo volumiobt[3580]: Use "back" if you want to return to menu main. Jul 14 07:46:36 myrivo volumiobt[3580]: [bluetooth]# agent on Jul 14 07:46:36 myrivo volumiobt[3580]: Agent is already registered Jul 14 07:46:36 myrivo volumiobt[3580]: [bluetooth]# discoverable on Jul 14 07:46:36 myrivo volumiobt[3580]: [bluetooth]# pairable on Jul 14 07:46:36 myrivo volumiobt[3580]: [bluetooth]# agent NoInputNoOutput Jul 14 07:46:36 myrivo volumiobt[3580]: Agent is already registered Jul 14 07:46:36 myrivo volumiobt[3580]: [bluetooth]# default-agent Jul 14 07:46:36 myrivo volumiobt[3580]: [bluetooth]# quit Jul 14 07:46:36 myrivo volumiobt[3580]: [59B blob data] Jul 14 07:46:36 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 14 07:46:36 myrivo volumio[3271]: info: go-librespot daemon successfully initialized Jul 14 07:46:36 myrivo kernel: fb: mem_free_work, free memory: addr:800000 Jul 14 07:46:36 myrivo volumio[3271]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 55 Jul 14 07:46:36 myrivo volumio[3271]: info: CoreCommandRouter::volumioGetState Jul 14 07:46:36 myrivo volumio[3271]: info: CorePlayQueue::getTrack 0 Jul 14 07:46:36 myrivo volumio[3271]: info: MRS: Getting audio outputs on start Jul 14 07:46:36 myrivo volumio[3271]: info: MRS: Requesting all other devices output Jul 14 07:46:36 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 14 07:46:37 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 14 07:46:37 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 14 07:46:38 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 14 07:46:38 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 14 07:46:39 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 14 07:46:39 myrivo volumio[3271]: info: Initializing connection to go-librespot Websocket Jul 14 07:46:39 myrivo go-librespot[3539]: time="2024-07-14T07:46:39+09:00" level=debug msg="new websocket client" Jul 14 07:46:39 myrivo volumio[3271]: info: Connection to go-librespot Websocket established Jul 14 07:46:39 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 14 07:46:40 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 14 07:46:40 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 14 07:46:41 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 14 07:46:41 myrivo volumio[3271]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jul 14 07:46:41 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 14 07:46:42 myrivo volumio[3271]: info: Checking for updated MCU Firmware Jul 14 07:46:42 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 14 07:46:42 myrivo volumio[3271]: info: Firware on device is on latest version, no need to update Jul 14 07:46:42 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 14 07:46:42 myrivo volumio[3271]: info: Getting Spotify volume Jul 14 07:46:43 myrivo volumio[3271]: info: Spotify volume: 100 Jul 14 07:46:43 myrivo volumio[3271]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 56 Jul 14 07:46:45 myrivo volumio[3271]: info: CoreCommandRouter::volumioGetState Jul 14 07:46:45 myrivo volumio[3271]: info: CorePlayQueue::getTrack 0 Jul 14 07:46:46 myrivo volumio[3271]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 14 07:46:46 myrivo volumio[3271]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 14 07:46:49 myrivo volumio[3271]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jul 14 07:46:49 myrivo volumio[3271]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jul 14 07:46:49 myrivo volumio[3271]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jul 14 07:46:49 myrivo volumio[3271]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jul 14 07:46:49 myrivo volumio[3271]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jul 14 07:46:49 myrivo volumio[3271]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jul 14 07:46:49 myrivo volumio[3271]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jul 14 07:46:49 myrivo volumio[3271]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jul 14 07:46:49 myrivo volumio[3271]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jul 14 07:46:49 myrivo volumio[3271]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jul 14 07:46:49 myrivo volumio[3271]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jul 14 07:46:49 myrivo volumio[3271]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jul 14 07:46:49 myrivo volumio[3271]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jul 14 07:46:49 myrivo volumio[3271]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jul 14 07:46:49 myrivo volumio[3271]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jul 14 07:46:49 myrivo volumio[3271]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jul 14 07:46:49 myrivo volumio[3271]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jul 14 07:46:49 myrivo volumio[3271]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jul 14 07:46:49 myrivo volumio[3271]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jul 14 07:46:49 myrivo volumio[3271]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jul 14 07:46:49 myrivo volumio[3271]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jul 14 07:46:49 myrivo volumio[3271]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jul 14 07:46:49 myrivo volumio[3271]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jul 14 07:46:49 myrivo volumio[3271]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jul 14 07:46:49 myrivo volumio[3271]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jul 14 07:46:49 myrivo volumio[3271]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jul 14 07:46:49 myrivo volumio[3271]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jul 14 07:46:50 myrivo volumio[3271]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jul 14 07:46:50 myrivo volumio[3271]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jul 14 07:46:50 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 14 07:46:50 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 14 07:46:50 myrivo volumio[3271]: info: Starting MyVolumio Remote Streaming Endpoints Jul 14 07:46:50 myrivo volumio[3271]: info: MyVolumio login type: Token Jul 14 07:46:50 myrivo volumio[3271]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jul 14 07:46:50 myrivo volumio[3271]: 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' Jul 14 07:46:51 myrivo volumio[3271]: xcb_connection_has_error() returned true Jul 14 07:46:51 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jul 14 07:46:51 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 14 07:46:51 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 14 07:46:51 myrivo volumio[3271]: xcb_connection_has_error() returned true Jul 14 07:46:51 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 14 07:46:51 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 14 07:46:51 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 14 07:46:51 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 14 07:46:51 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 14 07:46:51 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 14 07:46:51 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 14 07:46:51 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jul 14 07:46:51 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 14 07:46:55 myrivo volumio[3271]: error: MyVolumio Plugin failed to start in a timely fashion Jul 14 07:46:55 myrivo volumio[3271]: info: BOOT COMPLETED Jul 14 07:46:55 myrivo volumio[3271]: [Metrics] CommandRouter: 46s 618.75ms Jul 14 07:46:55 myrivo volumio[3271]: info: CoreCommandRouter::volumiosetStartupVolume Jul 14 07:46:55 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 14 07:46:55 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 14 07:46:55 myrivo volumio[3271]: info: CoreCommandRouter::Close All Modals sent Jul 14 07:46:55 myrivo volumio[3271]: info: CoreCommandRouter::Close All Modals sent Jul 14 07:46:57 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jul 14 07:46:57 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 14 07:46:57 myrivo volumio[3271]: xcb_connection_has_error() returned true Jul 14 07:46:57 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jul 14 07:46:58 myrivo volumio[3271]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 14 07:46:59 myrivo ntpd[3046]: Soliciting pool server 121.174.142.81 Jul 14 07:46:59 myrivo ntpd[3046]: Soliciting pool server 193.123.243.2 Jul 14 07:47:00 myrivo ntpd[3046]: Soliciting pool server 175.193.3.234 Jul 14 07:47:01 myrivo ntpd[3046]: Soliciting pool server 106.247.248.106 Jul 14 07:47:02 myrivo ntpd[3046]: Soliciting pool server 39.118.108.234 Jul 14 07:47:02 myrivo ntpd[3046]: Soliciting pool server 121.174.142.82 Jul 14 07:47:03 myrivo volumio[3271]: Cannot compose Albumart path Jul 14 07:47:03 myrivo ntpd[3046]: Soliciting pool server 194.0.5.123 Jul 14 07:47:03 myrivo ntpd[3046]: Soliciting pool server 2603:c022:8004:7800:65bf:c600:a739:1bee Jul 14 07:47:06 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 14 07:47:06 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 14 07:47:06 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 14 07:47:06 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 14 07:47:07 myrivo volumio[3271]: xcb_connection_has_error() returned true Jul 14 07:47:07 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jul 14 07:47:07 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 14 07:47:07 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 14 07:47:07 myrivo volumio[3271]: xcb_connection_has_error() returned true Jul 14 07:47:07 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 14 07:47:07 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 14 07:47:07 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 14 07:47:07 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 14 07:47:07 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 14 07:47:07 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 14 07:47:07 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 14 07:47:07 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jul 14 07:47:07 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 14 07:47:07 myrivo volumio[3271]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jul 14 07:47:07 myrivo volumio-remote-updater[2825]: No test mode Jul 14 07:47:07 myrivo volumio-remote-updater[2825]: No alpha test mode Jul 14 07:47:07 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jul 14 07:47:07 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 14 07:47:07 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 14 07:47:07 myrivo volumio[3271]: info: Discovery: Getting this device information Jul 14 07:47:07 myrivo volumio[3271]: info: CoreCommandRouter::volumioGetState Jul 14 07:47:07 myrivo volumio[3271]: info: CorePlayQueue::getTrack 0 Jul 14 07:47:07 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 14 07:47:07 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 14 07:47:07 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 14 07:47:07 myrivo volumio[3271]: info: Discovery: Getting this device information Jul 14 07:47:07 myrivo volumio[3271]: info: CoreCommandRouter::volumioGetState Jul 14 07:47:07 myrivo volumio[3271]: info: CorePlayQueue::getTrack 0 Jul 14 07:47:07 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 14 07:47:07 myrivo volumio[3271]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Jul 14 07:47:07 myrivo volumio[3271]: info: CoreCommandRouter::volumioGetState Jul 14 07:47:07 myrivo volumio[3271]: info: CorePlayQueue::getTrack 0 Jul 14 07:47:07 myrivo volumio[3271]: verbose: New Socket.io Connection to 70.12.14.88 from 70.12.14.228 UA: unknown Engine version: 3 Transport: websocket Total Clients: 5 Jul 14 07:47:07 myrivo volumio[3271]: verbose: New Socket.io Connection to 70.12.14.188 from 70.12.14.228 UA: unknown Engine version: 3 Transport: websocket Total Clients: 6 Jul 14 07:47:07 myrivo volumio[3271]: info: CoreCommandRouter::volumioGetState Jul 14 07:47:07 myrivo volumio[3271]: info: CorePlayQueue::getTrack 0 Jul 14 07:47:07 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jul 14 07:47:07 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jul 14 07:47:07 myrivo volumio[3271]: info: CoreCommandRouter::volumioGetState Jul 14 07:47:07 myrivo volumio[3271]: info: CorePlayQueue::getTrack 0 Jul 14 07:47:07 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jul 14 07:47:07 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jul 14 07:47:08 myrivo volumio[3271]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jul 14 07:47:08 myrivo volumio[3271]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jul 14 07:47:08 myrivo volumio[3271]: info: MyVolumio token set successfully Jul 14 07:47:08 myrivo volumio[3271]: info: MYVOLUMIO: Adding device Jul 14 07:47:08 myrivo volumio[3271]: info: MYVOLUMIO: Evaluating Server Jul 14 07:47:08 myrivo volumio[3271]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Jul 14 07:47:08 myrivo volumio[3271]: info: CoreCommandRouter::volumioGetState Jul 14 07:47:08 myrivo volumio[3271]: info: CorePlayQueue::getTrack 0 Jul 14 07:47:07 myrivo ntpd[3046]: receive: Unexpected origin timestamp 0xea3d826c.e47eae77 does not match aorg 0000000000.00000000 from server@175.193.3.234 xmt 0xea3d826b.fbf23931 Jul 14 07:47:07 myrivo ntpd[3046]: receive: Unexpected origin timestamp 0xea3d826c.e47c76f3 does not match aorg 0000000000.00000000 from server@106.247.248.106 xmt 0xea3d826b.fb6acb02 Jul 14 07:47:07 myrivo ntpd[3046]: receive: Unexpected origin timestamp 0xea3d826c.e479ac8e does not match aorg 0000000000.00000000 from server@121.174.142.82 xmt 0xea3d826b.fc04de93 Jul 14 07:47:07 myrivo ntpd[3046]: receive: Unexpected origin timestamp 0xea3d826c.e483136a does not match aorg 0000000000.00000000 from server@121.174.142.81 xmt 0xea3d826b.fbf33c90 Jul 14 07:47:08 myrivo ntpd[3046]: receive: Unexpected origin timestamp 0xea3d826c.e4713032 does not match aorg 0000000000.00000000 from server@194.0.5.123 xmt 0xea3d826b.fb6415ee Jul 14 07:47:08 myrivo volumio[3271]: info: MyVolumio status changed Jul 14 07:47:08 myrivo volumio[3271]: info: Streaming services startup Jul 14 07:47:08 myrivo volumio[3271]: info: Starting Streaming Daemon Jul 14 07:47:08 myrivo volumio[3271]: info: Removing browser output: myVolumio user plan is not superstar Jul 14 07:47:08 myrivo volumio[3271]: info: Removing audio output: Jul 14 07:47:08 myrivo volumio[3271]: info: Stoppping Tunnel 1 Jul 14 07:47:08 myrivo sudo[3692]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jul 14 07:47:08 myrivo sudo[3692]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 14 07:47:08 myrivo sudo[3692]: pam_unix(sudo:session): session closed for user root Jul 14 07:47:08 myrivo sudo[3695]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Jul 14 07:47:08 myrivo sudo[3695]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 14 07:47:08 myrivo volumio[3271]: error: Failed to ping endpoint eu3.myvolumio.org : unknown error Jul 14 07:47:08 myrivo volumio[3271]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 14 07:47:08 myrivo sudo[3695]: pam_unix(sudo:session): session closed for user root Jul 14 07:47:08 myrivo volumio[3271]: Error: Unable to resolve or reject the same promise twice Jul 14 07:47:08 myrivo volumio[3271]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Jul 14 07:47:08 myrivo volumio[3271]: at Socket. (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:32367) Jul 14 07:47:08 myrivo volumio[3271]: at Socket.emit (events.js:400:28) Jul 14 07:47:08 myrivo volumio[3271]: at addChunk (internal/streams/readable.js:293:12) Jul 14 07:47:08 myrivo volumio[3271]: at readableAddChunk (internal/streams/readable.js:267:9) Jul 14 07:47:08 myrivo volumio[3271]: at Socket.Readable.push (internal/streams/readable.js:206:10) Jul 14 07:47:08 myrivo volumio[3271]: at Pipe.onStreamRead (internal/stream_base_commons.js:188:23) Jul 14 07:47:08 myrivo volumio[3271]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 14 07:47:09 myrivo sudo[3707]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-14 07:46 Jul 14 07:47:09 myrivo sudo[3707]: 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="4b122b2365bb8c09786cd63b50d1ea6db780eb4e" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="49f98390f2d84c6549f33dc85b694fbd99e4c720" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 04 Jun 2024 09:27:25 PM CEST" VOLUMIO_VERSION="3.703" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="891b8adfc94c7cc44424972b60e8e09c"