2021-04-14 06:49:31

by Hans de Goede

[permalink] [raw]
Subject: iwlwifi prints a stacktrace _ registerdump when toggling rfkill on/off on a 3160 card

Hi All,

I just noticed these errors in my dmesg after toggling rfkill on/off
on a Lenovo yoga 300-11ibr. Note this device has a firmware rfkill
interface which results in the rfkill pin on the card actually being
toggled (the phy0 rfkill shows up as hardblocked when softblock is set
on the firmware interface).

[ 55.772139] iwlwifi 0000:02:00.0: RF_KILL bit toggled to disable radio.
[ 55.772174] iwlwifi 0000:02:00.0: reporting RF_KILL (radio disabled)
[ 55.784038] wlp2s0: deauthenticating from ac:22:05:16:f7:a9 by local choice (Reason: 3=DEAUTH_LEAVING)
[ 55.796078] ------------[ cut here ]------------
[ 55.796085] TX on unused queue 5
[ 55.796148] WARNING: CPU: 1 PID: 20 at drivers/net/wireless/intel/iwlwifi/pcie/tx.c:1475 iwl_trans_pcie_tx+0x99c/0xb30 [iwlwifi]
[ 55.796184] Modules linked in: rfcomm snd_seq_dummy snd_hrtimer ccm cmac bnep mei_hdcp intel_rapl_msr intel_powerclamp iTCO_wdt intel_pmc_bxt iTCO_vendor_support coretemp iwlmvm mac80211 kvm_intel libarc4 iwlwifi snd_hda_codec_hdmi kvm snd_hda_codec_realtek snd_hda_codec_generic ledtrig_audio snd_hda_intel snd_intel_dspcfg snd_intel_sdw_acpi irqbypass intel_cstate snd_hda_codec vfat snd_hda_core fat cfg80211 snd_hwdep snd_seq uvcvideo videobuf2_vmalloc videobuf2_memops videobuf2_v4l2 videobuf2_common pcspkr snd_seq_device videodev joydev mc snd_pcm asus_wmi wmi_bmof i2c_i801 r8169 i2c_smbus btusb hci_uart snd_timer processor_thermal_device processor_thermal_rfim processor_thermal_mbox intel_xhci_usb_role_switch processor_thermal_rapl mei_txe btqca snd intel_rapl_common btrtl mei btbcm int340x_thermal_zone lpc_ich btintel soundcore intel_soc_dts_iosf bluetooth nxp_nci_i2c nxp_nci nci ideapad_laptop platform_profile sparse_keymap nfc dw_dmac ecdh_generic ecc rfkill_gpio rfkill
[ 55.796282] int3400_thermal intel_int0002_vgpio acpi_thermal_rel zram ip_tables i915 mmc_block hid_multitouch crct10dif_pclmul crc32_pclmul i2c_algo_bit crc32c_intel drm_kms_helper ghash_clmulni_intel cec serio_raw drm sdhci_pci cqhci drm_privacy_screen_helper wmi sdhci_acpi sdhci video mmc_core i2c_hid_acpi i2c_hid pwm_lpss_platform pwm_lpss i2c_dev fuse
[ 55.796321] CPU: 1 PID: 20 Comm: kworker/1:0 Not tainted 5.12.0-rc6+ #301
[ 55.796326] Hardware name: LENOVO 80M1/Mini, BIOS C7CN31WW 08/05/2016
[ 55.796329] Workqueue: events cfg80211_rfkill_block_work [cfg80211]
[ 55.796422] RIP: 0010:iwl_trans_pcie_tx+0x99c/0xb30 [iwlwifi]
[ 55.796443] Code: 3d 0e 71 03 00 00 b8 ea ff ff ff 0f 85 84 f9 ff ff 44 89 ee 48 c7 c7 63 5e ea c0 89 04 24 c6 05 ef 70 03 00 01 e8 28 40 d0 c7 <0f> 0b 8b 04 24 e9 61 f9 ff ff 80 3d dd 70 03 00 00 0f 85 2d f9 ff
[ 55.796447] RSP: 0000:ffffb406800ef790 EFLAGS: 00010286
[ 55.796450] RAX: 0000000000000014 RBX: ffff9bb5c37983e8 RCX: 0000000000000027
[ 55.796453] RDX: ffff9bb6394985c8 RSI: 0000000000000001 RDI: ffff9bb6394985c0
[ 55.796455] RBP: ffff9bb5ccbc9f48 R08: 0000000000000000 R09: ffffb406800ef5c0
[ 55.796458] R10: ffffb406800ef5b8 R11: ffffffff89745ee8 R12: ffff9bb5c466da00
[ 55.796460] R13: 0000000000000005 R14: 0000000000000005 R15: 0000000000000008
[ 55.796462] FS: 0000000000000000(0000) GS:ffff9bb639480000(0000) knlGS:0000000000000000
[ 55.796465] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 55.796468] CR2: 00005646d9233080 CR3: 000000001faac000 CR4: 00000000001006e0
[ 55.796471] Call Trace:
[ 55.796481] ? iwl_mvm_set_tx_cmd_rate+0xfc/0x140 [iwlmvm]
[ 55.796513] ? iwl_mvm_set_tx_params+0x1b4/0x580 [iwlmvm]
[ 55.796535] iwl_mvm_tx_mpdu+0x1d0/0x5a0 [iwlmvm]
[ 55.796559] iwl_mvm_tx_skb_sta+0x1ab/0x490 [iwlmvm]
[ 55.796583] iwl_mvm_tx_skb+0x17/0x40 [iwlmvm]
[ 55.796603] iwl_mvm_mac_itxq_xmit+0x7e/0xf0 [iwlmvm]
[ 55.796624] ieee80211_queue_skb+0x47e/0x690 [mac80211]
[ 55.796722] ieee80211_tx+0x9e/0x110 [mac80211]
[ 55.796785] __ieee80211_tx_skb_tid_band+0x69/0x80 [mac80211]
[ 55.796846] ieee80211_send_deauth_disassoc+0xf5/0x120 [mac80211]
[ 55.796950] ieee80211_set_disassoc+0x3a7/0x470 [mac80211]
[ 55.797019] ieee80211_mgd_deauth.cold+0x49/0x1cb [mac80211]
[ 55.797087] cfg80211_mlme_deauth+0xa3/0x190 [cfg80211]
[ 55.797156] ? startup_64+0x3/0x40
[ 55.797164] cfg80211_mlme_down+0x56/0x60 [cfg80211]
[ 55.797224] cfg80211_disconnect+0x15c/0x1f0 [cfg80211]
[ 55.797286] cfg80211_netdev_notifier_call+0x101/0x470 [cfg80211]
[ 55.797343] ? __inode_wait_for_writeback+0x6e/0xc0
[ 55.797349] ? iwl_mvm_send_cmd_pdu+0x4e/0x80 [iwlmvm]
[ 55.797371] ? iwl_mvm_send_cmd+0x1b/0x50 [iwlmvm]
[ 55.797392] ? iwl_mvm_mc_iface_iterator+0x9d/0xd0 [iwlmvm]
[ 55.797411] ? __iterate_interfaces+0x9f/0x110 [mac80211]
[ 55.797473] ? iwl_mvm_set_tim+0x50/0x50 [iwlmvm]
[ 55.797492] ? rtnl_is_locked+0x11/0x20
[ 55.797498] ? inetdev_event+0x47/0x680
[ 55.797503] ? ieee80211_iterate_active_interfaces_atomic+0xd/0x20 [mac80211]
[ 55.797564] ? iwl_mvm_recalc_multicast+0x35/0x50 [iwlmvm]
[ 55.797584] raw_notifier_call_chain+0x44/0x60
[ 55.797590] __dev_close_many+0x4f/0xf0
[ 55.797595] dev_close_many+0x7b/0x110
[ 55.797600] dev_close+0x55/0x80
[ 55.797603] cfg80211_shutdown_all_interfaces+0x45/0xe0 [cfg80211]
[ 55.797660] cfg80211_rfkill_block_work+0x1a/0x20 [cfg80211]
[ 55.797715] process_one_work+0x1ec/0x380
[ 55.797720] worker_thread+0x53/0x3e0
[ 55.797723] ? process_one_work+0x380/0x380
[ 55.797726] kthread+0x11b/0x140
[ 55.797731] ? kthread_associate_blkcg+0xa0/0xa0
[ 55.797735] ret_from_fork+0x22/0x30
[ 55.797741] ---[ end trace 5d8b65a1d8014047 ]---
[ 55.835312] ------------[ cut here ]------------
[ 55.835320] Timeout waiting for hardware access (CSR_GP_CNTRL 0x000003d8)
[ 55.835372] WARNING: CPU: 1 PID: 20 at drivers/net/wireless/intel/iwlwifi/pcie/trans.c:2019 iwl_trans_pcie_grab_nic_access+0x19d/0x1d0 [iwlwifi]
[ 55.835405] Modules linked in: rfcomm snd_seq_dummy snd_hrtimer ccm cmac bnep mei_hdcp intel_rapl_msr intel_powerclamp iTCO_wdt intel_pmc_bxt iTCO_vendor_support coretemp iwlmvm mac80211 kvm_intel libarc4 iwlwifi snd_hda_codec_hdmi kvm snd_hda_codec_realtek snd_hda_codec_generic ledtrig_audio snd_hda_intel snd_intel_dspcfg snd_intel_sdw_acpi irqbypass intel_cstate snd_hda_codec vfat snd_hda_core fat cfg80211 snd_hwdep snd_seq uvcvideo videobuf2_vmalloc videobuf2_memops videobuf2_v4l2 videobuf2_common pcspkr snd_seq_device videodev joydev mc snd_pcm asus_wmi wmi_bmof i2c_i801 r8169 i2c_smbus btusb hci_uart snd_timer processor_thermal_device processor_thermal_rfim processor_thermal_mbox intel_xhci_usb_role_switch processor_thermal_rapl mei_txe btqca snd intel_rapl_common btrtl mei btbcm int340x_thermal_zone lpc_ich btintel soundcore intel_soc_dts_iosf bluetooth nxp_nci_i2c nxp_nci nci ideapad_laptop platform_profile sparse_keymap nfc dw_dmac ecdh_generic ecc rfkill_gpio rfkill
[ 55.835503] int3400_thermal intel_int0002_vgpio acpi_thermal_rel zram ip_tables i915 mmc_block hid_multitouch crct10dif_pclmul crc32_pclmul i2c_algo_bit crc32c_intel drm_kms_helper ghash_clmulni_intel cec serio_raw drm sdhci_pci cqhci drm_privacy_screen_helper wmi sdhci_acpi sdhci video mmc_core i2c_hid_acpi i2c_hid pwm_lpss_platform pwm_lpss i2c_dev fuse
[ 55.835542] CPU: 1 PID: 20 Comm: kworker/1:0 Tainted: G W 5.12.0-rc6+ #301
[ 55.835547] Hardware name: LENOVO 80M1/Mini, BIOS C7CN31WW 08/05/2016
[ 55.835550] Workqueue: events cfg80211_rfkill_block_work [cfg80211]
[ 55.835636] RIP: 0010:iwl_trans_pcie_grab_nic_access+0x19d/0x1d0 [iwlwifi]
[ 55.835657] Code: ad c8 48 8d 53 08 bf 00 20 00 00 e8 3d 45 27 c7 e9 40 ff ff ff 89 c6 48 c7 c7 48 92 ea c0 c6 05 14 47 03 00 01 e8 47 16 d0 c7 <0f> 0b e9 fc fe ff ff 48 8b 7d 38 48 c7 c1 b0 92 ea c0 31 d2 31 f6
[ 55.835661] RSP: 0018:ffffb406800efc08 EFLAGS: 00010296
[ 55.835665] RAX: 000000000000003d RBX: 00000000000003d8 RCX: 0000000000000027
[ 55.835667] RDX: ffff9bb6394985c8 RSI: 0000000000000001 RDI: ffff9bb6394985c0
[ 55.835669] RBP: ffff9bb5c6760028 R08: 0000000000000000 R09: ffffb406800efa38
[ 55.835672] R10: ffffb406800efa30 R11: ffffffff89745ee8 R12: 0000000000000000
[ 55.835674] R13: ffff9bb5c67626d4 R14: ffff9bb5ccbc8d18 R15: ffff9bb5ccdb5548
[ 55.835677] FS: 0000000000000000(0000) GS:ffff9bb639480000(0000) knlGS:0000000000000000
[ 55.835680] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 55.835682] CR2: 00005646d92400f0 CR3: 000000000fe50000 CR4: 00000000001006e0
[ 55.835685] Call Trace:
[ 55.835695] iwl_set_bits_prph+0x20/0x70 [iwlwifi]
[ 55.835716] iwl_fw_dbg_stop_restart_recording.part.0+0x23b/0x260 [iwlwifi]
[ 55.835739] ? __cancel_work_timer+0xea/0x170
[ 55.835747] iwl_mvm_stop_device+0x36/0x70 [iwlmvm]
[ 55.835773] __iwl_mvm_mac_stop+0x5c/0x110 [iwlmvm]
[ 55.835794] iwl_mvm_mac_stop+0x6a/0x80 [iwlmvm]
[ 55.835814] drv_stop+0x33/0xd0 [mac80211]
[ 55.835915] ieee80211_do_stop+0x52c/0x850 [mac80211]
[ 55.835977] ? dev_reset_queue+0x30/0x80
[ 55.835985] ieee80211_stop+0x2a/0x40 [mac80211]
[ 55.836044] __dev_close_many+0x8e/0xf0
[ 55.836050] dev_close_many+0x7b/0x110
[ 55.836054] dev_close+0x55/0x80
[ 55.836058] cfg80211_shutdown_all_interfaces+0x45/0xe0 [cfg80211]
[ 55.836123] cfg80211_rfkill_block_work+0x1a/0x20 [cfg80211]
[ 55.836178] process_one_work+0x1ec/0x380
[ 55.836182] worker_thread+0x53/0x3e0
[ 55.836186] ? process_one_work+0x380/0x380
[ 55.836189] kthread+0x11b/0x140
[ 55.836193] ? kthread_associate_blkcg+0xa0/0xa0
[ 55.836197] ret_from_fork+0x22/0x30
[ 55.836205] ---[ end trace 5d8b65a1d8014048 ]---
[ 55.836213] iwlwifi 0000:02:00.0: iwlwifi transaction failed, dumping registers
[ 55.836216] iwlwifi 0000:02:00.0: iwlwifi device config registers:
[ 55.836509] iwlwifi 0000:02:00.0: 00000000: 08b48086 00100406 02800093 00000010 91100004 00000000 00000000 00000000
[ 55.836513] iwlwifi 0000:02:00.0: 00000020: 00000000 00000000 00000000 82708086 00000000 000000c8 00000000 000001ff
[ 55.836517] iwlwifi 0000:02:00.0: 00000040: 00020010 10008ec0 00100c10 0006ec11 10110042 00000000 00000000 00000000
[ 55.836521] iwlwifi 0000:02:00.0: 00000060: 00000000 00080812 00000405 00000000 00010001 00000000 00000000 00000000
[ 55.836524] iwlwifi 0000:02:00.0: 00000080: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000
[ 55.836528] iwlwifi 0000:02:00.0: 000000a0: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000
[ 55.836531] iwlwifi 0000:02:00.0: 000000c0: 00000000 00000000 c823d001 0d000000 00814005 fee02004 00000000 0000002c
[ 55.836535] iwlwifi 0000:02:00.0: 000000e0: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000
[ 55.836538] iwlwifi 0000:02:00.0: 00000100: 14010001 00000000 00000000 00462031 00000000 00002000 00000000 00000000
[ 55.836542] iwlwifi 0000:02:00.0: 00000120: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000
[ 55.836546] iwlwifi 0000:02:00.0: 00000140: 14c10003 ffe54c6e e4029bff 15410018 080f080f 0001000b 0141cafe 00f01e1f
[ 55.836548] iwlwifi 0000:02:00.0: iwlwifi device memory mapped registers:
[ 55.836612] iwlwifi 0000:02:00.0: 00000000: 00c00000 80000000 00000000 00000080 00000000 00000000 00000000 00000000
[ 55.836615] iwlwifi 0000:02:00.0: 00000020: 00000001 000003d8 00000164 00000000 80000000 803a0000 80008040 00080042
[ 55.836630] iwlwifi 0000:02:00.0: iwlwifi device AER capability structure:
[ 55.836673] iwlwifi 0000:02:00.0: 00000000: 14010001 00000000 00000000 00462031 00000000 00002000 00000000 00000000
[ 55.836676] iwlwifi 0000:02:00.0: 00000020: 00000000 00000000 00000000
[ 55.836679] iwlwifi 0000:02:00.0: iwlwifi parent port (0000:00:1c.1) config registers:
[ 55.836801] iwlwifi 0000:00:1c.1: 00000000: 22ca8086 00100407 06040035 00810010 00000000 00000000 00020200 200000f0
[ 55.836805] iwlwifi 0000:00:1c.1: 00000020: 91109110 0001fff1 00000000 00000000 00000000 00000040 00000000 000202ff
[ 55.836808] iwlwifi 0000:00:1c.1: 00000040: 01428010 00008000 00100000 02323c12 70110042 000cb200 01400000 00000000
[ 55.836812] iwlwifi 0000:00:1c.1: 00000060: 00000000 00000817 00000400 00000000 00010042 00000000 00000000 00000000
[ 55.836816] iwlwifi 0000:00:1c.1: 00000080: 00019005 fee02004 00000025 00000000 0000a00d 390217aa 00000000 00000000
[ 55.836819] iwlwifi 0000:00:1c.1: 000000a0: c8030001 00000000 00000000 00000000 00000000 00000000 00000000 00000000
[ 55.836823] iwlwifi 0000:00:1c.1: 000000c0: 00000000 00000000 00000000 00000000 01000000 00000842 09118000 00000000
[ 55.836826] iwlwifi 0000:00:1c.1: 000000e0: 00300300 8c548c54 00000017 00000000 00000050 0c000040 04350f1c 02000004
[ 55.836830] iwlwifi 0000:00:1c.1: 00000100: 00000000 00000000 00000000 00060011 00000000 00002000 00000000 00000000
[ 55.836833] iwlwifi 0000:00:1c.1: 00000120: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000
[ 55.836836] iwlwifi 0000:00:1c.1: 00000140: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000
[ 55.836840] iwlwifi 0000:00:1c.1: 00000160: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000
[ 55.836843] iwlwifi 0000:00:1c.1: 00000180: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000
[ 55.836847] iwlwifi 0000:00:1c.1: 000001a0: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000
[ 55.836850] iwlwifi 0000:00:1c.1: 000001c0: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000
[ 55.836853] iwlwifi 0000:00:1c.1: 000001e0: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000
[ 55.836856] iwlwifi 0000:00:1c.1: 00000200: 00010000 0028281f 00000010
[ 55.896972] usb 1-4: USB disconnect, device number 2
[ 57.849271] Bluetooth: hci0: command 0x0c03 tx timeout
[ 58.102980] Bluetooth: hci0: HCI reset during shutdown failed
[ 64.342771] iwlwifi 0000:02:00.0: RF_KILL bit toggled to enable radio.
[ 64.342796] iwlwifi 0000:02:00.0: reporting RF_KILL (radio enabled)
[ 64.601150] usb 1-4: new full-speed USB device number 4 using xhci_hcd
[ 64.730107] usb 1-4: New USB device found, idVendor=8087, idProduct=07dc, bcdDevice= 0.01
[ 64.730126] usb 1-4: New USB device strings: Mfr=0, Product=0, SerialNumber=0
[ 64.745954] Bluetooth: hci0: read Intel version: 3707100100012d0d00
[ 64.751823] Bluetooth: hci0: Intel Bluetooth firmware file: intel/ibt-hw-37.7.10-fw-1.0.1.2d.d.bseq
[ 64.925885] Bluetooth: hci0: unexpected event for opcode 0xfc2f
[ 64.941930] Bluetooth: hci0: Intel BT fw patch 0x27 completed & activated

These messages are the result of a single off + back-on toggle (using the "airplane mode" button
which toggles all radios.

Regards,

Hans