Bug 215488
Summary: | 5.16.0+: iwlwifi: ax200: Firmware crashes on startup | ||
---|---|---|---|
Product: | Drivers | Reporter: | Bernhard Rosenkränzer (bero) |
Component: | network-wireless-intel | Assignee: | Default virtual assignee for network-wireless-intel (drivers_network-wireless-intel) |
Status: | RESOLVED CODE_FIX | ||
Severity: | normal | CC: | b.buschinski, golan.ben.ami, grzegorz.alibozek, linux, omikheev, youling257 |
Priority: | P1 | ||
Hardware: | All | ||
OS: | Linux | ||
Kernel Version: | 5.16.0 | Subsystem: | |
Regression: | Yes | Bisected commit-id: | |
Attachments: |
Full dmesg
full dmesg from spectre x360 Fix - formatted patch. |
Description
Bernhard Rosenkränzer
2022-01-13 10:04:36 UTC
Hi Bernhard, Thanks for the bisection. A couple of comments: 1. Thanks for the bisection. Just FYI, only changing to 68 will cause other failures as the fw api's change among versions. 2. We have not encountered this fw error during our validation cycles. I'm checking with the relevant team what data we'll need to repro and debug. 3. Is wifi functional for you? i do see you are associated: wlp4s0: associated 4. Can you add the full dmesg just to see the full picture? (it's a fw error so won't help much, but still) Thanks, Golan Created attachment 300281 [details]
Full dmesg
Attaching full dmesg output.
WiFi does not work at all, but the "associated" message is there (right between "PHY ctxt cmd error. ret=-5" and "Microcode SW error detected. Restarting 0x0", all happening at the same second). Possibly it "works" for a couple of milliseconds in between.
[Tue Jan 11 20:05:23 2022] iwlwifi 0000:04:00.0: failed to update power mode
[Tue Jan 11 20:05:24 2022] iwlwifi 0000:04:00.0: PHY ctxt cmd error. ret=-5
[Tue Jan 11 20:05:24 2022] wlp4s0: associated
[Tue Jan 11 20:05:24 2022] iwlwifi 0000:04:00.0: Microcode SW error detected. Restarting 0x0.
Thanks! I can now see that the reason for the assert is a bad bcast filtering sent by the driver, which the FW rejects. To solve it, i'd need a trace recording (https://wireless.wiki.kernel.org/en/users/drivers/iwlwifi/debugging#tracing) specifically, can you run with this: sudo trace-cmd record -e iwlwifi -e mac80211 -e cfg80211 -e iwlwifi_msg -e iwlwifi_dev* -e iwlwifi_dev_hcmd We have a direction for a fix. A *valid* W/A till we deliver will be to compile with IWLWIFI_BCAST_FILTERING disabled. I test linux kernel 5.17, it load cc-a0-68.ucode. [ 1144.202230] iwlwifi 0000:07:00.0: Direct firmware load for iwlwifi-cc-a0-69.ucode failed with error -2 [ 1144.202548] iwlwifi 0000:07:00.0: api flags index 2 larger than supported by driver [ 1144.202560] iwlwifi 0000:07:00.0: TLV_FW_FSEQ_VERSION: FSEQ Version: 89.3.35.37 [ 1144.202875] iwlwifi 0000:07:00.0: loaded firmware version 68.01d30b0c.0 cc-a0-68.ucode op_mode iwlmvm [ 1144.202915] iwlwifi 0000:07:00.0: Detected Intel(R) Wi-Fi 6 AX200 160MHz, REV=0x340 [ 1144.202997] thermal thermal_zone0: failed to read out thermal zone (-61) [ 1144.326460] iwlwifi 0000:07:00.0: Detected RF HR B3, rfid=0x10a100 [ 1144.391747] iwlwifi 0000:07:00.0: base HW address: e0:d4:e8:48:f5:67 [ 1144.593162] ACPI: \: failed to evaluate _DSM (0x1001) [ 1144.593169] ACPI: \: failed to evaluate _DSM (0x1001) [ 1144.593171] ACPI: \: failed to evaluate _DSM (0x1001) [ 1144.593172] ACPI: \: failed to evaluate _DSM (0x1001) [ 1144.593174] ACPI: \: failed to evaluate _DSM (0x1001) [ 1144.593175] ACPI: \: failed to evaluate _DSM (0x1001) [ 1144.720651] wlan0: authenticate with b4:a8:98:22:f9:ac [ 1144.724639] wlan0: send auth to b4:a8:98:22:f9:ac (try 1/3) [ 1144.762553] wlan0: authenticated [ 1144.763210] wlan0: associate with b4:a8:98:22:f9:ac (try 1/3) [ 1144.765361] wlan0: RX AssocResp from b4:a8:98:22:f9:ac (capab=0x1531 status=0 aid=3) [ 1144.768697] iwlwifi 0000:07:00.0: Microcode SW error detected. Restarting 0x0. [ 1144.768812] iwlwifi 0000:07:00.0: Start IWL Error Log Dump: [ 1144.768815] iwlwifi 0000:07:00.0: Transport status: 0x0000004B, valid: 6 [ 1144.768819] iwlwifi 0000:07:00.0: Loaded firmware version: 68.01d30b0c.0 cc-a0-68.ucode [ 1144.768823] iwlwifi 0000:07:00.0: 0x00001062 | ADVANCED_SYSASSERT [ 1144.768828] iwlwifi 0000:07:00.0: 0x00A0A200 | trm_hw_status0 [ 1144.768831] iwlwifi 0000:07:00.0: 0x00000000 | trm_hw_status1 [ 1144.768833] iwlwifi 0000:07:00.0: 0x004FB322 | branchlink2 [ 1144.768836] iwlwifi 0000:07:00.0: 0x004F1B86 | interruptlink1 [ 1144.768838] iwlwifi 0000:07:00.0: 0x004F1B86 | interruptlink2 [ 1144.768841] iwlwifi 0000:07:00.0: 0x00000000 | data1 [ 1144.768843] iwlwifi 0000:07:00.0: 0x00000001 | data2 [ 1144.768846] iwlwifi 0000:07:00.0: 0xDEADBEEF | data3 [ 1144.768848] iwlwifi 0000:07:00.0: 0x00000CFC | beacon time [ 1144.768850] iwlwifi 0000:07:00.0: 0x215DD333 | tsf low [ 1144.768853] iwlwifi 0000:07:00.0: 0x00000069 | tsf hi [ 1144.768855] iwlwifi 0000:07:00.0: 0x00000000 | time gp1 [ 1144.768857] iwlwifi 0000:07:00.0: 0x00055440 | time gp2 [ 1144.768860] iwlwifi 0000:07:00.0: 0x00000001 | uCode revision type [ 1144.768862] iwlwifi 0000:07:00.0: 0x00000044 | uCode version major [ 1144.768864] iwlwifi 0000:07:00.0: 0x01D30B0C | uCode version minor [ 1144.768867] iwlwifi 0000:07:00.0: 0x00000340 | hw version [ 1144.768869] iwlwifi 0000:07:00.0: 0x18C89000 | board version [ 1144.768871] iwlwifi 0000:07:00.0: 0x80ABFC32 | hcmd [ 1144.768874] iwlwifi 0000:07:00.0: 0x24020000 | isr0 [ 1144.768876] iwlwifi 0000:07:00.0: 0x21000000 | isr1 [ 1144.768878] iwlwifi 0000:07:00.0: 0x18F00002 | isr2 [ 1144.768880] iwlwifi 0000:07:00.0: 0x00C3000C | isr3 [ 1144.768882] iwlwifi 0000:07:00.0: 0x00000000 | isr4 [ 1144.768885] iwlwifi 0000:07:00.0: 0x00340103 | last cmd Id [ 1144.768887] iwlwifi 0000:07:00.0: 0x00014D7A | wait_event [ 1144.768889] iwlwifi 0000:07:00.0: 0x00000288 | l2p_control [ 1144.768891] iwlwifi 0000:07:00.0: 0x00018034 | l2p_duration [ 1144.768894] iwlwifi 0000:07:00.0: 0x000000BF | l2p_mhvalid [ 1144.768896] iwlwifi 0000:07:00.0: 0x000000EF | l2p_addr_match [ 1144.768898] iwlwifi 0000:07:00.0: 0x00000009 | lmpm_pmg_sel [ 1144.768900] iwlwifi 0000:07:00.0: 0x00000000 | timestamp [ 1144.768902] iwlwifi 0000:07:00.0: 0x00003890 | flow_handler [ 1144.768947] iwlwifi 0000:07:00.0: Start IWL Error Log Dump: [ 1144.768949] iwlwifi 0000:07:00.0: Transport status: 0x0000004B, valid: 7 [ 1144.768952] iwlwifi 0000:07:00.0: 0x20000070 | NMI_INTERRUPT_LMAC_FATAL [ 1144.768955] iwlwifi 0000:07:00.0: 0x00000000 | umac branchlink1 [ 1144.768958] iwlwifi 0000:07:00.0: 0x80455D20 | umac branchlink2 [ 1144.768960] iwlwifi 0000:07:00.0: 0x804748F2 | umac interruptlink1 [ 1144.768962] iwlwifi 0000:07:00.0: 0xC0081626 | umac interruptlink2 [ 1144.768965] iwlwifi 0000:07:00.0: 0x00000400 | umac data1 [ 1144.768967] iwlwifi 0000:07:00.0: 0xC0081626 | umac data2 [ 1144.768969] iwlwifi 0000:07:00.0: 0x00000000 | umac data3 [ 1144.768971] iwlwifi 0000:07:00.0: 0x00000044 | umac major [ 1144.768973] iwlwifi 0000:07:00.0: 0x01D30B0C | umac minor [ 1144.768974] iwlwifi 0000:07:00.0: 0x0005545C | frame pointer [ 1144.768976] iwlwifi 0000:07:00.0: 0xC0887EFC | stack pointer [ 1144.768978] iwlwifi 0000:07:00.0: 0x003501CF | last host cmd [ 1144.768979] iwlwifi 0000:07:00.0: 0x01020044 | isr status reg [ 1144.768993] iwlwifi 0000:07:00.0: IML/ROM dump: [ 1144.768994] iwlwifi 0000:07:00.0: 0x00000003 | IML/ROM error/state [ 1144.769008] iwlwifi 0000:07:00.0: 0x00005AD2 | IML/ROM data1 [ 1144.769021] iwlwifi 0000:07:00.0: 0x00000080 | IML/ROM WFPM_AUTH_KEY_0 [ 1144.769030] iwlwifi 0000:07:00.0: Fseq Registers: [ 1144.769034] iwlwifi 0000:07:00.0: 0x60000000 | FSEQ_ERROR_CODE [ 1144.769039] iwlwifi 0000:07:00.0: 0x80290021 | FSEQ_TOP_INIT_VERSION [ 1144.769043] iwlwifi 0000:07:00.0: 0x00050008 | FSEQ_CNVIO_INIT_VERSION [ 1144.769048] iwlwifi 0000:07:00.0: 0x0000A503 | FSEQ_OTP_VERSION [ 1144.769052] iwlwifi 0000:07:00.0: 0x80000003 | FSEQ_TOP_CONTENT_VERSION [ 1144.769057] iwlwifi 0000:07:00.0: 0x4552414E | FSEQ_ALIVE_TOKEN [ 1144.769061] iwlwifi 0000:07:00.0: 0x00100530 | FSEQ_CNVI_ID [ 1144.769066] iwlwifi 0000:07:00.0: 0x00000532 | FSEQ_CNVR_ID [ 1144.769070] iwlwifi 0000:07:00.0: 0x00100530 | CNVI_AUX_MISC_CHIP [ 1144.769077] iwlwifi 0000:07:00.0: 0x00000532 | CNVR_AUX_MISC_CHIP [ 1144.769084] iwlwifi 0000:07:00.0: 0x05B0905B | CNVR_SCU_SD_REGS_SD_REG_DIG_DCDC_VTRIM [ 1144.769090] iwlwifi 0000:07:00.0: 0x0000025B | CNVR_SCU_SD_REGS_SD_REG_ACTIVE_VDIG_MIRROR [ 1144.769267] iwlwifi 0000:07:00.0: WRT: Collecting data: ini trigger 4 fired (delay=0ms). [ 1144.769273] ieee80211 phy8: Hardware restart was requested [ 1144.769289] iwlwifi 0000:07:00.0: FW error in SYNC CMD SCAN_CFG_CMD [ 1144.769295] CPU: 2 PID: 13249 Comm: kworker/u16:125 Tainted: G W 5.17.0-rc1-android-x86_64+ #1 [ 1144.769301] Hardware name: To Be Filled By O.E.M. To Be Filled By O.E.M./B450 Gaming-ITX/ac, BIOS P4.10 07/08/2020 [ 1144.769304] Workqueue: phy8 ieee80211_iface_work [mac80211] [ 1144.769375] Call Trace: [ 1144.769379] <TASK> [ 1144.769382] dump_stack_lvl+0x34/0x44 [ 1144.769394] iwl_trans_txq_send_hcmd+0x384/0x390 [iwlwifi] [ 1144.769424] ? wait_woken+0x70/0x70 [ 1144.769433] iwl_trans_send_cmd+0x5a/0xe0 [iwlwifi] [ 1144.769459] iwl_mvm_send_cmd+0x12/0x40 [iwlmvm] [ 1144.769480] iwl_mvm_config_scan+0x104/0x170 [iwlmvm] [ 1144.769505] iwl_mvm_bss_info_changed+0x69d/0x7a0 [iwlmvm] [ 1144.769524] drv_bss_info_changed+0x8f/0x1a0 [mac80211] [ 1144.769581] ieee80211_sta_rx_queued_mgmt.cold+0x10da/0x1397 [mac80211] [ 1144.769663] ieee80211_iface_work+0x2fb/0x410 [mac80211] [ 1144.769728] ? __schedule+0x2f7/0x900 [ 1144.769733] process_one_work+0x1c3/0x3c0 [ 1144.769740] worker_thread+0x4d/0x3c0 [ 1144.769746] ? rescuer_thread+0x390/0x390 [ 1144.769751] kthread+0xd8/0x100 [ 1144.769756] ? kthread_complete_and_exit+0x20/0x20 [ 1144.769762] ret_from_fork+0x22/0x30 [ 1144.769769] </TASK> [ 1144.769787] ------------[ cut here ]------------ [ 1144.769789] WARNING: CPU: 2 PID: 13249 at iwl_mvm_bss_info_changed+0x423/0x7a0 [iwlmvm] [ 1144.769808] Modules linked in: hid_multitouch iwlmvm iwlwifi nct6775 hwmon_vid ccm binfmt_misc crc32_pclmul crc32c_intel kvm_amd kvm snd_hda_codec_realtek snd_hda_codec_generic ledtrig_audio snd_hda_codec_hdmi snd_hda_intel snd_hda_codec snd_hda_core snd_hwdep snd_pcm snd_timer snd soundcore snd_intel_dspcfg amdgpu drm_kms_helper cec fb_sys_fops syscopyarea sysfillrect sysimgblt gpu_sched drm_ttm_helper ttm drm ccp rng_core btusb btrtl btbcm btintel mac80211 libarc4 bluetooth ecdh_generic ecc cfg80211 igb i2c_algo_bit k10temp gpio_amdpt gpio_generic efi_pstore sdcardfs [last unloaded: hid_multitouch] [ 1144.769865] CPU: 2 PID: 13249 Comm: kworker/u16:125 Tainted: G W 5.17.0-rc1-android-x86_64+ #1 [ 1144.769870] Hardware name: To Be Filled By O.E.M. To Be Filled By O.E.M./B450 Gaming-ITX/ac, BIOS P4.10 07/08/2020 [ 1144.769872] Workqueue: phy8 ieee80211_iface_work [mac80211] [ 1144.769936] RIP: 0010:iwl_mvm_bss_info_changed+0x423/0x7a0 [iwlmvm] [ 1144.769954] Code: e0 36 0f 83 a1 02 00 00 31 d2 4c 89 e6 4c 89 f7 e8 d2 a1 01 00 31 d2 4c 89 e6 4c 89 f7 e8 55 51 02 00 85 c0 0f 84 b5 fd ff ff <0f> 0b e9 ae fd ff ff 49 8b 45 18 f6 80 c7 00 00 00 20 0f 84 d2 fd [ 1144.769959] RSP: 0018:ffff944888447d00 EFLAGS: 00010282 [ 1144.769963] RAX: 00000000fffffffb RBX: 000000002010200d RCX: 0000000000000000 [ 1144.769966] RDX: 0000000000000000 RSI: ffff944888447c50 RDI: ffff88b5d8c34028 [ 1144.769969] RBP: ffff944888447d68 R08: ffff944888447cb4 R09: ffff88b5944020a8 [ 1144.769971] R10: 000000000000003c R11: ffffffff8e245440 R12: ffff88b422ff5720 [ 1144.769974] R13: ffff88b5944020a0 R14: ffff88b5944020a8 R15: ffff88b5944020d8 [ 1144.769977] FS: 0000000000000000(0000) GS:ffff88b616e80000(0000) knlGS:0000000000000000 [ 1144.769980] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 [ 1144.769983] CR2: 00000000ee248000 CR3: 0000000122bba000 CR4: 00000000001506e0 [ 1144.769986] Call Trace: [ 1144.769988] <TASK> [ 1144.769991] drv_bss_info_changed+0x8f/0x1a0 [mac80211] [ 1144.770047] ieee80211_sta_rx_queued_mgmt.cold+0x10da/0x1397 [mac80211] [ 1144.770128] ieee80211_iface_work+0x2fb/0x410 [mac80211] [ 1144.770204] ? __schedule+0x2f7/0x900 [ 1144.770209] process_one_work+0x1c3/0x3c0 [ 1144.770215] worker_thread+0x4d/0x3c0 [ 1144.770220] ? rescuer_thread+0x390/0x390 [ 1144.770226] kthread+0xd8/0x100 [ 1144.770230] ? kthread_complete_and_exit+0x20/0x20 [ 1144.770235] ret_from_fork+0x22/0x30 [ 1144.770241] </TASK> [ 1144.770243] ---[ end trace 0000000000000000 ]--- [ 1144.770247] iwlwifi 0000:07:00.0: failed to update power mode Fails on HP Spectre 360, attaching dmesg Created attachment 300304 [details]
full dmesg from spectre x360
confirming that IWLWIFI_BCAST_FILTERING=n works Created attachment 300305 [details]
Fix - formatted patch.
Fix for 1062 - remove the deprecated feature.
Hi Guys, Whoever getting this signature 0x00001062 | ADVANCED_SYSASSERT - you have the same bug. As it wrote above, the valid WA is to disable IWLWIFI_BCAST_FILTERING. Next, we're going to send the patch I attached here to remove this deprecated feature. Sorry for the inconvenience. Moving to resolved and will close once fix lands to ToT. (In reply to Golan Ben Ami from comment #9) > Created attachment 300305 [details] > Fix - formatted patch. > > Fix for 1062 - remove the deprecated feature. I can't apply this patch with torvalds linux master tree. Yes, it's a big patch with multiple changes. I'll add here the link to the patch once we send it. (In reply to Golan Ben Ami from comment #12) > Yes, it's a big patch with multiple changes. > I'll add here the link to the patch once we send it. Just wondering: is it this one? https://lore.kernel.org/all/20220128124851.538580-1-luca@coelho.fi/ (In reply to Thorsten Leemhuis from comment #13) > (In reply to Golan Ben Ami from comment #12) > > Yes, it's a big patch with multiple changes. > > I'll add here the link to the patch once we send it. > > Just wondering: is it this one? > https://lore.kernel.org/all/20220128124851.538580-1-luca@coelho.fi/ Yes. *** Bug 215550 has been marked as a duplicate of this bug. *** |