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.
Created attachment 282885 [details] iw scan result
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
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.
(In reply to Johannes Berg from comment #3) > Regardless of the driver but Driver *bug*, that is.
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"
This is the firmware ... not the driver...
I am closing this bug. We've been waiting for your input with the patches in comment 3 for close to 1 month.
Created attachment 283297 [details] dmesg
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
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 ]---
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.
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 ]---
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
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 ]---
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 ]---
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 ]---
Also seen on Intel 7265D, Firmware 29, Kernel 5.4