Bug 218632 - iwlwifi: frequent occasional `FW error in SYNC CMD PHY_CONTEXT_CMD`
Summary: iwlwifi: frequent occasional `FW error in SYNC CMD PHY_CONTEXT_CMD`
Status: NEW
Alias: None
Product: Drivers
Classification: Unclassified
Component: network-wireless-intel (show other bugs)
Hardware: All Linux
: P3 normal
Assignee: Default virtual assignee for network-wireless-intel
URL:
Keywords:
Depends on:
Blocks:
 
Reported: 2024-03-23 01:23 UTC by Konstantin Kharlamov
Modified: 2024-04-07 19:00 UTC (History)
0 users

See Also:
Kernel Version: 6.8.1
Subsystem:
Regression: No
Bisected commit-id:

Attachments
`journalctl -b` output for the boot that goes \w the report (119.36 KB, application/zstd)
2024-03-23 01:23 UTC, Konstantin Kharlamov 		Details
Add an attachment (proposed patch, testcase, etc.)

Description Konstantin Kharlamov 2024-03-23 01:23:59 UTC 
Created attachment 306029 [details]
`journalctl -b` output for the boot that goes \w the report

Occasionally I'm getting `FW error in SYNC CMD PHY_CONTEXT_CMD` error in the log along with stacktrace. I'm not doing anything in particular at that moment, it just happens on its own, often. On previous 6.7.9 kernel version I also sometimes had system crash, I'm not sure if it's because of this or not. On my current 6.8.1 I did not have that happen (yet).

The stacktrace (and the system log since the last boot is attached as well):

    мар 23 03:19:26 dell-g15 kernel: wlan0: AP 60:e3:27:ab:bf:9c changed bandwidth, new config is 2472.000 MHz, width 2 (2462.000/0 MHz)
    мар 23 03:19:28 dell-g15 kernel: wlan0: AP 60:e3:27:ab:bf:9c changed bandwidth, new config is 2472.000 MHz, width 1 (2472.000/0 MHz)
    мар 23 03:19:31 dell-g15 kernel: wlan0: AP 60:e3:27:ab:bf:9c changed bandwidth, new config is 2472.000 MHz, width 2 (2462.000/0 MHz)
    мар 23 03:19:57 dell-g15 kernel: wlan0: AP 60:e3:27:ab:bf:9c changed bandwidth, new config is 2472.000 MHz, width 1 (2472.000/0 MHz)
    мар 23 03:20:00 dell-g15 kernel: wlan0: AP 60:e3:27:ab:bf:9c changed bandwidth, new config is 2472.000 MHz, width 2 (2462.000/0 MHz)
    мар 23 03:20:00 dell-g15 kernel: iwlwifi 0000:00:14.3: Microcode SW error detected. Restarting 0x0.
    мар 23 03:20:00 dell-g15 kernel: iwlwifi 0000:00:14.3: Start IWL Error Log Dump:
    мар 23 03:20:00 dell-g15 kernel: iwlwifi 0000:00:14.3: Transport status: 0x0000004B, valid: 6
    мар 23 03:20:00 dell-g15 kernel: iwlwifi 0000:00:14.3: Loaded firmware version: 77.ad46c98b.0 QuZ-a0-hr-b0-77.ucode
    мар 23 03:20:00 dell-g15 kernel: iwlwifi 0000:00:14.3: 0x00000071 | NMI_INTERRUPT_UMAC_FATAL
    мар 23 03:20:00 dell-g15 kernel: iwlwifi 0000:00:14.3: 0x000022F0 | trm_hw_status0
    мар 23 03:20:00 dell-g15 kernel: iwlwifi 0000:00:14.3: 0x00000000 | trm_hw_status1
    мар 23 03:20:00 dell-g15 kernel: iwlwifi 0000:00:14.3: 0x004C938E | branchlink2
    мар 23 03:20:00 dell-g15 kernel: iwlwifi 0000:00:14.3: 0x00007FA4 | interruptlink1
    мар 23 03:20:00 dell-g15 kernel: iwlwifi 0000:00:14.3: 0x00007FA4 | interruptlink2
    мар 23 03:20:00 dell-g15 kernel: iwlwifi 0000:00:14.3: 0x0000F178 | data1
    мар 23 03:20:00 dell-g15 kernel: iwlwifi 0000:00:14.3: 0x00001000 | data2
    мар 23 03:20:00 dell-g15 kernel: iwlwifi 0000:00:14.3: 0x00000000 | data3
    мар 23 03:20:00 dell-g15 kernel: iwlwifi 0000:00:14.3: 0xEAC18072 | beacon time
    мар 23 03:20:00 dell-g15 kernel: iwlwifi 0000:00:14.3: 0x76B88F96 | tsf low
    мар 23 03:20:00 dell-g15 kernel: iwlwifi 0000:00:14.3: 0x00000302 | tsf hi
    мар 23 03:20:00 dell-g15 kernel: iwlwifi 0000:00:14.3: 0x00000000 | time gp1
    мар 23 03:20:00 dell-g15 kernel: iwlwifi 0000:00:14.3: 0x05BEDF9F | time gp2
    мар 23 03:20:00 dell-g15 kernel: iwlwifi 0000:00:14.3: 0x00000001 | uCode revision type
    мар 23 03:20:00 dell-g15 kernel: iwlwifi 0000:00:14.3: 0x0000004D | uCode version major
    мар 23 03:20:00 dell-g15 kernel: iwlwifi 0000:00:14.3: 0xAD46C98B | uCode version minor
    мар 23 03:20:00 dell-g15 kernel: iwlwifi 0000:00:14.3: 0x00000351 | hw version
    мар 23 03:20:00 dell-g15 kernel: iwlwifi 0000:00:14.3: 0x00489001 | board version
    мар 23 03:20:00 dell-g15 kernel: iwlwifi 0000:00:14.3: 0x80DFF400 | hcmd
    мар 23 03:20:00 dell-g15 kernel: iwlwifi 0000:00:14.3: 0x00020000 | isr0
    мар 23 03:20:00 dell-g15 kernel: iwlwifi 0000:00:14.3: 0x60000000 | isr1
    мар 23 03:20:00 dell-g15 kernel: iwlwifi 0000:00:14.3: 0x18F00002 | isr2
    мар 23 03:20:00 dell-g15 kernel: iwlwifi 0000:00:14.3: 0x00C3000C | isr3
    мар 23 03:20:00 dell-g15 kernel: iwlwifi 0000:00:14.3: 0x00000000 | isr4
    мар 23 03:20:00 dell-g15 kernel: iwlwifi 0000:00:14.3: 0x0165001C | last cmd Id
    мар 23 03:20:00 dell-g15 kernel: iwlwifi 0000:00:14.3: 0x0000F178 | wait_event
    мар 23 03:20:00 dell-g15 kernel: iwlwifi 0000:00:14.3: 0x000000D0 | l2p_control
    мар 23 03:20:00 dell-g15 kernel: iwlwifi 0000:00:14.3: 0x00000020 | l2p_duration
    мар 23 03:20:00 dell-g15 kernel: iwlwifi 0000:00:14.3: 0x0000003F | l2p_mhvalid
    мар 23 03:20:00 dell-g15 kernel: iwlwifi 0000:00:14.3: 0x000000CE | l2p_addr_match
    мар 23 03:20:00 dell-g15 kernel: iwlwifi 0000:00:14.3: 0x00000009 | lmpm_pmg_sel
    мар 23 03:20:00 dell-g15 kernel: iwlwifi 0000:00:14.3: 0x00000000 | timestamp
    мар 23 03:20:00 dell-g15 kernel: iwlwifi 0000:00:14.3: 0x0000609C | flow_handler
    мар 23 03:20:00 dell-g15 kernel: iwlwifi 0000:00:14.3: Start IWL Error Log Dump:
    мар 23 03:20:00 dell-g15 kernel: iwlwifi 0000:00:14.3: Transport status: 0x0000004B, valid: 7
    мар 23 03:20:00 dell-g15 kernel: iwlwifi 0000:00:14.3: 0x20101A27 | ADVANCED_SYSASSERT
    мар 23 03:20:00 dell-g15 kernel: iwlwifi 0000:00:14.3: 0x00000000 | umac branchlink1
    мар 23 03:20:00 dell-g15 kernel: iwlwifi 0000:00:14.3: 0x804561E2 | umac branchlink2
    мар 23 03:20:00 dell-g15 kernel: iwlwifi 0000:00:14.3: 0x0106E094 | umac interruptlink1
    мар 23 03:20:00 dell-g15 kernel: iwlwifi 0000:00:14.3: 0x00000000 | umac interruptlink2
    мар 23 03:20:00 dell-g15 kernel: iwlwifi 0000:00:14.3: 0x00010000 | umac data1
    мар 23 03:20:00 dell-g15 kernel: iwlwifi 0000:00:14.3: 0x00000000 | umac data2
    мар 23 03:20:00 dell-g15 kernel: iwlwifi 0000:00:14.3: 0xDEADBEEF | umac data3
    мар 23 03:20:00 dell-g15 kernel: iwlwifi 0000:00:14.3: 0x0000004D | umac major
    мар 23 03:20:00 dell-g15 kernel: iwlwifi 0000:00:14.3: 0xAD46C98B | umac minor
    мар 23 03:20:00 dell-g15 kernel: iwlwifi 0000:00:14.3: 0x05BEDF99 | frame pointer
    мар 23 03:20:00 dell-g15 kernel: iwlwifi 0000:00:14.3: 0xC0886BC4 | stack pointer
    мар 23 03:20:00 dell-g15 kernel: iwlwifi 0000:00:14.3: 0x00CF050F | last host cmd
    мар 23 03:20:00 dell-g15 kernel: iwlwifi 0000:00:14.3: 0x00000000 | isr status reg
    мар 23 03:20:00 dell-g15 kernel: iwlwifi 0000:00:14.3: IML/ROM dump:
    мар 23 03:20:00 dell-g15 kernel: iwlwifi 0000:00:14.3: 0x00000003 | IML/ROM error/state
    мар 23 03:20:00 dell-g15 kernel: iwlwifi 0000:00:14.3: 0x00005EA9 | IML/ROM data1
    мар 23 03:20:00 dell-g15 kernel: iwlwifi 0000:00:14.3: 0x00000080 | IML/ROM WFPM_AUTH_KEY_0
    мар 23 03:20:00 dell-g15 kernel: iwlwifi 0000:00:14.3: Fseq Registers:
    мар 23 03:20:00 dell-g15 kernel: iwlwifi 0000:00:14.3: 0x60000000 | FSEQ_ERROR_CODE
    мар 23 03:20:00 dell-g15 kernel: iwlwifi 0000:00:14.3: 0x80290033 | FSEQ_TOP_INIT_VERSION
    мар 23 03:20:00 dell-g15 kernel: iwlwifi 0000:00:14.3: 0x00090006 | FSEQ_CNVIO_INIT_VERSION
    мар 23 03:20:00 dell-g15 kernel: iwlwifi 0000:00:14.3: 0x0000A482 | FSEQ_OTP_VERSION
    мар 23 03:20:00 dell-g15 kernel: iwlwifi 0000:00:14.3: 0x00000003 | FSEQ_TOP_CONTENT_VERSION
    мар 23 03:20:00 dell-g15 kernel: iwlwifi 0000:00:14.3: 0x4552414E | FSEQ_ALIVE_TOKEN
    мар 23 03:20:00 dell-g15 kernel: iwlwifi 0000:00:14.3: 0x20000302 | FSEQ_CNVI_ID
    мар 23 03:20:00 dell-g15 kernel: iwlwifi 0000:00:14.3: 0x01300504 | FSEQ_CNVR_ID
    мар 23 03:20:00 dell-g15 kernel: iwlwifi 0000:00:14.3: 0x20000302 | CNVI_AUX_MISC_CHIP
    мар 23 03:20:00 dell-g15 kernel: iwlwifi 0000:00:14.3: 0x01300504 | CNVR_AUX_MISC_CHIP
    мар 23 03:20:00 dell-g15 kernel: iwlwifi 0000:00:14.3: 0x05B0905B | CNVR_SCU_SD_REGS_SD_REG_DIG_DCDC_VTRIM
    мар 23 03:20:00 dell-g15 kernel: iwlwifi 0000:00:14.3: 0x0000025B | CNVR_SCU_SD_REGS_SD_REG_ACTIVE_VDIG_MIRROR
    мар 23 03:20:00 dell-g15 kernel: iwlwifi 0000:00:14.3: 0x00090006 | FSEQ_PREV_CNVIO_INIT_VERSION
    мар 23 03:20:00 dell-g15 kernel: iwlwifi 0000:00:14.3: 0x00290033 | FSEQ_WIFI_FSEQ_VERSION
    мар 23 03:20:00 dell-g15 kernel: iwlwifi 0000:00:14.3: 0x00290033 | FSEQ_BT_FSEQ_VERSION
    мар 23 03:20:00 dell-g15 kernel: iwlwifi 0000:00:14.3: 0x000000EB | FSEQ_CLASS_TP_VERSION
    мар 23 03:20:00 dell-g15 kernel: iwlwifi 0000:00:14.3: UMAC CURRENT PC: 0x80472fa4
    мар 23 03:20:00 dell-g15 kernel: iwlwifi 0000:00:14.3: LMAC1 CURRENT PC: 0xd0
    мар 23 03:20:00 dell-g15 kernel: iwlwifi 0000:00:14.3: WRT: Collecting data: ini trigger 4 fired (delay=0ms).
    мар 23 03:20:00 dell-g15 kernel: ieee80211 phy0: Hardware restart was requested
    мар 23 03:20:00 dell-g15 kernel: iwlwifi 0000:00:14.3: FW error in SYNC CMD PHY_CONTEXT_CMD
    мар 23 03:20:00 dell-g15 kernel: CPU: 8 PID: 84819 Comm: kworker/u24:1 Not tainted 6.8.1-arch1-1 #1 52f97d9bb37be6168651745a1a9f8f7240d21ce5
    мар 23 03:20:00 dell-g15 kernel: Hardware name: Dell Inc. Dell G15 5510/050GJP, BIOS 1.5.0 07/02/2021
    мар 23 03:20:00 dell-g15 kernel: Workqueue: events_unbound cfg80211_wiphy_work [cfg80211]
    мар 23 03:20:00 dell-g15 kernel: Call Trace:
    мар 23 03:20:00 dell-g15 kernel:  <TASK>
    мар 23 03:20:00 dell-g15 kernel:  dump_stack_lvl+0x47/0x60
    мар 23 03:20:00 dell-g15 kernel:  iwl_trans_txq_send_hcmd+0x33d/0x450 [iwlwifi 75e96f736d11c9947a7baf8e2f9907f2b810a8d5]
    мар 23 03:20:00 dell-g15 kernel:  ? __pfx_autoremove_wake_function+0x10/0x10
    мар 23 03:20:00 dell-g15 kernel:  iwl_trans_send_cmd+0x7f/0xd0 [iwlwifi 75e96f736d11c9947a7baf8e2f9907f2b810a8d5]
    мар 23 03:20:00 dell-g15 kernel:  iwl_mvm_send_cmd_pdu+0x62/0xb0 [iwlmvm c79e9426505c2f9a413ef0662b5aff79564a1c05]
    мар 23 03:20:00 dell-g15 kernel:  iwl_mvm_phy_ctxt_apply+0x495/0x540 [iwlmvm c79e9426505c2f9a413ef0662b5aff79564a1c05]
    мар 23 03:20:00 dell-g15 kernel:  iwl_mvm_change_chanctx+0x118/0x180 [iwlmvm c79e9426505c2f9a413ef0662b5aff79564a1c05]
    мар 23 03:20:00 dell-g15 kernel:  drv_change_chanctx+0x3d/0xf0 [mac80211 ef1a490be1742d5c46f53f5250aad3c8a9016ee4]
    мар 23 03:20:00 dell-g15 kernel:  _ieee80211_change_chanctx+0xbb/0x170 [mac80211 ef1a490be1742d5c46f53f5250aad3c8a9016ee4]
    мар 23 03:20:00 dell-g15 kernel:  ieee80211_link_change_bandwidth+0x11b/0x180 [mac80211 ef1a490be1742d5c46f53f5250aad3c8a9016ee4]
    мар 23 03:20:00 dell-g15 kernel:  ieee80211_rx_mgmt_beacon+0x9ca/0x1e50 [mac80211 ef1a490be1742d5c46f53f5250aad3c8a9016ee4]
    мар 23 03:20:00 dell-g15 kernel:  ? psi_task_switch+0xd6/0x230
    мар 23 03:20:00 dell-g15 kernel:  ieee80211_iface_work+0x2df/0x410 [mac80211 ef1a490be1742d5c46f53f5250aad3c8a9016ee4]
    мар 23 03:20:00 dell-g15 kernel:  cfg80211_wiphy_work+0xa7/0xe0 [cfg80211 4b58ada0938b18d790af24b55d12d718f52b2ade]
    мар 23 03:20:00 dell-g15 kernel:  process_one_work+0x178/0x350
    мар 23 03:20:00 dell-g15 kernel:  worker_thread+0x30f/0x450
    мар 23 03:20:00 dell-g15 kernel:  ? __pfx_worker_thread+0x10/0x10
    мар 23 03:20:00 dell-g15 kernel:  kthread+0xe5/0x120
    мар 23 03:20:00 dell-g15 kernel:  ? __pfx_kthread+0x10/0x10
    мар 23 03:20:00 dell-g15 kernel:  ret_from_fork+0x31/0x50
    мар 23 03:20:00 dell-g15 kernel:  ? __pfx_kthread+0x10/0x10
    мар 23 03:20:00 dell-g15 kernel:  ret_from_fork_asm+0x1b/0x30
    мар 23 03:20:00 dell-g15 kernel:  </TASK>
    мар 23 03:20:00 dell-g15 kernel: iwlwifi 0000:00:14.3: PHY ctxt cmd error. ret=-5
    мар 23 03:20:00 dell-g15 kernel: iwlwifi 0000:00:14.3: Failed to send MAC_CONFIG_CMD (action:2): -5
    мар 23 03:20:00 dell-g15 kernel: iwlwifi 0000:00:14.3: failed to update MAC f4:a4:75:d4:c3:fd

The aforementioned crash aside that may or may not be relevant, I don't seem to see any behavior changes due to the driver errors.

# Steps to reproduce

Connect to a wifi point and use the system as usual. Watch kernel logs.

## Expected

No kernel errors from iwlwifi

## Actual

There are kernel errors from iwlwifi

# Additional information

• Logs since the last boot attached
• Distro: Archlinux
Comment 1 Artem S. Tashkinov 2024-03-24 15:27:47 UTC 
Is your firmware up to date? Could you update it to the latest git master version and check again?

Secondly, is this a regression? Do any previous kernels work without errors?
Comment 2 Konstantin Kharlamov 2024-03-24 22:09:18 UTC 
> Is your firmware up to date? Could you update it to the latest git master
> version and check again?

Hmm, I updated a few hours ago up to e068ccf0 and for now haven't seen an error. Will test for some more, let's see if errors are gone.

> Secondly, is this a regression? Do any previous kernels work without errors?

I noticed errors on 6.7 kernel version; whether they were on older kernels I don't know offhand.
Comment 3 Konstantin Kharlamov 2024-03-25 09:47:29 UTC 
(In reply to Konstantin Kharlamov from comment #2)
> > Is your firmware up to date? Could you update it to the latest git master
> > version and check again?
> 
> Hmm, I updated a few hours ago up to e068ccf0 and for now haven't seen an
> error. Will test for some more, let's see if errors are gone.

Problem is still present.

It looks like the updated FW made it happen much more rarely (but it may be a coincidence). In the log attached initially it can be seen to have been happening every ≈½ an hour or so. But right now for 14.5 hours it happened just twice.

That said, the error trace is exactly the same (compared side-by-side in vimdiff), so it's still happening.
Comment 4 Konstantin Kharlamov 2024-04-07 19:00:16 UTC 
Turns out this does cause problems. If stack appears and music plays, that music lags.

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