Bug 203663

Summary: iwlwifi: 8265: Got an HT rate for a non data frame | flags:0x88/mcs:4 (drivers/net/wireless/intel/iwlwifi/mvm/tx.c:336 iwl_mvm_get_tx_rate)
Product: Drivers Reporter: lukas.redlinger
Component: network-wireless-intelAssignee: DO NOT USE - assign "network-wireless-intel" component instead (linuxwifi)
Status: REOPENED ---    
Severity: normal CC: leho, n.fabritius, nsprea, public, thomas.natschlaeger
Priority: P1    
Hardware: All   
OS: Linux   
Kernel Version: 5.2.11 Subsystem:
Regression: No Bisected commit-id:
Attachments: dmesg
iw scan result
dmesg

Description lukas.redlinger 2019-05-21 14:34:32 UTC 
Created attachment 282883 [details]
dmesg

Hi!

Linux 5.1.2-arch1-1-ARCH x86_64.
iwlwifi-8265-36.ucode

Intel 8265 connected to a EAP/PEAP 5GHz network.
20 MHz channels. 130Mbit/s @ MCS 15

Problem can be solved by enabling "swcrypt" flag in module options!

Additionally spurious dmesg entries like those (first one led me to try swcrypt):

> iwlwifi 0000:01:00.0: expected hw-decrypted unicast frame for station
> iwlwifi 0000:01:00.0: Unhandled alg: 0x707
> iwlwifi 0000:01:00.0: Unhandled alg: 0x71b

> Rekeying PTK for STA 08:cc:68:xx:xx:xx but driver can't safely do that.



I don't know too much about the wifi infrastructure, but MAC addresses indicate Cisco stuff. I guess some early [draft] 802.11n infrastructure.
Comment 1 lukas.redlinger 2019-05-21 14:35:09 UTC 
Created attachment 282885 [details]
iw scan result
Comment 2 lukas.redlinger 2019-05-22 06:53:49 UTC 
I got information about the infrastructure from the person who runs it:

> AIR-CT5508-K9 (WLC Controller)

> AIR-CAP2702E-E-K9 (AccessPoint type)
> AIR-AP2802E-E-K9  (AccessPoint type)
> AIR-CAP2602E-E-K9 (AccessPoint type)

 
> firmware 8.3.133.3
Comment 3 Johannes Berg 2019-05-22 07:33:57 UTC 
Interesting.

Regardless of the driver but, I'd be interested in what happens if you apply these patches to your kernel:

https://patchwork.kernel.org/project/linux-wireless/list/?series=114335

I wonder if then the AP will use extended key ID or not.
Comment 4 Johannes Berg 2019-05-22 07:35:31 UTC 
(In reply to Johannes Berg from comment #3)
> Regardless of the driver but

Driver *bug*, that is.
Comment 5 lukas.redlinger 2019-05-23 06:30:46 UTC 
So this issue needs to be closed "won't fix", as driver development for the Intel 8265 seems to be discontinued?! 

https://wireless.wiki.kernel.org/en/users/drivers/iwlwifi/core_release
>> Core 33: "Last version for 8260 and 8265"
Comment 6 Emmanuel Grumbach 2019-05-23 06:32:12 UTC 
This is the firmware ... not the driver...
Comment 7 Emmanuel Grumbach 2019-06-13 07:24:39 UTC 
I am closing this bug. We've been waiting for your input with the patches in comment 3 for close to 1 month.
Comment 8 lukas.redlinger 2019-06-17 10:29:27 UTC 
Created attachment 283297 [details]
dmesg
Comment 9 lukas.redlinger 2019-06-17 10:33:35 UTC 
Whilst tracing for the other bug (https://bugzilla.kernel.org/show_bug.cgi?id=203805) THIS one occured ("no ht rate...") and I had the trace running.

Trace can be found here:
https://drive.google.com/open?id=1_EpoDgkJQkJcXq7MNz3Pu-pAH84ZqB-h

dmesg already updated.

forcing the card to roam between 2.4 and 5 GHz seemd to cause the trouble.

scan_results for the SSID:
>f2:9f:c2:f8:xx:xx      5220    -65     [WPA2-PSK+FT/PSK-CCMP][ESS]     AGuest
>f2:9f:c2:f7:xx:xx      2437    -59     [WPA2-PSK+FT/PSK-CCMP][ESS]     AGuest
>f2:9f:c2:f8:xx:xx      5220    -79     [WPA2-PSK+FT/PSK-CCMP][ESS]     AGuest
>f2:9f:c2:f7:xx:xx      2462    -74     [WPA2-PSK+FT/PSK-CCMP][ESS]     AGuest
>f2:9f:c2:f7:xx:xx      2462    -80     [WPA2-PSK+FT/PSK-CCMP][ESS]     AGuest
Comment 10 lukas.redlinger 2019-09-20 07:37:01 UTC 
Still present in linux 5.3.0-arch1-1-ARCH with an Intel 8265 running firmware 36.77d01142.0.

No proof for this, but it seems as if that one would only happen once. Some minutes/hours later we run into this: https://bugzilla.kernel.org/show_bug.cgi?id=203805
Not sure if there is any connection - so don't take this assumption too serious!


[Fri Sep 20 09:20:32 2019] ------------[ cut here ]------------
[Fri Sep 20 09:20:32 2019] Got an HT rate (flags:0x8/mcs:7) for a non data frame
[Fri Sep 20 09:20:32 2019] WARNING: CPU: 3 PID: 30720 at drivers/net/wireless/intel/iwlwifi/mvm/tx.c:333 iwl_mvm_get_tx_rate.isra.0+0xb9/0xd0 [iwlmvm]
[Fri Sep 20 09:20:32 2019] Modules linked in: 8021q garp mrp stp llc ccm snd_hda_codec_hdmi snd_hda_codec_realtek snd_hda_codec_generic ledtrig_audio xt_state xt_conntrack nf_conntrack nf_defrag_ipv6 nf_defrag_ipv4 libcrc32c xt_tcpudp iptable_filter sof_pci_dev snd_sof_intel_hda_common snd_sof_intel_hda snd_sof_intel_byt snd_sof_intel_ipc snd_sof snd_sof_xtensa_dsp snd_soc_skl intel_rapl_msr intel_rapl_common snd_soc_hdac_hda snd_hda_ext_core snd_soc_skl_ipc snd_soc_sst_ipc snd_soc_sst_dsp snd_soc_acpi_intel_match snd_soc_acpi snd_soc_core snd_compress ac97_bus x86_pkg_temp_thermal iTCO_wdt intel_powerclamp mei_hdcp mei_wdt iTCO_vendor_support snd_pcm_dmaengine iwlmvm wmi_bmof coretemp snd_hda_intel kvm_intel mac80211 snd_hda_codec snd_hda_core kvm libarc4 snd_hwdep irqbypass snd_pcm intel_cstate intel_uncore intel_rapl_perf iwlwifi snd_timer nls_iso8859_1 nls_cp437 snd vfat i915 psmouse pcspkr cfg80211 fat e1000e i2c_i801 soundcore igb drm_kms_helper btusb btrtl btbcm btintel i2c_algo_bit dca drm
[Fri Sep 20 09:20:32 2019]  bluetooth mei_me intel_lpss_pci intel_lpss mei qmi_wwan cdc_wdm intel_gtt usbnet agpgart ecdh_generic syscopyarea rfkill mii sysfillrect qcserial sysimgblt usb_wwan input_leds ecc fb_sys_fops intel_pch_thermal wmi evdev mac_hid acpi_tad ip_tables x_tables ext4 crc32c_generic crc16 mbcache jbd2 hid_generic usbhid hid dm_crypt dm_mod sd_mod crct10dif_pclmul crc32_pclmul crc32c_intel ghash_clmulni_intel serio_raw ahci atkbd libps2 libahci aesni_intel libata aes_x86_64 sdhci_pci crypto_simd cqhci xhci_pci sdhci cryptd glue_helper xhci_hcd scsi_mod mmc_core i8042 serio
[Fri Sep 20 09:20:32 2019] CPU: 3 PID: 30720 Comm: sshd Not tainted 5.3.0-arch1-1-ARCH #1
[Fri Sep 20 09:20:32 2019] Hardware name: MSI MS-98L3/MS-98L3, BIOS V1.0b10 08/08/2019
[Fri Sep 20 09:20:32 2019] RIP: 0010:iwl_mvm_get_tx_rate.isra.0+0xb9/0xd0 [iwlmvm]
[Fri Sep 20 09:20:32 2019] Code: 5d 41 5c c1 e2 09 41 5d 09 d0 c3 80 3d fa e3 04 00 00 75 88 0f b7 f1 48 c7 c7 e0 e4 2f c1 c6 05 e7 e3 04 00 01 e8 86 57 fc d7 <0f> 0b 0f be 55 08 89 d3 e9 65 ff ff ff 0f be db eb a8 0f 1f 44 00
[Fri Sep 20 09:20:32 2019] RSP: 0018:ffffb242457cb5d8 EFLAGS: 00010286
[Fri Sep 20 09:20:32 2019] RAX: 0000000000000000 RBX: 0000000000000007 RCX: 0000000000000000
[Fri Sep 20 09:20:32 2019] RDX: 0000000000000402 RSI: 0000000000000086 RDI: 00000000ffffffff
[Fri Sep 20 09:20:32 2019] RBP: ffffb242457cb740 R08: 000000000000042e R09: 0000000000000001
[Fri Sep 20 09:20:32 2019] R10: 0000000000000000 R11: 0000000000000001 R12: ffff98d662fbc898
[Fri Sep 20 09:20:32 2019] R13: ffff98d662a32bd0 R14: ffffb242457cb740 R15: 0000000000004188
[Fri Sep 20 09:20:32 2019] FS:  00007f93db3dc740(0000) GS:ffff98d667ac0000(0000) knlGS:0000000000000000
[Fri Sep 20 09:20:32 2019] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[Fri Sep 20 09:20:32 2019] CR2: 00007f726c600000 CR3: 0000000161c66006 CR4: 00000000003606e0
[Fri Sep 20 09:20:32 2019] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
[Fri Sep 20 09:20:32 2019] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
[Fri Sep 20 09:20:32 2019] Call Trace:
[Fri Sep 20 09:20:32 2019]  iwl_mvm_set_tx_cmd_rate+0x66/0xc0 [iwlmvm]
[Fri Sep 20 09:20:32 2019]  iwl_mvm_set_tx_params+0x33a/0x500 [iwlmvm]
[Fri Sep 20 09:20:32 2019]  iwl_mvm_tx_mpdu+0x97/0x5b0 [iwlmvm]
[Fri Sep 20 09:20:32 2019]  ? ieee80211_tx_dequeue+0x472/0xb50 [mac80211]
[Fri Sep 20 09:20:32 2019]  iwl_mvm_tx_skb+0x1aa/0x450 [iwlmvm]
[Fri Sep 20 09:20:32 2019]  iwl_mvm_mac_itxq_xmit+0x88/0x100 [iwlmvm]
[Fri Sep 20 09:20:32 2019]  ieee80211_queue_skb+0x2b4/0x440 [mac80211]
[Fri Sep 20 09:20:32 2019]  __ieee80211_subif_start_xmit+0xb53/0xcf0 [mac80211]
[Fri Sep 20 09:20:32 2019]  ? _raw_spin_unlock_irq+0x1d/0x30
[Fri Sep 20 09:20:32 2019]  ? __switch_to+0x113/0x460
[Fri Sep 20 09:20:32 2019]  ? __switch_to_asm+0x34/0x70
[Fri Sep 20 09:20:32 2019]  ieee80211_subif_start_xmit+0x60/0x2f0 [mac80211]
[Fri Sep 20 09:20:32 2019]  dev_hard_start_xmit+0x99/0x220
[Fri Sep 20 09:20:32 2019]  __dev_queue_xmit+0x9c0/0xa90
[Fri Sep 20 09:20:32 2019]  ? preempt_count_add+0x49/0xa0
[Fri Sep 20 09:20:32 2019]  ip_finish_output2+0x240/0x5c0
[Fri Sep 20 09:20:32 2019]  ip_output+0x94/0x130
[Fri Sep 20 09:20:32 2019]  ? __ip_finish_output+0x1b0/0x1b0
[Fri Sep 20 09:20:32 2019]  __ip_queue_xmit+0x172/0x430
[Fri Sep 20 09:20:32 2019]  __tcp_transmit_skb+0x5c7/0xb00
[Fri Sep 20 09:20:32 2019]  tcp_write_xmit+0x387/0x1160
[Fri Sep 20 09:20:32 2019]  __tcp_push_pending_frames+0x32/0xf0
[Fri Sep 20 09:20:32 2019]  tcp_sendmsg_locked+0xc30/0xd30
[Fri Sep 20 09:20:32 2019]  tcp_sendmsg+0x28/0x40
[Fri Sep 20 09:20:32 2019]  sock_sendmsg+0x57/0x60
[Fri Sep 20 09:20:32 2019]  sock_write_iter+0x8f/0xf0
[Fri Sep 20 09:20:32 2019]  new_sync_write+0x161/0x200
[Fri Sep 20 09:20:32 2019]  vfs_write+0xac/0x1b0
[Fri Sep 20 09:20:32 2019]  ksys_write+0x67/0xe0
[Fri Sep 20 09:20:32 2019]  do_syscall_64+0x5f/0x1c0
[Fri Sep 20 09:20:32 2019]  entry_SYSCALL_64_after_hwframe+0x44/0xa9
[Fri Sep 20 09:20:32 2019] RIP: 0033:0x7f93db5611f8
[Fri Sep 20 09:20:32 2019] Code: 89 02 48 c7 c0 ff ff ff ff eb bb 0f 1f 80 00 00 00 00 f3 0f 1e fa 48 8d 05 25 50 0d 00 8b 00 85 c0 75 17 b8 01 00 00 00 0f 05 <48> 3d 00 f0 ff ff 77 60 c3 0f 1f 80 00 00 00 00 48 83 ec 28 48 89
[Fri Sep 20 09:20:32 2019] RSP: 002b:00007ffcae66f558 EFLAGS: 00000246 ORIG_RAX: 0000000000000001
[Fri Sep 20 09:20:32 2019] RAX: ffffffffffffffda RBX: 000000000000002c RCX: 00007f93db5611f8
[Fri Sep 20 09:20:32 2019] RDX: 000000000000002c RSI: 000055e506e90e20 RDI: 0000000000000004
[Fri Sep 20 09:20:32 2019] RBP: 000055e506e6df70 R08: 00007ffcae68a0f0 R09: 00007ffcae66f4e8
[Fri Sep 20 09:20:32 2019] R10: 00007ffcae66f4e0 R11: 0000000000000246 R12: 0000000000000000
[Fri Sep 20 09:20:32 2019] R13: 000055e504e74680 R14: 0000000000000004 R15: 0000000000000004
[Fri Sep 20 09:20:32 2019] ---[ end trace d33be743d87572a9 ]---
Comment 11 lukas.redlinger 2019-10-22 13:20:39 UTC 
Looks a little different in 5.2.11 (__ieee80211_rx_h_amsdu)

Seems to happen while/before roaming as "disconnected for new auth" happens within the same second.


[Tue Oct 22 15:10:12 2019] ------------[ cut here ]------------
[Tue Oct 22 15:10:12 2019] Got an HT rate (flags:0x88/mcs:14) for a non data frame
[Tue Oct 22 15:10:12 2019] WARNING: CPU: 0 PID: 512 at drivers/net/wireless/intel/iwlwifi/mvm/tx.c:333 iwl_mvm_get_tx_rate.isra.0+0xb9/0xd0 [iwlmvm]
[Tue Oct 22 15:10:12 2019] Modules linked in: ccm xt_state xt_conntrack nf_conntrack nf_defrag_ipv6 nf_defrag_ipv4 libcrc32c xt_tcpudp iptable_filter snd_hda_codec_hdmi snd_hda_codec_realtek snd_hda_codec_generic ledtrig_audio i915 snd_soc_skl snd_soc_hdac_hda rtl8xxxu snd_hda_ext_core snd_soc_skl_ipc intel_rapl snd_soc_sst_ipc snd_soc_sst_dsp rtl8192cu snd_soc_acpi_intel_match snd_soc_acpi arc4 snd_soc_core snd_compress rtl_usb x86_pkg_temp_thermal ac97_bus intel_powerclamp snd_pcm_dmaengine coretemp rtl8192c_common snd_hda_intel kvm_intel rtlwifi iwlmvm btusb btrtl drm_kms_helper snd_hda_codec btbcm uvcvideo btintel kvm snd_hda_core nls_iso8859_1 videobuf2_vmalloc videobuf2_memops nls_cp437 irqbypass videobuf2_v4l2 snd_hwdep vfat mac80211 videobuf2_common bluetooth drm 8250_dw snd_pcm iwlwifi fat intel_cstate iTCO_wdt mei_wdt mei_hdcp intel_wmi_thunderbolt videodev intel_uncore iTCO_vendor_support intel_gtt cfg80211 ecdh_generic snd_timer qmi_wwan agpgart idma64 intel_rapl_perf pcspkr igb e1000e
[Tue Oct 22 15:10:12 2019]  ftdi_sio cdc_wdm media ecc intel_xhci_usb_role_switch syscopyarea usbnet tpm_crb intel_lpss_pci snd sysfillrect qcserial mii usb_wwan roles i2c_algo_bit dca sysimgblt fb_sys_fops tpm_tis mei_me intel_lpss wmi intel_pch_thermal mei soundcore rfkill i2c_i801 tpm_tis_core pcc_cpufreq evdev tpm mac_hid rng_core ip_tables x_tables ext4 crc32c_generic crc16 mbcache jbd2 algif_skcipher af_alg dm_crypt dm_mod sd_mod crct10dif_pclmul crc32_pclmul crc32c_intel ghash_clmulni_intel aesni_intel ahci libahci xhci_pci libata aes_x86_64 crypto_simd cryptd glue_helper scsi_mod xhci_hcd
[Tue Oct 22 15:10:12 2019] CPU: 0 PID: 512 Comm: irq/135-iwlwifi Not tainted 5.2.11-arch1-1-ARCH #1
[Tue Oct 22 15:10:12 2019] Hardware name: CINCOZE DI-1000/DI-1000, BIOS 5.11 02/20/2019
[Tue Oct 22 15:10:12 2019] RIP: 0010:iwl_mvm_get_tx_rate.isra.0+0xb9/0xd0 [iwlmvm]
[Tue Oct 22 15:10:12 2019] Code: 5d 41 5c c1 e2 09 41 5d 09 d0 c3 80 3d d6 d7 04 00 00 75 88 0f b7 f1 48 c7 c7 40 84 ff c0 c6 05 c3 d7 04 00 01 e8 76 9b cc e2 <0f> 0b 0f be 55 08 89 d3 e9 65 ff ff ff 0f be db eb a8 0f 1f 44 00
[Tue Oct 22 15:10:12 2019] RSP: 0018:ffffa22a4165f0f8 EFLAGS: 00010282
[Tue Oct 22 15:10:12 2019] RAX: 0000000000000000 RBX: 000000000000000e RCX: 0000000000000000
[Tue Oct 22 15:10:12 2019] RDX: 0000000000000007 RSI: 0000000000000082 RDI: 00000000ffffffff
[Tue Oct 22 15:10:12 2019] RBP: ffffa22a4165f260 R08: 0000000000000450 R09: 0000000000000001
[Tue Oct 22 15:10:12 2019] R10: 0000000000000000 R11: 0000000000000001 R12: ffff94b15ecbc898
[Tue Oct 22 15:10:12 2019] R13: ffff94b1622a23d0 R14: ffffa22a4165f260 R15: 0000000000004188
[Tue Oct 22 15:10:12 2019] FS:  0000000000000000(0000) GS:ffff94b165a00000(0000) knlGS:0000000000000000
[Tue Oct 22 15:10:12 2019] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[Tue Oct 22 15:10:12 2019] CR2: 00007f43d18986c0 CR3: 000000017d00a004 CR4: 00000000003606f0
[Tue Oct 22 15:10:12 2019] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
[Tue Oct 22 15:10:12 2019] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
[Tue Oct 22 15:10:12 2019] Call Trace:
[Tue Oct 22 15:10:12 2019]  iwl_mvm_set_tx_cmd_rate+0x66/0xc0 [iwlmvm]
[Tue Oct 22 15:10:12 2019]  iwl_mvm_set_tx_params+0x33a/0x500 [iwlmvm]
[Tue Oct 22 15:10:12 2019]  ? invoke_tx_handlers_late+0x7b2/0x880 [mac80211]
[Tue Oct 22 15:10:12 2019]  iwl_mvm_tx_mpdu+0x97/0x5b0 [iwlmvm]
[Tue Oct 22 15:10:12 2019]  ? ieee80211_tx_dequeue+0x9c1/0xb50 [mac80211]
[Tue Oct 22 15:10:12 2019]  iwl_mvm_tx_skb+0x1b9/0x480 [iwlmvm]
[Tue Oct 22 15:10:12 2019]  iwl_mvm_mac_itxq_xmit+0x88/0x100 [iwlmvm]
[Tue Oct 22 15:10:12 2019]  ieee80211_queue_skb+0x2b4/0x440 [mac80211]
[Tue Oct 22 15:10:12 2019]  ieee80211_tx+0xef/0x150 [mac80211]
[Tue Oct 22 15:10:12 2019]  __ieee80211_subif_start_xmit+0x82c/0xcf0 [mac80211]
[Tue Oct 22 15:10:12 2019]  ? sched_clock_cpu+0x10/0xd0
[Tue Oct 22 15:10:12 2019]  ? cpumask_next+0x16/0x20
[Tue Oct 22 15:10:12 2019]  ? enqueue_entity+0x3da/0xbf0
[Tue Oct 22 15:10:12 2019]  ? sched_clock+0x5/0x10
[Tue Oct 22 15:10:12 2019]  ? enqueue_task_fair+0xbc/0x920
[Tue Oct 22 15:10:12 2019]  ? psi_task_change+0xe1/0x320
[Tue Oct 22 15:10:12 2019]  ieee80211_subif_start_xmit+0x60/0x2f0 [mac80211]
[Tue Oct 22 15:10:12 2019]  dev_hard_start_xmit+0x99/0x220
[Tue Oct 22 15:10:12 2019]  __dev_queue_xmit+0x9c0/0xa90
[Tue Oct 22 15:10:12 2019]  ? __cgroup_bpf_run_filter_skb+0x187/0x2c0
[Tue Oct 22 15:10:12 2019]  ip_finish_output2+0x240/0x5c0
[Tue Oct 22 15:10:12 2019]  ip_output+0x94/0x130
[Tue Oct 22 15:10:12 2019]  ? ip_fragment.constprop.0+0x90/0x90
[Tue Oct 22 15:10:12 2019]  __ip_queue_xmit+0x172/0x430
[Tue Oct 22 15:10:12 2019]  __tcp_transmit_skb+0x5bf/0xae0
[Tue Oct 22 15:10:12 2019]  tcp_rcv_established+0x56f/0x640
[Tue Oct 22 15:10:12 2019]  tcp_v4_do_rcv+0x13c/0x1e0
[Tue Oct 22 15:10:12 2019]  tcp_v4_rcv+0xc65/0xe20
[Tue Oct 22 15:10:12 2019]  ip_protocol_deliver_rcu+0x2b/0x200
[Tue Oct 22 15:10:12 2019]  ip_local_deliver_finish+0x55/0x70
[Tue Oct 22 15:10:12 2019]  ip_local_deliver+0x7b/0x140
[Tue Oct 22 15:10:12 2019]  ? ip_protocol_deliver_rcu+0x200/0x200
[Tue Oct 22 15:10:12 2019]  ip_rcv+0x7c/0x130
[Tue Oct 22 15:10:12 2019]  ? ip_rcv_finish_core.isra.0+0x3f0/0x3f0
[Tue Oct 22 15:10:12 2019]  __netif_receive_skb_one_core+0x80/0x90
[Tue Oct 22 15:10:12 2019]  netif_receive_skb_internal+0x93/0xe0
[Tue Oct 22 15:10:12 2019]  napi_gro_receive+0x114/0x170
[Tue Oct 22 15:10:12 2019]  ieee80211_deliver_skb+0xfb/0x260 [mac80211]
[Tue Oct 22 15:10:12 2019]  __ieee80211_rx_h_amsdu+0x17f/0x210 [mac80211]
[Tue Oct 22 15:10:12 2019]  ieee80211_rx_handlers+0x72c/0x2a80 [mac80211]
[Tue Oct 22 15:10:12 2019]  ? iwl_mvm_rs_tx_status+0xd94/0x2270 [iwlmvm]
[Tue Oct 22 15:10:12 2019]  ieee80211_prepare_and_rx_handle+0x974/0x1230 [mac80211]
[Tue Oct 22 15:10:12 2019]  ieee80211_rx_napi+0x43f/0xa00 [mac80211]
[Tue Oct 22 15:10:12 2019]  iwl_mvm_rx_rx_mpdu+0x63f/0xbd0 [iwlmvm]
[Tue Oct 22 15:10:12 2019]  iwl_pcie_rx_handle+0x3c1/0xa30 [iwlwifi]
[Tue Oct 22 15:10:12 2019]  iwl_pcie_irq_handler+0x6a6/0xc00 [iwlwifi]
[Tue Oct 22 15:10:12 2019]  ? irq_forced_thread_fn+0x80/0x80
[Tue Oct 22 15:10:12 2019]  irq_thread_fn+0x20/0x60
[Tue Oct 22 15:10:12 2019]  irq_thread+0xee/0x180
[Tue Oct 22 15:10:12 2019]  ? wake_threads_waitq+0x30/0x30
[Tue Oct 22 15:10:12 2019]  kthread+0xfb/0x130
[Tue Oct 22 15:10:12 2019]  ? irq_thread_dtor+0x90/0x90
[Tue Oct 22 15:10:12 2019]  ? kthread_park+0x80/0x80
[Tue Oct 22 15:10:12 2019]  ret_from_fork+0x35/0x40
[Tue Oct 22 15:10:12 2019] ---[ end trace 54eec023e1a5b42b ]---
[Tue Oct 22 15:10:12 2019] wlp1s0: disconnect from AP dc:8c:37:fc:e8:4f for new auth to dc:8c:37:fd:19:cf
[Tue Oct 22 15:10:12 2019] wlp1s0: authenticate with dc:8c:37:fd:19:cf
[Tue Oct 22 15:10:12 2019] wlp1s0: send auth to dc:8c:37:fd:19:cf (try 1/3)
[Tue Oct 22 15:10:12 2019] wlp1s0: authenticated
[Tue Oct 22 15:10:12 2019] wlp1s0: associate with dc:8c:37:fd:19:cf (try 1/3)
[Tue Oct 22 15:10:12 2019] wlp1s0: RX ReassocResp from dc:8c:37:fd:19:cf (capab=0x111 status=0 aid=1)
[Tue Oct 22 15:10:12 2019] wlp1s0: associated


Let me know if comment #3 is still needed or anything else that would help.
Comment 12 Thomas Natschläger 2019-11-06 09:51:47 UTC 
Still happening with kernel 5.3.8-arch1-1 on different platform...

[Wed Nov  6 10:08:06 2019] ------------[ cut here ]------------
[Wed Nov  6 10:08:06 2019] Got an HT rate (flags:0x88/mcs:7) for a non data frame
[Wed Nov  6 10:08:06 2019] WARNING: CPU: 3 PID: 545 at drivers/net/wireless/intel/iwlwifi/mvm/tx.c:333 iwl_mvm_get_tx_rate.isra.0+0xb9/0xd0 [iwlmvm]
[Wed Nov  6 10:08:06 2019] Modules linked in: ccm 8021q garp mrp stp llc snd_hda_codec_hdmi snd_hda_codec_realtek snd_hda_codec_generic ledtrig_audio xt_state xt_conntrack nf_conntrack nf_defrag_ipv6 nf_defrag_ipv4 libcrc32c xt_tcpudp iptable_filter sof_pci_dev snd_sof_intel_hda_common snd_sof_intel_hda snd_sof_intel_byt snd_sof_intel_ipc snd_sof intel_rapl_msr snd_sof_xtensa_dsp intel_rapl_common snd_soc_skl snd_soc_hdac_hda snd_hda_ext_core snd_soc_skl_ipc snd_soc_sst_ipc snd_soc_sst_dsp snd_soc_acpi_intel_match snd_soc_acpi x86_pkg_temp_thermal snd_soc_core intel_powerclamp iTCO_wdt coretemp iTCO_vendor_support iwlmvm snd_compress wmi_bmof i915 kvm_intel ac97_bus mac80211 snd_pcm_dmaengine snd_hda_intel kvm libarc4 snd_hda_codec nls_iso8859_1 snd_hda_core nls_cp437 irqbypass intel_cstate intel_uncore vfat snd_hwdep fat iwlwifi intel_rapl_perf drm_kms_helper snd_pcm snd_timer psmouse pcspkr snd cfg80211 e1000e drm igb soundcore i2c_i801 i2c_algo_bit dca btusb qmi_wwan btrtl cdc_wdm btbcm intel_gtt
[Wed Nov  6 10:08:06 2019]  btintel agpgart usbnet qcserial bluetooth mii usb_wwan intel_lpss_pci syscopyarea ecdh_generic intel_lpss sysfillrect rfkill sysimgblt input_leds ecc fb_sys_fops intel_pch_thermal wmi evdev mac_hid acpi_tad ip_tables x_tables ext4 crc32c_generic crc16 mbcache jbd2 hid_generic usbhid hid dm_crypt dm_mod sd_mod crct10dif_pclmul crc32_pclmul crc32c_intel ghash_clmulni_intel aesni_intel serio_raw atkbd libps2 ahci libahci aes_x86_64 crypto_simd sdhci_pci libata cqhci cryptd xhci_pci glue_helper sdhci xhci_hcd scsi_mod mmc_core i8042 serio
[Wed Nov  6 10:08:06 2019] CPU: 3 PID: 545 Comm: irq/146-iwlwifi Not tainted 5.3.8-arch1-1 #1
[Wed Nov  6 10:08:06 2019] Hardware name: MSI MS-98L3/MS-98L3, BIOS V1.0b10 08/08/2019
[Wed Nov  6 10:08:06 2019] RIP: 0010:iwl_mvm_get_tx_rate.isra.0+0xb9/0xd0 [iwlmvm]
[Wed Nov  6 10:08:06 2019] Code: 5d 41 5c c1 e2 09 41 5d 09 d0 c3 80 3d ee e3 04 00 00 75 88 0f b7 f1 48 c7 c7 d0 a4 44 c1 c6 05 db e3 04 00 01 e8 b6 98 a7 e5 <0f> 0b 0f be 55 08 89 d3 e9 65 ff ff ff 0f be db eb a8 0f 1f 44 00
[Wed Nov  6 10:08:06 2019] RSP: 0018:ffffa3bb4078b058 EFLAGS: 00010282
[Wed Nov  6 10:08:06 2019] RAX: 0000000000000000 RBX: 0000000000000007 RCX: 0000000000000000
[Wed Nov  6 10:08:06 2019] RDX: 0000000000000a04 RSI: 0000000000000082 RDI: 00000000ffffffff
[Wed Nov  6 10:08:06 2019] RBP: ffffa3bb4078b1c0 R08: 000000000000073e R09: 0000000000000001
[Wed Nov  6 10:08:06 2019] R10: 0000000000000000 R11: 0000000000000001 R12: ffff9ddba1d14898
[Wed Nov  6 10:08:06 2019] R13: ffff9ddba135abd0 R14: ffffa3bb4078b1c0 R15: 0000000000004188
[Wed Nov  6 10:08:06 2019] FS:  0000000000000000(0000) GS:ffff9ddba3ac0000(0000) knlGS:0000000000000000
[Wed Nov  6 10:08:06 2019] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[Wed Nov  6 10:08:06 2019] CR2: 00007efdba8ee130 CR3: 0000000115c0a005 CR4: 00000000003606e0
[Wed Nov  6 10:08:06 2019] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
[Wed Nov  6 10:08:06 2019] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
[Wed Nov  6 10:08:06 2019] Call Trace:
[Wed Nov  6 10:08:06 2019]  iwl_mvm_set_tx_cmd_rate+0x66/0xc0 [iwlmvm]
[Wed Nov  6 10:08:06 2019]  iwl_mvm_set_tx_params+0x33a/0x500 [iwlmvm]
[Wed Nov  6 10:08:06 2019]  iwl_mvm_tx_mpdu+0x97/0x5b0 [iwlmvm]
[Wed Nov  6 10:08:06 2019]  ? ieee80211_tx_dequeue+0x472/0xb50 [mac80211]
[Wed Nov  6 10:08:06 2019]  iwl_mvm_tx_skb+0x1aa/0x450 [iwlmvm]
[Wed Nov  6 10:08:06 2019]  iwl_mvm_mac_itxq_xmit+0x88/0x100 [iwlmvm]
[Wed Nov  6 10:08:06 2019]  ieee80211_queue_skb+0x2b4/0x440 [mac80211]
[Wed Nov  6 10:08:06 2019]  __ieee80211_subif_start_xmit+0xb53/0xcf0 [mac80211]
[Wed Nov  6 10:08:06 2019]  ? ieee80211_queue_skb+0x2b4/0x440 [mac80211]
[Wed Nov  6 10:08:06 2019]  ieee80211_subif_start_xmit+0x60/0x2f0 [mac80211]
[Wed Nov  6 10:08:06 2019]  dev_hard_start_xmit+0x99/0x220
[Wed Nov  6 10:08:06 2019]  __dev_queue_xmit+0x9cb/0xa80
[Wed Nov  6 10:08:06 2019]  ? __cgroup_bpf_run_filter_skb+0x365/0x480
[Wed Nov  6 10:08:06 2019]  ip_finish_output2+0x243/0x5d0
[Wed Nov  6 10:08:06 2019]  ip_output+0x94/0x130
[Wed Nov  6 10:08:06 2019]  ? __ip_finish_output+0x1b0/0x1b0
[Wed Nov  6 10:08:06 2019]  tcp_v4_send_synack+0x7d/0xe0
[Wed Nov  6 10:08:06 2019]  tcp_conn_request+0xc16/0xd50
[Wed Nov  6 10:08:06 2019]  tcp_rcv_state_process+0x24a/0xf8e
[Wed Nov  6 10:08:06 2019]  ? sk_filter_trim_cap+0x11d/0x270
[Wed Nov  6 10:08:06 2019]  ? tcp_v4_inbound_md5_hash+0x56/0x170
[Wed Nov  6 10:08:06 2019]  tcp_v4_do_rcv+0xbc/0x1e0
[Wed Nov  6 10:08:06 2019]  tcp_v4_rcv+0xd7f/0xe20
[Wed Nov  6 10:08:06 2019]  ip_protocol_deliver_rcu+0x2b/0x200
[Wed Nov  6 10:08:06 2019]  ip_local_deliver_finish+0x55/0x70
[Wed Nov  6 10:08:06 2019]  ip_local_deliver+0x7b/0x140
[Wed Nov  6 10:08:06 2019]  ? ip_protocol_deliver_rcu+0x200/0x200
[Wed Nov  6 10:08:06 2019]  ip_rcv+0x7c/0x130
[Wed Nov  6 10:08:06 2019]  ? ip_rcv_finish_core.isra.0+0x3f0/0x3f0
[Wed Nov  6 10:08:06 2019]  __netif_receive_skb_one_core+0x80/0x90
[Wed Nov  6 10:08:06 2019]  netif_receive_skb_internal+0x93/0xe0
[Wed Nov  6 10:08:06 2019]  napi_gro_receive+0x114/0x170
[Wed Nov  6 10:08:06 2019]  ieee80211_deliver_skb+0xf8/0x260 [mac80211]
[Wed Nov  6 10:08:06 2019]  __ieee80211_rx_h_amsdu+0x17f/0x210 [mac80211]
[Wed Nov  6 10:08:06 2019]  ieee80211_rx_handlers+0x72c/0x2a80 [mac80211]
[Wed Nov  6 10:08:06 2019]  ieee80211_prepare_and_rx_handle+0x974/0x1230 [mac80211]
[Wed Nov  6 10:08:06 2019]  ieee80211_rx_napi+0x43f/0xa00 [mac80211]
[Wed Nov  6 10:08:06 2019]  iwl_mvm_rx_rx_mpdu+0x63f/0xbd0 [iwlmvm]
[Wed Nov  6 10:08:06 2019]  iwl_pcie_rx_handle+0x3c1/0xa30 [iwlwifi]
[Wed Nov  6 10:08:06 2019]  iwl_pcie_irq_handler+0x6a6/0xc00 [iwlwifi]
[Wed Nov  6 10:08:06 2019]  ? irq_forced_thread_fn+0x80/0x80
[Wed Nov  6 10:08:06 2019]  irq_thread_fn+0x20/0x60
[Wed Nov  6 10:08:06 2019]  irq_thread+0xee/0x180
[Wed Nov  6 10:08:06 2019]  ? wake_threads_waitq+0x30/0x30
[Wed Nov  6 10:08:06 2019]  kthread+0xfb/0x130
[Wed Nov  6 10:08:06 2019]  ? irq_thread_dtor+0x90/0x90
[Wed Nov  6 10:08:06 2019]  ? kthread_park+0x80/0x80
[Wed Nov  6 10:08:06 2019]  ret_from_fork+0x1f/0x40
[Wed Nov  6 10:08:06 2019] ---[ end trace 5f3929888622b99d ]---
Comment 13 Holger 2019-12-19 10:47:48 UTC 
Got hit by this issue. I'm not able to reproduce it at the moment.

had@es-notebook-acer:~$ uname -a
Linux es-notebook-acer 5.4.1-050401-generic #201911290555 SMP Fri Nov 29 11:03:47 UTC 2019 x86_64 x86_64 x86_64 GNU/Linux

# dmesg | grep iwlwifi

[   25.474430] kernel: iwlwifi 0000:00:0c.0: Found debug destination: EXTERNAL_DRAM
[   25.474433] kernel: iwlwifi 0000:00:0c.0: Found debug configuration: 0
[   25.475173] kernel: iwlwifi 0000:00:0c.0: loaded firmware version 46.6bf1df06.0 op_mode iwlmvm
[   25.532344] kernel: iwlwifi 0000:00:0c.0: Detected Intel(R) Dual Band Wireless AC 9560, REV=0x318
[   25.540620] kernel: iwlwifi 0000:00:0c.0: Applying debug destination EXTERNAL_DRAM
[   25.542674] kernel: iwlwifi 0000:00:0c.0: Allocated 0x00400000 bytes for firmware monitor.
[   25.591342] kernel: iwlwifi 0000:00:0c.0: base HW address: d0:c6:37:xx:xx:xx


Accesspoint: Fritzbox 7490, 07.19-74231 BETA

Log:
[199248.538213] ------------[ cut here ]------------
[199248.538216] Got an HT rate (flags:0xa8/mcs:14) for a non data frame
[199248.538278] WARNING: CPU: 1 PID: 677 at drivers/net/wireless/intel/iwlwifi/mvm/tx.c:333 iwl_mvm_get_tx_rate.isra.0+0xcd/0xe0 [iwlmvm]
[199248.538279] Modules linked in: sctp libcrc32c ccm rfcomm cmac bnep uvcvideo videobuf2_vmalloc videobuf2_memops videobuf2_v4l2 btusb videobuf2_common btrtl videodev btbcm mc btintel bluetooth ecdh_generic ecc nls_iso8859_1 snd_hda_codec_hdmi snd_sof_pci snd_sof_intel_hda_common snd_soc_hdac_hda snd_sof_intel_hda snd_sof_intel_byt snd_sof_intel_ipc snd_sof snd_sof_xtensa_dsp snd_hda_ext_core snd_soc_acpi_intel_match snd_soc_acpi snd_hda_codec_realtek snd_soc_core snd_hda_codec_generic ledtrig_audio snd_compress ac97_bus snd_pcm_dmaengine snd_hda_intel snd_intel_nhlt snd_hda_codec snd_hda_core snd_hwdep snd_pcm snd_seq_midi mei_hdcp snd_seq_midi_event intel_rapl_msr snd_rawmidi intel_telemetry_pltdrv intel_punit_ipc intel_telemetry_core intel_pmc_ipc x86_pkg_temp_thermal intel_powerclamp snd_seq coretemp kvm_intel kvm input_leds joydev irqbypass intel_cstate iwlmvm snd_seq_device mac80211 libarc4 serio_raw intel_rapl_perf snd_timer snd iwlwifi acer_wmi sparse_keymap hid_multitouch wmi_bmof
[199248.538312]  processor_thermal_device cfg80211 mei_me intel_rapl_common mei soundcore intel_soc_dts_iosf mac_hid int3400_thermal acer_wireless acpi_thermal_rel int3403_thermal int340x_thermal_zone sch_fq_codel parport_pc ppdev lp parport ip_tables x_tables autofs4 dm_crypt hid_generic crct10dif_pclmul crc32_pclmul ghash_clmulni_intel i915 aesni_intel i2c_algo_bit crypto_simd cryptd glue_helper drm_kms_helper syscopyarea sdhci_pci sysfillrect sysimgblt cqhci intel_lpss_pci fb_sys_fops sdhci intel_lpss drm i2c_i801 idma64 virt_dma ahci libahci i2c_hid hid wmi video pinctrl_geminilake pinctrl_intel
[199248.538342] CPU: 1 PID: 677 Comm: irq/129-iwlwifi Not tainted 5.4.1-050401-generic #201911290555
[199248.538343] Hardware name: Acer Swift SF114-32/Sapporo_GL, BIOS V1.09 04/03/2019
[199248.538351] RIP: 0010:iwl_mvm_get_tx_rate.isra.0+0xcd/0xe0 [iwlmvm]
[199248.538353] Code: c3 0f 0b 31 db eb d2 80 3d cc 99 03 00 00 0f 85 79 ff ff ff 0f b7 f1 48 c7 c7 00 4b 95 c0 c6 05 b5 99 03 00 01 e8 7e 93 17 e8 <0f> 0b 41 0f be 54 24 08 89 d3 e9 54 ff ff ff 0f 1f 40 00 0f 1f 44
[199248.538354] RSP: 0000:ffffa464005f7388 EFLAGS: 00010282
[199248.538356] RAX: 0000000000000000 RBX: 000000000000000e RCX: 0000000000000006
[199248.538356] RDX: 0000000000000007 RSI: 0000000000000082 RDI: ffff979337a97440
[199248.538357] RBP: ffffa464005f73a8 R08: 0000000000001831 R09: 0000000000000004
[199248.538358] R10: 0000000000000000 R11: 0000000000000001 R12: ffffa464005f7510
[199248.538359] R13: ffff97931e4708a8 R14: ffff97932ec72bf0 R15: ffffa464005f7510
[199248.538360] FS:  0000000000000000(0000) GS:ffff979337a80000(0000) knlGS:0000000000000000
[199248.538361] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[199248.538361] CR2: 00003d1c658f3000 CR3: 0000000267cf6000 CR4: 0000000000340ee0
[199248.538362] Call Trace:
[199248.538374]  iwl_mvm_set_tx_cmd_rate+0x71/0xd0 [iwlmvm]
[199248.538382]  iwl_mvm_set_tx_params+0x32e/0x4d0 [iwlmvm]
[199248.538390]  iwl_mvm_tx_mpdu+0x96/0x590 [iwlmvm]
[199248.538398]  iwl_mvm_tx_skb+0x195/0x460 [iwlmvm]
[199248.538405]  iwl_mvm_mac_itxq_xmit+0x7c/0xf0 [iwlmvm]
[199248.538412]  iwl_mvm_mac_wake_tx_queue+0x29/0x80 [iwlmvm]
[199248.538440]  ieee80211_queue_skb+0x2b8/0x420 [mac80211]
[199248.538459]  ieee80211_xmit_fast+0x309/0x4b0 [mac80211]
[199248.538478]  __ieee80211_subif_start_xmit+0xf5/0x260 [mac80211]
[199248.538496]  ieee80211_subif_start_xmit+0x48/0x2d0 [mac80211]
[199248.538501]  ? dev_queue_xmit_nit+0x267/0x280
[199248.538503]  dev_hard_start_xmit+0x91/0x1f0
[199248.538505]  __dev_queue_xmit+0x734/0x9d0
[199248.538507]  dev_queue_xmit+0x10/0x20
[199248.538510]  ip_finish_output2+0x2cb/0x590
[199248.538511]  __ip_finish_output+0xbf/0x1d0
[199248.538513]  ip_finish_output+0x2d/0xb0
[199248.538514]  ip_output+0x75/0xf0
[199248.538516]  ? __ip_finish_output+0x1d0/0x1d0
[199248.538517]  ip_local_out+0x3d/0x50
[199248.538519]  __ip_queue_xmit+0x17a/0x450
[199248.538523]  ? default_wake_function+0x12/0x20
[199248.538526]  ? __kmalloc_node_track_caller+0x1fc/0x310
[199248.538528]  ip_queue_xmit+0x10/0x20
[199248.538530]  __tcp_transmit_skb+0x537/0xae0
[199248.538532]  __tcp_send_ack.part.0+0xcb/0x120
[199248.538533]  tcp_send_ack+0x1c/0x20
[199248.538535]  __tcp_ack_snd_check+0x3c/0x1a0
[199248.538536]  tcp_rcv_established+0x592/0x680
[199248.538538]  tcp_v4_do_rcv+0x140/0x200
[199248.538540]  tcp_v4_rcv+0xbf1/0xd00
[199248.538542]  ip_protocol_deliver_rcu+0x30/0x1b0
[199248.538543]  ip_local_deliver_finish+0x48/0x50
[199248.538545]  ip_local_deliver+0x73/0xf0
[199248.538546]  ? tcp_v4_early_demux+0xfa/0x160
[199248.538548]  ? ip_rcv_finish_core.isra.0+0x11d/0x3c0
[199248.538549]  ip_rcv_finish+0x85/0xa0
[199248.538550]  ip_rcv+0xbc/0xd0
[199248.538552]  __netif_receive_skb_one_core+0x87/0xa0
[199248.538554]  __netif_receive_skb+0x18/0x60
[199248.538555]  netif_receive_skb_internal+0x45/0xc0
[199248.538557]  ? tcp4_gro_complete+0x84/0x90
[199248.538559]  napi_gro_complete+0x66/0xd0
[199248.538560]  napi_gro_flush+0x9b/0xf0
[199248.538572]  iwl_pcie_rx_handle+0x31b/0x460 [iwlwifi]
[199248.538575]  ? irq_finalize_oneshot.part.0+0xf0/0xf0
[199248.538582]  iwl_pcie_irq_rx_msix_handler+0x58/0x100 [iwlwifi]
[199248.538584]  irq_thread_fn+0x28/0x60
[199248.538585]  irq_thread+0xda/0x170
[199248.538587]  ? irq_forced_thread_fn+0x80/0x80
[199248.538589]  kthread+0x104/0x140
[199248.538590]  ? irq_thread_check_affinity+0xf0/0xf0
[199248.538592]  ? kthread_park+0x90/0x90
[199248.538595]  ret_from_fork+0x1f/0x40
[199248.538597] ---[ end trace db403af89df135b7 ]---
[199248.542970] wlp0s12f0: disconnect from AP e0:28:6d:xx:xx:xx for new auth to e0:28:6d:xx:xx:xx
[199248.545843] iwlwifi 0000:00:0c.0: Unhandled alg: 0x707
[199248.546239] iwlwifi 0000:00:0c.0: Unhandled alg: 0x71b
[199248.549349] wlp0s12f0: authenticate with e0:28:6d:xx:xx:xx
[199248.558639] wlp0s12f0: send auth to e0:28:6d:xx:xx:xx (try 1/3)
[199248.599734] wlp0s12f0: authenticated
[199248.601466] wlp0s12f0: associate with e0:28:6d:xx:xx:xx (try 1/3)
[199248.603524] wlp0s12f0: RX ReassocResp from e0:28:6d:xx:xx:xx (capab=0x1511 status=0 aid=1)
[199248.605642] wlp0s12f0: associated
[199248.683563] wlp0s12f0: Limiting TX power to 20 (23 - 3) dBm as advertised by e0:28:6d:xx:xx:xx
Comment 14 lukas.redlinger 2020-02-24 10:16:13 UTC 
Intel 8265
5.5.2-arch1-1
firmware version 36.952d9faa.0
CISCO AC infrastructure with EAP-TLS and FT enabled


The problem still exists. The driver was crashing every ~20 Minutes (error sending STATISTICS_CMD, ...), so I played with module-options.

Disabling AC - an thus falling back to N - seems to make it [more] stable, but after some minutes I see this error on three different PCs.


Maybe that's the reason for all other symptoms we see?


Mon Feb 24 11:06:55 2020] ------------[ cut here ]------------
[Mon Feb 24 11:06:55 2020] Got an HT rate (flags:0xa8/mcs:15) for a non data frame
[Mon Feb 24 11:06:55 2020] WARNING: CPU: 3 PID: 1204440 at drivers/net/wireless/intel/iwlwifi/mvm/tx.c:333 iwl_mvm_get_tx_rate.isra.0+0xc0/0xd0 [iwlmvm]
[Mon Feb 24 11:06:55 2020] Modules linked in: iwlmvm iwlwifi 8021q garp mrp stp llc ccm snd_hda_codec_hdmi snd_hda_codec_realtek snd_hda_codec_generic ledtrig_audio xt_state xt_conntrack nf_conntrack nf_defrag_ipv6 nf_defrag_ipv4 libcrc32c xt_tcpudp iptable_filter rtl8xxxu intel_rapl_msr intel_rapl_common i915 x86_pkg_temp_thermal intel_powerclamp coretemp snd_hda_intel rtl8192cu snd_intel_dspcfg rtl_usb rtl8192c_common rtlwifi snd_hda_codec iTCO_wdt snd_hda_core mac80211 kvm btusb iTCO_vendor_support mei_wdt snd_hwdep mei_hdcp btrtl nls_iso8859_1 snd_pcm btbcm irqbypass btintel intel_cstate igb snd_timer drm_kms_helper nls_cp437 vfat intel_uncore fat drm i2c_algo_bit intel_rapl_perf intel_gtt e1000e qmi_wwan bluetooth snd cfg80211 pcspkr tpm_crb soundcore i2c_i801 dca agpgart cdc_wdm mei_me syscopyarea ecdh_generic usbnet sysfillrect sysimgblt rfkill qcserial tpm_tis ecc libarc4 mei ftdi_sio usb_wwan mii fb_sys_fops intel_xhci_usb_role_switch roles evdev tpm_tis_core mac_hid tpm rng_core ip_tables
[Mon Feb 24 11:06:55 2020]  x_tables ext4 crc32c_generic crc16 mbcache jbd2 dm_crypt dm_mod sd_mod crct10dif_pclmul crc32_pclmul crc32c_intel ghash_clmulni_intel serio_raw atkbd ahci libps2 libahci aesni_intel libata crypto_simd xhci_pci cryptd glue_helper scsi_mod xhci_hcd i8042 serio [last unloaded: iwlwifi]
[Mon Feb 24 11:06:55 2020] CPU: 3 PID: 1204440 Comm: irq/135-iwlwifi Not tainted 5.5.2-arch1-1 #1
[Mon Feb 24 11:06:55 2020] Hardware name: Kontron Europe GmbH A-150-KBL/ECX-KLU1, BIOS 1.11 11/27/2018
[Mon Feb 24 11:06:55 2020] RIP: 0010:iwl_mvm_get_tx_rate.isra.0+0xc0/0xd0 [iwlmvm]
[Mon Feb 24 11:06:55 2020] Code: 41 5d 09 d0 c3 0f 0b 31 db eb d4 80 3d 1c c1 04 00 00 75 81 0f b7 f1 48 c7 c7 28 02 0e c1 c6 05 09 c1 04 00 01 e8 02 55 5e e0 <0f> 0b 0f be 55 08 89 d3 e9 5e ff ff ff 0f 1f 00 0f 1f 44 00 00 40
[Mon Feb 24 11:06:55 2020] RSP: 0018:ffffb698804cf368 EFLAGS: 00010286
[Mon Feb 24 11:06:55 2020] RAX: 0000000000000000 RBX: 000000000000000f RCX: 0000000000000000
[Mon Feb 24 11:06:55 2020] RDX: 0000000000000603 RSI: 0000000000000086 RDI: 00000000ffffffff
[Mon Feb 24 11:06:55 2020] RBP: ffffb698804cf4d0 R08: 0000000000000cfb R09: 0000000000000001
[Mon Feb 24 11:06:55 2020] R10: 0000000000000000 R11: 0000000000000001 R12: ffff96aa214028e8
[Mon Feb 24 11:06:55 2020] R13: ffff96a9e7cdac30 R14: ffffb698804cf4d0 R15: 0000000000004188
[Mon Feb 24 11:06:55 2020] FS:  0000000000000000(0000) GS:ffff96aa35d80000(0000) knlGS:0000000000000000
[Mon Feb 24 11:06:55 2020] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[Mon Feb 24 11:06:55 2020] CR2: 00007f6896800000 CR3: 0000000055e0a004 CR4: 00000000003606e0
[Mon Feb 24 11:06:55 2020] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
[Mon Feb 24 11:06:55 2020] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
[Mon Feb 24 11:06:55 2020] Call Trace:
[Mon Feb 24 11:06:55 2020]  iwl_mvm_set_tx_cmd_rate+0x66/0xc0 [iwlmvm]
[Mon Feb 24 11:06:55 2020]  iwl_mvm_set_tx_params+0x32f/0x4f0 [iwlmvm]
[Mon Feb 24 11:06:55 2020]  iwl_mvm_tx_mpdu+0x92/0x5c0 [iwlmvm]
[Mon Feb 24 11:06:55 2020]  iwl_mvm_tx_skb_sta+0x1aa/0x480 [iwlmvm]
[Mon Feb 24 11:06:55 2020]  iwl_mvm_tx_skb+0x17/0x40 [iwlmvm]
[Mon Feb 24 11:06:55 2020]  iwl_mvm_mac_itxq_xmit+0x78/0xe0 [iwlmvm]
[Mon Feb 24 11:06:55 2020]  ieee80211_queue_skb+0x2b4/0x440 [mac80211]
[Mon Feb 24 11:06:55 2020]  __ieee80211_subif_start_xmit+0xb51/0xce0 [mac80211]
[Mon Feb 24 11:06:55 2020]  ? __ieee80211_subif_start_xmit+0x866/0xce0 [mac80211]
[Mon Feb 24 11:06:55 2020]  ? __update_load_avg_cfs_rq+0x1eb/0x2d0
[Mon Feb 24 11:06:55 2020]  ? _raw_spin_unlock+0x16/0x30
[Mon Feb 24 11:06:55 2020]  ieee80211_subif_start_xmit+0x60/0x2f0 [mac80211]
[Mon Feb 24 11:06:55 2020]  dev_hard_start_xmit+0x99/0x220
[Mon Feb 24 11:06:55 2020]  __dev_queue_xmit+0x7cf/0xa70
[Mon Feb 24 11:06:55 2020]  ip_finish_output2+0x2cd/0x590
[Mon Feb 24 11:06:55 2020]  ? rt_cpu_seq_stop+0x10/0x10
[Mon Feb 24 11:06:55 2020]  ip_output+0x78/0x130
[Mon Feb 24 11:06:55 2020]  ? __ip_finish_output+0x1c0/0x1c0
[Mon Feb 24 11:06:55 2020]  __ip_queue_xmit+0x172/0x430
[Mon Feb 24 11:06:55 2020]  __tcp_transmit_skb+0x562/0xb40
[Mon Feb 24 11:06:55 2020]  tcp_rcv_state_process+0xcf1/0xf87
[Mon Feb 24 11:06:55 2020]  tcp_v4_do_rcv+0xb8/0x1f0
[Mon Feb 24 11:06:55 2020]  tcp_v4_rcv+0xbc5/0xd10
[Mon Feb 24 11:06:55 2020]  ip_protocol_deliver_rcu+0x2b/0x1e0
[Mon Feb 24 11:06:55 2020]  ip_local_deliver_finish+0x55/0x70
[Mon Feb 24 11:06:55 2020]  ip_local_deliver+0x115/0x130
[Mon Feb 24 11:06:55 2020]  ? ip_protocol_deliver_rcu+0x1e0/0x1e0
[Mon Feb 24 11:06:55 2020]  ip_sublist_rcv_finish+0x38/0x50
[Mon Feb 24 11:06:55 2020]  ip_sublist_rcv+0x183/0x210
[Mon Feb 24 11:06:55 2020]  ? ip_rcv_finish_core.isra.0+0x470/0x470
[Mon Feb 24 11:06:55 2020]  ip_list_rcv+0x123/0x147
[Mon Feb 24 11:06:55 2020]  __netif_receive_skb_list_core+0x246/0x270
[Mon Feb 24 11:06:55 2020]  netif_receive_skb_list_internal+0x1c8/0x300
[Mon Feb 24 11:06:55 2020]  netif_receive_skb_list+0x25/0x100
[Mon Feb 24 11:06:55 2020]  iwl_pcie_rx_handle+0xa0c/0xaf0 [iwlwifi]
[Mon Feb 24 11:06:55 2020]  iwl_pcie_irq_handler+0x6a6/0xc00 [iwlwifi]
[Mon Feb 24 11:06:55 2020]  ? irq_forced_thread_fn+0x80/0x80
[Mon Feb 24 11:06:55 2020]  irq_thread_fn+0x20/0x60
[Mon Feb 24 11:06:55 2020]  irq_thread+0xee/0x180
[Mon Feb 24 11:06:55 2020]  ? wake_threads_waitq+0x30/0x30
[Mon Feb 24 11:06:55 2020]  kthread+0xfb/0x130
[Mon Feb 24 11:06:55 2020]  ? irq_thread_check_affinity+0xd0/0xd0
[Mon Feb 24 11:06:55 2020]  ? kthread_park+0x90/0x90
[Mon Feb 24 11:06:55 2020]  ret_from_fork+0x35/0x40
[Mon Feb 24 11:06:55 2020] ---[ end trace 709a1da38f6f5c31 ]---
Comment 15 lukas.redlinger 2020-12-04 10:37:31 UTC 
Intel 8265
Linux 5.9.11-arch2-1
firmware version 36.ad812ee0.0 8265-36.ucode op_mode iwlmvm

Still happening ~20 seconds after initial connect



Dec 04 11:27:26: wlp1s0: authenticated
Dec 04 11:27:26: wlp1s0: associate with 20:87:56:2d:f1:1c (try 1/3)
Dec 04 11:27:26: wlp1s0: send auth to 20:87:56:2d:f1:1c (try 1/3)
Dec 04 11:27:26: wlp1s0: RX ReassocResp from 20:87:56:2d:f1:1c (capab=0x411 status=0 aid=1)
Dec 04 11:27:26: wlp1s0: associated
Dec 04 11:27:47: ------------[ cut here ]------------
Dec 04 11:27:47: Got an HT rate (flags:0x28/mcs:11) for a non data frame
Dec 04 11:27:47: WARNING: CPU: 0 PID: 405 at drivers/net/wireless/intel/iwlwifi/mvm/tx.c:333 iwl_mvm_get_tx_rate+0xc6/0xe0 [iwlmvm]
Dec 04 11:27:47: Modules linked in: ccm 8021q garp mrp stp llc xt_state xt_conntrack nf_conntrack nf_defrag_ipv6 nf_defrag_ipv4 libcrc32c xt_tcpudp iptable_filter snd_hda_codec_hdmi snd_hda_codec_realtek snd_h>
Dec 04 11:27:47:  rc_core cfg80211 mii intel_gtt syscopyarea sysfillrect mei_me qcserial usb_wwan intel_xhci_usb_role_switch ecdh_generic sysimgblt rfkill ftdi_sio libarc4 ecc mei fb_sys_fops intel_pch_thermal>
Dec 04 11:27:47: CPU: 0 PID: 405 Comm: irq/135-iwlwifi Tainted: G          I       5.9.11-arch2-1 #1
Dec 04 11:27:47: Hardware name: CINCOZE DI-1000/DI-1000, BIOS 5.11 02/20/2019
Dec 04 11:27:47: RIP: 0010:iwl_mvm_get_tx_rate+0xc6/0xe0 [iwlmvm]
Dec 04 11:27:47: Code: 73 f3 0f be db eb 91 80 3d 96 ea 04 00 00 0f 85 7b ff ff ff 0f b7 f1 48 c7 c7 f0 04 f4 c0 c6 05 7f ea 04 00 01 e8 2a 9a 0c c3 <0f> 0b 0f be 55 08 89 d3 e9 58 ff ff ff 0f 0b 31 db e9 58 f>
Dec 04 11:27:47: RSP: 0018:ffffa7c90066f328 EFLAGS: 00010282
Dec 04 11:27:47: RAX: 0000000000000000 RBX: 000000000000000b RCX: 0000000000000000
Dec 04 11:27:47: RDX: 0000000000000603 RSI: ffffffff84959a97 RDI: 00000000ffffffff
Dec 04 11:27:47: RBP: ffffa7c90066f480 R08: 0000000000000467 R09: 0000000000000001
Dec 04 11:27:47: R10: 0000000000000000 R11: 0000000000000001 R12: ffff98847b89a900
Dec 04 11:27:47: R13: ffff9884a1ef1ec8 R14: ffffa7c90066f480 R15: ffff98849e939ce2
Dec 04 11:27:47: FS:  0000000000000000(0000) GS:ffff9884a5c00000(0000) knlGS:0000000000000000
Dec 04 11:27:47: CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
Dec 04 11:27:47: CR2: 000055bdaa6f3000 CR3: 000000016440e001 CR4: 00000000003706f0
Dec 04 11:27:47: DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
Dec 04 11:27:47: DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
Dec 04 11:27:47: Call Trace:
Dec 04 11:27:47:  iwl_mvm_set_tx_cmd_rate+0xc9/0x130 [iwlmvm]
Dec 04 11:27:47:  iwl_mvm_set_tx_params+0x1ae/0x5a0 [iwlmvm]
Dec 04 11:27:47:  iwl_mvm_tx_mpdu+0x93/0x590 [iwlmvm]
Dec 04 11:27:47:  ? ieee80211_xmit_fast_finish+0x56/0x1d0 [mac80211]
Dec 04 11:27:47:  iwl_mvm_tx_skb_sta+0x20a/0x480 [iwlmvm]
Dec 04 11:27:47:  iwl_mvm_tx_skb+0x17/0x40 [iwlmvm]
Dec 04 11:27:47:  iwl_mvm_mac_itxq_xmit+0x7b/0xf0 [iwlmvm]
Dec 04 11:27:47:  ieee80211_queue_skb+0x2b9/0x460 [mac80211]
Dec 04 11:27:47:  __ieee80211_subif_start_xmit+0xbae/0xdf0 [mac80211]
Dec 04 11:27:47:  ieee80211_subif_start_xmit+0x63/0x300 [mac80211]
Dec 04 11:27:47:  dev_hard_start_xmit+0x99/0x210
Dec 04 11:27:47:  __dev_queue_xmit+0x7a6/0x940
Dec 04 11:27:47:  ip_finish_output2+0x2d0/0x590
Dec 04 11:27:47:  ip_output+0x76/0x130
Dec 04 11:27:47:  ? __ip_finish_output+0x210/0x210
Dec 04 11:27:47:  __ip_queue_xmit+0x186/0x410
Dec 04 11:27:47:  __tcp_transmit_skb+0xa64/0xc40
Dec 04 11:27:47:  tcp_rcv_established+0x5b0/0x670
Dec 04 11:27:47:  tcp_v4_do_rcv+0x131/0x1f0
Dec 04 11:27:47:  tcp_v4_rcv+0xc44/0xdc0
Dec 04 11:27:47:  ip_protocol_deliver_rcu+0x2b/0x1e0
Dec 04 11:27:47:  ip_local_deliver_finish+0x55/0x70
Dec 04 11:27:47:  ip_local_deliver+0x115/0x130
Dec 04 11:27:47:  ? ip_protocol_deliver_rcu+0x1e0/0x1e0
Dec 04 11:27:47:  ip_sublist_rcv_finish+0x38/0x50
Dec 04 11:27:47:  ip_sublist_rcv+0x182/0x210
Dec 04 11:27:47:  ? ip_rcv_finish_core.constprop.0+0x470/0x470
Dec 04 11:27:47:  ip_list_rcv+0xf6/0x120
Dec 04 11:27:47:  __netif_receive_skb_list_core+0x246/0x270
Dec 04 11:27:47:  netif_receive_skb_list_internal+0x1c8/0x300
Dec 04 11:27:47:  netif_receive_skb_list+0x25/0x100
Dec 04 11:27:47:  iwl_pcie_rx_handle+0xa80/0xb30 [iwlwifi]
Dec 04 11:27:47:  iwl_pcie_irq_handler+0x6b1/0xc10 [iwlwifi]
Dec 04 11:27:47:  ? disable_irq_nosync+0x10/0x10
Dec 04 11:27:47:  irq_thread_fn+0x20/0x60
Dec 04 11:27:47:  irq_thread+0xf5/0x1a0
Dec 04 11:27:47:  ? irq_finalize_oneshot.part.0+0xe0/0xe0
Dec 04 11:27:47:  ? irq_thread_check_affinity+0xd0/0xd0
Dec 04 11:27:47:  kthread+0x142/0x160
Dec 04 11:27:47:  ? __kthread_bind_mask+0x60/0x60
Dec 04 11:27:47:  ret_from_fork+0x22/0x30
Dec 04 11:27:47: ---[ end trace 15ca64a8b14312d8 ]---
Comment 16 Nicolò 2022-10-19 14:21:42 UTC 
I have the same issue with
Intel Corporation Wireless 3160 [8086:08b3] (rev cb)
Linux 5.4
Firmware iwlwifi-3160-17.ucode

2022-10-18T17:09:46.919239+00:00 kernel: ------------[ cut here ]------------
2022-10-18T17:09:46.919253+00:00 kernel: Got an HT rate (flags:0x8a/mcs:4) for a non data frame
2022-10-18T17:09:46.919255+00:00 kernel: WARNING: CPU: 0 PID: 205 at drivers/net/wireless/intel/iwlwifi/mvm/tx.c:333 iwl_mvm_get_tx_rate.isra.0+0xc5/0xe0 [iwlmvm]
2022-10-18T17:09:46.919256+00:00 kernel: Modules linked in: fuse bridge stp llc nf_log_ipv4 nf_log_common ipt_REJECT nf_reject_ipv4 xt_LOG xt_limit xt_addrtype xt_tcpudp xt_conntrack ip6table_filter ip6_tables nf_conntrack_netbios_ns nf_conntrack_broadcast nf_nat_ftp nf_nat nf_conntrack_ftp nf_conntrack nf_defrag_ipv6 nf_defrag_ipv4 iptable_filter ip_tables x_tables btusb cdc_acm btrtl btbcm btintel uvcvideo videobuf2_vmalloc videobuf2_memops videobuf2_v4l2 videobuf2_common videodev mc sch_fq_codel iwlmvm mac80211 intel_rapl_msr uio iwlwifi snd_hda_codec_realtek snd_hda_codec_generic snd_hda_codec_hdmi snd_hda_intel snd_intel_nhlt intel_rapl_common snd_hda_codec x86_pkg_temp_thermal snd_hda_core intel_powerclamp snd_pcm coretemp snd_timer efivars mei_me mei cfg80211 i915 video
2022-10-18T17:09:46.919257+00:00 kernel: CPU: 0 PID: 205 Comm: irq/51-iwlwifi Not tainted 5.4.193-intel-pk-standard #1
2022-10-18T17:09:46.919268+00:00 kernel: Hardware name: ÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿ ÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿ/NUC5i3MYBE, BIOS MYBDWi30.86A.0028.2015.0304.1957 03/04/201
2022-10-18T17:09:46.919270+00:00 kernel: RIP: 0010:iwl_mvm_get_tx_rate.isra.0+0xc5/0xe0 [iwlmvm]
2022-10-18T17:09:46.919271+00:00 kernel: Code: d0 41 5e 5d c3 0f 0b 31 db eb d2 80 3d cc ab 02 00 00 75 81 0f b7 f1 48 c7 c7 70 12 62 c0 c6 05 b9 ab 02 00 01 e8 9c 55 1c c8 <0f> 0b 41 0f be 54 24 08 89 d3 e9 5c ff ff ff 66 66 2e 0f 1f 84 00
2022-10-18T17:09:46.919272+00:00 kernel: RSP: 0018:ffffb6b9c0003680 EFLAGS: 00010282
2022-10-18T17:09:46.919272+00:00 kernel: RAX: 0000000000000000 RBX: 0000000000000004 RCX: 0000000000000000
2022-10-18T17:09:46.919273+00:00 kernel: RDX: 0000000000000504 RSI: 0000000000000086 RDI: 00000000ffffffff
2022-10-18T17:09:46.919274+00:00 kernel: RBP: ffffb6b9c00036a0 R08: ffffffff883dd450 R09: 000000000000037a
2022-10-18T17:09:46.919274+00:00 kernel: R10: 0000000000000765 R11: 0000000000000007 R12: ffffb6b9c0003810
2022-10-18T17:09:46.919275+00:00 kernel: R13: ffff9bfc4f57c980 R14: ffff9bfc532a2c10 R15: ffffb6b9c0003810
2022-10-18T17:09:46.919276+00:00 kernel: FS:  0000000000000000(0000) GS:ffff9bfc56a00000(0000) knlGS:0000000000000000
2022-10-18T17:09:46.919276+00:00 kernel: CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
2022-10-18T17:09:46.919277+00:00 kernel: CR2: 00007fbfcae8a9f0 CR3: 000000020e134005 CR4: 00000000003606f0
2022-10-18T17:09:46.919277+00:00 kernel: Call Trace:
2022-10-18T17:09:46.919278+00:00 kernel: <IRQ>
2022-10-18T17:09:46.919279+00:00 kernel: iwl_mvm_set_tx_cmd_rate+0x71/0xd0 [iwlmvm]
2022-10-18T17:09:46.919280+00:00 kernel: iwl_mvm_set_tx_params+0x170/0x460 [iwlmvm]
2022-10-18T17:09:46.919281+00:00 kernel: iwl_mvm_tx_mpdu+0xab/0x5b0 [iwlmvm]
2022-10-18T17:09:46.919281+00:00 kernel: iwl_mvm_tx_skb_sta+0x19a/0x490 [iwlmvm]
2022-10-18T17:09:46.919282+00:00 kernel: iwl_mvm_tx_skb+0x1c/0x40 [iwlmvm]
2022-10-18T17:09:46.919283+00:00 kernel: iwl_mvm_mac_itxq_xmit+0x7a/0xe0 [iwlmvm]
2022-10-18T17:09:46.919284+00:00 kernel: iwl_mvm_mac_wake_tx_queue+0x38/0xa0 [iwlmvm]
2022-10-18T17:09:46.919284+00:00 kernel: ieee80211_queue_skb+0x30c/0x490 [mac80211]
2022-10-18T17:09:46.919285+00:00 kernel: __ieee80211_subif_start_xmit+0xc4a/0xdd0 [mac80211]
2022-10-18T17:09:46.919286+00:00 kernel: ? ___bpf_prog_run+0x97a/0x13f0
2022-10-18T17:09:46.919287+00:00 kernel: ? __bpf_prog_run32+0x3b/0x60
2022-10-18T17:09:46.919287+00:00 kernel: ? csum_partial+0x11/0x20
2022-10-18T17:09:46.919288+00:00 kernel: ieee80211_subif_start_xmit+0x47/0x300 [mac80211]
2022-10-18T17:09:46.919288+00:00 kernel: ? dev_queue_xmit_nit+0x25b/0x290
2022-10-18T17:09:46.919289+00:00 kernel: dev_hard_start_xmit+0xa6/0x240
2022-10-18T17:09:46.919290+00:00 kernel: __dev_queue_xmit+0x7e1/0xa10
2022-10-18T17:09:46.919290+00:00 kernel: ? __wake_up_common+0x85/0x180
2022-10-18T17:09:46.919291+00:00 kernel: ? __nf_conntrack_find_get.isra.0+0x198/0x210 [nf_conntrack]
2022-10-18T17:09:46.919292+00:00 kernel: dev_queue_xmit+0x10/0x20
2022-10-18T17:09:46.919292+00:00 kernel: ip_finish_output2+0x25c/0x530
2022-10-18T17:09:46.919293+00:00 kernel: __ip_finish_output+0xc0/0x1e0
2022-10-18T17:09:46.919294+00:00 kernel: ip_finish_output+0x37/0xb0
2022-10-18T17:09:46.919294+00:00 kernel: ip_output+0x74/0x100
2022-10-18T17:09:46.919295+00:00 kernel: ? __ip_finish_output+0x1e0/0x1e0
2022-10-18T17:09:46.919295+00:00 kernel: ip_local_out+0x3d/0x50
2022-10-18T17:09:46.919296+00:00 kernel: __ip_queue_xmit+0x17e/0x3f0
2022-10-18T17:09:46.919297+00:00 kernel: ip_queue_xmit+0x10/0x20
2022-10-18T17:09:46.919297+00:00 kernel: __tcp_transmit_skb+0x54f/0xaa0
2022-10-18T17:09:46.919298+00:00 kernel: tcp_write_xmit+0x3a9/0x12b0
2022-10-18T17:09:46.919298+00:00 kernel: tcp_tsq_write.part.0+0x60/0xb0
2022-10-18T17:09:46.919299+00:00 kernel: tcp_tsq_handler+0x43/0x80
2022-10-18T17:09:46.919300+00:00 kernel: tcp_tasklet_func+0xc9/0x100
2022-10-18T17:09:46.919300+00:00 kernel: tasklet_action_common.isra.0+0x54/0x110
2022-10-18T17:09:46.919301+00:00 kernel: tasklet_action+0x2d/0x30
2022-10-18T17:09:46.919301+00:00 kernel: __do_softirq+0x10a/0x336
2022-10-18T17:09:46.919302+00:00 kernel: ? rcu_dynticks_eqs_enter+0x16/0x30
2022-10-18T17:09:46.919303+00:00 kernel: do_softirq_own_stack+0x2a/0x40
2022-10-18T17:09:46.919303+00:00 kernel: </IRQ>
2022-10-18T17:09:46.919304+00:00 kernel: do_softirq.part.0+0x3c/0x40
2022-10-18T17:09:46.919304+00:00 kernel: __local_bh_enable_ip+0x7b/0x80
2022-10-18T17:09:46.919305+00:00 kernel: iwl_pcie_irq_handler+0x515/0xb80 [iwlwifi]
2022-10-18T17:09:46.919306+00:00 kernel: ? irq_forced_thread_fn+0x80/0x80
2022-10-18T17:09:46.919307+00:00 kernel: irq_thread_fn+0x28/0x60
2022-10-18T17:09:46.919307+00:00 kernel: irq_thread+0xf8/0x190
2022-10-18T17:09:46.919308+00:00 kernel: ? preempt_count_add+0x74/0xc0
2022-10-18T17:09:46.919309+00:00 kernel: ? wake_threads_waitq+0x30/0x30
2022-10-18T17:09:46.919309+00:00 kernel: kthread+0x12e/0x170
2022-10-18T17:09:46.919310+00:00 kernel: ? irq_thread_check_affinity+0x80/0x80
2022-10-18T17:09:46.919310+00:00 kernel: ? __kthread_cancel_work+0x40/0x40
2022-10-18T17:09:46.919311+00:00 kernel: ret_from_fork+0x35/0x40
2022-10-18T17:09:46.919312+00:00 kernel: ---[ end trace 4297006dfcc44c4d ]---
Comment 17 Nicolò 2022-10-20 14:05:52 UTC 
Also seen on Intel 7265D, Firmware 29, Kernel 5.4