Bug 204643 - iwlwifi: 8265: enabling RF-Kill causes kernel WARNING: TX on unused queue 5
Summary: iwlwifi: 8265: enabling RF-Kill causes kernel WARNING: TX on unused queue 5
Status: ASSIGNED
Alias: None
Product: Drivers
Classification: Unclassified
Component: network-wireless-intel (show other bugs)
Hardware: Intel Linux
: P1 normal
Assignee: DO NOT USE - assign "network-wireless-intel" component instead
URL:
Keywords:
Depends on:
Blocks:
 
Reported: 2019-08-21 10:45 UTC by Laurent Bonnaud
Modified: 2020-12-04 09:23 UTC (History)
6 users (show)

See Also:
Kernel Version: 5.2.9
Tree: Mainline
Regression: No


Attachments
Full kernel log (69.06 KB, text/plain)
2019-08-21 10:46 UTC, Laurent Bonnaud
Details

Description Laurent Bonnaud 2019-08-21 10:45:00 UTC
Hi,

I booted my laptop with Wi-Fi on and then disabled Wi-Fi with NetworkManager.

Here is my Wi-Fi adapter:

$ lspci
[...]
02:00.0 Network controller: Intel Corporation Wireless 8265 / 8275 (rev 78)

This caused the following WARNING in the kernel logs:

[   35.992645] iwlwifi 0000:02:00.0: RF_KILL bit toggled to disable radio.
[   35.992647] iwlwifi 0000:02:00.0: reporting RF_KILL (radio disabled)
[   35.993819] wlan0: deauthenticating from 60:a4:4c:9f:1a:30 by local choice (Reason: 3=DEAUTH_LEAVING)
[   35.995862] ------------[ cut here ]------------
[   35.995863] TX on unused queue 5
[   35.995904] WARNING: CPU: 3 PID: 146 at drivers/net/wireless/intel/iwlwifi/pcie/tx.c:2345 iwl_trans_pcie_tx+0x724/0x850 [iwlwifi]
[   35.995905] Modules linked in: ccm xt_conntrack xt_MASQUERADE iptable_nat nf_nat nf_conntrack nf_defrag_ipv6 nf_defrag_ipv4 xt_CHECKSUM iptable_mangle xt_tcpudp iptable_filter bpfilter bridge stp llc dummy overlay bnep binfmt_misc snd_hda_codec_hdmi mei_hdcp snd_soc_skl snd_soc_hdac_hda snd_hda_ext_core snd_soc_skl_ipc snd_soc_sst_ipc snd_soc_sst_dsp snd_hda_codec_realtek snd_soc_acpi_intel_match snd_hda_codec_generic snd_soc_acpi snd_soc_core snd_compress ac97_bus snd_pcm_dmaengine snd_hda_intel nls_iso8859_1 snd_hda_codec snd_hda_core snd_hwdep snd_pcm snd_seq_midi snd_seq_midi_event snd_rawmidi dell_rbtn arc4 intel_rapl x86_pkg_temp_thermal intel_powerclamp coretemp snd_seq kvm_intel kvm irqbypass crct10dif_pclmul crc32_pclmul ghash_clmulni_intel snd_seq_device snd_timer joydev dell_laptop ledtrig_audio aesni_intel dell_smm_hwmon i915 aes_x86_64 crypto_simd iwlmvm cryptd glue_helper intel_cstate dell_wmi intel_rapl_perf mac80211 uvcvideo videobuf2_vmalloc dell_smbios videobuf2_memops
[   35.995924]  videobuf2_v4l2 input_leds videobuf2_common dcdbas btusb rtsx_pci_ms videodev btrtl btbcm snd btintel drm_kms_helper serio_raw media iwlwifi wmi_bmof dell_wmi_descriptor intel_wmi_thunderbolt soundcore memstick bluetooth drm cfg80211 processor_thermal_device mei_me intel_xhci_usb_role_switch roles hid_multitouch intel_soc_dts_iosf i2c_algo_bit mei fb_sys_fops idma64 syscopyarea ecdh_generic ucsi_acpi sysfillrect typec_ucsi ecc sysimgblt intel_pch_thermal virt_dma mac_hid typec acpi_pad int3403_thermal int340x_thermal_zone dell_smo8800 intel_hid int3400_thermal acpi_thermal_rel sparse_keymap nf_tables sch_fq_codel nfnetlink parport_pc ppdev lp parport ip_tables x_tables autofs4 btrfs xor zstd_compress usbhid raid6_pq libcrc32c hid_generic rtsx_pci_sdmmc nvme rtsx_pci e1000e intel_lpss_pci nvme_core intel_lpss ahci i2c_i801 libahci wmi i2c_hid hid video
[   35.995948] CPU: 3 PID: 146 Comm: kworker/3:1 Not tainted 5.2.9-050209-generic #201908160940
[   35.995949] Hardware name: Dell Inc. Latitude 5590/0VYDFF, BIOS 1.10.1 07/19/2019
[   35.995963] Workqueue: events cfg80211_rfkill_sync_work [cfg80211]
[   35.995970] RIP: 0010:iwl_trans_pcie_tx+0x724/0x850 [iwlwifi]
[   35.995972] Code: 3d 0a 22 03 00 00 b8 ea ff ff ff 0f 85 40 fc ff ff 44 89 f6 48 c7 c7 50 4b a9 c0 89 45 d0 c6 05 eb 21 03 00 01 e8 c8 bc 62 d2 <0f> 0b 8b 45 d0 e9 1d fc ff ff 41 0f b6 87 80 00 00 00 83 e0 60 3c
[   35.995972] RSP: 0000:ffffac68c20676c8 EFLAGS: 00010282
[   35.995974] RAX: 0000000000000000 RBX: ffff9a2e80c44898 RCX: 0000000000000006
[   35.995974] RDX: 0000000000000007 RSI: 0000000000000086 RDI: ffff9a2e9e397440
[   35.995975] RBP: ffffac68c2067730 R08: 00000000000003a1 R09: 0000000000000004
[   35.995975] R10: 0000000000000000 R11: 0000000000000001 R12: ffff9a2e9829a3e8
[   35.995976] R13: 0000000000000005 R14: 0000000000000005 R15: 0000000000000008
[   35.995977] FS:  0000000000000000(0000) GS:ffff9a2e9e380000(0000) knlGS:0000000000000000
[   35.995978] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[   35.995978] CR2: 00007ff4e65c7000 CR3: 0000000329240001 CR4: 00000000003606e0
[   35.995979] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
[   35.995980] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
[   35.995980] Call Trace:
[   35.995990]  ? iwl_mvm_set_tx_params+0x341/0x4d0 [iwlmvm]
[   35.995996]  iwl_mvm_tx_mpdu+0x1d8/0x580 [iwlmvm]
[   35.996002]  iwl_mvm_tx_skb+0x19c/0x480 [iwlmvm]
[   35.996007]  iwl_mvm_mac_itxq_xmit+0x7c/0xf0 [iwlmvm]
[   35.996011]  iwl_mvm_mac_wake_tx_queue+0x29/0x80 [iwlmvm]
[   35.996030]  ieee80211_queue_skb+0x2b9/0x430 [mac80211]
[   35.996043]  ieee80211_tx+0xe1/0x140 [mac80211]
[   35.996054]  ieee80211_xmit+0xc2/0x100 [mac80211]
[   35.996064]  __ieee80211_tx_skb_tid_band+0x64/0x80 [mac80211]
[   35.996076]  ieee80211_send_deauth_disassoc+0x109/0x120 [mac80211]
[   35.996089]  ieee80211_set_disassoc+0x380/0x5d0 [mac80211]
[   35.996102]  ieee80211_mgd_deauth+0x104/0x460 [mac80211]
[   35.996114]  ieee80211_deauth+0x18/0x20 [mac80211]
[   35.996128]  cfg80211_mlme_deauth+0xb6/0x1e0 [cfg80211]
[   35.996139]  cfg80211_mlme_down+0x66/0x80 [cfg80211]
[   35.996151]  cfg80211_disconnect+0x127/0x1e0 [cfg80211]
[   35.996154]  ? _raw_spin_unlock_bh+0x1e/0x20
[   35.996162]  __cfg80211_leave+0x133/0x1b0 [cfg80211]
[   35.996170]  cfg80211_leave+0x2c/0x40 [cfg80211]
[   35.996177]  cfg80211_netdev_notifier_call+0x1b1/0x520 [cfg80211]
[   35.996193]  ? __iterate_interfaces+0x7d/0x110 [mac80211]
[   35.996198]  ? iwl_mvm_set_tim+0x50/0x50 [iwlmvm]
[   35.996201]  ? inetdev_event+0x46/0x570
[   35.996203]  notifier_call_chain+0x55/0x80
[   35.996205]  raw_notifier_call_chain+0x16/0x20
[   35.996207]  call_netdevice_notifiers_info+0x2e/0x60
[   35.996209]  __dev_close_many+0x63/0x120
[   35.996211]  dev_close_many+0x91/0x150
[   35.996212]  dev_close.part.0+0x4a/0x70
[   35.996213]  dev_close+0x18/0x20
[   35.996222]  cfg80211_shutdown_all_interfaces+0x77/0xd0 [cfg80211]
[   35.996229]  cfg80211_rfkill_sync_work+0x2b/0x40 [cfg80211]
[   35.996231]  process_one_work+0x1db/0x3c0
[   35.996232]  worker_thread+0x4d/0x400
[   35.996234]  kthread+0x104/0x140
[   35.996235]  ? process_one_work+0x3c0/0x3c0
[   35.996237]  ? kthread_park+0x80/0x80
[   35.996238]  ret_from_fork+0x35/0x40
[   35.996240] ---[ end trace 1a9c2fe8a2d59fc1 ]---
Comment 1 Laurent Bonnaud 2019-08-21 10:46:02 UTC
Created attachment 284555 [details]
Full kernel log
Comment 2 Laurent Bonnaud 2019-08-21 10:48:37 UTC
Here is more info about my Wi-Fi adapter:

$ dmesg | grep iwlwifi
[    3.501397] iwlwifi 0000:02:00.0: enabling device (0000 -> 0002)
[    3.516493] iwlwifi 0000:02:00.0: loaded firmware version 36.9f0a2d68.0 op_mode iwlmvm
[    3.555795] iwlwifi 0000:02:00.0: Detected Intel(R) Dual Band Wireless AC 8265, REV=0x230
[    3.619919] iwlwifi 0000:02:00.0: base HW address: a8:6d:aa:ac:d8:2a
Comment 3 Luca Coelho 2019-08-22 04:44:37 UTC
Can you try to upgrade to v5.1.20 or higher?

We made a bunch of RF-kill fixes that were picked to v5.1.20 and which will probably solve this problem.

Marking this as a duplicate of 200821.

*** This bug has been marked as a duplicate of bug 200821 ***
Comment 4 Laurent Bonnaud 2019-08-22 10:01:54 UTC
> Can you try to upgrade to v5.1.20 or higher?

Note that my report is about kernel 5.2.9.
Comment 5 Luca Coelho 2019-08-22 10:24:11 UTC
(In reply to Laurent Bonnaud from comment #4)
> > Can you try to upgrade to v5.1.20 or higher?
> 
> Note that my report is about kernel 5.2.9.

Ah, sorry, somehow I missed that (or confused with another bug).  We'll take a look into this.
Comment 6 Dominik Mierzejewski 2019-09-19 09:52:05 UTC
Same WARNING on 5.2.14 (Fedora 30). Happens sometimes after `rfkill block wlan`.

[   17.980807] iwlwifi 0000:01:00.0: loaded firmware version 17.3216344376.0 op_mode iwlmvm
[   18.147915] iwlwifi 0000:01:00.0: Detected Intel(R) Dual Band Wireless AC 7260, REV=0x144

[37796.839690] TX on unused queue 5
[37796.839801] WARNING: CPU: 1 PID: 5794 at drivers/net/wireless/intel/iwlwifi/pcie/tx.c:2345 iwl_trans_pcie_tx+0x9e7/0xfe0 [iwlwifi]
[37796.839803] Modules linked in: hid_logitech_hidpp uas usb_storage hid_logitech_dj snd_usb_audio snd_usbmidi_lib snd_rawmidi ax88179_178a usbnet mii hid_jabra ccm bnep xt_set ip6t_rpfilter nf_log_ipv6 ip6t_REJECT nf_reject_ipv6 nf_log_ipv4 nf_log_common ipt_REJECT nf_reject_ipv4 xt_LOG xt_pkttype xt_conntrack ip_set_hash_net ebtable_nat ip6table_nat ip6table_mangle ip6table_raw ip6table_security iptable_nat nf_nat iptable_mangle iptable_raw iptable_security nf_conntrack nf_defrag_ipv6 nf_defrag_ipv4 libcrc32c ip_set nfnetlink ebtable_filter ebtables ip6table_filter ip6_tables iptable_filter ip_tables bluetooth ecdh_generic ecc sunrpc vfat fat pn544_mei intel_rapl mei_phy arc4 pn544 hci x86_pkg_temp_thermal intel_powerclamp coretemp kvm_intel nfc iTCO_wdt mei_hdcp uvcvideo iTCO_vendor_support videobuf2_vmalloc snd_hda_codec_realtek videobuf2_memops kvm videobuf2_v4l2 snd_hda_codec_generic iwlmvm snd_hda_codec_hdmi ledtrig_audio snd_hda_intel videobuf2_common snd_hda_codec mac80211 videodev
[37796.839895]  media snd_hda_core iwlwifi snd_hwdep snd_seq snd_seq_device irqbypass cfg80211 snd_pcm intel_cstate joydev rtsx_pci_ms intel_uncore intel_rapl_perf i2c_i801 snd_timer memstick mei_me snd mei lpc_ich soundcore sony_laptop rfkill pcc_cpufreq binfmt_misc dm_crypt mmc_block i915 rtsx_pci_sdmmc mmc_core i2c_algo_bit drm_kms_helper crct10dif_pclmul crc32_pclmul crc32c_intel ghash_clmulni_intel drm rtsx_pci serio_raw video hid_multitouch fuse
[37796.839952] CPU: 1 PID: 5794 Comm: rfkill Not tainted 5.2.14-200.fc30.x86_64 #1
[37796.839955] Hardware name: Sony Corporation SVP1322C5E/VAIO, BIOS R2091V7 03/24/2014
[37796.839982] RIP: 0010:iwl_trans_pcie_tx+0x9e7/0xfe0 [iwlwifi]
[37796.839988] Code: 80 3d 8e 4b 03 00 00 b8 ea ff ff ff 0f 85 44 f9 ff ff 89 ce 48 c7 c7 08 ec 9a c0 89 04 24 c6 05 70 4b 03 00 01 e8 48 34 75 f5 <0f> 0b 8b 04 24 e9 22 f9 ff ff 41 0f b6 84 24 80 00 00 00 83 e0 60
[37796.839992] RSP: 0018:ffffb8bd087df7e0 EFLAGS: 00010282
[37796.839997] RAX: 0000000000000000 RBX: ffff99c5cdc3e3e8 RCX: 0000000000000006
[37796.840001] RDX: 0000000000000007 RSI: 0000000000000086 RDI: ffff99c5d7897900
[37796.840004] RBP: 0000000000000005 R08: 0000000000000001 R09: 00000000000004cb
[37796.840006] R10: 00000000000274ac R11: 0000000000000003 R12: ffff99c46deebb00
[37796.840010] R13: ffff99c5c1190898 R14: 0000000000000005 R15: 0000000000000008
[37796.840015] FS:  00007f6bcc673740(0000) GS:ffff99c5d7880000(0000) knlGS:0000000000000000
[37796.840019] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[37796.840022] CR2: 00007f6bcc6dc6c0 CR3: 0000000164c9a003 CR4: 00000000001606e0
[37796.840025] Call Trace:
[37796.840069]  ? iwl_mvm_get_tx_rate.isra.0+0x43/0xe0 [iwlmvm]
[37796.840098]  ? iwl_mvm_set_tx_cmd_rate+0x79/0xc0 [iwlmvm]
[37796.840176]  ? invoke_tx_handlers_late+0x782/0x860 [mac80211]
[37796.840207]  iwl_mvm_tx_mpdu+0x1cf/0x570 [iwlmvm]
[37796.840277]  ? ieee80211_tx_dequeue+0xa24/0xb30 [mac80211]
[37796.840308]  iwl_mvm_tx_skb+0x1a6/0x460 [iwlmvm]
[37796.840337]  iwl_mvm_mac_itxq_xmit+0x7d/0xe0 [iwlmvm]
[37796.840405]  ieee80211_queue_skb+0x2b4/0x450 [mac80211]
[37796.840474]  ieee80211_tx+0xd9/0x130 [mac80211]
[37796.840540]  __ieee80211_tx_skb_tid_band+0x60/0x80 [mac80211]
[37796.840607]  ieee80211_send_deauth_disassoc+0x100/0x120 [mac80211]
[37796.840675]  ieee80211_set_disassoc+0x376/0x590 [mac80211]
[37796.840688]  ? __switch_to_asm+0x40/0x70
[37796.840763]  ieee80211_mgd_deauth.cold+0x4a/0x1b8 [mac80211]
[37796.840836]  cfg80211_mlme_deauth+0xb3/0x1d0 [cfg80211]
[37796.840848]  ? startup_64+0x3/0x30
[37796.840907]  cfg80211_mlme_down+0x66/0x90 [cfg80211]
[37796.840968]  cfg80211_disconnect+0x129/0x1e0 [cfg80211]
[37796.841018]  cfg80211_leave+0x27/0x40 [cfg80211]
[37796.841064]  cfg80211_netdev_notifier_call+0x1a7/0x4e0 [cfg80211]
[37796.841093]  ? iwl_mvm_set_tim+0x50/0x50 [iwlmvm]
[37796.841162]  ? __iterate_interfaces+0x74/0x110 [mac80211]
[37796.841173]  ? inetdev_event+0x46/0x570
[37796.841238]  ? ieee80211_iterate_active_interfaces_atomic+0xd/0x10 [mac80211]
[37796.841264]  ? iwl_mvm_recalc_multicast+0x45/0x70 [iwlmvm]
[37796.841274]  notifier_call_chain+0x4c/0x70
[37796.841283]  __dev_close_many+0x5f/0x110
[37796.841290]  dev_close_many+0x9d/0x160
[37796.841298]  dev_close.part.0+0x64/0xa0
[37796.841352]  cfg80211_shutdown_all_interfaces+0x71/0xd0 [cfg80211]
[37796.841384]  cfg80211_rfkill_set_block+0x22/0x30 [cfg80211]
[37796.841391]  rfkill_set_block+0x92/0x140 [rfkill]
[37796.841397]  rfkill_fop_write+0x132/0x1d0 [rfkill]
[37796.841402]  vfs_write+0xb6/0x1a0
[37796.841406]  ksys_write+0xa7/0xe0
[37796.841411]  do_syscall_64+0x5f/0x1a0
[37796.841416]  ? page_fault+0x8/0x30
[37796.841421]  entry_SYSCALL_64_after_hwframe+0x44/0xa9
[37796.841425] RIP: 0033:0x7f6bcc763608
[37796.841429] 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 15 80 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
[37796.841431] RSP: 002b:00007fffa2ee2638 EFLAGS: 00000246 ORIG_RAX: 0000000000000001
[37796.841435] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f6bcc763608
[37796.841437] RDX: 0000000000000008 RSI: 00007fffa2ee2650 RDI: 0000000000000003
[37796.841439] RBP: 0000000000000000 R08: 000055a3a3a046c0 R09: 0000000000000000
[37796.841440] R10: 0000000000000000 R11: 0000000000000246 R12: 0000000000000003
[37796.841442] R13: 0000000000000000 R14: 0000000000000000 R15: 0000000000000000
[37796.841446] ---[ end trace 9c33bd6c1fcc3378 ]---
Comment 7 Dominik Mierzejewski 2019-09-19 13:10:33 UTC
Downstream bug report: https://bugzilla.redhat.com/show_bug.cgi?id=1753624 .
Comment 8 Dominik Mierzejewski 2020-04-12 09:13:36 UTC
Still 100% reproducible with 5.5.15 on Fedora 31.
Comment 9 Paul Menzel 2020-07-04 21:54:30 UTC
I have seen this since a while, and am still seeing this in Linux 5.7.6.

    $ more /proc/version
    Linux version 5.7.0-1-amd64 (debian-kernel@lists.debian.org) (gcc version 9.3.0 (Debian 9.3.0-14), GNU ld (GNU Binutils for Debian) 2.34) #1 SMP Debian 5.7.6-1 (2020-06-24)
Comment 10 Paul Menzel 2020-08-25 08:57:35 UTC
It’s still present in Linux 5.8.3.
Comment 11 Dominik Mierzejewski 2020-11-07 08:50:03 UTC
Still reproducible with Fedora 33 kernel 5.8.17-300.fc33.x86_64.
Comment 12 Dominik Mierzejewski 2020-11-12 20:26:07 UTC
And with 5.9.8-200.fc33.x86_64. Still 100% reproducible.

Note You need to log in before you can comment on or make changes to this bug.