I have a number of custom boards which fail to connect as a WiFi access point.
This seems to be usually stuck on the PTKINITNEGOTIATING failure shown in the log below.
When running same code in a DevKitC with the same processor, I usually get a connection. A HotSpot survey app on my phone shows the proper SSID, and sufficient power (~-35db), but inconsistently. My assumption is a different read and post rate for messages and the open source app, however I am not certain of that.
The reported timeout on the eapol1 seems to indicate that no response was seen, and I do not currently have any diagnostics on the client side.
Any suggestions would be helpful.
The log:
D (562969) wifi:ap recv assoc/reassoc request
I (562970) wifi
<4,0>, old:<4,0>, ap:<4,1>, sta:<255,255>, prof:4D (562970) wifi:bss=0x3ffd44f0, resp=16 join
I (562973) wifi
00:57:c1:b8:92:3e join, AID=1, bgn, 20D (562978) wifi:join success, ap send assoc response
D (562984) wpa: add sm, index=0 bitmap=1
[0m
D (562987) wpa: init wpa sm=0x3ffd4a88
[0m
D (562991) wpa: WPA: 00:57:c1:b8:92:3e WPA_PTK entering state INITIALIZE
[0m
D (562998) wpa: WPA: 00:57:c1:b8:92:3e WPA_PTK_GROUP entering state IDLE
[0m
D (563005) wpa: WPA: 00:57:c1:b8:92:3e WPA_PTK entering state AUTHENTICATION
[0m
D (563012) wpa: WPA: 00:57:c1:b8:92:3e WPA_PTK entering state AUTHENTICATION2
[0m
D (563020) wpa: WPA: Re-initialize GMK/Counter on first station
[0m
D (563028) wpa: WPA: 00:57:c1:b8:92:3e WPA_PTK entering state INITPSK
[0m
D (563033) wpa: WPA: 00:57:c1:b8:92:3e WPA_PTK entering state PTKSTART
[0m
D (563039) wpa: wpa_auth=0x3ffd115c sm=0x3ffd4a88 kdersc=0x0 kde=0x0 nounce=0x3ffd4ad4 kde_len=0 keyidx=0 encr=0 force=0
[0m
D (563051) wpa: WPA: Send EAPOL(version=2 secure=0 mic=0 ack=1 install=0 pairwise=8 kde_len=0 keyidx=0 encr=0)
[0m
D (563061) wpa: WPA: Use EAPOL-Key timeout of 1000 ms (retry counter 1)
[0m
D (564068) wpa: resend eapol1[0m
D (564068) wpa: WPA: 00:57:c1:b8:92:3e WPA_PTK entering state PTKSTART
[0m
D (564069) wpa: wpa_auth=0x3ffd115c sm=0x3ffd4a88 kdersc=0x0 kde=0x0 nounce=0x3ffd4ad4 kde_len=0 keyidx=0 encr=0 force=0
[0m
D (564078) wpa: WPA: Send EAPOL(version=2 secure=0 mic=0 ack=1 install=0 pairwise=8 kde_len=0 keyidx=0 encr=0)
[0m
D (564089) wpa: WPA: Use EAPOL-Key timeout of 1000 ms (retry counter 2)
[0m
[0;32mI (564279) APP_MAIN: Checking SPI Loop Temp Read is 38.44 (148), result - 0 [0m
[0;32mI (564280) APP_MAIN: Finished app_main, sleeping loop 98 [0m
D (564837) wpa: WPA: Received EAPOL-Key from 00:57:c1:b8:92:3e key_info=0x10a type=2 key_data_length=22
[0m
D (564838) wpa: wpa_rx: free eapol=0x0
[0m
D (564840) wpa: wpa_rx: new eapol=0x3ffd145c
[0m
D (564844) wpa: WPA: 00:57:c1:b8:92:3e WPA_PTK entering state PTKCALCNEGOTIATING
[0m
D (564852) wpa: wpa psk
[0m
D (564856) wpa: WPA: PTK derivation - A1=24:0a:c4:7b:f4:05 A2=00:57:c1:b8:92:3e
[0m
D (564862) wpa: WPA: PMK - hexdump(len=32):
[0m
D (564867) wpa: 47[0m
D (564869) wpa: 7c[0m
D (564871) wpa: 44[0m
D (564873) wpa: 66[0m
D (564875) wpa: b9[0m
D (564877) wpa: e5[0m
D (564880) wpa: 12[0m
D (564882) wpa: ce[0m
D (564884) wpa: 87[0m
D (564886) wpa: 5a[0m
D (564888) wpa: a6[0m
D (564890) wpa: 4e[0m
D (564893) wpa: da[0m
D (564895) wpa: 64[0m
D (564897) wpa: 46[0m
D (564899) wpa: 6a[0m
D (564901) wpa:
[0m
D (564903) wpa: f3[0m
D (564906) wpa: 77[0m
D (564908) wpa: 0b[0m
D (564910) wpa: 65[0m
D (564912) wpa: 26[0m
D (564914) wpa: f6[0m
D (564916) wpa: fc[0m
D (564919) wpa: f4[0m
D (564921) wpa: f5[0m
D (564923) wpa: 04[0m
D (564925) wpa: cc[0m
D (564927) wpa: 3e[0m
D (564929) wpa: 1c[0m
D (564932) wpa: be[0m
D (564934) wpa: 13[0m
D (564936) wpa: c6[0m
D (564938) wpa:
[0m
D (564940) wpa:
[0m
D (564942) wpa: WPA: PTK - hexdump(len=48):
[0m
D (564947) wpa: 76[0m
D (564949) wpa: ad[0m
D (564951) wpa: c5[0m
D (564953) wpa: 22[0m
D (564955) wpa: 4c[0m
D (564958) wpa: 11[0m
D (564960) wpa: da[0m
D (564962) wpa: d0[0m
D (564964) wpa: ad[0m
D (564966) wpa: ee[0m
D (564968) wpa: 62[0m
D (564971) wpa: 81[0m
D (564973) wpa: c6[0m
D (564975) wpa: 61[0m
D (564977) wpa: 40[0m
D (564979) wpa: 6c[0m
D (564981) wpa:
[0m
D (564984) wpa: 1f[0m
D (564986) wpa: 39[0m
D (564988) wpa: bd[0m
D (564990) wpa: d7[0m
D (564992) wpa: de[0m
D (564994) wpa: 42[0m
D (564997) wpa: 07[0m
D (564999) wpa: ce[0m
D (565001) wpa: e4[0m
D (565003) wpa: 4a[0m
D (565005) wpa: 26[0m
D (565007) wpa: d4[0m
D (565010) wpa: 82[0m
D (565012) wpa: 55[0m
D (565014) wpa: c2[0m
D (565016) wpa: 71[0m
D (565018) wpa:
[0m
D (565020) wpa: 26[0m
D (565023) wpa: c6[0m
D (565025) wpa: cc[0m
D (565027) wpa: aa[0m
D (565029) wpa: 9c[0m
D (565031) wpa: d0[0m
D (565033) wpa: 52[0m
D (565036) wpa: ff[0m
D (565038) wpa: 5d[0m
D (565040) wpa: c4[0m
D (565042) wpa: 27[0m
D (565044) wpa: 11[0m
D (565046) wpa: ac[0m
D (565049) wpa: ae[0m
D (565051) wpa: f2[0m
D (565053) wpa: 1b[0m
D (565055) wpa:
[0m
D (565057) wpa:
[0m
D (565060) wpa: you mic
[0m
D (565062) wpa:
[0m
D (565064) wpa: 5c [0m
D (565066) wpa: 6e [0m
D (565068) wpa: 07 [0m
D (565071) wpa: 72 [0m
D (565073) wpa: ab [0m
D (565075) wpa: 45 [0m
D (565077) wpa: 59 [0m
D (565079) wpa: e7 [0m
D (565081) wpa: bc [0m
D (565084) wpa: 59 [0m
D (565086) wpa: 6c [0m
D (565088) wpa: b7 [0m
D (565090) wpa: da [0m
D (565092) wpa: 99 [0m
D (565094) wpa: c6 [0m
D (565097) wpa: d5 [0m
D (565099) wpa:
[0m
D (565101) wpa: my mic
[0m
D (565103) wpa:
[0m
D (565106) wpa: 5c [0m
D (565108) wpa: 6e [0m
D (565110) wpa: 07 [0m
D (565112) wpa: 72 [0m
D (565114) wpa: ab [0m
D (565116) wpa: 45 [0m
D (565119) wpa: 59 [0m
D (565121) wpa: e7 [0m
D (565123) wpa: bc [0m
D (565125) wpa: 59 [0m
D (565127) wpa: 6c [0m
D (565129) wpa: b7 [0m
D (565132) wpa: da [0m
D (565134) wpa: 99 [0m
D (565136) wpa: c6 [0m
D (565138) wpa: d5 [0m
D (565140) wpa:
[0m
D (565142) wpa: mic verify ok, pmk=0x3ffd105c
[0m
D (565147) wpa: WPA: 00:57:c1:b8:92:3e WPA_PTK entering state PTKCALCNEGOTIATING2
[0m
D (565155) wpa: WPA: 00:57:c1:b8:92:3e WPA_PTK entering state PTKINITNEGOTIATING
[0m
D (565162) wpa: wpa_auth=0x3ffd115c sm=0x3ffd4a88 kdersc=0x3ffca044 kde=0x3ffd4474 nounce=0x3ffd4ad4 kde_len=94 keyidx=1 encr=1 force=0
[0m
D (565175) wpa: WPA: Send EAPOL(version=2 secure=1 mic=1 ack=1 install=1 pairwise=8 kde_len=94 keyidx=1 encr=1)
[0m
D (565187) wpa: WPA: Use EAPOL-Key timeout of 1000 ms (retry counter 1)
[0m
D (566192) wpa: resend eapol1[0m
D (566193) wpa: WPA: 00:57:c1:b8:92:3e WPA_PTK entering state PTKINITNEGOTIATING
[0m
D (566193) wpa: wpa_auth=0x3ffd115c sm=0x3ffd4a88 kdersc=0x3ffca204 kde=0x3ffd498c nounce=0x3ffd4ad4 kde_len=94 keyidx=1 encr=1 force=0
[0m
D (566205) wpa: WPA: Send EAPOL(version=2 secure=1 mic=1 ack=1 install=1 pairwise=8 kde_len=94 keyidx=1 encr=1)
[0m
D (566217) wpa: WPA: Use EAPOL-Key timeout of 1000 ms (retry counter 2)
[0m
D (567222) wpa: resend eapol1[0m
D (567223) wpa: WPA: 00:57:c1:b8:92:3e WPA_PTK entering state PTKINITNEGOTIATING
[0m
D (567223) wpa: wpa_auth=0x3ffd115c sm=0x3ffd4a88 kdersc=0x3ffca204 kde=0x3ffd498c nounce=0x3ffd4ad4 kde_len=94 keyidx=1 encr=1 force=0
[0m
D (567235) wpa: WPA: Send EAPOL(version=2 secure=1 mic=1 ack=1 install=1 pairwise=8 kde_len=94 keyidx=1 encr=1)
[0m
D (567247) wpa: WPA: Use EAPOL-Key timeout of 1000 ms (retry counter 3)
[0m
[0;32mI (567282) APP_MAIN: Checking SPI Loop Temp Read is 37.64 (149), result - 0 [0m
[0;32mI (567283) APP_MAIN: Finished app_main, sleeping loop 99 [0m
D (568252) wpa: resend eapol1[0m
D (568252) wpa: WPA: 00:57:c1:b8:92:3e WPA_PTK entering state PTKINITNEGOTIATING
[0m
D (568253) wpa: wpa_auth=0x3ffd115c sm=0x3ffd4a88 kdersc=0x3ffca204 kde=0x3ffd498c nounce=0x3ffd4ad4 kde_len=94 keyidx=1 encr=1 force=0
[0m
D (568264) wpa: WPA: Send EAPOL(version=2 secure=1 mic=1 ack=1 install=1 pairwise=8 kde_len=94 keyidx=1 encr=1)
[0m
D (568277) wpa: WPA: Use EAPOL-Key timeout of 1000 ms (retry counter 4)
[0m
D (569282) wpa: resend eapol1[0m
D (569282) wpa: WPA: 00:57:c1:b8:92:3e WPA_PTK entering state PTKINITNEGOTIATING
[0m
D (569283) wpa: WPA: 00:57:c1:b8:92:3e WPA_PTK entering state DISCONNECT