Wi-Fi not working on boot

[mightyguava]

Brand new Orange Pi 5B. I burned the ubuntu-22.04.3-preinstalled-server-arm64-orangepi-5b.img.xz to eMMC.

The only changes I've made so far are to configure netplan and run an apt dist-upgrade.

At startup, I get a message that says it's waiting for network ready and waits for about 2 minutes.

systemd-networkd-wait-online[652]: Timeout occurred while waiting for network connectivity.

After that boot completes, and I get a link-local IPv6 address but no IPv4 address.

Running

systemctl restart systemd-networkd

will get wifi to connect almost immediately.

/etc/netplan/50-cloud-init.yaml is configured with

# This file is generated from information provided by the datasource.  Changes
# to it will not persist across an instance reboot.  To disable cloud-init's
# network configuration capabilities, write a file
# /etc/cloud/cloud.cfg.d/99-disable-network-config.cfg with the following:
# network: {config: disabled}
network:
    ethernets:
      eth0:
        dhcp4: true
        optional: true
    version: 2
    renderer: networkd
    wifis:
      wlan0:
        dhcp4: true
        optional: true
        access-points:
          "$SSID":
            password: "$PASSWORD"

Not sure why this header is there but cloudinit has not been overwriting it across reboots.

Found these error logs but not sure what to do about it. Timestamp 02:30 is boot time, and then timestamp 02:34:43 is when I manually ran systemctl restart systemd-networkd

$ journalctl -u netplan-wpa-wlan0.service
-- Boot 79bb3e09a0dc4bdabb3217c1592b0726 --
Sep 19 02:30:08 mirror systemd[1]: Started WPA supplicant for netplan wlan0.
Sep 19 02:30:08 mirror wpa_supplicant[603]: Successfully initialized wpa_supplicant
Sep 19 02:30:08 mirror wpa_supplicant[603]: Could not set interface wlan0 flags (UP): Device or resource busy
Sep 19 02:30:08 mirror wpa_supplicant[603]: nl80211: Could not set interface 'wlan0' UP
Sep 19 02:30:08 mirror wpa_supplicant[603]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0
Sep 19 02:30:08 mirror wpa_supplicant[603]: Could not set interface wlan0 flags (UP): Device or resource busy
Sep 19 02:30:08 mirror wpa_supplicant[603]: WEXT: Could not set interface 'wlan0' UP
Sep 19 02:30:08 mirror wpa_supplicant[603]: wlan0: Failed to initialize driver interface
Sep 19 02:30:08 mirror wpa_supplicant[603]: wlan0: CTRL-EVENT-DSCP-POLICY clear_all
Sep 19 02:30:08 mirror systemd[1]: netplan-wpa-wlan0.service: Main process exited, code=exited, status=255/EXCEPTION
Sep 19 02:30:08 mirror systemd[1]: netplan-wpa-wlan0.service: Failed with result 'exit-code'.
Sep 19 02:34:43 mirror systemd[1]: Started WPA supplicant for netplan wlan0.
Sep 19 02:34:43 mirror wpa_supplicant[1031]: Successfully initialized wpa_supplicant
Sep 19 02:34:43 mirror wpa_supplicant[1031]: nl80211: kernel reports: Registration to specific type not supported
Sep 19 02:34:45 mirror wpa_supplicant[1031]: wlan0: Trying to associate with SSID 'yulu'
Sep 19 02:34:46 mirror wpa_supplicant[1031]: wlan0: Associated with a0:36:bc:38:86:dc
Sep 19 02:34:46 mirror wpa_supplicant[1031]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
Sep 19 02:34:46 mirror wpa_supplicant[1031]: wlan0: WPA: Key negotiation completed with a0:36:bc:38:86:dc [PTK=CCMP GTK=CCMP]
Sep 19 02:34:46 mirror wpa_supplicant[1031]: wlan0: CTRL-EVENT-CONNECTED - Connection to a0:36:bc:38:86:dc completed [id=0 id_str=]

$ journalctl -u systemd-networkd
Sep 19 02:30:09 mirror systemd[1]: Starting Network Configuration...
Sep 19 02:30:09 mirror systemd-networkd[616]: lo: Link UP
Sep 19 02:30:09 mirror systemd-networkd[616]: lo: Gained carrier
Sep 19 02:30:09 mirror systemd-networkd[616]: Enumeration completed
Sep 19 02:30:09 mirror systemd[1]: Started Network Configuration.
Sep 19 02:30:10 mirror systemd-networkd[616]: wlan0: Link UP
Sep 19 02:30:10 mirror systemd-networkd[616]: wlan0: Gained carrier
Sep 19 02:30:10 mirror systemd-networkd[616]: eth0: Link UP
Sep 19 02:30:12 mirror systemd-networkd[616]: wlan0: Gained IPv6LL
Sep 19 02:34:43 mirror systemd[1]: Stopping Network Configuration...
Sep 19 02:34:43 mirror systemd-networkd[616]: eth0: DHCPv6 lease lost
Sep 19 02:34:43 mirror systemd-networkd[616]: wlan0: DHCPv6 lease lost
Sep 19 02:34:43 mirror systemd[1]: systemd-networkd.service: Deactivated successfully.
Sep 19 02:34:43 mirror systemd[1]: Stopped Network Configuration.
Sep 19 02:34:43 mirror systemd[1]: Starting Network Configuration...
Sep 19 02:34:43 mirror systemd-networkd[1034]: wlan0: Link UP
Sep 19 02:34:43 mirror systemd-networkd[1034]: eth0: Link UP
Sep 19 02:34:43 mirror systemd-networkd[1034]: lo: Link UP
Sep 19 02:34:43 mirror systemd-networkd[1034]: lo: Gained carrier
Sep 19 02:34:43 mirror systemd-networkd[1034]: wlan0: Gained IPv6LL
Sep 19 02:34:43 mirror systemd-networkd[1034]: Enumeration completed
Sep 19 02:34:43 mirror systemd[1]: Started Network Configuration.
Sep 19 02:34:46 mirror systemd-networkd[1034]: wlan0: Gained carrier
Sep 19 02:34:47 mirror systemd-networkd[1034]: wlan0: DHCPv4 address 192.168.1.132/24 via 192.168.1.1

It seems something is competing with wpa_supplicant for wlan0 on boot?

[mightyguava]

More complete logs from journalctl -x below:

journalctl logs - collapsed

``` Sep 19 02:30:08 mirror systemd[1]: Reached target System Time Set. ░░ Subject: A start job for unit time-set.target has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit time-set.target has finished successfully. ░░ ░░ The job identifier is 9. Sep 19 02:30:08 mirror kernel: [dhd] dhd_rx_frame: net device is NOT registered. drop event packet Sep 19 02:30:08 mirror kernel: [dhd] dhd_conf_set_country : set country CN, revision 0 Sep 19 02:30:08 mirror kernel: [dhd] dhd_rx_frame: net device is NOT registered. drop event packet Sep 19 02:30:08 mirror kernel: [dhd] dhd_conf_set_country : Country code: CN (CN/0) Sep 19 02:30:08 mirror systemd-udevd[510]: Using default interface naming scheme 'v249'. Sep 19 02:30:08 mirror kernel: [dhd] Dongle Host Driver, version 101.10.361.20 (wlan=r892223-20220701-3) drivers/net/wireless/rockchip_wlan/rkwifi/bcmdhd compiled on Sep 11 2023 at 20:31:55 Sep 19 02:30:08 mirror kernel: [dhd] Register interface [wlan0] MAC: b8:2d:28:42:2a:f4 Sep 19 02:30:08 mirror kernel: [dhd] dhd_tcpack_suppress_set: TCP ACK Suppress mode 3 -> mode 0 Sep 19 02:30:08 mirror kernel: [dhd] dhd_tcpack_suppress_set: TCPACK_INFO_MAXNUM=10, TCPDATA_INFO_MAXNUM=10 Sep 19 02:30:08 mirror kernel: [dhd] [wlan0] wl_android_wifi_off : in g_wifi_on=1, on_failure=1 Sep 19 02:30:08 mirror kernel: [dhd] dhd_rtt_deinit: ENTER Sep 19 02:30:08 mirror kernel: [dhd] dhd_rtt_deinit: EXIT, err = 0 Sep 19 02:30:08 mirror kernel: [dhd] dhd_bus_devreset: == Power OFF == Sep 19 02:30:08 mirror kernel: [dhd] dhd_bus_stop: making DHD_BUS_DOWN Sep 19 02:30:08 mirror kernel: [dhd] dhd_dpc_kill: tasklet disabled Sep 19 02:30:08 mirror kernel: [dhd] dhd_bus_devreset: making DHD_BUS_DOWN Sep 19 02:30:08 mirror kernel: [dhd] dhd_bus_devreset: WLAN OFF Done Sep 19 02:30:08 mirror kernel: [dhd] wifi_platform_set_power = 0, delay: 0 msec Sep 19 02:30:08 mirror kernel: [dhd] ======== PULL WL_REG_ON(-1) LOW! ======== Sep 19 02:30:08 mirror kernel: [WLAN_RFKILL]: rockchip_wifi_power: 0 Sep 19 02:30:08 mirror kernel: [WLAN_RFKILL]: rockchip_wifi_power: toggle = false Sep 19 02:30:08 mirror kernel: wifi power off Sep 19 02:30:08 mirror systemd[1]: Found device /sys/subsystem/net/devices/wlan0. ░░ Subject: A start job for unit sys-subsystem-net-devices-wlan0.device has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit sys-subsystem-net-devices-wlan0.device has finished successfully. ░░ ░░ The job identifier is 78. Sep 19 02:30:08 mirror systemd[1]: Started WPA supplicant for netplan wlan0. ░░ Subject: A start job for unit netplan-wpa-wlan0.service has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit netplan-wpa-wlan0.service has finished successfully. ░░ ░░ The job identifier is 77. Sep 19 02:30:08 mirror wpa_supplicant[603]: Successfully initialized wpa_supplicant Sep 19 02:30:08 mirror wpa_supplicant[603]: Could not set interface wlan0 flags (UP): Device or resource busy Sep 19 02:30:08 mirror wpa_supplicant[603]: nl80211: Could not set interface 'wlan0' UP Sep 19 02:30:08 mirror wpa_supplicant[603]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Sep 19 02:30:08 mirror wpa_supplicant[603]: Could not set interface wlan0 flags (UP): Device or resource busy Sep 19 02:30:08 mirror wpa_supplicant[603]: WEXT: Could not set interface 'wlan0' UP Sep 19 02:30:08 mirror wpa_supplicant[603]: wlan0: Failed to initialize driver interface Sep 19 02:30:08 mirror kernel: [dhd] dhd_pri_open : probe is already running! return. Sep 19 02:30:08 mirror kernel: [dhd] dhd_pri_open : probe is already running! return. Sep 19 02:30:08 mirror wpa_supplicant[603]: wlan0: CTRL-EVENT-DSCP-POLICY clear_all Sep 19 02:30:08 mirror kernel: [WLAN_RFKILL]: wifi shut off power [GPIO24-0] Sep 19 02:30:08 mirror kernel: [dhd] [wlan0] wl_android_wifi_off : out Sep 19 02:30:08 mirror kernel: [dhd] dhdpcie_pci_probe : mutex is released. Sep 19 02:30:08 mirror kernel: [dhd] _dhd_module_init: Exit err=0 Sep 19 02:30:08 mirror systemd[1]: netplan-wpa-wlan0.service: Main process exited, code=exited, status=255/EXCEPTION ░░ Subject: Unit process exited ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ An ExecStart= process belonging to unit netplan-wpa-wlan0.service has exited. ░░ ░░ The process' exit code is 'exited' and its exit status is 255. Sep 19 02:30:08 mirror systemd[1]: netplan-wpa-wlan0.service: Failed with result 'exit-code'. ░░ Subject: Unit failed ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ The unit netplan-wpa-wlan0.service has entered the 'failed' state with result 'exit-code'. Sep 19 02:30:08 mirror cloud-init[607]: Cloud-init v. 23.2.2-0ubuntu0~22.04.1 running 'init-local' at Tue, 19 Sep 2023 02:30:08 +0000. Up 8.81 seconds. Sep 19 02:30:09 mirror systemd[1]: Finished Initial cloud-init job (pre-networking). ░░ Subject: A start job for unit cloud-init-local.service has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit cloud-init-local.service has finished successfully. ░░ ░░ The job identifier is 159. Sep 19 02:30:09 mirror systemd[1]: Reached target Preparation for Network. ░░ Subject: A start job for unit network-pre.target has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit network-pre.target has finished successfully. ░░ ░░ The job identifier is 160. Sep 19 02:30:09 mirror systemd[1]: Starting Raise network interfaces... ░░ Subject: A start job for unit networking.service has begun execution ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit networking.service has begun execution. ░░ ░░ The job identifier is 79. Sep 19 02:30:09 mirror systemd[1]: Starting Network Configuration... ░░ Subject: A start job for unit systemd-networkd.service has begun execution ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit systemd-networkd.service has begun execution. ░░ ░░ The job identifier is 73. Sep 19 02:30:09 mirror systemd-networkd[616]: lo: Link UP Sep 19 02:30:09 mirror systemd-networkd[616]: lo: Gained carrier Sep 19 02:30:09 mirror ifup[640]: /etc/network/if-up.d/resolved: 12: mystatedir: not found Sep 19 02:30:09 mirror systemd-networkd[616]: Enumeration completed Sep 19 02:30:09 mirror systemd[1]: Started Network Configuration. ░░ Subject: A start job for unit systemd-networkd.service has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit systemd-networkd.service has finished successfully. ░░ ░░ The job identifier is 73. Sep 19 02:30:09 mirror kernel: [dhd] dhd_query_bus_erros: Dongle Reset occurred, cannot proceed Sep 19 02:30:09 mirror kernel: [dhd] [Repeats 0 times] Sep 19 02:30:09 mirror kernel: [dhd] CFG80211-ERROR) wl_cfg80211_dump_station : WLC_GET_ASSOCLIST error -19 Sep 19 02:30:09 mirror kernel: [dhd] dhd_pri_open : no mutex held Sep 19 02:30:09 mirror kernel: [dhd] dhd_pri_open : set mutex lock Sep 19 02:30:09 mirror kernel: [dhd] [wlan0] dhd_open : Enter Sep 19 02:30:09 mirror kernel: [dhd] Dongle Host Driver, version 101.10.361.20 (wlan=r892223-20220701-3) drivers/net/wireless/rockchip_wlan/rkwifi/bcmdhd compiled on Sep 11 2023 at 20:31:55 Sep 19 02:30:09 mirror kernel: [dhd] dhd_open: ######### called for ifidx=0 ######### Sep 19 02:30:09 mirror kernel: [dhd] [wlan0] wl_android_wifi_on : in g_wifi_on=0 Sep 19 02:30:09 mirror kernel: [dhd] wifi_platform_set_power = 1, delay: 200 msec Sep 19 02:30:09 mirror kernel: [dhd] ======== PULL WL_REG_ON(-1) HIGH! ======== Sep 19 02:30:09 mirror kernel: [WLAN_RFKILL]: rockchip_wifi_power: 1 Sep 19 02:30:09 mirror kernel: [WLAN_RFKILL]: rockchip_wifi_power: toggle = false Sep 19 02:30:09 mirror systemd[1]: Starting Wait for Network to be Configured... ░░ Subject: A start job for unit systemd-networkd-wait-online.service has begun execution ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit systemd-networkd-wait-online.service has begun execution. ░░ ░░ The job identifier is 72. Sep 19 02:30:09 mirror systemd[1]: Starting Network Name Resolution... ░░ Subject: A start job for unit systemd-resolved.service has begun execution ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit systemd-resolved.service has begun execution. ░░ ░░ The job identifier is 111. Sep 19 02:30:09 mirror systemd[1]: Finished Raise network interfaces. ░░ Subject: A start job for unit networking.service has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit networking.service has finished successfully. ░░ ░░ The job identifier is 79. Sep 19 02:30:09 mirror kernel: [WLAN_RFKILL]: wifi turn on power [GPIO24-1] Sep 19 02:30:09 mirror systemd-resolved[663]: Positive Trust Anchors: Sep 19 02:30:09 mirror systemd-resolved[663]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Sep 19 02:30:09 mirror systemd-resolved[663]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa corp home internal intranet lan local private test Sep 19 02:30:09 mirror systemd-resolved[663]: Using system hostname 'mirror'. Sep 19 02:30:09 mirror kernel: [dhd] dhd_bus_devreset: == Power ON == Sep 19 02:30:09 mirror kernel: [dhd] dhd_bus_devreset: dhdpcie_bus_start_host_dev OK Sep 19 02:30:09 mirror kernel: [dhd] dhd_bus_aspm_enable_dev: EP already disabled linkctrl: 0x10110000 Sep 19 02:30:09 mirror kernel: [dhd] dhd_bus_aspm_enable_dev: RC already disabled linkctrl: 0x30110000 Sep 19 02:30:09 mirror kernel: [dhd] Disable CTO Sep 19 02:30:09 mirror kernel: [dhd] DHD: dongle ram size is set to 1310720(orig 1310720) at 0x170000 Sep 19 02:30:09 mirror kernel: [dhd] dhdpcie_bar1_window_switch_enab: bar1_switch_enab=0 ramstart=0x170000 ramend=0x2affff bar1_size=0x400000 Sep 19 02:30:09 mirror kernel: [dhd] dhdpcie_request_irq: INTx enabled Sep 19 02:30:09 mirror kernel: [dhd] dhd_bus_download_firmware: firmware path=/lib/firmware/ap6275p/fw_bcmdhd.bin, nvram path=/lib/firmware/ap6275p/nvram.txt Sep 19 02:30:09 mirror kernel: [dhd] dhdpcie_dump_resource: BAR0(VA): 0x0000000000000000, BAR0(PA): 0x00000000f4200000, SIZE: 32768 Sep 19 02:30:09 mirror kernel: [dhd] dhdpcie_dump_resource: BAR1(VA): 0x0000000000000000, BAR1(PA): 0x00000000f4400000, SIZE: 4194304 Sep 19 02:30:09 mirror kernel: [dhd] dhd_conf_set_path_params : Final fw_path=/lib/firmware/ap6275p/fw_bcm43752a2_pcie_ag.bin Sep 19 02:30:09 mirror kernel: [dhd] dhd_conf_set_path_params : Final nv_path=/lib/firmware/ap6275p/nvram_AP6275P.txt Sep 19 02:30:09 mirror kernel: [dhd] dhd_conf_set_path_params : Final clm_path=/lib/firmware/ap6275p/clm_bcm43752a2_pcie_ag.blob Sep 19 02:30:09 mirror kernel: [dhd] dhd_conf_set_path_params : Final conf_path=/lib/firmware/ap6275p/config_bcm43752a2_pcie_ag.txt Sep 19 02:30:09 mirror kernel: [dhd] dhd_get_download_buffer: Open image file failed /lib/firmware/ap6275p/config_bcm43752a2_pcie_ag.txt Sep 19 02:30:09 mirror kernel: [dhd] dhd_conf_read_config : Ignore config file /lib/firmware/ap6275p/config_bcm43752a2_pcie_ag.txt Sep 19 02:30:09 mirror kernel: [dhd] d2h_intr_method -> PCIE_INTX(0); d2h_intr_control -> D2H_INTMASK(0) Sep 19 02:30:09 mirror kernel: [dhd] dhdpcie_download_code_file: dhd_tcm_test_enable 0 Sep 19 02:30:09 mirror kernel: [dhd] dhdpcie_download_code_file: download firmware /lib/firmware/ap6275p/fw_bcm43752a2_pcie_ag.bin Sep 19 02:30:09 mirror kernel: [dhd] dhd_os_open_image1: /lib/firmware/ap6275p/fw_bcm43752a2_pcie_ag.bin (936074 bytes) open success Sep 19 02:30:09 mirror kernel: [dhd] dhd_os_open_image1: /lib/firmware/ap6275p/nvram_AP6275P.txt (7458 bytes) open success Sep 19 02:30:09 mirror kernel: [dhd] dhdpcie_download_nvram: dhd_get_download_buffer len 7458 Sep 19 02:30:09 mirror kernel: [dhd] # AP6275P_NVRAM_V1.2_20210918A Sep 19 02:30:09 mirror kernel: [dhd] dhdpcie_download_nvram: process_nvram_vars len 6036 Sep 19 02:30:09 mirror kernel: [dhd] dhdpcie_bus_write_vars: Download, Upload and compare of NVRAM succeeded. Sep 19 02:30:09 mirror kernel: [dhd] dhd_bus_aer_config: Configure AER registers for EP Sep 19 02:30:09 mirror kernel: [dhd] dhd_bus_aer_config: Configure AER registers for RC Sep 19 02:30:09 mirror kernel: [dhd] ### Total time ARM OOR to Readshared pass took 91672 usec ### Sep 19 02:30:09 mirror kernel: [dhd] dhdpcie_readshared: PCIe shared addr (0x0020a584) read took 90000 usec before dongle is ready Sep 19 02:30:09 mirror kernel: [dhd] H2D DMA WR INDX : array size 172 = 4 * 43 Sep 19 02:30:09 mirror kernel: [dhd] D2H DMA RD INDX : array size 16 = 4 * 4 Sep 19 02:30:09 mirror kernel: [dhd] D2H DMA WR INDX : array size 16 = 4 * 4 Sep 19 02:30:09 mirror kernel: [dhd] H2D DMA RD INDX : array size 172 = 4 * 43 Sep 19 02:30:09 mirror kernel: [dhd] dhdpcie_readshared: max H2D queues 40 Sep 19 02:30:09 mirror kernel: [dhd] FW supports debug buf dest ? N Sep 19 02:30:09 mirror kernel: [dhd] dhd_bus_init: Enabling bus->intr_enabled Sep 19 02:30:09 mirror kernel: [dhd] dhdpcie_oob_intr_register OOB irq=149 flags=0x4 Sep 19 02:30:09 mirror kernel: [dhd] dhdpcie_oob_intr_register: enable_irq_wake Sep 19 02:30:09 mirror kernel: [dhd] dhd_bus_start: Initializing 43 h2drings Sep 19 02:30:09 mirror kernel: [dhd] STATIC-MSG) dhd_wlan_mem_prealloc : sectoin 9, size 32896 Sep 19 02:30:09 mirror kernel: [dhd] dhd_prot_init:3925: h2d_max_txpost = 512 Sep 19 02:30:09 mirror kernel: [dhd] dhd_prot_init:3940: MAX_RXBUFPOST = 511 Sep 19 02:30:09 mirror kernel: [dhd] ENABLING DW:0 Sep 19 02:30:09 mirror kernel: [dhd] IDMA not enabled in FW !! Sep 19 02:30:09 mirror kernel: [dhd] IFRM not enabled in FW !! Sep 19 02:30:09 mirror kernel: [dhd] DAR not enabled in FW !! Sep 19 02:30:09 mirror kernel: [dhd] Enable hostcap: EXTD TXS in txcpl Sep 19 02:30:09 mirror kernel: [dhd] dhd_prot_d2h_sync_init(): D2H sync mechanism is NONE Sep 19 02:30:09 mirror kernel: [dhd] dhd_bus_hostready : Read PCICMD Reg: 0x00100006 Sep 19 02:30:09 mirror kernel: [dhd] dhd_bus_dump_dar_registers: dar_clk_ctrl(0xa08:0x10b0040) dar_pwr_ctrl(0xa0c:0x30000) dar_intstat(0xa10:0x0) Sep 19 02:30:09 mirror kernel: [dhd] dhd_bus_dump_dar_registers: dar_errlog(0xa60:0x0) dar_erraddr(0xa64:0x0) dar_pcie_mbint(0xa68:0x0) Sep 19 02:30:09 mirror kernel: [dhd] dhd_bus_hostready: Ring Hostready:1 Sep 19 02:30:09 mirror kernel: [dhd] dhd_send_d2h_ringcreate ringid: 3 idx: 46 max_h2d: 43 Sep 19 02:30:09 mirror kernel: [dhd] dhd_prot_process_d2h_ring_create_complete ring create Response status = 0 ring 3, id 0xfffc Sep 19 02:30:09 mirror kernel: [dhd] info buffer post after ring create Sep 19 02:30:09 mirror kernel: [dhd] wlc_ver_major 12, wlc_ver_minor 1 Sep 19 02:30:09 mirror kernel: [dhd] dhd_get_memdump_info: MEMDUMP ENABLED = 3 Sep 19 02:30:09 mirror kernel: [dhd] dhd_sync_with_dongle: GET_REVINFO device 0x449d, vendor 0x14e4, chipnum 0xaae8 Sep 19 02:30:09 mirror kernel: [dhd] dhd_sync_with_dongle: RxBuf Post : 2048 Sep 19 02:30:09 mirror kernel: [dhd] dhd_preinit_ioctls: preinit_status IOVAR not supported, use legacy preinit Sep 19 02:30:09 mirror kernel: [dhd] dhd_tcpack_suppress_set: TCP ACK Suppress mode 0 -> mode 3 Sep 19 02:30:09 mirror kernel: [dhd] dhd_tcpack_suppress_set: TCPACK_INFO_MAXNUM=10, TCPDATA_INFO_MAXNUM=10 Sep 19 02:30:09 mirror kernel: [dhd] dhd_legacy_preinit_ioctls: hostwake_oob enabled Sep 19 02:30:09 mirror kernel: [dhd] dhd_legacy_preinit_ioctls: use firmware generated mac_address b8:2d:28:42:2a:f4 Sep 19 02:30:09 mirror kernel: [dhd] dhd_os_open_image1: /lib/firmware/ap6275p/clm_bcm43752a2_pcie_ag.blob (30993 bytes) open success Sep 19 02:30:09 mirror kernel: [dhd] dhd_check_current_clm_data: ----- This FW is not included CLM data ----- Sep 19 02:30:10 mirror kernel: [dhd] dhd_check_current_clm_data: ----- This FW is included CLM data ----- Sep 19 02:30:10 mirror kernel: [dhd] Firmware up: op_mode=0x0005, MAC=b8:2d:28:42:2a:f4 Sep 19 02:30:10 mirror kernel: [dhd] dhd_legacy_preinit_ioctls: event_log_max_sets: 26 ret: 0 Sep 19 02:30:10 mirror kernel: [dhd] arp_enable:1 arp_ol:0 Sep 19 02:30:10 mirror kernel: [dhd] Driver: 101.10.361.20 (wlan=r892223-20220701-3) [dhd] Firmware: wl0: Jul 12 2022 18:54:54 version 18.35.387.23.146 (g412cc5ec) FWID 01-93c53be6 [dhd] CLM: 9.9.12_SS (2021-10-12 17:03:28) Sep 19 02:30:10 mirror kernel: [dhd] dhd_pno_init: Support Android Location Service Sep 19 02:30:10 mirror kernel: [dhd] dhd_rtt_init ENTRY Sep 19 02:30:10 mirror kernel: [dhd] rtt_do_get_ioctl: failed to send getbuf proxd iovar (CMD ID : 1), status=-23 Sep 19 02:30:10 mirror kernel: [dhd] dhd_rtt_init : FTM is not supported Sep 19 02:30:10 mirror kernel: [dhd] dhd_rtt_init EXIT, err = 0 Sep 19 02:30:10 mirror kernel: [dhd] [INIT] logset:8 is preserve/chatty Sep 19 02:30:10 mirror kernel: [dhd] [INIT] logset:10 is preserve/chatty Sep 19 02:30:10 mirror kernel: [dhd] dhd_ecounter_autoconfig Ecounter autoconfig in FW not supported Sep 19 02:30:10 mirror kernel: [dhd] dhd_rx_frame: net device is NOT registered. drop event packet Sep 19 02:30:10 mirror kernel: [dhd] dhd_conf_set_country : set country CN, revision 0 Sep 19 02:30:10 mirror kernel: [dhd] dhd_conf_set_country : Country code: CN (CN/0) Sep 19 02:30:10 mirror kernel: [dhd] dhd_bus_devreset: WLAN Power On Done Sep 19 02:30:10 mirror kernel: [dhd] [wlan0] wl_android_wifi_on : Success Sep 19 02:30:10 mirror kernel: [dhd] dhd_rx_frame: net device is NOT registered. drop event packet Sep 19 02:30:10 mirror kernel: [dhd] dhd_bus_aspm_enable_dev: RC ENABLE , linkctrl_before: 0x30110000 linkctrl_after: 0x30110002 Sep 19 02:30:10 mirror kernel: [dhd] dhd_bus_aspm_enable_dev: EP ENABLE , linkctrl_before: 0x10110040 linkctrl_after: 0x10110042 Sep 19 02:30:10 mirror kernel: [dhd] [wlan0] dhd_open : Exit ret=0 Sep 19 02:30:10 mirror kernel: [dhd] [wlan0] dhd_pri_open : tx queue started Sep 19 02:30:10 mirror kernel: [dhd] [wlan0] custom_xps_map_set : Done. mapping cpu Sep 19 02:30:10 mirror kernel: [dhd] dhd_pri_open : mutex is released. Sep 19 02:30:10 mirror kernel: rk_gmac-dwmac fe1c0000.ethernet eth0: PHY [stmmac-1:01] driver [YT8531 Gigabit Ethernet] (irq=POLL) Sep 19 02:30:10 mirror kernel: dwmac4: Master AXI performs any burst length Sep 19 02:30:10 mirror kernel: rk_gmac-dwmac fe1c0000.ethernet eth0: No Safety Features support found Sep 19 02:30:10 mirror kernel: rk_gmac-dwmac fe1c0000.ethernet eth0: IEEE 1588-2008 Advanced Timestamp supported Sep 19 02:30:10 mirror kernel: rk_gmac-dwmac fe1c0000.ethernet eth0: registered PTP clock Sep 19 02:30:10 mirror kernel: rk_gmac-dwmac fe1c0000.ethernet eth0: configuring for phy/rgmii-rxid link mode Sep 19 02:30:10 mirror systemd-networkd[616]: wlan0: Link UP Sep 19 02:30:10 mirror systemd[1]: Started Network Name Resolution. ░░ Subject: A start job for unit systemd-resolved.service has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit systemd-resolved.service has finished successfully. ░░ ░░ The job identifier is 111. Sep 19 02:30:10 mirror systemd[1]: Reached target Host and Network Name Lookups. ░░ Subject: A start job for unit nss-lookup.target has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit nss-lookup.target has finished successfully. ░░ ░░ The job identifier is 112. Sep 19 02:30:10 mirror systemd-networkd[616]: wlan0: Gained carrier Sep 19 02:30:10 mirror systemd-networkd[616]: eth0: Link UP Sep 19 02:30:12 mirror systemd-networkd[616]: wlan0: Gained IPv6LL Sep 19 02:30:12 mirror systemd-timesyncd[591]: Network configuration changed, trying to establish connection. Sep 19 02:30:13 mirror systemd[1]: systemd-rfkill.service: Deactivated successfully. ░░ Subject: Unit succeeded ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ The unit systemd-rfkill.service has successfully entered the 'dead' state. Sep 19 02:30:38 mirror systemd[1]: systemd-fsckd.service: Deactivated successfully. ░░ Subject: Unit succeeded ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ The unit systemd-fsckd.service has successfully entered the 'dead' state. Sep 19 02:32:09 mirror systemd-networkd-wait-online[652]: Timeout occurred while waiting for network connectivity. Sep 19 02:32:09 mirror systemd[1]: systemd-networkd-wait-online.service: Main process exited, code=exited, status=1/FAILURE ░░ Subject: Unit process exited ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ An ExecStart= process belonging to unit systemd-networkd-wait-online.service has exited. ░░ ░░ The process' exit code is 'exited' and its exit status is 1. Sep 19 02:32:09 mirror systemd[1]: systemd-networkd-wait-online.service: Failed with result 'exit-code'. ░░ Subject: Unit failed ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ The unit systemd-networkd-wait-online.service has entered the 'failed' state with result 'exit-code'. Sep 19 02:32:09 mirror systemd[1]: Failed to start Wait for Network to be Configured. ░░ Subject: A start job for unit systemd-networkd-wait-online.service has failed ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit systemd-networkd-wait-online.service has finished with a failure. ░░ ░░ The job identifier is 72 and the job result is failed. Sep 19 02:32:09 mirror systemd[1]: Starting Initial cloud-init job (metadata service crawler)... ░░ Subject: A start job for unit cloud-init.service has begun execution ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit cloud-init.service has begun execution. ░░ ░░ The job identifier is 158. Sep 19 02:32:09 mirror cloud-init[669]: Cloud-init v. 23.2.2-0ubuntu0~22.04.1 running 'init' at Tue, 19 Sep 2023 02:32:09 +0000. Up 129.84 seconds. Sep 19 02:32:09 mirror cloud-init[669]: ci-info: +++++++++++++++++++++++++++++++++++++Net device info+++++++++++++++++++++++++++++++++++++ Sep 19 02:32:09 mirror cloud-init[669]: ci-info: +--------+-------+------------------------------+-----------+-------+-------------------+ Sep 19 02:32:09 mirror cloud-init[669]: ci-info: | Device | Up | Address | Mask | Scope | Hw-Address | Sep 19 02:32:09 mirror cloud-init[669]: ci-info: +--------+-------+------------------------------+-----------+-------+-------------------+ Sep 19 02:32:09 mirror cloud-init[669]: ci-info: | eth0 | False | . | . | . | 7e:6c:be:90:fb:12 | Sep 19 02:32:09 mirror cloud-init[669]: ci-info: | lo | True | 127.0.0.1 | 255.0.0.0 | host | . | Sep 19 02:32:09 mirror cloud-init[669]: ci-info: | lo | True | ::1/128 | . | host | . | Sep 19 02:32:09 mirror cloud-init[669]: ci-info: | wlan0 | True | fe80::ba2d:28ff:fe42:2af4/64 | . | link | b8:2d:28:42:2a:f4 | Sep 19 02:32:09 mirror cloud-init[669]: ci-info: +--------+-------+------------------------------+-----------+-------+-------------------+ Sep 19 02:32:09 mirror cloud-init[669]: ci-info: +++++++++++++++++++Route IPv6 info+++++++++++++++++++ Sep 19 02:32:09 mirror cloud-init[669]: ci-info: +-------+-------------+---------+-----------+-------+ Sep 19 02:32:09 mirror cloud-init[669]: ci-info: | Route | Destination | Gateway | Interface | Flags | Sep 19 02:32:09 mirror cloud-init[669]: ci-info: +-------+-------------+---------+-----------+-------+ Sep 19 02:32:09 mirror cloud-init[669]: ci-info: | 1 | fe80::/64 | :: | wlan0 | U | Sep 19 02:32:09 mirror cloud-init[669]: ci-info: | 3 | local | :: | wlan0 | U | Sep 19 02:32:09 mirror cloud-init[669]: ci-info: | 4 | multicast | :: | wlan0 | U | Sep 19 02:32:09 mirror cloud-init[669]: ci-info: +-------+-------------+---------+-----------+-------+ Sep 19 02:32:10 mirror cloud-init[669]: 2023-09-19 02:32:10,023 - schema.py[DEPRECATED]: Deprecated cloud-config provided: Sep 19 02:32:10 mirror cloud-init[669]: chpasswd.list: List of ``username:password`` pairs. Each user will have the corresponding password set. A password can be randomly generated by specifying ``RANDOM`` or ``R`` as a user's password. A hashed password, created by a tool like ``mkpasswd``, can be specified. A regex (``r'\$(1|2a|2y|5|6)(\$.+){2}'``) is used to determine if a password value should be treated as a hash. Deprecated in version 22.2. Use ``users`` instead. Sep 19 02:32:10 mirror systemd[1]: Finished Initial cloud-init job (metadata service crawler). ░░ Subject: A start job for unit cloud-init.service has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit cloud-init.service has finished successfully. ░░ ░░ The job identifier is 158. Sep 19 02:32:10 mirror systemd[1]: Reached target Cloud-config availability. ░░ Subject: A start job for unit cloud-config.target has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit cloud-config.target has finished successfully. ░░ ░░ The job identifier is 157. Sep 19 02:32:10 mirror systemd[1]: Reached target System Initialization. ░░ Subject: A start job for unit sysinit.target has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit sysinit.target has finished successfully. ░░ ░░ The job identifier is 4. Sep 19 02:32:10 mirror systemd[1]: Condition check resulted in Process error reports when automatic reporting is enabled (file watch) being skipped. ░░ Subject: A start job for unit apport-autoreport.path has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit apport-autoreport.path has finished successfully. ░░ ░░ The job identifier is 109. Sep 19 02:32:10 mirror systemd[1]: Condition check resulted in Process error reports when automatic reporting is enabled (timer based) being skipped. ░░ Subject: A start job for unit apport-autoreport.timer has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit apport-autoreport.timer has finished successfully. ░░ ░░ The job identifier is 89. Sep 19 02:32:10 mirror systemd[1]: Started Daily apt download activities. ░░ Subject: A start job for unit apt-daily.timer has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit apt-daily.timer has finished successfully. ░░ ░░ The job identifier is 88. Sep 19 02:32:10 mirror systemd[1]: Started Daily apt upgrade and clean activities. ░░ Subject: A start job for unit apt-daily-upgrade.timer has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit apt-daily-upgrade.timer has finished successfully. ░░ ░░ The job identifier is 84. Sep 19 02:32:10 mirror systemd[1]: Started Daily atop restart. ░░ Subject: A start job for unit atop-rotate.timer has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit atop-rotate.timer has finished successfully. ░░ ░░ The job identifier is 91. Sep 19 02:32:10 mirror systemd[1]: Started Daily dpkg database backup timer. ░░ Subject: A start job for unit dpkg-db-backup.timer has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit dpkg-db-backup.timer has finished successfully. ░░ ░░ The job identifier is 96. Sep 19 02:32:10 mirror systemd[1]: Started Periodic ext4 Online Metadata Check for All Filesystems. ░░ Subject: A start job for unit e2scrub_all.timer has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit e2scrub_all.timer has finished successfully. ░░ ░░ The job identifier is 94. Sep 19 02:32:10 mirror systemd[1]: Started Discard unused blocks once a week. ░░ Subject: A start job for unit fstrim.timer has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit fstrim.timer has finished successfully. ░░ ░░ The job identifier is 93. Sep 19 02:32:10 mirror systemd[1]: Started Refresh fwupd metadata regularly. ░░ Subject: A start job for unit fwupd-refresh.timer has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit fwupd-refresh.timer has finished successfully. ░░ ░░ The job identifier is 98. Sep 19 02:32:10 mirror systemd[1]: Started Daily rotation of log files. ░░ Subject: A start job for unit logrotate.timer has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit logrotate.timer has finished successfully. ░░ ░░ The job identifier is 85. Sep 19 02:32:10 mirror systemd[1]: Started Daily man-db regeneration. ░░ Subject: A start job for unit man-db.timer has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit man-db.timer has finished successfully. ░░ ░░ The job identifier is 92. Sep 19 02:32:10 mirror systemd[1]: Started Message of the Day. ░░ Subject: A start job for unit motd-news.timer has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit motd-news.timer has finished successfully. ░░ ░░ The job identifier is 97. Sep 19 02:32:10 mirror systemd[1]: Condition check resulted in Timer to automatically fetch and run repair assertions being skipped. ░░ Subject: A start job for unit snapd.snap-repair.timer has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit snapd.snap-repair.timer has finished successfully. ░░ ░░ The job identifier is 95. Sep 19 02:32:10 mirror systemd[1]: Started Daily Cleanup of Temporary Directories. ░░ Subject: A start job for unit systemd-tmpfiles-clean.timer has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit systemd-tmpfiles-clean.timer has finished successfully. ░░ ░░ The job identifier is 90. Sep 19 02:32:10 mirror systemd[1]: Condition check resulted in Ubuntu Pro Timer for running repeated jobs being skipped. ░░ Subject: A start job for unit ua-timer.timer has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit ua-timer.timer has finished successfully. ░░ ░░ The job identifier is 99. Sep 19 02:32:10 mirror systemd[1]: Reached target Path Units. ░░ Subject: A start job for unit paths.target has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit paths.target has finished successfully. ░░ ░░ The job identifier is 108. Sep 19 02:32:10 mirror systemd[1]: Condition check resulted in Unix socket for apport crash forwarding being skipped. ░░ Subject: A start job for unit apport-forward.socket has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit apport-forward.socket has finished successfully. ░░ ░░ The job identifier is 101. Sep 19 02:32:10 mirror systemd[1]: Listening on cloud-init hotplug hook socket. ░░ Subject: A start job for unit cloud-init-hotplugd.socket has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit cloud-init-hotplugd.socket has finished successfully. ░░ ░░ The job identifier is 154. Sep 19 02:32:10 mirror systemd[1]: Listening on D-Bus System Message Bus Socket. ░░ Subject: A start job for unit dbus.socket has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit dbus.socket has finished successfully. ░░ ░░ The job identifier is 106. Sep 19 02:32:10 mirror systemd[1]: Listening on Open-iSCSI iscsid Socket. ░░ Subject: A start job for unit iscsid.socket has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit iscsid.socket has finished successfully. ░░ ░░ The job identifier is 103. Sep 19 02:32:10 mirror systemd[1]: Starting Socket activation for snappy daemon... ░░ Subject: A start job for unit snapd.socket has begun execution ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit snapd.socket has begun execution. ░░ ░░ The job identifier is 102. Sep 19 02:32:10 mirror systemd[1]: Listening on Socket activation for snappy daemon. ░░ Subject: A start job for unit snapd.socket has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit snapd.socket has finished successfully. ░░ ░░ The job identifier is 102. Sep 19 02:32:10 mirror systemd[1]: Reached target Socket Units. ░░ Subject: A start job for unit sockets.target has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit sockets.target has finished successfully. ░░ ░░ The job identifier is 100. Sep 19 02:32:10 mirror systemd[1]: Reached target Basic System. ░░ Subject: A start job for unit basic.target has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit basic.target has finished successfully. ░░ ░░ The job identifier is 3. Sep 19 02:32:10 mirror systemd[1]: System is tainted: cgroupsv1 ░░ Subject: The system is configured in a way that might cause problems ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ The following "tags" are possible: ░░ - "split-usr" — /usr is a separate file system and was not mounted when systemd ░░ was booted ░░ - "cgroups-missing" — the kernel was compiled without cgroup support or access ░░ to expected interface files is restricted ░░ - "var-run-bad" — /var/run is not a symlink to /run ░░ - "overflowuid-not-65534" — the kernel user ID used for "unknown" users (with ░░ NFS or user namespaces) is not 65534 ░░ - "overflowgid-not-65534" — the kernel group ID used for "unknown" users (with ░░ NFS or user namespaces) is not 65534 ░░ Current system is tagged as cgroupsv1. Sep 19 02:32:10 mirror systemd[1]: Starting Bluetooth AP6275P... ░░ Subject: A start job for unit ap6275p-bluetooth.service has begun execution ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit ap6275p-bluetooth.service has begun execution. ░░ ░░ The job identifier is 136. Sep 19 02:32:10 mirror systemd[1]: Starting Atop process accounting daemon... ░░ Subject: A start job for unit atopacct.service has begun execution ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit atopacct.service has begun execution. ░░ ░░ The job identifier is 138. Sep 19 02:32:10 mirror systemd[1]: Starting Set CPU governor to performance... ░░ Subject: A start job for unit cpu-governor-performance.service has begun execution ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit cpu-governor-performance.service has begun execution. ░░ ░░ The job identifier is 174. Sep 19 02:32:10 mirror atopacctd[701]: Version: 2.7.1 - 2022/01/08 12:48:36 Sep 19 02:32:10 mirror atopacctd[701]: accounting to /run/pacct_source Sep 19 02:32:10 mirror kernel: Process accounting resumed Sep 19 02:32:10 mirror systemd[1]: Started D-Bus System Message Bus. ░░ Subject: A start job for unit dbus.service has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit dbus.service has finished successfully. ░░ ░░ The job identifier is 147. Sep 19 02:32:10 mirror systemd[1]: Started Save initial kernel messages after boot. ░░ Subject: A start job for unit dmesg.service has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit dmesg.service has finished successfully. ░░ ░░ The job identifier is 122. Sep 19 02:32:10 mirror systemd[1]: Starting Remove Stale Online ext4 Metadata Check Snapshots... ░░ Subject: A start job for unit e2scrub_reap.service has begun execution ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit e2scrub_reap.service has begun execution. ░░ ░░ The job identifier is 119. Sep 19 02:32:10 mirror systemd[1]: Starting Enable the USB 2.0 port... ░░ Subject: A start job for unit enable-usb2.service has begun execution ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit enable-usb2.service has begun execution. ░░ ░░ The job identifier is 177. Sep 19 02:32:10 mirror systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. ░░ Subject: A start job for unit getty-static.service has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit getty-static.service has finished successfully. ░░ ░░ The job identifier is 169. Sep 19 02:32:10 mirror systemd[1]: Starting Set GPU governor to performance... ░░ Subject: A start job for unit gpu-governor-performance.service has begun execution ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit gpu-governor-performance.service has begun execution. ░░ ░░ The job identifier is 175. Sep 19 02:32:10 mirror systemd[1]: Starting Initialize hardware monitoring sensors... ░░ Subject: A start job for unit lm-sensors.service has begun execution ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit lm-sensors.service has begun execution. ░░ ░░ The job identifier is 170. Sep 19 02:32:10 mirror systemd[1]: Starting Dispatcher daemon for systemd-networkd... ░░ Subject: A start job for unit networkd-dispatcher.service has begun execution ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit networkd-dispatcher.service has begun execution. ░░ ░░ The job identifier is 146. Sep 19 02:32:10 mirror systemd[1]: Starting Authorization Manager... ░░ Subject: A start job for unit polkit.service has begun execution ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit polkit.service has begun execution. ░░ ░░ The job identifier is 172. Sep 19 02:32:10 mirror systemd[1]: Starting System Logging Service... ░░ Subject: A start job for unit rsyslog.service has begun execution ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit rsyslog.service has begun execution. ░░ ░░ The job identifier is 130. Sep 19 02:32:10 mirror systemd[1]: Condition check resulted in Secure Boot updates for DB and DBX being skipped. ░░ Subject: A start job for unit secureboot-db.service has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit secureboot-db.service has finished successfully. ░░ ░░ The job identifier is 129. Sep 19 02:32:10 mirror systemd[1]: Condition check resulted in Userspace listener for prompt events being skipped. ░░ Subject: A start job for unit snapd.aa-prompt-listener.service has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit snapd.aa-prompt-listener.service has finished successfully. ░░ ░░ The job identifier is 134. Sep 19 02:32:10 mirror systemd[1]: Condition check resulted in Automatically repair incorrect owner/permissions on core devices being skipped. ░░ Subject: A start job for unit snapd.core-fixup.service has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit snapd.core-fixup.service has finished successfully. ░░ ░░ The job identifier is 135. Sep 19 02:32:10 mirror systemd[1]: Condition check resulted in Wait for the Ubuntu Core chooser trigger being skipped. ░░ Subject: A start job for unit snapd.recovery-chooser-trigger.service has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit snapd.recovery-chooser-trigger.service has finished successfully. ░░ ░░ The job identifier is 117. Sep 19 02:32:10 mirror systemd[1]: Reached target Preparation for Logins. ░░ Subject: A start job for unit getty-pre.target has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit getty-pre.target has finished successfully. ░░ ░░ The job identifier is 118. Sep 19 02:32:10 mirror systemd[1]: Starting Snap Daemon... ░░ Subject: A start job for unit snapd.service has begun execution ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit snapd.service has begun execution. ░░ ░░ The job identifier is 114. Sep 19 02:32:10 mirror systemd[1]: Starting User Login Management... ░░ Subject: A start job for unit systemd-logind.service has begun execution ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit systemd-logind.service has begun execution. ░░ ░░ The job identifier is 141. Sep 19 02:32:10 mirror systemd[1]: Condition check resulted in Ubuntu Pro reboot cmds being skipped. ░░ Subject: A start job for unit ua-reboot-cmds.service has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit ua-reboot-cmds.service has finished successfully. ░░ ░░ The job identifier is 150. Sep 19 02:32:10 mirror systemd[1]: Starting Disk Manager... ░░ Subject: A start job for unit udisks2.service has begun execution ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit udisks2.service has begun execution. ░░ ░░ The job identifier is 176. Sep 19 02:32:10 mirror systemd[1]: Starting WPA supplicant... ░░ Subject: A start job for unit wpa_supplicant.service has begun execution ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit wpa_supplicant.service has begun execution. ░░ ░░ The job identifier is 145. Sep 19 02:32:10 mirror polkitd[712]: started daemon version 0.105 using authority implementation `local' version `0.105' Sep 19 02:32:10 mirror systemd[1]: Started Atop process accounting daemon. ░░ Subject: A start job for unit atopacct.service has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit atopacct.service has finished successfully. ░░ ░░ The job identifier is 138. Sep 19 02:32:10 mirror systemd[1]: enable-usb2.service: Deactivated successfully. ░░ Subject: Unit succeeded ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ The unit enable-usb2.service has successfully entered the 'dead' state. Sep 19 02:32:10 mirror systemd[1]: Finished Enable the USB 2.0 port. ░░ Subject: A start job for unit enable-usb2.service has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit enable-usb2.service has finished successfully. ░░ ░░ The job identifier is 177. Sep 19 02:32:10 mirror wpa_supplicant[720]: Successfully initialized wpa_supplicant Sep 19 02:32:10 mirror systemd[1]: gpu-governor-performance.service: Deactivated successfully. ░░ Subject: Unit succeeded ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ The unit gpu-governor-performance.service has successfully entered the 'dead' state. Sep 19 02:32:10 mirror systemd[1]: Finished Set GPU governor to performance. ░░ Subject: A start job for unit gpu-governor-performance.service has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit gpu-governor-performance.service has finished successfully. ░░ ░░ The job identifier is 175. Sep 19 02:32:10 mirror systemd[1]: e2scrub_reap.service: Deactivated successfully. ░░ Subject: Unit succeeded ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ The unit e2scrub_reap.service has successfully entered the 'dead' state. Sep 19 02:32:10 mirror systemd[1]: Finished Remove Stale Online ext4 Metadata Check Snapshots. ░░ Subject: A start job for unit e2scrub_reap.service has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit e2scrub_reap.service has finished successfully. ░░ ░░ The job identifier is 119. Sep 19 02:32:10 mirror rsyslogd[713]: imuxsock: Acquired UNIX socket '/run/systemd/journal/syslog' (fd 3) from systemd. [v8.2112.0] Sep 19 02:32:10 mirror rsyslogd[713]: rsyslogd's groupid changed to 111 Sep 19 02:32:10 mirror rsyslogd[713]: rsyslogd's userid changed to 104 Sep 19 02:32:10 mirror rsyslogd[713]: [origin software="rsyslogd" swVersion="8.2112.0" x-pid="713" x-info="https://www.rsyslog.com"] start Sep 19 02:32:10 mirror systemd[1]: Starting Atop advanced performance monitor... ░░ Subject: A start job for unit atop.service has begun execution ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit atop.service has begun execution. ░░ ░░ The job identifier is 148. Sep 19 02:32:10 mirror systemd[1]: Started System Logging Service. ░░ Subject: A start job for unit rsyslog.service has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit rsyslog.service has finished successfully. ░░ ░░ The job identifier is 130. Sep 19 02:32:10 mirror sensors[724]: npu_thermal-virtual-0 Sep 19 02:32:10 mirror sensors[724]: Adapter: Virtual device Sep 19 02:32:10 mirror sensors[724]: temp1: +53.6°C Sep 19 02:32:10 mirror sensors[724]: center_thermal-virtual-0 Sep 19 02:32:10 mirror sensors[724]: Adapter: Virtual device Sep 19 02:32:10 mirror sensors[724]: temp1: +54.5°C Sep 19 02:32:10 mirror sensors[724]: bigcore1_thermal-virtual-0 Sep 19 02:32:10 mirror sensors[724]: Adapter: Virtual device Sep 19 02:32:10 mirror sensors[724]: temp1: +56.4°C Sep 19 02:32:10 mirror sensors[724]: soc_thermal-virtual-0 Sep 19 02:32:10 mirror sensors[724]: Adapter: Virtual device Sep 19 02:32:10 mirror sensors[724]: temp1: +55.5°C (crit = +115.0°C) Sep 19 02:32:10 mirror sensors[724]: tcpm_source_psy_6_0022-i2c-6-22 Sep 19 02:32:10 mirror sensors[724]: Adapter: rk3x-i2c Sep 19 02:32:10 mirror sensors[724]: in0: 5.00 V (min = +5.00 V, max = +5.00 V) Sep 19 02:32:10 mirror sensors[724]: curr1: 3.00 A (max = +3.00 A) Sep 19 02:32:10 mirror sensors[724]: gpu_thermal-virtual-0 Sep 19 02:32:10 mirror sensors[724]: Adapter: Virtual device Sep 19 02:32:10 mirror sensors[724]: temp1: +53.6°C Sep 19 02:32:10 mirror sensors[724]: littlecore_thermal-virtual-0 Sep 19 02:32:10 mirror sensors[724]: Adapter: Virtual device Sep 19 02:32:10 mirror sensors[724]: temp1: +56.4°C Sep 19 02:32:10 mirror sensors[724]: bigcore0_thermal-virtual-0 Sep 19 02:32:10 mirror sensors[724]: Adapter: Virtual device Sep 19 02:32:10 mirror sensors[724]: temp1: +56.4°C Sep 19 02:32:10 mirror systemd[1]: Finished Initialize hardware monitoring sensors. ░░ Subject: A start job for unit lm-sensors.service has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit lm-sensors.service has finished successfully. ░░ ░░ The job identifier is 170. Sep 19 02:32:10 mirror systemd[1]: Started WPA supplicant. ░░ Subject: A start job for unit wpa_supplicant.service has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit wpa_supplicant.service has finished successfully. ░░ ░░ The job identifier is 145. Sep 19 02:32:10 mirror udisksd[719]: udisks daemon version 2.9.4 starting Sep 19 02:32:10 mirror systemd[1]: Started Authorization Manager. ░░ Subject: A start job for unit polkit.service has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit polkit.service has finished successfully. ░░ ░░ The job identifier is 172. Sep 19 02:32:10 mirror systemd[1]: Reached target Network. ░░ Subject: A start job for unit network.target has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit network.target has finished successfully. ░░ ░░ The job identifier is 75. Sep 19 02:32:10 mirror systemd[1]: Reached target Network is Online. ░░ Subject: A start job for unit network-online.target has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit network-online.target has finished successfully. ░░ ░░ The job identifier is 71. Sep 19 02:32:10 mirror systemd[1]: Started Download data for packages that failed at package install time. ░░ Subject: A start job for unit update-notifier-download.timer has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit update-notifier-download.timer has finished successfully. ░░ ░░ The job identifier is 87. Sep 19 02:32:10 mirror systemd[1]: Started Check to see whether there is a new version of Ubuntu available. ░░ Subject: A start job for unit update-notifier-motd.timer has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit update-notifier-motd.timer has finished successfully. ░░ ░░ The job identifier is 86. Sep 19 02:32:10 mirror systemd[1]: Reached target Timer Units. ░░ Subject: A start job for unit timers.target has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit timers.target has finished successfully. ░░ ░░ The job identifier is 83. Sep 19 02:32:10 mirror systemd[1]: Starting Modem Manager... ░░ Subject: A start job for unit ModemManager.service has begun execution ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit ModemManager.service has begun execution. ░░ ░░ The job identifier is 171. Sep 19 02:32:10 mirror systemd[1]: Condition check resulted in Login to default iSCSI targets being skipped. ░░ Subject: A start job for unit open-iscsi.service has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit open-iscsi.service has finished successfully. ░░ ░░ The job identifier is 70. Sep 19 02:32:10 mirror systemd[1]: Reached target Preparation for Remote File Systems. ░░ Subject: A start job for unit remote-fs-pre.target has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit remote-fs-pre.target has finished successfully. ░░ ░░ The job identifier is 81. Sep 19 02:32:10 mirror kernel: xhci-hcd xhci-hcd.9.auto: xHCI Host Controller Sep 19 02:32:10 mirror kernel: xhci-hcd xhci-hcd.9.auto: new USB bus registered, assigned bus number 7 Sep 19 02:32:10 mirror kernel: xhci-hcd xhci-hcd.9.auto: hcc params 0x0220fe64 hci version 0x110 quirks 0x0000202002010010 Sep 19 02:32:10 mirror kernel: xhci-hcd xhci-hcd.9.auto: irq 132, io mem 0xfc000000 Sep 19 02:32:10 mirror kernel: usb usb7: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.10 Sep 19 02:32:10 mirror kernel: usb usb7: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Sep 19 02:32:10 mirror kernel: usb usb7: Product: xHCI Host Controller Sep 19 02:32:10 mirror kernel: usb usb7: Manufacturer: Linux 5.10.160-rockchip xhci-hcd Sep 19 02:32:10 mirror kernel: usb usb7: SerialNumber: xhci-hcd.9.auto Sep 19 02:32:10 mirror kernel: hub 7-0:1.0: USB hub found Sep 19 02:32:10 mirror kernel: hub 7-0:1.0: 1 port detected Sep 19 02:32:10 mirror kernel: xhci-hcd xhci-hcd.9.auto: xHCI Host Controller Sep 19 02:32:10 mirror kernel: xhci-hcd xhci-hcd.9.auto: new USB bus registered, assigned bus number 8 Sep 19 02:32:10 mirror kernel: xhci-hcd xhci-hcd.9.auto: Host supports USB 3.0 SuperSpeed Sep 19 02:32:10 mirror kernel: usb usb8: We don't know the algorithms for LPM for this host, disabling LPM. Sep 19 02:32:10 mirror kernel: usb usb8: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 5.10 Sep 19 02:32:10 mirror kernel: usb usb8: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Sep 19 02:32:10 mirror kernel: usb usb8: Product: xHCI Host Controller Sep 19 02:32:10 mirror kernel: usb usb8: Manufacturer: Linux 5.10.160-rockchip xhci-hcd Sep 19 02:32:10 mirror kernel: usb usb8: SerialNumber: xhci-hcd.9.auto Sep 19 02:32:10 mirror kernel: hub 8-0:1.0: USB hub found Sep 19 02:32:10 mirror kernel: hub 8-0:1.0: 1 port detected Sep 19 02:32:10 mirror systemd[1]: Reached target Remote File Systems. ░░ Subject: A start job for unit remote-fs.target has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit remote-fs.target has finished successfully. ░░ ░░ The job identifier is 139. Sep 19 02:32:10 mirror systemd[1]: Starting LSB: automatic crash report generation... ░░ Subject: A start job for unit apport.service has begun execution ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit apport.service has begun execution. ░░ ░░ The job identifier is 151. Sep 19 02:32:10 mirror systemd[1]: Finished Availability of block devices. ░░ Subject: A start job for unit blk-availability.service has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit blk-availability.service has finished successfully. ░░ ░░ The job identifier is 20. Sep 19 02:32:10 mirror systemd[1]: Started Regular background program processing daemon. ░░ Subject: A start job for unit cron.service has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit cron.service has finished successfully. ░░ ░░ The job identifier is 126. Sep 19 02:32:10 mirror systemd-logind[718]: New seat seat0. ░░ Subject: A new seat seat0 is now available ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ Documentation: sd-login(3) ░░ ░░ A new seat seat0 has been configured and is now available. Sep 19 02:32:10 mirror cron[747]: (CRON) INFO (pidfile fd = 3) Sep 19 02:32:10 mirror systemd[1]: Starting LSB: Stop plymouth during boot and start it on shutdown... ░░ Subject: A start job for unit plymouth.service has begun execution ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit plymouth.service has begun execution. ░░ ░░ The job identifier is 173. Sep 19 02:32:10 mirror systemd-logind[718]: Watching system buttons on /dev/input/event1 (SONiX USB Keyboard) Sep 19 02:32:10 mirror systemd-logind[718]: Watching system buttons on /dev/input/event3 (SONiX USB Keyboard System Control) Sep 19 02:32:10 mirror systemd-logind[718]: Watching system buttons on /dev/input/event0 (rk805 pwrkey) Sep 19 02:32:10 mirror cron[747]: (CRON) INFO (Running @reboot jobs) Sep 19 02:32:10 mirror systemd[1]: Starting Pollinate to seed the pseudo random number generator... ░░ Subject: A start job for unit pollinate.service has begun execution ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit pollinate.service has begun execution. ░░ ░░ The job identifier is 132. Sep 19 02:32:10 mirror systemd[1]: Starting /etc/rc.local Compatibility... ░░ Subject: A start job for unit rc-local.service has begun execution ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit rc-local.service has begun execution. ░░ ░░ The job identifier is 140. Sep 19 02:32:10 mirror udisksd[719]: failed to load module mdraid: libbd_mdraid.so.2: cannot open shared object file: No such file or directory Sep 19 02:32:10 mirror systemd[1]: Starting Permit User Sessions... ░░ Subject: A start job for unit systemd-user-sessions.service has begun execution ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit systemd-user-sessions.service has begun execution. ░░ ░░ The job identifier is 137. Sep 19 02:32:10 mirror systemd[1]: cpu-governor-performance.service: Deactivated successfully. ░░ Subject: Unit succeeded ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ The unit cpu-governor-performance.service has successfully entered the 'dead' state. Sep 19 02:32:10 mirror systemd[1]: Finished Set CPU governor to performance. ░░ Subject: A start job for unit cpu-governor-performance.service has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit cpu-governor-performance.service has finished successfully. ░░ ░░ The job identifier is 174. Sep 19 02:32:10 mirror systemd[1]: Started Atop advanced performance monitor. ░░ Subject: A start job for unit atop.service has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit atop.service has finished successfully. ░░ ░░ The job identifier is 148. Sep 19 02:32:10 mirror udisksd[719]: Failed to load the 'mdraid' libblockdev plugin Sep 19 02:32:10 mirror networkd-dispatcher[711]: No valid path found for iw Sep 19 02:32:10 mirror systemd[1]: Started LSB: Stop plymouth during boot and start it on shutdown. ░░ Subject: A start job for unit plymouth.service has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit plymouth.service has finished successfully. ░░ ░░ The job identifier is 173. Sep 19 02:32:10 mirror systemd[1]: Started /etc/rc.local Compatibility. ░░ Subject: A start job for unit rc-local.service has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit rc-local.service has finished successfully. ░░ ░░ The job identifier is 140. Sep 19 02:32:10 mirror systemd[1]: Finished Permit User Sessions. ░░ Subject: A start job for unit systemd-user-sessions.service has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit systemd-user-sessions.service has finished successfully. ░░ ░░ The job identifier is 137. Sep 19 02:32:10 mirror systemd[1]: Started User Login Management. ░░ Subject: A start job for unit systemd-logind.service has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit systemd-logind.service has finished successfully. ░░ ░░ The job identifier is 141. Sep 19 02:32:10 mirror systemd[1]: Started Serial Getty on ttyFIQ0. ░░ Subject: A start job for unit serial-getty@ttyFIQ0.service has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit serial-getty@ttyFIQ0.service has finished successfully. ░░ ░░ The job identifier is 162. Sep 19 02:32:10 mirror apport[745]: * Starting automatic crash report generation: apport Sep 19 02:32:10 mirror systemd[1]: Starting Set console scheme... ░░ Subject: A start job for unit setvtrgb.service has begun execution ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit setvtrgb.service has begun execution. ░░ ░░ The job identifier is 82. Sep 19 02:32:10 mirror ModemManager[742]: ModemManager (version 1.20.0) starting in system bus... Sep 19 02:32:10 mirror systemd[1]: Started Unattended Upgrades Shutdown. ░░ Subject: A start job for unit unattended-upgrades.service has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit unattended-upgrades.service has finished successfully. ░░ ░░ The job identifier is 128. Sep 19 02:32:10 mirror systemd[1]: Started Dispatcher daemon for systemd-networkd. ░░ Subject: A start job for unit networkd-dispatcher.service has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit networkd-dispatcher.service has finished successfully. ░░ ░░ The job identifier is 146. Sep 19 02:32:10 mirror apport[745]: ...done. Sep 19 02:32:10 mirror systemd[1]: Started LSB: automatic crash report generation. ░░ Subject: A start job for unit apport.service has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit apport.service has finished successfully. ░░ ░░ The job identifier is 151. Sep 19 02:32:10 mirror systemd[1]: Finished Set console scheme. ░░ Subject: A start job for unit setvtrgb.service has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit setvtrgb.service has finished successfully. ░░ ░░ The job identifier is 82. Sep 19 02:32:10 mirror systemd[1]: Created slice Slice /system/getty. ░░ Subject: A start job for unit system-getty.slice has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit system-getty.slice has finished successfully. ░░ ░░ The job identifier is 168. Sep 19 02:32:10 mirror systemd[1]: Started Getty on tty1. ░░ Subject: A start job for unit getty@tty1.service has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit getty@tty1.service has finished successfully. ░░ ░░ The job identifier is 167. Sep 19 02:32:10 mirror pollinate[808]: client sent challenge to [https://entropy.ubuntu.com/] Sep 19 02:32:10 mirror systemd[1]: Reached target Login Prompts. ░░ Subject: A start job for unit getty.target has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit getty.target has finished successfully. ░░ ░░ The job identifier is 161. Sep 19 02:32:10 mirror systemd[1]: Started Disk Manager. ░░ Subject: A start job for unit udisks2.service has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit udisks2.service has finished successfully. ░░ ░░ The job identifier is 176. Sep 19 02:32:10 mirror udisksd[719]: Acquired the name org.freedesktop.UDisks2 on the system message bus Sep 19 02:32:10 mirror snapd[715]: overlord.go:268: Acquiring state lock file Sep 19 02:32:10 mirror snapd[715]: overlord.go:273: Acquired state lock file Sep 19 02:32:10 mirror systemd[1]: Started Modem Manager. ░░ Subject: A start job for unit ModemManager.service has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit ModemManager.service has finished successfully. ░░ ░░ The job identifier is 171. Sep 19 02:32:10 mirror snapd[715]: daemon.go:247: started snapd/2.58+22.04.1 (series 16; classic; devmode) ubuntu/22.04 (arm64) linux/5.10.160-rockchip. Sep 19 02:32:10 mirror kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Sep 19 02:32:10 mirror systemd[1]: tmp-syscheck\x2dmountpoint\x2d2021072242.mount: Deactivated successfully. ░░ Subject: Unit succeeded ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ The unit tmp-syscheck\x2dmountpoint\x2d2021072242.mount has successfully entered the 'dead' state. Sep 19 02:32:10 mirror snapd[715]: daemon.go:340: adjusting startup timeout by 30s (pessimistic estimate of 30s plus 5s per snap) Sep 19 02:32:10 mirror snapd[715]: backends.go:58: AppArmor status: apparmor not enabled Sep 19 02:32:10 mirror pollinate[839]: [431B blob data] Sep 19 02:32:10 mirror systemd[1]: pollinate.service: Deactivated successfully. ░░ Subject: Unit succeeded ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ The unit pollinate.service has successfully entered the 'dead' state. Sep 19 02:32:10 mirror systemd[1]: Finished Pollinate to seed the pseudo random number generator. ░░ Subject: A start job for unit pollinate.service has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit pollinate.service has finished successfully. ░░ ░░ The job identifier is 132. Sep 19 02:32:10 mirror systemd[1]: Starting OpenBSD Secure Shell server... ░░ Subject: A start job for unit ssh.service has begun execution ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit ssh.service has begun execution. ░░ ░░ The job identifier is 149. Sep 19 02:32:10 mirror systemd[1]: Started Snap Daemon. ░░ Subject: A start job for unit snapd.service has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit snapd.service has finished successfully. ░░ ░░ The job identifier is 114. Sep 19 02:32:10 mirror systemd[1]: Starting Wait until snapd is fully seeded... ░░ Subject: A start job for unit snapd.seeded.service has begun execution ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit snapd.seeded.service has begun execution. ░░ ░░ The job identifier is 152. Sep 19 02:32:10 mirror dbus-daemon[703]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.12' (uid=0 pid=715 comm="/usr/lib/snapd/snapd ") Sep 19 02:32:10 mirror systemd[1]: Starting Time & Date Service... ░░ Subject: A start job for unit systemd-timedated.service has begun execution ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit systemd-timedated.service has begun execution. ░░ ░░ The job identifier is 238. Sep 19 02:32:10 mirror sshd[859]: Server listening on 0.0.0.0 port 22. Sep 19 02:32:10 mirror sshd[859]: Server listening on :: port 22. Sep 19 02:32:10 mirror systemd[1]: Started OpenBSD Secure Shell server. ░░ Subject: A start job for unit ssh.service has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit ssh.service has finished successfully. ░░ ░░ The job identifier is 149. Sep 19 02:32:10 mirror dbus-daemon[703]: [system] Successfully activated service 'org.freedesktop.timedate1' Sep 19 02:32:10 mirror systemd[1]: Started Time & Date Service. ░░ Subject: A start job for unit systemd-timedated.service has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit systemd-timedated.service has finished successfully. ░░ ░░ The job identifier is 238. Sep 19 02:32:10 mirror systemd[1]: Finished Wait until snapd is fully seeded. ░░ Subject: A start job for unit snapd.seeded.service has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit snapd.seeded.service has finished successfully. ░░ ░░ The job identifier is 152. Sep 19 02:32:10 mirror systemd[1]: Starting Apply the settings specified in cloud-config... ░░ Subject: A start job for unit cloud-config.service has begun execution ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit cloud-config.service has begun execution. ░░ ░░ The job identifier is 156. Sep 19 02:32:10 mirror systemd[1]: Condition check resulted in Auto import assertions from block devices being skipped. ░░ Subject: A start job for unit snapd.autoimport.service has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit snapd.autoimport.service has finished successfully. ░░ ░░ The job identifier is 125. Sep 19 02:32:11 mirror cloud-init[872]: Cloud-init v. 23.2.2-0ubuntu0~22.04.1 running 'modules:config' at Tue, 19 Sep 2023 02:32:11 +0000. Up 131.19 seconds. Sep 19 02:32:11 mirror systemd[1]: Finished Apply the settings specified in cloud-config. ░░ Subject: A start job for unit cloud-config.service has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit cloud-config.service has finished successfully. ░░ ░░ The job identifier is 156. Sep 19 02:32:11 mirror systemd[1]: Condition check resulted in Ubuntu Pro Background Auto Attach being skipped. ░░ Subject: A start job for unit ubuntu-advantage.service has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit ubuntu-advantage.service has finished successfully. ░░ ░░ The job identifier is 127. Sep 19 02:32:13 mirror ModemManager[742]: [base-manager] couldn't check support for device '/sys/devices/platform/fe1c0000.ethernet': not supported by any plugin Sep 19 02:32:15 mirror ap6275p-bluetooth.sh[873]: /usr/lib/scripts/ap6275p-bluetooth.sh: line 3: warning: command substitution: ignored null byte in input Sep 19 02:32:15 mirror ap6275p-bluetooth.sh[873]: /usr/lib/scripts/ap6275p-bluetooth.sh: line 4: warning: command substitution: ignored null byte in input Sep 19 02:32:15 mirror kernel: [BT_RFKILL]: ENABLE UART_RTS Sep 19 02:32:15 mirror kernel: ttyFIQ ttyFIQ0: tty_port_close_start: tty->count = 1 port count = 2 Sep 19 02:32:15 mirror systemd[1]: dmesg.service: Deactivated successfully. ░░ Subject: Unit succeeded ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ The unit dmesg.service has successfully entered the 'dead' state. Sep 19 02:32:15 mirror rfkill[876]: unblock set for all Sep 19 02:32:15 mirror systemd[1]: Started Bluetooth AP6275P. ░░ Subject: A start job for unit ap6275p-bluetooth.service has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit ap6275p-bluetooth.service has finished successfully. ░░ ░░ The job identifier is 136. Sep 19 02:32:15 mirror kernel: [BT_RFKILL]: DISABLE UART_RTS Sep 19 02:32:15 mirror kernel: [BT_RFKILL]: bt turn on power Sep 19 02:32:15 mirror systemd[1]: Reached target Multi-User System. ░░ Subject: A start job for unit multi-user.target has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit multi-user.target has finished successfully. ░░ ░░ The job identifier is 2. Sep 19 02:32:15 mirror ap6275p-bluetooth.sh[898]: ###AMPAK FW Auto detection patch version = [v1.1 20161117]### Sep 19 02:32:15 mirror ap6275p-bluetooth.sh[898]: FW folder path = /lib/firmware/ap6275p Sep 19 02:32:15 mirror systemd[1]: Reached target Graphical Interface. ░░ Subject: A start job for unit graphical.target has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit graphical.target has finished successfully. ░░ ░░ The job identifier is 1. Sep 19 02:32:15 mirror kernel: of_dma_request_slave_channel: dma-names property of node '/serial@febc0000' missing or empty Sep 19 02:32:15 mirror kernel: dw-apb-uart febc0000.serial: failed to request DMA, use interrupt mode Sep 19 02:32:15 mirror systemd[1]: Starting Execute cloud user/final scripts... ░░ Subject: A start job for unit cloud-final.service has begun execution ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit cloud-final.service has begun execution. ░░ ░░ The job identifier is 155. Sep 19 02:32:15 mirror systemd[1]: Starting Record Runlevel Change in UTMP... ░░ Subject: A start job for unit systemd-update-utmp-runlevel.service has begun execution ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit systemd-update-utmp-runlevel.service has begun execution. ░░ ░░ The job identifier is 123. Sep 19 02:32:15 mirror systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. ░░ Subject: Unit succeeded ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ The unit systemd-update-utmp-runlevel.service has successfully entered the 'dead' state. Sep 19 02:32:15 mirror systemd[1]: Finished Record Runlevel Change in UTMP. ░░ Subject: A start job for unit systemd-update-utmp-runlevel.service has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit systemd-update-utmp-runlevel.service has finished successfully. ░░ ░░ The job identifier is 123. Sep 19 02:32:15 mirror ap6275p-bluetooth.sh[898]: proc_resetchip id = BCM4362A2 Sep 19 02:32:15 mirror ap6275p-bluetooth.sh[898]: FW path = /lib/firmware/ap6275p/BCM4362A2.hcd Sep 19 02:32:15 mirror cloud-init[906]: Cloud-init v. 23.2.2-0ubuntu0~22.04.1 running 'modules:final' at Tue, 19 Sep 2023 02:32:15 +0000. Up 135.82 seconds. Sep 19 02:32:15 mirror cloud-init[906]: Cloud-init v. 23.2.2-0ubuntu0~22.04.1 finished at Tue, 19 Sep 2023 02:32:15 +0000. Datasource DataSourceNoCloud [seed=/dev/mmcblk0p1][dsmode=net]. Up 135.93 seconds Sep 19 02:32:16 mirror systemd[1]: Finished Execute cloud user/final scripts. ░░ Subject: A start job for unit cloud-final.service has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit cloud-final.service has finished successfully. ░░ ░░ The job identifier is 155. Sep 19 02:32:16 mirror systemd[1]: Reached target Cloud-init target. ░░ Subject: A start job for unit cloud-init.target has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit cloud-init.target has finished successfully. ░░ ░░ The job identifier is 153. Sep 19 02:32:16 mirror systemd[1]: Startup finished in 6.302s (kernel) + 2min 9.716s (userspace) = 2min 16.019s. ░░ Subject: System start-up is now complete ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ All system services necessary queued for starting at boot have been ░░ started. Note that this does not mean that the machine is now idle as services ░░ might still be busy with completing start-up. ░░ ░░ Kernel start-up required 6302490 microseconds. ░░ ░░ Initial RAM disk start-up required INITRD_USEC microseconds. ░░ ░░ Userspace start-up required 129716690 microseconds. Sep 19 02:32:17 mirror kernel: Bluetooth: HCI UART driver ver 2.3 Sep 19 02:32:17 mirror kernel: Bluetooth: HCI UART protocol H4 registered Sep 19 02:32:17 mirror kernel: Bluetooth: HCI UART protocol BCSP registered Sep 19 02:32:17 mirror kernel: Bluetooth: HCI UART protocol ATH3K registered Sep 19 02:32:17 mirror kernel: Bluetooth: HCI UART protocol Three-wire (H5) registered Sep 19 02:32:17 mirror kernel: Bluetooth: HCI UART protocol Intel registered Sep 19 02:32:17 mirror kernel: Bluetooth: HCI UART protocol AG6XX registered Sep 19 02:32:17 mirror systemd[1]: Starting Bluetooth service... ░░ Subject: A start job for unit bluetooth.service has begun execution ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit bluetooth.service has begun execution. ░░ ░░ The job identifier is 336. Sep 19 02:32:17 mirror ap6275p-bluetooth.sh[898]: send hci_download_minidriverproc_resetDone setting line discpline Sep 19 02:32:17 mirror systemd[1]: Starting Load/Save RF Kill Switch Status... ░░ Subject: A start job for unit systemd-rfkill.service has begun execution ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit systemd-rfkill.service has begun execution. ░░ ░░ The job identifier is 327. Sep 19 02:32:17 mirror systemd[1]: Started Load/Save RF Kill Switch Status. ░░ Subject: A start job for unit systemd-rfkill.service has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit systemd-rfkill.service has finished successfully. ░░ ░░ The job identifier is 327. Sep 19 02:32:17 mirror bluetoothd[912]: Bluetooth daemon 5.64 Sep 19 02:32:17 mirror systemd[1]: Started Bluetooth service. ░░ Subject: A start job for unit bluetooth.service has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit bluetooth.service has finished successfully. ░░ ░░ The job identifier is 336. Sep 19 02:32:17 mirror systemd[1]: Reached target Bluetooth Support. ░░ Subject: A start job for unit bluetooth.target has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit bluetooth.target has finished successfully. ░░ ░░ The job identifier is 335. Sep 19 02:32:17 mirror bluetoothd[912]: Starting SDP server Sep 19 02:32:17 mirror kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Sep 19 02:32:17 mirror kernel: Bluetooth: BNEP filters: protocol multicast Sep 19 02:32:17 mirror kernel: Bluetooth: BNEP socket layer initialized Sep 19 02:32:17 mirror bluetoothd[912]: Bluetooth management interface 1.18 initialized Sep 19 02:32:17 mirror dbus-daemon[703]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.14' (uid=0 pid=912 comm="/usr/lib/bluetooth/bluetoothd ") Sep 19 02:32:17 mirror systemd[1]: Starting Hostname Service... ░░ Subject: A start job for unit systemd-hostnamed.service has begun execution ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit systemd-hostnamed.service has begun execution. ░░ ░░ The job identifier is 425. Sep 19 02:32:17 mirror dbus-daemon[703]: [system] Successfully activated service 'org.freedesktop.hostname1' Sep 19 02:32:17 mirror systemd[1]: Started Hostname Service. ░░ Subject: A start job for unit systemd-hostnamed.service has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit systemd-hostnamed.service has finished successfully. ░░ ░░ The job identifier is 425. Sep 19 02:32:20 mirror login[807]: pam_unix(login:session): session opened for user ubuntu(uid=1000) by LOGIN(uid=0) Sep 19 02:32:20 mirror systemd[1]: Created slice User Slice of UID 1000. ░░ Subject: A start job for unit user-1000.slice has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit user-1000.slice has finished successfully. ░░ ░░ The job identifier is 515. Sep 19 02:32:20 mirror systemd[1]: Starting User Runtime Directory /run/user/1000... ░░ Subject: A start job for unit user-runtime-dir@1000.service has begun execution ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit user-runtime-dir@1000.service has begun execution. ░░ ░░ The job identifier is 519. Sep 19 02:32:20 mirror systemd-logind[718]: New session 1 of user ubuntu. ░░ Subject: A new session 1 has been created for user ubuntu ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ Documentation: sd-login(3) ░░ ░░ A new session with the ID 1 has been created for the user ubuntu. ░░ ░░ The leading process of the session is 807. Sep 19 02:32:20 mirror systemd[1]: Finished User Runtime Directory /run/user/1000. ░░ Subject: A start job for unit user-runtime-dir@1000.service has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit user-runtime-dir@1000.service has finished successfully. ░░ ░░ The job identifier is 519. Sep 19 02:32:20 mirror systemd[1]: Starting User Manager for UID 1000... ░░ Subject: A start job for unit user@1000.service has begun execution ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit user@1000.service has begun execution. ░░ ░░ The job identifier is 514. Sep 19 02:32:20 mirror systemd[993]: pam_unix(systemd-user:session): session opened for user ubuntu(uid=1000) by (uid=0) Sep 19 02:32:20 mirror systemd[993]: Queued start job for default target Main User Target. Sep 19 02:32:20 mirror systemd[993]: Created slice User Application Slice. ░░ Subject: A start job for unit UNIT has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit UNIT has finished successfully. ░░ ░░ The job identifier is 15. Sep 19 02:32:20 mirror systemd[993]: Reached target Paths. ░░ Subject: A start job for unit UNIT has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit UNIT has finished successfully. ░░ ░░ The job identifier is 24. Sep 19 02:32:20 mirror systemd[993]: Reached target Timers. ░░ Subject: A start job for unit UNIT has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit UNIT has finished successfully. ░░ ░░ The job identifier is 11. Sep 19 02:32:20 mirror systemd[993]: Starting D-Bus User Message Bus Socket... ░░ Subject: A start job for unit UNIT has begun execution ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit UNIT has begun execution. ░░ ░░ The job identifier is 20. Sep 19 02:32:20 mirror systemd[993]: Listening on GnuPG network certificate management daemon. ░░ Subject: A start job for unit UNIT has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit UNIT has finished successfully. ░░ ░░ The job identifier is 17. Sep 19 02:32:20 mirror systemd[993]: Listening on GnuPG cryptographic agent and passphrase cache (access for web browsers). ░░ Subject: A start job for unit UNIT has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit UNIT has finished successfully. ░░ ░░ The job identifier is 19. Sep 19 02:32:20 mirror systemd[993]: Listening on GnuPG cryptographic agent and passphrase cache (restricted). ░░ Subject: A start job for unit UNIT has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit UNIT has finished successfully. ░░ ░░ The job identifier is 18. Sep 19 02:32:20 mirror systemd[993]: Listening on GnuPG cryptographic agent (ssh-agent emulation). ░░ Subject: A start job for unit UNIT has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit UNIT has finished successfully. ░░ ░░ The job identifier is 21. Sep 19 02:32:20 mirror systemd[993]: Listening on GnuPG cryptographic agent and passphrase cache. ░░ Subject: A start job for unit UNIT has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit UNIT has finished successfully. ░░ ░░ The job identifier is 22. Sep 19 02:32:20 mirror systemd[993]: Listening on debconf communication socket. ░░ Subject: A start job for unit UNIT has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit UNIT has finished successfully. ░░ ░░ The job identifier is 14. Sep 19 02:32:20 mirror systemd[993]: Listening on REST API socket for snapd user session agent. ░░ Subject: A start job for unit UNIT has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit UNIT has finished successfully. ░░ ░░ The job identifier is 23. Sep 19 02:32:20 mirror systemd[993]: Listening on D-Bus User Message Bus Socket. ░░ Subject: A start job for unit UNIT has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit UNIT has finished successfully. ░░ ░░ The job identifier is 20. Sep 19 02:32:20 mirror systemd[993]: Reached target Sockets. ░░ Subject: A start job for unit UNIT has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit UNIT has finished successfully. ░░ ░░ The job identifier is 13. Sep 19 02:32:20 mirror systemd[993]: Reached target Basic System. ░░ Subject: A start job for unit UNIT has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit UNIT has finished successfully. ░░ ░░ The job identifier is 10. Sep 19 02:32:20 mirror systemd[993]: Reached target Main User Target. ░░ Subject: A start job for unit UNIT has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit UNIT has finished successfully. ░░ ░░ The job identifier is 9. Sep 19 02:32:20 mirror systemd[993]: Startup finished in 80ms. ░░ Subject: User manager start-up is now complete ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ The user manager instance for user 1000 has been started. All services queued ░░ for starting have been started. Note that other services might still be starting ░░ up or be started at any later time. ░░ ░░ Startup of the manager took 80466 microseconds. Sep 19 02:32:20 mirror systemd[1]: Started User Manager for UID 1000. ░░ Subject: A start job for unit user@1000.service has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit user@1000.service has finished successfully. ░░ ░░ The job identifier is 514. Sep 19 02:32:20 mirror systemd[1]: Started Session 1 of User ubuntu. ░░ Subject: A start job for unit session-1.scope has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit session-1.scope has finished successfully. ░░ ░░ The job identifier is 605. Sep 19 02:32:22 mirror systemd[1]: systemd-rfkill.service: Deactivated successfully. ░░ Subject: Unit succeeded ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ The unit systemd-rfkill.service has successfully entered the 'dead' state. Sep 19 02:32:40 mirror systemd[1]: systemd-timedated.service: Deactivated successfully. ░░ Subject: Unit succeeded ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ The unit systemd-timedated.service has successfully entered the 'dead' state. Sep 19 02:32:43 mirror snapd[715]: stateengine.go:149: state ensure error: persistent network error: Get "https://api.snapcraft.io/api/v1/snaps/sections": dial tcp: lookup api.snapcraft.io: Temporary failure in name resolution Sep 19 02:32:45 mirror snapd[715]: daemon.go:521: gracefully waiting for running hooks Sep 19 02:32:45 mirror snapd[715]: daemon.go:523: done waiting for running hooks Sep 19 02:32:45 mirror snapd[715]: overlord.go:512: Released state lock file Sep 19 02:32:45 mirror snapd[715]: daemon stop requested to wait for socket activation Sep 19 02:32:45 mirror systemd[1]: snapd.service: Deactivated successfully. ░░ Subject: Unit succeeded ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ The unit snapd.service has successfully entered the 'dead' state. Sep 19 02:32:47 mirror systemd[1]: systemd-hostnamed.service: Deactivated successfully. ░░ Subject: Unit succeeded ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ The unit systemd-hostnamed.service has successfully entered the 'dead' state. Sep 19 02:34:41 mirror sudo[1020]: ubuntu : TTY=tty1 ; PWD=/home/ubuntu ; USER=root ; COMMAND=/bin/bash Sep 19 02:34:41 mirror sudo[1020]: pam_unix(sudo:session): session opened for user root(uid=0) by ubuntu(uid=1000) Sep 19 02:34:43 mirror systemd[1]: Condition check resulted in OpenVSwitch configuration for cleanup being skipped. ░░ Subject: A start job for unit netplan-ovs-cleanup.service has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit netplan-ovs-cleanup.service has finished successfully. ░░ ░░ The job identifier is 704. Sep 19 02:34:43 mirror systemd[1]: Started WPA supplicant for netplan wlan0. ░░ Subject: A start job for unit netplan-wpa-wlan0.service has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit netplan-wpa-wlan0.service has finished successfully. ░░ ░░ The job identifier is 705. Sep 19 02:34:43 mirror systemd[1]: Stopping Network Configuration... ░░ Subject: A stop job for unit systemd-networkd.service has begun execution ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A stop job for unit systemd-networkd.service has begun execution. ░░ ░░ The job identifier is 697. Sep 19 02:34:43 mirror wpa_supplicant[1031]: Successfully initialized wpa_supplicant Sep 19 02:34:43 mirror systemd-networkd[616]: eth0: DHCPv6 lease lost Sep 19 02:34:43 mirror systemd-networkd[616]: wlan0: DHCPv6 lease lost Sep 19 02:34:43 mirror systemd-timesyncd[591]: Network configuration changed, trying to establish connection. Sep 19 02:34:43 mirror kernel: [dhd] P2P interface registered Sep 19 02:34:43 mirror wpa_supplicant[1031]: nl80211: kernel reports: Registration to specific type not supported Sep 19 02:34:43 mirror systemd-timesyncd[591]: Network configuration changed, trying to establish connection. Sep 19 02:34:43 mirror systemd[1]: systemd-networkd.service: Deactivated successfully. ░░ Subject: Unit succeeded ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ The unit systemd-networkd.service has successfully entered the 'dead' state. Sep 19 02:34:43 mirror systemd[1]: Stopped Network Configuration. ░░ Subject: A stop job for unit systemd-networkd.service has finished ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A stop job for unit systemd-networkd.service has finished. ░░ ░░ The job identifier is 697 and the job result is done. Sep 19 02:34:43 mirror systemd[1]: Starting Network Configuration... ░░ Subject: A start job for unit systemd-networkd.service has begun execution ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit systemd-networkd.service has begun execution. ░░ ░░ The job identifier is 697. Sep 19 02:34:43 mirror systemd-networkd[1034]: wlan0: Link UP Sep 19 02:34:43 mirror systemd-networkd[1034]: eth0: Link UP Sep 19 02:34:43 mirror systemd-networkd[1034]: lo: Link UP Sep 19 02:34:43 mirror systemd-networkd[1034]: lo: Gained carrier Sep 19 02:34:43 mirror systemd-networkd[1034]: wlan0: Gained IPv6LL Sep 19 02:34:43 mirror systemd-networkd[1034]: Enumeration completed Sep 19 02:34:43 mirror systemd[1]: Started Network Configuration. ░░ Subject: A start job for unit systemd-networkd.service has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit systemd-networkd.service has finished successfully. ░░ ░░ The job identifier is 697. Sep 19 02:34:45 mirror wpa_supplicant[1031]: wlan0: Trying to associate with SSID 'yulu' Sep 19 02:34:45 mirror kernel: [dhd] [wlan0] wl_ext_set_chanspec : channel 5g-161(0xe39b) Sep 19 02:34:45 mirror kernel: [dhd] [wlan0] wl_conn_debug_info : Connecting with a0:36:bc:38:86:dc ssid "yulu", len (4), channel=5g-161(chan_cnt=1), sec=wpa2/psk/mfpn/aes, rssi=-28 Sep 19 02:34:46 mirror kernel: [dhd] [wlan0] wl_iw_event : Link UP with a0:36:bc:38:86:dc Sep 19 02:34:46 mirror kernel: [dhd] [wlan0] wl_ext_iapsta_event : [S] Link UP with a0:36:bc:38:86:dc Sep 19 02:34:46 mirror kernel: [dhd] [wlan0] wl_bss_connect_done : Report connect result - connection succeeded Sep 19 02:34:46 mirror wpa_supplicant[1031]: wlan0: Associated with a0:36:bc:38:86:dc Sep 19 02:34:46 mirror wpa_supplicant[1031]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Sep 19 02:34:46 mirror kernel: NOHZ tick-stop error: Non-RCU local softirq work is pending, handler #08!!! Sep 19 02:34:46 mirror kernel: [dhd] dhd_prot_flow_ring_create: Send Flow Create Req flow ID 41 for peer a0:36:bc:38:86:dc prio 3 ifindex 0 items 512 Sep 19 02:34:46 mirror kernel: [dhd] dhd_prot_flow_ring_create_response_process: Flow Create Response status = 0 Flow 41 Sep 19 02:34:46 mirror kernel: NOHZ tick-stop error: Non-RCU local softirq work is pending, handler #08!!! Sep 19 02:34:46 mirror kernel: [dhd] [wlan0] wl_add_keyext : key index (0) Sep 19 02:34:46 mirror wpa_supplicant[1031]: wlan0: WPA: Key negotiation completed with a0:36:bc:38:86:dc [PTK=CCMP GTK=CCMP] Sep 19 02:34:46 mirror wpa_supplicant[1031]: wlan0: CTRL-EVENT-CONNECTED - Connection to a0:36:bc:38:86:dc completed [id=0 id_str=] Sep 19 02:34:46 mirror systemd-networkd[1034]: wlan0: Gained carrier Sep 19 02:34:46 mirror systemd-timesyncd[591]: Network configuration changed, trying to establish connection. Sep 19 02:34:46 mirror kernel: NOHZ tick-stop error: Non-RCU local softirq work is pending, handler #08!!! Sep 19 02:34:46 mirror kernel: [dhd] dhd_prot_flow_ring_create: Send Flow Create Req flow ID 40 for peer 33:33:00:00:00:02 prio 0 ifindex 0 items 512 Sep 19 02:34:46 mirror kernel: [dhd] dhd_prot_flow_ring_create_response_process: Flow Create Response status = 0 Flow 40 Sep 19 02:34:47 mirror kernel: NOHZ tick-stop error: Non-RCU local softirq work is pending, handler #08!!! Sep 19 02:34:47 mirror kernel: NOHZ tick-stop error: Non-RCU local softirq work is pending, handler #08!!! Sep 19 02:34:47 mirror kernel: NOHZ tick-stop error: Non-RCU local softirq work is pending, handler #08!!! Sep 19 02:34:47 mirror systemd-networkd[1034]: wlan0: DHCPv4 address 192.168.1.132/24 via 192.168.1.1 Sep 19 02:34:47 mirror systemd-timesyncd[591]: Network configuration changed, trying to establish connection. Sep 19 02:34:47 mirror systemd-timesyncd[591]: Network configuration changed, trying to establish connection. Sep 19 02:34:47 mirror systemd-timesyncd[591]: Network configuration changed, trying to establish connection. Sep 19 02:34:47 mirror kernel: NOHZ tick-stop error: Non-RCU local softirq work is pending, handler #08!!! Sep 19 02:34:47 mirror kernel: NOHZ tick-stop error: Non-RCU local softirq work is pending, handler #08!!! Sep 19 02:34:48 mirror kernel: NOHZ tick-stop error: Non-RCU local softirq work is pending, handler #08!!! Sep 19 02:34:48 mirror kernel: NOHZ tick-stop error: Non-RCU local softirq work is pending, handler #08!!! Sep 19 02:35:17 mirror systemd[1]: Starting Download data for packages that failed at package install time... ░░ Subject: A start job for unit update-notifier-download.service has begun execution ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit update-notifier-download.service has begun execution. ░░ ░░ The job identifier is 708. Sep 19 02:35:17 mirror systemd[1]: update-notifier-download.service: Deactivated successfully. ░░ Subject: Unit succeeded ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ The unit update-notifier-download.service has successfully entered the 'dead' state. Sep 19 02:35:17 mirror systemd[1]: Finished Download data for packages that failed at package install time. ░░ Subject: A start job for unit update-notifier-download.service has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit update-notifier-download.service has finished successfully. ░░ ░░ The job identifier is 708. Sep 19 02:35:19 mirror sshd[1042]: Accepted publickey for ubuntu from 192.168.1.110 port 62399 ssh2: RSA SHA256:h9uZe0oatIrXVAaXmMyxzyBzXlI3zLlcaXL5Ky16YSg Sep 19 02:35:19 mirror sshd[1042]: pam_unix(sshd:session): session opened for user ubuntu(uid=1000) by (uid=0) Sep 19 02:35:19 mirror systemd-logind[718]: New session 3 of user ubuntu. ░░ Subject: A new session 3 has been created for user ubuntu ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ Documentation: sd-login(3) ░░ ░░ A new session with the ID 3 has been created for the user ubuntu. ░░ ░░ The leading process of the session is 1042. Sep 19 02:35:19 mirror systemd[1]: Started Session 3 of User ubuntu. ░░ Subject: A start job for unit session-3.scope has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit session-3.scope has finished successfully. ░░ ░░ The job identifier is 796. Sep 19 02:35:19 mirror sshd[1097]: Received disconnect from 192.168.1.110 port 62399:11: disconnected by user Sep 19 02:35:19 mirror sshd[1097]: Disconnected from user ubuntu 192.168.1.110 port 62399 Sep 19 02:35:19 mirror sshd[1042]: pam_unix(sshd:session): session closed for user ubuntu Sep 19 02:35:19 mirror systemd-logind[718]: Session 3 logged out. Waiting for processes to exit. Sep 19 02:35:27 mirror systemd-timesyncd[591]: Timed out waiting for reply from 185.125.190.58:123 (ntp.ubuntu.com). Sep 19 02:35:26 mirror systemd-resolved[663]: Clock change detected. Flushing caches. Sep 19 02:35:26 mirror systemd-timesyncd[591]: Initial synchronization to time server 185.125.190.56:123 (ntp.ubuntu.com). Sep 19 02:42:39 mirror wpa_supplicant[1031]: wlan0: WPA: Group rekeying completed with a0:36:bc:38:86:dc [GTK=CCMP] Sep 19 02:45:24 mirror systemd[1]: Starting Cleanup of Temporary Directories... ░░ Subject: A start job for unit systemd-tmpfiles-clean.service has begun execution ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit systemd-tmpfiles-clean.service has begun execution. ░░ ░░ The job identifier is 888. Sep 19 02:45:24 mirror systemd[1]: systemd-tmpfiles-clean.service: Deactivated successfully. ░░ Subject: Unit succeeded ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ The unit systemd-tmpfiles-clean.service has successfully entered the 'dead' state. Sep 19 02:45:24 mirror systemd[1]: Finished Cleanup of Temporary Directories. ░░ Subject: A start job for unit systemd-tmpfiles-clean.service has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit systemd-tmpfiles-clean.service has finished successfully. ░░ ░░ The job identifier is 888. Sep 19 02:54:04 mirror sshd[1310]: Accepted publickey for ubuntu from 192.168.1.110 port 62801 ssh2: RSA SHA256:h9uZe0oatIrXVAaXmMyxzyBzXlI3zLlcaXL5Ky16YSg Sep 19 02:54:04 mirror sshd[1310]: pam_unix(sshd:session): session opened for user ubuntu(uid=1000) by (uid=0) Sep 19 02:54:04 mirror systemd[1]: Started Session 4 of User ubuntu. ░░ Subject: A start job for unit session-4.scope has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit session-4.scope has finished successfully. ░░ ░░ The job identifier is 892. Sep 19 02:54:04 mirror systemd-logind[718]: New session 4 of user ubuntu. ░░ Subject: A new session 4 has been created for user ubuntu ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ Documentation: sd-login(3) ░░ ░░ A new session with the ID 4 has been created for the user ubuntu. ░░ ░░ The leading process of the session is 1310. Sep 19 02:54:57 mirror sshd[1365]: Received disconnect from 192.168.1.110 port 62801:11: disconnected by user Sep 19 02:54:57 mirror sshd[1365]: Disconnected from user ubuntu 192.168.1.110 port 62801 Sep 19 02:54:57 mirror sshd[1310]: pam_unix(sshd:session): session closed for user ubuntu Sep 19 02:54:57 mirror systemd[1]: session-4.scope: Deactivated successfully. ░░ Subject: Unit succeeded ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ The unit session-4.scope has successfully entered the 'dead' state. Sep 19 02:54:57 mirror systemd-logind[718]: Session 4 logged out. Waiting for processes to exit. Sep 19 02:54:57 mirror systemd-logind[718]: Removed session 4. ░░ Subject: Session 4 has been terminated ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ Documentation: sd-login(3) ░░ ░░ A session with the ID 4 has been terminated. Sep 19 02:55:07 mirror sshd[1396]: Accepted publickey for ubuntu from 192.168.1.110 port 62815 ssh2: RSA SHA256:h9uZe0oatIrXVAaXmMyxzyBzXlI3zLlcaXL5Ky16YSg Sep 19 02:55:07 mirror sshd[1396]: pam_unix(sshd:session): session opened for user ubuntu(uid=1000) by (uid=0) Sep 19 02:55:07 mirror systemd-logind[718]: New session 5 of user ubuntu. ░░ Subject: A new session 5 has been created for user ubuntu ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ Documentation: sd-login(3) ░░ ░░ A new session with the ID 5 has been created for the user ubuntu. ░░ ░░ The leading process of the session is 1396. Sep 19 02:55:07 mirror systemd[1]: Started Session 5 of User ubuntu. ░░ Subject: A start job for unit session-5.scope has finished successfully ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ A start job for unit session-5.scope has finished successfully. ░░ ░░ The job identifier is 984. Sep 19 02:55:08 mirror kernel: [dhd] dhd_prot_flow_ring_create: Send Flow Create Req flow ID 39 for peer f8:4d:89:8a:3f:48 prio 1 ifindex 0 items 512 Sep 19 02:55:08 mirror kernel: [dhd] dhd_prot_flow_ring_create_response_process: Flow Create Response status = 0 Flow 39 Sep 19 02:55:08 mirror sshd[1451]: Received disconnect from 192.168.1.110 port 62815:11: disconnected by user Sep 19 02:55:08 mirror sshd[1451]: Disconnected from user ubuntu 192.168.1.110 port 62815 Sep 19 02:55:08 mirror sshd[1396]: pam_unix(sshd:session): session closed for user ubuntu Sep 19 02:55:08 mirror systemd-logind[718]: Session 5 logged out. Waiting for processes to exit. Sep 19 02:55:08 mirror systemd[1]: session-5.scope: Deactivated successfully. ░░ Subject: Unit succeeded ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ ░░ The unit session-5.scope has successfully entered the 'dead' state. Sep 19 02:55:08 mirror systemd-logind[718]: Removed session 5. ░░ Subject: Session 5 has been terminated ░░ Defined-By: systemd ░░ Support: http://www.ubuntu.com/support ░░ Documentation: sd-login(3) ░░ ░░ A session with the ID 5 has been terminated. ```

This part specifically

Sep 19 02:30:08 mirror kernel: [WLAN_RFKILL]: rockchip_wifi_power: 0
Sep 19 02:30:08 mirror kernel: [WLAN_RFKILL]: rockchip_wifi_power: toggle = false
Sep 19 02:30:08 mirror kernel: wifi power off
Sep 19 02:30:08 mirror systemd[1]: Found device /sys/subsystem/net/devices/wlan0.
░░ Subject: A start job for unit sys-subsystem-net-devices-wlan0.device has finished successfully
░░ Defined-By: systemd
░░ Support: http://www.ubuntu.com/support
░░ 
░░ A start job for unit sys-subsystem-net-devices-wlan0.device has finished successfully.
░░ 
░░ The job identifier is 78.
Sep 19 02:30:08 mirror systemd[1]: Started WPA supplicant for netplan wlan0.
░░ Subject: A start job for unit netplan-wpa-wlan0.service has finished successfully
░░ Defined-By: systemd
░░ Support: http://www.ubuntu.com/support
░░ 
░░ A start job for unit netplan-wpa-wlan0.service has finished successfully.
░░ 
░░ The job identifier is 77.
Sep 19 02:30:08 mirror wpa_supplicant[603]: Successfully initialized wpa_supplicant
Sep 19 02:30:08 mirror wpa_supplicant[603]: Could not set interface wlan0 flags (UP): Device or resource busy
Sep 19 02:30:08 mirror wpa_supplicant[603]: nl80211: Could not set interface 'wlan0' UP
Sep 19 02:30:08 mirror wpa_supplicant[603]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0
Sep 19 02:30:08 mirror wpa_supplicant[603]: Could not set interface wlan0 flags (UP): Device or resource busy
Sep 19 02:30:08 mirror wpa_supplicant[603]: WEXT: Could not set interface 'wlan0' UP
Sep 19 02:30:08 mirror wpa_supplicant[603]: wlan0: Failed to initialize driver interface

Looks like something is powering off wifi right before wpa_supplicant tries to run?

[Joshua-Riek]

I wifi should be configured with the network config file in the boot partition before the first boot.

Also, if you install the desktop version, do you have a wifi issue?

[mightyguava]

As in I should put my netplan YAML changes in /boot/firmware/network-config instead? I didn’t see anything in the wiki so I just found some instructions on Google. The use of cloud-init confused me too. What is that being used for?

Will try desktop tomorrow but I’d really like to get server working as I want to give the display device directly to an application instead of the desktop.

[mightyguava]

I wifi should be configured with the network config file in the boot partition before the first boot.

Ah I understand now, you are explaining what that header is about. My config is correct looking at https://github.com/Joshua-Riek/ubuntu-rockchip/issues/283.

So after a couple restarts I actually did manage to get wifi working at boot, same netplan config file. For conciseness, here's journalctl -xe | grep wlan0 for the successful run

Sep 19 09:07:29 mirror kernel: [dhd] Register interface [wlan0]  MAC: b8:2d:28:42:2a:f4
Sep 19 09:07:29 mirror kernel: [dhd] [wlan0] wl_android_wifi_off : in g_wifi_on=1, on_failure=1
Sep 19 09:07:29 mirror systemd[1]: Found device /sys/subsystem/net/devices/wlan0.
░░ Subject: A start job for unit sys-subsystem-net-devices-wlan0.device has finished successfully
░░ A start job for unit sys-subsystem-net-devices-wlan0.device has finished successfully.
Sep 19 09:07:29 mirror systemd[1]: Started WPA supplicant for netplan wlan0.
░░ Subject: A start job for unit netplan-wpa-wlan0.service has finished successfully
░░ A start job for unit netplan-wpa-wlan0.service has finished successfully.
Sep 19 09:07:29 mirror kernel: [dhd] [wlan0] wl_android_wifi_off : out
Sep 19 09:07:29 mirror kernel: [dhd] [wlan0] dhd_open : Enter
Sep 19 09:07:29 mirror kernel: [dhd] [wlan0] wl_android_wifi_on : in g_wifi_on=0
Sep 19 09:07:30 mirror kernel: [dhd] [wlan0] wl_android_wifi_on : Success
Sep 19 09:07:30 mirror kernel: [dhd] [wlan0] dhd_open : Exit ret=0
Sep 19 09:07:30 mirror kernel: [dhd] [wlan0] dhd_pri_open : tx queue started
Sep 19 09:07:30 mirror kernel: [dhd] [wlan0] custom_xps_map_set : Done. mapping cpu
Sep 19 09:07:30 mirror systemd-networkd[620]: wlan0: Link UP
Sep 19 09:07:30 mirror systemd-networkd[620]: wlan0: Link DOWN
Sep 19 09:07:30 mirror systemd-networkd[620]: wlan0: Re-configuring with /run/systemd/network/10-netplan-wlan0.network
Sep 19 09:07:30 mirror systemd-networkd[620]: wlan0: Link UP
Sep 19 09:07:30 mirror systemd-networkd[620]: wlan0: Gained carrier
Sep 19 09:07:30 mirror systemd-networkd[620]: wlan0: Lost carrier
Sep 19 09:07:30 mirror systemd-networkd[620]: wlan0: DHCPv6 lease lost
Sep 19 09:07:32 mirror systemd-networkd[620]: wlan0: Gained IPv6LL
Sep 19 09:07:33 mirror wpa_supplicant[606]: wlan0: Trying to associate with SSID 'my-ssid'
Sep 19 09:07:33 mirror kernel: [dhd] [wlan0] wl_ext_set_chanspec : channel 5g-161(0xe39b)
Sep 19 09:07:33 mirror kernel: [dhd] [wlan0] wl_conn_debug_info : Connecting with a0:36:bc:38:86:dc ssid "my-ssid", len (4), channel=5g-161(chan_cnt=1), sec=wpa2/psk/mfpn/aes, rssi=-31
Sep 19 09:07:33 mirror kernel: [dhd] [wlan0] wl_iw_event : Link UP with a0:36:bc:38:86:dc
Sep 19 09:07:33 mirror kernel: [dhd] [wlan0] wl_ext_iapsta_event : [S] Link UP with a0:36:bc:38:86:dc
Sep 19 09:07:33 mirror kernel: [dhd] [wlan0] wl_bss_connect_done : Report connect result - connection succeeded
Sep 19 09:07:33 mirror wpa_supplicant[606]: wlan0: Associated with a0:36:bc:38:86:dc
Sep 19 09:07:33 mirror wpa_supplicant[606]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
Sep 19 09:07:33 mirror kernel: [dhd] [wlan0] wl_add_keyext : key index (0)
Sep 19 09:07:33 mirror wpa_supplicant[606]: wlan0: WPA: Key negotiation completed with a0:36:bc:38:86:dc [PTK=CCMP GTK=CCMP]
Sep 19 09:07:33 mirror wpa_supplicant[606]: wlan0: CTRL-EVENT-CONNECTED - Connection to a0:36:bc:38:86:dc completed [id=0 id_str=]
Sep 19 09:07:33 mirror systemd-networkd[620]: wlan0: Gained carrier
Sep 19 09:07:34 mirror systemd-networkd[620]: wlan0: DHCPv4 address 192.168.1.132/24 via 192.168.1.1
Sep 19 09:09:30 mirror cloud-init[675]: ci-info: | wlan0  |  True |        192.168.1.132         | 255.255.255.0 | global | b8:2d:28:42:2a:f4 |
Sep 19 09:09:30 mirror cloud-init[675]: ci-info: | wlan0  |  True | fe80::ba2d:28ff:fe42:2af4/64 |       .       |  link  | b8:2d:28:42:2a:f4 |
Sep 19 09:09:30 mirror cloud-init[675]: ci-info: |   0   |   0.0.0.0    | 192.168.1.1 |     0.0.0.0     |   wlan0   |   UG  |
Sep 19 09:09:30 mirror cloud-init[675]: ci-info: |   1   | 192.168.1.0  |   0.0.0.0   |  255.255.255.0  |   wlan0   |   U   |
Sep 19 09:09:30 mirror cloud-init[675]: ci-info: |   2   | 192.168.1.1  |   0.0.0.0   | 255.255.255.255 |   wlan0   |   UH  |
Sep 19 09:09:30 mirror cloud-init[675]: ci-info: |   3   | 192.168.1.5  |   0.0.0.0   | 255.255.255.255 |   wlan0   |   UH  |
Sep 19 09:09:30 mirror cloud-init[675]: ci-info: |   4   | 192.168.1.10 |   0.0.0.0   | 255.255.255.255 |   wlan0   |   UH  |
Sep 19 09:09:30 mirror cloud-init[675]: ci-info: |   1   |  fe80::/64  |    ::   |   wlan0   |   U   |
Sep 19 09:09:30 mirror cloud-init[675]: ci-info: |   3   |    local    |    ::   |   wlan0   |   U   |
Sep 19 09:09:30 mirror cloud-init[675]: ci-info: |   4   |  multicast  |    ::   |   wlan0   |   U   |

And a subsequent restart that failed to connect to wifi, with no config changes.

Sep 19 09:11:21 mirror kernel: [dhd] Register interface [wlan0]  MAC: b8:2d:28:42:2a:f4
Sep 19 09:11:21 mirror kernel: [dhd] [wlan0] wl_android_wifi_off : in g_wifi_on=1, on_failure=1
Sep 19 09:11:21 mirror systemd[1]: Found device /sys/subsystem/net/devices/wlan0.
░░ Subject: A start job for unit sys-subsystem-net-devices-wlan0.device has finished successfully
░░ A start job for unit sys-subsystem-net-devices-wlan0.device has finished successfully.
Sep 19 09:11:21 mirror systemd[1]: Started WPA supplicant for netplan wlan0.
░░ Subject: A start job for unit netplan-wpa-wlan0.service has finished successfully
░░ A start job for unit netplan-wpa-wlan0.service has finished successfully.
Sep 19 09:11:21 mirror wpa_supplicant[591]: Could not set interface wlan0 flags (UP): Device or resource busy
Sep 19 09:11:21 mirror wpa_supplicant[591]: nl80211: Could not set interface 'wlan0' UP
Sep 19 09:11:21 mirror wpa_supplicant[591]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0
Sep 19 09:11:21 mirror wpa_supplicant[591]: Could not set interface wlan0 flags (UP): Device or resource busy
Sep 19 09:11:21 mirror wpa_supplicant[591]: WEXT: Could not set interface 'wlan0' UP
Sep 19 09:11:21 mirror wpa_supplicant[591]: wlan0: Failed to initialize driver interface
Sep 19 09:11:21 mirror wpa_supplicant[591]: wlan0: CTRL-EVENT-DSCP-POLICY clear_all
Sep 19 09:11:21 mirror systemd[1]: netplan-wpa-wlan0.service: Main process exited, code=exited, status=255/EXCEPTION
░░ An ExecStart= process belonging to unit netplan-wpa-wlan0.service has exited.
Sep 19 09:11:21 mirror systemd[1]: netplan-wpa-wlan0.service: Failed with result 'exit-code'.
░░ The unit netplan-wpa-wlan0.service has entered the 'failed' state with result 'exit-code'.
Sep 19 09:11:21 mirror kernel: [dhd] [wlan0] wl_android_wifi_off : out
Sep 19 09:11:22 mirror kernel: [dhd] [wlan0] dhd_open : Enter
Sep 19 09:11:22 mirror kernel: [dhd] [wlan0] wl_android_wifi_on : in g_wifi_on=0
Sep 19 09:11:22 mirror kernel: [dhd] [wlan0] wl_android_wifi_on : Success
Sep 19 09:11:22 mirror kernel: [dhd] [wlan0] dhd_open : Exit ret=0
Sep 19 09:11:22 mirror kernel: [dhd] [wlan0] dhd_pri_open : tx queue started
Sep 19 09:11:22 mirror kernel: [dhd] [wlan0] custom_xps_map_set : Done. mapping cpu
Sep 19 09:11:22 mirror systemd-networkd[606]: wlan0: Link UP
Sep 19 09:11:23 mirror systemd-networkd[606]: wlan0: Gained carrier
Sep 19 09:11:24 mirror systemd-networkd[606]: wlan0: Gained IPv6LL
Sep 19 09:13:22 mirror cloud-init[658]: ci-info: | wlan0  |  True | fe80::ba2d:28ff:fe42:2af4/64 |     .     |  link | b8:2d:28:42:2a:f4 |
Sep 19 09:13:22 mirror cloud-init[658]: ci-info: |   1   |  fe80::/64  |    ::   |   wlan0   |   U   |
Sep 19 09:13:22 mirror cloud-init[658]: ci-info: |   3   |    local    |    ::   |   wlan0   |   U   |
Sep 19 09:13:22 mirror cloud-init[658]: ci-info: |   4   |  multicast  |    ::   |   wlan0   |   U   |

It sure seems like there's a race with something occupying wlan0 the same time that netplan-wpa-wlan0.service tries to initialize it.

[mightyguava]

The suspect being this block

Sep 19 09:07:29 mirror kernel: [dhd] [wlan0] wl_android_wifi_off : out
Sep 19 09:07:29 mirror kernel: [dhd] [wlan0] dhd_open : Enter
Sep 19 09:07:29 mirror kernel: [dhd] [wlan0] wl_android_wifi_on : in g_wifi_on=0
Sep 19 09:07:30 mirror kernel: [dhd] [wlan0] wl_android_wifi_on : Success
Sep 19 09:07:30 mirror kernel: [dhd] [wlan0] dhd_open : Exit ret=0
Sep 19 09:07:30 mirror kernel: [dhd] [wlan0] dhd_pri_open : tx queue started
Sep 19 09:07:30 mirror kernel: [dhd] [wlan0] custom_xps_map_set : Done. mapping cpu

If it appears before wpa_supplicant, wifi works on boot. If it appears after, wifi doesn't work on boot as wpa_supplicant can't access wlan0.

Do you know what this is and why the ubuntu image is toggling "android_wifi"?

[mightyguava]

Following instructions from ChatGPT 🤖 , I ran

systemctl edit netplan-wpa-wlan0.service

and added

[Service]
ExecStartPre=/bin/sleep 1

to add a 1 second delay before wpa_supplicant. Now my wifi starts consistently across reboots.

The remaining issue is that systemd-networkd-wait-online still thinks network isn't available and waits the full 2 minutes, even though you can clearly see that wlan0 is getting a DHCP address, and NTP is working.

Logs below

journalctl -b (collapsed)

``` Sep 19 10:07:50 mirror kernel: [dhd] Register interface [wlan0] MAC: b8:2d:28:42:2a:f4 Sep 19 10:07:50 mirror kernel: [dhd] dhd_tcpack_suppress_set: TCP ACK Suppress mode 3 -> mode 0 Sep 19 10:07:50 mirror kernel: [dhd] dhd_tcpack_suppress_set: TCPACK_INFO_MAXNUM=10, TCPDATA_INFO_MAXNUM=10 Sep 19 10:07:50 mirror kernel: [dhd] [wlan0] wl_android_wifi_off : in g_wifi_on=1, on_failure=1 Sep 19 10:07:50 mirror kernel: [dhd] dhd_rtt_deinit: ENTER Sep 19 10:07:50 mirror kernel: [dhd] dhd_rtt_deinit: EXIT, err = 0 Sep 19 10:07:50 mirror kernel: [dhd] dhd_bus_devreset: == Power OFF == Sep 19 10:07:50 mirror kernel: [dhd] dhd_bus_stop: making DHD_BUS_DOWN Sep 19 10:07:50 mirror kernel: [dhd] dhd_dpc_kill: tasklet disabled Sep 19 10:07:50 mirror kernel: [dhd] dhd_bus_devreset: making DHD_BUS_DOWN Sep 19 10:07:50 mirror kernel: [dhd] dhd_bus_devreset: WLAN OFF Done Sep 19 10:07:50 mirror kernel: [dhd] wifi_platform_set_power = 0, delay: 0 msec Sep 19 10:07:50 mirror kernel: [dhd] ======== PULL WL_REG_ON(-1) LOW! ======== Sep 19 10:07:50 mirror kernel: [WLAN_RFKILL]: rockchip_wifi_power: 0 Sep 19 10:07:50 mirror kernel: [WLAN_RFKILL]: rockchip_wifi_power: toggle = false Sep 19 10:07:50 mirror kernel: wifi power off Sep 19 10:07:50 mirror systemd[1]: Found device /sys/subsystem/net/devices/wlan0. Sep 19 10:07:50 mirror systemd[1]: Starting WPA supplicant for netplan wlan0... Sep 19 10:07:50 mirror kernel: [WLAN_RFKILL]: wifi shut off power [GPIO24-0] Sep 19 10:07:50 mirror kernel: [dhd] [wlan0] wl_android_wifi_off : out Sep 19 10:07:50 mirror kernel: [dhd] dhdpcie_pci_probe : mutex is released. Sep 19 10:07:50 mirror kernel: [dhd] _dhd_module_init: Exit err=0 Sep 19 10:07:51 mirror cloud-init[607]: Cloud-init v. 23.2.2-0ubuntu0~22.04.1 running 'init-local' at Tue, 19 Sep 2023 10:07:50 +0000. Up 8.87 seconds. Sep 19 10:07:51 mirror systemd[1]: Finished Initial cloud-init job (pre-networking). Sep 19 10:07:51 mirror systemd[1]: Reached target Preparation for Network. Sep 19 10:07:51 mirror systemd[1]: Starting Raise network interfaces... Sep 19 10:07:51 mirror systemd[1]: Starting Network Configuration... Sep 19 10:07:51 mirror ifup[640]: /etc/network/if-up.d/resolved: 12: mystatedir: not found Sep 19 10:07:51 mirror systemd-networkd[616]: lo: Link UP Sep 19 10:07:51 mirror systemd-networkd[616]: lo: Gained carrier Sep 19 10:07:51 mirror systemd-networkd[616]: Enumeration completed Sep 19 10:07:51 mirror systemd[1]: Started Network Configuration. Sep 19 10:07:51 mirror kernel: [dhd] dhd_query_bus_erros: Dongle Reset occurred, cannot proceed Sep 19 10:07:51 mirror kernel: [dhd] [Repeats 0 times] Sep 19 10:07:51 mirror kernel: [dhd] CFG80211-ERROR) wl_cfg80211_dump_station : WLC_GET_ASSOCLIST error -19 Sep 19 10:07:51 mirror kernel: [dhd] dhd_pri_open : no mutex held Sep 19 10:07:51 mirror kernel: [dhd] dhd_pri_open : set mutex lock Sep 19 10:07:51 mirror kernel: [dhd] [wlan0] dhd_open : Enter Sep 19 10:07:51 mirror kernel: [dhd] Dongle Host Driver, version 101.10.361.20 (wlan=r892223-20220701-3) drivers/net/wireless/rockchip_wlan/rkwifi/bcmdhd compiled on Sep 11 2023 at 20:31:55 Sep 19 10:07:51 mirror kernel: [dhd] dhd_open: ######### called for ifidx=0 ######### Sep 19 10:07:51 mirror kernel: [dhd] [wlan0] wl_android_wifi_on : in g_wifi_on=0 Sep 19 10:07:51 mirror kernel: [dhd] wifi_platform_set_power = 1, delay: 200 msec Sep 19 10:07:51 mirror kernel: [dhd] ======== PULL WL_REG_ON(-1) HIGH! ======== Sep 19 10:07:51 mirror kernel: [WLAN_RFKILL]: rockchip_wifi_power: 1 Sep 19 10:07:51 mirror kernel: [WLAN_RFKILL]: rockchip_wifi_power: toggle = false Sep 19 10:07:51 mirror systemd[1]: Starting Wait for Network to be Configured... Sep 19 10:07:51 mirror systemd[1]: Starting Network Name Resolution... Sep 19 10:07:51 mirror systemd[1]: Finished Raise network interfaces. Sep 19 10:07:51 mirror systemd[1]: Started WPA supplicant for netplan wlan0. Sep 19 10:07:51 mirror wpa_supplicant[664]: Successfully initialized wpa_supplicant Sep 19 10:07:51 mirror kernel: [WLAN_RFKILL]: wifi turn on power [GPIO24-1] Sep 19 10:07:51 mirror systemd-resolved[663]: Positive Trust Anchors: Sep 19 10:07:51 mirror systemd-resolved[663]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Sep 19 10:07:51 mirror systemd-resolved[663]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa corp home internal intranet lan local private test Sep 19 10:07:51 mirror systemd-resolved[663]: Using system hostname 'mirror'. Sep 19 10:07:51 mirror kernel: [dhd] dhd_bus_devreset: == Power ON == Sep 19 10:07:51 mirror kernel: [dhd] dhd_bus_devreset: dhdpcie_bus_start_host_dev OK Sep 19 10:07:51 mirror kernel: [dhd] dhd_bus_aspm_enable_dev: EP already disabled linkctrl: 0x10110000 Sep 19 10:07:51 mirror kernel: [dhd] dhd_bus_aspm_enable_dev: RC already disabled linkctrl: 0x30110000 Sep 19 10:07:51 mirror kernel: [dhd] Disable CTO Sep 19 10:07:51 mirror kernel: [dhd] DHD: dongle ram size is set to 1310720(orig 1310720) at 0x170000 Sep 19 10:07:51 mirror kernel: [dhd] dhdpcie_bar1_window_switch_enab: bar1_switch_enab=0 ramstart=0x170000 ramend=0x2affff bar1_size=0x400000 Sep 19 10:07:51 mirror kernel: [dhd] dhdpcie_request_irq: INTx enabled Sep 19 10:07:51 mirror kernel: [dhd] dhd_bus_download_firmware: firmware path=/lib/firmware/ap6275p/fw_bcmdhd.bin, nvram path=/lib/firmware/ap6275p/nvram.txt Sep 19 10:07:51 mirror kernel: [dhd] dhdpcie_dump_resource: BAR0(VA): 0x0000000000000000, BAR0(PA): 0x00000000f4200000, SIZE: 32768 Sep 19 10:07:51 mirror kernel: [dhd] dhdpcie_dump_resource: BAR1(VA): 0x0000000000000000, BAR1(PA): 0x00000000f4400000, SIZE: 4194304 Sep 19 10:07:51 mirror kernel: [dhd] dhd_conf_set_path_params : Final fw_path=/lib/firmware/ap6275p/fw_bcm43752a2_pcie_ag.bin Sep 19 10:07:51 mirror kernel: [dhd] dhd_conf_set_path_params : Final nv_path=/lib/firmware/ap6275p/nvram_AP6275P.txt Sep 19 10:07:51 mirror kernel: [dhd] dhd_conf_set_path_params : Final clm_path=/lib/firmware/ap6275p/clm_bcm43752a2_pcie_ag.blob Sep 19 10:07:51 mirror kernel: [dhd] dhd_conf_set_path_params : Final conf_path=/lib/firmware/ap6275p/config_bcm43752a2_pcie_ag.txt Sep 19 10:07:51 mirror kernel: [dhd] dhd_get_download_buffer: Open image file failed /lib/firmware/ap6275p/config_bcm43752a2_pcie_ag.txt Sep 19 10:07:51 mirror kernel: [dhd] dhd_conf_read_config : Ignore config file /lib/firmware/ap6275p/config_bcm43752a2_pcie_ag.txt Sep 19 10:07:51 mirror kernel: [dhd] d2h_intr_method -> PCIE_INTX(0); d2h_intr_control -> D2H_INTMASK(0) Sep 19 10:07:51 mirror kernel: [dhd] dhdpcie_download_code_file: dhd_tcm_test_enable 0 Sep 19 10:07:51 mirror kernel: [dhd] dhdpcie_download_code_file: download firmware /lib/firmware/ap6275p/fw_bcm43752a2_pcie_ag.bin Sep 19 10:07:51 mirror kernel: [dhd] dhd_os_open_image1: /lib/firmware/ap6275p/fw_bcm43752a2_pcie_ag.bin (936074 bytes) open success Sep 19 10:07:51 mirror kernel: [dhd] dhd_os_open_image1: /lib/firmware/ap6275p/nvram_AP6275P.txt (7458 bytes) open success Sep 19 10:07:51 mirror kernel: [dhd] dhdpcie_download_nvram: dhd_get_download_buffer len 7458 Sep 19 10:07:51 mirror kernel: [dhd] # AP6275P_NVRAM_V1.2_20210918A Sep 19 10:07:51 mirror kernel: [dhd] dhdpcie_download_nvram: process_nvram_vars len 6036 Sep 19 10:07:51 mirror kernel: [dhd] dhdpcie_bus_write_vars: Download, Upload and compare of NVRAM succeeded. Sep 19 10:07:51 mirror kernel: [dhd] dhd_bus_aer_config: Configure AER registers for EP Sep 19 10:07:51 mirror kernel: [dhd] dhd_bus_aer_config: Configure AER registers for RC Sep 19 10:07:52 mirror kernel: [dhd] ### Total time ARM OOR to Readshared pass took 91531 usec ### Sep 19 10:07:52 mirror kernel: [dhd] dhdpcie_readshared: PCIe shared addr (0x0020a584) read took 90000 usec before dongle is ready Sep 19 10:07:52 mirror kernel: [dhd] H2D DMA WR INDX : array size 172 = 4 * 43 Sep 19 10:07:52 mirror kernel: [dhd] D2H DMA RD INDX : array size 16 = 4 * 4 Sep 19 10:07:52 mirror kernel: [dhd] D2H DMA WR INDX : array size 16 = 4 * 4 Sep 19 10:07:52 mirror kernel: [dhd] H2D DMA RD INDX : array size 172 = 4 * 43 Sep 19 10:07:52 mirror kernel: [dhd] dhdpcie_readshared: max H2D queues 40 Sep 19 10:07:52 mirror kernel: [dhd] FW supports debug buf dest ? N Sep 19 10:07:52 mirror kernel: [dhd] dhd_bus_init: Enabling bus->intr_enabled Sep 19 10:07:52 mirror kernel: [dhd] dhdpcie_oob_intr_register OOB irq=149 flags=0x4 Sep 19 10:07:52 mirror kernel: [dhd] dhdpcie_oob_intr_register: enable_irq_wake Sep 19 10:07:52 mirror kernel: [dhd] dhd_bus_start: Initializing 43 h2drings Sep 19 10:07:52 mirror kernel: [dhd] STATIC-MSG) dhd_wlan_mem_prealloc : sectoin 9, size 32896 Sep 19 10:07:52 mirror kernel: [dhd] dhd_prot_init:3925: h2d_max_txpost = 512 Sep 19 10:07:52 mirror kernel: [dhd] dhd_prot_init:3940: MAX_RXBUFPOST = 511 Sep 19 10:07:52 mirror kernel: [dhd] ENABLING DW:0 Sep 19 10:07:52 mirror kernel: [dhd] IDMA not enabled in FW !! Sep 19 10:07:52 mirror kernel: [dhd] IFRM not enabled in FW !! Sep 19 10:07:52 mirror kernel: [dhd] DAR not enabled in FW !! Sep 19 10:07:52 mirror kernel: [dhd] Enable hostcap: EXTD TXS in txcpl Sep 19 10:07:52 mirror kernel: [dhd] dhd_prot_d2h_sync_init(): D2H sync mechanism is NONE Sep 19 10:07:52 mirror kernel: [dhd] dhd_bus_hostready : Read PCICMD Reg: 0x00100006 Sep 19 10:07:52 mirror kernel: [dhd] dhd_bus_dump_dar_registers: dar_clk_ctrl(0xa08:0x10b0040) dar_pwr_ctrl(0xa0c:0x30000) dar_intstat(0xa10:0x0) Sep 19 10:07:52 mirror kernel: [dhd] dhd_bus_dump_dar_registers: dar_errlog(0xa60:0x0) dar_erraddr(0xa64:0x0) dar_pcie_mbint(0xa68:0x0) Sep 19 10:07:52 mirror kernel: [dhd] dhd_bus_hostready: Ring Hostready:1 Sep 19 10:07:52 mirror kernel: [dhd] dhd_send_d2h_ringcreate ringid: 3 idx: 46 max_h2d: 43 Sep 19 10:07:52 mirror kernel: [dhd] dhd_prot_process_d2h_ring_create_complete ring create Response status = 0 ring 3, id 0xfffc Sep 19 10:07:52 mirror kernel: [dhd] info buffer post after ring create Sep 19 10:07:52 mirror kernel: [dhd] wlc_ver_major 12, wlc_ver_minor 1 Sep 19 10:07:52 mirror kernel: [dhd] dhd_get_memdump_info: MEMDUMP ENABLED = 3 Sep 19 10:07:52 mirror kernel: [dhd] dhd_sync_with_dongle: GET_REVINFO device 0x449d, vendor 0x14e4, chipnum 0xaae8 Sep 19 10:07:52 mirror kernel: [dhd] dhd_sync_with_dongle: RxBuf Post : 2048 Sep 19 10:07:52 mirror kernel: [dhd] dhd_preinit_ioctls: preinit_status IOVAR not supported, use legacy preinit Sep 19 10:07:52 mirror kernel: [dhd] dhd_tcpack_suppress_set: TCP ACK Suppress mode 0 -> mode 3 Sep 19 10:07:52 mirror kernel: [dhd] dhd_tcpack_suppress_set: TCPACK_INFO_MAXNUM=10, TCPDATA_INFO_MAXNUM=10 Sep 19 10:07:52 mirror kernel: [dhd] dhd_legacy_preinit_ioctls: hostwake_oob enabled Sep 19 10:07:52 mirror kernel: [dhd] dhd_legacy_preinit_ioctls: use firmware generated mac_address b8:2d:28:42:2a:f4 Sep 19 10:07:52 mirror kernel: [dhd] dhd_os_open_image1: /lib/firmware/ap6275p/clm_bcm43752a2_pcie_ag.blob (30993 bytes) open success Sep 19 10:07:52 mirror kernel: [dhd] dhd_check_current_clm_data: ----- This FW is not included CLM data ----- Sep 19 10:07:52 mirror kernel: [dhd] dhd_check_current_clm_data: ----- This FW is included CLM data ----- Sep 19 10:07:52 mirror kernel: [dhd] Firmware up: op_mode=0x0005, MAC=b8:2d:28:42:2a:f4 Sep 19 10:07:52 mirror kernel: [dhd] dhd_legacy_preinit_ioctls: event_log_max_sets: 26 ret: 0 Sep 19 10:07:52 mirror kernel: [dhd] arp_enable:1 arp_ol:0 Sep 19 10:07:52 mirror kernel: [dhd] Driver: 101.10.361.20 (wlan=r892223-20220701-3) [dhd] Firmware: wl0: Jul 12 2022 18:54:54 version 18.35.387.23.146 (g412cc5ec) FWID 01-93c53be6 [dhd] CLM: 9.9.12_SS (2021-10-12 17:03:28) Sep 19 10:07:52 mirror kernel: [dhd] dhd_pno_init: Support Android Location Service Sep 19 10:07:52 mirror kernel: [dhd] dhd_rtt_init ENTRY Sep 19 10:07:52 mirror kernel: [dhd] rtt_do_get_ioctl: failed to send getbuf proxd iovar (CMD ID : 1), status=-23 Sep 19 10:07:52 mirror kernel: [dhd] dhd_rtt_init : FTM is not supported Sep 19 10:07:52 mirror kernel: [dhd] dhd_rtt_init EXIT, err = 0 Sep 19 10:07:52 mirror kernel: [dhd] [INIT] logset:8 is preserve/chatty Sep 19 10:07:52 mirror kernel: [dhd] [INIT] logset:10 is preserve/chatty Sep 19 10:07:52 mirror kernel: [dhd] dhd_ecounter_autoconfig Ecounter autoconfig in FW not supported Sep 19 10:07:52 mirror kernel: [dhd] dhd_rx_frame: net device is NOT registered. drop event packet Sep 19 10:07:52 mirror kernel: [dhd] dhd_conf_set_country : set country CN, revision 0 Sep 19 10:07:52 mirror kernel: [dhd] dhd_conf_set_country : Country code: CN (CN/0) Sep 19 10:07:52 mirror kernel: [dhd] dhd_bus_devreset: WLAN Power On Done Sep 19 10:07:52 mirror kernel: [dhd] [wlan0] wl_android_wifi_on : Success Sep 19 10:07:52 mirror kernel: [dhd] dhd_rx_frame: net device is NOT registered. drop event packet Sep 19 10:07:52 mirror kernel: [dhd] dhd_bus_aspm_enable_dev: RC ENABLE , linkctrl_before: 0x30110000 linkctrl_after: 0x30110002 Sep 19 10:07:52 mirror kernel: [dhd] dhd_bus_aspm_enable_dev: EP ENABLE , linkctrl_before: 0x10110040 linkctrl_after: 0x10110042 Sep 19 10:07:52 mirror kernel: [dhd] [wlan0] dhd_open : Exit ret=0 Sep 19 10:07:52 mirror kernel: [dhd] [wlan0] dhd_pri_open : tx queue started Sep 19 10:07:52 mirror kernel: [dhd] [wlan0] custom_xps_map_set : Done. mapping cpu Sep 19 10:07:52 mirror kernel: [dhd] dhd_pri_open : mutex is released. Sep 19 10:07:52 mirror kernel: rk_gmac-dwmac fe1c0000.ethernet eth0: PHY [stmmac-1:01] driver [YT8531 Gigabit Ethernet] (irq=POLL) Sep 19 10:07:52 mirror kernel: dwmac4: Master AXI performs any burst length Sep 19 10:07:52 mirror kernel: rk_gmac-dwmac fe1c0000.ethernet eth0: No Safety Features support found Sep 19 10:07:52 mirror kernel: rk_gmac-dwmac fe1c0000.ethernet eth0: IEEE 1588-2008 Advanced Timestamp supported Sep 19 10:07:52 mirror kernel: rk_gmac-dwmac fe1c0000.ethernet eth0: registered PTP clock Sep 19 10:07:52 mirror kernel: rk_gmac-dwmac fe1c0000.ethernet eth0: configuring for phy/rgmii-rxid link mode Sep 19 10:07:52 mirror systemd-networkd[616]: wlan0: Link UP Sep 19 10:07:52 mirror systemd[1]: Started Network Name Resolution. Sep 19 10:07:52 mirror systemd[1]: Reached target Host and Network Name Lookups. Sep 19 10:07:52 mirror systemd-networkd[616]: wlan0: Gained carrier Sep 19 10:07:52 mirror systemd-networkd[616]: eth0: Link UP Sep 19 10:07:52 mirror systemd-networkd[616]: wlan0: Lost carrier Sep 19 10:07:52 mirror kernel: [dhd] P2P interface registered Sep 19 10:07:52 mirror systemd-networkd[616]: wlan0: DHCPv6 lease lost Sep 19 10:07:52 mirror wpa_supplicant[664]: nl80211: kernel reports: Registration to specific type not supported Sep 19 10:07:53 mirror systemd-networkd[616]: wlan0: Gained IPv6LL Sep 19 10:07:54 mirror wpa_supplicant[664]: wlan0: Trying to associate with SSID 'my-ssid' Sep 19 10:07:55 mirror kernel: [dhd] [wlan0] wl_ext_set_chanspec : channel 5g-161(0xe39b) Sep 19 10:07:55 mirror kernel: [dhd] [wlan0] wl_conn_debug_info : Connecting with a0:36:bc:38:86:dc ssid "my-ssid", len (4), channel=5g-161(chan_cnt=1), sec=wpa2/psk/mfpn/aes, rssi=-22 Sep 19 10:07:55 mirror kernel: [dhd] [wlan0] wl_iw_event : Link UP with a0:36:bc:38:86:dc Sep 19 10:07:55 mirror kernel: [dhd] [wlan0] wl_ext_iapsta_event : [S] Link UP with a0:36:bc:38:86:dc Sep 19 10:07:55 mirror kernel: [dhd] [wlan0] wl_bss_connect_done : Report connect result - connection succeeded Sep 19 10:07:55 mirror kernel: NOHZ tick-stop error: Non-RCU local softirq work is pending, handler #08!!! Sep 19 10:07:55 mirror wpa_supplicant[664]: wlan0: Associated with a0:36:bc:38:86:dc Sep 19 10:07:55 mirror wpa_supplicant[664]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Sep 19 10:07:55 mirror kernel: [dhd] dhd_prot_flow_ring_create: Send Flow Create Req flow ID 41 for peer a0:36:bc:38:86:dc prio 3 ifindex 0 items 512 Sep 19 10:07:55 mirror kernel: [dhd] dhd_prot_flow_ring_create_response_process: Flow Create Response status = 0 Flow 41 Sep 19 10:07:55 mirror kernel: NOHZ tick-stop error: Non-RCU local softirq work is pending, handler #08!!! Sep 19 10:07:55 mirror kernel: [dhd] [wlan0] wl_add_keyext : key index (0) Sep 19 10:07:55 mirror wpa_supplicant[664]: wlan0: WPA: Key negotiation completed with a0:36:bc:38:86:dc [PTK=CCMP GTK=CCMP] Sep 19 10:07:55 mirror wpa_supplicant[664]: wlan0: CTRL-EVENT-CONNECTED - Connection to a0:36:bc:38:86:dc completed [id=0 id_str=] Sep 19 10:07:55 mirror systemd-networkd[616]: wlan0: Gained carrier Sep 19 10:07:55 mirror systemd-timesyncd[592]: Network configuration changed, trying to establish connection. Sep 19 10:07:55 mirror kernel: NOHZ tick-stop error: Non-RCU local softirq work is pending, handler #08!!! Sep 19 10:07:55 mirror systemd[1]: systemd-rfkill.service: Deactivated successfully. Sep 19 10:07:56 mirror kernel: NOHZ tick-stop error: Non-RCU local softirq work is pending, handler #08!!! Sep 19 10:07:56 mirror kernel: NOHZ tick-stop error: Non-RCU local softirq work is pending, handler #08!!! Sep 19 10:07:56 mirror kernel: NOHZ tick-stop error: Non-RCU local softirq work is pending, handler #08!!! Sep 19 10:07:56 mirror systemd-networkd[616]: wlan0: DHCPv4 address 192.168.1.132/24 via 192.168.1.1 Sep 19 10:07:56 mirror systemd-timesyncd[592]: Network configuration changed, trying to establish connection. Sep 19 10:07:56 mirror systemd-timesyncd[592]: Network configuration changed, trying to establish connection. Sep 19 10:07:56 mirror systemd-timesyncd[592]: Network configuration changed, trying to establish connection. Sep 19 10:07:57 mirror kernel: NOHZ tick-stop error: Non-RCU local softirq work is pending, handler #08!!! Sep 19 10:07:58 mirror kernel: [dhd] dhd_prot_flow_ring_create: Send Flow Create Req flow ID 40 for peer 33:33:00:00:00:02 prio 0 ifindex 0 items 512 Sep 19 10:07:58 mirror kernel: [dhd] dhd_prot_flow_ring_create_response_process: Flow Create Response status = 0 Flow 40 Sep 19 10:07:58 mirror kernel: NOHZ tick-stop error: Non-RCU local softirq work is pending, handler #08!!! Sep 19 10:07:58 mirror kernel: NOHZ tick-stop error: Non-RCU local softirq work is pending, handler #08!!! Sep 19 10:07:58 mirror kernel: NOHZ tick-stop error: Non-RCU local softirq work is pending, handler #08!!! Sep 19 10:08:20 mirror systemd[1]: systemd-fsckd.service: Deactivated successfully. Sep 19 10:08:24 mirror systemd-timesyncd[592]: Initial synchronization to time server 91.189.91.157:123 (ntp.ubuntu.com). Sep 19 10:08:24 mirror systemd-resolved[663]: Clock change detected. Flushing caches. Sep 19 10:09:50 mirror systemd-networkd-wait-online[655]: Timeout occurred while waiting for network connectivity. Sep 19 10:09:50 mirror systemd[1]: systemd-networkd-wait-online.service: Main process exited, code=exited, status=1/FAILURE Sep 19 10:09:50 mirror systemd[1]: systemd-networkd-wait-online.service: Failed with result 'exit-code'. Sep 19 10:09:50 mirror systemd[1]: Failed to start Wait for Network to be Configured. Sep 19 10:09:50 mirror systemd[1]: Starting Initial cloud-init job (metadata service crawler)... Sep 19 10:09:50 mirror cloud-init[672]: Cloud-init v. 23.2.2-0ubuntu0~22.04.1 running 'init' at Tue, 19 Sep 2023 10:09:50 +0000. Up 129.96 seconds. Sep 19 10:09:50 mirror cloud-init[672]: ci-info: +++++++++++++++++++++++++++++++++++++++Net device info++++++++++++++++++++++++++++++++++++++++ Sep 19 10:09:50 mirror cloud-init[672]: ci-info: +--------+-------+------------------------------+---------------+--------+-------------------+ Sep 19 10:09:50 mirror cloud-init[672]: ci-info: | Device | Up | Address | Mask | Scope | Hw-Address | Sep 19 10:09:50 mirror cloud-init[672]: ci-info: +--------+-------+------------------------------+---------------+--------+-------------------+ Sep 19 10:09:50 mirror cloud-init[672]: ci-info: | eth0 | False | . | . | . | 7e:6c:be:90:fb:12 | Sep 19 10:09:50 mirror cloud-init[672]: ci-info: | lo | True | 127.0.0.1 | 255.0.0.0 | host | . | Sep 19 10:09:50 mirror cloud-init[672]: ci-info: | lo | True | ::1/128 | . | host | . | Sep 19 10:09:50 mirror cloud-init[672]: ci-info: | wlan0 | True | 192.168.1.132 | 255.255.255.0 | global | b8:2d:28:42:2a:f4 | Sep 19 10:09:50 mirror cloud-init[672]: ci-info: | wlan0 | True | fe80::ba2d:28ff:fe42:2af4/64 | . | link | b8:2d:28:42:2a:f4 | Sep 19 10:09:50 mirror cloud-init[672]: ci-info: +--------+-------+------------------------------+---------------+--------+-------------------+ Sep 19 10:09:50 mirror cloud-init[672]: ci-info: ++++++++++++++++++++++++++++++Route IPv4 info+++++++++++++++++++++++++++++++ Sep 19 10:09:50 mirror cloud-init[672]: ci-info: +-------+--------------+-------------+-----------------+-----------+-------+ Sep 19 10:09:50 mirror cloud-init[672]: ci-info: | Route | Destination | Gateway | Genmask | Interface | Flags | Sep 19 10:09:50 mirror cloud-init[672]: ci-info: +-------+--------------+-------------+-----------------+-----------+-------+ Sep 19 10:09:50 mirror cloud-init[672]: ci-info: | 0 | 0.0.0.0 | 192.168.1.1 | 0.0.0.0 | wlan0 | UG | Sep 19 10:09:50 mirror cloud-init[672]: ci-info: | 1 | 192.168.1.0 | 0.0.0.0 | 255.255.255.0 | wlan0 | U | Sep 19 10:09:50 mirror cloud-init[672]: ci-info: | 2 | 192.168.1.1 | 0.0.0.0 | 255.255.255.255 | wlan0 | UH | Sep 19 10:09:50 mirror cloud-init[672]: ci-info: | 3 | 192.168.1.5 | 0.0.0.0 | 255.255.255.255 | wlan0 | UH | Sep 19 10:09:50 mirror cloud-init[672]: ci-info: | 4 | 192.168.1.10 | 0.0.0.0 | 255.255.255.255 | wlan0 | UH | Sep 19 10:09:50 mirror cloud-init[672]: ci-info: +-------+--------------+-------------+-----------------+-----------+-------+ Sep 19 10:09:50 mirror cloud-init[672]: ci-info: +++++++++++++++++++Route IPv6 info+++++++++++++++++++ Sep 19 10:09:50 mirror cloud-init[672]: ci-info: +-------+-------------+---------+-----------+-------+ Sep 19 10:09:50 mirror cloud-init[672]: ci-info: | Route | Destination | Gateway | Interface | Flags | Sep 19 10:09:50 mirror cloud-init[672]: ci-info: +-------+-------------+---------+-----------+-------+ Sep 19 10:09:50 mirror cloud-init[672]: ci-info: | 1 | fe80::/64 | :: | wlan0 | U | Sep 19 10:09:50 mirror cloud-init[672]: ci-info: | 3 | local | :: | wlan0 | U | Sep 19 10:09:50 mirror cloud-init[672]: ci-info: | 4 | multicast | :: | wlan0 | U | Sep 19 10:09:50 mirror cloud-init[672]: ci-info: +-------+-------------+---------+-----------+-------+ Sep 19 10:09:50 mirror cloud-init[672]: 2023-09-19 10:09:50,712 - schema.py[DEPRECATED]: Deprecated cloud-config provided: Sep 19 10:09:50 mirror cloud-init[672]: chpasswd.list: List of ``username:password`` pairs. Each user will have the corresponding password set. A password can be randomly generated by specifying ``RANDOM`` or ``R`` as a user's password. A hashed password, created by a tool like ``mkpasswd``, can be specified. A regex (``r'\$(1|2a|2y|5|6)(\$.+){2}'``) is used to determine if a password value should be treated as a hash. Deprecated in version 22.2. Use ``users`` instead. ```

[mightyguava]

I ended up changing the timeout for systemd-networkd-wait-online to 10 seconds instead of the default 2 minutes. There's nothing in the logs as to why it's failing. Any idea where to file a bug against?

Neither the 1 second delay in netplan-wpa-wlan0.service nor lowering the timeout for the network wait feels right nor satisfying...

[Joshua-Riek]

Thanks for putting some time into this, I don't have a lot of time right now, I'd like to make sure wifi is working normally.

Now I do know that the 2 minute wait is a normal Ubuntu 22.04 thing (however, i find it very annoying, if its safe to set it to 10 seconds i will probably set that as the default configuration). It used to be 5 minutes in Ubuntu 20.04

I did try to edit the delay to 10 seconds over the weekend but did not spend much time on it. Did you just set a timeout in the systemd service?

[mightyguava]

Yeah I did

sudo systemctl edit --full systemd-networkd-wait-online.service

And edited the command to

ExecStart=/usr/lib/systemd/systemd-networkd-wait-online --timeout=10

been stable across a few restarts.

Theoretically this service is supposed to exit successfully as soon as one of the interfaces connects to internet. Does it always hang and fail after 2 minutes for you as well?

[Joshua-Riek]

I did some short testing, and the 2-minute time-out does not happen on my Orange Pi 5B. However, I encountered this on a different device over the weekend, so I know this exists. I don't have time to dig deeper right now, so I can't provide much information.

[Joshua-Riek]

I made a change to overwrite the default link-local address https://github.com/Joshua-Riek/ubuntu-rockchip/commit/6eda35739053c6ac02bdf39e754e5fbc7a0c3a91. Maybe this will help.

build: https://github.com/Joshua-Riek/ubuntu-rockchip/actions/runs/6254729259

reference: https://askubuntu.com/questions/1421785/a-start-job-is-running-for-wait-for-network-to-be-configured-because-ipv6-blocke

[mightyguava]

Oohhhh that makes sense. Great catch. I do not have ipv6.

I switched over to the Ubuntu desktop version and WiFi has been working fine. Getting some more sd cards in a couple days. I’ll flash and test your fix then.

[mightyguava]

I flashed your new image and double checked that link local setting. Still getting a 2 minute wait on start.

[Joshua-Riek]

Whats the output of the command sudo networkctl?

[Joshua-Riek]

Also, have you connected to ethernet?

[mightyguava]

ubuntu@ubuntu:~$ sudo networkctl
IDX LINK TYPE     OPERATIONAL SETUP
  1 lo   loopback carrier     unmanaged
  2 eth0 ether    routable    configured

I was on wifi only earlier. Now I'm on ethernet. Still see the 2min timeout. Weirdly when I connect to ethernet, the wifi device fails to initialize.

[mightyguava]

Okay, now my wlan0 device is refusing to get created at all even without internet. Haven't made any config changes since the last update. It works just fine with the desktop image still so don't think it's a hardware issue.

[mightyguava]

This is reaaally weird, I don't see dhd in my logs anywhere. dmesg | grep dhd is empty. Not in journalctl either. Is that like my install got corrupted?

[Joshua-Riek]

Okay, now my wlan0 device is refusing to get created at all even without internet. Haven't made any config changes since the last update. It works just fine with the desktop image still so don't think it's a hardware issue.

I think you downloaded the Orange Pi 5 image and not the 5B image.

I have been trying to reproduce this on my side with no luck yet.

[mightyguava]

I actually just went and re-flashed the SD card half an hour ago and still have not been getting it to work. Ran this

sudo dd bs=4194304 if=ubuntu-22.04.3-preinstalled-server-arm64-orangepi-5b.img of=/dev/disk4

Put the sd card in. No wlan0 showing up in /sys/class/net.

Gotta go grab dinner. That's probably all the time I have for the day, will look more tomorrow.

[mightyguava]

The weirdest part is that wifi worked on the first boot with your new image. But it hasn’t worked afterwards even after re-imaging

[mightyguava]

Managed to get my wlan0 interface to show up again: the dhd stuff and wlan0 don't show up if I have my Anker USB hub (which has a keyboard & mouse attached) into the lower (closer to the board) of 2 USB-3 sockets. It's fine connected to the upper one, and connecting the keyboard by itself is fine in either socket. I suppose it's probably some obscure driver bug I'm hitting.

[mightyguava]

Diffing the dmesg between the good & bad runs, I see these in the bad run

+rk-pcie fe190000.pcie: PCIe Link Fail
+rk-pcie fe190000.pcie: failed to initialize host

which seems to lead to the rest of the failures.

dmesg diff

```diff --- dmesg-cut.good.txt Fri Sep 22 22:18:31 2023 +++ dmesg-cut.bad.txt Fri Sep 22 22:18:18 2023 @@ -165,24 +165,24 @@ HugeTLB registered 32.0 MiB page size, pre-allocated 0 pages HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages HugeTLB registered 64.0 KiB page size, pre-allocated 0 pages -raid6: neonx8 gen() 5667 MB/s -raid6: neonx8 xor() 4411 MB/s -raid6: neonx4 gen() 5823 MB/s -raid6: neonx4 xor() 4425 MB/s -raid6: neonx2 gen() 5341 MB/s -raid6: neonx2 xor() 4104 MB/s -raid6: neonx1 gen() 4465 MB/s -raid6: neonx1 xor() 3441 MB/s -raid6: int64x8 gen() 1389 MB/s -raid6: int64x8 xor() 905 MB/s -raid6: int64x4 gen() 1712 MB/s -raid6: int64x4 xor() 932 MB/s -raid6: int64x2 gen() 2506 MB/s -raid6: int64x2 xor() 1376 MB/s -raid6: int64x1 gen() 2086 MB/s -raid6: int64x1 xor() 1017 MB/s -raid6: using algorithm neonx4 gen() 5823 MB/s -raid6: .... xor() 4425 MB/s, rmw enabled +raid6: neonx8 gen() 5657 MB/s +raid6: neonx8 xor() 4392 MB/s +raid6: neonx4 gen() 5799 MB/s +raid6: neonx4 xor() 4405 MB/s +raid6: neonx2 gen() 5318 MB/s +raid6: neonx2 xor() 4086 MB/s +raid6: neonx1 gen() 4445 MB/s +raid6: neonx1 xor() 3425 MB/s +raid6: int64x8 gen() 1374 MB/s +raid6: int64x8 xor() 913 MB/s +raid6: int64x4 gen() 1704 MB/s +raid6: int64x4 xor() 928 MB/s +raid6: int64x2 gen() 2497 MB/s +raid6: int64x2 xor() 1369 MB/s +raid6: int64x1 gen() 2075 MB/s +raid6: int64x1 xor() 1013 MB/s +raid6: using algorithm neonx4 gen() 5799 MB/s +raid6: .... xor() 4405 MB/s, rmw enabled raid6: using neon recovery algorithm fiq_debugger fiq_debugger.0: IRQ fiq not found fiq_debugger fiq_debugger.0: IRQ wakeup not found @@ -305,10 +305,10 @@ ntfs: driver 2.1.32 [Flags: R/W NET: Registered protocol family 38 xor: measuring software checksum speed - 8regs : 6494 MB/sec - 32regs : 8615 MB/sec - arm64_neon : 10509 MB/sec -xor: using function: arm64_neon (10509 MB/sec) + 8regs : 6482 MB/sec + 32regs : 8608 MB/sec + arm64_neon : 10598 MB/sec +xor: using function: arm64_neon (10598 MB/sec) Key type asymmetric registered Asymmetric key parser 'x509' registered Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242) @@ -531,12 +531,21 @@ rk-pcie fe190000.pcie: PCIe Linking... LTSSM is 0x3 rk-pcie fe190000.pcie: PCIe Linking... LTSSM is 0x3 rk-pcie fe190000.pcie: PCIe Linking... LTSSM is 0x3 -rk-pcie fe190000.pcie: PCIe Linking... LTSSM is 0x3 dwhdmi-rockchip fde80000.hdmi: don't use dsc mode dwhdmi-rockchip fde80000.hdmi: dw hdmi qp use tmds mode rockchip-hdptx-phy-hdmi fed60000.hdmiphy: bus_width:0x5aa320,bit_rate:5940000 rockchip-hdptx-phy-hdmi fed60000.hdmiphy: hdptx phy lane locked! rk-pcie fe190000.pcie: PCIe Linking... LTSSM is 0x3 +rk-pcie fe190000.pcie: PCIe Linking... LTSSM is 0x3 +rockchip-vop2 fdd90000.vop: [drm:vop2_crtc_atomic_disable +rockchip-vop2 fdd90000.vop: [drm:vop2_crtc_atomic_enable +rockchip-vop2 fdd90000.vop: [drm:vop2_crtc_atomic_enable +rockchip-vop2 fdd90000.vop: [drm:vop2_crtc_atomic_enable +dwhdmi-rockchip fde80000.hdmi: final tmdsclk = 594000000 +dwhdmi-rockchip fde80000.hdmi: don't use dsc mode +dwhdmi-rockchip fde80000.hdmi: dw hdmi qp use tmds mode +rockchip-hdptx-phy-hdmi fed60000.hdmiphy: bus_width:0x5aa320,bit_rate:5940000 +rockchip-hdptx-phy-hdmi fed60000.hdmiphy: hdptx phy lane locked! Console: switching to colour frame buffer device 480x135 rockchip-drm display-subsystem: [drm [drm @@ -726,6 +735,7 @@ ohci-platform fc8c0000.usb: irq 23, io mem 0xfc8c0000 usb usb4: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 5.10 usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=1 +usb 1-1: new high-speed USB device number 2 using ehci-platform usb usb4: Product: Generic Platform OHCI controller usb usb4: Manufacturer: Linux 5.10.160-rockchip ohci_hcd usb usb4: SerialNumber: fc8c0000.usb @@ -761,23 +771,29 @@ usbcore: registered new interface driver usbtouchscreen input: rk805 pwrkey as /devices/platform/feb20000.spi/spi_master/spi2/spi2.0/rk805-pwrkey.5.auto/input/input0 i2c /dev entries driver +usb 1-1: New USB device found, idVendor=05e3, idProduct=0610, bcdDevice= 6.63 rk860-regulator 0-0042: Looking up vin-supply from device tree vdd_cpu_big0_s0: supplied by vcc5v0_sys +usb 1-1: New USB device strings: Mfr=1, Product=2, SerialNumber=0 vcc5v0_sys: could not add device link regulator.29: -ENOENT vdd_cpu_big0_s0: 550 <--> 1050 mV at 800 mV, enabled +usb 1-1: Product: USB2.1 Hub rk860-regulator 0-0043: Looking up vin-supply from device tree vdd_cpu_big1_s0: supplied by vcc5v0_sys +usb 1-1: Manufacturer: GenesysLogic vcc5v0_sys: could not add device link regulator.30: -ENOENT vdd_cpu_big1_s0: 550 <--> 1050 mV at 800 mV, enabled +hub 1-1:1.0: USB hub found Goodix-TS 2-0014: Looking up AVDD28-supply from device tree Goodix-TS 2-0014: Looking up AVDD28-supply property in node /i2c@feaa0000/touchscreen@14 failed Goodix-TS 2-0014: supply AVDD28 not found, using dummy regulator +hub 1-1:1.0: 4 ports detected Goodix-TS 2-0014: Looking up VDDIO-supply from device tree Goodix-TS 2-0014: Looking up VDDIO-supply property in node /i2c@feaa0000/touchscreen@14 failed Goodix-TS 2-0014: supply VDDIO not found, using dummy regulator +usb 4-1: new full-speed USB device number 2 using ohci-platform Goodix-TS 2-0014: i2c test failed attempt 1: -6 Goodix-TS 2-0014: i2c test failed attempt 2: -6 -usb 3-1: new full-speed USB device number 2 using ohci-platform Goodix-TS 2-0014: I2C communication failure: -6 rk860-regulator 2-0042: Looking up vin-supply from device tree vdd_npu_s0: supplied by vcc5v0_sys @@ -786,20 +802,21 @@ typec_fusb302 6-0022: Looking up vbus-supply from device tree rtc-hym8563 6-0051: rtc information is valid rtc-hym8563 6-0051: registered as rtc0 -rtc-hym8563 6-0051: setting system clock to 2021-01-01T12:04:44 UTC (1609502684) +rtc-hym8563 6-0051: setting system clock to 2023-09-23T02:14:27 UTC (1695435267) Goodix-TS 7-0014: Looking up AVDD28-supply from device tree Goodix-TS 7-0014: Looking up AVDD28-supply property in node /i2c@fec90000/touchscreen@14 failed Goodix-TS 7-0014: supply AVDD28 not found, using dummy regulator Goodix-TS 7-0014: Looking up VDDIO-supply from device tree Goodix-TS 7-0014: Looking up VDDIO-supply property in node /i2c@fec90000/touchscreen@14 failed Goodix-TS 7-0014: supply VDDIO not found, using dummy regulator +usb 1-1.3: new full-speed USB device number 3 using ehci-platform +usb 4-1: New USB device found, idVendor=1997, idProduct=2433, bcdDevice= 1.02 Goodix-TS 7-0014: i2c test failed attempt 1: -6 -usb 3-1: New USB device found, idVendor=0c45, idProduct=7691, bcdDevice= 0.65 +usb 4-1: New USB device strings: Mfr=1, Product=2, SerialNumber=0 +usb 4-1: Product: mini keyboard Goodix-TS 7-0014: i2c test failed attempt 2: -6 -usb 3-1: New USB device strings: Mfr=1, Product=2, SerialNumber=0 -usb 3-1: Product: USB Keyboard +usb 4-1: Manufacturer: Goodix-TS 7-0014: I2C communication failure: -6 -usb 3-1: Manufacturer: SONiX rkcifhw fdce0000.rkcif: Adding to iommu group 16 rkcifhw fdce0000.rkcif: No reserved memory region assign to CIF rockchip-mipi-csi2-hw fdd10000.mipi0-csi2-hw: enter mipi csi2 hw probe! @@ -807,9 +824,13 @@ rockchip-mipi-csi2-hw fdd20000.mipi1-csi2-hw: enter mipi csi2 hw probe! rockchip-mipi-csi2-hw fdd20000.mipi1-csi2-hw: probe success, v4l2_dev:mipi1-csi2-hw! rockchip-mipi-csi2-hw fdd30000.mipi2-csi2-hw: enter mipi csi2 hw probe! +usb 1-1.3: New USB device found, idVendor=046d, idProduct=c52b, bcdDevice=24.11 rockchip-mipi-csi2-hw fdd30000.mipi2-csi2-hw: probe success, v4l2_dev:mipi2-csi2-hw! +usb 1-1.3: New USB device strings: Mfr=1, Product=2, SerialNumber=0 rockchip-mipi-csi2-hw fdd40000.mipi3-csi2-hw: enter mipi csi2 hw probe! +usb 1-1.3: Product: USB Receiver rockchip-mipi-csi2-hw fdd40000.mipi3-csi2-hw: probe success, v4l2_dev:mipi3-csi2-hw! +usb 1-1.3: Manufacturer: Logitech rockchip-mipi-csi2-hw fdd50000.mipi4-csi2-hw: enter mipi csi2 hw probe! rockchip-mipi-csi2-hw fdd50000.mipi4-csi2-hw: probe success, v4l2_dev:mipi4-csi2-hw! rockchip-mipi-csi2-hw fdd60000.mipi5-csi2-hw: enter mipi csi2 hw probe! @@ -830,15 +851,16 @@ cpu cpu0: bin=0 cpu cpu0: leakage=10 cpu cpu0: Looking up cpu-supply from device tree -cpu cpu0: pvtm=1458 -cpu cpu0: pvtm-volt-sel=2 +cpu cpu0: pvtm=1459 +cpu cpu0: pvtm-volt-sel=3 +usb 1-1.4: new full-speed USB device number 4 using ehci-platform cpu cpu0: Looking up cpu-supply from device tree cpu cpu0: Looking up mem-supply from device tree cpu cpu4: Looking up cpu-supply from device tree cpu cpu4: bin=0 cpu cpu4: leakage=9 cpu cpu4: Looking up cpu-supply from device tree -cpu cpu4: pvtm=1690 +cpu cpu4: pvtm=1691 cpu cpu4: pvtm-volt-sel=4 cpu cpu4: Looking up cpu-supply from device tree cpu cpu4: Looking up mem-supply from device tree @@ -865,6 +887,10 @@ cpu cpu6: EM: OPP:600000 is inefficient cpu cpu6: EM: created perf domain cpu cpu6: l=10000 h=85000 hyst=5000 l_limit=0 h_limit=2208000000 h_table=0 +usb 1-1.4: New USB device found, idVendor=0c45, idProduct=7691, bcdDevice= 0.65 +usb 1-1.4: New USB device strings: Mfr=1, Product=2, SerialNumber=0 +usb 1-1.4: Product: USB Keyboard +usb 1-1.4: Manufacturer: SONiX sdhci: Secure Digital Host Controller Interface driver sdhci: Copyright(c) Pierre Ossman Synopsys Designware Multimedia Card Interface Driver @@ -878,20 +904,35 @@ SMCCC: SOC_ID: ARCH_SOC_ID not implemented, skipping .... cryptodev: driver 1.12 loaded. hid: raw HID events driver (C) Jiri Kosina -input: SONiX USB Keyboard as /devices/platform/fc840000.usb/usb3/3-1/3-1:1.0/0003:0C45:7691.0001/input/input1 +input: mini keyboard as /devices/platform/fc8c0000.usb/usb4/4-1/4-1:1.0/0003:1997:2433.0001/input/input1 mmc0: SDHCI controller on fe2e0000.mmc [fe2e0000.mmc -hid-generic 0003:0C45:7691.0001: input,hidraw0: USB HID v1.11 Keyboard [SONiX USB Keyboard mmc0: Host Software Queue enabled +hid-generic 0003:1997:2433.0001: input,hidraw0: USB HID v1.01 Keyboard [ mini keyboard mmc0: new HS400 Enhanced strobe MMC card at address 0001 +input: mini keyboard Mouse as /devices/platform/fc8c0000.usb/usb4/4-1/4-1:1.1/0003:1997:2433.0002/input/input2 mmcblk0: mmc0:0001 DV4064 58.2 GiB +input: mini keyboard System Control as /devices/platform/fc8c0000.usb/usb4/4-1/4-1:1.1/0003:1997:2433.0002/input/input3 mmcblk0boot0: mmc0:0001 DV4064 partition 1 4.00 MiB -input: SONiX USB Keyboard Consumer Control as /devices/platform/fc840000.usb/usb3/3-1/3-1:1.1/0003:0C45:7691.0002/input/input2 mmcblk0boot1: mmc0:0001 DV4064 partition 2 4.00 MiB mmcblk0rpmb: mmc0:0001 DV4064 partition 3 16.0 MiB, chardev (237:0) mmcblk0: p1 p2 -input: SONiX USB Keyboard System Control as /devices/platform/fc840000.usb/usb3/3-1/3-1:1.1/0003:0C45:7691.0002/input/input3 -input: SONiX USB Keyboard as /devices/platform/fc840000.usb/usb3/3-1/3-1:1.1/0003:0C45:7691.0002/input/input4 -hid-generic 0003:0C45:7691.0002: input,hidraw1: USB HID v1.11 Keyboard [SONiX USB Keyboard +input: mini keyboard Consumer Control as /devices/platform/fc8c0000.usb/usb4/4-1/4-1:1.1/0003:1997:2433.0002/input/input4 +hid-generic 0003:1997:2433.0002: input,hidraw1: USB HID v1.01 Mouse [ mini keyboard +input: Logitech USB Receiver as /devices/platform/fc800000.usb/usb1/1-1/1-1.3/1-1.3:1.0/0003:046D:C52B.0003/input/input5 +hid-generic 0003:046D:C52B.0003: input,hidraw2: USB HID v1.11 Keyboard [Logitech USB Receiver +input: Logitech USB Receiver Mouse as /devices/platform/fc800000.usb/usb1/1-1/1-1.3/1-1.3:1.1/0003:046D:C52B.0004/input/input6 +input: Logitech USB Receiver Consumer Control as /devices/platform/fc800000.usb/usb1/1-1/1-1.3/1-1.3:1.1/0003:046D:C52B.0004/input/input7 +input: Logitech USB Receiver System Control as /devices/platform/fc800000.usb/usb1/1-1/1-1.3/1-1.3:1.1/0003:046D:C52B.0004/input/input8 +hid-generic 0003:046D:C52B.0004: input,hiddev96,hidraw3: USB HID v1.11 Mouse [Logitech USB Receiver +hid-generic 0003:046D:C52B.0005: hiddev97,hidraw4: USB HID v1.11 Device [Logitech USB Receiver +input: SONiX USB Keyboard as /devices/platform/fc800000.usb/usb1/1-1/1-1.4/1-1.4:1.0/0003:0C45:7691.0006/input/input10 +rk-pcie fe190000.pcie: PCIe Link Fail +rk-pcie fe190000.pcie: failed to initialize host +hid-generic 0003:0C45:7691.0006: input,hidraw5: USB HID v1.11 Keyboard [SONiX USB Keyboard +input: SONiX USB Keyboard Consumer Control as /devices/platform/fc800000.usb/usb1/1-1/1-1.4/1-1.4:1.1/0003:0C45:7691.0007/input/input11 +input: SONiX USB Keyboard System Control as /devices/platform/fc800000.usb/usb1/1-1/1-1.4/1-1.4:1.1/0003:0C45:7691.0007/input/input12 +input: SONiX USB Keyboard as /devices/platform/fc800000.usb/usb1/1-1/1-1.4/1-1.4:1.1/0003:0C45:7691.0007/input/input13 +hid-generic 0003:0C45:7691.0007: input,hidraw6: USB HID v1.11 Keyboard [SONiX USB Keyboard usbcore: registered new interface driver usbhid usbhid: USB HID core driver ashmem: initialized @@ -905,11 +946,11 @@ rockchip-pm-domain fd8d8000.power-management:power-controller: Looking up audio-supply from device tree rockchip-pm-domain fd8d8000.power-management:power-controller: Looking up audio-supply property in node /power-management@fd8d8000/power-controller failed debugfs: File 'Capture' in directory 'dapm' already present! -input: rockchip-dp0 rockchip-dp0 as /devices/platform/dp0-sound/sound/card0/input5 -input: rockchip-hdmi0 rockchip-hdmi0 as /devices/platform/hdmi0-sound/sound/card1/input6 -input: headset-keys as /devices/platform/es8388-sound/input/input7 +input: rockchip-dp0 rockchip-dp0 as /devices/platform/dp0-sound/sound/card0/input14 +input: rockchip-hdmi0 rockchip-hdmi0 as /devices/platform/hdmi0-sound/sound/card1/input15 +input: headset-keys as /devices/platform/es8388-sound/input/input16 ES8323 6-0010: ASoC: error at soc_component_write_no_lock on ES8323.6-0010: -5 -input: rockchip-es8388 Headset as /devices/platform/es8388-sound/sound/card2/input8 +input: rockchip-es8388 Headset as /devices/platform/es8388-sound/sound/card2/input17 Initializing XFRM netlink socket NET: Registered protocol family 17 [BT_RFKILL @@ -923,162 +964,136 @@ [WLAN_RFKILL [WLAN_RFKILL [WLAN_RFKILL -rk-pcie fe190000.pcie: PCIe Link up, LTSSM is 0x30011 -[WLAN_RFKILL -[WLAN_RFKILL -rk-pcie fe190000.pcie: PCI host bridge to bus 0004:40 +[WLAN_RFKILL +[WLAN_RFKILL [BT_RFKILL -pci_bus 0004:40: root bus resource [bus 40-4f [BT_RFKILL -pci_bus 0004:40: root bus resource [??? 0xf4000000-0xf40fffff flags 0x0 [BT_RFKILL -pci_bus 0004:40: root bus resource [io 0x0000-0xfffff [BT_RFKILL -pci_bus 0004:40: root bus resource [mem 0xf4200000-0xf4ffffff Key type dns_resolver registered -pci_bus 0004:40: root bus resource [mem 0xa00000000-0xa3fffffff pref Key type ceph registered -pci 0004:40:00.0: [1d87:3588 libceph: loaded (mon/osd proto 15/24) -pci 0004:40:00.0: reg 0x38: [mem 0x00000000-0x0000ffff pref registered taskstats version 1 -pci 0004:40:00.0: supports D1 D2 vendor storage:20190527 ret = 0 Loading compiled-in X.509 certificates -pci 0004:40:00.0: PME# supported from D0 D1 D3hot -pci 0004:40:00.0: Primary bus is hard wired to 0 Btrfs loaded, crc32c=crc32c-generic -pci 0004:40:00.0: bridge configuration invalid ([bus 01-ff -pci 0004:41:00.0: [14e4:449d pstore: Using crash dump compression: deflate -pci 0004:41:00.0: reg 0x10: [mem 0x00000000-0x0000ffff 64bit rga3_core0 fdb60000.rga: Adding to iommu group 2 -pci 0004:41:00.0: reg 0x18: [mem 0x00000000-0x003fffff 64bit rga: rga3_core0, irq = 35, match scheduler -pci 0004:41:00.0: supports D1 D2 rga: rga3_core0 hardware loaded successfully, hw_version:3.0.76831. -pci 0004:41:00.0: PME# supported from D0 D1 D2 D3hot D3cold -pci_bus 0004:41: busn_res: [bus 41-4f rga: rga3_core0 probe successfully -pci 0004:40:00.0: BAR 8: assigned [mem 0xf4200000-0xf47fffff rga3_core1 fdb70000.rga: Adding to iommu group 3 -pci 0004:40:00.0: BAR 6: assigned [mem 0xf4800000-0xf480ffff pref -pci 0004:41:00.0: BAR 2: assigned [mem 0xf4400000-0xf47fffff 64bit -pci 0004:41:00.0: BAR 0: assigned [mem 0xf4200000-0xf420ffff 64bit rga: rga3_core1, irq = 36, match scheduler -pci 0004:40:00.0: PCI bridge to [bus 41 rga: rga3_core1 hardware loaded successfully, hw_version:3.0.76831. -pci 0004:40:00.0: bridge window [mem 0xf4200000-0xf47fffff -pcieport 0004:40:00.0: PME: Signaling with IRQ 148 rga: rga3_core1 probe successfully rga: rga2, irq = 37, match scheduler rga: rga2 hardware loaded successfully, hw_version:3.2.63318. rga: rga2 probe successfully rga_iommu: IOMMU binding successfully, default mapping core[0x1 rga: Module initialized. v1.2.27 +rockchip-pm-domain fd8d8000.power-management:power-controller: Looking up gpu-supply from device tree +rockchip-pm-domain fd8d8000.power-management:power-controller: Looking up gpu-supply property in node /power-management@fd8d8000/power-controller failed reg-fixed-voltage combophy-avdd0v85: Looking up vin-supply from device tree combophy_avdd0v85: supplied by vdd_0v85_s0 +mali fb000000.gpu: Kernel DDK version g18p0-01eac0 vdd_0v85_s0: could not add device link regulator.32: -ENOENT -rockchip-pm-domain fd8d8000.power-management:power-controller: Looking up gpu-supply from device tree -rockchip-pm-domain fd8d8000.power-management:power-controller: Looking up gpu-supply property in node /power-management@fd8d8000/power-controller failed +mali fb000000.gpu: Looking up mali-supply from device tree +mali fb000000.gpu: Looking up mem-supply from device tree combophy_avdd0v85: 850 mV, enabled -mali fb000000.gpu: Kernel DDK version g18p0-01eac0 +mali fb000000.gpu: Looking up mali-supply from device tree reg-fixed-voltage combophy-avdd0v85: combophy_avdd0v85 supplying 850000uV -mali fb000000.gpu: Looking up mali-supply from device tree reg-fixed-voltage combophy-avdd1v8: Looking up vin-supply from device tree -mali fb000000.gpu: Looking up mem-supply from device tree combophy_avdd1v8: supplied by avcc_1v8_s0 -mali fb000000.gpu: Looking up mali-supply from device tree +vdd_gpu_s0: could not add device link fb000000.gpu: -EEXIST avcc_1v8_s0: could not add device link regulator.33: -ENOENT -vdd_gpu_s0: could not add device link fb000000.gpu: -EEXIST vdd_gpu_s0: Failed to create debugfs directory mali fb000000.gpu: Looking up mem-supply from device tree vdd_gpu_s0: could not add device link fb000000.gpu: -EEXIST vdd_gpu_s0: Failed to create debugfs directory mali fb000000.gpu: Looking up mali-supply from device tree -combophy_avdd1v8: 1800 mV, enabled vdd_gpu_s0: could not add device link fb000000.gpu: -EEXIST vdd_gpu_s0: Failed to create debugfs directory +combophy_avdd1v8: 1800 mV, enabled reg-fixed-voltage combophy-avdd1v8: combophy_avdd1v8 supplying 1800000uV mali fb000000.gpu: bin=0 vcc_3v3_sd_s0: 3300 mV, disabled reg-fixed-voltage vcc-3v3-sd-s0: Looking up vin-supply from device tree vcc_3v3_sd_s0: supplied by vcc_3v3_s3 mali fb000000.gpu: leakage=15 -reg-fixed-voltage vcc-3v3-sd-s0: vcc_3v3_sd_s0 supplying 3300000uV mali fb000000.gpu: Looking up mali-supply from device tree debugfs: Directory 'fb000000.gpu-mali' with parent 'vdd_gpu_s0' already present! +reg-fixed-voltage vcc-3v3-sd-s0: vcc_3v3_sd_s0 supplying 3300000uV rockchip-dmc dmc: Looking up center-supply from device tree rockchip-dmc dmc: Looking up mem-supply from device tree rockchip-dmc dmc: Looking up center-supply from device tree -mali fb000000.gpu: pvtm=903 vdd_ddr_s0: could not add device link dmc: -EEXIST vdd_ddr_s0: Failed to create debugfs directory rockchip-dmc dmc: Looking up mem-supply from device tree -mali fb000000.gpu: pvtm-volt-sel=4 vdd_log_s0: could not add device link dmc: -EEXIST +mali fb000000.gpu: pvtm=903 vdd_log_s0: Failed to create debugfs directory rockchip-dmc dmc: bin=0 +mali fb000000.gpu: pvtm-volt-sel=4 +rockchip-dmc dmc: leakage=35 mali fb000000.gpu: avs=0 -rockchip-dmc dmc: leakage=35 +rockchip-dmc dmc: leakage-volt-sel=1 W : [File -rockchip-dmc dmc: leakage-volt-sel=1 rockchip-dmc dmc: soc version=0, speed=1 rockchip-dmc dmc: avs=0 -mali fb000000.gpu: r0p0 status 5 not found in HW issues table; rockchip-dmc dmc: current ATF version 0x100 rockchip-dmc dmc: normal_rate = 1560000000 -mali fb000000.gpu: falling back to closest match: r0p0 status 0 -mali fb000000.gpu: Execution proceeding normally with fallback match rockchip-dmc dmc: reboot_rate = 2112000000 -mali fb000000.gpu: GPU identified as 0x7 arch 10.8.6 r0p0 status 0 rockchip-dmc dmc: suspend_rate = 528000000 -mali fb000000.gpu: No priority control manager is configured rockchip-dmc dmc: video_4k_rate = 1560000000 rockchip-dmc dmc: video_4k_10b_rate = 1560000000 -mali fb000000.gpu: No memory group manager is configured rockchip-dmc dmc: video_svep_rate = 1560000000 rockchip-dmc dmc: boost_rate = 2112000000 rockchip-dmc dmc: fixed_rate(isp|cif0|cif1|dualview) = 2112000000 rockchip-dmc dmc: performance_rate = 2112000000 rockchip-dmc dmc: hdmirx_rate = 2112000000 -mali fb000000.gpu: Protected memory allocator not available rockchip-dmc dmc: failed to get vop bandwidth to dmc rate -mali fb000000.gpu: Capping CSF_FIRMWARE_TIMEOUT to CSF_FIRMWARE_PING_TIMEOUT rockchip-dmc dmc: failed to get vop pn to msch rl rockchip-dmc dmc: l=10000 h=2147483647 hyst=5000 l_limit=0 h_limit=0 h_table=0 -mali fb000000.gpu: l=10000 h=85000 hyst=5000 l_limit=0 h_limit=800000000 h_table=0 rockchip-dmc dmc: could not find power_model node -mali fb000000.gpu: Probed as mali0 -input: adc-keys as /devices/platform/adc-keys/input/input9 +mali fb000000.gpu: r0p0 status 5 not found in HW issues table; +mali fb000000.gpu: falling back to closest match: r0p0 status 0 +input: adc-keys as /devices/platform/adc-keys/input/input18 +mali fb000000.gpu: Execution proceeding normally with fallback match dwmmc_rockchip fe2c0000.mmc: No normal pinctrl state rockchip-pm-domain fd8d8000.power-management:power-controller: Looking up sdio-supply from device tree +rockchip-pm-domain fd8d8000.power-management:power-controller: Looking up sdio-supply property in node /power-management@fd8d8000/power-controller failed +rockchip-pm-domain fd8d8000.power-management:power-controller: Looking up pcie-supply from device tree +rockchip-pm-domain fd8d8000.power-management:power-controller: Looking up pcie-supply property in node /power-management@fd8d8000/power-controller failed +rockchip-pm-domain fd8d8000.power-management:power-controller: Looking up npu-supply from device tree +rockchip-pm-domain fd8d8000.power-management:power-controller: Looking up npu-supply property in node /power-management@fd8d8000/power-controller failed RKNPU fdab0000.npu: Adding to iommu group 0 RKNPU fdab0000.npu: RKNPU: rknpu iommu is enabled, using iommu mode RKNPU fdab0000.npu: Looking up rknpu-supply from device tree -dwmmc_rockchip fe2c0000.mmc: No idle pinctrl state -rockchip-pm-domain fd8d8000.power-management:power-controller: Looking up sdio-supply property in node /power-management@fd8d8000/power-controller failed -dwmmc_rockchip fe2c0000.mmc: IDMAC supports 32-bit address mode. -dwmmc_rockchip fe2c0000.mmc: Using internal DMA controller. -dwmmc_rockchip fe2c0000.mmc: Version ID is 270a -dwmmc_rockchip fe2c0000.mmc: DW MMC controller at irq 101,32 bit host data width,256 deep fifo -rockchip-pm-domain fd8d8000.power-management:power-controller: Looking up pcie-supply from device tree RKNPU fdab0000.npu: Looking up mem-supply from device tree RKNPU fdab0000.npu: can't request region for resource [mem 0xfdab0000-0xfdabffff RKNPU fdab0000.npu: can't request region for resource [mem 0xfdac0000-0xfdacffff RKNPU fdab0000.npu: can't request region for resource [mem 0xfdad0000-0xfdadffff [drm -dwmmc_rockchip fe2c0000.mmc: Looking up vmmc-supply from device tree -rockchip-pm-domain fd8d8000.power-management:power-controller: Looking up pcie-supply property in node /power-management@fd8d8000/power-controller failed -dwmmc_rockchip fe2c0000.mmc: Looking up vqmmc-supply from device tree -rockchip-pm-domain fd8d8000.power-management:power-controller: Looking up npu-supply from device tree -rockchip-pm-domain fd8d8000.power-management:power-controller: Looking up npu-supply property in node /power-management@fd8d8000/power-controller failed rockchip-pm-domain fd8d8000.power-management:power-controller: Looking up nputop-supply from device tree rockchip-pm-domain fd8d8000.power-management:power-controller: Looking up nputop-supply property in node /power-management@fd8d8000/power-controller failed rockchip-pm-domain fd8d8000.power-management:power-controller: Looking up npu1-supply from device tree rockchip-pm-domain fd8d8000.power-management:power-controller: Looking up npu1-supply property in node /power-management@fd8d8000/power-controller failed +mali fb000000.gpu: GPU identified as 0x7 arch 10.8.6 r0p0 status 0 rockchip-pm-domain fd8d8000.power-management:power-controller: Looking up npu2-supply from device tree +mali fb000000.gpu: No priority control manager is configured +mali fb000000.gpu: No memory group manager is configured +mali fb000000.gpu: Protected memory allocator not available +mali fb000000.gpu: Capping CSF_FIRMWARE_TIMEOUT to CSF_FIRMWARE_PING_TIMEOUT +mali fb000000.gpu: l=10000 h=85000 hyst=5000 l_limit=0 h_limit=800000000 h_table=0 +dwmmc_rockchip fe2c0000.mmc: No idle pinctrl state +dwmmc_rockchip fe2c0000.mmc: IDMAC supports 32-bit address mode. +mali fb000000.gpu: Probed as mali0 rockchip-pm-domain fd8d8000.power-management:power-controller: Looking up npu2-supply property in node /power-management@fd8d8000/power-controller failed +dwmmc_rockchip fe2c0000.mmc: Using internal DMA controller. +dwmmc_rockchip fe2c0000.mmc: Version ID is 270a +dwmmc_rockchip fe2c0000.mmc: DW MMC controller at irq 101,32 bit host data width,256 deep fifo +dwmmc_rockchip fe2c0000.mmc: Looking up vmmc-supply from device tree +dwmmc_rockchip fe2c0000.mmc: Looking up vqmmc-supply from device tree RKNPU fdab0000.npu: Looking up rknpu-supply from device tree vdd_npu_s0: could not add device link fdab0000.npu: -EEXIST vdd_npu_s0: Failed to create debugfs directory @@ -1091,12 +1106,12 @@ RKNPU fdab0000.npu: RKNPU: bin=0 RKNPU fdab0000.npu: leakage=8 RKNPU fdab0000.npu: Looking up rknpu-supply from device tree +mmc_host mmc1: Bus speed (slot 0) = 400000Hz (slot req 400000Hz, actual 400000HZ div = 0) debugfs: Directory 'fdab0000.npu-rknpu' with parent 'vdd_npu_s0' already present! -mmc_host mmc1: Bus speed (slot 0) = 400000Hz (slot req 400000Hz, actual 400000HZ div = 0) RKNPU fdab0000.npu: pvtm=896 RKNPU fdab0000.npu: pvtm-volt-sel=4 +vccio_sd_s0: ramp_delay not set RKNPU fdab0000.npu: avs=0 -vccio_sd_s0: ramp_delay not set RKNPU fdab0000.npu: l=10000 h=85000 hyst=5000 l_limit=0 h_limit=800000000 h_table=0 mmc_host mmc1: Bus speed (slot 0) = 148500000Hz (slot req 150000000Hz, actual 148500000HZ div = 0) RKNPU fdab0000.npu: failed to find power_model node @@ -1106,8 +1121,8 @@ dwmmc_rockchip fe2c0000.mmc: Successfully tuned phase to 135 mmc1: new ultra high speed SDR104 SDXC card at address 59b4 mmcblk1: mmc1:59b4 SD64G 59.4 GiB + mmcblk1: p1 p2 cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' - mmcblk1: p1 p2 rockchip-pm rockchip-suspend: not set pwm-regulator-config rockchip-suspend not set sleep-mode-config for mem-lite rockchip-suspend not set wakeup-config for mem-lite @@ -1131,7 +1146,6 @@ cgroup_memory=1 async_tx: api initialized (async) EXT4-fs (mmcblk1p2): mounted filesystem with ordered data mode. Opts: (null) -systemd[1 NET: Registered protocol family 10 Segment Routing with IPv6 systemd[1 @@ -1186,188 +1200,14 @@ systemd[1 systemd[1 systemd[1 -systemd[1 -systemd-journald[435 -systemd-journald[435 device-mapper: ioctl: 4.44.0-ioctl (2021-02-01) initialised: dm-devel@redhat.com +systemd-journald[453 Adding 2097148k swap on /swapfile. Priority:-2 extents:6 across:2473980k SS -[dhd -[dhd -[dhd - drivers/net/wireless/rockchip_wlan/rkwifi/bcmdhd compiled on Sep 11 2023 at 20:31:55 - -dwmmc_rockchip fe2c0000.mmc: Unexpected interrupt latency -[dhd -[dhd -[WLAN_RFKILL -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -pcieh 0004:41:00.0: enabling device (0000 -> 0002) -[dhd -[dhd +systemd-journald[453 [BT_RFKILL -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd FAT-fs (mmcblk1p1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive! [BT_RFKILL [BT_RFKILL -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd - Firmware: wl0: Jul 12 2022 18:54:54 version 18.35.387.23.146 (g412cc5ec) FWID 01-93c53be6 - CLM: 9.9.12_SS (2021-10-12 17:03:28) -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd - drivers/net/wireless/rockchip_wlan/rkwifi/bcmdhd compiled on Sep 11 2023 at 20:31:55 - -[dhd - -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd -[WLAN_RFKILL -[WLAN_RFKILL -wifi power off -[WLAN_RFKILL -[dhd -[dhd -[dhd -[dhd -[dhd -[dhd rk_gmac-dwmac fe1c0000.ethernet eth0: PHY [stmmac-1:01 dwmac4: Master AXI performs any burst length rk_gmac-dwmac fe1c0000.ethernet eth0: No Safety Features support found @@ -1399,9 +1239,6 @@ usb usb8: SerialNumber: xhci-hcd.9.auto hub 8-0:1.0: USB hub found hub 8-0:1.0: 1 port detected -[dhd -[dhd -[dhd squashfs: version 4.0 (2009/01/31) Phillip Lougher [BT_RFKILL ttyFIQ ttyFIQ0: tty_port_close_start: tty->count = 1 port count = 2 @@ -1419,7 +1256,5 @@ Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Bluetooth: BNEP filters: protocol multicast Bluetooth: BNEP socket layer initialized -[dhd -[dhd -[dhd +systemd-journald[453 ```

I thought about this maybe being a power draw issue, but the hub is only powering a keyboard & mouse... and I don't have any of these problems when booting into the desktop image... I assume the destkop image has the same drivers and kernel as the server image...?

[Joshua-Riek]

I would take all and any kernel messages with a grain of salt. The kernel is an absolute hackjob of a mess, but since I can not reproduce this, I would say your power supply is bad. Or your router has some weird internet configuration.

[mightyguava]

The power supply being suspect makes sense, but I'm having the same issue with both a brand new OrangePi 5V 4A charger as well as an Anker 65W PD charger. Router issues shouldn't cause the wlan0 interface to not initialize.

My router is OPNSense running a standard configuration, no VLANs or other fancy stuff, just DHCPv4.

Anyways, here's the networkctl output you asked for running your new image.

ubuntu@ubuntu:~$ sudo networkctl
IDX LINK  TYPE     OPERATIONAL SETUP
  1 lo    loopback carrier     unmanaged
  2 eth0  ether    routable    configured
  3 wlan0 wlan     off         unmanaged

Still had to wait for that 2 minutes...

[Joshua-Riek]

I'm not sure what I can do since I can not reproduce this issue on my end.

Now, I could add the below as default, but I fear this could cause issues with other network configurations.

ExecStart=/usr/lib/systemd/systemd-networkd-wait-online --timeout=10

[mightyguava]

Outside of the pcie weirdness, I think there were 2 concrete things:

Add an override for netplan-wpa-wlan0.service

[Service]
ExecStartPre=/bin/sleep 1

And the timeout=10 you had above yeah. Thanks for helping me debug these issues!

[Joshua-Riek]

Of course, but to confirm, is wifi working for you without any modification?

[mightyguava]

No.

After flashing the server image, configuring netplan with my SSID/pw, no other modifications

WiFi does not work on boot or restarts. I have to manually run systemctl restart systemd-networkd or netplan apply after the system boots.

Adding the /bin/sleep 1 override to netplan-wpa-wlan0.service

WiFi is working after boot, but blocks 2 minutes on systemd-networkd-wait-online

Further adding --timeout=10 to systemd-networkd-wait-online

WiFi works after boot, but blocks 10 seconds on systemd-networkd-wait-online.

Using the desktop image

Have not encountered any issues

[Joshua-Riek]

I did some testing on my Orange Pi 5B and could not reproduce this issue. WiFi works as expected on the server image. My guess is your network-config is not setup correctly.

[mightyguava]

My network-config was posted at the start of this issue and I have not changed it. Since it’s a timing issue I wonder if my install being on emmc might contribute to it. I’m happy to move on though as there are workarounds and things are working now. Thanks for your help!

[Joshua-Riek]

Hey, I'm sorry you're still having an issue with this, but I can not reproduce it as it works fine on my end. So I will be closing this.

[phisch]

I have the same issue on my Orange Pi 5B. Running netplan apply after boot connects just fine to my wifi, but it does not connect at boot.

edit: I "solved" this issue by adding @reboot /usr/sbin/netplan apply to my crontab. Really hope to find out what causes this issue though.