Bug 195957

Summary: iwlwifi set key failed
Product: Drivers Reporter: s.illes79 (s.illes79)
Component: network-wirelessAssignee: DO NOT USE - assign "network-wireless-intel" component instead (linuxwifi)
Status: CLOSED CODE_FIX    
Severity: normal    
Priority: P1    
Hardware: All   
OS: Linux   
Kernel Version: 4.12.0-041200rc3 Subsystem:
Regression: No Bisected commit-id:
Attachments: trace
syslog with wpa_supplicant log
dmesg memory allocation and set key

Description s.illes79@gmail.com 2017-06-01 12:40:50 UTC
Every minute or so I'm seeing this:

[14931.077058] iwlwifi 0000:3a:00.0: set key failed
[14994.853363] wlp58s0: deauthenticated from e4:f4:c6:13:3c:93 (Reason: 7=CLASS3_FRAME_FROM_NONASSOC_STA)
[14994.977921] wlp58s0: authenticate with e4:f4:c6:13:3c:93
[14994.985080] wlp58s0: send auth to e4:f4:c6:13:3c:93 (try 1/3)
[14994.988445] wlp58s0: authenticated
[14994.992494] wlp58s0: associate with e4:f4:c6:13:3c:93 (try 1/3)
[14994.996490] wlp58s0: RX AssocResp from e4:f4:c6:13:3c:93 (capab=0x1431 status=0 aid=2)
[14994.999416] wlp58s0: associated
[14995.026491] iwlwifi 0000:3a:00.0: set key failed
[15219.631460] wlp58s0: deauthenticated from e4:f4:c6:13:3c:93 (Reason: 7=CLASS3_FRAME_FROM_NONASSOC_STA)
[15219.777152] wlp58s0: authenticate with e4:f4:c6:13:3c:93
[15219.784196] wlp58s0: send auth to e4:f4:c6:13:3c:93 (try 1/3)
[15219.792805] wlp58s0: authenticated
[15219.799962] wlp58s0: associate with e4:f4:c6:13:3c:93 (try 1/3)
[15219.803915] wlp58s0: RX AssocResp from e4:f4:c6:13:3c:93 (capab=0x1431 status=0 aid=2)
[15219.805735] wlp58s0: associated
[15219.830893] iwlwifi 0000:3a:00.0: set key failed


Hardware: Dell XPS 13 9350 Developer edition with BIOS 1.4.17 
I'm using latest FW:
[ 8.983191] iwlwifi 0000:3a:00.0: loaded firmware version 27.526902.0 op_mode iwlmvm

wifi is a bit instable: 
--- 192.168.1.1 ping statistics ---
7806 packets transmitted, 7652 received, 1% packet loss, time 7839167ms
rtt min/avg/max/mdev = 0.474/7.725/646.802/23.187 ms

was all good until I upgraded the BIOS, than I had Firmware errors(https://bugzilla.kernel.org/show_bug.cgi?id=195945), than updated firmware, now WIFI is not stable
Comment 1 Emmanuel Grumbach 2017-06-01 17:47:17 UTC
Please record tracing of this. Thank you.
Comment 2 s.illes79@gmail.com 2017-06-01 21:24:44 UTC
I'm happy to do that, if u tell me how, no idea how to do it :)
Comment 3 Emmanuel Grumbach 2017-06-02 05:14:01 UTC
Sorry, you'll find instructions here https://wireless.wiki.kernel.org/en/users/drivers/iwlwifi/debugging#tracing
Comment 4 s.illes79@gmail.com 2017-06-02 17:14:01 UTC
Created attachment 256837 [details]
trace

finally started to happen again.
Comment 5 Emmanuel Grumbach 2017-06-06 11:00:12 UTC
Very strange. I need to look at this a bit more. Can you please add the following switches in the recording:

-e iwlwifi -e mac80211 -e cfg80211 -e iwlwifi_msg

thanks.
Comment 6 Emmanuel Grumbach 2017-06-06 20:38:54 UTC
You seem to have PTK rekeying...
Can you please send the logs of the wpa_supplicant? They are typically in syslog. Setting more debug level to the wpa_supplicant can be nice. Not sure how to do that in ARCH though.
Comment 7 s.illes79@gmail.com 2017-06-07 08:07:02 UTC
Created attachment 256899 [details]
syslog with wpa_supplicant log

this is an old log, I'm still waiting for the set key to start failing
Comment 8 s.illes79@gmail.com 2017-06-07 12:30:06 UTC
trace with more flags:
https://drive.google.com/open?id=0B0vWxunrQlxuMWVMRlduak1TeWM

Started to happen after a memory allocation issue, see attached logs
Comment 9 s.illes79@gmail.com 2017-06-07 12:31:05 UTC
Created attachment 256905 [details]
dmesg memory allocation and set key
Comment 10 s.illes79@gmail.com 2017-06-07 14:44:22 UTC
under Linux XPS13 4.10.0-22-generic #24-Ubuntu
I'm getting this:

[Jun 7 15:28] wlp58s0: deauthenticated from e4:f4:c6:13:3c:93 (Reason: 7=CLASS3_FRAME_FROM_NONASSOC_STA)
[  +0.165290] wlp58s0: authenticate with e4:f4:c6:13:3c:93
[  +0.006880] wlp58s0: send auth to e4:f4:c6:13:3c:93 (try 1/3)
[  +0.002029] wlp58s0: authenticated
[  +0.000909] wlp58s0: associate with e4:f4:c6:13:3c:93 (try 1/3)
[  +0.003907] wlp58s0: RX AssocResp from e4:f4:c6:13:3c:93 (capab=0x1431 status=0 aid=4)
[  +0.001369] wlp58s0: associated
[Jun 7 15:29] wlp58s0: deauthenticated from e4:f4:c6:13:3c:93 (Reason: 7=CLASS3_FRAME_FROM_NONASSOC_STA)
[  +0.128125] wlp58s0: authenticate with e4:f4:c6:13:3c:93
[  +0.006944] wlp58s0: send auth to e4:f4:c6:13:3c:93 (try 1/3)
[  +0.002091] wlp58s0: authenticated
[  +0.001685] wlp58s0: associate with e4:f4:c6:13:3c:93 (try 1/3)
[  +0.003933] wlp58s0: RX AssocResp from e4:f4:c6:13:3c:93 (capab=0x1431 status=0 aid=4)
[  +0.001470] wlp58s0: associated
[Jun 7 15:39] wlp58s0: deauthenticated from e4:f4:c6:13:3c:93 (Reason: 7=CLASS3_FRAME_FROM_NONASSOC_STA)
[  +3.757755] wlp58s0: authenticate with e4:f4:c6:13:3c:93
[  +0.007013] wlp58s0: send auth to e4:f4:c6:13:3c:93 (try 1/3)
[  +0.001906] wlp58s0: authenticated
[  +0.002705] wlp58s0: associate with e4:f4:c6:13:3c:93 (try 1/3)
[  +0.004879] wlp58s0: RX AssocResp from e4:f4:c6:13:3c:93 (capab=0x1431 status=0 aid=4)
[  +0.002005] wlp58s0: associated

no Set key error, but WIFI is a bit iffy :D
Comment 11 Emmanuel Grumbach 2017-06-07 15:24:54 UTC
Before we continue, please apply this:

https://git.kernel.org/pub/scm/linux/kernel/git/iwlwifi/iwlwifi-fixes.git/commit/?id=71793b7d3eb0897ca0dc4a109ede608d3e2c70ed

Let's see how it feels afterwards.

Thanks.
Comment 12 Emmanuel Grumbach 2017-06-07 15:25:25 UTC
This will not fix your disconnections, but it will make the kernel log cleaner and this is a needed fix anyway.
Comment 13 Emmanuel Grumbach 2017-06-14 14:14:45 UTC
Ping? :)
Comment 14 s.illes79@gmail.com 2017-06-14 14:26:13 UTC
sorry not yet had time to compile and patch my own kernel , has this been merged to rc4?
Comment 15 Emmanuel Grumbach 2017-06-14 16:19:30 UTC
nope - it is in 4.12-rc5.
Comment 16 s.illes79@gmail.com 2017-06-16 15:15:50 UTC
I haven't seen this set key failed with -rc5 

it's using 
9.041248] iwlwifi 0000:3a:00.0: loaded firmware version 27.455470.0 op_mode iwlmvm

instead of 26

WIFI seems stable as well ...
Comment 17 Emmanuel Grumbach 2017-06-17 18:27:22 UTC
Thanks. Closing the bug.