2020-07-04 22:02:24

by Paul Menzel

[permalink] [raw]
Subject: iwlwifi: TX on unused queue 5

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"