iwlwifi: TX on unused queue 5

[Paul Menzel]

Dear Linux folks,


Since at least Linux 5.2.9 a warning is thrown by *iwlwifi*.

> [   21.211815] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
> [   22.685490] rfkill: input handler disabled
> [   26.529753] iwlwifi 0000:02:00.0: RF_KILL bit toggled to disable radio.
> [   26.529754] iwlwifi 0000:02:00.0: reporting RF_KILL (radio disabled)
> [   26.530095] wlan0: deauthenticating from 54:67:51:dd:7a:b3 by local choice (Reason: 3=DEAUTH_LEAVING)
> [   26.530170] ------------[ cut here ]------------
> [   26.530170] TX on unused queue 5
> [   26.530196] WARNING: CPU: 3 PID: 130 at drivers/net/wireless/intel/iwlwifi/pcie/tx.c:2337 iwl_trans_pcie_tx+0x9be/0x1080 [iwlwifi]
> [   26.530197] Modules linked in: ctr ccm fuse binfmt_misc nls_ascii nls_cp437 vfat fat uvcvideo videobuf2_vmalloc videobuf2_memops videobuf2_v4l2 videobuf2_common videodev mc btusb btrtl btbcm btintel bluetooth ecdh_generic ecc x86_pkg_temp_thermal intel_powerclamp coretemp kvm_intel kvm iwlmvm snd_hda_codec_realtek dell_laptop dell_wmi irqbypass snd_hda_codec_generic iTCO_wdt dell_smbios snd_hda_codec_hdmi mac80211 ledtrig_audio iTCO_vendor_support mei_wdt ppdev crc32_pclmul sparse_keymap dell_wmi_descriptor dcdbas wmi_bmof watchdog ghash_clmulni_intel libarc4 snd_hda_intel sdhci_pci intel_rapl_msr intel_cstate snd_intel_dspcfg dell_smm_hwmon tpm_tis cqhci intel_uncore snd_hda_codec sdhci tpm_tis_core i915 iwlwifi snd_hda_core mmc_core intel_rapl_perf tpm cfg80211 xhci_pci ehci_pci efi_pstore snd_hwdep e1000e xhci_hcd ehci_hcd efivars snd_pcm rng_core pcspkr sg joydev parport_pc drm_kms_helper wmi snd_timer parport usbcore snd cec mei_me ptp processor_thermal_device int3403_thermal video
> [   26.530218]  lpc_ich soundcore i2c_algo_bit mei i2c_i801 usb_common intel_rapl_common pps_core mfd_core button intel_soc_dts_iosf int3400_thermal dell_rbtn int3402_thermal acpi_thermal_rel int340x_thermal_zone rfkill battery acpi_pad ac drm pkcs8_key_parser efivarfs ip_tables x_tables autofs4 ext4 crc16 mbcache jbd2 crc32c_generic dm_crypt dm_mod sd_mod t10_pi crc_t10dif crct10dif_generic crct10dif_pclmul crct10dif_common crc32c_intel ahci libahci aesni_intel libata glue_helper libaes crypto_simd psmouse scsi_mod evdev cryptd serio_raw fan
> [   26.530233] CPU: 3 PID: 130 Comm: kworker/3:2 Not tainted 5.7.0-1-amd64 #1 Debian 5.7.6-1
> [   26.530234] Hardware name: Dell Inc. Latitude E7250/0TVD2T, BIOS A19 01/23/2018
> [   26.530247] Workqueue: events cfg80211_rfkill_block_work [cfg80211]
> [   26.530251] RIP: 0010:iwl_trans_pcie_tx+0x9be/0x1080 [iwlwifi]
> [   26.530253] Code: 80 3d a2 b7 02 00 00 b8 ea ff ff ff 0f 85 b2 f9 ff ff 89 ce 48 c7 c7 07 64 ce c0 89 04 24 c6 05 84 b7 02 00 01 e8 24 b2 db d2 <0f> 0b 8b 04 24 e9 90 f9 ff ff 48 c7 44 24 50 00 00 00 00 8b 44 24
> [   26.530253] RSP: 0018:ffffb76240213778 EFLAGS: 00010286
> [   26.530254] RAX: 0000000000000000 RBX: ffff8d1d8e57c8f0 RCX: 0000000000000998
> [   26.530255] RDX: 0000000000000001 RSI: 0000000000000086 RDI: 0000000000000247
> [   26.530255] RBP: ffff8d1dc10f9e88 R08: 0000000000000998 R09: 0000000000000004
> [   26.530256] R10: 0000000000000000 R11: 0000000000000001 R12: 0000000000000005
> [   26.530256] R13: 0000000000000005 R14: ffff8d1d73762080 R15: ffff8d1dc10883e8
> [   26.530257] FS:  0000000000000000(0000) GS:ffff8d1dcdd80000(0000) knlGS:0000000000000000
> [   26.530258] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
> [   26.530258] CR2: 0000558f3adf3808 CR3: 00000003bc6d4005 CR4: 00000000003606e0
> [   26.530259] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
> [   26.530259] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
> [   26.530260] Call Trace:
> [   26.530268]  ? iwl_mvm_set_tx_cmd+0x1a9/0x4b0 [iwlmvm]
> [   26.530273]  ? iwl_mvm_get_tx_rate.isra.0+0x87/0xe0 [iwlmvm]
> [   26.530277]  ? iwl_mvm_get_tx_ant+0x40/0x70 [iwlmvm]
> [   26.530281]  ? iwl_mvm_set_tx_cmd_rate+0x79/0xc0 [iwlmvm]
> [   26.530286]  ? iwl_mvm_set_tx_params+0x33e/0x4f0 [iwlmvm]
> [   26.530291]  iwl_mvm_tx_mpdu+0x170/0x500 [iwlmvm]
> [   26.530296]  iwl_mvm_tx_skb_sta+0x19d/0x470 [iwlmvm]
> [   26.530300]  iwl_mvm_tx_skb+0x17/0x40 [iwlmvm]
> [   26.530304]  iwl_mvm_mac_itxq_xmit+0x76/0xa0 [iwlmvm]
> [   26.530318]  ieee80211_queue_skb+0x2b7/0x450 [mac80211]
> [   26.530330]  ieee80211_tx+0xe7/0x140 [mac80211]
> [   26.530342]  __ieee80211_tx_skb_tid_band+0x6c/0x80 [mac80211]
> [   26.530353]  ieee80211_send_deauth_disassoc+0xf5/0x120 [mac80211]
> [   26.530365]  ieee80211_set_disassoc+0x361/0x5b0 [mac80211]
> [   26.530368]  ? __switch_to_asm+0x40/0x70
> [   26.530380]  ieee80211_mgd_deauth.cold+0x49/0x1bf [mac80211]
> [   26.530392]  cfg80211_mlme_deauth+0xb3/0x1d0 [cfg80211]
> [   26.530396]  ? rtc_dev_compat_ioctl+0x13/0x60
> [   26.530406]  cfg80211_mlme_down+0x66/0x90 [cfg80211]
> [   26.530418]  cfg80211_disconnect+0x129/0x1e0 [cfg80211]
> [   26.530427]  cfg80211_leave+0x27/0x40 [cfg80211]
> [   26.530435]  cfg80211_netdev_notifier_call+0x1a9/0x560 [cfg80211]
> [   26.530440]  ? iwl_mvm_send_cmd+0x12/0x30 [iwlmvm]
> [   26.530443]  ? iwl_mvm_mc_iface_iterator+0xc6/0xf0 [iwlmvm]
> [   26.530446]  ? iwl_mvm_set_tim+0x50/0x50 [iwlmvm]
> [   26.530458]  ? __iterate_interfaces+0x74/0x110 [mac80211]
> [   26.530460]  ? rtnl_is_locked+0x11/0x20
> [   26.530462]  ? inetdev_event+0x47/0x5a0
> [   26.530473]  ? ieee80211_iterate_active_interfaces_atomic+0xd/0x10 [mac80211]
> [   26.530476]  ? iwl_mvm_recalc_multicast+0x45/0x70 [iwlmvm]
> [   26.530478]  notifier_call_chain+0x4c/0x70
> [   26.530480]  __dev_close_many+0x5f/0x110
> [   26.530482]  dev_close_many+0x91/0x150
> [   26.530483]  ? __switch_to_asm+0x34/0x70
> [   26.530484]  ? __switch_to_asm+0x40/0x70
> [   26.530486]  dev_close.part.0+0x5e/0xa0
> [   26.530494]  cfg80211_shutdown_all_interfaces+0x71/0xd0 [cfg80211]
> [   26.530503]  cfg80211_rfkill_block_work+0x1a/0x20 [cfg80211]
> [   26.530505]  process_one_work+0x1b4/0x380
> [   26.530506]  worker_thread+0x50/0x3c0
> [   26.530508]  kthread+0xf9/0x130
> [   26.530509]  ? process_one_work+0x380/0x380
> [   26.530511]  ? kthread_park+0x90/0x90
> [   26.530512]  ret_from_fork+0x35/0x40
> [   26.530513] ---[ end trace c3a80f450141d4c6 ]---
> [   26.759202] ------------[ cut here ]------------
> [   26.759204] Timeout waiting for hardware access (CSR_GP_CNTRL 0x000003d8)
> [   26.759233] WARNING: CPU: 3 PID: 130 at drivers/net/wireless/intel/iwlwifi/pcie/trans.c:2084 iwl_trans_pcie_grab_nic_access+0x1af/0x1e0 [iwlwifi]
> [   26.759234] Modules linked in: ctr ccm fuse binfmt_misc nls_ascii nls_cp437 vfat fat uvcvideo videobuf2_vmalloc videobuf2_memops videobuf2_v4l2 videobuf2_common videodev mc btusb btrtl btbcm btintel bluetooth ecdh_generic ecc x86_pkg_temp_thermal intel_powerclamp coretemp kvm_intel kvm iwlmvm snd_hda_codec_realtek dell_laptop dell_wmi irqbypass snd_hda_codec_generic iTCO_wdt dell_smbios snd_hda_codec_hdmi mac80211 ledtrig_audio iTCO_vendor_support mei_wdt ppdev crc32_pclmul sparse_keymap dell_wmi_descriptor dcdbas wmi_bmof watchdog ghash_clmulni_intel libarc4 snd_hda_intel sdhci_pci intel_rapl_msr intel_cstate snd_intel_dspcfg dell_smm_hwmon tpm_tis cqhci intel_uncore snd_hda_codec sdhci tpm_tis_core i915 iwlwifi snd_hda_core mmc_core intel_rapl_perf tpm cfg80211 xhci_pci ehci_pci efi_pstore snd_hwdep e1000e xhci_hcd ehci_hcd efivars snd_pcm rng_core pcspkr sg joydev parport_pc drm_kms_helper wmi snd_timer parport usbcore snd cec mei_me ptp processor_thermal_device int3403_thermal video
> [   26.759255]  lpc_ich soundcore i2c_algo_bit mei i2c_i801 usb_common intel_rapl_common pps_core mfd_core button intel_soc_dts_iosf int3400_thermal dell_rbtn int3402_thermal acpi_thermal_rel int340x_thermal_zone rfkill battery acpi_pad ac drm pkcs8_key_parser efivarfs ip_tables x_tables autofs4 ext4 crc16 mbcache jbd2 crc32c_generic dm_crypt dm_mod sd_mod t10_pi crc_t10dif crct10dif_generic crct10dif_pclmul crct10dif_common crc32c_intel ahci libahci aesni_intel libata glue_helper libaes crypto_simd psmouse scsi_mod evdev cryptd serio_raw fan
> [   26.759271] CPU: 3 PID: 130 Comm: kworker/3:2 Tainted: G        W         5.7.0-1-amd64 #1 Debian 5.7.6-1
> [   26.759272] Hardware name: Dell Inc. Latitude E7250/0TVD2T, BIOS A19 01/23/2018
> [   26.759290] Workqueue: events cfg80211_rfkill_block_work [cfg80211]
> [   26.759295] RIP: 0010:iwl_trans_pcie_grab_nic_access+0x1af/0x1e0 [iwlwifi]
> [   26.759296] Code: e6 d3 49 8d 56 08 bf 00 02 00 00 e8 1b 89 dd d2 e9 3a ff ff ff 89 c6 48 c7 c7 e8 82 ce c0 c6 05 fa 95 02 00 01 e8 93 90 db d2 <0f> 0b e9 f5 fe ff ff 48 8b 7d 38 48 c7 c1 50 83 ce c0 31 d2 31 f6
> [   26.759296] RSP: 0018:ffffb76240213bf0 EFLAGS: 00010086
> [   26.759297] RAX: 0000000000000000 RBX: ffffb76240213c20 RCX: 00000000000009db
> [   26.759298] RDX: 0000000000000001 RSI: 0000000000000092 RDI: 0000000000000047
> [   26.759298] RBP: ffff8d1dc3660018 R08: 00000000000009db R09: 0000000000000004
> [   26.759299] R10: 0000000000000000 R11: 0000000000000001 R12: 0000000000000000
> [   26.759299] R13: ffff8d1dc36625fc R14: 00000000000003d8 R15: ffff8d1ac76c1490
> [   26.759300] FS:  0000000000000000(0000) GS:ffff8d1dcdd80000(0000) knlGS:0000000000000000
> [   26.759301] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
> [   26.759301] CR2: 0000558f3adf3808 CR3: 00000003deb2c004 CR4: 00000000003606e0
> [   26.759302] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
> [   26.759302] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
> [   26.759303] Call Trace:
> [   26.759309]  iwl_set_bits_prph+0x37/0xa0 [iwlwifi]
> [   26.759314]  iwl_fw_dbg_stop_restart_recording+0x1c0/0x1d0 [iwlwifi]
> [   26.759319]  __iwl_mvm_mac_stop+0x63/0x130 [iwlmvm]
> [   26.759323]  iwl_mvm_mac_stop+0xa5/0xc0 [iwlmvm]
> [   26.759339]  drv_stop+0x34/0x100 [mac80211]
> [   26.759349]  ieee80211_do_stop+0x579/0x880 [mac80211]
> [   26.759360]  ieee80211_stop+0x16/0x20 [mac80211]
> [   26.759363]  __dev_close_many+0x9e/0x110
> [   26.759364]  dev_close_many+0x91/0x150
> [   26.759366]  ? __switch_to_asm+0x34/0x70
> [   26.759367]  ? __switch_to_asm+0x40/0x70
> [   26.759369]  dev_close.part.0+0x5e/0xa0
> [   26.759377]  cfg80211_shutdown_all_interfaces+0x71/0xd0 [cfg80211]
> [   26.759386]  cfg80211_rfkill_block_work+0x1a/0x20 [cfg80211]
> [   26.759389]  process_one_work+0x1b4/0x380
> [   26.759390]  worker_thread+0x50/0x3c0
> [   26.759392]  kthread+0xf9/0x130
> [   26.759394]  ? process_one_work+0x380/0x380
> [   26.759395]  ? kthread_park+0x90/0x90
> [   26.759396]  ret_from_fork+0x35/0x40
> [   26.759398] ---[ end trace c3a80f450141d4c7 ]---
> [   26.759401] iwlwifi 0000:02:00.0: iwlwifi transaction failed, dumping registers
> [   26.759404] iwlwifi 0000:02:00.0: iwlwifi device config registers:
> [   26.759738] iwlwifi 0000:02:00.0: 00000000: 095a8086 00100406 02800059 00000010 f7000004 00000000 00000000 00000000
> [   26.759740] iwlwifi 0000:02:00.0: 00000020: 00000000 00000000 00000000 54108086 00000000 000000c8 00000000 000001ff
> [   26.759742] iwlwifi 0000:02:00.0: 00000040: 00020010 10008ec0 00190c10 0046e811 10110142 00000000 00000000 00000000
> [   26.759743] iwlwifi 0000:02:00.0: 00000060: 00000000 00080812 00000405 00000000 00010001 00000000 00000000 00000000
> [   26.759745] iwlwifi 0000:02:00.0: 00000080: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000
> [   26.759746] iwlwifi 0000:02:00.0: 000000a0: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000
> [   26.759747] iwlwifi 0000:02:00.0: 000000c0: 00000000 00000000 c823d001 0d000000 00814005 fee00358 00000000 00000000
> [   26.759749] iwlwifi 0000:02:00.0: 000000e0: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000
> [   26.759750] iwlwifi 0000:02:00.0: 00000100: 14010001 00000000 00000000 00462031 00000000 00002000 00000000 00000000
> [   26.759752] iwlwifi 0000:02:00.0: 00000120: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000
> [   26.759753] iwlwifi 0000:02:00.0: 00000140: 14c10003 ff451933 340286ff 15410018 10031003 0001001e 00f01e1f 40a0000f
> [   26.759755] iwlwifi 0000:02:00.0: iwlwifi device memory mapped registers:
> [   26.759945] iwlwifi 0000:02:00.0: 00000000: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000
> [   26.759947] iwlwifi 0000:02:00.0: 00000020: 00000001 000003d8 00000210 d55555d5 00000000 d55555d5 80008040 00080042
> [   26.760000] iwlwifi 0000:02:00.0: iwlwifi device AER capability structure:
> [   26.760070] iwlwifi 0000:02:00.0: 00000000: 14010001 00000000 00000000 00462031 00000000 00002000 00000000 00000000
> [   26.760071] iwlwifi 0000:02:00.0: 00000020: 00000000 00000000 00000000
> [   26.760072] iwlwifi 0000:02:00.0: iwlwifi parent port (0000:00:1c.3) config registers:

Bug #204643 in the Linux Kernel Bugzilla [1] has more details.

What can be done to debug this further?


Kind regards,

Paul


[1]: https://bugzilla.kernel.org/show_bug.cgi?id=204643
     "iwlwifi: 8265: enabling RF-Kill causes kernel WARNING: TX on 
unused queue 5"