search

espressif / esp-hosted

Hosted Solution (Linux/MCU) with ESP32 (Wi-Fi + BT + BLE)
Other
706 stars 169 forks source link

[Question][NG Mode, iMX8QXP Host, SPI mode] NetworkManager Or WPA Suppliciant to managment wifi connection #478

Open nguyenlkdn opened 2 months ago

nguyenlkdn commented 2 months ago

Hi all! I am comparing performance between NetworkManager and WPA Supplicant services to manage WiFi network interface for ESP NG

Host

Slave

Software

I found an issues with NetworkManager to wifi connect to AP broascast on channel 1 It always connect failed with reason 201 image

nmcli -t dev wifi connect VIETTEL_AP_8CE000 password 1234567890a
Sep  5 11:44:18 gridania-pvt1-soc kernel: [ 2126.216462] esp32_spi: cmd_auth_request: Authentication request: 30:4f:75:8c:e0:08 1 0 0 0
Sep  5 11:44:20 gridania-pvt1-soc kernel: [ 2128.343267] esp32_spi: process_disconnect_event: Disconnect event for ssid VIETTEL_AP_8CE000 [reason:201]
Sep  5 11:44:20 gridania-pvt1-soc kernel: [ 2128.444054] prepare_command_request code: 4
Sep  5 11:44:25 gridania-pvt1-soc kernel: [ 2133.345838] prepare_command_request code: 6
Sep  5 11:44:25 gridania-pvt1-soc kernel: [ 2133.871614] prepare_command_request code: 4
Sep  5 11:44:27 gridania-pvt1-soc kernel: [ 2135.576906] prepare_command_request code: 11
Sep  5 11:44:27 gridania-pvt1-soc kernel: [ 2135.576937] esp32_spi: cmd_auth_request: Authentication request: 30:4f:75:8c:e0:08 1 0 0 0
Sep  5 11:44:29 gridania-pvt1-soc kernel: [ 2137.699479] esp32_spi: process_disconnect_event: Disconnect event for ssid VIETTEL_AP_8CE000 [reason:201]

After i changed AP to broadcase on other channel ex. 10 image

It was able to connect successfully

root@gridania-pvt1-soc:~# nmcli dev wifi connect VIETTEL_AP_8CE000 password 1234567890a
Device 'wlan0' successfully activated with 'c4f09538-d512-4005-aee1-bd2afac2f0e8'.
Sep  5 11:55:31 gridania-pvt1-soc kernel: [ 2799.175164] prepare_command_request code: 6
Sep  5 11:55:31 gridania-pvt1-soc kernel: [ 2799.224946] esp32_spi: process_disconnect_event: Disconnect event for ssid VIETTEL_AP_8CE000 [reason:3]
Sep  5 11:55:31 gridania-pvt1-soc kernel: [ 2799.225077] esp32_spi: esp_reg_notifier: cfg80211 regulatory domain callback for 00, current=VN
Sep  5 11:55:31 gridania-pvt1-soc kernel: [ 2799.225087] prepare_command_request code: 18
Sep  5 11:55:31 gridania-pvt1-soc kernel: [ 2799.239659] prepare_command_request code: 15
Sep  5 11:55:31 gridania-pvt1-soc kernel: [ 2799.256375] prepare_command_request code: 13
Sep  5 11:55:31 gridania-pvt1-soc kernel: [ 2799.269801] esp32_spi: esp_inetaddr_event: Interface Down: 0
Sep  5 11:55:31 gridania-pvt1-soc kernel: [ 2799.300516] prepare_command_request code: 15
Sep  5 11:55:31 gridania-pvt1-soc kernel: [ 2799.390447] prepare_command_request code: 4
Sep  5 11:55:33 gridania-pvt1-soc kernel: [ 2801.745847] prepare_command_request code: 4
Sep  5 11:55:35 gridania-pvt1-soc kernel: [ 2803.454739] prepare_command_request code: 11
Sep  5 11:55:35 gridania-pvt1-soc kernel: [ 2803.454771] esp32_spi: cmd_auth_request: Authentication request: 30:4f:75:8c:e0:08 10 0 0 0
Sep  5 11:55:35 gridania-pvt1-soc kernel: [ 2803.906366] prepare_command_request code: 12
Sep  5 11:55:35 gridania-pvt1-soc kernel: [ 2803.906400] esp32_spi: cmd_assoc_request: Association request: 30:4f:75:8c:e0:08 10 35
Sep  5 11:55:35 gridania-pvt1-soc kernel: [ 2803.948415] esp32_spi: process_assoc_event: Connection status: 0
Sep  5 11:55:35 gridania-pvt1-soc kernel: [ 2803.948536] esp32_spi: esp_reg_notifier: cfg80211 regulatory domain callback for VN, current=00
Sep  5 11:55:35 gridania-pvt1-soc kernel: [ 2803.948549] prepare_command_request code: 18
Sep  5 11:55:35 gridania-pvt1-soc kernel: [ 2803.969629] prepare_command_request code: 15
Sep  5 11:55:36 gridania-pvt1-soc kernel: [ 2804.043909] prepare_command_request code: 8
Sep  5 11:55:36 gridania-pvt1-soc kernel: [ 2804.059203] prepare_command_request code: 10
Sep  5 11:55:36 gridania-pvt1-soc kernel: [ 2804.077356] prepare_command_request code: 8
Sep  5 11:55:36 gridania-pvt1-soc kernel: [ 2804.117638] prepare_command_request code: 27
Sep  5 11:55:36 gridania-pvt1-soc kernel: [ 2804.141148] prepare_command_request code: 15
Sep  5 11:55:36 gridania-pvt1-soc kernel: [ 2804.167818] prepare_command_request code: 15
Sep  5 11:55:36 gridania-pvt1-soc kernel: [ 2804.391703] esp32_spi: esp_inetaddr_event: NETDEV_UP interface wlan0 ip changed to  192.168.100.012
Sep  5 11:55:36 gridania-pvt1-soc kernel: [ 2804.391721] prepare_command_request code: 13
Sep  5 11:55:36 gridania-pvt1-soc kernel: [ 2804.427995] prepare_command_request code: 14
Sep  5 11:55:36 gridania-pvt1-soc kernel: [ 2804.526154] prepare_command_request code: 27
Sep  5 11:55:36 gridania-pvt1-soc kernel: [ 2804.549524] prepare_command_request code: 27
Sep  5 11:55:37 gridania-pvt1-soc kernel: [ 2805.206766] prepare_command_request code: 27
Sep  5 11:55:37 gridania-pvt1-soc kernel: [ 2805.236272] prepare_command_request code: 27
Sep  5 11:55:37 gridania-pvt1-soc kernel: [ 2806.006467] prepare_command_request code: 27
Sep  5 11:55:38 gridania-pvt1-soc kernel: [ 2806.030453] prepare_command_request code: 27
root@gridania-pvt1-soc:~# ifconfig wlan0
wlan0: flags=4163<UP,BROADCAST,RUNNING,MULTICAST>  mtu 1500
        inet 192.168.100.12  netmask 255.255.255.0  broadcast 192.168.100.255
        ether dc:da:0c:30:c1:0c  txqueuelen 1000  (Ethernet)
        RX packets 17828  bytes 11074452 (10.5 MiB)
        RX errors 0  dropped 593  overruns 0  frame 0
        TX packets 17897  bytes 12611828 (12.0 MiB)
        TX errors 0  dropped 29 overruns 0  carrier 0  collisions 0

For the other functions it looks work very well on both NetworkManager and WPA Supplicant I refer to use NetworkManager because it support a lot of management tasks such as easy to connect, store AP configs easier...

So which one is the best choose for manage our wifi interface and ESPNG full compatible with both services?

mantriyogesh commented 2 months ago

@kapilkedawat ++

kapilkedawat commented 2 months ago

could you please share wpa_supplicant logs as well?

nguyenlkdn commented 2 months ago

@kapilkedawat

Wifi Ap broadcast on channel 1 Using WPA Supplicant

Kernel Log

Sep  6 12:02:05 gridania-pvt1-soc kernel: [   35.908244] esp32_spi: process_esp_bootup_event: Received ESP bootup event
Sep  6 12:02:05 gridania-pvt1-soc kernel: [   35.908271] esp32_spi: process_event_esp_bootup: Bootup Event tag: 3
Sep  6 12:02:05 gridania-pvt1-soc kernel: [   35.908282] esp32_spi: esp_validate_chipset: Chipset=ESP32-S3 ID=09 detected over SPI
Sep  6 12:02:05 gridania-pvt1-soc kernel: [   35.908289] esp32_spi: process_event_esp_bootup: Bootup Event tag: 2
Sep  6 12:02:05 gridania-pvt1-soc kernel: [   35.908295] esp32_spi: process_event_esp_bootup: Bootup Event tag: 0
Sep  6 12:02:05 gridania-pvt1-soc kernel: [   35.908302] esp32_spi: process_event_esp_bootup: Bootup Event tag: 1
Sep  6 12:02:05 gridania-pvt1-soc kernel: [   35.908308] esp32_spi: process_fw_data: ESP chipset's last reset cause:
Sep  6 12:02:05 gridania-pvt1-soc kernel: [   35.908314] esp32_spi: print_reset_reason: POWERON_RESET
Sep  6 12:02:05 gridania-pvt1-soc kernel: [   35.908326] esp32_spi: check_esp_version: ESP-Hosted Version: NG-1.0.3.0.0
Sep  6 12:02:05 gridania-pvt1-soc kernel: [   35.908800] esp32_spi: esp_reg_notifier: Driver init is ongoing
Sep  6 12:02:05 gridania-pvt1-soc kernel: [   35.908887] prepare_command_request code: 1
Sep  6 12:02:05 gridania-pvt1-soc kernel: [   35.985482] prepare_command_request code: 3
Sep  6 12:02:05 gridania-pvt1-soc kernel: [   36.010517] prepare_command_request code: 15
Sep  6 12:02:05 gridania-pvt1-soc kernel: [   36.029197] esp32_spi: print_capabilities: Capabilities: 0xa0. Features supported are:
Sep  6 12:02:05 gridania-pvt1-soc kernel: [   36.029211] esp32_spi: print_capabilities:      * WLAN on SPI
Sep  6 12:02:05 gridania-pvt1-soc kernel: [   36.063171] prepare_command_request code: 15
Sep  6 12:02:05 gridania-pvt1-soc kernel: [   36.122216] prepare_command_request code: 15
Sep  6 12:02:14 gridania-pvt1-soc kernel: [   45.232264] prepare_command_request code: 15
Sep  6 12:02:14 gridania-pvt1-soc kernel: [   45.261501] prepare_command_request code: 15
Sep  6 12:02:15 gridania-pvt1-soc kernel: [   45.281979] prepare_command_request code: 15
Sep  6 12:02:15 gridania-pvt1-soc kernel: [   45.297511] prepare_command_request code: 15
Sep  6 12:02:15 gridania-pvt1-soc kernel: [   45.322644] prepare_command_request code: 15
Sep  6 12:02:15 gridania-pvt1-soc kernel: [   45.466850] prepare_command_request code: 4
Sep  6 12:02:22 gridania-pvt1-soc kernel: [   52.904245] prepare_command_request code: 4
Sep  6 12:02:30 gridania-pvt1-soc kernel: [   60.344369] prepare_command_request code: 4
Sep  6 12:02:32 gridania-pvt1-soc kernel: [   62.796312] prepare_command_request code: 11
Sep  6 12:02:32 gridania-pvt1-soc kernel: [   62.796349] esp32_spi: cmd_auth_request: Authentication request: 30:4f:75:8c:e0:08 1 0 0 0
Sep  6 12:02:32 gridania-pvt1-soc kernel: [   63.218866] esp32_spi: decode_common_resp: [0xb] Command failed
Sep  6 12:02:32 gridania-pvt1-soc kernel: [   63.218891] esp32_spi: cmd_auth_request: wait_and_decode_cmd_resp(priv, cmd_node) failure, ret: -1
Sep  6 12:02:33 gridania-pvt1-soc kernel: [   63.319557] prepare_command_request code: 4
Sep  6 12:02:35 gridania-pvt1-soc kernel: [   65.749956] prepare_command_request code: 11
Sep  6 12:02:35 gridania-pvt1-soc kernel: [   65.749990] esp32_spi: cmd_auth_request: Authentication request: 30:4f:75:8c:e0:08 1 0 0 0
Sep  6 12:02:36 gridania-pvt1-soc kernel: [   66.288614] prepare_command_request code: 12
Sep  6 12:02:36 gridania-pvt1-soc kernel: [   66.288646] esp32_spi: cmd_assoc_request: Association request: 30:4f:75:8c:e0:08 1 35
Sep  6 12:02:36 gridania-pvt1-soc kernel: [   66.406214] esp32_spi: process_assoc_event: Connection status: 0
Sep  6 12:02:36 gridania-pvt1-soc kernel: [   66.406349] esp32_spi: esp_reg_notifier: cfg80211 regulatory domain callback for VN, current=
Sep  6 12:02:36 gridania-pvt1-soc kernel: [   66.429500] prepare_command_request code: 15
Sep  6 12:02:36 gridania-pvt1-soc kernel: [   66.519003] prepare_command_request code: 27
Sep  6 12:02:36 gridania-pvt1-soc kernel: [   66.553438] prepare_command_request code: 27
Sep  6 12:02:36 gridania-pvt1-soc kernel: [   66.704052] prepare_command_request code: 8
Sep  6 12:02:36 gridania-pvt1-soc kernel: [   66.731595] prepare_command_request code: 10
Sep  6 12:02:36 gridania-pvt1-soc kernel: [   66.753339] prepare_command_request code: 8
Sep  6 12:02:36 gridania-pvt1-soc kernel: [   66.805646] prepare_command_request code: 15
Sep  6 12:02:38 gridania-pvt1-soc kernel: [   68.312630] esp32_spi: esp_inetaddr_event: NETDEV_UP interface wlan0 ip changed to  192.168.100.012
Sep  6 12:02:38 gridania-pvt1-soc kernel: [   68.312653] prepare_command_request code: 13
Sep  6 12:02:38 gridania-pvt1-soc kernel: [   68.340021] prepare_command_request code: 14
Sep  6 12:02:38 gridania-pvt1-soc kernel: [   68.356928] prepare_command_request code: 14
Sep  6 12:02:38 gridania-pvt1-soc kernel: [   68.607079] prepare_command_request code: 27
Sep  6 12:02:38 gridania-pvt1-soc kernel: [   68.634927] prepare_command_request code: 27
Sep  6 12:02:40 gridania-pvt1-soc kernel: [   70.672183] prepare_command_request code: 27
Sep  6 12:02:40 gridania-pvt1-soc kernel: [   70.725911] prepare_command_request code: 27
Sep  6 12:02:42 gridania-pvt1-soc kernel: [   72.763522] prepare_command_request code: 27
Sep  6 12:02:42 gridania-pvt1-soc kernel: [   72.802984] prepare_command_request code: 27
Sep  6 12:02:44 gridania-pvt1-soc kernel: [   74.845602] prepare_command_request code: 27
Sep  6 12:02:44 gridania-pvt1-soc kernel: [   74.890624] prepare_command_request code: 27
Sep  6 12:02:46 gridania-pvt1-soc kernel: [   76.921679] prepare_command_request code: 27
Sep  6 12:02:46 gridania-pvt1-soc kernel: [   76.960937] prepare_command_request code: 27
Sep  6 12:02:48 gridania-pvt1-soc kernel: [   78.993586] prepare_command_request code: 27
Sep  6 12:02:48 gridania-pvt1-soc kernel: [   79.027769] prepare_command_request code: 27

Wpa Service Log:

rfkill: Cannot open RFKILL control device
wlan0: SME: Trying to authenticate with 30:4f:75:8c:e0:08 (SSID='VIETTEL_AP_8CE000' freq=2412 MHz)
wlan0: SME: Authentication request to the driver failed
wlan0: SME: Trying to authenticate with 30:4f:75:8c:e0:08 (SSID='VIETTEL_AP_8CE000' freq=2412 MHz)
wlan0: Trying to associate with 30:4f:75:8c:e0:08 (SSID='VIETTEL_AP_8CE000' freq=2412 MHz)
wlan0: Associated with 30:4f:75:8c:e0:08
wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=VN
wlan0: WPA: Key negotiation completed with 30:4f:75:8c:e0:08 [PTK=CCMP GTK=CCMP]
wlan0: CTRL-EVENT-CONNECTED - Connection to 30:4f:75:8c:e0:08 completed [id=5 id_str=]
wlan0: CTRL-EVENT-DISCONNECTED bssid=30:4f:75:8c:e0:08 reason=4
wlan0: SME: Trying to authenticate with 30:4f:75:8c:e0:08 (SSID='VIETTEL_AP_8CE000' freq=2412 MHz)
wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD
wlan0: SME: Trying to authenticate with 30:4f:75:8c:e0:08 (SSID='VIETTEL_AP_8CE000' freq=2457 MHz)
wlan0: Trying to associate with 30:4f:75:8c:e0:08 (SSID='VIETTEL_AP_8CE000' freq=2457 MHz)
wlan0: Associated with 30:4f:75:8c:e0:08
wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=VN
wlan0: WPA: Key negotiation completed with 30:4f:75:8c:e0:08 [PTK=CCMP GTK=CCMP]
wlan0: CTRL-EVENT-CONNECTED - Connection to 30:4f:75:8c:e0:08 completed [id=5 id_str=]

ESP32S3 Log:

ESP-ROM:esp32s3-20210327
Build:Mar 27 2021
rst:0x1 (POWERON),boot:0xb (SPI_FAST_FLASH_BOOT)
SPIWP:0xee
mode:DIO, clock div:1
load:0x3fce3818,len:0x1754
load:0x403c9700,len:0x4
load:0x403c9704,len:0xbac
load:0x403cc700,len:0x2d7c
entry 0x403c98ec
I (27) boot: ESP-IDF v5.1.3-dirty 2nd stage bootloader
I (27) boot: compile time Aug 26 2024 15:11:52
W (27) boot: Unicore bootloader
I (30) boot: chip revision: v0.2
I (34) boot.esp32s3: Boot SPI Speed : 80MHz
I (39) boot.esp32s3: SPI Mode       : DIO
I (44) boot.esp32s3: SPI Flash Size : 4MB
I (48) boot: Enabling RNG early entropy source...
I (54) boot: Partition Table:
I (57) boot: ## Label            Usage          Type ST Offset   Length
I (65) boot:  0 nvs              WiFi data        01 02 00009000 00004000
I (72) boot:  1 otadata          OTA data         01 00 0000d000 00002000
I (79) boot:  2 phy_init         RF data          01 01 0000f000 00001000
I (87) boot:  3 factory          factory app      00 00 00010000 00100000
I (94) boot:  4 ota_0            OTA app          00 10 00110000 00100000
I (102) boot:  5 ota_1            OTA app          00 11 00210000 00100000
I (109) boot: End of partition table
I (114) boot: Defaulting to factory image
I (118) esp_image: segment 0: paddr=00010020 vaddr=3c080020 size=1e124h (123172) map
I (149) esp_image: segment 1: paddr=0002e14c vaddr=3fc96800 size=01ecch (  7884) load
I (151) esp_image: segment 2: paddr=00030020 vaddr=42000020 size=70e94h (462484) map
I (238) esp_image: segment 3: paddr=000a0ebc vaddr=3fc986cc size=01e04h (  7684) load
I (244) esp_image: segment 4: paddr=000a2cc8 vaddr=40374000 size=1278ch ( 75660) load
I (269) boot: Loaded app from partition at offset 0x10000
I (274) boot: Disabling RNG early entropy source...
I (280) cpu_start: Unicore app
I (281) cpu_start: Pro cpu up.
I (281) cpu_start: Single core mode
I (293) cpu_start: Pro cpu start user code
I (293) cpu_start: cpu freq: 240000000 Hz
I (293) cpu_start: Application information:
I (296) cpu_start: Project name:     network_adapter
I (301) cpu_start: App version:      NG-1.0.3.0.0
I (307) cpu_start: Compile time:     Aug 26 2024 15:22:05
I (313) cpu_start: ELF file SHA256:  2e7c46558b7cc4c4...
I (319) cpu_start: ESP-IDF:          v5.1.3-dirty
I (324) cpu_start: Min chip rev:     v0.0
I (329) cpu_start: Max chip rev:     v0.99 
I (334) cpu_start: Chip rev:         v0.2
I (338) heap_init: Initializing. RAM available for dynamic allocation:
I (346) heap_init: At 3FC9DB08 len 0004BC08 (303 KiB): DRAM
I (352) heap_init: At 3FCE9710 len 00005724 (21 KiB): STACK/DRAM
I (359) heap_init: At 3FCF0000 len 00008000 (32 KiB): DRAM
I (365) heap_init: At 600FE010 len 00001FD8 (7 KiB): RTCRAM
I (372) spi_flash: detected chip: winbond
I (376) spi_flash: flash io: dio
I (380) sleep: Configure to isolate all GPIO pins in sleep state
I (386) sleep: Enable automatic switching of GPIO sleep configuration
I (394) app_start: Starting scheduler on CPU0
I (398) main_task: Started on CPU0
I (402) main_task: Calling app_main()
I (406) stats: *********************************************************************
I (414) stats:                 ESP-Hosted Firmware version :: NG-1.0.3.0.0                        
I (424) stats:                 Transport used :: SPI only                      
I (432) stats: *********************************************************************
I (441) FW_MAIN: Supported features are:
I (445) FW_MAIN: - WLAN over SPI
I (449) FW_MAIN: Capabilities: 0xa0
I (457) pp: pp rom version: e7ae62f
I (458) net80211: net80211 rom version: e7ae62f
I (464) wifi:wifi driver task: 3fca410c, prio:23, stack:6656, core=0
I (469) wifi:wifi firmware version: d23b633
I (473) wifi:wifi certification version: v7.0
I (477) wifi:config NVS flash: disabled
I (480) wifi:config nano formating: disabled
I (484) wifi:Init data frame dynamic rx buffer num: 64
I (489) wifi:Init static rx mgmt buffer num: 5
I (493) wifi:Init management short buffer num: 32
I (498) wifi:Init dynamic tx buffer num: 64
I (502) wifi:Init static tx FG buffer num: 2
I (506) wifi:Init static rx buffer size: 1600
I (510) wifi:Init static rx buffer num: 16
I (513) wifi:Init dynamic rx buffer num: 64
I (518) wifi_init: rx ba win: 32
I (521) wifi_init: tcpip mbox: 32
I (525) wifi_init: udp mbox: 6
I (529) wifi_init: tcp mbox: 6
I (533) wifi_init: tcp tx win: 5760
I (537) wifi_init: tcp rx win: 5760
I (541) wifi_init: tcp mss: 1440
I (545) wifi_init: WiFi IRAM OP enabled
I (549) wifi_init: WiFi RX IRAM OP enabled
I (554) FW_SPI: Using SPI interface
I (558) gpio: GPIO[7]| InputEn: 0| OutputEn: 1| OpenDrain: 0| Pullup: 0| Pulldown: 0| Intr:0 
I (568) gpio: GPIO[8]| InputEn: 0| OutputEn: 1| OpenDrain: 0| Pullup: 0| Pulldown: 0| Intr:0 
I (1578) FW_MAIN: Initial set up done
I (1578) main_task: Returned from app_main()
I (1599) FW_MAIN: INIT Interface command

I (1599) phy_init: phy_version 640,cd64a1a,Jan 24 2024,17:28:12
I (1634) wifi:mode : sta (dc:da:0c:30:c1:0c)
I (1635) wifi:enable tsf
I (1636) FW_CMD: station started and disabled softap mode
I (1670) FW_MAIN: Get MAC command

I (1696) FW_MAIN: Get Tx power command

I (1751) FW_MAIN: Get Tx power command

I (1791) FW_MAIN: Get Tx power command

I (10987) FW_MAIN: Get Tx power command

I (11010) FW_MAIN: Get Tx power command

I (11026) FW_MAIN: Get Tx power command

I (11049) FW_MAIN: Get Tx power command

I (11066) FW_MAIN: Get Tx power command

I (11239) FW_MAIN: Scan request

I (13686) FW_MAIN: Auth request

I (14087) FW_CMD: Connecting to VIETTEL_AP_8CE000, channel: 1 [3]
I (14088) FW_CMD: AUTH Commit

E (14139) wifi:null wpa_sta_ap_set_rsnxe
I (14140) wifi:new:<1,0>, old:<1,0>, ap:<255,255>, sta:<1,0>, prof:1
I (14140) wifi:state: init -> auth (b0)
I (15142) wifi:state: auth -> init (200)
I (15143) wifi:new:<1,0>, old:<1,0>, ap:<255,255>, sta:<1,0>, prof:1
I (15143) FW_CMD: STA disconnected [2]

I (15146) FW_CMD: STA Disconnect event: 2

I (15151) FW_CMD: cleanup_ap_bssid
I (15274) FW_MAIN: Scan request

I (20178) FW_MAIN: disconnect request

I (20178) FW_CMD: Disconnect request: reason [3], interface=0

I (20706) FW_MAIN: Scan request

I (23138) FW_MAIN: Auth request

I (23539) FW_CMD: Connecting to VIETTEL_AP_8CE000, channel: 1 [3]
I (23540) FW_CMD: AUTH Commit

E (23550) wifi:null wpa_sta_ap_set_rsnxe
I (23551) wifi:new:<1,0>, old:<1,0>, ap:<255,255>, sta:<1,0>, prof:1
I (23551) wifi:state: init -> auth (b0)
I (23563) FW_CMD: handle_wpa_sta_rx_mgmt:711 Auth[11] recvd

I (23593) FW_MAIN: Assoc request

I (23593) wifi:state: auth -> assoc (0)
I (24150) FW_CMD: handle_wpa_sta_rx_mgmt:719 ASSOC Resp[1] recvd

I (24150) FW_CMD: STA connect event [channel 1]

I (24151) BSSID: 0x3fca59de   30 4f 75 8c e0 08                                 |0Ou...|
I (24160) wifi:state: assoc -> run (10)
I (24202) FW_MAIN: REG set command
I (24230) FW_MAIN: Get Tx power command

I (24258) FW_MAIN: RSSI command

I (24290) FW_MAIN: RSSI command

I (24573) FW_MAIN: Add key request

I (24573) FW_CMD: set_key_internal:1541

I (24573) FW_CMD: Setting PTK 

I (24598) FW_MAIN: Set default key request

I (24616) FW_MAIN: Add key request

I (24616) FW_CMD: set_key_internal:1541

I (24616) FW_CMD: Setting GTK [1]

I (24618) wifi:connected with VIETTEL_AP_8CE000, aid = 3, channel 1, BW20, bssid = 30:4f:75:8c:e0:08
I (24626) wifi:security: WPA2-PSK, phy: bgn, rssi: -60
I (24631) wifi:pm start, type: 1

I (24634) wifi:dp: 1, bi: 102400, li: 3, scale listen interval from 307200 us to 307200 us
I (24643) wifi:set rx beacon pti, rx_bcn_pti: 0, bcn_timeout: 25000, mt_pti: 0, mt_time: 10000
I (24651) FW_CMD: Wifi Station Connected event!! 

I (24685) wifi:AP's beacon interval = 102400 us, DTIM period = 1
I (24693) FW_MAIN: Get Tx power command

I (25128) wifi:<ba-add>idx:0 (ifx:0, 30:4f:75:8c:e0:08), tid:7, ssn:3, winSize:64
I (25566) FW_MAIN: Set IP Address

I (25586) FW_MAIN: Set multicast mac address list

I (25598) FW_MAIN: Set multicast mac address list

I (26343) FW_MAIN: RSSI command

I (26374) FW_MAIN: RSSI command

I (28438) FW_MAIN: RSSI command

I (28466) FW_MAIN: RSSI command

I (30510) FW_MAIN: RSSI command

I (30549) FW_MAIN: RSSI command

I (32614) FW_MAIN: RSSI command

I (32634) FW_MAIN: RSSI command

I (34690) FW_MAIN: RSSI command

I (34714) FW_MAIN: RSSI command

I (36762) FW_MAIN: RSSI command

I (36783) FW_MAIN: RSSI command

I (38830) FW_MAIN: RSSI command

I (38860) FW_MAIN: RSSI command

I (40910) FW_MAIN: RSSI command

I (40934) FW_MAIN: RSSI command

I (42981) FW_MAIN: RSSI command

I (43006) FW_MAIN: RSSI command

I (45058) FW_MAIN: RSSI command

I (45081) FW_MAIN: RSSI command

Wifi Ap broadcast on channel 10 Using WPA Supplicant

Kernel Log

[   94.657772] esp32_spi: process_esp_bootup_event: Received ESP bootup event
[   94.657798] esp32_spi: process_event_esp_bootup: Bootup Event tag: 3
[   94.657808] esp32_spi: esp_validate_chipset: Chipset=ESP32-S3 ID=09 detected over SPI
[   94.657815] esp32_spi: process_event_esp_bootup: Bootup Event tag: 2
[   94.657822] esp32_spi: process_event_esp_bootup: Bootup Event tag: 0
[   94.657829] esp32_spi: process_event_esp_bootup: Bootup Event tag: 1
[   94.657835] esp32_spi: process_fw_data: ESP chipset's last reset cause:
[   94.657842] esp32_spi: print_reset_reason: POWERON_RESET
[   94.657853] esp32_spi: check_esp_version: ESP-Hosted Version: NG-1.0.3.0.0
[   94.658314] esp32_spi: esp_reg_notifier: Driver init is ongoing
[   94.658401] prepare_command_request code: 1
[   94.716726] prepare_command_request code: 3
[   94.741274] prepare_command_request code: 15
[   94.764835] esp32_spi: print_capabilities: Capabilities: 0xa0. Features supported are:
[   94.764848] esp32_spi: print_capabilities:    * WLAN on SPI
[   94.792835] prepare_command_request code: 15
[   94.832299] prepare_command_request code: 15
[  104.033834] prepare_command_request code: 15
[  104.057106] prepare_command_request code: 15
[  104.077689] prepare_command_request code: 15
[  104.093139] prepare_command_request code: 15
[  104.115523] prepare_command_request code: 15
[  104.270501] prepare_command_request code: 4
[  106.715582] prepare_command_request code: 11
[  106.715615] esp32_spi: cmd_auth_request: Authentication request: 30:4f:75:8c:e0:08 10 0 0 0
[  107.167075] prepare_command_request code: 12
[  107.167104] esp32_spi: cmd_assoc_request: Association request: 30:4f:75:8c:e0:08 10 35
[  107.199549] esp32_spi: process_assoc_event: Connection status: 0
[  107.199682] esp32_spi: esp_reg_notifier: cfg80211 regulatory domain callback for VN, current=\x00\x00
[  107.199694] prepare_command_request code: 18
[  107.213366] prepare_command_request code: 15
[  107.276003] NOHZ tick-stop error: Non-RCU local softirq work is pending, handler #08!!!
[  107.288253] NOHZ tick-stop error: Non-RCU local softirq work is pending, handler #08!!!
[  107.291522] prepare_command_request code: 8
[  107.319174] prepare_command_request code: 10
[  107.338879] prepare_command_request code: 8
[  107.389424] prepare_command_request code: 15
[  107.796754] esp32_spi: esp_inetaddr_event: NETDEV_UP interface wlan0 ip changed to  192.168.100.012
[  107.796775] prepare_command_request code: 13
[  107.825539] prepare_command_request code: 14
[  107.850440] prepare_command_request code: 14
[  109.188024] prepare_command_request code: 27
[  109.220744] prepare_command_request code: 27
[  111.266043] prepare_command_request code: 27
[  111.303491] prepare_command_request code: 27
[  113.337871] prepare_command_request code: 27
[  113.378586] prepare_command_request code: 27
[  115.422808] prepare_command_request code: 27
[  115.463773] prepare_command_request code: 27
[  117.500208] prepare_command_request code: 27
[  117.541074] prepare_command_request code: 27
[  119.577206] prepare_command_request code: 27
[  119.618207] prepare_command_request code: 27
[  121.652818] prepare_command_request code: 27
[  121.688056] prepare_command_request code: 27
[  123.737603] prepare_command_request code: 27
[  123.781367] prepare_command_request code: 27
[  125.813593] prepare_command_request code: 27
[  125.853500] prepare_command_request code: 27
[  127.885754] prepare_command_request code: 27
[  127.916994] prepare_command_request code: 27

WPA Service Log

rfkill: Cannot open RFKILL control device
wlan0: SME: Trying to authenticate with 30:4f:75:8c:e0:08 (SSID='VIETTEL_AP_8CE000' freq=2457 MHz)
wlan0: Trying to associate with 30:4f:75:8c:e0:08 (SSID='VIETTEL_AP_8CE000' freq=2457 MHz)
wlan0: Associated with 30:4f:75:8c:e0:08
wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=VN
wlan0: WPA: Key negotiation completed with 30:4f:75:8c:e0:08 [PTK=CCMP GTK=CCMP]
wlan0: CTRL-EVENT-CONNECTED - Connection to 30:4f:75:8c:e0:08 completed [id=5 id_str=]

Ah i see that if AP broadcast on channel 1, WPA Supplicant also got some unexpected error on the first time authenticate , after retry 2nd time it successes.

I tried several times no any problem. It always success on the first time authentication for AP Channel 10, and always need 2nd time to success authen on channel 1.

kapilkedawat commented 2 months ago

Hi @nguyenlkdn, from the logs, it looks like AP has sent deauth with reason code 2(WLAN_REASON_PREV_AUTH_NOT_VALID), this could be due to the fact that station was previously connected to AP and then your reboot it. What happens at third attempt?

nguyenlkdn commented 2 months ago

Hi @nguyenlkdn, from the logs, it looks like AP has sent deauth with reason code 2(WLAN_REASON_PREV_AUTH_NOT_VALID), this could be due to the fact that station was previously connected to AP and then your reboot it. What happens at third attempt?

I always reboot the board to perform the connection, so any previous connections cannot be established.

Which procedure do you want me to attempt for 3rd?

I performed third time by restart wpa service and It always authencator failed by 201 code. I had seen that ESP disconnect immediately at time i stopped wpa supplicant.

Sep  6 12:52:25 gridania-pvt1-soc kernel: [ 2380.872955] prepare_command_request code: 27
Sep  6 12:52:26 gridania-pvt1-soc kernel: [ 2381.727060] prepare_command_request code: 6
Sep  6 12:52:26 gridania-pvt1-soc kernel: [ 2381.772890] prepare_command_request code: 13
Sep  6 12:52:26 gridania-pvt1-soc kernel: [ 2381.778907] prepare_command_request code: 14
Sep  6 12:52:26 gridania-pvt1-soc kernel: [ 2381.785519] esp32_spi: process_disconnect_event: Disconnect event for ssid VIETTEL_AP_8CE000 [reason:3]
Sep  6 12:52:26 gridania-pvt1-soc kernel: [ 2381.791237] esp32_spi: esp_inetaddr_event: Interface Down: 0
Sep  6 12:52:26 gridania-pvt1-soc kernel: [ 2381.796771] esp32_spi: esp_reg_notifier: cfg80211 regulatory domain callback for 00, current=VN
Sep  6 12:52:26 gridania-pvt1-soc kernel: [ 2381.796791] prepare_command_request code: 18
Sep  6 12:52:26 gridania-pvt1-soc kernel: [ 2381.832457] prepare_command_request code: 15
Sep  6 12:52:51 gridania-pvt1-soc kernel: [ 2406.498444] prepare_command_request code: 15
Sep  6 12:52:51 gridania-pvt1-soc kernel: [ 2406.525344] prepare_command_request code: 15
Sep  6 12:52:51 gridania-pvt1-soc kernel: [ 2406.548750] prepare_command_request code: 15
Sep  6 12:52:51 gridania-pvt1-soc kernel: [ 2406.566129] prepare_command_request code: 15
Sep  6 12:52:51 gridania-pvt1-soc kernel: [ 2406.591436] prepare_command_request code: 15
Sep  6 12:52:51 gridania-pvt1-soc kernel: [ 2406.725350] prepare_command_request code: 4
Sep  6 12:52:58 gridania-pvt1-soc kernel: [ 2413.440158] prepare_command_request code: 4
Sep  6 12:53:04 gridania-pvt1-soc kernel: [ 2420.154969] prepare_command_request code: 4

And after restart wpa suppliciant it alway authen failed. 

Sep  6 12:52:25 gridania-pvt1-soc kernel: [ 2380.872955] prepare_command_request code: 27
Sep  6 12:52:26 gridania-pvt1-soc kernel: [ 2381.727060] prepare_command_request code: 6
Sep  6 12:52:26 gridania-pvt1-soc kernel: [ 2381.772890] prepare_command_request code: 13
Sep  6 12:52:26 gridania-pvt1-soc kernel: [ 2381.778907] prepare_command_request code: 14
Sep  6 12:52:26 gridania-pvt1-soc kernel: [ 2381.785519] esp32_spi: process_disconnect_event: Disconnect event for ssid VIETTEL_AP_8CE000 [reason:3]
Sep  6 12:52:26 gridania-pvt1-soc kernel: [ 2381.791237] esp32_spi: esp_inetaddr_event: Interface Down: 0
Sep  6 12:52:26 gridania-pvt1-soc kernel: [ 2381.796771] esp32_spi: esp_reg_notifier: cfg80211 regulatory domain callback for 00, current=VN
Sep  6 12:52:26 gridania-pvt1-soc kernel: [ 2381.796791] prepare_command_request code: 18
Sep  6 12:52:26 gridania-pvt1-soc kernel: [ 2381.832457] prepare_command_request code: 15
Sep  6 12:52:51 gridania-pvt1-soc kernel: [ 2406.498444] prepare_command_request code: 15
Sep  6 12:52:51 gridania-pvt1-soc kernel: [ 2406.525344] prepare_command_request code: 15
Sep  6 12:52:51 gridania-pvt1-soc kernel: [ 2406.548750] prepare_command_request code: 15
Sep  6 12:52:51 gridania-pvt1-soc kernel: [ 2406.566129] prepare_command_request code: 15
Sep  6 12:52:51 gridania-pvt1-soc kernel: [ 2406.591436] prepare_command_request code: 15
Sep  6 12:52:51 gridania-pvt1-soc kernel: [ 2406.725350] prepare_command_request code: 4
Sep  6 12:52:58 gridania-pvt1-soc kernel: [ 2413.440158] prepare_command_request code: 4
Sep  6 12:53:04 gridania-pvt1-soc kernel: [ 2420.154969] prepare_command_request code: 4
Sep  6 12:53:11 gridania-pvt1-soc kernel: [ 2426.867551] prepare_command_request code: 4
Sep  6 12:53:13 gridania-pvt1-soc kernel: [ 2428.582602] prepare_command_request code: 11
Sep  6 12:53:13 gridania-pvt1-soc kernel: [ 2428.582634] esp32_spi: cmd_auth_request: Authentication request: 30:4f:75:8c:e0:08 1 0 0 0
Sep  6 12:53:15 gridania-pvt1-soc kernel: [ 2430.694993] esp32_spi: process_disconnect_event: Disconnect event for ssid VIETTEL_AP_8CE000 [reason:201]
Sep  6 12:53:15 gridania-pvt1-soc kernel: [ 2430.795878] prepare_command_request code: 4
Sep  6 12:53:20 gridania-pvt1-soc kernel: [ 2435.699312] prepare_command_request code: 6
Sep  6 12:53:20 gridania-pvt1-soc kernel: [ 2436.217940] prepare_command_request code: 4
Sep  6 12:53:22 gridania-pvt1-soc kernel: [ 2437.935325] prepare_command_request code: 11
Sep  6 12:53:22 gridania-pvt1-soc kernel: [ 2437.935356] esp32_spi: cmd_auth_request: Authentication request: 30:4f:75:8c:e0:08 1 0 0 0
Sep  6 12:53:24 gridania-pvt1-soc kernel: [ 2440.056292] esp32_spi: process_disconnect_event: Disconnect event for ssid VIETTEL_AP_8CE000 [reason:201]
Sep  6 12:53:24 gridania-pvt1-soc kernel: [ 2440.056456] esp32_spi: esp_reg_notifier: cfg80211 regulatory domain callback for 00, current=00
Sep  6 12:53:24 gridania-pvt1-soc kernel: [ 2440.056652] prepare_command_request code: 15
Sep  6 12:53:28 gridania-pvt1-soc kernel: [ 2443.368174] prepare_command_request code: 6
Sep  6 12:53:29 gridania-pvt1-soc kernel: [ 2444.386667] prepare_command_request code: 4
Sep  6 12:53:30 gridania-pvt1-soc kernel: [ 2446.103008] prepare_command_request code: 11
Sep  6 12:53:30 gridania-pvt1-soc kernel: [ 2446.103041] esp32_spi: cmd_auth_request: Authentication request: 30:4f:75:8c:e0:08 1 0 0 0
Sep  6 12:53:32 gridania-pvt1-soc kernel: [ 2448.216566] esp32_spi: process_disconnect_event: Disconnect event for ssid VIETTEL_AP_8CE000 [reason:201]
Sep  6 12:53:32 gridania-pvt1-soc kernel: [ 2448.216738] esp32_spi: esp_reg_notifier: cfg80211 regulatory domain callback for 00, current=00
Sep  6 12:53:32 gridania-pvt1-soc kernel: [ 2448.216925] prepare_command_request code: 15
Sep  6 12:53:36 gridania-pvt1-soc kernel: [ 2451.529031] prepare_command_request code: 6
Sep  6 12:53:41 gridania-pvt1-soc kernel: [ 2456.550007] prepare_command_request code: 4
Sep  6 12:53:48 gridania-pvt1-soc kernel: [ 2463.276133] prepare_command_request code: 4
Sep  6 12:53:49 gridania-pvt1-soc kernel: [ 2465.000123] prepare_command_request code: 11
Sep  6 12:53:49 gridania-pvt1-soc kernel: [ 2465.000157] esp32_spi: cmd_auth_request: Authentication request: 30:4f:75:8c:e0:08 1 0 0 0
Sep  6 12:53:51 gridania-pvt1-soc kernel: [ 2467.120939] esp32_spi: process_disconnect_event: Disconnect event for ssid VIETTEL_AP_8CE000 [reason:201]
Sep  6 12:53:51 gridania-pvt1-soc kernel: [ 2467.121101] esp32_spi: esp_reg_notifier: cfg80211 regulatory domain callback for 00, current=00
Sep  6 12:53:51 gridania-pvt1-soc kernel: [ 2467.121348] prepare_command_request code: 15
Sep  6 12:53:55 gridania-pvt1-soc kernel: [ 2470.432552] prepare_command_request code: 6
Sep  6 12:54:05 gridania-pvt1-soc kernel: [ 2480.464264] prepare_command_request code: 4
Sep  6 12:54:11 gridania-pvt1-soc kernel: [ 2487.184168] prepare_command_request code: 4
Sep  6 12:54:18 gridania-pvt1-soc kernel: [ 2493.905350] prepare_command_request code: 4
Sep  6 12:54:20 gridania-pvt1-soc kernel: [ 2495.623267] prepare_command_request code: 11
Sep  6 12:54:20 gridania-pvt1-soc kernel: [ 2495.623302] esp32_spi: cmd_auth_request: Authentication request: 30:4f:75:8c:e0:08 1 0 0 0
Sep  6 12:54:20 gridania-pvt1-soc kernel: [ 2496.042721] esp32_spi: decode_common_resp: [0xb] Command failed
Sep  6 12:54:20 gridania-pvt1-soc kernel: [ 2496.042743] esp32_spi: cmd_auth_request: wait_and_decode_cmd_resp(priv, cmd_node) failure, ret: -1
Sep  6 12:54:30 gridania-pvt1-soc kernel: [ 2506.052918] prepare_command_request code: 4
Sep  6 12:54:37 gridania-pvt1-soc kernel: [ 2512.776147] prepare_command_request code: 4
Sep  6 12:54:44 gridania-pvt1-soc kernel: [ 2519.502004] prepare_command_request code: 4
Sep  6 12:54:50 gridania-pvt1-soc kernel: [ 2526.225270] prepare_command_request code: 4
Sep  6 12:54:57 gridania-pvt1-soc kernel: [ 2532.945245] prepare_command_request code: 4
Sep  6 12:55:04 gridania-pvt1-soc kernel: [ 2539.661799] prepare_command_request code: 4
Sep  6 12:55:11 gridania-pvt1-soc kernel: [ 2546.384169] prepare_command_request code: 4
Sep  6 12:55:12 gridania-pvt1-soc kernel: [ 2548.099934] prepare_command_request code: 11
Sep  6 12:55:12 gridania-pvt1-soc kernel: [ 2548.099966] esp32_spi: cmd_auth_request: Authentication request: 30:4f:75:8c:e0:08 1 0 0 0
Sep  6 12:55:14 gridania-pvt1-soc kernel: [ 2550.215358] esp32_spi: process_disconnect_event: Disconnect event for ssid VIETTEL_AP_8CE000 [reason:201]
Sep  6 12:55:14 gridania-pvt1-soc kernel: [ 2550.215524] esp32_spi: esp_reg_notifier: cfg80211 regulatory domain callback for 00, current=00
Sep  6 12:55:14 gridania-pvt1-soc kernel: [ 2550.215717] prepare_command_request code: 15
Sep  6 12:55:18 gridania-pvt1-soc kernel: [ 2553.528886] prepare_command_request code: 6

WPA Logging:

root@gridania-pvt1-soc:~# wpa_supplicant -D nl80211 -i wlan0 -c /etc/ion-wpa2.conf
Successfully initialized wpa_supplicant
nl80211: kernel reports: Registration to specific type not supported
nl80211: kernel reports: Registration to specific type not supported
nl80211: kernel reports: Registration to specific type not supported
nl80211: kernel reports: Registration to specific type not supported
nl80211: kernel reports: Registration to specific type not supported
nl80211: kernel reports: Registration to specific type not supported
nl80211: kernel reports: Registration to specific type not supported
nl80211: kernel reports: Registration to specific type not supported
nl80211: kernel reports: Registration to specific type not supported
nl80211: kernel reports: Registration to specific type not supported
nl80211: kernel reports: Registration to specific type not supported
nl80211: kernel reports: Registration to specific type not supported
nl80211: kernel reports: Registration to specific type not supported
nl80211: kernel reports: Registration to specific type not supported
nl80211: kernel reports: Registration to specific type not supported
nl80211: kernel reports: Registration to specific type not supported
nl80211: kernel reports: Registration to specific type not supported
nl80211: kernel reports: Registration to specific type not supported
nl80211: kernel reports: Registration to specific type not supported
nl80211: kernel reports: Registration to specific type not supported
nl80211: kernel reports: Registration to specific type not supported
nl80211: kernel reports: Registration to specific type not supported
rfkill: Cannot open RFKILL control device
wlan0: SME: Trying to authenticate with 30:4f:75:8c:e0:08 (SSID='VIETTEL_AP_8CE000' freq=2412 MHz)
wlan0: CTRL-EVENT-DISCONNECTED bssid=30:4f:75:8c:e0:08 reason=201
wlan0: SME: Trying to authenticate with 30:4f:75:8c:e0:08 (SSID='VIETTEL_AP_8CE000' freq=2412 MHz)
wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD
wlan0: SME: Trying to authenticate with 30:4f:75:8c:e0:08 (SSID='VIETTEL_AP_8CE000' freq=2412 MHz)
wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD
wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=5 ssid="VIETTEL_AP_8CE000" auth_failures=1 duration=10 reason=CONN_FAILED
wlan0: CTRL-EVENT-SSID-REENABLED id=5 ssid="VIETTEL_AP_8CE000"
wlan0: SME: Trying to authenticate with 30:4f:75:8c:e0:08 (SSID='VIETTEL_AP_8CE000' freq=2412 MHz)
wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD
wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=5 ssid="VIETTEL_AP_8CE000" auth_failures=2 duration=23 reason=CONN_FAILED
wlan0: CTRL-EVENT-SSID-REENABLED id=5 ssid="VIETTEL_AP_8CE000"
wlan0: SME: Trying to authenticate with 30:4f:75:8c:e0:08 (SSID='VIETTEL_AP_8CE000' freq=2412 MHz)
wlan0: SME: Authentication request to the driver failed
wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=5 ssid="VIETTEL_AP_8CE000" auth_failures=3 duration=46 reason=CONN_FAILED
wlan0: CTRL-EVENT-SSID-REENABLED id=5 ssid="VIETTEL_AP_8CE000"
wlan0: SME: Trying to authenticate with 30:4f:75:8c:e0:08 (SSID='VIETTEL_AP_8CE000' freq=2412 MHz)
wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD
wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=5 ssid="VIETTEL_AP_8CE000" auth_failures=4 duration=77 reason=CONN_FAILED
nguyenlkdn commented 2 months ago

++Updated for 3rd attempt results

It able to connect AP after 9 times retry authen

Kernel Log

Sep  6 12:52:26 gridania-pvt1-soc kernel: [ 2381.785519] esp32_spi: process_disconnect_event: Disconnect event for ssid VIETTEL_AP_8CE000 [reason:3]
Sep  6 12:52:26 gridania-pvt1-soc kernel: [ 2381.791237] esp32_spi: esp_inetaddr_event: Interface Down: 0
Sep  6 12:52:26 gridania-pvt1-soc kernel: [ 2381.796771] esp32_spi: esp_reg_notifier: cfg80211 regulatory domain callback for 00, current=VN
Sep  6 12:52:26 gridania-pvt1-soc kernel: [ 2381.796791] prepare_command_request code: 18
Sep  6 12:52:26 gridania-pvt1-soc kernel: [ 2381.832457] prepare_command_request code: 15
Sep  6 12:52:51 gridania-pvt1-soc kernel: [ 2406.498444] prepare_command_request code: 15
Sep  6 12:52:51 gridania-pvt1-soc kernel: [ 2406.525344] prepare_command_request code: 15
Sep  6 12:52:51 gridania-pvt1-soc kernel: [ 2406.548750] prepare_command_request code: 15
Sep  6 12:52:51 gridania-pvt1-soc kernel: [ 2406.566129] prepare_command_request code: 15
Sep  6 12:52:51 gridania-pvt1-soc kernel: [ 2406.591436] prepare_command_request code: 15
Sep  6 12:52:51 gridania-pvt1-soc kernel: [ 2406.725350] prepare_command_request code: 4
Sep  6 12:52:58 gridania-pvt1-soc kernel: [ 2413.440158] prepare_command_request code: 4
Sep  6 12:53:04 gridania-pvt1-soc kernel: [ 2420.154969] prepare_command_request code: 4
Sep  6 12:53:11 gridania-pvt1-soc kernel: [ 2426.867551] prepare_command_request code: 4
Sep  6 12:53:13 gridania-pvt1-soc kernel: [ 2428.582602] prepare_command_request code: 11
Sep  6 12:53:13 gridania-pvt1-soc kernel: [ 2428.582634] esp32_spi: cmd_auth_request: Authentication request: 30:4f:75:8c:e0:08 1 0 0 0
Sep  6 12:53:15 gridania-pvt1-soc kernel: [ 2430.694993] esp32_spi: process_disconnect_event: Disconnect event for ssid VIETTEL_AP_8CE000 [reason:201]
Sep  6 12:53:15 gridania-pvt1-soc kernel: [ 2430.795878] prepare_command_request code: 4
Sep  6 12:53:20 gridania-pvt1-soc kernel: [ 2435.699312] prepare_command_request code: 6
Sep  6 12:53:20 gridania-pvt1-soc kernel: [ 2436.217940] prepare_command_request code: 4
Sep  6 12:53:22 gridania-pvt1-soc kernel: [ 2437.935325] prepare_command_request code: 11
Sep  6 12:53:22 gridania-pvt1-soc kernel: [ 2437.935356] esp32_spi: cmd_auth_request: Authentication request: 30:4f:75:8c:e0:08 1 0 0 0
Sep  6 12:53:24 gridania-pvt1-soc kernel: [ 2440.056292] esp32_spi: process_disconnect_event: Disconnect event for ssid VIETTEL_AP_8CE000 [reason:201]
Sep  6 12:53:24 gridania-pvt1-soc kernel: [ 2440.056456] esp32_spi: esp_reg_notifier: cfg80211 regulatory domain callback for 00, current=00
Sep  6 12:53:24 gridania-pvt1-soc kernel: [ 2440.056652] prepare_command_request code: 15
Sep  6 12:53:28 gridania-pvt1-soc kernel: [ 2443.368174] prepare_command_request code: 6
Sep  6 12:53:29 gridania-pvt1-soc kernel: [ 2444.386667] prepare_command_request code: 4
Sep  6 12:53:30 gridania-pvt1-soc kernel: [ 2446.103008] prepare_command_request code: 11
Sep  6 12:53:30 gridania-pvt1-soc kernel: [ 2446.103041] esp32_spi: cmd_auth_request: Authentication request: 30:4f:75:8c:e0:08 1 0 0 0
Sep  6 12:53:32 gridania-pvt1-soc kernel: [ 2448.216566] esp32_spi: process_disconnect_event: Disconnect event for ssid VIETTEL_AP_8CE000 [reason:201]
Sep  6 12:53:32 gridania-pvt1-soc kernel: [ 2448.216738] esp32_spi: esp_reg_notifier: cfg80211 regulatory domain callback for 00, current=00
Sep  6 12:53:32 gridania-pvt1-soc kernel: [ 2448.216925] prepare_command_request code: 15
Sep  6 12:53:36 gridania-pvt1-soc kernel: [ 2451.529031] prepare_command_request code: 6
Sep  6 12:53:41 gridania-pvt1-soc kernel: [ 2456.550007] prepare_command_request code: 4
Sep  6 12:53:48 gridania-pvt1-soc kernel: [ 2463.276133] prepare_command_request code: 4
Sep  6 12:53:49 gridania-pvt1-soc kernel: [ 2465.000123] prepare_command_request code: 11
Sep  6 12:53:49 gridania-pvt1-soc kernel: [ 2465.000157] esp32_spi: cmd_auth_request: Authentication request: 30:4f:75:8c:e0:08 1 0 0 0
Sep  6 12:53:51 gridania-pvt1-soc kernel: [ 2467.120939] esp32_spi: process_disconnect_event: Disconnect event for ssid VIETTEL_AP_8CE000 [reason:201]
Sep  6 12:53:51 gridania-pvt1-soc kernel: [ 2467.121101] esp32_spi: esp_reg_notifier: cfg80211 regulatory domain callback for 00, current=00
Sep  6 12:53:51 gridania-pvt1-soc kernel: [ 2467.121348] prepare_command_request code: 15
Sep  6 12:53:55 gridania-pvt1-soc kernel: [ 2470.432552] prepare_command_request code: 6
Sep  6 12:54:05 gridania-pvt1-soc kernel: [ 2480.464264] prepare_command_request code: 4
Sep  6 12:54:11 gridania-pvt1-soc kernel: [ 2487.184168] prepare_command_request code: 4
Sep  6 12:54:18 gridania-pvt1-soc kernel: [ 2493.905350] prepare_command_request code: 4
Sep  6 12:54:20 gridania-pvt1-soc kernel: [ 2495.623267] prepare_command_request code: 11
Sep  6 12:54:20 gridania-pvt1-soc kernel: [ 2495.623302] esp32_spi: cmd_auth_request: Authentication request: 30:4f:75:8c:e0:08 1 0 0 0
Sep  6 12:54:20 gridania-pvt1-soc kernel: [ 2496.042721] esp32_spi: decode_common_resp: [0xb] Command failed
Sep  6 12:54:20 gridania-pvt1-soc kernel: [ 2496.042743] esp32_spi: cmd_auth_request: wait_and_decode_cmd_resp(priv, cmd_node) failure, ret: -1
Sep  6 12:54:30 gridania-pvt1-soc kernel: [ 2506.052918] prepare_command_request code: 4
Sep  6 12:54:37 gridania-pvt1-soc kernel: [ 2512.776147] prepare_command_request code: 4
Sep  6 12:54:44 gridania-pvt1-soc kernel: [ 2519.502004] prepare_command_request code: 4
Sep  6 12:54:50 gridania-pvt1-soc kernel: [ 2526.225270] prepare_command_request code: 4
Sep  6 12:54:57 gridania-pvt1-soc kernel: [ 2532.945245] prepare_command_request code: 4
Sep  6 12:55:04 gridania-pvt1-soc kernel: [ 2539.661799] prepare_command_request code: 4
Sep  6 12:55:11 gridania-pvt1-soc kernel: [ 2546.384169] prepare_command_request code: 4
Sep  6 12:55:12 gridania-pvt1-soc kernel: [ 2548.099934] prepare_command_request code: 11
Sep  6 12:55:12 gridania-pvt1-soc kernel: [ 2548.099966] esp32_spi: cmd_auth_request: Authentication request: 30:4f:75:8c:e0:08 1 0 0 0
Sep  6 12:55:14 gridania-pvt1-soc kernel: [ 2550.215358] esp32_spi: process_disconnect_event: Disconnect event for ssid VIETTEL_AP_8CE000 [reason:201]
Sep  6 12:55:14 gridania-pvt1-soc kernel: [ 2550.215524] esp32_spi: esp_reg_notifier: cfg80211 regulatory domain callback for 00, current=00
Sep  6 12:55:14 gridania-pvt1-soc kernel: [ 2550.215717] prepare_command_request code: 15
Sep  6 12:55:18 gridania-pvt1-soc kernel: [ 2553.528886] prepare_command_request code: 6
Sep  6 12:55:28 gridania-pvt1-soc kernel: [ 2563.556107] prepare_command_request code: 4
Sep  6 12:55:34 gridania-pvt1-soc kernel: [ 2570.265889] prepare_command_request code: 4
Sep  6 12:55:41 gridania-pvt1-soc kernel: [ 2576.979470] prepare_command_request code: 4
Sep  6 12:55:48 gridania-pvt1-soc kernel: [ 2583.706956] prepare_command_request code: 4
Sep  6 12:55:55 gridania-pvt1-soc kernel: [ 2590.423610] prepare_command_request code: 4
Sep  6 12:56:01 gridania-pvt1-soc kernel: [ 2597.144033] prepare_command_request code: 4
Sep  6 12:56:08 gridania-pvt1-soc kernel: [ 2603.864142] prepare_command_request code: 4
Sep  6 12:56:15 gridania-pvt1-soc kernel: [ 2610.580170] prepare_command_request code: 4
Sep  6 12:56:22 gridania-pvt1-soc kernel: [ 2617.300817] prepare_command_request code: 4
Sep  6 12:56:28 gridania-pvt1-soc kernel: [ 2624.020147] prepare_command_request code: 4
Sep  6 12:56:35 gridania-pvt1-soc kernel: [ 2630.740652] prepare_command_request code: 4
Sep  6 12:56:37 gridania-pvt1-soc kernel: [ 2632.456292] prepare_command_request code: 11
Sep  6 12:56:37 gridania-pvt1-soc kernel: [ 2632.456326] esp32_spi: cmd_auth_request: Authentication request: 30:4f:75:8c:e0:08 1 0 0 0
Sep  6 12:56:38 gridania-pvt1-soc kernel: [ 2633.718227] prepare_command_request code: 12
Sep  6 12:56:38 gridania-pvt1-soc kernel: [ 2633.718262] esp32_spi: cmd_assoc_request: Association request: 30:4f:75:8c:e0:08 1 35
Sep  6 12:56:39 gridania-pvt1-soc kernel: [ 2634.746768] esp32_spi: process_disconnect_event: Disconnect event for ssid VIETTEL_AP_8CE000 [reason:39]
Sep  6 12:56:39 gridania-pvt1-soc kernel: [ 2634.746920] esp32_spi: esp_reg_notifier: cfg80211 regulatory domain callback for 00, current=00
Sep  6 12:56:39 gridania-pvt1-soc kernel: [ 2634.747107] prepare_command_request code: 15
Sep  6 12:56:43 gridania-pvt1-soc kernel: [ 2638.740061] prepare_command_request code: 6
Sep  6 12:56:53 gridania-pvt1-soc kernel: [ 2648.766965] prepare_command_request code: 4
Sep  6 12:57:00 gridania-pvt1-soc kernel: [ 2655.484160] prepare_command_request code: 4
Sep  6 12:57:06 gridania-pvt1-soc kernel: [ 2662.204139] prepare_command_request code: 4
Sep  6 12:57:13 gridania-pvt1-soc kernel: [ 2668.928143] prepare_command_request code: 4
Sep  6 12:57:20 gridania-pvt1-soc kernel: [ 2675.647508] prepare_command_request code: 4
Sep  6 12:57:27 gridania-pvt1-soc kernel: [ 2682.364135] prepare_command_request code: 4
Sep  6 12:57:33 gridania-pvt1-soc kernel: [ 2689.091043] prepare_command_request code: 4
Sep  6 12:57:40 gridania-pvt1-soc kernel: [ 2695.810059] prepare_command_request code: 4
Sep  6 12:57:47 gridania-pvt1-soc kernel: [ 2702.537451] prepare_command_request code: 4
Sep  6 12:57:53 gridania-pvt1-soc kernel: [ 2709.256150] prepare_command_request code: 4
Sep  6 12:58:00 gridania-pvt1-soc kernel: [ 2715.984206] prepare_command_request code: 4
Sep  6 12:58:02 gridania-pvt1-soc kernel: [ 2717.702641] prepare_command_request code: 11
Sep  6 12:58:02 gridania-pvt1-soc kernel: [ 2717.702674] esp32_spi: cmd_auth_request: Authentication request: 30:4f:75:8c:e0:08 1 0 0 0
Sep  6 12:58:04 gridania-pvt1-soc kernel: [ 2719.816205] esp32_spi: process_disconnect_event: Disconnect event for ssid VIETTEL_AP_8CE000 [reason:201]
Sep  6 12:58:04 gridania-pvt1-soc kernel: [ 2719.816355] esp32_spi: esp_reg_notifier: cfg80211 regulatory domain callback for 00, current=00
Sep  6 12:58:04 gridania-pvt1-soc kernel: [ 2719.816545] prepare_command_request code: 15
Sep  6 12:58:07 gridania-pvt1-soc kernel: [ 2723.128034] prepare_command_request code: 6
Sep  6 12:58:17 gridania-pvt1-soc kernel: [ 2733.157008] prepare_command_request code: 4
Sep  6 12:58:24 gridania-pvt1-soc kernel: [ 2739.881873] prepare_command_request code: 4
Sep  6 12:58:31 gridania-pvt1-soc kernel: [ 2746.598831] prepare_command_request code: 4
Sep  6 12:58:38 gridania-pvt1-soc kernel: [ 2753.317216] prepare_command_request code: 4
Sep  6 12:58:44 gridania-pvt1-soc kernel: [ 2760.031126] prepare_command_request code: 4
Sep  6 12:58:51 gridania-pvt1-soc kernel: [ 2766.747209] prepare_command_request code: 4
Sep  6 12:58:58 gridania-pvt1-soc kernel: [ 2773.471530] prepare_command_request code: 4
Sep  6 12:59:04 gridania-pvt1-soc kernel: [ 2780.188024] prepare_command_request code: 4
Sep  6 12:59:11 gridania-pvt1-soc kernel: [ 2786.907753] prepare_command_request code: 4
Sep  6 12:59:18 gridania-pvt1-soc kernel: [ 2793.628031] prepare_command_request code: 4
Sep  6 12:59:25 gridania-pvt1-soc kernel: [ 2800.346917] prepare_command_request code: 4
Sep  6 12:59:31 gridania-pvt1-soc kernel: [ 2807.059963] prepare_command_request code: 4
Sep  6 12:59:38 gridania-pvt1-soc kernel: [ 2813.780139] prepare_command_request code: 4
Sep  6 12:59:45 gridania-pvt1-soc kernel: [ 2820.502639] prepare_command_request code: 4
Sep  6 12:59:51 gridania-pvt1-soc kernel: [ 2827.219952] prepare_command_request code: 4
Sep  6 12:59:58 gridania-pvt1-soc kernel: [ 2833.940140] prepare_command_request code: 4
Sep  6 13:00:05 gridania-pvt1-soc kernel: [ 2840.656128] prepare_command_request code: 4
Sep  6 13:00:12 gridania-pvt1-soc kernel: [ 2847.379445] prepare_command_request code: 4
Sep  6 13:00:18 gridania-pvt1-soc kernel: [ 2854.096134] prepare_command_request code: 4
Sep  6 13:00:25 gridania-pvt1-soc kernel: [ 2860.814526] prepare_command_request code: 4
Sep  6 13:00:32 gridania-pvt1-soc kernel: [ 2867.533136] prepare_command_request code: 4
Sep  6 13:00:33 gridania-pvt1-soc kernel: [ 2869.247088] prepare_command_request code: 11
Sep  6 13:00:33 gridania-pvt1-soc kernel: [ 2869.247121] esp32_spi: cmd_auth_request: Authentication request: 30:4f:75:8c:e0:08 1 0 0 0
Sep  6 13:00:35 gridania-pvt1-soc kernel: [ 2870.633926] prepare_command_request code: 12
Sep  6 13:00:35 gridania-pvt1-soc kernel: [ 2870.633962] esp32_spi: cmd_assoc_request: Association request: 30:4f:75:8c:e0:08 1 35
Sep  6 13:00:36 gridania-pvt1-soc kernel: [ 2871.632288] esp32_spi: process_assoc_event: Connection status: 0
Sep  6 13:00:36 gridania-pvt1-soc kernel: [ 2871.633259] esp32_spi: esp_reg_notifier: cfg80211 regulatory domain callback for VN, current=00
Sep  6 13:00:36 gridania-pvt1-soc kernel: [ 2871.633274] prepare_command_request code: 18
Sep  6 13:00:36 gridania-pvt1-soc kernel: [ 2871.653354] prepare_command_request code: 15
Sep  6 13:00:36 gridania-pvt1-soc kernel: [ 2871.948995] NOHZ tick-stop error: Non-RCU local softirq work is pending, handler #08!!!
Sep  6 13:00:36 gridania-pvt1-soc kernel: [ 2871.951074] prepare_command_request code: 8
Sep  6 13:00:36 gridania-pvt1-soc kernel: [ 2871.978090] prepare_command_request code: 10
Sep  6 13:00:36 gridania-pvt1-soc kernel: [ 2872.000842] prepare_command_request code: 8
Sep  6 13:00:36 gridania-pvt1-soc kernel: [ 2872.047363] prepare_command_request code: 15
Sep  6 13:00:37 gridania-pvt1-soc kernel: [ 2873.051717] prepare_command_request code: 27
Sep  6 13:00:37 gridania-pvt1-soc kernel: [ 2873.089076] prepare_command_request code: 27
Sep  6 13:00:39 gridania-pvt1-soc kernel: [ 2875.123149] prepare_command_request code: 27
Sep  6 13:00:39 gridania-pvt1-soc kernel: [ 2875.156617] prepare_command_request code: 27
Sep  6 13:00:41 gridania-pvt1-soc kernel: [ 2877.213030] prepare_command_request code: 27
Sep  6 13:00:41 gridania-pvt1-soc kernel: [ 2877.251038] prepare_command_request code: 27
Sep  6 13:00:44 gridania-pvt1-soc kernel: [ 2879.291023] prepare_command_request code: 27
Sep  6 13:00:44 gridania-pvt1-soc kernel: [ 2879.333531] prepare_command_request code: 27
Sep  6 13:00:46 gridania-pvt1-soc kernel: [ 2881.361934] prepare_command_request code: 27
Sep  6 13:00:46 gridania-pvt1-soc kernel: [ 2881.393899] prepare_command_request code: 27
Sep  6 13:00:48 gridania-pvt1-soc kernel: [ 2883.440785] prepare_command_request code: 27
Sep  6 13:00:48 gridania-pvt1-soc kernel: [ 2883.491264] prepare_command_request code: 27
Sep  6 13:00:48 gridania-pvt1-soc kernel: [ 2883.672514] esp32_spi: esp_inetaddr_event: NETDEV_UP interface wlan0 ip changed to  192.168.100.012
Sep  6 13:00:48 gridania-pvt1-soc kernel: [ 2883.672536] prepare_command_request code: 13
Sep  6 13:00:48 gridania-pvt1-soc kernel: [ 2883.697193] prepare_command_request code: 14
Sep  6 13:00:48 gridania-pvt1-soc kernel: [ 2883.727867] prepare_command_request code: 14
Sep  6 13:00:50 gridania-pvt1-soc kernel: [ 2885.531716] prepare_command_request code: 27
Sep  6 13:00:50 gridania-pvt1-soc kernel: [ 2885.560553] prepare_command_request code: 27
Sep  6 13:00:52 gridania-pvt1-soc kernel: [ 2887.606204] prepare_command_request code: 27
Sep  6 13:00:52 gridania-pvt1-soc kernel: [ 2887.647530] prepare_command_request code: 27
Sep  6 13:00:54 gridania-pvt1-soc kernel: [ 2889.684355] prepare_command_request code: 27
Sep  6 13:00:54 gridania-pvt1-soc kernel: [ 2889.717393] prepare_command_request code: 27
Sep  6 13:00:56 gridania-pvt1-soc kernel: [ 2891.750013] prepare_command_request code: 27
Sep  6 13:00:56 gridania-pvt1-soc kernel: [ 2891.786433] prepare_command_request code: 27
Sep  6 13:00:58 gridania-pvt1-soc kernel: [ 2893.830461] prepare_command_request code: 27
Sep  6 13:00:58 gridania-pvt1-soc kernel: [ 2893.876959] prepare_command_request code: 27
Sep  6 13:01:00 gridania-pvt1-soc kernel: [ 2895.913843] prepare_command_request code: 27

WPA LOg

rfkill: Cannot open RFKILL control device
wlan0: SME: Trying to authenticate with 30:4f:75:8c:e0:08 (SSID='VIETTEL_AP_8CE000' freq=2412 MHz)
wlan0: CTRL-EVENT-DISCONNECTED bssid=30:4f:75:8c:e0:08 reason=201
wlan0: SME: Trying to authenticate with 30:4f:75:8c:e0:08 (SSID='VIETTEL_AP_8CE000' freq=2412 MHz)
wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD
wlan0: SME: Trying to authenticate with 30:4f:75:8c:e0:08 (SSID='VIETTEL_AP_8CE000' freq=2412 MHz)
wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD
wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=5 ssid="VIETTEL_AP_8CE000" auth_failures=1 duration=10 reason=CONN_FAILED
wlan0: CTRL-EVENT-SSID-REENABLED id=5 ssid="VIETTEL_AP_8CE000"
wlan0: SME: Trying to authenticate with 30:4f:75:8c:e0:08 (SSID='VIETTEL_AP_8CE000' freq=2412 MHz)
wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD
wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=5 ssid="VIETTEL_AP_8CE000" auth_failures=2 duration=23 reason=CONN_FAILED
wlan0: CTRL-EVENT-SSID-REENABLED id=5 ssid="VIETTEL_AP_8CE000"
wlan0: SME: Trying to authenticate with 30:4f:75:8c:e0:08 (SSID='VIETTEL_AP_8CE000' freq=2412 MHz)
wlan0: SME: Authentication request to the driver failed
wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=5 ssid="VIETTEL_AP_8CE000" auth_failures=3 duration=46 reason=CONN_FAILED
wlan0: CTRL-EVENT-SSID-REENABLED id=5 ssid="VIETTEL_AP_8CE000"
wlan0: SME: Trying to authenticate with 30:4f:75:8c:e0:08 (SSID='VIETTEL_AP_8CE000' freq=2412 MHz)
wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD
wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=5 ssid="VIETTEL_AP_8CE000" auth_failures=4 duration=77 reason=CONN_FAILED
wlan0: CTRL-EVENT-SSID-REENABLED id=5 ssid="VIETTEL_AP_8CE000"
wlan0: SME: Trying to authenticate with 30:4f:75:8c:e0:08 (SSID='VIETTEL_AP_8CE000' freq=2412 MHz)
wlan0: Trying to associate with 30:4f:75:8c:e0:08 (SSID='VIETTEL_AP_8CE000' freq=2412 MHz)
wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD
wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=5 ssid="VIETTEL_AP_8CE000" auth_failures=5 duration=75 reason=CONN_FAILED
wlan0: CTRL-EVENT-SSID-REENABLED id=5 ssid="VIETTEL_AP_8CE000"
wlan0: SME: Trying to authenticate with 30:4f:75:8c:e0:08 (SSID='VIETTEL_AP_8CE000' freq=2412 MHz)
wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD
wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=5 ssid="VIETTEL_AP_8CE000" auth_failures=6 duration=143 reason=CONN_FAILED
wlan0: CTRL-EVENT-SSID-REENABLED id=5 ssid="VIETTEL_AP_8CE000"
wlan0: SME: Trying to authenticate with 30:4f:75:8c:e0:08 (SSID='VIETTEL_AP_8CE000' freq=2412 MHz)
wlan0: Trying to associate with 30:4f:75:8c:e0:08 (SSID='VIETTEL_AP_8CE000' freq=2412 MHz)
wlan0: Associated with 30:4f:75:8c:e0:08
wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=VN
wlan0: WPA: Key negotiation completed with 30:4f:75:8c:e0:08 [PTK=CCMP GTK=CCMP]
wlan0: CTRL-EVENT-CONNECTED - Connection to 30:4f:75:8c:e0:08 completed [id=5 id_str=]