-- Logs begin at Mon 2024-08-19 10:49:34 BST, end at Mon 2024-08-19 16:35:04 BST. -- Aug 19 16:34:03 rivo volumio[3381]: info: CALLMETHOD: system_controller network saveHotspotSettings [object Object] Aug 19 16:34:03 rivo volumio[3381]: info: CoreCommandRouter::executeOnPlugin: network , saveHotspotSettings Aug 19 16:34:04 rivo sudo[19972]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hostapd/hostapd.conf Aug 19 16:34:04 rivo sudo[19972]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 19 16:34:04 rivo sudo[19972]: pam_unix(sudo:session): session closed for user root Aug 19 16:34:04 rivo volumio[3381]: info: Permissions for /etc/hostapd/hostapd.conf Aug 19 16:34:04 rivo volumio[3381]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Aug 19 16:34:04 rivo volumio[3381]: info: Discovery: Restarting Advertising due to device name change Aug 19 16:34:04 rivo sudo[19975]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Aug 19 16:34:04 rivo sudo[19975]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 19 16:34:04 rivo wpa_supplicant[3173]: nl80211: Failed to open /proc/sys/net/ipv4/conf/p2p-dev-wlan0/drop_unicast_in_l2_multicast: No such file or directory Aug 19 16:34:04 rivo wpa_supplicant[3173]: nl80211: Failed to set IPv4 unicast in multicast filter Aug 19 16:34:04 rivo systemd[1]: Stopping Wireless Services... Aug 19 16:34:04 rivo wpa_supplicant[3173]: nl80211: Failed to open /proc/sys/net/ipv4/conf/p2p-dev-wlan0/drop_unicast_in_l2_multicast: No such file or directory Aug 19 16:34:04 rivo wpa_supplicant[3173]: nl80211: Failed to set IPv4 unicast in multicast filter Aug 19 16:34:04 rivo wpa_supplicant[3173]: nl80211: deinit ifname=p2p-dev-wlan0 disabled_11b_rates=0 Aug 19 16:34:04 rivo wpa_supplicant[3173]: p2p-dev-wlan0: CTRL-EVENT-TERMINATING Aug 19 16:34:04 rivo kernel: [35B blob data] Aug 19 16:34:04 rivo kernel: Exit. P2P interface stopped Aug 19 16:34:04 rivo wpa_supplicant[3173]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Aug 19 16:34:04 rivo dhcpcd[3333]: wlan0: carrier acquired Aug 19 16:34:04 rivo kernel: dhd_stop: Enter ffffffc050e1d000 Aug 19 16:34:04 rivo kernel: CFGP2P-ERROR) wl_cfgp2p_disable_discovery : do nothing, not initialized Aug 19 16:34:04 rivo kernel: CFG80211-ERROR) wl_cfgp2p_down : Clean up the p2p discovery IF Aug 19 16:34:04 rivo kernel: wl_cfgp2p_del_p2p_disc_if: wdev: ffffffc050ea0c00, wdev->net: (null) Aug 19 16:34:04 rivo kernel: P2P interface unregistered Aug 19 16:34:04 rivo kernel: CFGP2P-ERROR) wl_cfgp2p_deinit_priv : In Aug 19 16:34:04 rivo kernel: dhd_tcpack_suppress_set: TCP ACK Suppress mode 1 -> mode 0 Aug 19 16:34:04 rivo kernel: wl_android_wifi_off in 1 Aug 19 16:34:04 rivo kernel: wl_android_wifi_off in 2: g_wifi_on=1, on_failure=1 Aug 19 16:34:04 rivo kernel: dhd_dbg_detach_pkt_monitor, 2204 Aug 19 16:34:04 rivo kernel: dhd_bus_devreset: == Power OFF == Aug 19 16:34:04 rivo kernel: bcmsdh_oob_intr_unregister: Enter Aug 19 16:34:04 rivo kernel: dhd_txglom_enable: enable 0 Aug 19 16:34:04 rivo kernel: dhd_conf_set_txglom_params: txglom_mode=copy Aug 19 16:34:04 rivo kernel: dhd_conf_set_txglom_params: txglomsize=0, deferred_tx_len=0 Aug 19 16:34:04 rivo kernel: dhd_conf_set_txglom_params: txinrx_thres=128, dhd_txminmax=-1 Aug 19 16:34:04 rivo kernel: dhd_conf_set_txglom_params: tx_max_offset=0, txctl_tmo_fix=300 Aug 19 16:34:04 rivo kernel: dhd_bus_devreset: WLAN OFF DONE Aug 19 16:34:04 rivo kernel: wifi_platform_set_power = 0 Aug 19 16:34:04 rivo kernel: ======== PULL WL_REG_ON(-1) LOW! ======== Aug 19 16:34:04 rivo kernel: aml_wifi wifi: [extern_wifi_set_enable] WIFI Disable! 482 Aug 19 16:34:04 rivo volumio[3381]: info: CALLMETHOD: system_controller network saveHotspotSettings [object Object] Aug 19 16:34:04 rivo volumio[3381]: info: CoreCommandRouter::executeOnPlugin: network , saveHotspotSettings Aug 19 16:34:04 rivo kernel: wl_android_wifi_off out Aug 19 16:34:04 rivo kernel: wl_ext_iapsta_dettach_netdev: Enter Aug 19 16:34:04 rivo kernel: dhd_stop: Exit Aug 19 16:34:04 rivo wpa_supplicant[3173]: wlan0: CTRL-EVENT-TERMINATING Aug 19 16:34:04 rivo systemd[1]: wireless.service: Succeeded. Aug 19 16:34:04 rivo systemd[1]: Stopped Wireless Services. Aug 19 16:34:04 rivo dhcpcd[3333]: wlan0: IAID 28:29:8b:9a Aug 19 16:34:04 rivo dhcpcd[3333]: wlan0: carrier lost Aug 19 16:34:04 rivo systemd[1]: Starting Wireless Services... Aug 19 16:34:05 rivo volumio[3381]: info: Discovery: A device disappeared from network Aug 19 16:34:05 rivo volumio[3381]: info: Discovery: Device rivo disappeared from network Aug 19 16:34:05 rivo wireless.js[19981]: Cleaning previous... Aug 19 16:34:05 rivo sudo[20015]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hostapd/hostapd.conf Aug 19 16:34:05 rivo sudo[20015]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 19 16:34:05 rivo sudo[20015]: pam_unix(sudo:session): session closed for user root Aug 19 16:34:05 rivo volumio[3381]: info: Permissions for /etc/hostapd/hostapd.conf Aug 19 16:34:05 rivo volumio[3381]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Aug 19 16:34:05 rivo volumio[3381]: info: Discovery: Restarting Advertising due to device name change Aug 19 16:34:05 rivo sudo[20019]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Aug 19 16:34:05 rivo sudo[20019]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 19 16:34:05 rivo sudo[20021]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Aug 19 16:34:05 rivo sudo[20021]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 19 16:34:05 rivo systemd[1]: wireless.service: Control process exited, code=killed, status=15/TERM Aug 19 16:34:05 rivo systemd[1]: wireless.service: Killing process 20020 (sh) with signal SIGKILL. Aug 19 16:34:05 rivo systemd[1]: wireless.service: Killing process 20021 (sudo) with signal SIGKILL. Aug 19 16:34:05 rivo systemd[1]: wireless.service: Killing process 20023 (ip) with signal SIGKILL. Aug 19 16:34:05 rivo systemd[1]: wireless.service: Succeeded. Aug 19 16:34:05 rivo systemd[1]: Stopped Wireless Services. Aug 19 16:34:05 rivo systemd[1]: Starting Wireless Services... Aug 19 16:34:06 rivo wireless.js[20025]: Cleaning previous... Aug 19 16:34:06 rivo sudo[20040]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Aug 19 16:34:06 rivo sudo[20040]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 19 16:34:06 rivo sudo[20040]: pam_unix(sudo:session): session closed for user root Aug 19 16:34:06 rivo sudo[20043]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Aug 19 16:34:06 rivo sudo[20043]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 19 16:34:06 rivo sudo[20043]: pam_unix(sudo:session): session closed for user root Aug 19 16:34:07 rivo wireless.js[20025]: Stopped aP Aug 19 16:34:07 rivo sudo[20051]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Aug 19 16:34:07 rivo sudo[20051]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 19 16:34:07 rivo kernel: dhd_open: Enter ffffffc050e1d000 Aug 19 16:34:07 rivo kernel: dhd_open : no mutex held. set lock Aug 19 16:34:07 rivo kernel: Dongle Host Driver, version 1.579.77.41.10 (r) Aug 19 16:34:07 rivo kernel: wl_ext_iapsta_attach_netdev: ifidx=0, bssidx=0 Aug 19 16:34:07 rivo kernel: wl_android_wifi_on in 1 Aug 19 16:34:07 rivo kernel: wl_android_wifi_on in 2: g_wifi_on=0 Aug 19 16:34:07 rivo kernel: wifi_platform_set_power = 1 Aug 19 16:34:07 rivo kernel: ======== PULL WL_REG_ON(-1) HIGH! ======== Aug 19 16:34:07 rivo kernel: aml_wifi wifi: [extern_wifi_set_enable] WIFI Disable! 482 Aug 19 16:34:07 rivo kernel: aml_wifi wifi: [extern_wifi_set_enable] WIFI Enable! 482 Aug 19 16:34:07 rivo kernel: sdio_reset_comm(): Aug 19 16:34:07 rivo kernel: meson-mmc: actual_clock :400000, HHI_nand: 0x80 Aug 19 16:34:07 rivo kernel: meson-mmc: [meson_mmc_clk_set_rate_v3] after clock: 0x1000023c Aug 19 16:34:07 rivo kernel: meson-mmc: actual_clock :0, HHI_nand: 0x80 Aug 19 16:34:07 rivo kernel: meson-mmc: [meson_mmc_clk_set_rate_v3] after clock: 0x1000023c Aug 19 16:34:07 rivo kernel: meson-mmc: actual_clock :400000, HHI_nand: 0x80 Aug 19 16:34:07 rivo kernel: meson-mmc: [meson_mmc_clk_set_rate_v3] after clock: 0x1000023c Aug 19 16:34:07 rivo kernel: sdio: queuing unknown CIS tuple 0x80 (2 bytes) Aug 19 16:34:07 rivo kernel: sdio: queuing unknown CIS tuple 0x80 (3 bytes) Aug 19 16:34:07 rivo kernel: sdio: queuing unknown CIS tuple 0x80 (3 bytes) Aug 19 16:34:07 rivo kernel: sdio: queuing unknown CIS tuple 0x80 (7 bytes) Aug 19 16:34:07 rivo kernel: sdio: queuing unknown CIS tuple 0x81 (9 bytes) Aug 19 16:34:08 rivo kernel: meson-aml-mmc ffe03000.sdio: divider requested rate 200000000 != actual rate 199999997: ret=0 Aug 19 16:34:08 rivo kernel: meson-mmc: actual_clock :199999997, HHI_nand: 0x80 Aug 19 16:34:08 rivo kernel: meson-mmc: [meson_mmc_clk_set_rate_v3] after clock: 0x10000245 Aug 19 16:34:08 rivo kernel: meson-mmc: Data 1 aligned delay is 0 Aug 19 16:34:08 rivo kernel: meson-mmc: sdio: clk 199999997 tuning start Aug 19 16:34:08 rivo kernel: meson-mmc: Tuning transfer error: nmatch=0 tuning_err:0xffffffac Aug 19 16:34:08 rivo kernel: meson-mmc: sdio: rx_tuning_result[1] = 40 Aug 19 16:34:08 rivo kernel: meson-mmc: sdio: rx_tuning_result[2] = 40 Aug 19 16:34:08 rivo kernel: meson-mmc: sdio: rx_tuning_result[3] = 40 Aug 19 16:34:08 rivo kernel: meson-mmc: sdio: rx_tuning_result[4] = 40 Aug 19 16:34:08 rivo kernel: meson-mmc: [prev_map] fixed_adj_win_map:30 Aug 19 16:34:08 rivo kernel: meson-mmc: [0]=0 Aug 19 16:34:08 rivo kernel: meson-mmc: [1]=1 Aug 19 16:34:08 rivo kernel: meson-mmc: [2]=1 Aug 19 16:34:08 rivo kernel: meson-mmc: [3]=1 Aug 19 16:34:08 rivo kernel: meson-mmc: [4]=1 Aug 19 16:34:08 rivo kernel: meson-mmc: [_find_fixed_adj_valid_win]retry test fixed adj... Aug 19 16:34:08 rivo kernel: meson-mmc: step:4, delay1:0x4104104, delay2:0x4004104 Aug 19 16:34:08 rivo kernel: meson-mmc: Tuning transfer error: nmatch=0 tuning_err:0xffffffac Aug 19 16:34:08 rivo kernel: meson-mmc: sdio: rx_tuning_result[1] = 40 Aug 19 16:34:08 rivo kernel: meson-mmc: sdio: rx_tuning_result[2] = 40 Aug 19 16:34:08 rivo kernel: meson-mmc: sdio: rx_tuning_result[3] = 40 Aug 19 16:34:08 rivo kernel: meson-mmc: sdio: rx_tuning_result[4] = 40 Aug 19 16:34:08 rivo kernel: meson-mmc: [_find_fixed_adj_valid_win]retry test fixed adj... Aug 19 16:34:08 rivo kernel: meson-mmc: step:8, delay1:0x8208208, delay2:0x8008208 Aug 19 16:34:08 rivo kernel: meson-mmc: sdio: rx_tuning_result[0] = 40 Aug 19 16:34:08 rivo kernel: meson-mmc: Tuning transfer error: nmatch=0 tuning_err:0xffffffac Aug 19 16:34:08 rivo kernel: meson-mmc: sdio: rx_tuning_result[2] = 40 Aug 19 16:34:08 rivo kernel: meson-mmc: sdio: rx_tuning_result[3] = 40 Aug 19 16:34:08 rivo kernel: meson-mmc: sdio: rx_tuning_result[4] = 40 Aug 19 16:34:08 rivo kernel: meson-mmc: [>>>>>>>>bitmap_xor_dst] fixed_adj_win_map:2 Aug 19 16:34:08 rivo kernel: meson-mmc: [0]=0 Aug 19 16:34:08 rivo kernel: meson-mmc: [1]=1 Aug 19 16:34:08 rivo kernel: meson-mmc: [2]=0 Aug 19 16:34:08 rivo kernel: meson-mmc: [3]=0 Aug 19 16:34:08 rivo kernel: meson-mmc: [4]=0 Aug 19 16:34:08 rivo kernel: meson-mmc: [_find_fixed_adj_valid_win] fir_adj:1 Aug 19 16:34:08 rivo kernel: meson-mmc: [>>>>>>>>prev_map_range] fixed_adj_win_map:495 Aug 19 16:34:08 rivo kernel: meson-mmc: [1]=1 Aug 19 16:34:08 rivo kernel: meson-mmc: [2]=1 Aug 19 16:34:08 rivo kernel: meson-mmc: [3]=1 Aug 19 16:34:08 rivo kernel: meson-mmc: [4]=1 Aug 19 16:34:08 rivo kernel: meson-mmc: [0]=0 Aug 19 16:34:08 rivo kernel: meson-mmc: left:3, right:0, mid:4, size:4 Aug 19 16:34:08 rivo kernel: meson-mmc: step:0, delay1:0x0, delay2:0x0 Aug 19 16:34:08 rivo kernel: meson-mmc: sdio: sd_emmc_regs->gclock=0x10000245,sd_emmc_regs->gadjust=0x32000 Aug 19 16:34:08 rivo kernel: meson-mmc: delay1:0x0, delay2:0x0 Aug 19 16:34:08 rivo kernel: sdioh_start: set sd_f2_blocksize 256 Aug 19 16:34:08 rivo kernel: dhd_bus_devreset: == WLAN ON == Aug 19 16:34:08 rivo kernel: F1 signature read @0x18000000=0x17294359 Aug 19 16:34:08 rivo kernel: F1 signature OK, socitype:0x1 chip:0x4359 rev:0x9 pkg:0x2 Aug 19 16:34:08 rivo kernel: DHD: dongle ram size is set to 917504(orig 917504) at 0x160000 Aug 19 16:34:08 rivo kernel: dhd_bus_set_default_min_res_mask: Unhandled chip id Aug 19 16:34:08 rivo kernel: dhd_os_open_image: /lib/firmware/brcm/config_bcm4359c0_ag.txt (185 bytes) open success Aug 19 16:34:08 rivo kernel: dhd_conf_read_sdio_params: dhd_slpauto = 0 Aug 19 16:34:08 rivo kernel: dhd_conf_read_others: ccode = ALL Aug 19 16:34:08 rivo kernel: dhd_conf_read_others: regrev = 38 Aug 19 16:34:08 rivo kernel: dhd_conf_read_nv_by_chip: nv_by_chip_count=5 Aug 19 16:34:08 rivo kernel: dhd_conf_read_nv_by_chip: chip=0xa9a6, chiprev=0, name=nvram_ap6212.txt Aug 19 16:34:08 rivo kernel: dhd_conf_read_nv_by_chip: chip=0xa9a6, chiprev=1, name=nvram_ap6212a.txt Aug 19 16:34:08 rivo kernel: dhd_conf_read_nv_by_chip: chip=0x4345, chiprev=6, name=nvram_ap6255.txt Aug 19 16:34:08 rivo kernel: dhd_conf_read_nv_by_chip: chip=0x4354, chiprev=2, name=nvram_ap6356.txt Aug 19 16:34:08 rivo kernel: dhd_conf_read_nv_by_chip: chip=0x4359, chiprev=9, name=nvram_ap6359sa.txt Aug 19 16:34:08 rivo kernel: Final fw_path=/lib/firmware/brcm/fw_bcm4359c0_ag.bin Aug 19 16:34:08 rivo kernel: Final nv_path=/lib/firmware/brcm/nvram_ap6359sa.txt Aug 19 16:34:08 rivo kernel: Final clm_path=/lib/firmware/brcm/clm.blob Aug 19 16:34:08 rivo kernel: Final conf_path=/lib/firmware/brcm/config_bcm4359c0_ag.txt Aug 19 16:34:08 rivo kernel: dhd_os_open_image: /lib/firmware/brcm/fw_bcm4359c0_ag.bin (595758 bytes) open success Aug 19 16:34:08 rivo kernel: dhd_os_open_image: /lib/firmware/brcm/nvram_ap6359sa.txt (5861 bytes) open success Aug 19 16:34:08 rivo kernel: [43B blob data] Aug 19 16:34:08 rivo kernel: dhdsdio_write_vars: Download, Upload and compare of NVRAM succeeded. Aug 19 16:34:08 rivo kernel: dhd_bus_init: enable 0x06, ready 0x06 (waited 0us) Aug 19 16:34:08 rivo kernel: si_get_pmu_reg_addr: addrRET: 18000670 Aug 19 16:34:08 rivo kernel: bcmsdh_oob_intr_register: HW_OOB irq=70 flags=0x8 Aug 19 16:34:08 rivo kernel: dhd_get_memdump_info: File [/data/misc/wifi/.memdump.info] doesn't exist Aug 19 16:34:08 rivo kernel: dhd_get_memdump_info: MEMDUMP ENABLED = 2 Aug 19 16:34:08 rivo kernel: dhd_tcpack_suppress_set: TCP ACK Suppress mode 0 -> mode 1 Aug 19 16:34:08 rivo kernel: dhd_apply_default_clm: Ignore clm file /lib/firmware/brcm/clm.blob Aug 19 16:34:08 rivo kernel: Firmware up: op_mode=0x0405, MAC=b8:2d:28:29:8b:9a Aug 19 16:34:08 rivo kernel: Driver: 1.579.77.41.10 (r) Firmware: wl0: Jan 19 2018 12:14:30 version 9.87.51.11.8 (a85e25e@shgit) (r) FWID 01-cb5aa0a5 CLM: 9.7.5 Aug 19 16:34:08 rivo kernel: dhd_txglom_enable: enable 1 Aug 19 16:34:08 rivo kernel: dhd_conf_set_txglom_params: txglom_mode=copy Aug 19 16:34:08 rivo kernel: dhd_conf_set_txglom_params: txglomsize=36, deferred_tx_len=0 Aug 19 16:34:08 rivo kernel: dhd_conf_set_txglom_params: txinrx_thres=128, dhd_txminmax=-1 Aug 19 16:34:08 rivo kernel: dhd_conf_set_txglom_params: tx_max_offset=0, txctl_tmo_fix=300 Aug 19 16:34:08 rivo kernel: dhd_conf_get_disable_proptx: fw_proptx=0, disable_proptx=1 Aug 19 16:34:08 rivo kernel: dhd_pno_init: Support Android Location Service Aug 19 16:34:08 rivo kernel: rtt_do_get_ioctl: failed to send getbuf proxd iovar (CMD ID : 1), status=-23 Aug 19 16:34:08 rivo kernel: dhd_rtt_init : FTM is not supported Aug 19 16:34:08 rivo kernel: dhd_preinit_ioctls: SensorHub diabled 0 Aug 19 16:34:08 rivo kernel: dhd_preinit_ioctls failed to set ShubHub disable Aug 19 16:34:08 rivo kernel: failed to set WNM capabilities Aug 19 16:34:08 rivo kernel: dhd_conf_set_country: set country ALL, revision 38 Aug 19 16:34:08 rivo kernel: CONFIG-ERROR) dhd_conf_set_bufiovar: country setting failed -2 Aug 19 16:34:08 rivo kernel: Country code: US (US/0) Aug 19 16:34:08 rivo kernel: CONFIG-ERROR) dhd_conf_set_intiovar: txbf setting failed -23 Aug 19 16:34:08 rivo kernel: wl_android_wifi_on: Success Aug 19 16:34:08 rivo dhcpcd[3333]: wlan0: carrier acquired Aug 19 16:34:08 rivo kernel: dhd_open : the lock is released. Aug 19 16:34:08 rivo kernel: dhd_open: Exit ret=0 Aug 19 16:34:08 rivo sudo[20051]: pam_unix(sudo:session): session closed for user root Aug 19 16:34:08 rivo dhcpcd[3333]: wlan0: IAID 28:29:8b:9a Aug 19 16:34:08 rivo sudo[20056]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Aug 19 16:34:08 rivo sudo[20056]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 19 16:34:08 rivo sudo[20056]: pam_unix(sudo:session): session closed for user root Aug 19 16:34:08 rivo sudo[20063]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Aug 19 16:34:08 rivo sudo[20063]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 19 16:34:08 rivo sudo[20063]: pam_unix(sudo:session): session closed for user root Aug 19 16:34:08 rivo sudo[20066]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Aug 19 16:34:08 rivo sudo[20066]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 19 16:34:09 rivo dhcpcd[3333]: wlan0: soliciting a DHCP lease Aug 19 16:34:09 rivo dhcpcd[3333]: wlan0: soliciting an IPv6 router Aug 19 16:34:09 rivo volumio[3381]: info: Discovery: Started advertising with name: Rivo Aug 19 16:34:10 rivo volumio[3381]: info: Discovery: adding ec70237a-fc75-4ee4-8dc6-8cd070433648 Aug 19 16:34:10 rivo volumio[3381]: info: Discovery: Found device Rivo Aug 19 16:34:10 rivo volumio[3381]: info: CoreCommandRouter::volumioGetState Aug 19 16:34:10 rivo sudo[20066]: pam_unix(sudo:session): session closed for user root Aug 19 16:34:10 rivo wireless.js[20025]: Start wireless flow Aug 19 16:34:10 rivo wireless.js[20025]: Stopped hotspot (if there).. Aug 19 16:34:10 rivo volumio[3381]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 19 16:34:10 rivo volumio[3381]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 19 16:34:10 rivo volumio[3381]: info: Discovery: Getting this device information Aug 19 16:34:10 rivo volumio[3381]: info: CoreCommandRouter::volumioGetState Aug 19 16:34:10 rivo volumio[3381]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 19 16:34:10 rivo sudo[20075]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Aug 19 16:34:10 rivo sudo[20075]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 19 16:34:10 rivo sudo[20075]: pam_unix(sudo:session): session closed for user root Aug 19 16:34:10 rivo sudo[20078]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Aug 19 16:34:10 rivo sudo[20078]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 19 16:34:10 rivo kernel: dhd_stop: Enter ffffffc050e1d000 Aug 19 16:34:10 rivo kernel: CFGP2P-ERROR) wl_cfgp2p_disable_discovery : do nothing, not initialized Aug 19 16:34:10 rivo kernel: CFGP2P-ERROR) wl_cfgp2p_deinit_priv : In Aug 19 16:34:10 rivo kernel: dhd_tcpack_suppress_set: TCP ACK Suppress mode 1 -> mode 0 Aug 19 16:34:10 rivo kernel: wl_android_wifi_off in 1 Aug 19 16:34:10 rivo kernel: wl_android_wifi_off in 2: g_wifi_on=1, on_failure=1 Aug 19 16:34:10 rivo kernel: dhd_dbg_detach_pkt_monitor, 2204 Aug 19 16:34:10 rivo kernel: dhd_bus_devreset: == Power OFF == Aug 19 16:34:10 rivo kernel: bcmsdh_oob_intr_unregister: Enter Aug 19 16:34:10 rivo kernel: dhd_txglom_enable: enable 0 Aug 19 16:34:10 rivo kernel: dhd_conf_set_txglom_params: txglom_mode=copy Aug 19 16:34:10 rivo kernel: dhd_conf_set_txglom_params: txglomsize=0, deferred_tx_len=0 Aug 19 16:34:10 rivo kernel: dhd_conf_set_txglom_params: txinrx_thres=128, dhd_txminmax=-1 Aug 19 16:34:10 rivo kernel: dhd_conf_set_txglom_params: tx_max_offset=0, txctl_tmo_fix=300 Aug 19 16:34:10 rivo kernel: dhd_bus_devreset: WLAN OFF DONE Aug 19 16:34:10 rivo kernel: wifi_platform_set_power = 0 Aug 19 16:34:10 rivo kernel: ======== PULL WL_REG_ON(-1) LOW! ======== Aug 19 16:34:10 rivo kernel: aml_wifi wifi: [extern_wifi_set_enable] WIFI Disable! 482 Aug 19 16:34:10 rivo volumio[3381]: info: Discovery: Started advertising with name: Rivo Aug 19 16:34:10 rivo kernel: wl_android_wifi_off out Aug 19 16:34:10 rivo kernel: wl_ext_iapsta_dettach_netdev: Enter Aug 19 16:34:10 rivo kernel: dhd_stop: Exit Aug 19 16:34:10 rivo dhcpcd[3333]: wlan0: carrier lost Aug 19 16:34:10 rivo sudo[20078]: pam_unix(sudo:session): session closed for user root Aug 19 16:34:11 rivo wireless.js[20025]: DHCP IP Aug 19 16:34:11 rivo wireless.js[20025]: Start ap Aug 19 16:34:11 rivo sudo[20085]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Aug 19 16:34:11 rivo wpa_supplicant[20083]: Successfully initialized wpa_supplicant Aug 19 16:34:11 rivo sudo[20085]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 19 16:34:11 rivo kernel: dhd_open: Enter ffffffc050e1d000 Aug 19 16:34:11 rivo kernel: dhd_open : no mutex held. set lock Aug 19 16:34:11 rivo kernel: Dongle Host Driver, version 1.579.77.41.10 (r) Aug 19 16:34:11 rivo kernel: wl_ext_iapsta_attach_netdev: ifidx=0, bssidx=0 Aug 19 16:34:11 rivo kernel: wl_android_wifi_on in 1 Aug 19 16:34:11 rivo kernel: wl_android_wifi_on in 2: g_wifi_on=0 Aug 19 16:34:11 rivo kernel: wifi_platform_set_power = 1 Aug 19 16:34:11 rivo kernel: ======== PULL WL_REG_ON(-1) HIGH! ======== Aug 19 16:34:11 rivo kernel: aml_wifi wifi: [extern_wifi_set_enable] WIFI Disable! 482 Aug 19 16:34:11 rivo dhcpcd[20087]: sending commands to master dhcpcd process Aug 19 16:34:11 rivo sudo[20085]: pam_unix(sudo:session): session closed for user root Aug 19 16:34:11 rivo kernel: aml_wifi wifi: [extern_wifi_set_enable] WIFI Enable! 482 Aug 19 16:34:11 rivo kernel: sdio_reset_comm(): Aug 19 16:34:11 rivo kernel: meson-mmc: actual_clock :400000, HHI_nand: 0x80 Aug 19 16:34:11 rivo kernel: meson-mmc: [meson_mmc_clk_set_rate_v3] after clock: 0x1000023c Aug 19 16:34:11 rivo kernel: meson-mmc: actual_clock :0, HHI_nand: 0x80 Aug 19 16:34:11 rivo kernel: meson-mmc: [meson_mmc_clk_set_rate_v3] after clock: 0x1000023c Aug 19 16:34:11 rivo kernel: meson-mmc: actual_clock :400000, HHI_nand: 0x80 Aug 19 16:34:11 rivo kernel: meson-mmc: [meson_mmc_clk_set_rate_v3] after clock: 0x1000023c Aug 19 16:34:11 rivo kernel: sdio: queuing unknown CIS tuple 0x80 (2 bytes) Aug 19 16:34:11 rivo kernel: sdio: queuing unknown CIS tuple 0x80 (3 bytes) Aug 19 16:34:11 rivo kernel: sdio: queuing unknown CIS tuple 0x80 (3 bytes) Aug 19 16:34:11 rivo kernel: sdio: queuing unknown CIS tuple 0x80 (7 bytes) Aug 19 16:34:11 rivo kernel: sdio: queuing unknown CIS tuple 0x81 (9 bytes) Aug 19 16:34:11 rivo kernel: meson-aml-mmc ffe03000.sdio: divider requested rate 200000000 != actual rate 199999997: ret=0 Aug 19 16:34:11 rivo kernel: meson-mmc: actual_clock :199999997, HHI_nand: 0x80 Aug 19 16:34:11 rivo kernel: meson-mmc: [meson_mmc_clk_set_rate_v3] after clock: 0x10000245 Aug 19 16:34:11 rivo kernel: meson-mmc: Data 1 aligned delay is 0 Aug 19 16:34:11 rivo kernel: meson-mmc: sdio: clk 199999997 tuning start Aug 19 16:34:11 rivo kernel: meson-mmc: Tuning transfer error: nmatch=0 tuning_err:0xffffffac Aug 19 16:34:11 rivo kernel: meson-mmc: sdio: rx_tuning_result[1] = 40 Aug 19 16:34:11 rivo kernel: meson-mmc: sdio: rx_tuning_result[2] = 40 Aug 19 16:34:11 rivo kernel: meson-mmc: sdio: rx_tuning_result[3] = 40 Aug 19 16:34:11 rivo kernel: meson-mmc: sdio: rx_tuning_result[4] = 40 Aug 19 16:34:11 rivo kernel: meson-mmc: [prev_map] fixed_adj_win_map:30 Aug 19 16:34:11 rivo kernel: meson-mmc: [0]=0 Aug 19 16:34:11 rivo kernel: meson-mmc: [1]=1 Aug 19 16:34:11 rivo kernel: meson-mmc: [2]=1 Aug 19 16:34:11 rivo kernel: meson-mmc: [3]=1 Aug 19 16:34:11 rivo kernel: meson-mmc: [4]=1 Aug 19 16:34:11 rivo kernel: meson-mmc: [_find_fixed_adj_valid_win]retry test fixed adj... Aug 19 16:34:11 rivo kernel: meson-mmc: step:4, delay1:0x4104104, delay2:0x4004104 Aug 19 16:34:11 rivo kernel: meson-mmc: Tuning transfer error: nmatch=0 tuning_err:0xffffffac Aug 19 16:34:11 rivo kernel: meson-mmc: sdio: rx_tuning_result[1] = 40 Aug 19 16:34:11 rivo kernel: meson-mmc: sdio: rx_tuning_result[2] = 40 Aug 19 16:34:11 rivo kernel: meson-mmc: sdio: rx_tuning_result[3] = 40 Aug 19 16:34:11 rivo kernel: meson-mmc: sdio: rx_tuning_result[4] = 40 Aug 19 16:34:11 rivo kernel: meson-mmc: [_find_fixed_adj_valid_win]retry test fixed adj... Aug 19 16:34:11 rivo kernel: meson-mmc: step:8, delay1:0x8208208, delay2:0x8008208 Aug 19 16:34:11 rivo kernel: meson-mmc: sdio: rx_tuning_result[0] = 40 Aug 19 16:34:11 rivo kernel: meson-mmc: Tuning transfer error: nmatch=0 tuning_err:0xffffffac Aug 19 16:34:11 rivo kernel: meson-mmc: sdio: rx_tuning_result[2] = 40 Aug 19 16:34:11 rivo kernel: meson-mmc: sdio: rx_tuning_result[3] = 40 Aug 19 16:34:11 rivo kernel: meson-mmc: sdio: rx_tuning_result[4] = 40 Aug 19 16:34:11 rivo kernel: meson-mmc: [>>>>>>>>bitmap_xor_dst] fixed_adj_win_map:2 Aug 19 16:34:11 rivo kernel: meson-mmc: [0]=0 Aug 19 16:34:11 rivo kernel: meson-mmc: [1]=1 Aug 19 16:34:11 rivo kernel: meson-mmc: [2]=0 Aug 19 16:34:11 rivo kernel: meson-mmc: [3]=0 Aug 19 16:34:11 rivo kernel: meson-mmc: [4]=0 Aug 19 16:34:11 rivo kernel: meson-mmc: [_find_fixed_adj_valid_win] fir_adj:1 Aug 19 16:34:11 rivo kernel: meson-mmc: [>>>>>>>>prev_map_range] fixed_adj_win_map:495 Aug 19 16:34:11 rivo kernel: meson-mmc: [1]=1 Aug 19 16:34:11 rivo kernel: meson-mmc: [2]=1 Aug 19 16:34:11 rivo kernel: meson-mmc: [3]=1 Aug 19 16:34:11 rivo kernel: meson-mmc: [4]=1 Aug 19 16:34:11 rivo kernel: meson-mmc: [0]=0 Aug 19 16:34:11 rivo kernel: meson-mmc: left:3, right:0, mid:4, size:4 Aug 19 16:34:11 rivo kernel: meson-mmc: step:0, delay1:0x0, delay2:0x0 Aug 19 16:34:11 rivo kernel: meson-mmc: sdio: sd_emmc_regs->gclock=0x10000245,sd_emmc_regs->gadjust=0x32000 Aug 19 16:34:11 rivo kernel: meson-mmc: delay1:0x0, delay2:0x0 Aug 19 16:34:11 rivo kernel: sdioh_start: set sd_f2_blocksize 256 Aug 19 16:34:11 rivo kernel: dhd_bus_devreset: == WLAN ON == Aug 19 16:34:11 rivo kernel: F1 signature read @0x18000000=0x17294359 Aug 19 16:34:11 rivo kernel: F1 signature OK, socitype:0x1 chip:0x4359 rev:0x9 pkg:0x2 Aug 19 16:34:11 rivo kernel: DHD: dongle ram size is set to 917504(orig 917504) at 0x160000 Aug 19 16:34:11 rivo kernel: dhd_bus_set_default_min_res_mask: Unhandled chip id Aug 19 16:34:11 rivo kernel: dhd_os_open_image: /lib/firmware/brcm/config_bcm4359c0_ag.txt (185 bytes) open success Aug 19 16:34:11 rivo kernel: dhd_conf_read_sdio_params: dhd_slpauto = 0 Aug 19 16:34:11 rivo kernel: dhd_conf_read_others: ccode = ALL Aug 19 16:34:11 rivo kernel: dhd_conf_read_others: regrev = 38 Aug 19 16:34:11 rivo kernel: dhd_conf_read_nv_by_chip: nv_by_chip_count=5 Aug 19 16:34:11 rivo kernel: dhd_conf_read_nv_by_chip: chip=0xa9a6, chiprev=0, name=nvram_ap6212.txt Aug 19 16:34:11 rivo kernel: dhd_conf_read_nv_by_chip: chip=0xa9a6, chiprev=1, name=nvram_ap6212a.txt Aug 19 16:34:11 rivo kernel: dhd_conf_read_nv_by_chip: chip=0x4345, chiprev=6, name=nvram_ap6255.txt Aug 19 16:34:11 rivo kernel: dhd_conf_read_nv_by_chip: chip=0x4354, chiprev=2, name=nvram_ap6356.txt Aug 19 16:34:11 rivo kernel: dhd_conf_read_nv_by_chip: chip=0x4359, chiprev=9, name=nvram_ap6359sa.txt Aug 19 16:34:11 rivo kernel: Final fw_path=/lib/firmware/brcm/fw_bcm4359c0_ag.bin Aug 19 16:34:11 rivo kernel: Final nv_path=/lib/firmware/brcm/nvram_ap6359sa.txt Aug 19 16:34:11 rivo kernel: Final clm_path=/lib/firmware/brcm/clm.blob Aug 19 16:34:11 rivo kernel: Final conf_path=/lib/firmware/brcm/config_bcm4359c0_ag.txt Aug 19 16:34:11 rivo kernel: dhd_os_open_image: /lib/firmware/brcm/fw_bcm4359c0_ag.bin (595758 bytes) open success Aug 19 16:34:12 rivo wireless.js[20025]: trying... Aug 19 16:34:12 rivo kernel: dhd_os_open_image: /lib/firmware/brcm/nvram_ap6359sa.txt (5861 bytes) open success Aug 19 16:34:12 rivo kernel: [43B blob data] Aug 19 16:34:12 rivo kernel: dhdsdio_write_vars: Download, Upload and compare of NVRAM succeeded. Aug 19 16:34:12 rivo kernel: dhd_bus_init: enable 0x06, ready 0x06 (waited 0us) Aug 19 16:34:12 rivo kernel: si_get_pmu_reg_addr: addrRET: 18000670 Aug 19 16:34:12 rivo kernel: bcmsdh_oob_intr_register: HW_OOB irq=70 flags=0x8 Aug 19 16:34:12 rivo kernel: dhd_get_memdump_info: File [/data/misc/wifi/.memdump.info] doesn't exist Aug 19 16:34:12 rivo kernel: dhd_get_memdump_info: MEMDUMP ENABLED = 2 Aug 19 16:34:12 rivo kernel: dhd_tcpack_suppress_set: TCP ACK Suppress mode 0 -> mode 1 Aug 19 16:34:12 rivo kernel: dhd_apply_default_clm: Ignore clm file /lib/firmware/brcm/clm.blob Aug 19 16:34:12 rivo kernel: Firmware up: op_mode=0x0405, MAC=b8:2d:28:29:8b:9a Aug 19 16:34:12 rivo kernel: Driver: 1.579.77.41.10 (r) Firmware: wl0: Jan 19 2018 12:14:30 version 9.87.51.11.8 (a85e25e@shgit) (r) FWID 01-cb5aa0a5 CLM: 9.7.5 Aug 19 16:34:12 rivo kernel: dhd_txglom_enable: enable 1 Aug 19 16:34:12 rivo kernel: dhd_conf_set_txglom_params: txglom_mode=copy Aug 19 16:34:12 rivo kernel: dhd_conf_set_txglom_params: txglomsize=36, deferred_tx_len=0 Aug 19 16:34:12 rivo kernel: dhd_conf_set_txglom_params: txinrx_thres=128, dhd_txminmax=-1 Aug 19 16:34:12 rivo kernel: dhd_conf_set_txglom_params: tx_max_offset=0, txctl_tmo_fix=300 Aug 19 16:34:12 rivo kernel: dhd_conf_get_disable_proptx: fw_proptx=0, disable_proptx=1 Aug 19 16:34:12 rivo kernel: dhd_pno_init: Support Android Location Service Aug 19 16:34:12 rivo kernel: rtt_do_get_ioctl: failed to send getbuf proxd iovar (CMD ID : 1), status=-23 Aug 19 16:34:12 rivo kernel: dhd_rtt_init : FTM is not supported Aug 19 16:34:12 rivo kernel: dhd_preinit_ioctls: SensorHub diabled 0 Aug 19 16:34:12 rivo kernel: dhd_preinit_ioctls failed to set ShubHub disable Aug 19 16:34:12 rivo kernel: failed to set WNM capabilities Aug 19 16:34:12 rivo kernel: dhd_conf_set_country: set country ALL, revision 38 Aug 19 16:34:12 rivo kernel: CONFIG-ERROR) dhd_conf_set_bufiovar: country setting failed -2 Aug 19 16:34:12 rivo kernel: Country code: US (US/0) Aug 19 16:34:12 rivo kernel: CONFIG-ERROR) dhd_conf_set_intiovar: txbf setting failed -23 Aug 19 16:34:12 rivo kernel: wl_android_wifi_on: Success Aug 19 16:34:12 rivo kernel: dhd_open : the lock is released. Aug 19 16:34:12 rivo kernel: dhd_open: Exit ret=0 Aug 19 16:34:12 rivo sudo[20100]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 19 16:34:12 rivo sudo[20100]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 19 16:34:12 rivo sudo[20100]: pam_unix(sudo:session): session closed for user root Aug 19 16:34:12 rivo kernel: P2P interface registered Aug 19 16:34:12 rivo kernel: wl_cfgp2p_add_p2p_disc_if: wdev: ffffffc0447e8000, wdev->net: (null) Aug 19 16:34:12 rivo kernel: [35B blob data] Aug 19 16:34:12 rivo kernel: P2P interface started Aug 19 16:34:12 rivo ntpd[3332]: ntpd exiting on signal 15 (Terminated) Aug 19 16:34:12 rivo ntpd[3332]: 132.226.210.133 local addr 192.168.1.6 -> Aug 19 16:34:12 rivo systemd[1]: Stopping Network Time Service... Aug 19 16:34:12 rivo systemd[1]: ntp.service: Succeeded. Aug 19 16:34:12 rivo systemd[1]: Stopped Network Time Service. Aug 19 16:34:12 rivo systemd[1]: Starting Network Time Service... Aug 19 16:34:12 rivo ntpd[20134]: ntpd 4.2.8p12@1.3728-o (1): Starting Aug 19 16:34:12 rivo ntpd[20134]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -c /run/ntp.conf.dhcp -u 104:103 Aug 19 16:34:12 rivo systemd[1]: Started Network Time Service. Aug 19 16:34:12 rivo dhcpcd[3333]: wlan0: carrier acquired Aug 19 16:34:12 rivo dhcpcd[3333]: wlan0: IAID 28:29:8b:9a Aug 19 16:34:12 rivo dhcpcd[3333]: wlan0: carrier lost Aug 19 16:34:12 rivo ntpd[20148]: proto: precision = 1.250 usec (-20) Aug 19 16:34:12 rivo ntpd[20148]: Listen and drop on 0 v6wildcard [::]:123 Aug 19 16:34:12 rivo ntpd[20148]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Aug 19 16:34:12 rivo ntpd[20148]: Listen normally on 2 lo 127.0.0.1:123 Aug 19 16:34:12 rivo ntpd[20148]: Listen normally on 3 eth0 192.168.1.6:123 Aug 19 16:34:12 rivo ntpd[20148]: Listening on routing socket on fd #20 for interface updates Aug 19 16:34:12 rivo ntpd[20148]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Aug 19 16:34:12 rivo ntpd[20148]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Aug 19 16:34:12 rivo dhcpcd[3333]: control command: /sbin/dhcpcd Aug 19 16:34:13 rivo wireless.js[20025]: trying... Aug 19 16:34:13 rivo sudo[20166]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 19 16:34:13 rivo sudo[20166]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 19 16:34:13 rivo sudo[20166]: pam_unix(sudo:session): session closed for user root Aug 19 16:34:14 rivo wireless.js[20025]: trying... Aug 19 16:34:14 rivo sudo[20169]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 19 16:34:14 rivo sudo[20169]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 19 16:34:14 rivo sudo[20169]: pam_unix(sudo:session): session closed for user root Aug 19 16:34:15 rivo wireless.js[20025]: trying... Aug 19 16:34:15 rivo sudo[20172]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 19 16:34:15 rivo sudo[20172]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 19 16:34:15 rivo sudo[20172]: pam_unix(sudo:session): session closed for user root Aug 19 16:34:16 rivo wireless.js[20025]: trying... Aug 19 16:34:16 rivo sudo[20175]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 19 16:34:16 rivo sudo[20175]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 19 16:34:16 rivo sudo[20175]: pam_unix(sudo:session): session closed for user root Aug 19 16:34:17 rivo wireless.js[20025]: trying... Aug 19 16:34:17 rivo sudo[20178]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 19 16:34:17 rivo sudo[20178]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 19 16:34:17 rivo sudo[20178]: pam_unix(sudo:session): session closed for user root Aug 19 16:34:18 rivo wireless.js[20025]: trying... Aug 19 16:34:18 rivo sudo[20181]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 19 16:34:18 rivo sudo[20181]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 19 16:34:18 rivo sudo[20181]: pam_unix(sudo:session): session closed for user root Aug 19 16:34:19 rivo wireless.js[20025]: trying... Aug 19 16:34:19 rivo sudo[20184]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 19 16:34:19 rivo sudo[20184]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 19 16:34:19 rivo sudo[20184]: pam_unix(sudo:session): session closed for user root Aug 19 16:34:20 rivo wireless.js[20025]: trying... Aug 19 16:34:20 rivo sudo[20187]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 19 16:34:20 rivo sudo[20187]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 19 16:34:20 rivo sudo[20187]: pam_unix(sudo:session): session closed for user root Aug 19 16:34:21 rivo wireless.js[20025]: trying... Aug 19 16:34:21 rivo sudo[20190]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 19 16:34:21 rivo sudo[20190]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 19 16:34:21 rivo sudo[20190]: pam_unix(sudo:session): session closed for user root Aug 19 16:34:22 rivo wireless.js[20025]: trying... Aug 19 16:34:22 rivo sudo[20193]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 19 16:34:22 rivo sudo[20193]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 19 16:34:22 rivo sudo[20193]: pam_unix(sudo:session): session closed for user root Aug 19 16:34:22 rivo volumio[3381]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 19 16:34:22 rivo volumio[3381]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 19 16:34:22 rivo volumio[3381]: info: Discovery: Getting this device information Aug 19 16:34:22 rivo volumio[3381]: info: CoreCommandRouter::volumioGetState Aug 19 16:34:22 rivo volumio[3381]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 19 16:34:23 rivo wireless.js[20025]: trying... Aug 19 16:34:23 rivo sudo[20196]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 19 16:34:23 rivo sudo[20196]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 19 16:34:23 rivo sudo[20196]: pam_unix(sudo:session): session closed for user root Aug 19 16:34:24 rivo wireless.js[20025]: trying... Aug 19 16:34:24 rivo sudo[20199]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 19 16:34:24 rivo sudo[20199]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 19 16:34:24 rivo sudo[20199]: pam_unix(sudo:session): session closed for user root Aug 19 16:34:25 rivo wireless.js[20025]: trying... Aug 19 16:34:25 rivo sudo[20202]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 19 16:34:25 rivo sudo[20202]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 19 16:34:25 rivo sudo[20202]: pam_unix(sudo:session): session closed for user root Aug 19 16:34:26 rivo wireless.js[20025]: trying... Aug 19 16:34:26 rivo sudo[20205]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 19 16:34:26 rivo sudo[20205]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 19 16:34:26 rivo sudo[20205]: pam_unix(sudo:session): session closed for user root Aug 19 16:34:27 rivo wireless.js[20025]: trying... Aug 19 16:34:27 rivo sudo[20208]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 19 16:34:27 rivo sudo[20208]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 19 16:34:27 rivo sudo[20208]: pam_unix(sudo:session): session closed for user root Aug 19 16:34:28 rivo wireless.js[20025]: trying... Aug 19 16:34:28 rivo sudo[20211]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 19 16:34:28 rivo sudo[20211]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 19 16:34:28 rivo sudo[20211]: pam_unix(sudo:session): session closed for user root Aug 19 16:34:29 rivo wireless.js[20025]: trying... Aug 19 16:34:29 rivo sudo[20214]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 19 16:34:29 rivo sudo[20214]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 19 16:34:29 rivo sudo[20214]: pam_unix(sudo:session): session closed for user root Aug 19 16:34:30 rivo wireless.js[20025]: trying... Aug 19 16:34:30 rivo sudo[20218]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 19 16:34:30 rivo sudo[20218]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 19 16:34:30 rivo sudo[20218]: pam_unix(sudo:session): session closed for user root Aug 19 16:34:31 rivo wireless.js[20025]: trying... Aug 19 16:34:31 rivo sudo[20221]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 19 16:34:31 rivo sudo[20221]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 19 16:34:31 rivo sudo[20221]: pam_unix(sudo:session): session closed for user root Aug 19 16:34:32 rivo wireless.js[20025]: Overtime, starting plan B Aug 19 16:34:32 rivo systemd[1]: Started Wireless Services. Aug 19 16:34:32 rivo sudo[20019]: pam_unix(sudo:session): session closed for user root Aug 19 16:34:32 rivo sudo[19975]: pam_unix(sudo:session): session closed for user root Aug 19 16:34:37 rivo volumio[3381]: info: CoreCommandRouter::executeOnPlugin: wizard , reportWirelessConnection Aug 19 16:34:37 rivo volumio[3381]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessInfo Aug 19 16:34:37 rivo volumio[3381]: info: CoreCommandRouter::executeOnPlugin: wizard , reportWirelessConnection Aug 19 16:34:37 rivo volumio[3381]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessInfo Aug 19 16:34:37 rivo sudo[20227]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 19 16:34:37 rivo sudo[20227]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 19 16:34:37 rivo sudo[20229]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 19 16:34:37 rivo sudo[20229]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 19 16:34:37 rivo sudo[20227]: pam_unix(sudo:session): session closed for user root Aug 19 16:34:37 rivo sudo[20229]: pam_unix(sudo:session): session closed for user root Aug 19 16:34:42 rivo volumio[3381]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart Aug 19 16:34:42 rivo volumio[3381]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart Aug 19 16:34:42 rivo volumio[3381]: info: Refreshing Cached IP Addresses Aug 19 16:34:42 rivo sudo[20236]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Aug 19 16:34:42 rivo sudo[20236]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 19 16:34:42 rivo sudo[20236]: pam_unix(sudo:session): session closed for user root Aug 19 16:34:42 rivo sudo[20239]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 19 16:34:42 rivo sudo[20239]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 19 16:34:42 rivo volumio[3381]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart Aug 19 16:34:42 rivo sudo[20241]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 19 16:34:42 rivo sudo[20241]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 19 16:34:42 rivo sudo[20239]: pam_unix(sudo:session): session closed for user root Aug 19 16:34:42 rivo sudo[20241]: pam_unix(sudo:session): session closed for user root Aug 19 16:34:42 rivo volumio[3381]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart Aug 19 16:34:42 rivo volumio[3381]: info: Refreshing Cached IP Addresses Aug 19 16:34:42 rivo sudo[20248]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Aug 19 16:34:42 rivo sudo[20248]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 19 16:34:42 rivo volumio[3381]: error: Upnp client error: Error: This socket has been ended by the other party Aug 19 16:34:42 rivo sudo[20250]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 19 16:34:42 rivo sudo[20250]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 19 16:34:42 rivo sudo[20248]: pam_unix(sudo:session): session closed for user root Aug 19 16:34:42 rivo volumio[3381]: error: Upnp client error: Error: This socket has been ended by the other party Aug 19 16:34:42 rivo sudo[20253]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 19 16:34:42 rivo sudo[20250]: pam_unix(sudo:session): session closed for user root Aug 19 16:34:42 rivo sudo[20253]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 19 16:34:42 rivo sudo[20253]: pam_unix(sudo:session): session closed for user root Aug 19 16:34:46 rivo volumio[3381]: info: CoreCommandRouter::volumioGetQueue Aug 19 16:34:46 rivo volumio[3381]: info: CoreStateMachine::getQueue Aug 19 16:34:46 rivo volumio[3381]: info: CorePlayQueue::getQueue Aug 19 16:34:52 rivo sudo[20259]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 19 16:34:52 rivo sudo[20259]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 19 16:34:52 rivo sudo[20261]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 19 16:34:52 rivo sudo[20261]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 19 16:34:52 rivo sudo[20259]: pam_unix(sudo:session): session closed for user root Aug 19 16:34:52 rivo sudo[20261]: pam_unix(sudo:session): session closed for user root Aug 19 16:34:52 rivo sudo[20267]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Aug 19 16:34:52 rivo sudo[20267]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 19 16:34:52 rivo sudo[20267]: pam_unix(sudo:session): session closed for user root Aug 19 16:34:52 rivo volumio[3381]: info: Upmpdcli Daemon Started Aug 19 16:34:52 rivo sudo[20270]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 19 16:34:52 rivo sudo[20270]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 19 16:34:52 rivo sudo[20270]: pam_unix(sudo:session): session closed for user root Aug 19 16:34:52 rivo sudo[20272]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 19 16:34:52 rivo sudo[20272]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 19 16:34:52 rivo volumio[3381]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 19 16:34:52 rivo volumio[3381]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 19 16:34:52 rivo volumio[3381]: info: Discovery: Getting this device information Aug 19 16:34:52 rivo volumio[3381]: info: CoreCommandRouter::volumioGetState Aug 19 16:34:52 rivo volumio[3381]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 19 16:34:52 rivo sudo[20275]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Aug 19 16:34:52 rivo sudo[20275]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 19 16:34:52 rivo sudo[20272]: pam_unix(sudo:session): session closed for user root Aug 19 16:34:52 rivo sudo[20275]: pam_unix(sudo:session): session closed for user root Aug 19 16:34:52 rivo volumio[3381]: info: Upmpdcli Daemon Started Aug 19 16:34:53 rivo volumio[3381]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Aug 19 16:34:53 rivo volumio[3381]: info: Preload queue cleared Aug 19 16:34:55 rivo volumio[3381]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Aug 19 16:34:55 rivo volumio[3381]: info: Preload queue cleared Aug 19 16:35:01 rivo volumio[3381]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Aug 19 16:35:01 rivo volumio[3381]: info: Preload queue cleared Aug 19 16:35:03 rivo volumio[3381]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Aug 19 16:35:03 rivo volumio[3381]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 19 16:35:03 rivo volumio[3381]: TypeError: Cannot read property 'length' of undefined Aug 19 16:35:03 rivo volumio[3381]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Aug 19 16:35:03 rivo volumio[3381]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Aug 19 16:35:03 rivo volumio[3381]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Aug 19 16:35:03 rivo volumio[3381]: at Parser.emit (events.js:400:28) Aug 19 16:35:03 rivo volumio[3381]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Aug 19 16:35:03 rivo volumio[3381]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Aug 19 16:35:03 rivo volumio[3381]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Aug 19 16:35:03 rivo volumio[3381]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Aug 19 16:35:03 rivo volumio[3381]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Aug 19 16:35:03 rivo volumio[3381]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Aug 19 16:35:03 rivo volumio[3381]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Aug 19 16:35:03 rivo volumio[3381]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Aug 19 16:35:03 rivo volumio[3381]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Aug 19 16:35:03 rivo volumio[3381]: at IncomingMessage.emit (events.js:412:35) Aug 19 16:35:03 rivo volumio[3381]: at endReadableNT (internal/streams/readable.js:1333:12) Aug 19 16:35:03 rivo volumio[3381]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Aug 19 16:35:03 rivo volumio[3381]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 19 16:35:04 rivo sudo[20333]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-19 16:34 Aug 19 16:35:04 rivo sudo[20333]: 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="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 05:06:46 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="420eec03f218d056e8306370042003fc"