Hi!
17/01/2024 01.07, Martin Blumenstingl wrote:
Hi Ping-Ke and Viacheslav,
On Mon, Jan 15, 2024 at 3:17 AM Ping-Ke Shih <pkshih@xxxxxxxxxxx> wrote:
[...]
I don't have this kind of wifi cards, could you help to check if AP mode
works in your side?
I'll check that in the next few days.
AP mode is also not working for me, I get the same problem as
Viacheslav reported.
Also I'm wondering where code enables beacons (is it
rtw_core_enable_beacon() or is there another relevant function?).
Knowing that would be helpful to analyze this further.
The main function to get and set beacon template to firmware is
rtw_fw_download_rsvd_page(). The basic concept is to put beacon frame via
qsel=BCN to a special TX FIFO area called "reserve page", and then
hardware/firmware will send beacon in interval of 100ms.
Thanks for the explanation - that helped me find a better starting point!
I didn't have much time in the past few days, but I have some findings
and questions:
1) I found the following comment/code in the downstream driver [0]:
/*
* Disable Hw protection for a time which revserd for Hw sending beacon.
* Fix download reserved page packet fail that access collision with
the protection time.
*/
val8 = rtw_read8(adapter, REG_BCN_CTRL_8822C);
restore[1] = val8;
val8 &= ~BIT_EN_BCN_FUNCTION_8822C;
val8 |= BIT_DIS_TSF_UDT_8822C;
rtw_write8(adapter, REG_BCN_CTRL_8822C, val8);
This is not part of the upstream rtw88 driver, so I made a patch and
attached it.
Unfortunately it doesn't fix the problem.
2) PCI is the only HCI which does not need the checksum in the
pkt_Info (USB and SDIO need the checksum).
The checksum is added by rtw_tx_fill_txdesc_checksum() which is only
called in usb.c and sdio.c.
My understanding is that for reserved pages we can have more than one
pkt_info in the buffer (my starting point for this thought is
rtw_fill_rsvd_page_desc() from fw.c).
In usb.c and sdio.c we're only calculating the checksum for the very
first pkt_info, not for any subsequent ones (I didn't even know that
it's possible to have more than one pkt_Info outside of RX and TX
aggregation).
However, it seems that the downstream code calculates the TX checksum
for *all* pkt_info in the buffer, see [1]
This code is missing from rtw88 at the moment. Since I didn't have
time I did not try to implement this yet.
3) Has anybody tried AP mode with rtw88 on a (supported) USB chipset?
If my thought (from #2) is correct then AP mode would show the same
problems there.
I have USB RTL8821CU. It works in AP mode:
[511809.841083] usb 1-1.4: New USB device found, idVendor=0bda,
idProduct=c820, bcdDevice= 2.00
[511809.841111] usb 1-1.4: New USB device strings: Mfr=1, Product=2,
SerialNumber=3
[511809.841118] usb 1-1.4: Product: 802.11ac NIC
[511809.841124] usb 1-1.4: Manufacturer: Realtek
[511809.841130] usb 1-1.4: SerialNumber: 123456
[511809.973789] usbcore: registered new interface driver btusb
[511810.432057] usbcore: registered new interface driver rtl8821cu
client log:
Jan 17 12:35:02 adeepn wpa_supplicant[1868]: wlo1: SME: Trying to
authenticate with 00:13:ef:5f:22:2f (SSID='Hostspot' freq=2437 MHz)
Jan 17 12:35:03 adeepn kernel: wlo1: authenticate with 00:13:ef:5f:22:2f
Jan 17 12:35:03 adeepn kernel: wlo1: 80 MHz not supported, disabling VHT
Jan 17 12:35:03 adeepn kernel: wlo1: send auth to 00:13:ef:5f:22:2f (try
1/3)
Jan 17 12:35:03 adeepn kernel: wlo1: authenticated
Jan 17 12:35:03 adeepn kernel: iwlwifi 0000:00:14.3 wlo1: disabling
HT/VHT/HE as WMM/QoS is not supported by the AP
Jan 17 12:35:03 adeepn kernel: wlo1: associate with 00:13:ef:5f:22:2f
(try 1/3)
Jan 17 12:35:03 adeepn kernel: wlo1: RX AssocResp from 00:13:ef:5f:22:2f
(capab=0x1411 status=0 aid=1)
Jan 17 12:35:03 adeepn kernel: wlo1: associated
Jan 17 12:35:02 adeepn wpa_supplicant[1868]: wlo1: Trying to associate
with 00:13:ef:5f:22:2f (SSID='Hostspot' freq=2437 MHz)
Jan 17 12:35:02 adeepn wpa_supplicant[1868]: wlo1: Associated with
00:13:ef:5f:22:2f
Jan 17 12:35:02 adeepn wpa_supplicant[1868]: wlo1:
CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
Jan 17 12:35:02 adeepn wpa_supplicant[1868]: wlo1: WPA: Key negotiation
completed with 00:13:ef:5f:22:2f [PTK=CCMP GTK=CCMP]
Jan 17 12:35:02 adeepn wpa_supplicant[1868]: wlo1: CTRL-EVENT-CONNECTED
- Connection to 00:13:ef:5f:22:2f completed [id=0 id_str=]
Jan 17 12:35:02 adeepn wpa_supplicant[1868]: wlo1:
CTRL-EVENT-SIGNAL-CHANGE above=1 signal=-47 noise=9999 txrate=1000
Jan 17 12:35:02 adeepn NetworkManager[1827]: <info> [1705484102.2430]
device (wlo1): Activation: (wifi) Stage 2 of 5 (Device Configure)
successful. Connected to wireless network "Hostspot"
Log from hostapd in attachment.
4) Viacheslav, I think you previously mentioned that you did a bit of
work with the downstream driver.
It would be awesome if you could also take a look at the rtw88 and
downstream driver code and start comparing them (logic that's
different or completely missing from rtw88 is suspicious).
I made some minor fixes to restore functionality after kernel updates.
But in this case, I will need help to understand where and how to start
figuring this out.
Moreover, we've encountered another problem: after two or three days the
client WiFi connection stops working, the network interface loses its
DHCP address, and consequently nothing else works.
Best regards,
Martin
[0] https://github.com/chewitt/RTL8822CS/blob/60cd82134d63aa9436b43c42933a86d6e5a191ba/hal/rtl8822c/rtl8822c_ops.c#L1885-L1893
[1] https://github.com/chewitt/RTL8822CS/blob/main/hal/rtl8822c/sdio/rtl8822cs_xmit.c#L311-L312
1705484236.144054: random: getrandom() support available
1705484236.144669: Configuration file: tmp-hotspot.conf
1705484236.153165: nl80211: TDLS supported
1705484236.153385: nl80211: Supported cipher 00-0f-ac:1
1705484236.153435: nl80211: Supported cipher 00-0f-ac:5
1705484236.153473: nl80211: Supported cipher 00-0f-ac:2
1705484236.153507: nl80211: Supported cipher 00-0f-ac:4
1705484236.153541: nl80211: Supported cipher 00-0f-ac:6
1705484236.153574: nl80211: Supported cipher 00-0f-ac:8
1705484236.153607: nl80211: Supported cipher 00-0f-ac:9
1705484236.153640: nl80211: Supported cipher 00-0f-ac:10
1705484236.153673: nl80211: Supported cipher 00-0f-ac:11
1705484236.153706: nl80211: Supported cipher 00-0f-ac:12
1705484236.153738: nl80211: Supported cipher 00-0f-ac:13
1705484236.154288: nl80211: Supported vendor command: vendor_id=0x1a11 subcmd=4106
1705484236.154330: nl80211: Supported vendor command: vendor_id=0x1a11 subcmd=4107
1705484236.154520: nl80211: key_mgmt=0xf0f enc=0xfef auth=0x7 flags=0xa4858c0 rrm_flags=0x10 probe_resp_offloads=0x0 max_stations=0 max_remain_on_chan=5000 max_scan_ssids=9
1705484236.154582: nl80211: interface wlan1 in phy phy1
1705484236.154962: nl80211: Set mode ifindex 85 iftype 3 (AP)
1705484237.236678: nl80211: Setup AP(wlan1) - device_ap_sme=1 use_monitor=0
1705484237.236957: nl80211: Subscribe to mgmt frames with AP handle 0xaaab0d1007f0 (device SME)
1705484237.237018: nl80211: Register frame type=0xd0 (WLAN_FC_STYPE_ACTION) nl_handle=0xaaab0d1007f0 match=04 multicast=0
1705484237.237165: nl80211: Register frame type=0xd0 (WLAN_FC_STYPE_ACTION) nl_handle=0xaaab0d1007f0 match=0501 multicast=0
1705484237.237210: nl80211: Register frame type=0xd0 (WLAN_FC_STYPE_ACTION) nl_handle=0xaaab0d1007f0 match=0503 multicast=0
1705484237.237248: nl80211: Register frame type=0xd0 (WLAN_FC_STYPE_ACTION) nl_handle=0xaaab0d1007f0 match=0504 multicast=0
1705484237.237286: nl80211: Register frame type=0xd0 (WLAN_FC_STYPE_ACTION) nl_handle=0xaaab0d1007f0 match=06 multicast=0
1705484237.237322: nl80211: Register frame type=0xd0 (WLAN_FC_STYPE_ACTION) nl_handle=0xaaab0d1007f0 match=08 multicast=0
1705484237.237359: nl80211: Register frame type=0xd0 (WLAN_FC_STYPE_ACTION) nl_handle=0xaaab0d1007f0 match=09 multicast=0
1705484237.237396: nl80211: Register frame type=0xd0 (WLAN_FC_STYPE_ACTION) nl_handle=0xaaab0d1007f0 match=0a multicast=0
1705484237.237432: nl80211: Register frame type=0xd0 (WLAN_FC_STYPE_ACTION) nl_handle=0xaaab0d1007f0 match=11 multicast=0
1705484237.237469: nl80211: Register frame type=0xd0 (WLAN_FC_STYPE_ACTION) nl_handle=0xaaab0d1007f0 match=12 multicast=0
1705484237.237506: nl80211: Register frame type=0xd0 (WLAN_FC_STYPE_ACTION) nl_handle=0xaaab0d1007f0 match=7f multicast=0
1705484237.237541: nl80211: Register frame type=0xb0 (WLAN_FC_STYPE_AUTH) nl_handle=0xaaab0d1007f0 match= multicast=0
1705484237.237641: nl80211: Enable Probe Request reporting nl_preq=0xaaab0d0fc040
1705484237.237649: nl80211: Register frame type=0x40 (WLAN_FC_STYPE_PROBE_REQ) nl_handle=0xaaab0d0fc040 match= multicast=0
1705484237.238343: rfkill: initial event: idx=3 type=1 op=0 soft=0 hard=0
1705484237.238993: nl80211: Add own interface ifindex 85 (ifidx_reason -1)
1705484237.239018: nl80211: if_indices[16]: 85(-1)
1705484237.239076: phy: phy1
1705484237.239087: BSS count 1, BSSID mask 00:00:00:00:00:00 (0 bits)
1705484237.239291: wlan1: interface state UNINITIALIZED->COUNTRY_UPDATE
1705484237.239463: Previous country code RU, new country code RU
1705484237.240010: nl80211: Regulatory information - country=RU
1705484237.240030: nl80211: 2400-2483 @ 40 MHz 20 mBm
1705484237.240042: nl80211: 5150-5350 @ 160 MHz 20 mBm (no outdoor)
1705484237.240051: nl80211: 5650-5850 @ 160 MHz 20 mBm (no outdoor)
1705484237.240059: nl80211: 57000-66000 @ 2160 MHz 40 mBm (no outdoor)
1705484237.240098: nl80211: Added 802.11b mode based on 802.11g information
1705484237.240120: nl80211: Mode IEEE 802.11g: 2412 2417 2422 2427 2432 2437 2442 2447 2452 2457 2462 2467[NO_IR] 2472[NO_IR] 2484[DISABLED]
1705484237.240144: nl80211: Mode IEEE 802.11a: 5180 5200 5220 5240 5260[RADAR] 5280[RADAR] 5300[RADAR] 5320[RADAR] 5500[RADAR] 5520[RADAR] 5540[RADAR] 5560[RADAR] 5580[RADAR] 5600[RADAR] 5620[RADAR] 5640[RADAR] 5660[RADAR] 5680[RADAR]
1705484237.240217: nl80211: Mode IEEE 802.11b: 2412 2417 2422 2427 2432 2437 2442 2447 2452 2457 2462 2467[NO_IR] 2472[NO_IR] 2484[DISABLED]
1705484237.240224: Allowed channel: mode=1 chan=1 freq=2412 MHz max_tx_power=20 dBm
1705484237.240231: Allowed channel: mode=1 chan=2 freq=2417 MHz max_tx_power=20 dBm
1705484237.240237: Allowed channel: mode=1 chan=3 freq=2422 MHz max_tx_power=20 dBm
1705484237.240243: Allowed channel: mode=1 chan=4 freq=2427 MHz max_tx_power=20 dBm
1705484237.240248: Allowed channel: mode=1 chan=5 freq=2432 MHz max_tx_power=20 dBm
1705484237.240254: Allowed channel: mode=1 chan=6 freq=2437 MHz max_tx_power=20 dBm
1705484237.240260: Allowed channel: mode=1 chan=7 freq=2442 MHz max_tx_power=20 dBm
1705484237.240266: Allowed channel: mode=1 chan=8 freq=2447 MHz max_tx_power=20 dBm
1705484237.240272: Allowed channel: mode=1 chan=9 freq=2452 MHz max_tx_power=20 dBm
1705484237.240277: Allowed channel: mode=1 chan=10 freq=2457 MHz max_tx_power=20 dBm
1705484237.240283: Allowed channel: mode=1 chan=11 freq=2462 MHz max_tx_power=20 dBm
1705484237.240289: Allowed channel: mode=2 chan=36 freq=5180 MHz max_tx_power=20 dBm
1705484237.240295: Allowed channel: mode=2 chan=40 freq=5200 MHz max_tx_power=20 dBm
1705484237.240301: Allowed channel: mode=2 chan=44 freq=5220 MHz max_tx_power=20 dBm
1705484237.240307: Allowed channel: mode=2 chan=48 freq=5240 MHz max_tx_power=20 dBm
1705484237.240313: Allowed channel: mode=2 chan=149 freq=5745 MHz max_tx_power=20 dBm
1705484237.240319: Allowed channel: mode=2 chan=153 freq=5765 MHz max_tx_power=20 dBm
1705484237.240325: Allowed channel: mode=2 chan=157 freq=5785 MHz max_tx_power=20 dBm
1705484237.240331: Allowed channel: mode=2 chan=161 freq=5805 MHz max_tx_power=20 dBm
1705484237.240337: Allowed channel: mode=2 chan=165 freq=5825 MHz max_tx_power=20 dBm
1705484237.240343: Allowed channel: mode=0 chan=1 freq=2412 MHz max_tx_power=20 dBm
1705484237.240348: Allowed channel: mode=0 chan=2 freq=2417 MHz max_tx_power=20 dBm
1705484237.240354: Allowed channel: mode=0 chan=3 freq=2422 MHz max_tx_power=20 dBm
1705484237.240360: Allowed channel: mode=0 chan=4 freq=2427 MHz max_tx_power=20 dBm
1705484237.240366: Allowed channel: mode=0 chan=5 freq=2432 MHz max_tx_power=20 dBm
1705484237.240372: Allowed channel: mode=0 chan=6 freq=2437 MHz max_tx_power=20 dBm
1705484237.240378: Allowed channel: mode=0 chan=7 freq=2442 MHz max_tx_power=20 dBm
1705484237.240384: Allowed channel: mode=0 chan=8 freq=2447 MHz max_tx_power=20 dBm
1705484237.240389: Allowed channel: mode=0 chan=9 freq=2452 MHz max_tx_power=20 dBm
1705484237.240395: Allowed channel: mode=0 chan=10 freq=2457 MHz max_tx_power=20 dBm
1705484237.240401: Allowed channel: mode=0 chan=11 freq=2462 MHz max_tx_power=20 dBm
1705484237.240414: Completing interface initialization
1705484237.240419: Mode: IEEE 802.11g Channel: 6 Frequency: 2437 MHz
1705484237.240427: DFS 0 channels required radar detection
1705484237.240437: nl80211: Set freq 2437 (ht_enabled=0, vht_enabled=0, he_enabled=0, bandwidth=20 MHz, cf1=2437 MHz, cf2=0 MHz)
1705484237.240448: * freq=2437
1705484237.240454: * he_enabled=0
1705484237.240459: * vht_enabled=0
1705484237.240464: * ht_enabled=0
1705484237.240469: * channel_type=0
1705484237.240536: RATE[0] rate=10 flags=0x1
1705484237.240545: RATE[1] rate=20 flags=0x1
1705484237.240551: RATE[2] rate=55 flags=0x1
1705484237.240556: RATE[3] rate=110 flags=0x1
1705484237.240562: RATE[4] rate=60 flags=0x0
1705484237.240567: RATE[5] rate=90 flags=0x0
1705484237.240572: RATE[6] rate=120 flags=0x0
1705484237.240577: RATE[7] rate=180 flags=0x0
1705484237.240583: RATE[8] rate=240 flags=0x0
1705484237.240588: RATE[9] rate=360 flags=0x0
1705484237.240593: RATE[10] rate=480 flags=0x0
1705484237.240599: RATE[11] rate=540 flags=0x0
1705484237.240605: hostapd_setup_bss(hapd=0xaaab0d0f9da0 (wlan1), first=1)
1705484237.240613: nl80211: flush -> DEL_STATION wlan1 (all)
1705484237.240948: Using interface wlan1 with hwaddr 00:13:ef:5f:22:2f and ssid "Hostspot"
1705484237.241035: Deriving WPA PSK based on passphrase
1705484237.241044: SSID - hexdump_ascii(len=8):
48 6f 73 74 73 70 6f 74 Hostspot
1705484237.241066: PSK (ASCII passphrase) - hexdump_ascii(len=8):
31 32 33 34 35 36 37 38 12345678
1705484237.270457: PSK (from passphrase) - hexdump(len=32): ed c9 7f 47 99 f3 a7 88 64 a5 1d c2 fb 93 69 6f 27 68 cd a6 ea 98 46 95 a7 4b 12 35 f5 09 1c e3
1705484237.270679: random: Got 20/20 random bytes
1705484237.270702: Get randomness: len=32 entropy=0
1705484237.270962: GMK - hexdump(len=32): 79 06 50 47 ef d2 e7 55 cb c5 00 7b 5d 60 dc 6a 70 b2 91 61 17 17 3d ce 5b d9 29 19 b8 73 dd 33
1705484237.270993: Get randomness: len=32 entropy=0
1705484237.271142: Key Counter - hexdump(len=32): 56 91 1a 29 16 f0 6d b6 d2 47 b8 f4 5d 02 a0 84 e1 ff 96 9f 41 31 4a 6e b0 fd 42 b6 75 c7 1b 6b
1705484237.271166: WPA: Delay group state machine start until Beacon frames have been configured
1705484237.271384: nl80211: Set beacon (beacon_set=0)
1705484237.271397: nl80211: Beacon head - hexdump(len=59): 80 00 00 00 ff ff ff ff ff ff 00 13 ef 5f 22 2f 00 13 ef 5f 22 2f 00 00 00 00 00 00 00 00 00 00 64 00 11 04 00 08 48 6f 73 74 73 70 6f 74 01 08 82 84 8b 96 0c 12 18 24 03 01 06
1705484237.271436: nl80211: Beacon tail - hexdump(len=45): 07 06 52 55 20 01 0b 14 2a 01 04 32 04 30 48 60 6c 30 14 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 00 0f ac 02 00 00 7f 04 00 00 00 02
1705484237.271467: nl80211: ifindex=85
1705484237.271473: nl80211: beacon_int=100
1705484237.271478: nl80211: beacon_rate=0
1705484237.271483: nl80211: rate_type=0
1705484237.271487: nl80211: dtim_period=2
1705484237.271493: nl80211: ssid=Hostspot
1705484237.271512: * beacon_int=100
1705484237.271517: * dtim_period=2
1705484237.271523: nl80211: hidden SSID not in use
1705484237.271528: nl80211: privacy=2
1705484237.271534: nl80211: auth_algs=0x1
1705484237.271539: nl80211: wpa_version=0x2
1705484237.271545: nl80211: key_mgmt_suites=0x2
1705484237.271551: nl80211: pairwise_ciphers=0x10
1705484237.271557: nl80211: group_cipher=0x10
1705484237.271563: nl80211: beacon_ies - hexdump(len=6): 7f 04 00 00 00 02
1705484237.271572: nl80211: proberesp_ies - hexdump(len=6): 7f 04 00 00 00 02
1705484237.271581: nl80211: assocresp_ies - hexdump(len=6): 7f 04 00 00 00 02
1705484237.408745: wlan1: Deauthenticate all stations at BSS start
1705484237.408983: nl80211: sta_remove -> DEL_STATION wlan1 ff:ff:ff:ff:ff:ff --> -22 (Invalid argument)
1705484237.409014: WPA: Start group state machine to set initial keys
1705484237.409021: WPA: group state machine entering state GTK_INIT (VLAN-ID 0)
1705484237.409029: Get randomness: len=16 entropy=0
1705484237.409343: GTK - hexdump(len=16): 0a ee 49 50 56 2a f9 3d 82 3b be 1a 86 76 cd 0c
1705484237.409367: WPA: group state machine entering state SETKEYSDONE (VLAN-ID 0)
1705484237.409484: wpa_driver_nl80211_set_key: ifindex=85 (wlan1) alg=3 addr=0xaaaae4014b78 key_idx=1 set_tx=1 seq_len=0 key_len=16 key_flag=0x1a
1705484237.409543: nl80211: NEW_KEY
1705484237.409564: nl80211: KEY_DATA - hexdump(len=16): 0a ee 49 50 56 2a f9 3d 82 3b be 1a 86 76 cd 0c
1705484237.409580: broadcast key
1705484237.409718: nl80211: NL80211_CMD_SET_KEY - default key
1705484237.409773: nl80211: Set wlan1 operstate 0->1 (UP)
1705484237.409784: netlink: Operstate: ifindex=85 linkmode=-1 (no change), operstate=6 (IF_OPER_UP)
1705484237.409901: nl80211: TX queue param set: queue=0 aifs=1 cw_min=3 cw_max=7 burst_time=15 --> res=0
1705484237.409946: nl80211: TX queue param set: queue=1 aifs=1 cw_min=7 cw_max=15 burst_time=30 --> res=0
1705484237.410001: nl80211: TX queue param set: queue=2 aifs=3 cw_min=15 cw_max=63 burst_time=0 --> res=0
1705484237.410042: nl80211: TX queue param set: queue=3 aifs=7 cw_min=15 cw_max=1023 burst_time=0 --> res=0
1705484237.410054: nl80211: Set Deny ACL (num_mac_acl=0)
1705484237.410116: wlan1: interface state COUNTRY_UPDATE->ENABLED
1705484237.410225: wlan1: AP-ENABLED
1705484237.410236: wlan1: Setup of interface done.
1705484237.410246: ctrl_iface not configured!
1705484237.410295: nl80211: Event message available
1705484237.410329: nl80211: Drv Event 15 (NL80211_CMD_START_AP) received for wlan1
1705484237.410355: wlan1: nl80211: Ignored unknown event (cmd=15)
1705484245.841253: nl80211: Event message available
1705484245.841978: nl80211: Drv Event 19 (NL80211_CMD_NEW_STATION) received for wlan1
1705484245.842110: nl80211: New station 88:d8:2e:f8:af:25
1705484245.842181: nl80211: Assoc Req IEs - hexdump(len=80): 00 08 48 6f 73 74 73 70 6f 74 01 08 02 04 0b 16 0c 12 18 24 32 04 30 48 60 6c 30 14 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 00 0f ac 02 80 00 7f 0b 04 00 ca 02 01 40 40 c0 01 21 20 3b 11 51 51 53 54 73 74 75 76 77 78 79 7a 7b 7c 7d 7e 7f
1705484245.842635: wlan1: Event ASSOC (0) received
1705484245.842745: 1705484245.842753: wlan1: STA 88:d8:2e:f8:af:25 IEEE 802.11: associated
1705484245.843568: STA included RSN IE in (Re)AssocReq
1705484245.843663: New STA
1705484245.844119: ap_sta_add: register ap_handle_timer timeout for 88:d8:2e:f8:af:25 (300 seconds - ap_max_inactivity)
1705484245.844267: nl80211: Set STA flags - ifname=wlan1 addr=88:d8:2e:f8:af:25 total_flags=0x60 flags_or=0x0 flags_and=0xfffffff1 authorized=0
1705484245.845156: 1705484245.845171: wlan1: STA 88:d8:2e:f8:af:25 WPA: event 1 notification
1705484245.845588: wpa_driver_nl80211_set_key: ifindex=85 (wlan1) alg=0 addr=0xaaab0d126870 key_idx=0 set_tx=1 seq_len=0 key_len=0 key_flag=0x20
1705484245.845721: nl80211: DEL_KEY
1705484245.845804: addr=88:d8:2e:f8:af:25
1705484245.845871: pairwise key
1705484245.846249: IEEE 802.1X: Ignore STA - 802.1X not enabled or forced for WPS
1705484245.846372: 1705484245.846380: wlan1: STA 88:d8:2e:f8:af:25 WPA: start authentication
1705484245.846440: WPA: 88:d8:2e:f8:af:25 WPA_PTK entering state INITIALIZE
1705484245.846715: wpa_driver_nl80211_set_key: ifindex=85 (wlan1) alg=0 addr=0xaaab0d126870 key_idx=0 set_tx=1 seq_len=0 key_len=0 key_flag=0x20
1705484245.846816: nl80211: DEL_KEY
1705484245.846890: addr=88:d8:2e:f8:af:25
1705484245.846952: pairwise key
1705484245.847410: nl80211: Set STA flags - ifname=wlan1 addr=88:d8:2e:f8:af:25 total_flags=0x60 flags_or=0x0 flags_and=0xfffffffe authorized=0
1705484245.847877: 1705484245.847888: wlan1: STA 88:d8:2e:f8:af:25 IEEE 802.1X: unauthorizing port
1705484245.847980: WPA: 88:d8:2e:f8:af:25 WPA_PTK_GROUP entering state IDLE
1705484245.848048: WPA: 88:d8:2e:f8:af:25 WPA_PTK entering state AUTHENTICATION
1705484245.848114: WPA: 88:d8:2e:f8:af:25 WPA_PTK entering state AUTHENTICATION2
1705484245.848174: WPA: Re-initialize GMK/Counter on first station
1705484245.848226: Get randomness: len=32 entropy=1
1705484245.849484: GMK - hexdump(len=32): 6d 33 ce 27 04 22 f2 96 62 44 27 6d dd 51 f8 53 46 6e e2 a9 10 f4 9c 5b f6 2f 4c 43 70 dd d0 69
1705484245.849790: Get randomness: len=32 entropy=0
1705484245.850809: Key Counter - hexdump(len=32): 75 7b 66 ca 22 1e 91 31 96 24 a3 86 0e 7b f4 a4 25 81 17 07 1a a8 25 13 6e 1e d4 8d 82 34 d3 e1
1705484245.851004: Get randomness: len=16 entropy=0
1705484245.851729: GTK - hexdump(len=16): 0f 61 23 8a fb 78 3f da 9f 33 d9 00 4b 3e 12 e6
1705484245.852098: wpa_driver_nl80211_set_key: ifindex=85 (wlan1) alg=3 addr=0xaaaae4014b78 key_idx=1 set_tx=1 seq_len=0 key_len=16 key_flag=0x1a
1705484245.852206: nl80211: NEW_KEY
1705484245.852291: nl80211: KEY_DATA - hexdump(len=16): 0f 61 23 8a fb 78 3f da 9f 33 d9 00 4b 3e 12 e6
1705484245.852397: broadcast key
1705484245.852991: nl80211: NL80211_CMD_SET_KEY - default key
1705484245.853572: Get randomness: len=32 entropy=0
1705484245.855030: WPA: Assign ANonce - hexdump(len=32): 80 1e a4 b4 a3 b0 eb af 67 36 c9 6d b0 f7 63 87 79 55 38 df be f6 72 d8 80 64 8f 59 4a 90 21 1d
1705484245.855317: WPA: 88:d8:2e:f8:af:25 WPA_PTK entering state INITPSK
1705484245.855435: Searching a PSK for 88:d8:2e:f8:af:25 prev_psk=(nil)
1705484245.855557: Searching a PSK for 88:d8:2e:f8:af:25 prev_psk=(nil)
1705484245.855853: WPA: 88:d8:2e:f8:af:25 WPA_PTK entering state PTKSTART
1705484245.856035: 1705484245.856043: wlan1: STA 88:d8:2e:f8:af:25 WPA: sending 1/4 msg of 4-Way Handshake
1705484245.856085: WPA: Send EAPOL(version=2 secure=0 mic=0 ack=1 install=0 pairwise=1 kde_len=0 keyidx=0 encr=0)
1705484245.856184: WPA: Replay Counter - hexdump(len=8): 00 00 00 00 00 00 00 01
1705484245.856653: WPA: Use EAPOL-Key timeout of 1000 ms (retry counter 1)
1705484245.856722: wlan1: hostapd_new_assoc_sta: reschedule ap_handle_timer timeout for 88:d8:2e:f8:af:25 (300 seconds - ap_max_inactivity)
1705484245.865312: wlan1: Event EAPOL_RX (23) received
1705484245.865519: IEEE 802.1X: 121 bytes from 88:d8:2e:f8:af:25
1705484245.865569: IEEE 802.1X: version=1 type=3 length=117
1705484245.865606: WPA: RX EAPOL data - hexdump(len=121): 01 03 00 75 02 01 0a 00 00 00 00 00 00 00 00 00 01 0e 04 4e b2 2c 0d c2 6d 50 ba 74 3a 89 0d 25 40 05 5d a1 db 42 0d 21 43 d4 a0 88 a8 8c 64 46 ae 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 ed 92 80 3b 52 e5 f2 4e 88 b5 53 f5 28 e6 e2 bc 00 16 30 14 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 00 0f ac 02 80 00
1705484245.866021: WPA: Received EAPOL-Key from 88:d8:2e:f8:af:25 key_info=0x10a type=2 mic_len=16 key_data_length=22
1705484245.866065: WPA: EAPOL-Key header (ending before Key MIC) - hexdump(len=77): 02 01 0a 00 00 00 00 00 00 00 00 00 01 0e 04 4e b2 2c 0d c2 6d 50 ba 74 3a 89 0d 25 40 05 5d a1 db 42 0d 21 43 d4 a0 88 a8 8c 64 46 ae 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
1705484245.866338: WPA: EAPOL-Key Key MIC - hexdump(len=16): ed 92 80 3b 52 e5 f2 4e 88 b5 53 f5 28 e6 e2 bc
1705484245.866415: WPA: Received Key Nonce - hexdump(len=32): 0e 04 4e b2 2c 0d c2 6d 50 ba 74 3a 89 0d 25 40 05 5d a1 db 42 0d 21 43 d4 a0 88 a8 8c 64 46 ae
1705484245.866540: WPA: Received Replay Counter - hexdump(len=8): 00 00 00 00 00 00 00 01
1705484245.866649: 1705484245.866657: wlan1: STA 88:d8:2e:f8:af:25 WPA: received EAPOL-Key frame (2/4 Pairwise)
1705484245.866699: WPA: 88:d8:2e:f8:af:25 WPA_PTK entering state PTKCALCNEGOTIATING
1705484245.866742: Searching a PSK for 88:d8:2e:f8:af:25 prev_psk=(nil)
1705484245.866791: WPA: PTK derivation using PRF(SHA1)
1705484245.867159: WPA: PTK derivation - A1=00:13:ef:5f:22:2f A2=88:d8:2e:f8:af:25
1705484245.867217: WPA: Nonce1 - hexdump(len=32): 80 1e a4 b4 a3 b0 eb af 67 36 c9 6d b0 f7 63 87 79 55 38 df be f6 72 d8 80 64 8f 59 4a 90 21 1d
1705484245.867341: WPA: Nonce2 - hexdump(len=32): 0e 04 4e b2 2c 0d c2 6d 50 ba 74 3a 89 0d 25 40 05 5d a1 db 42 0d 21 43 d4 a0 88 a8 8c 64 46 ae
1705484245.867466: WPA: PMK - hexdump(len=32): ed c9 7f 47 99 f3 a7 88 64 a5 1d c2 fb 93 69 6f 27 68 cd a6 ea 98 46 95 a7 4b 12 35 f5 09 1c e3
1705484245.867587: WPA: PTK - hexdump(len=48): 67 91 2d 86 34 56 c3 55 62 d0 08 b1 b7 e3 0a 0f 04 fc db fc 06 7c 85 7b 18 68 3a ed 80 a3 8a 96 23 3d 74 a1 cb b6 c6 ca 2a 32 ef d2 a7 e2 ef a6
1705484245.867755: WPA: KCK - hexdump(len=16): 67 91 2d 86 34 56 c3 55 62 d0 08 b1 b7 e3 0a 0f
1705484245.867832: WPA: KEK - hexdump(len=16): 04 fc db fc 06 7c 85 7b 18 68 3a ed 80 a3 8a 96
1705484245.867908: WPA: TK - hexdump(len=16): 23 3d 74 a1 cb b6 c6 ca 2a 32 ef d2 a7 e2 ef a6
1705484245.867987: WPA: EAPOL-Key MIC using HMAC-SHA1
1705484245.868102: WPA: RSN IE in EAPOL-Key - hexdump(len=22): 30 14 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 00 0f ac 02 80 00
1705484245.868226: WPA: 88:d8:2e:f8:af:25 WPA_PTK entering state PTKCALCNEGOTIATING2
1705484245.868268: WPA: 88:d8:2e:f8:af:25 WPA_PTK entering state PTKINITNEGOTIATING
1705484245.869391: 1705484245.869407: wlan1: STA 88:d8:2e:f8:af:25 WPA: sending 3/4 msg of 4-Way Handshake
1705484245.869598: WPA: Send EAPOL(version=2 secure=1 mic=1 ack=1 install=1 pairwise=1 kde_len=46 keyidx=0 encr=1)
1705484245.869668: WPA: Replay Counter - hexdump(len=8): 00 00 00 00 00 00 00 02
1705484245.869736: Plaintext EAPOL-Key Key Data - hexdump(len=56): 30 14 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 00 0f ac 02 00 00 dd 16 00 0f ac 01 01 00 0f 61 23 8a fb 78 3f da 9f 33 d9 00 4b 3e 12 e6 dd 00 00 00 00 00 00 00 00 00
1705484245.870126: WPA: Encrypt Key Data using AES-WRAP (KEK length 16)
1705484245.870251: WPA: EAPOL-Key MIC using HMAC-SHA1
1705484245.870752: WPA: Use EAPOL-Key timeout of 1000 ms (retry counter 1)
1705484245.879677: wlan1: Event EAPOL_RX (23) received
1705484245.879869: IEEE 802.1X: 99 bytes from 88:d8:2e:f8:af:25
1705484245.879918: IEEE 802.1X: version=1 type=3 length=95
1705484245.879956: WPA: RX EAPOL data - hexdump(len=99): 01 03 00 5f 02 03 0a 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 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 e9 e0 49 16 de 34 f5 1a dc ee 66 5b 9a 51 d7 44 00 00
1705484245.880311: WPA: Received EAPOL-Key from 88:d8:2e:f8:af:25 key_info=0x30a type=2 mic_len=16 key_data_length=0
1705484245.880355: WPA: EAPOL-Key header (ending before Key MIC) - hexdump(len=77): 02 03 0a 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 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
1705484245.880637: WPA: EAPOL-Key Key MIC - hexdump(len=16): e9 e0 49 16 de 34 f5 1a dc ee 66 5b 9a 51 d7 44
1705484245.880714: WPA: Received Key Nonce - 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
1705484245.881077: WPA: Received Replay Counter - hexdump(len=8): 00 00 00 00 00 00 00 02
1705484245.881191: 1705484245.881199: wlan1: STA 88:d8:2e:f8:af:25 WPA: received EAPOL-Key frame (4/4 Pairwise)
1705484245.881241: WPA: EAPOL-Key MIC using HMAC-SHA1
1705484245.881462: WPA: 88:d8:2e:f8:af:25 WPA_PTK entering state PTKINITDONE
1705484245.881781: wpa_driver_nl80211_set_key: ifindex=85 (wlan1) alg=3 addr=0xaaab0d126870 key_idx=0 set_tx=1 seq_len=0 key_len=16 key_flag=0x2c
1705484245.881878: nl80211: NEW_KEY
1705484245.882031: nl80211: KEY_DATA - hexdump(len=16): 23 3d 74 a1 cb b6 c6 ca 2a 32 ef d2 a7 e2 ef a6
1705484245.882118: addr=88:d8:2e:f8:af:25
1705484245.882158: pairwise key
1705484245.882613: wlan1: AP-STA-CONNECTED 88:d8:2e:f8:af:25
1705484245.882669: nl80211: Set STA flags - ifname=wlan1 addr=88:d8:2e:f8:af:25 total_flags=0x61 flags_or=0x1 flags_and=0xffffffff authorized=1
1705484245.882976: 1705484245.882984: wlan1: STA 88:d8:2e:f8:af:25 IEEE 802.1X: authorizing port
1705484245.883066: 1705484245.883074: wlan1: STA 88:d8:2e:f8:af:25 RADIUS: starting accounting session BF8257E7EDC5AC2F
1705484245.883300: 1705484245.883309: wlan1: STA 88:d8:2e:f8:af:25 WPA: pairwise key handshake completed (RSN)
1705484245.883464: wlan1: EAPOL-4WAY-HS-COMPLETED 88:d8:2e:f8:af:25
1705484264.494331: Signal 2 received - terminating
1705484264.494600: hostapd_interface_deinit_free(0xaaab0d0f85e0)
1705484264.494657: hostapd_interface_deinit_free: num_bss=1 conf->num_bss=1
1705484264.494692: hostapd_interface_deinit(0xaaab0d0f85e0)
1705484264.494725: wlan1: interface state ENABLED->DISABLED
1705484264.494758: hostapd_bss_deinit: deinit bss wlan1
1705484264.494849: 1705484264.494857: wlan1: STA 88:d8:2e:f8:af:25 MLME: MLME-DEAUTHENTICATE.indication(88:d8:2e:f8:af:25, 1)
1705484264.494890: Removing station 88:d8:2e:f8:af:25
1705484264.494955: wlan1: AP-STA-DISCONNECTED 88:d8:2e:f8:af:25
1705484264.494993: nl80211: Set STA flags - ifname=wlan1 addr=88:d8:2e:f8:af:25 total_flags=0x60 flags_or=0x0 flags_and=0xfffffff1 authorized=0
1705484264.495410: ap_free_sta: cancel ap_handle_timer for 88:d8:2e:f8:af:25
1705484264.495523: wlan1: Deauthenticate all stations
1705484264.495761: nl80211: sta_remove -> DEL_STATION wlan1 ff:ff:ff:ff:ff:ff --> -22 (Invalid argument)
1705484264.495830: wlan1: AP-DISABLED
1705484264.495864: hostapd_cleanup(hapd=0xaaab0d0f9da0 (wlan1))
1705484264.495906: wlan1: CTRL-EVENT-TERMINATING
1705484264.496318: hostapd_free_hapd_data(wlan1)
1705484264.497796: hostapd_interface_deinit_free: driver=0xaaaae40be5a0 drv_priv=0xaaab0d0fb620 -> hapd_deinit
1705484264.498134: nl80211: deinit ifname=wlan1 disabled_11b_rates=0
1705484264.517055: nl80211: Disable Probe Request reporting nl_preq=0x88882223858748c9
1705484264.517453: nl80211: Remove monitor interface: refcount=0
1705484264.517506: nl80211: Remove beacon (ifindex=85)
1705484264.561421: netlink: Operstate: ifindex=85 linkmode=0 (kernel-control), operstate=6 (IF_OPER_UP)
1705484264.561789: nl80211: Set mode ifindex 85 iftype 2 (STATION)
1705484264.564377: nl80211: Teardown AP(wlan1) - device_ap_sme=1 use_monitor=0
1705484264.564492: nl80211: Unsubscribe mgmt frames handle 0x8888222385988f79 (AP teardown (dev SME))
1705484264.564721: hostapd_interface_free(0xaaab0d0f85e0)
1705484264.564770: hostapd_interface_free: free hapd 0xaaab0d0f9da0
1705484264.565040: hostapd_cleanup_iface(0xaaab0d0f85e0)
1705484264.565091: hostapd_cleanup_iface_partial(0xaaab0d0f85e0)
1705484264.565181: hostapd_cleanup_iface: free iface=0xaaab0d0f85e0
