search

espressif / esp-hosted

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

NG mode, AP not found to connect. #473

Closed lwj850533527 closed 1 week ago

lwj850533527 commented 3 weeks ago

Hi @kapilkedawat.

During the connection process, sometimes authentication may return a failure The latest code has already set auth_type, Can the scan action be deleted

image image image

lwj850533527 commented 2 weeks ago

Hi @kapilkedawat. HI @Shreyas0-7. Is there any attempt to solve this problem?

kapilkedawat commented 2 weeks ago

Hi @lwj850533527, apologies for the delay. Its most likely again a porting issue on your side, if its not able to scan, it definitely won't be able to connect. Could you please check if there are some local changes which may be causing this.

lwj850533527 commented 2 weeks ago

Hi @kapilkedawat. The code of this file has not been modified in porting. At the same time, I tried to write the auth_type as WPA-PSK when “AP not found to connect” happen. It can connect AP well.

image image

lwj850533527 commented 2 weeks ago

Hi @kapilkedawat. I have set the scan type to WIFI_SCAN_TYPE_ACTIVE (=0), which seems to reduce the probability of this issue

image

lwj850533527 commented 2 weeks ago

Is there any other solution to directly auth_type from host. Thoroughly solve the issue

Shreyas0-7 commented 2 weeks ago

Hey @lwj850533527 it might be because we while we are doing passive scan we are not able to find the AP. Will check if we can et it to active scanning. Till that time can you please inform us on what channel AP is on. Also if possible can you please share wpa_supplicant logs so that we can check if we are receving probe response from the AP or not. wpa_supplicant -c "conf_file" -i "interface" -dd

lwj850533527 commented 2 weeks ago

This is the log of wpa_supplicant and ESP32 host. There have been three times “AP not found to connect” here

image


Sep 11 14:47:52 wpa_supplicant[1534]: wlan0: Control interface command 'REMOVE_NETWORK 0'
Sep 11 14:47:52 wpa_supplicant[1534]: CTRL_IFACE: REMOVE_NETWORK id=0
Sep 11 14:47:52 wpa_supplicant[1534]: RSN: Flush PMKSA cache entry for d4:3a:2e:d2:4a:d8
Sep 11 14:47:52 wpa_supplicant[1534]: wlan0: PMKSA-CACHE-REMOVED d4:3a:2e:d2:4a:d8 0
Sep 11 14:47:52 wpa_supplicant[1534]: CTRL-DEBUG: ctrl_sock-sendmsg: sock=15 sndbuf=180224 outq=0 send_len=39
Sep 11 14:47:52 wpa_supplicant[1534]: CTRL_IFACE monitor sent successfully to /tmp/wpa_ctrl_1537-1\x00
Sep 11 14:47:52 wpa_supplicant[1534]: nl80211: Delete PMKID for d4:3a:2e:d2:4a:d8
Sep 11 14:47:52 wpa_supplicant[1534]: nl80211: NL80211_CMD_DEL_PMKSA failed: -95 (Operation not supported)
Sep 11 14:47:52 wpa_supplicant[1534]: wlan0: RSN: PMKSA cache entry free_cb: d4:3a:2e:d2:4a:d8 reason=0
Sep 11 14:47:52 wpa_supplicant[1534]: CTRL-DEBUG: ctrl_sock-sendmsg: sock=15 sndbuf=180224 outq=704 send_len=28
Sep 11 14:47:52 wpa_supplicant[1534]: CTRL_IFACE monitor sent successfully to /tmp/wpa_ctrl_1537-1\x00
Sep 11 14:47:52 wpa_supplicant[1534]: CTRL-DEBUG: ctrl_sock-sendto: sock=15 sndbuf=180224 outq=1408 send_len=3
Sep 11 14:47:52 wpa_supplicant[1534]: wlan0: Control interface command 'ADD_NETWORK'
Sep 11 14:47:52 wpa_supplicant[1534]: CTRL_IFACE: ADD_NETWORK
Sep 11 14:47:52 wpa_supplicant[1534]: CTRL-DEBUG: ctrl_sock-sendmsg: sock=15 sndbuf=180224 outq=0 send_len=26
Sep 11 14:47:52 wpa_supplicant[1534]: CTRL_IFACE monitor sent successfully to /tmp/wpa_ctrl_1537-1\x00
Sep 11 14:47:52 wpa_supplicant[1534]: CTRL-DEBUG: ctrl_sock-sendto: sock=15 sndbuf=180224 outq=704 send_len=2
Sep 11 14:47:52 wpa_supplicant[1534]: wlan0: Control interface command 'SET_NETWORK [REMOVED]'
Sep 11 14:47:52 wpa_supplicant[1534]: CTRL_IFACE: SET_NETWORK id=0 name='ssid'
Sep 11 14:47:52 wpa_supplicant[1534]: CTRL_IFACE: value - hexdump(len=11): [REMOVED]
Sep 11 14:47:52 wpa_supplicant[1534]: ssid - hexdump(len=9): 65 73 73 65 6e 74 69 65 6c
Sep 11 14:47:52 wpa_supplicant[1534]: CTRL-DEBUG: ctrl_sock-sendto: sock=15 sndbuf=180224 outq=0 send_len=3
Sep 11 14:47:52 wpa_supplicant[1534]: wlan0: Control interface command 'SET_NETWORK [REMOVED]'
Sep 11 14:47:52 wpa_supplicant[1534]: CTRL_IFACE: SET_NETWORK id=0 name='scan_ssid'
Sep 11 14:47:52 wpa_supplicant[1534]: CTRL_IFACE: value - hexdump(len=1): [REMOVED]
Sep 11 14:47:52 wpa_supplicant[1534]: scan_ssid=1 (0x1)
Sep 11 14:47:52 wpa_supplicant[1534]: CTRL-DEBUG: ctrl_sock-sendto: sock=15 sndbuf=180224 outq=0 send_len=3
Sep 11 14:47:52 wpa_supplicant[1534]: wlan0: Control interface command 'SET_NETWORK [REMOVED]'
Sep 11 14:47:52 wpa_supplicant[1534]: CTRL_IFACE: SET_NETWORK id=0 name='psk'
Sep 11 14:47:52 wpa_supplicant[1534]: CTRL_IFACE: value - hexdump(len=10): [REMOVED]
Sep 11 14:47:52 wpa_supplicant[1534]: PSK (ASCII passphrase) - hexdump(len=8): [REMOVED]
Sep 11 14:47:52 wpa_supplicant[1534]: PSK (from passphrase) - hexdump(len=32): [REMOVED]
Sep 11 14:47:52 wpa_supplicant[1534]: CTRL-DEBUG: ctrl_sock-sendto: sock=15 sndbuf=180224 outq=0 send_len=3
Sep 11 14:47:52 wpa_supplicant[1534]: wlan0: Control interface command 'SET_NETWORK [REMOVED]'
Sep 11 14:47:52 wpa_supplicant[1534]: CTRL_IFACE: SET_NETWORK id=0 name='key_mgmt'
Sep 11 14:47:52 wpa_supplicant[1534]: CTRL_IFACE: value - hexdump(len=11): [REMOVED]
Sep 11 14:47:52 wpa_supplicant[1534]: key_mgmt: 0x402
Sep 11 14:47:52 wpa_supplicant[1534]: CTRL-DEBUG: ctrl_sock-sendto: sock=15 sndbuf=180224 outq=0 send_len=3
Sep 11 14:47:52 wpa_supplicant[1534]: wlan0: Control interface command 'SET_NETWORK [REMOVED]'
Sep 11 14:47:52 wpa_supplicant[1534]: CTRL_IFACE: SET_NETWORK id=0 name='ieee80211w'
Sep 11 14:47:52 wpa_supplicant[1534]: CTRL_IFACE: value - hexdump(len=1): [REMOVED]
Sep 11 14:47:52 wpa_supplicant[1534]: ieee80211w=1 (0x1)
Sep 11 14:47:52 wpa_supplicant[1534]: CTRL-DEBUG: ctrl_sock-sendto: sock=15 sndbuf=180224 outq=0 send_len=3
Sep 11 14:47:52 wpa_supplicant[1534]: wlan0: Control interface command 'ENABLE_NETWORK 0'
Sep 11 14:47:52 wpa_supplicant[1534]: CTRL_IFACE: ENABLE_NETWORK id=0
Sep 11 14:47:52 wpa_supplicant[1534]: CTRL-DEBUG: ctrl_sock-sendto: sock=15 sndbuf=180224 outq=0 send_len=3
Sep 11 14:47:52 wpa_supplicant[1534]: wlan0: Control interface command 'SAVE_CONFIG'
Sep 11 14:47:52 wpa_supplicant[1534]: Writing configuration file '/etc/wpa_supplicant.conf.tmp'
Sep 11 14:47:52 wpa_supplicant[1534]: Configuration file '/etc/wpa_supplicant.conf' written successfully
Sep 11 14:47:52 wpa_supplicant[1534]: CTRL_IFACE: SAVE_CONFIG - Configuration updated
Sep 11 14:47:52 wpa_supplicant[1534]: CTRL-DEBUG: ctrl_sock-sendto: sock=15 sndbuf=180224 outq=0 send_len=3
Sep 11 14:47:52 wpa_supplicant[1534]: wlan0: Control interface command 'SELECT_NETWORK 0'
Sep 11 14:47:52 wpa_supplicant[1534]: CTRL_IFACE: SELECT_NETWORK id=0
Sep 11 14:47:52 wpa_supplicant[1534]: SAE: Derive PT - group 19
Sep 11 14:47:52 wpa_supplicant[1534]: SAE: SSID - hexdump(len=9): 65 73 73 65 6e 74 69 65 6c
Sep 11 14:47:52 wpa_supplicant[1534]: SAE: password - hexdump(len=8): [REMOVED]
Sep 11 14:47:52 wpa_supplicant[1534]: SAE: pwd-seed - hexdump(len=32): [REMOVED]
Sep 11 14:47:52 wpa_supplicant[1534]: SAE: pwd-value (u1 P1) - hexdump(len=48): [REMOVED]
Sep 11 14:47:52 wpa_supplicant[1534]: SAE: u1 - hexdump(len=32): [REMOVED]
Sep 11 14:47:52 wpa_supplicant[1534]: SSWU: m - hexdump(len=32): [REMOVED]
Sep 11 14:47:52 wpa_supplicant[1534]: SSWU: t - hexdump(len=32): [REMOVED]
Sep 11 14:47:52 wpa_supplicant[1534]: SSWU: x1a = b / (z * a) - hexdump(len=32): [REMOVED]
Sep 11 14:47:52 wpa_supplicant[1534]: SSWU: x1b = (-b/a) * (1 + t) - hexdump(len=32): [REMOVED]
Sep 11 14:47:52 wpa_supplicant[1534]: SSWU: x1 = CSEL(l, x1a, x1b) - hexdump(len=32): [REMOVED]
Sep 11 14:47:52 wpa_supplicant[1534]: SSWU: gx1 = x1^3 + a * x1 + b - hexdump(len=32): [REMOVED]
Sep 11 14:47:52 wpa_supplicant[1534]: SSWU: x2 = z * u^2 * x1 - hexdump(len=32): [REMOVED]
Sep 11 14:47:52 wpa_supplicant[1534]: SSWU: gx2 = x2^3 + a * x2 + b - hexdump(len=32): [REMOVED]
Sep 11 14:47:52 wpa_supplicant[1534]: SSWU: gx1^((p-1)/2) modulo p - hexdump(len=32): [REMOVED]
Sep 11 14:47:52 wpa_supplicant[1534]: SSWU: v = CSEL(l, gx1, gx2) - hexdump(len=32): [REMOVED]
Sep 11 14:47:52 wpa_supplicant[1534]: SSWU: x = CSEL(l, x1, x2) - hexdump(len=32): [REMOVED]
Sep 11 14:47:52 wpa_supplicant[1534]: SSWU: y = sqrt(v) - hexdump(len=32): [REMOVED]
Sep 11 14:47:52 wpa_supplicant[1534]: SSWU: p - y - hexdump(len=32): [REMOVED]
Sep 11 14:47:52 wpa_supplicant[1534]: SSWU: P.x - hexdump(len=32): [REMOVED]
Sep 11 14:47:52 wpa_supplicant[1534]: SSWU: P.y - hexdump(len=32): [REMOVED]
Sep 11 14:47:52 wpa_supplicant[1534]: SAE: pwd-value (u2 P2) - hexdump(len=48): [REMOVED]
Sep 11 14:47:52 wpa_supplicant[1534]: SAE: u2 - hexdump(len=32): [REMOVED]
Sep 11 14:47:52 wpa_supplicant[1534]: SSWU: m - hexdump(len=32): [REMOVED]
Sep 11 14:47:52 wpa_supplicant[1534]: SSWU: t - hexdump(len=32): [REMOVED]
Sep 11 14:47:52 wpa_supplicant[1534]: SSWU: x1a = b / (z * a) - hexdump(len=32): [REMOVED]
Sep 11 14:47:52 wpa_supplicant[1534]: SSWU: x1b = (-b/a) * (1 + t) - hexdump(len=32): [REMOVED]
Sep 11 14:47:52 wpa_supplicant[1534]: SSWU: x1 = CSEL(l, x1a, x1b) - hexdump(len=32): [REMOVED]
Sep 11 14:47:52 wpa_supplicant[1534]: SSWU: gx1 = x1^3 + a * x1 + b - hexdump(len=32): [REMOVED]
Sep 11 14:47:52 wpa_supplicant[1534]: SSWU: x2 = z * u^2 * x1 - hexdump(len=32): [REMOVED]
Sep 11 14:47:52 wpa_supplicant[1534]: SSWU: gx2 = x2^3 + a * x2 + b - hexdump(len=32): [REMOVED]
Sep 11 14:47:52 wpa_supplicant[1534]: SSWU: gx1^((p-1)/2) modulo p - hexdump(len=32): [REMOVED]
Sep 11 14:47:52 wpa_supplicant[1534]: SSWU: v = CSEL(l, gx1, gx2) - hexdump(len=32): [REMOVED]
Sep 11 14:47:52 wpa_supplicant[1534]: SSWU: x = CSEL(l, x1, x2) - hexdump(len=32): [REMOVED]
Sep 11 14:47:52 wpa_supplicant[1534]: SSWU: y = sqrt(v) - hexdump(len=32): [REMOVED]
Sep 11 14:47:52 wpa_supplicant[1534]: SSWU: p - y - hexdump(len=32): [REMOVED]
Sep 11 14:47:52 wpa_supplicant[1534]: SSWU: P.x - hexdump(len=32): [REMOVED]
Sep 11 14:47:52 wpa_supplicant[1534]: SSWU: P.y - hexdump(len=32): [REMOVED]
Sep 11 14:47:52 wpa_supplicant[1534]: SAE: Derive PT - group 20
Sep 11 14:47:52 wpa_supplicant[1534]: SAE: SSID - hexdump(len=9): 65 73 73 65 6e 74 69 65 6c
Sep 11 14:47:52 wpa_supplicant[1534]: SAE: password - hexdump(len=8): [REMOVED]
Sep 11 14:47:52 wpa_supplicant[1534]: SAE: pwd-seed - hexdump(len=48): [REMOVED]
Sep 11 14:47:52 wpa_supplicant[1534]: SAE: pwd-value (u1 P1) - hexdump(len=72): [REMOVED]
Sep 11 14:47:52 wpa_supplicant[1534]: SAE: u1 - hexdump(len=48): [REMOVED]
Sep 11 14:47:52 wpa_supplicant[1534]: SSWU: m - hexdump(len=48): [REMOVED]
Sep 11 14:47:52 wpa_supplicant[1534]: SSWU: t - hexdump(len=48): [REMOVED]
Sep 11 14:47:52 wpa_supplicant[1534]: SSWU: x1a = b / (z * a) - hexdump(len=48): [REMOVED]
Sep 11 14:47:52 wpa_supplicant[1534]: SSWU: x1b = (-b/a) * (1 + t) - hexdump(len=48): [REMOVED]
Sep 11 14:47:52 wpa_supplicant[1534]: SSWU: x1 = CSEL(l, x1a, x1b) - hexdump(len=48): [REMOVED]
Sep 11 14:47:52 wpa_supplicant[1534]: SSWU: gx1 = x1^3 + a * x1 + b - hexdump(len=48): [REMOVED]
Sep 11 14:47:52 wpa_supplicant[1534]: SSWU: x2 = z * u^2 * x1 - hexdump(len=48): [REMOVED]
Sep 11 14:47:52 wpa_supplicant[1534]: SSWU: gx2 = x2^3 + a * x2 + b - hexdump(len=48): [REMOVED]
Sep 11 14:47:52 wpa_supplicant[1534]: SSWU: gx1^((p-1)/2) modulo p - hexdump(len=48): [REMOVED]
Sep 11 14:47:52 wpa_supplicant[1534]: SSWU: v = CSEL(l, gx1, gx2) - hexdump(len=48): [REMOVED]
Sep 11 14:47:52 wpa_supplicant[1534]: SSWU: x = CSEL(l, x1, x2) - hexdump(len=48): [REMOVED]
Sep 11 14:47:52 wpa_supplicant[1534]: SSWU: y = sqrt(v) - hexdump(len=48): [REMOVED]
Sep 11 14:47:52 wpa_supplicant[1534]: SSWU: p - y - hexdump(len=48): [REMOVED]
Sep 11 14:47:52 wpa_supplicant[1534]: SSWU: P.x - hexdump(len=48): [REMOVED]
Sep 11 14:47:52 wpa_supplicant[1534]: SSWU: P.y - hexdump(len=48): [REMOVED]
Sep 11 14:47:52 wpa_supplicant[1534]: SAE: pwd-value (u2 P2) - hexdump(len=72): [REMOVED]
Sep 11 14:47:52 wpa_supplicant[1534]: SAE: u2 - hexdump(len=48): [REMOVED]
Sep 11 14:47:52 wpa_supplicant[1534]: SSWU: m - hexdump(len=48): [REMOVED]
Sep 11 14:47:52 wpa_supplicant[1534]: SSWU: t - hexdump(len=48): [REMOVED]
Sep 11 14:47:52 wpa_supplicant[1534]: SSWU: x1a = b / (z * a) - hexdump(len=48): [REMOVED]
Sep 11 14:47:52 wpa_supplicant[1534]: SSWU: x1b = (-b/a) * (1 + t) - hexdump(len=48): [REMOVED]
Sep 11 14:47:52 wpa_supplicant[1534]: SSWU: x1 = CSEL(l, x1a, x1b) - hexdump(len=48): [REMOVED]
Sep 11 14:47:52 wpa_supplicant[1534]: SSWU: gx1 = x1^3 + a * x1 + b - hexdump(len=48): [REMOVED]
Sep 11 14:47:52 wpa_supplicant[1534]: SSWU: x2 = z * u^2 * x1 - hexdump(len=48): [REMOVED]
Sep 11 14:47:52 wpa_supplicant[1534]: SSWU: gx2 = x2^3 + a * x2 + b - hexdump(len=48): [REMOVED]
Sep 11 14:47:52 wpa_supplicant[1534]: SSWU: gx1^((p-1)/2) modulo p - hexdump(len=48): [REMOVED]
Sep 11 14:47:52 wpa_supplicant[1534]: SSWU: v = CSEL(l, gx1, gx2) - hexdump(len=48): [REMOVED]
Sep 11 14:47:52 wpa_supplicant[1534]: SSWU: x = CSEL(l, x1, x2) - hexdump(len=48): [REMOVED]
Sep 11 14:47:52 wpa_supplicant[1534]: SSWU: y = sqrt(v) - hexdump(len=48): [REMOVED]
Sep 11 14:47:52 wpa_supplicant[1534]: SSWU: p - y - hexdump(len=48): [REMOVED]
Sep 11 14:47:52 wpa_supplicant[1534]: SSWU: P.x - hexdump(len=48): [REMOVED]
Sep 11 14:47:52 wpa_supplicant[1534]: SSWU: P.y - hexdump(len=48): [REMOVED]
Sep 11 14:47:52 wpa_supplicant[1534]: SAE: Derive PT - group 21
Sep 11 14:47:52 wpa_supplicant[1534]: SAE: SSID - hexdump(len=9): 65 73 73 65 6e 74 69 65 6c
Sep 11 14:47:52 wpa_supplicant[1534]: SAE: password - hexdump(len=8): [REMOVED]
Sep 11 14:47:52 wpa_supplicant[1534]: SAE: pwd-seed - hexdump(len=64): [REMOVED]
Sep 11 14:47:52 wpa_supplicant[1534]: SAE: pwd-value (u1 P1) - hexdump(len=99): [REMOVED]
Sep 11 14:47:52 wpa_supplicant[1534]: SAE: u1 - hexdump(len=66): [REMOVED]
Sep 11 14:47:52 wpa_supplicant[1534]: SSWU: m - hexdump(len=66): [REMOVED]
Sep 11 14:47:52 wpa_supplicant[1534]: SSWU: t - hexdump(len=66): [REMOVED]
Sep 11 14:47:52 wpa_supplicant[1534]: SSWU: x1a = b / (z * a) - hexdump(len=66): [REMOVED]
Sep 11 14:47:52 wpa_supplicant[1534]: SSWU: x1b = (-b/a) * (1 + t) - hexdump(len=66): [REMOVED]
Sep 11 14:47:52 wpa_supplicant[1534]: SSWU: x1 = CSEL(l, x1a, x1b) - hexdump(len=66): [REMOVED]
Sep 11 14:47:52 wpa_supplicant[1534]: SSWU: gx1 = x1^3 + a * x1 + b - hexdump(len=66): [REMOVED]
Sep 11 14:47:52 wpa_supplicant[1534]: SSWU: x2 = z * u^2 * x1 - hexdump(len=66): [REMOVED]
Sep 11 14:47:52 wpa_supplicant[1534]: SSWU: gx2 = x2^3 + a * x2 + b - hexdump(len=66): [REMOVED]
Sep 11 14:47:52 wpa_supplicant[1534]: SSWU: gx1^((p-1)/2) modulo p - hexdump(len=66): [REMOVED]
Sep 11 14:47:52 wpa_supplicant[1534]: SSWU: v = CSEL(l, gx1, gx2) - hexdump(len=66): [REMOVED]
Sep 11 14:47:52 wpa_supplicant[1534]: SSWU: x = CSEL(l, x1, x2) - hexdump(len=66): [REMOVED]
Sep 11 14:47:52 wpa_supplicant[1534]: SSWU: y = sqrt(v) - hexdump(len=66): [REMOVED]
Sep 11 14:47:52 wpa_supplicant[1534]: SSWU: p - y - hexdump(len=66): [REMOVED]
Sep 11 14:47:52 wpa_supplicant[1534]: SSWU: P.x - hexdump(len=66): [REMOVED]
Sep 11 14:47:52 wpa_supplicant[1534]: SSWU: P.y - hexdump(len=66): [REMOVED]
Sep 11 14:47:52 wpa_supplicant[1534]: SAE: pwd-value (u2 P2) - hexdump(len=99): [REMOVED]
Sep 11 14:47:52 wpa_supplicant[1534]: SAE: u2 - hexdump(len=66): [REMOVED]
Sep 11 14:47:52 wpa_supplicant[1534]: SSWU: m - hexdump(len=66): [REMOVED]
Sep 11 14:47:52 wpa_supplicant[1534]: SSWU: t - hexdump(len=66): [REMOVED]
Sep 11 14:47:52 wpa_supplicant[1534]: SSWU: x1a = b / (z * a) - hexdump(len=66): [REMOVED]
Sep 11 14:47:52 wpa_supplicant[1534]: SSWU: x1b = (-b/a) * (1 + t) - hexdump(len=66): [REMOVED]
Sep 11 14:47:52 wpa_supplicant[1534]: SSWU: x1 = CSEL(l, x1a, x1b) - hexdump(len=66): [REMOVED]
Sep 11 14:47:52 wpa_supplicant[1534]: SSWU: gx1 = x1^3 + a * x1 + b - hexdump(len=66): [REMOVED]
Sep 11 14:47:52 wpa_supplicant[1534]: SSWU: x2 = z * u^2 * x1 - hexdump(len=66): [REMOVED]
Sep 11 14:47:52 wpa_supplicant[1534]: SSWU: gx2 = x2^3 + a * x2 + b - hexdump(len=66): [REMOVED]
Sep 11 14:47:52 wpa_supplicant[1534]: SSWU: gx1^((p-1)/2) modulo p - hexdump(len=66): [REMOVED]
Sep 11 14:47:52 wpa_supplicant[1534]: SSWU: v = CSEL(l, gx1, gx2) - hexdump(len=66): [REMOVED]
Sep 11 14:47:52 wpa_supplicant[1534]: SSWU: x = CSEL(l, x1, x2) - hexdump(len=66): [REMOVED]
Sep 11 14:47:52 wpa_supplicant[1534]: SSWU: y = sqrt(v) - hexdump(len=66): [REMOVED]
Sep 11 14:47:52 wpa_supplicant[1534]: SSWU: p - y - hexdump(len=66): [REMOVED]
Sep 11 14:47:52 wpa_supplicant[1534]: SSWU: P.x - hexdump(len=66): [REMOVED]
Sep 11 14:47:52 wpa_supplicant[1534]: SSWU: P.y - hexdump(len=66): [REMOVED]
Sep 11 14:47:52 wpa_supplicant[1534]: wlan0: Setting scan request: 0.000000 sec
Sep 11 14:47:52 wpa_supplicant[1534]: CTRL-DEBUG: ctrl_sock-sendto: sock=15 sndbuf=180224 outq=0 send_len=3
Sep 11 14:47:52 wpa_supplicant[1534]: wlan0: State: DISCONNECTED -> SCANNING
Sep 11 14:47:52 wpa_supplicant[1534]: Scan SSID - hexdump(len=9): 65 73 73 65 6e 74 69 65 6c
Sep 11 14:47:52 wpa_supplicant[1534]: wlan0: Starting AP scan for wildcard SSID
Sep 11 14:47:52 wpa_supplicant[1534]: wlan0: Add radio work 'scan'@0x1c3cb0
Sep 11 14:47:52 wpa_supplicant[1534]: wlan0: First radio work item in the queue - schedule start immediately
Sep 11 14:47:52 wpa_supplicant[1534]: wlan0: Starting radio work 'scan'@0x1c3cb0 after 0.000097 second wait
Sep 11 14:47:52 wpa_supplicant[1534]: wlan0: nl80211: scan request
Sep 11 14:47:52 wpa_supplicant[1534]: nl80211: Scan SSID essentiel
Sep 11 14:47:52 wpa_supplicant[1534]: nl80211: Scan SSID
Sep 11 14:47:52 wpa_supplicant[1534]: nl80211: Scan extra IEs - hexdump(len=13): 7f 0b 00 00 00 00 00 00 40 00 00 00 20
Sep 11 14:47:53 wpa_supplicant[1534]: Scan requested (ret=0) - scan timeout 30 seconds
Sep 11 14:47:53 wpa_supplicant[1534]: nl80211: Event message available
Sep 11 14:47:53 wpa_supplicant[1534]: nl80211: Drv Event 33 (NL80211_CMD_TRIGGER_SCAN) received for wlan0
Sep 11 14:47:53 wpa_supplicant[1534]: wlan0: nl80211: Scan trigger
Sep 11 14:47:53 wpa_supplicant[1534]: wlan0: Event SCAN_STARTED (47) received
Sep 11 14:47:53 wpa_supplicant[1534]: wlan0: Own scan request started a scan in 0.000395 seconds
Sep 11 14:47:53 wpa_supplicant[1534]: CTRL-DEBUG: ctrl_sock-sendmsg: sock=15 sndbuf=180224 outq=0 send_len=24
Sep 11 14:47:53 wpa_supplicant[1534]: CTRL_IFACE monitor sent successfully to /tmp/wpa_ctrl_1537-1\x00
Sep 11 14:47:54 wpa_supplicant[1534]: nl80211: Event message available
Sep 11 14:47:54 wpa_supplicant[1534]: nl80211: Drv Event 34 (NL80211_CMD_NEW_SCAN_RESULTS) received for wlan0
Sep 11 14:47:54 wpa_supplicant[1534]: wlan0: nl80211: New scan results available
Sep 11 14:47:54 wpa_supplicant[1534]: nl80211: Scan probed for SSID 'essentiel'
Sep 11 14:47:54 wpa_supplicant[1534]: nl80211: Scan probed for SSID ''
Sep 11 14:47:54 wpa_supplicant[1534]: nl80211: Scan included frequencies: 2412 2417 2422 2427 2432 2437 2442 2447 2452 2457 2462 2467 2472 2484
Sep 11 14:47:54 wpa_supplicant[1534]: wlan0: Event SCAN_RESULTS (3) received
Sep 11 14:47:54 wpa_supplicant[1534]: wlan0: Scan completed in 1.686020 seconds
Sep 11 14:47:54 wpa_supplicant[1534]: nl80211: Received scan results (1 BSSes)
Sep 11 14:47:54 wpa_supplicant[1534]: wlan0: BSS: Start scan result update 2
Sep 11 14:47:54 wpa_supplicant[1534]: wlan0: BSS: Add new id 1 BSSID d4:3a:2e:d2:4a:d8 SSID 'essentiel' freq 2427 HESSID d4:3a:2e:d2:4a:d8
Sep 11 14:47:54 wpa_supplicant[1534]: CTRL-DEBUG: ctrl_sock-sendmsg: sock=15 sndbuf=180224 outq=0 send_len=40
Sep 11 14:47:54 wpa_supplicant[1534]: CTRL_IFACE monitor sent successfully to /tmp/wpa_ctrl_1537-1\x00
Sep 11 14:47:54 wpa_supplicant[1534]: BSS: last_scan_res_used=1/32
Sep 11 14:47:54 wpa_supplicant[1534]: wlan0: New scan results available (own=1 ext=0)
Sep 11 14:47:54 wpa_supplicant[1534]: CTRL-DEBUG: ctrl_sock-sendmsg: sock=15 sndbuf=180224 outq=704 send_len=24
Sep 11 14:47:54 wpa_supplicant[1534]: CTRL_IFACE monitor sent successfully to /tmp/wpa_ctrl_1537-1\x00
Sep 11 14:47:54 wpa_supplicant[1534]: wlan0: Radio work 'scan'@0x1c3cb0 done in 1.724861 seconds
Sep 11 14:47:54 wpa_supplicant[1534]: wlan0: radio_work_free('scan'@0x1c3cb0): num_active_works --> 0
Sep 11 14:47:54 wpa_supplicant[1534]: wlan0: Scan results matching the currently selected network
Sep 11 14:47:54 wpa_supplicant[1534]: wlan0: 0: d4:3a:2e:d2:4a:d8 freq=2427 level=-19 snr=70 est_throughput=65000
Sep 11 14:47:54 wpa_supplicant[1534]: wlan0: Selecting BSS from priority group 0
Sep 11 14:47:54 wpa_supplicant[1534]: wlan0: 0: d4:3a:2e:d2:4a:d8 ssid='essentiel' wpa_ie_len=0 rsn_ie_len=30 caps=0x1c31 level=-19 freq=2427
Sep 11 14:47:54 wpa_supplicant[1534]: wlan0:    selected based on RSN IE
Sep 11 14:47:54 wpa_supplicant[1534]: wlan0:    selected BSS d4:3a:2e:d2:4a:d8 ssid='essentiel'
Sep 11 14:47:54 wpa_supplicant[1534]: wlan0: Considering connect request: reassociate: 1  selected: d4:3a:2e:d2:4a:d8  bssid: 00:00:00:00:00:00  pending: 00:00:00:00:00:00  wpa_state: SCANNING  ssid=0x1ab5c0  current_ssid=0x1ab5c0
Sep 11 14:47:54 wpa_supplicant[1534]: wlan0: Request association with d4:3a:2e:d2:4a:d8
Sep 11 14:47:54 wpa_supplicant[1534]: TDLS: TDLS is allowed in the target BSS
Sep 11 14:47:54 wpa_supplicant[1534]: TDLS: TDLS channel switch allowed in the target BSS
Sep 11 14:47:54 wpa_supplicant[1534]: wlan0: No ongoing scan/p2p-scan found to abort
Sep 11 14:47:54 wpa_supplicant[1534]: wlan0: Add radio work 'sme-connect'@0x1c0600
Sep 11 14:47:54 wpa_supplicant[1534]: wlan0: First radio work item in the queue - schedule start immediately
Sep 11 14:47:54 wpa_supplicant[1534]: wlan0: Starting radio work 'sme-connect'@0x1c0600 after 0.000114 second wait
Sep 11 14:47:54 wpa_supplicant[1534]: wlan0: WPA: clearing own WPA/RSN IE
Sep 11 14:47:54 wpa_supplicant[1534]: wlan0: RSN: clearing own RSNXE
Sep 11 14:47:54 wpa_supplicant[1534]: wlan0: Automatic auth_alg selection: 0x1
Sep 11 14:47:54 wpa_supplicant[1534]: wlan0: Using SAE auth_alg
Sep 11 14:47:54 wpa_supplicant[1534]: RSN: PMKSA cache search - network_ctx=0x1ab5c0 try_opportunistic=0 akmp=0x0
Sep 11 14:47:54 wpa_supplicant[1534]: RSN: Search for BSSID d4:3a:2e:d2:4a:d8
Sep 11 14:47:54 wpa_supplicant[1534]: RSN: No PMKSA cache entry found
Sep 11 14:47:54 wpa_supplicant[1534]: wlan0: RSN: using IEEE 802.11i/D9.0
Sep 11 14:47:54 wpa_supplicant[1534]: wlan0: WPA: Selected cipher suites: group 16 pairwise 16 key_mgmt 1026 proto 2
Sep 11 14:47:54 wpa_supplicant[1534]: wlan0: WPA: Selected mgmt group cipher 32
Sep 11 14:47:54 wpa_supplicant[1534]: wlan0: WPA: clearing AP WPA IE
Sep 11 14:47:54 wpa_supplicant[1534]: WPA: set AP RSN IE - hexdump(len=32): 30 1e 01 00 00 0f ac 04 01 00 00 0f ac 04 02 00 00 0f ac 02 00 0f ac 08 80 00 00 00 00 0f ac 06
Sep 11 14:47:54 wpa_supplicant[1534]: WPA: set AP RSNXE - hexdump(len=3): f4 01 20
Sep 11 14:47:54 wpa_supplicant[1534]: wlan0: WPA: AP group 0x10 network profile group 0x18; available group 0x10
Sep 11 14:47:54 wpa_supplicant[1534]: wlan0: WPA: using GTK CCMP
Sep 11 14:47:54 wpa_supplicant[1534]: wlan0: WPA: AP pairwise 0x10 network profile pairwise 0x18; available pairwise 0x10
Sep 11 14:47:54 wpa_supplicant[1534]: wlan0: WPA: using PTK CCMP
Sep 11 14:47:54 wpa_supplicant[1534]: wlan0: WPA: AP key_mgmt 0x402 network profile key_mgmt 0x402; available key_mgmt 0x402
Sep 11 14:47:54 wpa_supplicant[1534]: wlan0: RSN: using KEY_MGMT SAE
Sep 11 14:47:54 wpa_supplicant[1534]: wlan0: WPA: AP mgmt_group_cipher 0x20 network profile mgmt_group_cipher 0x0; available mgmt_group_cipher 0x20
Sep 11 14:47:54 wpa_supplicant[1534]: wlan0: WPA: using MGMT group cipher AES-128-CMAC
Sep 11 14:47:54 wpa_supplicant[1534]: WPA: Set own WPA IE default - hexdump(len=28): 30 1a 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 00 0f ac 08 80 00 00 00 00 0f ac 06
Sep 11 14:47:54 wpa_supplicant[1534]: RSN: Set own RSNXE default - hexdump(len=3): f4 01 20
Sep 11 14:47:54 wpa_supplicant[1534]: PSK (set in config) - hexdump(len=32): [REMOVED]
Sep 11 14:47:54 wpa_supplicant[1534]: WPA: Set PMK based on external data - hexdump(len=32): [REMOVED]
Sep 11 14:47:54 wpa_supplicant[1534]: wlan0: SME: Selected AP supports MFP: require MFP
Sep 11 14:47:54 wpa_supplicant[1534]: RRM: Determining whether RRM can be used - device support: 0x0
Sep 11 14:47:54 wpa_supplicant[1534]: RRM: Insufficient RRM support in driver - do not use RRM
Sep 11 14:47:54 wpa_supplicant[1534]: Added supported operating classes IE - hexdump(len=5): 3b 03 51 51 52
Sep 11 14:47:54 wpa_supplicant[1534]: RSN: PMKSA cache search - network_ctx=0x1ab5c0 try_opportunistic=0 akmp=0x400
Sep 11 14:47:54 wpa_supplicant[1534]: RSN: Search for BSSID d4:3a:2e:d2:4a:d8
Sep 11 14:47:54 wpa_supplicant[1534]: RSN: No PMKSA cache entry found
Sep 11 14:47:54 wpa_supplicant[1534]: SAE: Selecting supported ECC group 19
Sep 11 14:47:54 wpa_supplicant[1534]: wlan0: SME: Selected SAE group 19
Sep 11 14:47:54 wpa_supplicant[1534]: SAE: Derive PWE from PT
Sep 11 14:47:54 wpa_supplicant[1534]: SAE: PT.x - hexdump(len=32): [REMOVED]
Sep 11 14:47:54 wpa_supplicant[1534]: SAE: PT.y - hexdump(len=32): [REMOVED]
Sep 11 14:47:54 wpa_supplicant[1534]: SAE: val = H(0^n, MAX(addrs) || MIN(addrs))
Sep 11 14:47:54 wpa_supplicant[1534]: SAE: val - hexdump(len=32): e0 aa e9 c9 3e ed ae be a1 1a ac 59 97 78 dd 72 60 d8 6c ea 76 ec 26 01 1c 39 02 2f 0f 1d bb fa
Sep 11 14:47:54 wpa_supplicant[1534]: SAE: val(reduced to 1..q-1) - hexdump(len=32): [REMOVED]
Sep 11 14:47:54 wpa_supplicant[1534]: SAE: PWE.x - hexdump(len=32): [REMOVED]
Sep 11 14:47:54 wpa_supplicant[1534]: SAE: PWE.y - hexdump(len=32): [REMOVED]
Sep 11 14:47:54 wpa_supplicant[1534]: SAE: own commit-scalar - hexdump(len=32): 69 85 45 4d ab 76 7a 6c de 8a ac 62 a3 1a 79 91 82 4e a0 10 cb dc f7 8b 28 ed fe 52 46 22 cf 6e
Sep 11 14:47:54 wpa_supplicant[1534]: SAE: own commit-element(x) - hexdump(len=32): ba a6 a0 dc f6 6d c9 03 54 d3 c9 d4 85 e5 e2 f0 30 ca 2a c4 d3 5b 0f 03 ae 8c 82 f2 1c 5b 04 f9
Sep 11 14:47:54 wpa_supplicant[1534]: SAE: own commit-element(y) - hexdump(len=32): 2d f1 49 03 8a 3f cc 92 1e 78 fa 7a 7e d0 e9 8c e7 c0 f9 1b 2a f9 4b 40 cc 6f 4a 10 be 54 da cb
Sep 11 14:47:54 wpa_supplicant[1534]: EAPOL: External notification - EAP success=0
Sep 11 14:47:54 wpa_supplicant[1534]: EAPOL: External notification - EAP fail=0
Sep 11 14:47:54 wpa_supplicant[1534]: EAPOL: External notification - portControl=Auto
Sep 11 14:47:54 wpa_supplicant[1534]: wlan0: Cancelling scan request
Sep 11 14:47:54 wpa_supplicant[1534]: wlan0: SME: Trying to authenticate with d4:3a:2e:d2:4a:d8 (SSID='essentiel' freq=2427 MHz)
Sep 11 14:47:54 wpa_supplicant[1534]: CTRL-DEBUG: ctrl_sock-sendmsg: sock=15 sndbuf=180224 outq=0 send_len=83
Sep 11 14:47:54 wpa_supplicant[1534]: CTRL_IFACE monitor sent successfully to /tmp/wpa_ctrl_1537-1\x00
Sep 11 14:47:54 wpa_supplicant[1534]: EAPOL: External notification - portValid=0
Sep 11 14:47:54 wpa_supplicant[1534]: wlan0: State: SCANNING -> AUTHENTICATING
Sep 11 14:47:54 wpa_supplicant[1534]: Not configuring frame filtering - BSS 00:00:00:00:00:00 is not a Hotspot 2.0 network
Sep 11 14:47:54 wpa_supplicant[1534]: wlan0: Determining shared radio frequencies (max len 1)
Sep 11 14:47:54 wpa_supplicant[1534]: wlan0: Shared frequencies (len=0): completed iteration
Sep 11 14:47:54 wpa_supplicant[1534]: nl80211: Authenticate (ifindex=9)
Sep 11 14:47:54 wpa_supplicant[1534]:   * bssid=d4:3a:2e:d2:4a:d8
Sep 11 14:47:54 wpa_supplicant[1534]:   * freq=2427
Sep 11 14:47:54 wpa_supplicant[1534]:   * SSID=essentiel
Sep 11 14:47:54 wpa_supplicant[1534]:   * IEs - hexdump(len=0): [NULL]
Sep 11 14:47:54 wpa_supplicant[1534]:   * auth_data - hexdump(len=102): 01 00 7e 00 13 00 69 85 45 4d ab 76 7a 6c de 8a ac 62 a3 1a 79 91 82 4e a0 10 cb dc f7 8b 28 ed fe 52 46 22 cf 6e ba a6 a0 dc f6 6d c9 03 54 d3 c9 d4 85 e5 e2 f0 30 ca 2a c4 d3 5b
Sep 11 14:47:54 wpa_supplicant[1534]:   * Auth Type 4
Sep 11 14:47:54 kernel: [16410.549986] esp32_spi: esp_reg_notifier: cfg80211 regulatory domain callback for 00, current=<6>
Sep 11 14:47:54 kernel: [16764.107682] esp32_spi: cmd_auth_request: Authentication request: d4:3a:2e:d2:4a:d8 4 3 102 0
Sep 11 14:47:54 kernel: [16764.341567] esp32_spi: decode_common_resp: [0xb] Command failed
Sep 11 14:47:54 kernel: [16764.341622] esp32_spi: cmd_auth_request: wait_and_decode_cmd_resp(priv, cmd_node) failure, ret: -1
Sep 11 14:47:54 wpa_supplicant[1534]: wlan0: nl80211: MLME command failed (auth): count=0 ret=-1 (Operation not permitted)
Sep 11 14:47:54 wpa_supplicant[1534]: wlan0: SME: Authentication request to the driver failed
Sep 11 14:47:54 wpa_supplicant[1534]: CTRL-DEBUG: ctrl_sock-sendmsg: sock=15 sndbuf=180224 outq=0 send_len=48
Sep 11 14:47:54 wpa_supplicant[1534]: CTRL_IFACE monitor sent successfully to /tmp/wpa_ctrl_1537-1\x00
Sep 11 14:47:54 wpa_supplicant[1534]: wlan0: Radio work 'sme-connect'@0x1c0600 done in 0.265388 seconds
Sep 11 14:47:54 wpa_supplicant[1534]: wlan0: radio_work_free('sme-connect'@0x1c0600): num_active_works --> 0
Sep 11 14:47:54 wpa_supplicant[1534]: Added BSSID d4:3a:2e:d2:4a:d8 into ignore list, ignoring for 10 seconds
Sep 11 14:47:54 wpa_supplicant[1534]: wlan0: Consecutive connection failures: 1 --> request scan in 100 ms
Sep 11 14:47:54 wpa_supplicant[1534]: wlan0: Setting scan request: 0.100000 sec
Sep 11 14:47:54 wpa_supplicant[1534]: nl80211: Data frame filter flags=0x0
Sep 11 14:47:54 wpa_supplicant[1534]: nl80211: Failed to open /proc/sys/net/ipv6/conf/wlan0/drop_unicast_in_l2_multicast: No such file or directory
Sep 11 14:47:54 wpa_supplicant[1534]: nl80211: Failed to open /proc/sys/net/ipv6/conf/wlan0/drop_unsolicited_na: No such file or directory
Sep 11 14:47:54 wpa_supplicant[1534]: wlan0: State: AUTHENTICATING -> DISCONNECTED
Sep 11 14:47:54 wpa_supplicant[1534]: nl80211: Set wlan0 operstate 0->0 (DORMANT)
Sep 11 14:47:54 wpa_supplicant[1534]: netlink: Operstate: ifindex=9 linkmode=-1 (no change), operstate=5 (IF_OPER_DORMANT)
Sep 11 14:47:54 wpa_supplicant[1534]: EAPOL: External notification - portEnabled=0
Sep 11 14:47:55 wpa_supplicant[1534]: EAPOL: External notification - portValid=0
Sep 11 14:47:55 wpa_supplicant[1534]: EAPOL: External notification - EAP success=0
Sep 11 14:47:55 wpa_supplicant[1534]: wlan0: State: DISCONNECTED -> SCANNING
Sep 11 14:47:55 wpa_supplicant[1534]: Scan SSID - hexdump(len=9): 65 73 73 65 6e 74 69 65 6c
Sep 11 14:47:55 wpa_supplicant[1534]: wlan0: Starting AP scan for wildcard SSID
Sep 11 14:47:55 wpa_supplicant[1534]: wlan0: Add radio work 'scan'@0x1cd718
Sep 11 14:47:55 wpa_supplicant[1534]: wlan0: First radio work item in the queue - schedule start immediately
Sep 11 14:47:55 wpa_supplicant[1534]: wlan0: Starting radio work 'scan'@0x1cd718 after 0.000095 second wait
Sep 11 14:47:55 wpa_supplicant[1534]: wlan0: nl80211: scan request
Sep 11 14:47:55 wpa_supplicant[1534]: nl80211: Scan SSID essentiel
Sep 11 14:47:55 wpa_supplicant[1534]: nl80211: Scan SSID
Sep 11 14:47:55 wpa_supplicant[1534]: nl80211: Scan extra IEs - hexdump(len=13): 7f 0b 00 00 00 00 00 00 40 00 00 00 20
Sep 11 14:47:55 wpa_supplicant[1534]: Scan requested (ret=0) - scan timeout 30 seconds
Sep 11 14:47:55 wpa_supplicant[1534]: nl80211: Event message available
Sep 11 14:47:55 wpa_supplicant[1534]: nl80211: Drv Event 33 (NL80211_CMD_TRIGGER_SCAN) received for wlan0
Sep 11 14:47:55 wpa_supplicant[1534]: wlan0: nl80211: Scan trigger
Sep 11 14:47:55 wpa_supplicant[1534]: wlan0: Event SCAN_STARTED (47) received
Sep 11 14:47:55 wpa_supplicant[1534]: wlan0: Own scan request started a scan in 0.000433 seconds
Sep 11 14:47:55 wpa_supplicant[1534]: CTRL-DEBUG: ctrl_sock-sendmsg: sock=15 sndbuf=180224 outq=0 send_len=24
Sep 11 14:47:55 wpa_supplicant[1534]: CTRL_IFACE monitor sent successfully to /tmp/wpa_ctrl_1537-1\x00
Sep 11 14:47:56 wpa_supplicant[1534]: nl80211: Event message available
Sep 11 14:47:56 wpa_supplicant[1534]: nl80211: Drv Event 34 (NL80211_CMD_NEW_SCAN_RESULTS) received for wlan0
Sep 11 14:47:56 wpa_supplicant[1534]: wlan0: nl80211: New scan results available
Sep 11 14:47:56 wpa_supplicant[1534]: nl80211: Scan probed for SSID 'essentiel'
Sep 11 14:47:56 wpa_supplicant[1534]: nl80211: Scan probed for SSID ''
Sep 11 14:47:56 wpa_supplicant[1534]: nl80211: Scan included frequencies: 2412 2417 2422 2427 2432 2437 2442 2447 2452 2457 2462 2467 2472 2484
Sep 11 14:47:56 wpa_supplicant[1534]: wlan0: Event SCAN_RESULTS (3) received
Sep 11 14:47:56 wpa_supplicant[1534]: wlan0: Scan completed in 1.689824 seconds
Sep 11 14:47:56 wpa_supplicant[1534]: nl80211: Received scan results (1 BSSes)
Sep 11 14:47:56 wpa_supplicant[1534]: wlan0: BSS: Start scan result update 3
Sep 11 14:47:56 wpa_supplicant[1534]: BSS: last_scan_res_used=1/32
Sep 11 14:47:56 wpa_supplicant[1534]: wlan0: New scan results available (own=1 ext=0)
Sep 11 14:47:56 wpa_supplicant[1534]: CTRL-DEBUG: ctrl_sock-sendmsg: sock=15 sndbuf=180224 outq=0 send_len=24
Sep 11 14:47:56 wpa_supplicant[1534]: CTRL_IFACE monitor sent successfully to /tmp/wpa_ctrl_1537-1\x00
Sep 11 14:47:56 wpa_supplicant[1534]: wlan0: Radio work 'scan'@0x1cd718 done in 1.721232 seconds
Sep 11 14:47:56 wpa_supplicant[1534]: wlan0: radio_work_free('scan'@0x1cd718): num_active_works --> 0
Sep 11 14:47:56 wpa_supplicant[1534]: wlan0: Selecting BSS from priority group 0
Sep 11 14:47:56 wpa_supplicant[1534]: wlan0: 0: d4:3a:2e:d2:4a:d8 ssid='essentiel' wpa_ie_len=0 rsn_ie_len=30 caps=0x1c31 level=-19 freq=2427
Sep 11 14:47:56 wpa_supplicant[1534]: wlan0:    skip - BSSID ignored (count=1 limit=0)
Sep 11 14:47:56 wpa_supplicant[1534]: wlan0: No APs found - clear BSSID ignore list and try again
Sep 11 14:47:56 wpa_supplicant[1534]: Removed BSSID d4:3a:2e:d2:4a:d8 from ignore list (clear)
Sep 11 14:47:56 wpa_supplicant[1534]: wlan0: Selecting BSS from priority group 0
Sep 11 14:47:56 wpa_supplicant[1534]: wlan0: 0: d4:3a:2e:d2:4a:d8 ssid='essentiel' wpa_ie_len=0 rsn_ie_len=30 caps=0x1c31 level=-19 freq=2427
Sep 11 14:47:56 wpa_supplicant[1534]: wlan0:    selected based on RSN IE
Sep 11 14:47:56 wpa_supplicant[1534]: wlan0:    selected BSS d4:3a:2e:d2:4a:d8 ssid='essentiel'
Sep 11 14:47:56 wpa_supplicant[1534]: wlan0: Considering connect request: reassociate: 0  selected: d4:3a:2e:d2:4a:d8  bssid: 00:00:00:00:00:00  pending: 00:00:00:00:00:00  wpa_state: SCANNING  ssid=0x1ab5c0  current_ssid=(nil)
Sep 11 14:47:56 wpa_supplicant[1534]: wlan0: Request association with d4:3a:2e:d2:4a:d8
Sep 11 14:47:56 wpa_supplicant[1534]: wlan0: Re-association to the same ESS
Sep 11 14:47:56 wpa_supplicant[1534]: TDLS: TDLS is allowed in the target BSS
Sep 11 14:47:56 wpa_supplicant[1534]: TDLS: TDLS channel switch allowed in the target BSS
Sep 11 14:47:56 wpa_supplicant[1534]: wlan0: No ongoing scan/p2p-scan found to abort
Sep 11 14:47:56 wpa_supplicant[1534]: wlan0: Add radio work 'sme-connect'@0x1c3cb0
Sep 11 14:47:56 wpa_supplicant[1534]: wlan0: First radio work item in the queue - schedule start immediately
Sep 11 14:47:56 wpa_supplicant[1534]: wlan0: Starting radio work 'sme-connect'@0x1c3cb0 after 0.000121 second wait
Sep 11 14:47:56 wpa_supplicant[1534]: wlan0: WPA: clearing own WPA/RSN IE
Sep 11 14:47:56 wpa_supplicant[1534]: wlan0: RSN: clearing own RSNXE
Sep 11 14:47:56 wpa_supplicant[1534]: wlan0: Automatic auth_alg selection: 0x1
Sep 11 14:47:56 wpa_supplicant[1534]: wlan0: Using SAE auth_alg
Sep 11 14:47:56 wpa_supplicant[1534]: RSN: PMKSA cache search - network_ctx=(nil) try_opportunistic=0 akmp=0x0
Sep 11 14:47:56 wpa_supplicant[1534]: RSN: Search for BSSID d4:3a:2e:d2:4a:d8
Sep 11 14:47:56 wpa_supplicant[1534]: RSN: No PMKSA cache entry found
Sep 11 14:47:56 wpa_supplicant[1534]: wlan0: RSN: using IEEE 802.11i/D9.0
Sep 11 14:47:56 wpa_supplicant[1534]: wlan0: WPA: Selected cipher suites: group 16 pairwise 16 key_mgmt 1026 proto 2
Sep 11 14:47:56 wpa_supplicant[1534]: wlan0: WPA: Selected mgmt group cipher 32
Sep 11 14:47:56 wpa_supplicant[1534]: wlan0: WPA: clearing AP WPA IE
Sep 11 14:47:56 wpa_supplicant[1534]: WPA: set AP RSN IE - hexdump(len=32): 30 1e 01 00 00 0f ac 04 01 00 00 0f ac 04 02 00 00 0f ac 02 00 0f ac 08 80 00 00 00 00 0f ac 06
Sep 11 14:47:56 wpa_supplicant[1534]: WPA: set AP RSNXE - hexdump(len=3): f4 01 20
Sep 11 14:47:56 wpa_supplicant[1534]: wlan0: WPA: AP group 0x10 network profile group 0x18; available group 0x10
Sep 11 14:47:56 wpa_supplicant[1534]: wlan0: WPA: using GTK CCMP
Sep 11 14:47:56 wpa_supplicant[1534]: wlan0: WPA: AP pairwise 0x10 network profile pairwise 0x18; available pairwise 0x10
Sep 11 14:47:56 wpa_supplicant[1534]: wlan0: WPA: using PTK CCMP
Sep 11 14:47:56 wpa_supplicant[1534]: wlan0: WPA: AP key_mgmt 0x402 network profile key_mgmt 0x402; available key_mgmt 0x402
Sep 11 14:47:56 wpa_supplicant[1534]: wlan0: RSN: using KEY_MGMT SAE
Sep 11 14:47:56 wpa_supplicant[1534]: wlan0: WPA: AP mgmt_group_cipher 0x20 network profile mgmt_group_cipher 0x0; available mgmt_group_cipher 0x20
Sep 11 14:47:56 wpa_supplicant[1534]: wlan0: WPA: using MGMT group cipher AES-128-CMAC
Sep 11 14:47:56 wpa_supplicant[1534]: WPA: Set own WPA IE default - hexdump(len=28): 30 1a 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 00 0f ac 08 80 00 00 00 00 0f ac 06
Sep 11 14:47:56 wpa_supplicant[1534]: RSN: Set own RSNXE default - hexdump(len=3): f4 01 20
Sep 11 14:47:56 wpa_supplicant[1534]: PSK (set in config) - hexdump(len=32): [REMOVED]
Sep 11 14:47:56 wpa_supplicant[1534]: WPA: Set PMK based on external data - hexdump(len=32): [REMOVED]
Sep 11 14:47:56 wpa_supplicant[1534]: wlan0: SME: Selected AP supports MFP: require MFP
Sep 11 14:47:56 wpa_supplicant[1534]: RRM: Determining whether RRM can be used - device support: 0x0
Sep 11 14:47:56 wpa_supplicant[1534]: RRM: Insufficient RRM support in driver - do not use RRM
Sep 11 14:47:56 wpa_supplicant[1534]: Added supported operating classes IE - hexdump(len=5): 3b 03 51 51 52
Sep 11 14:47:56 wpa_supplicant[1534]: RSN: PMKSA cache search - network_ctx=0x1ab5c0 try_opportunistic=0 akmp=0x400
Sep 11 14:47:56 wpa_supplicant[1534]: RSN: Search for BSSID d4:3a:2e:d2:4a:d8
Sep 11 14:47:56 wpa_supplicant[1534]: RSN: No PMKSA cache entry found
Sep 11 14:47:56 wpa_supplicant[1534]: SAE: Selecting supported ECC group 19
Sep 11 14:47:56 wpa_supplicant[1534]: wlan0: SME: Selected SAE group 19
Sep 11 14:47:56 wpa_supplicant[1534]: SAE: Derive PWE from PT
Sep 11 14:47:56 wpa_supplicant[1534]: SAE: PT.x - hexdump(len=32): [REMOVED]
Sep 11 14:47:56 wpa_supplicant[1534]: SAE: PT.y - hexdump(len=32): [REMOVED]
Sep 11 14:47:56 wpa_supplicant[1534]: SAE: val = H(0^n, MAX(addrs) || MIN(addrs))
Sep 11 14:47:56 wpa_supplicant[1534]: SAE: val - hexdump(len=32): e0 aa e9 c9 3e ed ae be a1 1a ac 59 97 78 dd 72 60 d8 6c ea 76 ec 26 01 1c 39 02 2f 0f 1d bb fa
Sep 11 14:47:56 wpa_supplicant[1534]: SAE: val(reduced to 1..q-1) - hexdump(len=32): [REMOVED]
Sep 11 14:47:56 wpa_supplicant[1534]: SAE: PWE.x - hexdump(len=32): [REMOVED]
Sep 11 14:47:56 wpa_supplicant[1534]: SAE: PWE.y - hexdump(len=32): [REMOVED]
Sep 11 14:47:56 wpa_supplicant[1534]: SAE: own commit-scalar - hexdump(len=32): cc bf 24 1f 83 08 23 3f 1a c3 c7 0a 05 91 96 0d 87 4b f6 a5 9b d7 2f 7b 71 1b 66 5c ed fb 5b f9
Sep 11 14:47:56 wpa_supplicant[1534]: SAE: own commit-element(x) - hexdump(len=32): 15 cb f7 98 c8 0f 44 fb bb 34 c7 8d 7a d5 df 0d 72 fb 0c 75 f9 ce e5 dc 03 bc f8 e3 73 94 3f 73
Sep 11 14:47:56 wpa_supplicant[1534]: SAE: own commit-element(y) - hexdump(len=32): 3c 6c 3d 8d 67 5a 86 fa 3c 68 9b db 6e 51 14 b4 bf 0e e7 2c 2a 22 4c 95 9b e7 10 d0 d7 96 ba 25
Sep 11 14:47:56 wpa_supplicant[1534]: EAPOL: External notification - EAP success=0
Sep 11 14:47:56 wpa_supplicant[1534]: EAPOL: External notification - EAP fail=0
Sep 11 14:47:56 wpa_supplicant[1534]: EAPOL: External notification - portControl=Auto
Sep 11 14:47:56 wpa_supplicant[1534]: wlan0: Cancelling scan request
Sep 11 14:47:56 wpa_supplicant[1534]: wlan0: SME: Trying to authenticate with d4:3a:2e:d2:4a:d8 (SSID='essentiel' freq=2427 MHz)
Sep 11 14:47:56 wpa_supplicant[1534]: CTRL-DEBUG: ctrl_sock-sendmsg: sock=15 sndbuf=180224 outq=0 send_len=83
Sep 11 14:47:56 wpa_supplicant[1534]: CTRL_IFACE monitor sent successfully to /tmp/wpa_ctrl_1537-1\x00
Sep 11 14:47:56 wpa_supplicant[1534]: EAPOL: External notification - portValid=0
Sep 11 14:47:56 wpa_supplicant[1534]: wlan0: State: SCANNING -> AUTHENTICATING
Sep 11 14:47:56 wpa_supplicant[1534]: Not configuring frame filtering - BSS 00:00:00:00:00:00 is not a Hotspot 2.0 network
Sep 11 14:47:56 wpa_supplicant[1534]: wlan0: Determining shared radio frequencies (max len 1)
Sep 11 14:47:56 wpa_supplicant[1534]: wlan0: Shared frequencies (len=0): completed iteration
Sep 11 14:47:56 wpa_supplicant[1534]: nl80211: Authenticate (ifindex=9)
Sep 11 14:47:56 wpa_supplicant[1534]:   * bssid=d4:3a:2e:d2:4a:d8
Sep 11 14:47:56 wpa_supplicant[1534]:   * freq=2427
Sep 11 14:47:56 wpa_supplicant[1534]:   * SSID=essentiel
Sep 11 14:47:56 wpa_supplicant[1534]:   * IEs - hexdump(len=0): [NULL]
Sep 11 14:47:56 wpa_supplicant[1534]:   * auth_data - hexdump(len=102): 01 00 7e 00 13 00 cc bf 24 1f 83 08 23 3f 1a c3 c7 0a 05 91 96 0d 87 4b f6 a5 9b d7 2f 7b 71 1b 66 5c ed fb 5b f9 15 cb f7 98 c8 0f 44 fb bb 34 c7 8d 7a d5 df 0d 72 fb 0c 75 f9 ce
Sep 11 14:47:56 wpa_supplicant[1534]:   * Auth Type 4
Sep 11 14:47:56 kernel: [16766.198912] esp32_spi: cmd_auth_request: Authentication request: d4:3a:2e:d2:4a:d8 4 3 102 0
Sep 11 14:47:57 kernel: [16766.430605] esp32_spi: decode_common_resp: [0xb] Command failed
Sep 11 14:47:57 kernel: [16766.430661] esp32_spi: cmd_auth_request: wait_and_decode_cmd_resp(priv, cmd_node) failure, ret: -1
Sep 11 14:47:57 wpa_supplicant[1534]: wlan0: nl80211: MLME command failed (auth): count=0 ret=-1 (Operation not permitted)
Sep 11 14:47:57 wpa_supplicant[1534]: wlan0: SME: Authentication request to the driver failed
Sep 11 14:47:57 wpa_supplicant[1534]: CTRL-DEBUG: ctrl_sock-sendmsg: sock=15 sndbuf=180224 outq=0 send_len=48
Sep 11 14:47:57 wpa_supplicant[1534]: CTRL_IFACE monitor sent successfully to /tmp/wpa_ctrl_1537-1\x00
Sep 11 14:47:57 wpa_supplicant[1534]: wlan0: Radio work 'sme-connect'@0x1c3cb0 done in 0.265286 seconds
Sep 11 14:47:57 wpa_supplicant[1534]: wlan0: radio_work_free('sme-connect'@0x1c3cb0): num_active_works --> 0
Sep 11 14:47:57 wpa_supplicant[1534]: Added BSSID d4:3a:2e:d2:4a:d8 into ignore list, ignoring for 10 seconds
Sep 11 14:47:57 wpa_supplicant[1534]: wlan0: Consecutive connection failures: 2 --> request scan in 500 ms
Sep 11 14:47:57 wpa_supplicant[1534]: wlan0: Setting scan request: 0.500000 sec
Sep 11 14:47:57 wpa_supplicant[1534]: nl80211: Data frame filter flags=0x0
Sep 11 14:47:57 wpa_supplicant[1534]: nl80211: Failed to open /proc/sys/net/ipv6/conf/wlan0/drop_unicast_in_l2_multicast: No such file or directory
Sep 11 14:47:57 wpa_supplicant[1534]: nl80211: Failed to open /proc/sys/net/ipv6/conf/wlan0/drop_unsolicited_na: No such file or directory
Sep 11 14:47:57 wpa_supplicant[1534]: wlan0: State: AUTHENTICATING -> DISCONNECTED
Sep 11 14:47:57 wpa_supplicant[1534]: nl80211: Set wlan0 operstate 0->0 (DORMANT)
Sep 11 14:47:57 wpa_supplicant[1534]: netlink: Operstate: ifindex=9 linkmode=-1 (no change), operstate=5 (IF_OPER_DORMANT)
Sep 11 14:47:57 wpa_supplicant[1534]: EAPOL: External notification - portEnabled=0
Sep 11 14:47:57 wpa_supplicant[1534]: EAPOL: External notification - portValid=0
Sep 11 14:47:57 wpa_supplicant[1534]: EAPOL: External notification - EAP success=0
Sep 11 14:47:57 wpa_supplicant[1534]: wlan0: State: DISCONNECTED -> SCANNING
Sep 11 14:47:57 wpa_supplicant[1534]: Scan SSID - hexdump(len=9): 65 73 73 65 6e 74 69 65 6c
Sep 11 14:47:57 wpa_supplicant[1534]: wlan0: Starting AP scan for wildcard SSID
Sep 11 14:47:57 wpa_supplicant[1534]: wlan0: Add radio work 'scan'@0x1bf5c0
Sep 11 14:47:57 wpa_supplicant[1534]: wlan0: First radio work item in the queue - schedule start immediately
Sep 11 14:47:57 wpa_supplicant[1534]: wlan0: Starting radio work 'scan'@0x1bf5c0 after 0.000099 second wait
Sep 11 14:47:57 wpa_supplicant[1534]: wlan0: nl80211: scan request
Sep 11 14:47:57 wpa_supplicant[1534]: nl80211: Scan SSID essentiel
Sep 11 14:47:57 wpa_supplicant[1534]: nl80211: Scan SSID
Sep 11 14:47:57 wpa_supplicant[1534]: nl80211: Scan extra IEs - hexdump(len=13): 7f 0b 00 00 00 00 00 00 40 00 00 00 20
Sep 11 14:47:57 wpa_supplicant[1534]: Scan requested (ret=0) - scan timeout 30 seconds
Sep 11 14:47:57 wpa_supplicant[1534]: nl80211: Event message available
Sep 11 14:47:57 wpa_supplicant[1534]: nl80211: Drv Event 33 (NL80211_CMD_TRIGGER_SCAN) received for wlan0
Sep 11 14:47:57 wpa_supplicant[1534]: wlan0: nl80211: Scan trigger
Sep 11 14:47:57 wpa_supplicant[1534]: wlan0: Event SCAN_STARTED (47) received
Sep 11 14:47:57 wpa_supplicant[1534]: wlan0: Own scan request started a scan in 0.000398 seconds
Sep 11 14:47:57 wpa_supplicant[1534]: CTRL-DEBUG: ctrl_sock-sendmsg: sock=15 sndbuf=180224 outq=0 send_len=24
Sep 11 14:47:57 wpa_supplicant[1534]: CTRL_IFACE monitor sent successfully to /tmp/wpa_ctrl_1537-1\x00
Sep 11 14:47:59 wpa_supplicant[1534]: nl80211: Event message available
Sep 11 14:47:59 wpa_supplicant[1534]: nl80211: Drv Event 34 (NL80211_CMD_NEW_SCAN_RESULTS) received for wlan0
Sep 11 14:47:59 wpa_supplicant[1534]: wlan0: nl80211: New scan results available
Sep 11 14:47:59 wpa_supplicant[1534]: nl80211: Scan probed for SSID 'essentiel'
Sep 11 14:47:59 wpa_supplicant[1534]: nl80211: Scan probed for SSID ''
Sep 11 14:47:59 wpa_supplicant[1534]: nl80211: Scan included frequencies: 2412 2417 2422 2427 2432 2437 2442 2447 2452 2457 2462 2467 2472 2484
Sep 11 14:47:59 wpa_supplicant[1534]: wlan0: Event SCAN_RESULTS (3) received
Sep 11 14:47:59 wpa_supplicant[1534]: wlan0: Scan completed in 1.689836 seconds
Sep 11 14:47:59 wpa_supplicant[1534]: nl80211: Received scan results (1 BSSes)
Sep 11 14:47:59 wpa_supplicant[1534]: wlan0: BSS: Start scan result update 4
Sep 11 14:47:59 wpa_supplicant[1534]: BSS: last_scan_res_used=1/32
Sep 11 14:47:59 wpa_supplicant[1534]: wlan0: New scan results available (own=1 ext=0)
Sep 11 14:47:59 wpa_supplicant[1534]: CTRL-DEBUG: ctrl_sock-sendmsg: sock=15 sndbuf=180224 outq=0 send_len=24
Sep 11 14:47:59 wpa_supplicant[1534]: CTRL_IFACE monitor sent successfully to /tmp/wpa_ctrl_1537-1\x00
Sep 11 14:47:59 wpa_supplicant[1534]: wlan0: Radio work 'scan'@0x1bf5c0 done in 1.723501 seconds
Sep 11 14:47:59 wpa_supplicant[1534]: wlan0: radio_work_free('scan'@0x1bf5c0): num_active_works --> 0
Sep 11 14:47:59 wpa_supplicant[1534]: wlan0: Selecting BSS from priority group 0
Sep 11 14:47:59 wpa_supplicant[1534]: wlan0: 0: d4:3a:2e:d2:4a:d8 ssid='essentiel' wpa_ie_len=0 rsn_ie_len=30 caps=0x1c31 level=-19 freq=2427
Sep 11 14:47:59 wpa_supplicant[1534]: wlan0:    skip - BSSID ignored (count=1 limit=0)
Sep 11 14:47:59 wpa_supplicant[1534]: wlan0: No APs found - clear BSSID ignore list and try again
Sep 11 14:47:59 wpa_supplicant[1534]: Removed BSSID d4:3a:2e:d2:4a:d8 from ignore list (clear)
Sep 11 14:47:59 wpa_supplicant[1534]: wlan0: Selecting BSS from priority group 0
Sep 11 14:47:59 wpa_supplicant[1534]: wlan0: 0: d4:3a:2e:d2:4a:d8 ssid='essentiel' wpa_ie_len=0 rsn_ie_len=30 caps=0x1c31 level=-19 freq=2427
Sep 11 14:47:59 wpa_supplicant[1534]: wlan0:    selected based on RSN IE
Sep 11 14:47:59 wpa_supplicant[1534]: wlan0:    selected BSS d4:3a:2e:d2:4a:d8 ssid='essentiel'
Sep 11 14:47:59 wpa_supplicant[1534]: wlan0: Considering connect request: reassociate: 0  selected: d4:3a:2e:d2:4a:d8  bssid: 00:00:00:00:00:00  pending: 00:00:00:00:00:00  wpa_state: SCANNING  ssid=0x1ab5c0  current_ssid=(nil)
Sep 11 14:47:59 wpa_supplicant[1534]: wlan0: Request association with d4:3a:2e:d2:4a:d8
Sep 11 14:47:59 wpa_supplicant[1534]: wlan0: Re-association to the same ESS
Sep 11 14:47:59 wpa_supplicant[1534]: TDLS: TDLS is allowed in the target BSS
Sep 11 14:47:59 wpa_supplicant[1534]: TDLS: TDLS channel switch allowed in the target BSS
Sep 11 14:47:59 wpa_supplicant[1534]: wlan0: No ongoing scan/p2p-scan found to abort
Sep 11 14:47:59 wpa_supplicant[1534]: wlan0: Add radio work 'sme-connect'@0x1c09c8
Sep 11 14:47:59 wpa_supplicant[1534]: wlan0: First radio work item in the queue - schedule start immediately
Sep 11 14:47:59 wpa_supplicant[1534]: wlan0: Starting radio work 'sme-connect'@0x1c09c8 after 0.000111 second wait
Sep 11 14:47:59 wpa_supplicant[1534]: wlan0: WPA: clearing own WPA/RSN IE
Sep 11 14:47:59 wpa_supplicant[1534]: wlan0: RSN: clearing own RSNXE
Sep 11 14:47:59 wpa_supplicant[1534]: wlan0: Automatic auth_alg selection: 0x1
Sep 11 14:47:59 wpa_supplicant[1534]: wlan0: Using SAE auth_alg
Sep 11 14:47:59 wpa_supplicant[1534]: RSN: PMKSA cache search - network_ctx=(nil) try_opportunistic=0 akmp=0x0
Sep 11 14:47:59 wpa_supplicant[1534]: RSN: Search for BSSID d4:3a:2e:d2:4a:d8
Sep 11 14:47:59 wpa_supplicant[1534]: RSN: No PMKSA cache entry found
Sep 11 14:47:59 wpa_supplicant[1534]: wlan0: RSN: using IEEE 802.11i/D9.0
Sep 11 14:47:59 wpa_supplicant[1534]: wlan0: WPA: Selected cipher suites: group 16 pairwise 16 key_mgmt 1026 proto 2
Sep 11 14:47:59 wpa_supplicant[1534]: wlan0: WPA: Selected mgmt group cipher 32
Sep 11 14:47:59 wpa_supplicant[1534]: wlan0: WPA: clearing AP WPA IE
Sep 11 14:47:59 wpa_supplicant[1534]: WPA: set AP RSN IE - hexdump(len=32): 30 1e 01 00 00 0f ac 04 01 00 00 0f ac 04 02 00 00 0f ac 02 00 0f ac 08 80 00 00 00 00 0f ac 06
Sep 11 14:47:59 wpa_supplicant[1534]: WPA: set AP RSNXE - hexdump(len=3): f4 01 20
Sep 11 14:47:59 wpa_supplicant[1534]: wlan0: WPA: AP group 0x10 network profile group 0x18; available group 0x10
Sep 11 14:47:59 wpa_supplicant[1534]: wlan0: WPA: using GTK CCMP
Sep 11 14:47:59 wpa_supplicant[1534]: wlan0: WPA: AP pairwise 0x10 network profile pairwise 0x18; available pairwise 0x10
Sep 11 14:47:59 wpa_supplicant[1534]: wlan0: WPA: using PTK CCMP
Sep 11 14:47:59 wpa_supplicant[1534]: wlan0: WPA: AP key_mgmt 0x402 network profile key_mgmt 0x402; available key_mgmt 0x402
Sep 11 14:47:59 wpa_supplicant[1534]: wlan0: RSN: using KEY_MGMT SAE
Sep 11 14:47:59 wpa_supplicant[1534]: wlan0: WPA: AP mgmt_group_cipher 0x20 network profile mgmt_group_cipher 0x0; available mgmt_group_cipher 0x20
Sep 11 14:47:59 wpa_supplicant[1534]: wlan0: WPA: using MGMT group cipher AES-128-CMAC
Sep 11 14:47:59 wpa_supplicant[1534]: WPA: Set own WPA IE default - hexdump(len=28): 30 1a 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 00 0f ac 08 80 00 00 00 00 0f ac 06
Sep 11 14:47:59 wpa_supplicant[1534]: RSN: Set own RSNXE default - hexdump(len=3): f4 01 20
Sep 11 14:47:59 wpa_supplicant[1534]: PSK (set in config) - hexdump(len=32): [REMOVED]
Sep 11 14:47:59 wpa_supplicant[1534]: WPA: Set PMK based on external data - hexdump(len=32): [REMOVED]
Sep 11 14:47:59 wpa_supplicant[1534]: wlan0: SME: Selected AP supports MFP: require MFP
Sep 11 14:47:59 wpa_supplicant[1534]: RRM: Determining whether RRM can be used - device support: 0x0
Sep 11 14:47:59 wpa_supplicant[1534]: RRM: Insufficient RRM support in driver - do not use RRM
Sep 11 14:47:59 wpa_supplicant[1534]: Added supported operating classes IE - hexdump(len=5): 3b 03 51 51 52
Sep 11 14:47:59 wpa_supplicant[1534]: RSN: PMKSA cache search - network_ctx=0x1ab5c0 try_opportunistic=0 akmp=0x400
Sep 11 14:47:59 wpa_supplicant[1534]: RSN: Search for BSSID d4:3a:2e:d2:4a:d8
Sep 11 14:47:59 wpa_supplicant[1534]: RSN: No PMKSA cache entry found
Sep 11 14:47:59 wpa_supplicant[1534]: SAE: Selecting supported ECC group 19
Sep 11 14:47:59 wpa_supplicant[1534]: wlan0: SME: Selected SAE group 19
Sep 11 14:47:59 wpa_supplicant[1534]: SAE: Derive PWE from PT
Sep 11 14:47:59 wpa_supplicant[1534]: SAE: PT.x - hexdump(len=32): [REMOVED]
Sep 11 14:47:59 wpa_supplicant[1534]: SAE: PT.y - hexdump(len=32): [REMOVED]
Sep 11 14:47:59 wpa_supplicant[1534]: SAE: val = H(0^n, MAX(addrs) || MIN(addrs))
Sep 11 14:47:59 wpa_supplicant[1534]: SAE: val - hexdump(len=32): e0 aa e9 c9 3e ed ae be a1 1a ac 59 97 78 dd 72 60 d8 6c ea 76 ec 26 01 1c 39 02 2f 0f 1d bb fa
Sep 11 14:47:59 wpa_supplicant[1534]: SAE: val(reduced to 1..q-1) - hexdump(len=32): [REMOVED]
Sep 11 14:47:59 wpa_supplicant[1534]: SAE: PWE.x - hexdump(len=32): [REMOVED]
Sep 11 14:47:59 wpa_supplicant[1534]: SAE: PWE.y - hexdump(len=32): [REMOVED]
Sep 11 14:47:59 wpa_supplicant[1534]: SAE: own commit-scalar - hexdump(len=32): 72 1f 09 d0 30 49 ad 98 a6 bb 49 44 bf 8a 26 ae 42 c5 8f 1f 2a 90 ba 15 c0 3f 72 26 f2 87 6a bd
Sep 11 14:47:59 wpa_supplicant[1534]: SAE: own commit-element(x) - hexdump(len=32): c3 e4 e5 c1 9b 5c c2 e1 be d7 d8 58 98 c5 ba 59 13 45 9b a6 bf a2 27 9c 3d bd 14 ea 3c 94 ea 53
Sep 11 14:47:59 wpa_supplicant[1534]: SAE: own commit-element(y) - hexdump(len=32): be 57 70 a2 a7 ff cb a6 87 bf a7 54 a4 ed 39 02 32 dc 07 88 71 8e bf 2a 47 3e d2 86 66 7e 9e b3
Sep 11 14:47:59 wpa_supplicant[1534]: EAPOL: External notification - EAP success=0
Sep 11 14:47:59 wpa_supplicant[1534]: EAPOL: External notification - EAP fail=0
Sep 11 14:47:59 wpa_supplicant[1534]: EAPOL: External notification - portControl=Auto
Sep 11 14:47:59 wpa_supplicant[1534]: wlan0: Cancelling scan request
Sep 11 14:47:59 wpa_supplicant[1534]: wlan0: SME: Trying to authenticate with d4:3a:2e:d2:4a:d8 (SSID='essentiel' freq=2427 MHz)
Sep 11 14:47:59 wpa_supplicant[1534]: CTRL-DEBUG: ctrl_sock-sendmsg: sock=15 sndbuf=180224 outq=0 send_len=83
Sep 11 14:47:59 wpa_supplicant[1534]: CTRL_IFACE monitor sent successfully to /tmp/wpa_ctrl_1537-1\x00
Sep 11 14:47:59 wpa_supplicant[1534]: EAPOL: External notification - portValid=0
Sep 11 14:47:59 wpa_supplicant[1534]: wlan0: State: SCANNING -> AUTHENTICATING
Sep 11 14:47:59 wpa_supplicant[1534]: Not configuring frame filtering - BSS 00:00:00:00:00:00 is not a Hotspot 2.0 network
Sep 11 14:47:59 wpa_supplicant[1534]: wlan0: Determining shared radio frequencies (max len 1)
Sep 11 14:47:59 wpa_supplicant[1534]: wlan0: Shared frequencies (len=0): completed iteration
Sep 11 14:47:59 wpa_supplicant[1534]: nl80211: Authenticate (ifindex=9)
Sep 11 14:47:59 wpa_supplicant[1534]:   * bssid=d4:3a:2e:d2:4a:d8
Sep 11 14:47:59 wpa_supplicant[1534]:   * freq=2427
Sep 11 14:47:59 wpa_supplicant[1534]:   * SSID=essentiel
Sep 11 14:47:59 wpa_supplicant[1534]:   * IEs - hexdump(len=0): [NULL]
Sep 11 14:47:59 wpa_supplicant[1534]:   * auth_data - hexdump(len=102): 01 00 7e 00 13 00 72 1f 09 d0 30 49 ad 98 a6 bb 49 44 bf 8a 26 ae 42 c5 8f 1f 2a 90 ba 15 c0 3f 72 26 f2 87 6a bd c3 e4 e5 c1 9b 5c c2 e1 be d7 d8 58 98 c5 ba 59 13 45 9b a6 bf a2
Sep 11 14:47:59 wpa_supplicant[1534]:   * Auth Type 4
Sep 11 14:47:59 kernel: [16768.691160] esp32_spi: cmd_auth_request: Authentication request: d4:3a:2e:d2:4a:d8 4 3 102 0
Sep 11 14:47:59 kernel: [16768.921117] esp32_spi: decode_common_resp: [0xb] Command failed
Sep 11 14:47:59 kernel: [16768.921168] esp32_spi: cmd_auth_request: wait_and_decode_cmd_resp(priv, cmd_node) failure, ret: -1
Sep 11 14:47:59 wpa_supplicant[1534]: wlan0: nl80211: MLME command failed (auth): count=0 ret=-1 (Operation not permitted)
Sep 11 14:47:59 wpa_supplicant[1534]: wlan0: SME: Authentication request to the driver failed
Sep 11 14:47:59 wpa_supplicant[1534]: CTRL-DEBUG: ctrl_sock-sendmsg: sock=15 sndbuf=180224 outq=0 send_len=48
Sep 11 14:47:59 wpa_supplicant[1534]: CTRL_IFACE monitor sent successfully to /tmp/wpa_ctrl_1537-1\x00
Sep 11 14:47:59 wpa_supplicant[1534]: wlan0: Radio work 'sme-connect'@0x1c09c8 done in 0.263511 seconds
Sep 11 14:47:59 wpa_supplicant[1534]: wlan0: radio_work_free('sme-connect'@0x1c09c8): num_active_works --> 0
Sep 11 14:47:59 wpa_supplicant[1534]: Added BSSID d4:3a:2e:d2:4a:d8 into ignore list, ignoring for 10 seconds
Sep 11 14:47:59 wpa_supplicant[1534]: wlan0: Consecutive connection failures: 3 --> request scan in 1000 ms
Sep 11 14:47:59 wpa_supplicant[1534]: wlan0: Setting scan request: 1.000000 sec
Sep 11 14:47:59 wpa_supplicant[1534]: nl80211: Data frame filter flags=0x0
Sep 11 14:47:59 wpa_supplicant[1534]: nl80211: Failed to open /proc/sys/net/ipv6/conf/wlan0/drop_unicast_in_l2_multicast: No such file or directory
Sep 11 14:47:59 wpa_supplicant[1534]: nl80211: Failed to open /proc/sys/net/ipv6/conf/wlan0/drop_unsolicited_na: No such file or directory
Sep 11 14:47:59 wpa_supplicant[1534]: wlan0: State: AUTHENTICATING -> DISCONNECTED
Sep 11 14:47:59 wpa_supplicant[1534]: nl80211: Set wlan0 operstate 0->0 (DORMANT)
Sep 11 14:47:59 wpa_supplicant[1534]: netlink: Operstate: ifindex=9 linkmode=-1 (no change), operstate=5 (IF_OPER_DORMANT)
Sep 11 14:47:59 wpa_supplicant[1534]: EAPOL: External notification - portEnabled=0
Sep 11 14:47:59 wpa_supplicant[1534]: EAPOL: External notification - portValid=0
Sep 11 14:47:59 wpa_supplicant[1534]: EAPOL: External notification - EAP success=0
Sep 11 14:48:00 wpa_supplicant[1534]: wlan0: State: DISCONNECTED -> SCANNING
Sep 11 14:48:00 wpa_supplicant[1534]: Scan SSID - hexdump(len=9): 65 73 73 65 6e 74 69 65 6c
Sep 11 14:48:00 wpa_supplicant[1534]: wlan0: Starting AP scan for wildcard SSID
Sep 11 14:48:00 wpa_supplicant[1534]: wlan0: Add radio work 'scan'@0x1c0648
Sep 11 14:48:00 wpa_supplicant[1534]: wlan0: First radio work item in the queue - schedule start immediately
Sep 11 14:48:00 wpa_supplicant[1534]: wlan0: Starting radio work 'scan'@0x1c0648 after 0.000103 second wait
Sep 11 14:48:00 wpa_supplicant[1534]: wlan0: nl80211: scan request
Sep 11 14:48:00 wpa_supplicant[1534]: nl80211: Scan SSID essentiel
Sep 11 14:48:00 wpa_supplicant[1534]: nl80211: Scan SSID
Sep 11 14:48:00 wpa_supplicant[1534]: nl80211: Scan extra IEs - hexdump(len=13): 7f 0b 00 00 00 00 00 00 40 00 00 00 20
Sep 11 14:48:00 wpa_supplicant[1534]: Scan requested (ret=0) - scan timeout 30 seconds
Sep 11 14:48:00 wpa_supplicant[1534]: nl80211: Event message available
Sep 11 14:48:00 wpa_supplicant[1534]: nl80211: Drv Event 33 (NL80211_CMD_TRIGGER_SCAN) received for wlan0
Sep 11 14:48:00 wpa_supplicant[1534]: wlan0: nl80211: Scan trigger
Sep 11 14:48:00 wpa_supplicant[1534]: wlan0: Event SCAN_STARTED (47) received
Sep 11 14:48:00 wpa_supplicant[1534]: wlan0: Own scan request started a scan in 0.000334 seconds
Sep 11 14:48:00 wpa_supplicant[1534]: CTRL-DEBUG: ctrl_sock-sendmsg: sock=15 sndbuf=180224 outq=0 send_len=24
Sep 11 14:48:00 wpa_supplicant[1534]: CTRL_IFACE monitor sent successfully to /tmp/wpa_ctrl_1537-1\x00
Sep 11 14:48:02 wpa_supplicant[1534]: nl80211: Event message available
Sep 11 14:48:02 wpa_supplicant[1534]: nl80211: Drv Event 34 (NL80211_CMD_NEW_SCAN_RESULTS) received for wlan0
Sep 11 14:48:02 wpa_supplicant[1534]: wlan0: nl80211: New scan results available
Sep 11 14:48:02 wpa_supplicant[1534]: nl80211: Scan probed for SSID 'essentiel'
Sep 11 14:48:02 wpa_supplicant[1534]: nl80211: Scan probed for SSID ''
Sep 11 14:48:02 wpa_supplicant[1534]: nl80211: Scan included frequencies: 2412 2417 2422 2427 2432 2437 2442 2447 2452 2457 2462 2467 2472 2484
Sep 11 14:48:02 wpa_supplicant[1534]: wlan0: Event SCAN_RESULTS (3) received
Sep 11 14:48:02 wpa_supplicant[1534]: wlan0: Scan completed in 1.690385 seconds
Sep 11 14:48:02 wpa_supplicant[1534]: nl80211: Received scan results (1 BSSes)
Sep 11 14:48:02 wpa_supplicant[1534]: wlan0: BSS: Start scan result update 5
Sep 11 14:48:02 wpa_supplicant[1534]: BSS: last_scan_res_used=1/32
Sep 11 14:48:02 wpa_supplicant[1534]: wlan0: New scan results available (own=1 ext=0)
Sep 11 14:48:02 wpa_supplicant[1534]: CTRL-DEBUG: ctrl_sock-sendmsg: sock=15 sndbuf=180224 outq=0 send_len=24
Sep 11 14:48:02 wpa_supplicant[1534]: CTRL_IFACE monitor sent successfully to /tmp/wpa_ctrl_1537-1\x00
Sep 11 14:48:02 wpa_supplicant[1534]: wlan0: Radio work 'scan'@0x1c0648 done in 1.721913 seconds
Sep 11 14:48:02 wpa_supplicant[1534]: wlan0: radio_work_free('scan'@0x1c0648): num_active_works --> 0
Sep 11 14:48:02 wpa_supplicant[1534]: wlan0: Selecting BSS from priority group 0
Sep 11 14:48:02 wpa_supplicant[1534]: wlan0: 0: d4:3a:2e:d2:4a:d8 ssid='essentiel' wpa_ie_len=0 rsn_ie_len=30 caps=0x1c31 level=-19 freq=2427
Sep 11 14:48:02 wpa_supplicant[1534]: wlan0:    skip - BSSID ignored (count=1 limit=0)
Sep 11 14:48:02 wpa_supplicant[1534]: wlan0: No APs found - clear BSSID ignore list and try again
Sep 11 14:48:02 wpa_supplicant[1534]: Removed BSSID d4:3a:2e:d2:4a:d8 from ignore list (clear)
Sep 11 14:48:02 wpa_supplicant[1534]: wlan0: Selecting BSS from priority group 0
Sep 11 14:48:02 wpa_supplicant[1534]: wlan0: 0: d4:3a:2e:d2:4a:d8 ssid='essentiel' wpa_ie_len=0 rsn_ie_len=30 caps=0x1c31 level=-19 freq=2427
Sep 11 14:48:02 wpa_supplicant[1534]: wlan0:    selected based on RSN IE
Sep 11 14:48:02 wpa_supplicant[1534]: wlan0:    selected BSS d4:3a:2e:d2:4a:d8 ssid='essentiel'
Sep 11 14:48:02 wpa_supplicant[1534]: wlan0: Considering connect request: reassociate: 0  selected: d4:3a:2e:d2:4a:d8  bssid: 00:00:00:00:00:00  pending: 00:00:00:00:00:00  wpa_state: SCANNING  ssid=0x1ab5c0  current_ssid=(nil)
Sep 11 14:48:02 wpa_supplicant[1534]: wlan0: Request association with d4:3a:2e:d2:4a:d8
Sep 11 14:48:02 wpa_supplicant[1534]: wlan0: Re-association to the same ESS
Sep 11 14:48:02 wpa_supplicant[1534]: TDLS: TDLS is allowed in the target BSS
Sep 11 14:48:02 wpa_supplicant[1534]: TDLS: TDLS channel switch allowed in the target BSS
Sep 11 14:48:02 wpa_supplicant[1534]: wlan0: No ongoing scan/p2p-scan found to abort
Sep 11 14:48:02 wpa_supplicant[1534]: wlan0: Add radio work 'sme-connect'@0x1c3cb0
Sep 11 14:48:02 wpa_supplicant[1534]: wlan0: First radio work item in the queue - schedule start immediately
Sep 11 14:48:02 wpa_supplicant[1534]: wlan0: Starting radio work 'sme-connect'@0x1c3cb0 after 0.000109 second wait
Sep 11 14:48:02 wpa_supplicant[1534]: wlan0: WPA: clearing own WPA/RSN IE
Sep 11 14:48:02 wpa_supplicant[1534]: wlan0: RSN: clearing own RSNXE
Sep 11 14:48:02 wpa_supplicant[1534]: wlan0: Automatic auth_alg selection: 0x1
Sep 11 14:48:02 wpa_supplicant[1534]: wlan0: Using SAE auth_alg
Sep 11 14:48:02 wpa_supplicant[1534]: RSN: PMKSA cache search - network_ctx=(nil) try_opportunistic=0 akmp=0x0
Sep 11 14:48:02 wpa_supplicant[1534]: RSN: Search for BSSID d4:3a:2e:d2:4a:d8
Sep 11 14:48:02 wpa_supplicant[1534]: RSN: No PMKSA cache entry found
Sep 11 14:48:02 wpa_supplicant[1534]: wlan0: RSN: using IEEE 802.11i/D9.0
Sep 11 14:48:02 wpa_supplicant[1534]: wlan0: WPA: Selected cipher suites: group 16 pairwise 16 key_mgmt 1026 proto 2
Sep 11 14:48:02 wpa_supplicant[1534]: wlan0: WPA: Selected mgmt group cipher 32
Sep 11 14:48:02 wpa_supplicant[1534]: wlan0: WPA: clearing AP WPA IE
Sep 11 14:48:02 wpa_supplicant[1534]: WPA: set AP RSN IE - hexdump(len=32): 30 1e 01 00 00 0f ac 04 01 00 00 0f ac 04 02 00 00 0f ac 02 00 0f ac 08 80 00 00 00 00 0f ac 06
Sep 11 14:48:02 wpa_supplicant[1534]: WPA: set AP RSNXE - hexdump(len=3): f4 01 20
Sep 11 14:48:02 wpa_supplicant[1534]: wlan0: WPA: AP group 0x10 network profile group 0x18; available group 0x10
Sep 11 14:48:02 wpa_supplicant[1534]: wlan0: WPA: using GTK CCMP
Sep 11 14:48:02 wpa_supplicant[1534]: wlan0: WPA: AP pairwise 0x10 network profile pairwise 0x18; available pairwise 0x10
Sep 11 14:48:02 wpa_supplicant[1534]: wlan0: WPA: using PTK CCMP
Sep 11 14:48:02 wpa_supplicant[1534]: wlan0: WPA: AP key_mgmt 0x402 network profile key_mgmt 0x402; available key_mgmt 0x402
Sep 11 14:48:02 wpa_supplicant[1534]: wlan0: RSN: using KEY_MGMT SAE
Sep 11 14:48:02 wpa_supplicant[1534]: wlan0: WPA: AP mgmt_group_cipher 0x20 network profile mgmt_group_cipher 0x0; available mgmt_group_cipher 0x20
Sep 11 14:48:02 wpa_supplicant[1534]: wlan0: WPA: using MGMT group cipher AES-128-CMAC
Sep 11 14:48:02 wpa_supplicant[1534]: WPA: Set own WPA IE default - hexdump(len=28): 30 1a 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 00 0f ac 08 80 00 00 00 00 0f ac 06
Sep 11 14:48:02 wpa_supplicant[1534]: RSN: Set own RSNXE default - hexdump(len=3): f4 01 20
Sep 11 14:48:02 wpa_supplicant[1534]: PSK (set in config) - hexdump(len=32): [REMOVED]
Sep 11 14:48:02 wpa_supplicant[1534]: WPA: Set PMK based on external data - hexdump(len=32): [REMOVED]
Sep 11 14:48:02 wpa_supplicant[1534]: wlan0: SME: Selected AP supports MFP: require MFP
Sep 11 14:48:02 wpa_supplicant[1534]: RRM: Determining whether RRM can be used - device support: 0x0
Sep 11 14:48:02 wpa_supplicant[1534]: RRM: Insufficient RRM support in driver - do not use RRM
Sep 11 14:48:02 wpa_supplicant[1534]: Added supported operating classes IE - hexdump(len=5): 3b 03 51 51 52
Sep 11 14:48:02 wpa_supplicant[1534]: RSN: PMKSA cache search - network_ctx=0x1ab5c0 try_opportunistic=0 akmp=0x400
Sep 11 14:48:02 wpa_supplicant[1534]: RSN: Search for BSSID d4:3a:2e:d2:4a:d8
Sep 11 14:48:02 wpa_supplicant[1534]: RSN: No PMKSA cache entry found
Sep 11 14:48:02 wpa_supplicant[1534]: SAE: Selecting supported ECC group 19
Sep 11 14:48:02 wpa_supplicant[1534]: wlan0: SME: Selected SAE group 19
Sep 11 14:48:02 wpa_supplicant[1534]: SAE: Derive PWE from PT
Sep 11 14:48:02 wpa_supplicant[1534]: SAE: PT.x - hexdump(len=32): [REMOVED]
Sep 11 14:48:02 wpa_supplicant[1534]: SAE: PT.y - hexdump(len=32): [REMOVED]
Sep 11 14:48:02 wpa_supplicant[1534]: SAE: val = H(0^n, MAX(addrs) || MIN(addrs))
Sep 11 14:48:02 wpa_supplicant[1534]: SAE: val - hexdump(len=32): e0 aa e9 c9 3e ed ae be a1 1a ac 59 97 78 dd 72 60 d8 6c ea 76 ec 26 01 1c 39 02 2f 0f 1d bb fa
Sep 11 14:48:02 wpa_supplicant[1534]: SAE: val(reduced to 1..q-1) - hexdump(len=32): [REMOVED]
Sep 11 14:48:02 wpa_supplicant[1534]: SAE: PWE.x - hexdump(len=32): [REMOVED]
Sep 11 14:48:02 wpa_supplicant[1534]: SAE: PWE.y - hexdump(len=32): [REMOVED]
Sep 11 14:48:02 wpa_supplicant[1534]: SAE: own commit-scalar - hexdump(len=32): e3 ac 7c 0e 8c e9 7b b4 89 79 57 6e e9 03 34 0c 0d b5 06 f2 6e b4 2b 9d b0 44 0b d9 47 e7 44 01
Sep 11 14:48:02 wpa_supplicant[1534]: SAE: own commit-element(x) - hexdump(len=32): b8 cc e2 e9 00 ac 06 d2 50 45 f5 0f 58 4a c1 e1 7a fc 0a 58 0a f1 80 1b 0d 37 bf 9a 60 a1 70 16
Sep 11 14:48:02 wpa_supplicant[1534]: SAE: own commit-element(y) - hexdump(len=32): 4b fa 45 3d 1a 25 71 37 68 6e dc c3 49 7c 1a e3 3d c7 33 17 79 d6 1e 52 dc c2 5e c7 7e 63 99 74
Sep 11 14:48:02 wpa_supplicant[1534]: EAPOL: External notification - EAP success=0
Sep 11 14:48:02 wpa_supplicant[1534]: EAPOL: External notification - EAP fail=0
Sep 11 14:48:02 wpa_supplicant[1534]: EAPOL: External notification - portControl=Auto
Sep 11 14:48:02 wpa_supplicant[1534]: wlan0: Cancelling scan request
Sep 11 14:48:02 wpa_supplicant[1534]: wlan0: SME: Trying to authenticate with d4:3a:2e:d2:4a:d8 (SSID='essentiel' freq=2427 MHz)
Sep 11 14:48:02 wpa_supplicant[1534]: CTRL-DEBUG: ctrl_sock-sendmsg: sock=15 sndbuf=180224 outq=0 send_len=83
Sep 11 14:48:02 wpa_supplicant[1534]: CTRL_IFACE monitor sent successfully to /tmp/wpa_ctrl_1537-1\x00
Sep 11 14:48:02 wpa_supplicant[1534]: EAPOL: External notification - portValid=0
Sep 11 14:48:02 wpa_supplicant[1534]: wlan0: State: SCANNING -> AUTHENTICATING
Sep 11 14:48:02 wpa_supplicant[1534]: Not configuring frame filtering - BSS 00:00:00:00:00:00 is not a Hotspot 2.0 network
Sep 11 14:48:02 wpa_supplicant[1534]: wlan0: Determining shared radio frequencies (max len 1)
Sep 11 14:48:02 wpa_supplicant[1534]: wlan0: Shared frequencies (len=0): completed iteration
Sep 11 14:48:02 wpa_supplicant[1534]: nl80211: Authenticate (ifindex=9)
Sep 11 14:48:02 wpa_supplicant[1534]:   * bssid=d4:3a:2e:d2:4a:d8
Sep 11 14:48:02 wpa_supplicant[1534]:   * freq=2427
Sep 11 14:48:02 wpa_supplicant[1534]:   * SSID=essentiel
Sep 11 14:48:02 wpa_supplicant[1534]:   * IEs - hexdump(len=0): [NULL]
Sep 11 14:48:02 wpa_supplicant[1534]:   * auth_data - hexdump(len=102): 01 00 7e 00 13 00 e3 ac 7c 0e 8c e9 7b b4 89 79 57 6e e9 03 34 0c 0d b5 06 f2 6e b4 2b 9d b0 44 0b d9 47 e7 44 01 b8 cc e2 e9 00 ac 06 d2 50 45 f5 0f 58 4a c1 e1 7a fc 0a 58 0a f1
Sep 11 14:48:02 wpa_supplicant[1534]:   * Auth Type 4
Sep 11 14:48:02 kernel: [16771.678145] esp32_spi: cmd_auth_request: Authentication request: d4:3a:2e:d2:4a:d8 4 3 102 0
Sep 11 14:48:02 wpa_supplicant[1534]: nl80211: Authentication request send successfully
Sep 11 14:48:02 wpa_supplicant[1534]: nl80211: Event message available
Sep 11 14:48:02 wpa_supplicant[1534]: nl80211: Drv Event 37 (NL80211_CMD_AUTHENTICATE) received for wlan0
Sep 11 14:48:02 wpa_supplicant[1534]: nl80211: MLME event 37 (NL80211_CMD_AUTHENTICATE) on wlan0(64:e8:33:cd:ce:0c) A1=64:e8:33:cd:ce:0c A2=d4:3a:2e:d2:4a:d8
Sep 11 14:48:02 wpa_supplicant[1534]: nl80211: MLME event frame - hexdump(len=128): b0 00 00 00 64 e8 33 cd ce 0c d4 3a 2e d2 4a d8 d4 3a 2e d2 4a d8 00 00 03 00 01 00 7e 00 13 00 1d 01 1b 42 c2 0b ba 98 46 82 e7 8b f8 8c a3 5e 47 34 58 98 01 f1 0e 29
Sep 11 14:48:02 wpa_supplicant[1534]: nl80211: Authenticate event
Sep 11 14:48:02 wpa_supplicant[1534]: wlan0: Event AUTH (10) received
Sep 11 14:48:02 wpa_supplicant[1534]: wlan0: SME: Authentication response: peer=d4:3a:2e:d2:4a:d8 auth_type=3 auth_transaction=1 status_code=126
Sep 11 14:48:02 wpa_supplicant[1534]: SME: Authentication response IEs - hexdump(len=98): 13 00 1d 01 1b 42 c2 0b ba 98 46 82 e7 8b f8 8c a3 5e 47 34 58 98 01 f1 0e 29 46 32 4a bc 27 6e 4f 8a bd dc 6e f7 70 a3 6f 0d 5b d4 88 d7 b7 97 ab 43 75 2f 6b 68
Sep 11 14:48:02 wpa_supplicant[1534]: wlan0: SME: SAE authentication transaction 1 status code 126
Sep 11 14:48:02 wpa_supplicant[1534]: wlan0: SME SAE commit
Sep 11 14:48:02 wpa_supplicant[1534]: SAE: Peer commit-scalar - hexdump(len=32): 1d 01 1b 42 c2 0b ba 98 46 82 e7 8b f8 8c a3 5e 47 34 58 98 01 f1 0e 29 46 32 4a bc 27 6e 4f 8a
Sep 11 14:48:02 wpa_supplicant[1534]: SAE: Peer commit-element(x) - hexdump(len=32): bd dc 6e f7 70 a3 6f 0d 5b d4 88 d7 b7 97 ab 43 75 2f 6b 68 d5 e0 04 44 55 09 d9 37 bb c3 1f d4
Sep 11 14:48:02 wpa_supplicant[1534]: SAE: Peer commit-element(y) - hexdump(len=32): fa 8c a3 d6 a2 51 53 b0 b1 f7 3d da 6d 83 08 53 c8 32 80 08 0f 7d ac 99 83 e8 ee 3c 72 39 72 a2
Sep 11 14:48:02 wpa_supplicant[1534]: SAE: Possible elements at the end of the frame - hexdump(len=0):
Sep 11 14:48:02 wpa_supplicant[1534]: SAE: k - hexdump(len=32): [REMOVED]
Sep 11 14:48:02 wpa_supplicant[1534]: SAE: salt for keyseed derivation - hexdump(len=32): 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
Sep 11 14:48:02 wpa_supplicant[1534]: SAE: keyseed - hexdump(len=32): [REMOVED]
Sep 11 14:48:02 wpa_supplicant[1534]: SAE: PMKID - hexdump(len=16): 00 ad 97 52 4e f5 36 4b cf fc 3e fa e1 8f d7 6a
Sep 11 14:48:02 wpa_supplicant[1534]: SAE: KCK - hexdump(len=32): [REMOVED]
Sep 11 14:48:02 wpa_supplicant[1534]: SAE: PMK - hexdump(len=32): [REMOVED]
Sep 11 14:48:02 wpa_supplicant[1534]: wlan0: Automatic auth_alg selection: 0x1
Sep 11 14:48:02 wpa_supplicant[1534]: wlan0: Using SAE auth_alg
Sep 11 14:48:02 wpa_supplicant[1534]: RSN: PMKSA cache search - network_ctx=0x1ab5c0 try_opportunistic=0 akmp=0x0
Sep 11 14:48:02 wpa_supplicant[1534]: RSN: Search for BSSID d4:3a:2e:d2:4a:d8
Sep 11 14:48:02 wpa_supplicant[1534]: RSN: No PMKSA cache entry found
Sep 11 14:48:02 wpa_supplicant[1534]: wlan0: RSN: using IEEE 802.11i/D9.0
Sep 11 14:48:02 wpa_supplicant[1534]: wlan0: WPA: Selected cipher suites: group 16 pairwise 16 key_mgmt 1026 proto 2
Sep 11 14:48:02 wpa_supplicant[1534]: wlan0: WPA: Selected mgmt group cipher 32
Sep 11 14:48:02 wpa_supplicant[1534]: wlan0: WPA: clearing AP WPA IE
Sep 11 14:48:02 wpa_supplicant[1534]: WPA: set AP RSN IE - hexdump(len=32): 30 1e 01 00 00 0f ac 04 01 00 00 0f ac 04 02 00 00 0f ac 02 00 0f ac 08 80 00 00 00 00 0f ac 06
Sep 11 14:48:02 wpa_supplicant[1534]: WPA: set AP RSNXE - hexdump(len=3): f4 01 20
Sep 11 14:48:02 wpa_supplicant[1534]: wlan0: WPA: AP group 0x10 network profile group 0x18; available group 0x10
Sep 11 14:48:02 wpa_supplicant[1534]: wlan0: WPA: using GTK CCMP
Sep 11 14:48:02 wpa_supplicant[1534]: wlan0: WPA: AP pairwise 0x10 network profile pairwise 0x18; available pairwise 0x10
Sep 11 14:48:02 wpa_supplicant[1534]: wlan0: WPA: using PTK CCMP
Sep 11 14:48:02 wpa_supplicant[1534]: wlan0: WPA: AP key_mgmt 0x402 network profile key_mgmt 0x402; available key_mgmt 0x402
Sep 11 14:48:02 wpa_supplicant[1534]: wlan0: RSN: using KEY_MGMT SAE
Sep 11 14:48:02 wpa_supplicant[1534]: wlan0: WPA: AP mgmt_group_cipher 0x20 network profile mgmt_group_cipher 0x0; available mgmt_group_cipher 0x20
Sep 11 14:48:02 wpa_supplicant[1534]: wlan0: WPA: using MGMT group cipher AES-128-CMAC
Sep 11 14:48:02 wpa_supplicant[1534]: WPA: Set own WPA IE default - hexdump(len=28): 30 1a 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 00 0f ac 08 80 00 00 00 00 0f ac 06
Sep 11 14:48:02 wpa_supplicant[1534]: WPA: Leave previously set WPA IE default - hexdump(len=28): 30 1a 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 00 0f ac 08 80 00 00 00 00 0f ac 06
Sep 11 14:48:02 wpa_supplicant[1534]: RSN: Set own RSNXE default - hexdump(len=3): f4 01 20
Sep 11 14:48:02 wpa_supplicant[1534]: RSN: Leave previously set RSNXE default - hexdump(len=3): f4 01 20
Sep 11 14:48:02 wpa_supplicant[1534]: PSK (set in config) - hexdump(len=32): [REMOVED]
Sep 11 14:48:02 wpa_supplicant[1534]: WPA: Set PMK based on external data - hexdump(len=32): [REMOVED]
Sep 11 14:48:02 wpa_supplicant[1534]: wlan0: SME: Selected AP supports MFP: require MFP
Sep 11 14:48:02 wpa_supplicant[1534]: RRM: Determining whether RRM can be used - device support: 0x0
Sep 11 14:48:02 wpa_supplicant[1534]: RRM: Insufficient RRM support in driver - do not use RRM
Sep 11 14:48:02 wpa_supplicant[1534]: Added supported operating classes IE - hexdump(len=5): 3b 03 51 51 52
Sep 11 14:48:02 wpa_supplicant[1534]: RSN: PMKSA cache search - network_ctx=0x1ab5c0 try_opportunistic=0 akmp=0x400
Sep 11 14:48:02 wpa_supplicant[1534]: RSN: Search for BSSID d4:3a:2e:d2:4a:d8
Sep 11 14:48:02 wpa_supplicant[1534]: RSN: No PMKSA cache entry found
Sep 11 14:48:02 wpa_supplicant[1534]: EAPOL: External notification - EAP success=0
Sep 11 14:48:02 wpa_supplicant[1534]: EAPOL: External notification - EAP fail=0
Sep 11 14:48:02 wpa_supplicant[1534]: EAPOL: External notification - portControl=Auto
Sep 11 14:48:02 wpa_supplicant[1534]: wlan0: Cancelling scan request
Sep 11 14:48:02 wpa_supplicant[1534]: wlan0: SME: Trying to authenticate with d4:3a:2e:d2:4a:d8 (SSID='essentiel' freq=2427 MHz)
Sep 11 14:48:02 wpa_supplicant[1534]: CTRL-DEBUG: ctrl_sock-sendmsg: sock=15 sndbuf=180224 outq=0 send_len=83
Sep 11 14:48:02 wpa_supplicant[1534]: CTRL_IFACE monitor sent successfully to /tmp/wpa_ctrl_1537-1\x00
Sep 11 14:48:02 wpa_supplicant[1534]: EAPOL: External notification - portValid=0
Sep 11 14:48:02 wpa_supplicant[1534]: wlan0: State: AUTHENTICATING -> AUTHENTICATING
Sep 11 14:48:02 wpa_supplicant[1534]: Not configuring frame filtering - BSS 00:00:00:00:00:00 is not a Hotspot 2.0 network
Sep 11 14:48:02 wpa_supplicant[1534]: wlan0: Determining shared radio frequencies (max len 1)
Sep 11 14:48:02 wpa_supplicant[1534]: wlan0: Shared frequencies (len=0): completed iteration
Sep 11 14:48:02 wpa_supplicant[1534]: nl80211: Authenticate (ifindex=9)
Sep 11 14:48:02 wpa_supplicant[1534]:   * bssid=d4:3a:2e:d2:4a:d8
Sep 11 14:48:02 wpa_supplicant[1534]:   * freq=2427
Sep 11 14:48:02 wpa_supplicant[1534]:   * SSID=essentiel
Sep 11 14:48:02 wpa_supplicant[1534]:   * IEs - hexdump(len=0): [NULL]
Sep 11 14:48:02 wpa_supplicant[1534]:   * auth_data - hexdump(len=38): 02 00 00 00 01 00 0c 42 e3 80 61 9d f6 70 96 65 b8 0a 35 4c 47 42 08 81 4a 54 36 1c 2b 68 af 69 ed 5b 3f d6 e4 37
Sep 11 14:48:02 wpa_supplicant[1534]:   * Auth Type 4
Sep 11 14:48:02 kernel: [16772.075485] esp32_spi: cmd_auth_request: Authentication request: d4:3a:2e:d2:4a:d8 4 3 38 0
Sep 11 14:48:02 wpa_supplicant[1534]: nl80211: Authentication request send successfully
Sep 11 14:48:02 wpa_supplicant[1534]: nl80211: Event message available
Sep 11 14:48:02 wpa_supplicant[1534]: nl80211: Drv Event 37 (NL80211_CMD_AUTHENTICATE) received for wlan0
Sep 11 14:48:02 wpa_supplicant[1534]: nl80211: MLME event 37 (NL80211_CMD_AUTHENTICATE) on wlan0(64:e8:33:cd:ce:0c) A1=64:e8:33:cd:ce:0c A2=d4:3a:2e:d2:4a:d8
Sep 11 14:48:02 wpa_supplicant[1534]: nl80211: MLME event frame - hexdump(len=64): b0 00 00 00 64 e8 33 cd ce 0c d4 3a 2e d2 4a d8 d4 3a 2e d2 4a d8 00 00 03 00 02 00 00 00 01 00 f8 35 67 57 4b 2a a1 76 5b 11 ae be 44 ac d7 a0 a6 7b ca e5 fc 25 ec 9f
Sep 11 14:48:02 wpa_supplicant[1534]: nl80211: Authenticate event
Sep 11 14:48:02 wpa_supplicant[1534]: wlan0: Event AUTH (10) received
Sep 11 14:48:02 wpa_supplicant[1534]: wlan0: SME: Authentication response: peer=d4:3a:2e:d2:4a:d8 auth_type=3 auth_transaction=2 status_code=0
Sep 11 14:48:02 wpa_supplicant[1534]: SME: Authentication response IEs - hexdump(len=34): 01 00 f8 35 67 57 4b 2a a1 76 5b 11 ae be 44 ac d7 a0 a6 7b ca e5 fc 25 ec 9f 50 0f 6a c0 71 f6 f8 83
Sep 11 14:48:02 wpa_supplicant[1534]: wlan0: SME: SAE authentication transaction 2 status code 0
Sep 11 14:48:02 wpa_supplicant[1534]: wlan0: SME SAE confirm
Sep 11 14:48:02 wpa_supplicant[1534]: SAE: peer-send-confirm 1
Sep 11 14:48:02 wpa_supplicant[1534]: SME: SAE completed - setting PMK for 4-way handshake
Sep 11 14:48:02 wpa_supplicant[1534]: WPA: Set PMK based on external data - hexdump(len=32): [REMOVED]
Sep 11 14:48:02 wpa_supplicant[1534]: RSN: Added PMKSA cache entry for d4:3a:2e:d2:4a:d8 network_ctx=0x1ab5c0 akmp=0x400
Sep 11 14:48:02 wpa_supplicant[1534]: wlan0: PMKSA-CACHE-ADDED d4:3a:2e:d2:4a:d8 0
Sep 11 14:48:02 wpa_supplicant[1534]: CTRL-DEBUG: ctrl_sock-sendmsg: sock=15 sndbuf=180224 outq=0 send_len=37
Sep 11 14:48:02 wpa_supplicant[1534]: CTRL_IFACE monitor sent successfully to /tmp/wpa_ctrl_1537-1\x00
Sep 11 14:48:02 wpa_supplicant[1534]: nl80211: Add PMKID for d4:3a:2e:d2:4a:d8
Sep 11 14:48:02 wpa_supplicant[1534]: nl80211: NL80211_CMD_SET_PMKSA failed: -95 (Operation not supported)
Sep 11 14:48:02 wpa_supplicant[1534]: SME: Association Request IEs - hexdump(len=49): 30 1a 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 00 0f ac 08 80 00 00 00 00 0f ac 06 7f 0b 00 00 00 00 00 00 40 00 00 00 20 3b 03 51 51 52 f4 01 20
Sep 11 14:48:02 wpa_supplicant[1534]: wlan0: Trying to associate with d4:3a:2e:d2:4a:d8 (SSID='essentiel' freq=2427 MHz)
Sep 11 14:48:02 wpa_supplicant[1534]: CTRL-DEBUG: ctrl_sock-sendmsg: sock=15 sndbuf=180224 outq=0 send_len=75
Sep 11 14:48:02 wpa_supplicant[1534]: CTRL_IFACE monitor sent successfully to /tmp/wpa_ctrl_1537-1\x00
Sep 11 14:48:02 wpa_supplicant[1534]: wlan0: State: AUTHENTICATING -> ASSOCIATING
Sep 11 14:48:02 wpa_supplicant[1534]: nl80211: Set wlan0 operstate 0->0 (DORMANT)
Sep 11 14:48:02 wpa_supplicant[1534]: netlink: Operstate: ifindex=9 linkmode=-1 (no change), operstate=5 (IF_OPER_DORMANT)
Sep 11 14:48:02 wpa_supplicant[1534]: WPA: set own WPA/RSN IE - hexdump(len=28): 30 1a 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 00 0f ac 08 80 00 00 00 00 0f ac 06
Sep 11 14:48:02 wpa_supplicant[1534]: RSN: set own RSNXE - hexdump(len=3): f4 01 20
Sep 11 14:48:02 wpa_supplicant[1534]: nl80211: Associate (ifindex=9)
Sep 11 14:48:02 wpa_supplicant[1534]:   * bssid=d4:3a:2e:d2:4a:d8
Sep 11 14:48:02 wpa_supplicant[1534]:   * freq=2427
Sep 11 14:48:02 wpa_supplicant[1534]:   * SSID=essentiel
Sep 11 14:48:02 wpa_supplicant[1534]:   * IEs - hexdump(len=49): 30 1a 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 00 0f ac 08 80 00 00 00 00 0f ac 06 7f 0b 00 00 00 00 00 00 40 00 00 00 20 3b 03 51 51 52 f4 01 20
Sep 11 14:48:02 wpa_supplicant[1534]:   * WPA Versions 0x2
Sep 11 14:48:02 wpa_supplicant[1534]:   * pairwise=0xfac04
Sep 11 14:48:02 wpa_supplicant[1534]:   * group=0xfac04
Sep 11 14:48:02 wpa_supplicant[1534]:   * akm=0xfac08
Sep 11 14:48:02 kernel: [16772.165896] esp32_spi: cmd_assoc_request: Association request: d4:3a:2e:d2:4a:d8 4 49
Sep 11 14:48:02 wpa_supplicant[1534]: nl80211: Association request send successfully
Sep 11 14:48:02 wpa_supplicant[1534]: nl80211: Event message available
Sep 11 14:48:02 wpa_supplicant[1534]: nl80211: Drv Event 38 (NL80211_CMD_ASSOCIATE) received for wlan0
Sep 11 14:48:02 kernel: [16772.241841] esp32_spi: process_assoc_event: Connection status: 0
Sep 11 14:48:02 wpa_supplicant[1534]: nl80211: MLME event 38 (NL80211_CMD_ASSOCIATE) on wlan0(64:e8:33:cd:ce:0c) A1=64:e8:33:cd:ce:0c A2=d4:3a:2e:d2:4a:d8
Sep 11 14:48:02 wpa_supplicant[1534]: nl80211: MLME event frame - hexdump(len=317): 10 00 00 00 64 e8 33 cd ce 0c d4 3a 2e d2 4a d8 d4 3a 2e d2 4a d8 00 00 31 1c 00 00 08 c0 01 08 82 84 8b 96 12 24 48 6c 32 04 0c 18 30 60 46 05 02 00 01 00 00 dd 18 00
Sep 11 14:48:02 wpa_supplicant[1534]: nl80211: Associate event
Sep 11 14:48:02 wpa_supplicant[1534]: nl80211: Associated on 2427 MHz
Sep 11 14:48:02 wpa_supplicant[1534]: nl80211: Associated with d4:3a:2e:d2:4a:d8
Sep 11 14:48:02 wpa_supplicant[1534]: nl80211: Set drv->ssid based on scan res info to 'essentiel'
Sep 11 14:48:02 wpa_supplicant[1534]: wlan0: Event ASSOC (0) received
Sep 11 14:48:02 wpa_supplicant[1534]: wlan0: Association info event
Sep 11 14:48:02 wpa_supplicant[1534]: req_ies - hexdump(len=49): 30 1a 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 00 0f ac 08 80 00 00 00 00 0f ac 06 7f 0b 00 00 00 00 00 00 40 00 00 00 20 3b 03 51 51 52 f4 01 20
Sep 11 14:48:02 wpa_supplicant[1534]: resp_ies - hexdump(len=287): 01 08 82 84 8b 96 12 24 48 6c 32 04 0c 18 30 60 46 05 02 00 01 00 00 dd 18 00 50 f2 02 01 01 80 00 03 a4 00 00 27 a4 00 00 42 43 5e 00 62 32 2f 00 2d 1a ef 19 17 ff ff 00 00 01 00 00 0
Sep 11 14:48:02 wpa_supplicant[1534]: IEEE 802.11 element parse ignored unknown element (id=74 elen=14)
Sep 11 14:48:02 wpa_supplicant[1534]: wlan0: freq=2427 MHz
Sep 11 14:48:02 wpa_supplicant[1534]: WPA: set own WPA/RSN IE - hexdump(len=28): 30 1a 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 00 0f ac 08 80 00 00 00 00 0f ac 06
Sep 11 14:48:02 wpa_supplicant[1534]: wlan0: RSN: PMKID from assoc IE not found from PMKSA cache
Sep 11 14:48:02 wpa_supplicant[1534]: RSN: set own RSNXE - hexdump(len=3): f4 01 20
Sep 11 14:48:02 wpa_supplicant[1534]: wlan0: State: ASSOCIATING -> ASSOCIATED
Sep 11 14:48:02 wpa_supplicant[1534]: nl80211: Set wlan0 operstate 0->0 (DORMANT)
Sep 11 14:48:02 wpa_supplicant[1534]: netlink: Operstate: ifindex=9 linkmode=-1 (no change), operstate=5 (IF_OPER_DORMANT)
Sep 11 14:48:02 wpa_supplicant[1534]: wlan0: Associated to a new BSS: BSSID=d4:3a:2e:d2:4a:d8
Sep 11 14:48:02 wpa_supplicant[1534]: wlan0: Associated with d4:3a:2e:d2:4a:d8
Sep 11 14:48:02 wpa_supplicant[1534]: CTRL-DEBUG: ctrl_sock-sendmsg: sock=15 sndbuf=180224 outq=0 send_len=33
Sep 11 14:48:02 wpa_supplicant[1534]: CTRL_IFACE monitor sent successfully to /tmp/wpa_ctrl_1537-1\x00
Sep 11 14:48:02 wpa_supplicant[1534]: wlan0: WPA: Association event - clear replay counter
Sep 11 14:48:02 wpa_supplicant[1534]: wlan0: WPA: Clear old PTK
Sep 11 14:48:02 wpa_supplicant[1534]: TDLS: Remove peers on association
Sep 11 14:48:02 wpa_supplicant[1534]: EAPOL: External notification - portEnabled=0
Sep 11 14:48:02 wpa_supplicant[1534]: EAPOL: External notification - portValid=0
Sep 11 14:48:02 wpa_supplicant[1534]: EAPOL: External notification - EAP success=0
Sep 11 14:48:02 wpa_supplicant[1534]: EAPOL: External notification - portEnabled=1
Sep 11 14:48:02 wpa_supplicant[1534]: EAPOL: SUPP_PAE entering state CONNECTING
Sep 11 14:48:02 wpa_supplicant[1534]: EAPOL: enable timer tick
Sep 11 14:48:02 wpa_supplicant[1534]: EAPOL: SUPP_BE entering state IDLE
Sep 11 14:48:02 wpa_supplicant[1534]: wlan0: Setting authentication timeout: 10 sec 0 usec
Sep 11 14:48:02 wpa_supplicant[1534]: wlan0: Cancelling scan request
Sep 11 14:48:02 wpa_supplicant[1534]: IEEE 802.11 element parse ignored unknown element (id=74 elen=14)
Sep 11 14:48:02 wpa_supplicant[1534]: WMM AC: AC mandatory: AC_BE=0 AC_BK=0 AC_VI=0 AC_VO=0
Sep 11 14:48:02 wpa_supplicant[1534]: WMM AC: U-APSD queues=0x0
Sep 11 14:48:02 wpa_supplicant[1534]: WMM AC: Valid WMM association, WMM AC is enabled
Sep 11 14:48:02 wpa_supplicant[1534]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
Sep 11 14:48:02 wpa_supplicant[1534]: CTRL-DEBUG: ctrl_sock-sendmsg: sock=15 sndbuf=180224 outq=0 send_len=40
Sep 11 14:48:02 wpa_supplicant[1534]: CTRL_IFACE monitor sent successfully to /tmp/wpa_ctrl_1537-1\x00
Sep 11 14:48:02 wpa_supplicant[1534]: nl80211: Event message available
Sep 11 14:48:02 wpa_supplicant[1534]: nl80211: Drv Event 46 (NL80211_CMD_CONNECT) received for wlan0
Sep 11 14:48:02 wpa_supplicant[1534]: nl80211: Ignore connect event (cmd=46) when using userspace SME
Sep 11 14:48:02 kernel: [16772.331629] esp32_spi: process_rx_packet: Rx PACKET_TYPE_EAPOL!!!!
Sep 11 14:48:02 wpa_supplicant[1534]: l2_packet_receive: src=d4:3a:2e:d2:4a:d8 len=121
Sep 11 14:48:02 wpa_supplicant[1534]: wlan0: RX EAPOL from d4:3a:2e:d2:4a:d8
Sep 11 14:48:02 wpa_supplicant[1534]: RX EAPOL - hexdump(len=121): 01 03 00 75 02 00 88 00 10 00 00 00 00 00 00 00 01 8b c5 2a 74 cf 40 2a 0d c1 5b 78 83 1a 18 11 50 36 c9 6d 6d 77 c9 3b 72 f5 88 81 33 2f ba 57 94 00 00 00 00 00 00 00 00 00 00 00 00 0
Sep 11 14:48:02 wpa_supplicant[1534]: wlan0: Setting authentication timeout: 10 sec 0 usec
Sep 11 14:48:02 wpa_supplicant[1534]: wlan0: IEEE 802.1X RX: version=1 type=3 length=117
Sep 11 14:48:02 wpa_supplicant[1534]: WPA: RX EAPOL-Key - hexdump(len=121): 01 03 00 75 02 00 88 00 10 00 00 00 00 00 00 00 01 8b c5 2a 74 cf 40 2a 0d c1 5b 78 83 1a 18 11 50 36 c9 6d 6d 77 c9 3b 72 f5 88 81 33 2f ba 57 94 00 00 00 00 00 00 00 00 00 0
Sep 11 14:48:02 wpa_supplicant[1534]: wlan0:   EAPOL-Key type=2
Sep 11 14:48:02 wpa_supplicant[1534]: wlan0:   key_info 0x88 (ver=0 keyidx=0 rsvd=0 Pairwise Ack)
Sep 11 14:48:02 wpa_supplicant[1534]: wlan0:   key_length=16 key_data_length=22
Sep 11 14:48:02 wpa_supplicant[1534]:   replay_counter - hexdump(len=8): 00 00 00 00 00 00 00 01
Sep 11 14:48:02 wpa_supplicant[1534]:   key_nonce - hexdump(len=32): 8b c5 2a 74 cf 40 2a 0d c1 5b 78 83 1a 18 11 50 36 c9 6d 6d 77 c9 3b 72 f5 88 81 33 2f ba 57 94
Sep 11 14:48:02 wpa_supplicant[1534]:   key_iv - hexdump(len=16): 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
Sep 11 14:48:02 wpa_supplicant[1534]:   key_rsc - hexdump(len=8): 00 00 00 00 00 00 00 00
Sep 11 14:48:02 wpa_supplicant[1534]:   key_id (reserved) - hexdump(len=8): 00 00 00 00 00 00 00 00
Sep 11 14:48:02 wpa_supplicant[1534]:   key_mic - hexdump(len=16): 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
Sep 11 14:48:02 wpa_supplicant[1534]: wlan0: State: ASSOCIATED -> 4WAY_HANDSHAKE
Sep 11 14:48:02 wpa_supplicant[1534]: wlan0: WPA: RX message 1 of 4-Way Handshake from d4:3a:2e:d2:4a:d8 (ver=0)
Sep 11 14:48:02 wpa_supplicant[1534]: RSN: msg 1/4 key data - hexdump(len=22): dd 14 00 0f ac 04 00 ad 97 52 4e f5 36 4b cf fc 3e fa e1 8f d7 6a
Sep 11 14:48:02 wpa_supplicant[1534]: WPA: PMKID in EAPOL-Key - hexdump(len=22): dd 14 00 0f ac 04 00 ad 97 52 4e f5 36 4b cf fc 3e fa e1 8f d7 6a
Sep 11 14:48:02 wpa_supplicant[1534]: RSN: PMKID from Authenticator - hexdump(len=16): 00 ad 97 52 4e f5 36 4b cf fc 3e fa e1 8f d7 6a
Sep 11 14:48:02 wpa_supplicant[1534]: RSN: matched PMKID - hexdump(len=16): 00 ad 97 52 4e f5 36 4b cf fc 3e fa e1 8f d7 6a
Sep 11 14:48:02 wpa_supplicant[1534]: WPA: Set PMK based on current PMKSA - hexdump(len=32): [REMOVED]
Sep 11 14:48:02 wpa_supplicant[1534]: RSN: PMK from PMKSA cache - hexdump(len=32): [REMOVED]
Sep 11 14:48:02 wpa_supplicant[1534]: EAPOL: PMKSA caching was used - skip EAPOL
Sep 11 14:48:02 wpa_supplicant[1534]: EAPOL: SUPP_PAE entering state AUTHENTICATING
Sep 11 14:48:02 wpa_supplicant[1534]: EAPOL: SUPP_BE entering state SUCCESS
Sep 11 14:48:02 wpa_supplicant[1534]: EAP: EAP entering state DISABLED
Sep 11 14:48:02 wpa_supplicant[1534]: EAPOL: SUPP_BE entering state IDLE
Sep 11 14:48:02 wpa_supplicant[1534]: Get randomness: len=32 entropy=5
Sep 11 14:48:02 wpa_supplicant[1534]: WPA: Renewed SNonce - hexdump(len=32): e8 f0 bc 1d bb e3 96 c8 9e c4 fd 17 03 61 b7 83 12 25 c9 d7 27 fc ba aa 56 9d 47 62 37 53 72 a7
Sep 11 14:48:02 wpa_supplicant[1534]: WPA: PTK derivation using PRF(SHA256)
Sep 11 14:48:02 wpa_supplicant[1534]: WPA: PTK derivation - A1=64:e8:33:cd:ce:0c A2=d4:3a:2e:d2:4a:d8
Sep 11 14:48:02 wpa_supplicant[1534]: WPA: Nonce1 - hexdump(len=32): e8 f0 bc 1d bb e3 96 c8 9e c4 fd 17 03 61 b7 83 12 25 c9 d7 27 fc ba aa 56 9d 47 62 37 53 72 a7
Sep 11 14:48:02 wpa_supplicant[1534]: WPA: Nonce2 - hexdump(len=32): 8b c5 2a 74 cf 40 2a 0d c1 5b 78 83 1a 18 11 50 36 c9 6d 6d 77 c9 3b 72 f5 88 81 33 2f ba 57 94
Sep 11 14:48:02 wpa_supplicant[1534]: WPA: PMK - hexdump(len=32): [REMOVED]
Sep 11 14:48:02 wpa_supplicant[1534]: WPA: PTK - hexdump(len=48): [REMOVED]
Sep 11 14:48:02 wpa_supplicant[1534]: WPA: KCK - hexdump(len=16): [REMOVED]
Sep 11 14:48:02 wpa_supplicant[1534]: WPA: KEK - hexdump(len=16): [REMOVED]
Sep 11 14:48:02 wpa_supplicant[1534]: WPA: TK - hexdump(len=16): [REMOVED]
Sep 11 14:48:02 wpa_supplicant[1534]: WPA: WPA IE for msg 2/4 - hexdump(len=31): 30 1a 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 00 0f ac 08 80 00 00 00 00 0f ac 06 f4 01 20
Sep 11 14:48:02 wpa_supplicant[1534]: WPA: Replay Counter - hexdump(len=8): 00 00 00 00 00 00 00 01
Sep 11 14:48:02 wpa_supplicant[1534]: wlan0: WPA: Sending EAPOL-Key 2/4
Sep 11 14:48:02 wpa_supplicant[1534]: WPA: Send EAPOL-Key frame to d4:3a:2e:d2:4a:d8 ver=0 mic_len=16 key_mgmt=0x400
Sep 11 14:48:02 wpa_supplicant[1534]: WPA: EAPOL-Key MIC using AES-CMAC (AKM-defined - SAE)
Sep 11 14:48:02 wpa_supplicant[1534]: WPA: KCK - hexdump(len=16): [REMOVED]
Sep 11 14:48:02 wpa_supplicant[1534]: WPA: Derived Key MIC - hexdump(len=16): d0 f9 ed a0 0b a8 1b 18 3b a6 77 f4 ef 9c 42 6e
Sep 11 14:48:02 wpa_supplicant[1534]: WPA: TX EAPOL-Key - hexdump(len=130): 01 03 00 7e 02 01 08 00 00 00 00 00 00 00 00 00 01 e8 f0 bc 1d bb e3 96 c8 9e c4 fd 17 03 61 b7 83 12 25 c9 d7 27 fc ba aa 56 9d 47 62 37 53 72 a7 00 00 00 00 00 00 00 00 00 0
Sep 11 14:48:02 kernel: [16772.361875] esp32_spi: process_rx_packet: Rx PACKET_TYPE_EAPOL!!!!
Sep 11 14:48:02 wpa_supplicant[1534]: l2_packet_receive: src=d4:3a:2e:d2:4a:d8 len=203
Sep 11 14:48:02 wpa_supplicant[1534]: wlan0: RX EAPOL from d4:3a:2e:d2:4a:d8
Sep 11 14:48:02 wpa_supplicant[1534]: RX EAPOL - hexdump(len=203): 01 03 00 c7 02 13 c8 00 10 00 00 00 00 00 00 00 02 8b c5 2a 74 cf 40 2a 0d c1 5b 78 83 1a 18 11 50 36 c9 6d 6d 77 c9 3b 72 f5 88 81 33 2f ba 57 94 00 00 00 00 00 00 00 00 00 00 00 00 0
Sep 11 14:48:02 wpa_supplicant[1534]: wlan0: IEEE 802.1X RX: version=1 type=3 length=199
Sep 11 14:48:02 wpa_supplicant[1534]: WPA: RX EAPOL-Key - hexdump(len=203): 01 03 00 c7 02 13 c8 00 10 00 00 00 00 00 00 00 02 8b c5 2a 74 cf 40 2a 0d c1 5b 78 83 1a 18 11 50 36 c9 6d 6d 77 c9 3b 72 f5 88 81 33 2f ba 57 94 00 00 00 00 00 00 00 00 00 0
Sep 11 14:48:02 wpa_supplicant[1534]: wlan0:   EAPOL-Key type=2
Sep 11 14:48:02 wpa_supplicant[1534]: wlan0:   key_info 0x13c8 (ver=0 keyidx=0 rsvd=0 Pairwise Install Ack MIC Secure Encr)
Sep 11 14:48:02 wpa_supplicant[1534]: wlan0:   key_length=16 key_data_length=104
Sep 11 14:48:02 wpa_supplicant[1534]:   replay_counter - hexdump(len=8): 00 00 00 00 00 00 00 02
Sep 11 14:48:02 wpa_supplicant[1534]:   key_nonce - hexdump(len=32): 8b c5 2a 74 cf 40 2a 0d c1 5b 78 83 1a 18 11 50 36 c9 6d 6d 77 c9 3b 72 f5 88 81 33 2f ba 57 94
Sep 11 14:48:02 wpa_supplicant[1534]:   key_iv - hexdump(len=16): 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
Sep 11 14:48:02 wpa_supplicant[1534]:   key_rsc - hexdump(len=8): 16 0d 00 00 00 00 00 00
Sep 11 14:48:02 wpa_supplicant[1534]:   key_id (reserved) - hexdump(len=8): 00 00 00 00 00 00 00 00
Sep 11 14:48:02 wpa_supplicant[1534]:   key_mic - hexdump(len=16): c0 6e f3 93 7a cc e8 3a 19 c9 22 2e 7d cf c0 04
Sep 11 14:48:02 wpa_supplicant[1534]: WPA: EAPOL-Key MIC using AES-CMAC (AKM-defined - SAE)
Sep 11 14:48:02 wpa_supplicant[1534]: RSN: encrypted key data - hexdump(len=104): b1 56 0d 38 7b 10 4c 4a 32 cc 47 dd 71 ee 82 40 10 83 31 41 7f d0 3c ff 41 e2 3b e8 be 9c 6a 5d df 58 2d 22 3f 5f 7b ae 29 d6 f0 22 38 a2 d3 2d 89 bb 5f 60 f5 14 96 51 d
Sep 11 14:48:02 wpa_supplicant[1534]: WPA: Decrypt Key Data using AES-UNWRAP (KEK length 16)
Sep 11 14:48:02 wpa_supplicant[1534]: WPA: decrypted EAPOL-Key key data - hexdump(len=96): [REMOVED]
Sep 11 14:48:02 wpa_supplicant[1534]: wlan0: State: 4WAY_HANDSHAKE -> 4WAY_HANDSHAKE
Sep 11 14:48:02 wpa_supplicant[1534]: wlan0: WPA: RX message 3 of 4-Way Handshake from d4:3a:2e:d2:4a:d8 (ver=0)
Sep 11 14:48:02 wpa_supplicant[1534]: WPA: IE KeyData - hexdump(len=96): 30 1e 01 00 00 0f ac 04 01 00 00 0f ac 04 02 00 00 0f ac 02 00 0f ac 08 80 00 00 00 00 0f ac 06 f4 01 20 dd 16 00 0f ac 01 01 00 10 48 76 9e e3 a5 53 13 42 7f 2a 20 b1 8e bf 75 d
Sep 11 14:48:02 wpa_supplicant[1534]: WPA: RSN IE in EAPOL-Key - hexdump(len=32): 30 1e 01 00 00 0f ac 04 01 00 00 0f ac 04 02 00 00 0f ac 02 00 0f ac 08 80 00 00 00 00 0f ac 06
Sep 11 14:48:02 wpa_supplicant[1534]: WPA: RSNXE in EAPOL-Key - hexdump(len=3): f4 01 20
Sep 11 14:48:02 wpa_supplicant[1534]: WPA: GTK in EAPOL-Key - hexdump(len=24): [REMOVED]
Sep 11 14:48:02 wpa_supplicant[1534]: WPA: IGTK in EAPOL-Key - hexdump(len=30): [REMOVED]
Sep 11 14:48:02 wpa_supplicant[1534]: WPA: Transition Disable KDE in EAPOL-Key - hexdump(len=7): dd 05 50 6f 9a 20 01
Sep 11 14:48:02 wpa_supplicant[1534]: wlan0: WPA: Sending EAPOL-Key 4/4
Sep 11 14:48:02 wpa_supplicant[1534]: WPA: Send EAPOL-Key frame to d4:3a:2e:d2:4a:d8 ver=0 mic_len=16 key_mgmt=0x400
Sep 11 14:48:02 wpa_supplicant[1534]: WPA: EAPOL-Key MIC using AES-CMAC (AKM-defined - SAE)
Sep 11 14:48:02 wpa_supplicant[1534]: WPA: KCK - hexdump(len=16): [REMOVED]
Sep 11 14:48:02 wpa_supplicant[1534]: WPA: Derived Key MIC - hexdump(len=16): 09 31 3a 83 de ff 77 78 b3 a5 8b 9a f4 40 52 da
Sep 11 14:48:02 wpa_supplicant[1534]: WPA: TX EAPOL-Key - hexdump(len=99): 01 03 00 5f 02 03 08 00 00 00 00 00 00 00 00 00 02 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
Sep 11 14:48:02 wpa_supplicant[1534]: wlan0: WPA: Installing PTK to the driver
Sep 11 14:48:02 wpa_supplicant[1534]: wpa_driver_nl80211_set_key: ifindex=9 (wlan0) alg=3 addr=0x1ac42c key_idx=0 set_tx=1 seq_len=6 key_len=16 key_flag=0x2c
Sep 11 14:48:02 wpa_supplicant[1534]: nl80211: NEW_KEY
Sep 11 14:48:02 wpa_supplicant[1534]: nl80211: KEY_DATA - hexdump(len=16): [REMOVED]
Sep 11 14:48:02 wpa_supplicant[1534]: nl80211: KEY_SEQ - hexdump(len=6): 00 00 00 00 00 00
Sep 11 14:48:02 wpa_supplicant[1534]:    addr=d4:3a:2e:d2:4a:d8
Sep 11 14:48:02 wpa_supplicant[1534]:    pairwise key
Sep 11 14:48:03 wpa_supplicant[1534]: EAPOL: External notification - portValid=1
Sep 11 14:48:03 wpa_supplicant[1534]: EAPOL: SUPP_PAE entering state AUTHENTICATED
Sep 11 14:48:03 wpa_supplicant[1534]: EAPOL: Supplicant port status: Authorized
Sep 11 14:48:03 wpa_supplicant[1534]: nl80211: Set supplicant port authorized for d4:3a:2e:d2:4a:d8
Sep 11 14:48:03 wpa_supplicant[1534]: EAPOL authentication completed - result=SUCCESS
Sep 11 14:48:03 wpa_supplicant[1534]: wlan0: State: 4WAY_HANDSHAKE -> GROUP_HANDSHAKE
Sep 11 14:48:03 wpa_supplicant[1534]: RSN: received GTK in pairwise handshake - hexdump(len=18): [REMOVED]
Sep 11 14:48:03 wpa_supplicant[1534]: WPA: Group Key - hexdump(len=16): [REMOVED]
Sep 11 14:48:03 wpa_supplicant[1534]: wlan0: WPA: Installing GTK to the driver (keyidx=1 tx=0 len=16)
Sep 11 14:48:03 wpa_supplicant[1534]: WPA: RSC - hexdump(len=6): 16 0d 00 00 00 00
Sep 11 14:48:03 wpa_supplicant[1534]: wpa_driver_nl80211_set_key: ifindex=9 (wlan0) alg=3 addr=0x120ef0 key_idx=1 set_tx=0 seq_len=6 key_len=16 key_flag=0x14
Sep 11 14:48:03 wpa_supplicant[1534]: nl80211: NEW_KEY
Sep 11 14:48:03 wpa_supplicant[1534]: nl80211: KEY_DATA - hexdump(len=16): [REMOVED]
Sep 11 14:48:03 wpa_supplicant[1534]: nl80211: KEY_SEQ - hexdump(len=6): 16 0d 00 00 00 00
Sep 11 14:48:03 wpa_supplicant[1534]:    broadcast key
Sep 11 14:48:03 wpa_supplicant[1534]: wlan0: WPA: IGTK keyid 4 pn 010000000000
Sep 11 14:48:03 wpa_supplicant[1534]: WPA: IGTK - hexdump(len=16): [REMOVED]
Sep 11 14:48:03 wpa_supplicant[1534]: wpa_driver_nl80211_set_key: ifindex=9 (wlan0) alg=4 addr=0x120ef0 key_idx=4 set_tx=0 seq_len=6 key_len=16 key_flag=0x14
Sep 11 14:48:03 wpa_supplicant[1534]: nl80211: NEW_KEY
Sep 11 14:48:03 wpa_supplicant[1534]: nl80211: KEY_DATA - hexdump(len=16): [REMOVED]
Sep 11 14:48:03 wpa_supplicant[1534]: nl80211: KEY_SEQ - hexdump(len=6): 01 00 00 00 00 00
Sep 11 14:48:03 wpa_supplicant[1534]:    broadcast key
Sep 11 14:48:03 wpa_supplicant[1534]: wlan0: WPA: Key negotiation completed with d4:3a:2e:d2:4a:d8 [PTK=CCMP GTK=CCMP]
Sep 11 14:48:03 wpa_supplicant[1534]: CTRL-DEBUG: ctrl_sock-sendmsg: sock=15 sndbuf=180224 outq=0 send_len=73
Sep 11 14:48:03 wpa_supplicant[1534]: CTRL_IFACE monitor sent successfully to /tmp/wpa_ctrl_1537-1\x00
Sep 11 14:48:03 wpa_supplicant[1534]: wlan0: Cancelling authentication timeout
Sep 11 14:48:03 wpa_supplicant[1534]: wlan0: State: GROUP_HANDSHAKE -> COMPLETED
Sep 11 14:48:03 wpa_supplicant[1534]: wlan0: Radio work 'sme-connect'@0x1c3cb0 done in 0.857684 seconds
Sep 11 14:48:03 wpa_supplicant[1534]: wlan0: radio_work_free('sme-connect'@0x1c3cb0): num_active_works --> 0
Sep 11 14:48:03 wpa_supplicant[1534]: wlan0: CTRL-EVENT-CONNECTED - Connection to d4:3a:2e:d2:4a:d8 completed [id=0 id_str=]
Sep 11 14:48:03 wpa_supplicant[1534]: CTRL-DEBUG: ctrl_sock-sendmsg: sock=15 sndbuf=180224 outq=0 send_len=79
Sep 11 14:48:03 wpa_supplicant[1534]: CTRL_IFACE monitor sent successfully to /tmp/wpa_ctrl_1537-1\x00
Sep 11 14:48:03 wpa_supplicant[1534]: nl80211: Set wlan0 operstate 0->1 (UP)
Sep 11 14:48:03 wpa_supplicant[1534]: netlink: Operstate: ifindex=9 linkmode=-1 (no change), operstate=6 (IF_OPER_UP)
Sep 11 14:48:03 wpa_supplicant[1534]: EAPOL: External notification - portValid=1
Sep 11 14:48:03 wpa_supplicant[1534]: EAPOL: External notification - EAP success=1
Sep 11 14:48:03 wpa_supplicant[1534]: EAP: EAP entering state DISABLED
Sep 11 14:48:03 wpa_supplicant[1534]: wlan0: TRANSITION-DISABLE 01
Sep 11 14:48:03 wpa_supplicant[1534]: CTRL-DEBUG: ctrl_sock-sendmsg: sock=15 sndbuf=180224 outq=0 send_len=21
Sep 11 14:48:03 wpa_supplicant[1534]: CTRL_IFACE monitor sent successfully to /tmp/wpa_ctrl_1537-1\x00
Sep 11 14:48:03 wpa_supplicant[1534]: WPA3-Personal transition mode disabled based on AP notification
Sep 11 14:48:03 wpa_supplicant[1534]: Writing configuration file '/etc/wpa_supplicant.conf.tmp'
Sep 11 14:48:03 wpa_supplicant[1534]: Configuration file '/etc/wpa_supplicant.conf' written successfully
Sep 11 14:48:03 wpa_supplicant[1534]: RTM_NEWLINK: ifi_index=9 ifname=wlan0 operstate=6 linkmode=1 ifi_family=0 ifi_flags=0x11043 ([UP][RUNNING][LOWER_UP])
Sep 11 14:48:03 udhcpc[1549]: started, v1.34.1
Sep 11 14:48:03 udhcpc[1549]: broadcasting discover
Sep 11 14:48:03 udhcpc[1549]: broadcasting select for 192.168.2.134, server 192.168.2.100
Sep 11 14:48:03 udhcpc[1549]: lease of 192.168.2.134 obtained from 192.168.2.100, lease time 43200
Sep 11 14:48:03 kernel: [16772.692441] esp32_spi: esp_inetaddr_event: NETDEV_UP interface wlan0 ip changed to  192.168.002.134
Sep 11 14:48:04 wpa_supplicant[1534]: EAPOL: startWhen --> 0
Sep 11 14:48:04 wpa_supplicant[1534]: EAPOL: disable timer tick
Shreyas0-7 commented 1 week ago

Hey @lwj850533527 we have merged a change where we are trying to do active scan, can you please check if you are still facing the issue after that change.

lwj850533527 commented 1 week ago

HI @Shreyas0-7. After that change, I haven't faced this issue yet. This scanning action is to get the auth_type, can it be transmitted from the host

Shreyas0-7 commented 1 week ago

Hey @lwj850533527 the auth_type which we receive from host has different context than what we are setting for our esp wifi config for esp_wifi_connect.