Bug 194933 - iwlwifi:7260: Hardware restart required, FW error in SYNC CMD PHY_CONTEXT_CMD during authentication
Summary: iwlwifi:7260: Hardware restart required, FW error in SYNC CMD PHY_CONTEXT_CMD...
Status: CLOSED PATCH_ALREADY_AVAILABLE
Alias: None
Product: Networking
Classification: Unclassified
Component: Wireless (show other bugs)
Hardware: All Linux
: P1 normal
Assignee: networking_wireless@kernel-bugs.osdl.org
URL:
Keywords:
Depends on:
Blocks:
 
Reported: 2017-03-19 18:45 UTC by Aleksey Shipilev
Modified: 2024-04-07 19:05 UTC (History)
3 users (show)

See Also:
Kernel Version: 4.4.0-66-generic
Subsystem:
Regression: No
Bisected commit-id:


Attachments
dmesg (27.69 KB, application/pgp-encrypted)
2017-03-19 18:45 UTC, Aleksey Shipilev
Details
iwldump (22.63 KB, application/pgp-encrypted)
2017-03-19 18:46 UTC, Aleksey Shipilev
Details

Description Aleksey Shipilev 2017-03-19 18:45:38 UTC
Created attachment 255349 [details]
dmesg

This happens for one of four APs at my home. Other three APs work fine. dmesg after the failure looks like this:

[  301.900464] wlp2s0: authenticate with xx:xx:xx:xx:xx:xx
[  301.902693] iwlwifi 0000:02:00.0: Microcode SW error detected.  Restarting 0x2000000.
[  301.902705] iwlwifi 0000:02:00.0: CSR values:
[  301.902712] iwlwifi 0000:02:00.0: (2nd byte of CSR_INT_COALESCING is CSR_INT_PERIODIC_REG)
[  301.902827] iwlwifi 0000:02:00.0:        CSR_HW_IF_CONFIG_REG: 0X40489204
[  301.902980] iwlwifi 0000:02:00.0:          CSR_INT_COALESCING: 0X80000040
[  301.903133] iwlwifi 0000:02:00.0:                     CSR_INT: 0X00000000
[  301.903286] iwlwifi 0000:02:00.0:                CSR_INT_MASK: 0X00000000
[  301.903439] iwlwifi 0000:02:00.0:           CSR_FH_INT_STATUS: 0X00000000
[  301.903592] iwlwifi 0000:02:00.0:                 CSR_GPIO_IN: 0X00000000
[  301.903745] iwlwifi 0000:02:00.0:                   CSR_RESET: 0X00000000
[  301.903900] iwlwifi 0000:02:00.0:                CSR_GP_CNTRL: 0X080403cd
[  301.904053] iwlwifi 0000:02:00.0:                  CSR_HW_REV: 0X00000144
[  301.904206] iwlwifi 0000:02:00.0:              CSR_EEPROM_REG: 0X00000000
[  301.904359] iwlwifi 0000:02:00.0:               CSR_EEPROM_GP: 0X80000000
[  301.904512] iwlwifi 0000:02:00.0:              CSR_OTP_GP_REG: 0X803a0000
[  301.904666] iwlwifi 0000:02:00.0:                 CSR_GIO_REG: 0X001f0042
[  301.904820] iwlwifi 0000:02:00.0:            CSR_GP_UCODE_REG: 0X00000000
[  301.904972] iwlwifi 0000:02:00.0:           CSR_GP_DRIVER_REG: 0X00000000
[  301.905126] iwlwifi 0000:02:00.0:           CSR_UCODE_DRV_GP1: 0X00000000
[  301.905279] iwlwifi 0000:02:00.0:           CSR_UCODE_DRV_GP2: 0X00000000
[  301.905433] iwlwifi 0000:02:00.0:                 CSR_LED_REG: 0X00000060
[  301.905586] iwlwifi 0000:02:00.0:        CSR_DRAM_INT_TBL_REG: 0X880c8447
[  301.905740] iwlwifi 0000:02:00.0:        CSR_GIO_CHICKEN_BITS: 0X27800200
[  301.905893] iwlwifi 0000:02:00.0:             CSR_ANA_PLL_CFG: 0Xd55555d5
[  301.906082] iwlwifi 0000:02:00.0:      CSR_MONITOR_STATUS_REG: 0X3c08019d
[  301.906230] iwlwifi 0000:02:00.0:           CSR_HW_REV_WA_REG: 0X0001001a
[  301.906383] iwlwifi 0000:02:00.0:        CSR_DBG_HPET_MEM_REG: 0Xffff0000
[  301.906390] iwlwifi 0000:02:00.0: FH register values:
[  301.906538] iwlwifi 0000:02:00.0:         FH_RSCSR_CHNL0_STTS_WPTR_REG: 0X31f0c400
[  301.906691] iwlwifi 0000:02:00.0:        FH_RSCSR_CHNL0_RBDCB_BASE_REG: 0X031f0c30
[  301.906845] iwlwifi 0000:02:00.0:                  FH_RSCSR_CHNL0_WPTR: 0X000000a0
[  301.906998] iwlwifi 0000:02:00.0:         FH_MEM_RCSR_CHNL0_CONFIG_REG: 0X80801114
[  301.907151] iwlwifi 0000:02:00.0:          FH_MEM_RSSR_SHARED_CTRL_REG: 0X000000fc
[  301.907305] iwlwifi 0000:02:00.0:            FH_MEM_RSSR_RX_STATUS_REG: 0X07030000
[  301.907458] iwlwifi 0000:02:00.0:    FH_MEM_RSSR_RX_ENABLE_ERR_IRQ2DRV: 0X00000000
[  301.907611] iwlwifi 0000:02:00.0:                FH_TSSR_TX_STATUS_REG: 0X07ff0001
[  301.907765] iwlwifi 0000:02:00.0:                 FH_TSSR_TX_ERROR_REG: 0X00000000
[  301.908018] iwlwifi 0000:02:00.0: Start IWL Error Log Dump:
[  301.908026] iwlwifi 0000:02:00.0: Status: 0x00000000, count: 6
[  301.908032] iwlwifi 0000:02:00.0: Loaded firmware version: 17.352738.0
[  301.908040] iwlwifi 0000:02:00.0: 0x000014FD | ADVANCED_SYSASSERT          
[  301.908046] iwlwifi 0000:02:00.0: 0x002002A0 | uPc
[  301.908053] iwlwifi 0000:02:00.0: 0x00000000 | branchlink1
[  301.908060] iwlwifi 0000:02:00.0: 0x00000B30 | branchlink2
[  301.908066] iwlwifi 0000:02:00.0: 0x00016498 | interruptlink1
[  301.908074] iwlwifi 0000:02:00.0: 0x00000000 | interruptlink2
[  301.908080] iwlwifi 0000:02:00.0: 0xDEADBEEF | data1
[  301.908087] iwlwifi 0000:02:00.0: 0xDEADBEEF | data2
[  301.908094] iwlwifi 0000:02:00.0: 0xDEADBEEF | data3
[  301.908101] iwlwifi 0000:02:00.0: 0x00000000 | beacon time
[  301.908108] iwlwifi 0000:02:00.0: 0x018CDD79 | tsf low
[  301.908114] iwlwifi 0000:02:00.0: 0x0000008E | tsf hi
[  301.908120] iwlwifi 0000:02:00.0: 0x00000000 | time gp1
[  301.908127] iwlwifi 0000:02:00.0: 0x0058D969 | time gp2
[  301.908133] iwlwifi 0000:02:00.0: 0x00000000 | time gp3
[  301.908139] iwlwifi 0000:02:00.0: 0x00000011 | uCode version major
[  301.908145] iwlwifi 0000:02:00.0: 0x000561E2 | uCode version minor
[  301.908152] iwlwifi 0000:02:00.0: 0x00000144 | hw version
[  301.908158] iwlwifi 0000:02:00.0: 0x40489204 | board version
[  301.908165] iwlwifi 0000:02:00.0: 0x09450008 | hcmd
[  301.908172] iwlwifi 0000:02:00.0: 0x00022080 | isr0
[  301.908178] iwlwifi 0000:02:00.0: 0x00000000 | isr1
[  301.908185] iwlwifi 0000:02:00.0: 0x00000002 | isr2
[  301.908191] iwlwifi 0000:02:00.0: 0x00414080 | isr3
[  301.908198] iwlwifi 0000:02:00.0: 0x00000000 | isr4
[  301.908205] iwlwifi 0000:02:00.0: 0x01000112 | isr_pref
[  301.908211] iwlwifi 0000:02:00.0: 0x00000000 | wait_event
[  301.908218] iwlwifi 0000:02:00.0: 0x00000080 | l2p_control
[  301.908225] iwlwifi 0000:02:00.0: 0x00018020 | l2p_duration
[  301.908232] iwlwifi 0000:02:00.0: 0x0000003F | l2p_mhvalid
[  301.908238] iwlwifi 0000:02:00.0: 0x00000000 | l2p_addr_match
[  301.908245] iwlwifi 0000:02:00.0: 0x00000007 | lmpm_pmg_sel
[  301.908252] iwlwifi 0000:02:00.0: 0x15061434 | timestamp
[  301.908259] iwlwifi 0000:02:00.0: 0x0000A0B8 | flow_handler
[  301.908269] ieee80211 phy0: Hardware restart was requested
[  301.917984] iwlwifi 0000:02:00.0: FW error in SYNC CMD PHY_CONTEXT_CMD
[  301.917998] CPU: 3 PID: 1529 Comm: wpa_supplicant Tainted: G           OE   4.4.0-66-generic #87-Ubuntu
[  301.918003] Hardware name: ASUSTeK COMPUTER INC. UX32LN/UX32LN, BIOS UX32LN.203 05/21/2014
[  301.918008]  0000000000000286 00000000ecb7e5e9 ffff8800c8b07618 ffffffff813f8083
[  301.918019]  ffff8800c8b076b0 00000000fffffffb ffff8800c8b07688 ffffffffc08063fc
[  301.918026]  000000001a3a8000 0000000000000000 ffff88031a3a8000 ffffffff810c41e0
[  301.918033] Call Trace:
[  301.918046]  [<ffffffff813f8083>] dump_stack+0x63/0x90
[  301.918071]  [<ffffffffc08063fc>] iwl_trans_pcie_send_hcmd+0x44c/0x5a0 [iwlwifi]
[  301.918081]  [<ffffffff810c41e0>] ? wake_atomic_t_function+0x60/0x60
[  301.918107]  [<ffffffffc0a3197d>] iwl_mvm_send_cmd+0x3d/0xb0 [iwlmvm]
[  301.918127]  [<ffffffffc0a31a3f>] iwl_mvm_send_cmd_pdu+0x4f/0x70 [iwlmvm]
[  301.918147]  [<ffffffffc0a2ef27>] iwl_mvm_phy_ctxt_apply.constprop.3+0x117/0x1c0 [iwlmvm]
[  301.918171]  [<ffffffffc0a44bfe>] ? iwl_mvm_bt_coex_notif_handle+0x12e/0x1e0 [iwlmvm]
[  301.918190]  [<ffffffffc0a2f082>] iwl_mvm_phy_ctxt_changed+0x22/0x30 [iwlmvm]
[  301.918206]  [<ffffffffc0a2684c>] iwl_mvm_change_chanctx+0x6c/0xb0 [iwlmvm]
[  301.918262]  [<ffffffffc0923049>] ieee80211_recalc_chanctx_min_def+0x179/0x330 [mac80211]
[  301.918309]  [<ffffffffc0924087>] ieee80211_assign_vif_chanctx+0x207/0x490 [mac80211]
[  301.918354]  [<ffffffffc09256d2>] ieee80211_vif_use_channel+0x172/0x2c0 [mac80211]
[  301.918400]  [<ffffffffc0938414>] ieee80211_prep_connection+0x194/0x840 [mac80211]
[  301.918448]  [<ffffffffc093f600>] ieee80211_mgd_auth+0x280/0x380 [mac80211]
[  301.918456]  [<ffffffff8181d9f6>] ? packet_poll+0xd6/0x160
[  301.918502]  [<ffffffffc0908218>] ieee80211_auth+0x18/0x20 [mac80211]
[  301.918552]  [<ffffffffc075cb91>] cfg80211_mlme_auth+0x101/0x210 [cfg80211]
[  301.918590]  [<ffffffffc0743293>] nl80211_authenticate+0x2e3/0x350 [cfg80211]
[  301.918602]  [<ffffffff81765e84>] genl_family_rcv_msg+0x1e4/0x3e0
[  301.918611]  [<ffffffff8171c60b>] ? __alloc_skb+0x5b/0x1f0
[  301.918620]  [<ffffffff81766080>] ? genl_family_rcv_msg+0x3e0/0x3e0
[  301.918627]  [<ffffffff817660f6>] genl_rcv_msg+0x76/0xb0
[  301.918635]  [<ffffffff817655f4>] netlink_rcv_skb+0xa4/0xc0
[  301.918643]  [<ffffffff81765c88>] genl_rcv+0x28/0x40
[  301.918650]  [<ffffffff81764daf>] netlink_unicast+0x12f/0x1b0
[  301.918658]  [<ffffffff817652d1>] netlink_sendmsg+0x4a1/0x5f0
[  301.918667]  [<ffffffff8139f001>] ? aa_sock_msg_perm+0x61/0x150
[  301.918674]  [<ffffffff81713ae8>] sock_sendmsg+0x38/0x50
[  301.918681]  [<ffffffff81714591>] ___sys_sendmsg+0x281/0x290
[  301.918688]  [<ffffffff8171735a>] ? sock_def_readable+0x3a/0x60
[  301.918695]  [<ffffffff810b52a3>] ? update_curr+0xe3/0x160
[  301.918705]  [<ffffffff810b2b9c>] ? __enqueue_entity+0x6c/0x70
[  301.918712]  [<ffffffff810ba95c>] ? set_next_entity+0x9c/0xb0
[  301.918720]  [<ffffffff8102d66c>] ? __switch_to+0x1dc/0x5c0
[  301.918726]  [<ffffffff81714ee1>] __sys_sendmsg+0x51/0x90
[  301.918733]  [<ffffffff81714f32>] SyS_sendmsg+0x12/0x20
[  301.918741]  [<ffffffff8183c5f2>] entry_SYSCALL_64_fastpath+0x16/0x71
[  301.918752] iwlwifi 0000:02:00.0: PHY ctxt cmd error. ret=-5
[  301.918764] iwlwifi 0000:02:00.0: Failed to send MAC context (action:2): -5
[  301.918770] iwlwifi 0000:02:00.0: failed to update MAC f8:16:54:d3:13:87
[  301.918783] iwlwifi 0000:02:00.0: Failed to send MAC context (action:2): -5
[  301.918788] iwlwifi 0000:02:00.0: failed to update MAC f8:16:54:d3:13:87
[  301.918802] iwlwifi 0000:02:00.0: Failed to disable queue 0 (ret=-5)
[  301.918808] iwlwifi 0000:02:00.0: Failed to disable queue 1 (ret=-5)
[  301.918813] iwlwifi 0000:02:00.0: Failed to disable queue 2 (ret=-5)
[  301.918819] iwlwifi 0000:02:00.0: Failed to disable queue 3 (ret=-5)
[  301.919472] wlp2s0: failed to insert STA entry for the AP (error -5)
[  301.919485] iwlwifi 0000:02:00.0: Failed to send MAC context (action:2): -5
[  301.919492] iwlwifi 0000:02:00.0: failed to update MAC f8:16:54:d3:13:87
[  301.919502] iwlwifi 0000:02:00.0: Failed to send binding (action:3): -5
[  301.919513] iwlwifi 0000:02:00.0: Failed to send MAC context (action:2): -5
[  301.919519] iwlwifi 0000:02:00.0: failed to update MAC f8:16:54:d3:13:87
[  302.064489] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled
[  302.064922] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled
[  302.261755] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled
[  302.262669] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled

This is perfectly reproducible. Happy to try any new firmware. Attaching GPG-encrypted dmesg and iwldump files.
Comment 1 Aleksey Shipilev 2017-03-19 18:46:13 UTC
Created attachment 255351 [details]
iwldump
Comment 2 Luca Coelho 2017-03-19 19:19:08 UTC
Thanks for reporting! To start with, could you try to use our Core releases (backport)? You can find information on how to do it here:

https://wireless.wiki.kernel.org/en/users/drivers/iwlwifi/core_release

We have a couple of 14FD sysasserts (one of them is related to monitor interfaces, the other one doesn't seem to be reproducible in newer firmwares).  I'll check if your dumps point to some similarities with the cases we have already seen.
Comment 3 Luca Coelho 2017-03-19 19:20:38 UTC
Ahmmm, sorry, the other two cases were not with 7260.  But I think it's still worth trying to test with the core releases.
Comment 4 Aleksey Shipilev 2017-03-19 19:55:43 UTC
You know what, I pulled -17.ucode from core_release, and replaced the distro one in /lib/firmware. Now I can associate to the AP that was consistently failing before! Other three APs that used to work still work perfectly well.

Does this make sense?

Firmware versions and checksums are certainly different:

a6713c3a51ba86643a982776fcd95927f2a4bb7f  /lib/firmware/iwlwifi-7260-17.ucode.ubuntu
5b264975a965a395b3698e6186f6a30dda0e5919  /lib/firmware/iwlwifi-7260-17.ucode

...

[   25.282935] iwlwifi 0000:02:00.0: loaded firmware version 17.352738.0 op_mode iwlmvm

vs.

[   21.784137] iwlwifi 0000:02:00.0: loaded firmware version 17.459231.0 op_mode iwlmvm

I can live with new ucode, thanks! I guess it would be some time until it trickles down to Xubuntu's linux-firmware.
Comment 5 Luca Coelho 2017-03-19 20:00:48 UTC
Great that it works with the new firmware! We have recently updated it, with some bugfixes, so yes, it does make sense that it works now. :)

You should ping your distro to ask them to update it.  I don't really know how often Ubuntu updates the firmwares package...
Comment 6 Aleksey Shipilev 2017-03-19 20:28:20 UTC
For the record, submitted to Ubuntu here:
 https://bugs.launchpad.net/ubuntu/+source/linux-firmware/+bug/1674159
Comment 7 Konstantin Kharlamov 2024-04-07 19:05:27 UTC
Hello, sorry for unrelated question, but did you explicitly add the `linuxwifi@intel.com` email?

I am also affected by a bug in iwlwifi¹ and one thing I noticed is that updated to the report go to no-one, i.e. when I add a comment the top of the page literally says: `Email sent to: no one`.

I presume it should be getting reflected to some kind of mailing list, is linuxwifi@intel.com the one?

1: https://bugzilla.kernel.org/show_bug.cgi?id=218632

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