Re: Next patch to test for everyone (especially if you had iwlwifi firmware crashes)

Reply: Bjoern A. Zeeb: "Re: Next patch to test for everyone (especially if you had iwlwifi firmware crashes)"
In reply to: Bjoern A. Zeeb: "Next patch to test for everyone (especially if you had iwlwifi firmware crashes)"
Go to: [ bottom of page ] [ top of archives ] [ this month ]
From: Eirik_Øverby <ltning-freebsd-wireless_at_anduin.net>
Date: Thu, 10 Apr 2025 22:20:06 UTC
On 10.04.2025 17:47, Bjoern A. Zeeb wrote:
> I just uploaded a combined patch to Phabircator for people to test:
> https://reviews.freebsd.org/D49761
> 
> Firmware crashes we have seen with
> iwlwifi0: 0x00010000 | umac data1
> iwlwifi0: 0x00000000 | umac data2
> iwlwifi0: 0xDEADBEEF | umac data3
> iwlwifi0: 0xXXXX050F | last host cmd
> likely are fixed by this change.

While it's still a bit early to be sure (it's only been a few hours), 
this certainly seems to have helped. I can now seemingly reliably 
connect to wifi on boot or after resume, and there are fewer delays and 
less scary output on the console.

(I did get another firmware crash while trying to change bands and was 
doing a lot of 'service netif restart wlan0', but at no point did it not 
come back online, and I did not need to 'kldunload if_iwlwifi' to 
recover. Panic below sig. The last reconnect was me rebooting the AP - 
again showing that it handled that relatively gracefully.)

Thanks, Bjoern (and the Foundation, as I've been reminded). Also thanks 
for the helpful hints about how to cherry-pick commits - your suggestion 
to use devel/arcanist (arc patch --nobranch D.....) was very helpful.

/Eirik


Apr 11 00:10:48 f13 kernel: iwlwifi0: Microcode SW error detected. 
Restarting 0x0.
Apr 11 00:10:48 f13 kernel: iwlwifi0: Start IWL Error Log Dump:
Apr 11 00:10:48 f13 kernel: iwlwifi0: Transport status: 0x0000004B, valid: 6
Apr 11 00:10:48 f13 kernel: iwlwifi0: Loaded firmware version: 
89.6b44fa0b.0 ty-a0-gf-a0-89.ucode
Apr 11 00:10:48 f13 kernel: iwlwifi0: 0x00000071 | 
NMI_INTERRUPT_UMAC_FATAL
Apr 11 00:10:48 f13 kernel: iwlwifi0: 0x00A08200 | trm_hw_status0
Apr 11 00:10:48 f13 kernel: iwlwifi0: 0x00000000 | trm_hw_status1
Apr 11 00:10:48 f13 kernel: iwlwifi0: 0x004DACF8 | branchlink2
Apr 11 00:10:48 f13 kernel: iwlwifi0: 0x004D091A | interruptlink1
Apr 11 00:10:48 f13 kernel: iwlwifi0: 0x004D091A | interruptlink2
Apr 11 00:10:48 f13 kernel: iwlwifi0: 0x000167E4 | data1
Apr 11 00:10:48 f13 kernel: iwlwifi0: 0x00000010 | data2
Apr 11 00:10:48 f13 kernel: iwlwifi0: 0x00000000 | data3
Apr 11 00:10:48 f13 kernel: iwlwifi0: 0x2DC12572 | beacon time
Apr 11 00:10:48 f13 kernel: iwlwifi0: 0x8750BA96 | tsf low
Apr 11 00:10:48 f13 kernel: iwlwifi0: 0x0000000A | tsf hi
Apr 11 00:10:48 f13 kernel: iwlwifi0: 0x00000000 | time gp1
Apr 11 00:10:48 f13 kernel: iwlwifi0: 0x015ABCD8 | time gp2
Apr 11 00:10:48 f13 kernel: iwlwifi0: 0x00000001 | uCode revision type
Apr 11 00:10:48 f13 kernel: iwlwifi0: 0x00000059 | uCode version major
Apr 11 00:10:48 f13 kernel: iwlwifi0: 0x6B44FA0B | uCode version minor
Apr 11 00:10:48 f13 kernel: iwlwifi0: 0x00000420 | hw version
Apr 11 00:10:48 f13 kernel: iwlwifi0: 0x10480002 | board version
Apr 11 00:10:48 f13 kernel: iwlwifi0: 0x80F8FF00 | hcmd
Apr 11 00:10:48 f13 kernel: iwlwifi0: 0x24020000 | isr0
Apr 11 00:10:48 f13 kernel: iwlwifi0: 0x21000000 | isr1
Apr 11 00:10:48 f13 kernel: iwlwifi0: 0x48F0000A | isr2
Apr 11 00:10:48 f13 kernel: iwlwifi0: 0x00C3018C | isr3
Apr 11 00:10:48 f13 kernel: iwlwifi0: 0x00200000 | isr4
Apr 11 00:10:48 f13 kernel: iwlwifi0: 0x040F001C | last cmd Id
Apr 11 00:10:48 f13 kernel: iwlwifi0: 0x000167E4 | wait_event
Apr 11 00:10:48 f13 kernel: iwlwifi0: 0x000008D0 | l2p_control
Apr 11 00:10:48 f13 kernel: iwlwifi0: 0x00018034 | l2p_duration
Apr 11 00:10:48 f13 kernel: iwlwifi0: 0x0000003F | l2p_mhvalid
Apr 11 00:10:48 f13 kernel: iwlwifi0: 0x00CF00F8 | l2p_addr_match
Apr 11 00:10:48 f13 kernel: iwlwifi0: 0x00000009 | lmpm_pmg_sel
Apr 11 00:10:48 f13 kernel: iwlwifi0: 0x00000000 | timestamp
Apr 11 00:10:48 f13 kernel: iwlwifi0: 0x00004850 | flow_handler
Apr 11 00:10:48 f13 kernel: iwlwifi0: Start IWL Error Log Dump:
Apr 11 00:10:48 f13 kernel: iwlwifi0: Transport status: 0x0000004B, valid: 7
Apr 11 00:10:48 f13 kernel: iwlwifi0: 0x2010350B | ADVANCED_SYSASSERT
Apr 11 00:10:48 f13 kernel: iwlwifi0: 0x00000000 | umac branchlink1
Apr 11 00:10:48 f13 kernel: iwlwifi0: 0x804838BE | umac branchlink2
Apr 11 00:10:48 f13 kernel: iwlwifi0: 0x0108AF90 | umac interruptlink1
Apr 11 00:10:48 f13 kernel: iwlwifi0: 0x00000000 | umac interruptlink2
Apr 11 00:10:48 f13 kernel: iwlwifi0: 0x00000005 | umac data1
Apr 11 00:10:48 f13 kernel: iwlwifi0: 0x00000001 | umac data2
Apr 11 00:10:48 f13 kernel: iwlwifi0: 0xDEADBEEF | umac data3
Apr 11 00:10:48 f13 kernel: iwlwifi0: 0x00000059 | umac major
Apr 11 00:10:48 f13 kernel: iwlwifi0: 0x6B44FA0B | umac minor
Apr 11 00:10:48 f13 kernel: iwlwifi0: 0x015ABCD2 | frame pointer
Apr 11 00:10:48 f13 kernel: iwlwifi0: 0xC0886BD4 | stack pointer
Apr 11 00:10:48 f13 kernel: iwlwifi0: 0x00580516 | last host cmd
Apr 11 00:10:48 f13 kernel: iwlwifi0: 0x00000000 | isr status reg
Apr 11 00:10:48 f13 kernel: iwlwifi0: IML/ROM dump:
Apr 11 00:10:48 f13 kernel: iwlwifi0: 0x00000B03 | IML/ROM error/state
Apr 11 00:10:48 f13 kernel: iwlwifi0: 0x000086BA | IML/ROM data1
Apr 11 00:10:48 f13 kernel: iwlwifi0: 0x00000090 | IML/ROM WFPM_AUTH_KEY_0
Apr 11 00:10:48 f13 kernel: iwlwifi0: Fseq Registers:
Apr 11 00:10:48 f13 kernel: iwlwifi0: 0x60000000 | FSEQ_ERROR_CODE
Apr 11 00:10:48 f13 kernel: iwlwifi0: 0x80440007 | FSEQ_TOP_INIT_VERSION
Apr 11 00:10:48 f13 kernel: iwlwifi0: 0x00080009 | FSEQ_CNVIO_INIT_VERSION
Apr 11 00:10:48 f13 kernel: iwlwifi0: 0x0000A652 | FSEQ_OTP_VERSION
Apr 11 00:10:48 f13 kernel: iwlwifi0: 0x00000002 | FSEQ_TOP_CONTENT_VERSION
Apr 11 00:10:48 f13 kernel: iwlwifi0: 0x4552414E | FSEQ_ALIVE_TOKEN
Apr 11 00:10:48 f13 kernel: iwlwifi0: 0x00400410 | FSEQ_CNVI_ID
Apr 11 00:10:48 f13 kernel: iwlwifi0: 0x00400410 | FSEQ_CNVR_ID
Apr 11 00:10:48 f13 kernel: iwlwifi0: 0x00400410 | CNVI_AUX_MISC_CHIP
Apr 11 00:10:48 f13 kernel: iwlwifi0: 0x00400410 | CNVR_AUX_MISC_CHIP
Apr 11 00:10:48 f13 kernel: iwlwifi0: 0x00009061 | 
CNVR_SCU_SD_REGS_SD_REG_DIG_DCDC_VTRIM
Apr 11 00:10:48 f13 kernel: iwlwifi0: 0x00000061 | 
CNVR_SCU_SD_REGS_SD_REG_ACTIVE_VDIG_MIRROR
Apr 11 00:10:48 f13 kernel: iwlwifi0: 0x00080009 | 
FSEQ_PREV_CNVIO_INIT_VERSION
Apr 11 00:10:48 f13 kernel: iwlwifi0: 0x00440007 | FSEQ_WIFI_FSEQ_VERSION
Apr 11 00:10:48 f13 kernel: iwlwifi0: 0xB76519DB | FSEQ_BT_FSEQ_VERSION
Apr 11 00:10:48 f13 kernel: iwlwifi0: 0x000000DC | FSEQ_CLASS_TP_VERSION
Apr 11 00:10:48 f13 kernel: iwlwifi0: UMAC CURRENT PC: 0x804a578c
Apr 11 00:10:48 f13 kernel: iwlwifi0: LMAC1 CURRENT PC: 0xd0
Apr 11 00:10:48 f13 kernel: iwlwifi0: WRT: Collecting data: ini trigger 
4 fired (delay=0ms).
Apr 11 00:10:48 f13 kernel: iwlwifi0: FW error in SYNC CMD UNKNOWN
Apr 11 00:10:48 f13 kernel: #0 0xffffffff80dc8b0b at linux_dump_stack+0x1b
Apr 11 00:10:48 f13 kernel: #1 0xffffffff8454a7db at 
iwl_trans_pcie_send_hcmd+0x44b
Apr 11 00:10:48 f13 kernel: #2 0xffffffff844db318 at iwl_trans_send_cmd+0x88
Apr 11 00:10:48 f13 kernel: #3 0xffffffff845256d8 at 
iwl_mvm_send_cmd_status+0x28
Apr 11 00:10:48 f13 kernel: #4 0xffffffff84517509 at 
iwl_mvm_sta_rx_agg+0x299
Apr 11 00:10:48 f13 kernel: #5 0xffffffff844f13b8 at 
iwl_mvm_mac_ampdu_action+0x238
Apr 11 00:10:48 f13 kernel: #6 0xffffffff80dbc129 at 
lkpi_ic_ampdu_rx_start+0x129
Apr 11 00:10:48 f13 kernel: #7 0xffffffff80cd1a44 at 
ht_recv_action_ba_addba_request+0x74
Apr 11 00:10:48 f13 kernel: #8 0xffffffff80cf5b8e at sta_input+0x79e
Apr 11 00:10:48 f13 kernel: #9 0xffffffff80cd4e0c at 
ieee80211_input_mimo+0x20c
Apr 11 00:10:48 f13 kernel: #10 0xffffffff80db7b8b at 
lkpi_80211_lhw_rxq_task+0x12b
Apr 11 00:10:48 f13 kernel: #11 0xffffffff80bb4bd2 at 
taskqueue_run_locked+0x182
Apr 11 00:10:48 f13 kernel: #12 0xffffffff80bb5da2 at 
taskqueue_thread_loop+0xc2
Apr 11 00:10:48 f13 kernel: #13 0xffffffff80b0519b at fork_exit+0x7b
Apr 11 00:10:48 f13 kernel: #14 0xffffffff8102c58e at fork_trampoline+0xe
Apr 11 00:10:48 f13 kernel: iwlwifi0: lkpi_ic_ampdu_rx_start: 
mo_ampdu_action returned -5. ni 0xfffffe0154166000 rap 0xfffffe0154168c70
Apr 11 00:11:29 f13 ntpd[70227]: error resolving pool 
0.freebsd.pool.ntp.org: Non-recoverable failure in name resolution (4)
Apr 11 00:11:48 f13 dhclient[78445]: My address (192.168.20.10) was 
deleted, dhclient exiting
Apr 11 00:11:48 f13 wpa_supplicant[78323]: wlan0: 
CTRL-EVENT-DISCONNECTED bssid=f0:9f:c2:04:c7:51 reason=3 locally_generated=1
Apr 11 00:11:48 f13 wpa_supplicant[78323]: wlan0: CTRL-EVENT-DSCP-POLICY 
clear_all
Apr 11 00:11:48 f13 syslogd: last message repeated 1 times
Apr 11 00:11:48 f13 wpa_supplicant[78323]: wlan0: CTRL-EVENT-TERMINATING
Apr 11 00:11:48 f13 dhclient[78445]: connection closed
Apr 11 00:11:48 f13 dhclient[78445]: exiting.
Apr 11 00:11:48 f13 kernel: iwlwifi0: mcast filter cmd error. ret=-5
Apr 11 00:11:48 f13 kernel: iwlwifi0: Failed to synchronize multicast 
groups update
Apr 11 00:11:48 f13 kernel: iwlwifi0: Failed to send flush command (-5)
Apr 11 00:11:48 f13 kernel: iwlwifi0: flush request fail
Apr 11 00:11:48 f13 kernel: iwlwifi0: lkpi_ic_ampdu_rx_stop: 
mo_ampdu_action returned -5. ni 0xfffffe0154166000 rap 0xfffffe0154166d58
Apr 11 00:11:48 f13 kernel: iwlwifi0: lkpi_ic_ampdu_rx_stop: 
mo_ampdu_action returned -5. ni 0xfffffe0154166000 rap 0xfffffe0154167390
Apr 11 00:11:48 f13 kernel: iwlwifi0: _lkpi_iv_key_delete: set_key cmd 
1(DISABLE) for sta f0:9f:c2:04:c7:51 failed: -5
Apr 11 00:11:48 f13 kernel: iwlwifi0: lkpi_ic_ampdu_rx_stop: 
mo_ampdu_action returned -5. ni 0xfffffe0154166000 rap 0xfffffe0154168c70
Apr 11 00:11:48 f13 kernel: iwlwifi0: _lkpi_iv_key_delete: set_key cmd 
1(DISABLE) for sta f0:9f:c2:04:c7:51 failed: -5
Apr 11 00:11:48 f13 kernel: iwlwifi0: PHY ctxt cmd error. ret=-5
Apr 11 00:11:48 f13 kernel: bad state = 0
Apr 11 00:11:48 f13 kernel: iwlwifi0: Failed to trigger RX queues sync (-5)
Apr 11 00:11:48 f13 kernel: wlan0: link state changed to DOWN
Apr 11 00:11:48 f13 kernel: iwlwifi0: Failed to send rate scale config (-5)
Apr 11 00:11:48 f13 kernel: iwlwifi0: lkpi_sta_del_keys: set_key cmd 
1(DISABLE) for sta f0:9f:c2:04:c7:51 failed: -5
Apr 11 00:11:48 f13 syslogd: last message repeated 1 times
Apr 11 00:11:48 f13 kernel: iwlwifi0: lkpi_sta_run_to_init:3092: 
lkpi_sta_del_keys failed: 2
Apr 11 00:11:48 f13 kernel: iwlwifi0: Failed to synchronize multicast 
groups update
Apr 11 00:11:48 f13 kernel: iwlwifi0: Failed to send flush command (-5)
Apr 11 00:11:48 f13 kernel: iwlwifi0: Failed to send MAC_CONFIG_CMD 
(action:2): -5
Apr 11 00:11:48 f13 kernel: iwlwifi0: failed to update MAC 
0xfffffe0168fb7ed2M
Apr 11 00:11:48 f13 kernel: iwlwifi0: Failed to send LINK_CONFIG_CMD 
(action:2): -5
Apr 11 00:11:48 f13 kernel: iwlwifi0: Failed to send LINK_CONFIG_CMD 
(action:3): -5
Apr 11 00:11:48 f13 kernel: iwlwifi0: Failed to send LINK_CONFIG_CMD 
(action:1): -5
Apr 11 00:11:48 f13 kernel: iwlwifi0: PHY ctxt cmd error. ret=-5
Apr 11 00:11:48 f13 kernel: iwlwifi0: Failed to synchronize multicast 
groups update
Apr 11 00:11:48 f13 kernel: iwlwifi0: Failed to send LINK_CONFIG_CMD 
(action:3): -5
Apr 11 00:11:48 f13 kernel: iwlwifi0: Failed to send MAC_CONFIG_CMD 
(action:3): -5
Apr 11 00:11:48 f13 kernel: iwlwifi0: WFPM_UMAC_PD_NOTIFICATION: 0x20
Apr 11 00:11:48 f13 kernel: iwlwifi0: WFPM_LMAC2_PD_NOTIFICATION: 0x1f
Apr 11 00:11:48 f13 kernel: iwlwifi0: WFPM_AUTH_KEY_0: 0x90
Apr 11 00:11:48 f13 kernel: iwlwifi0: CNVI_SCU_SEQ_DATA_DW9: 0x0
Apr 11 00:11:48 f13 kernel: wlan0: Ethernet address: 8c:b8:7e:c9:89:57
Apr 11 00:11:48 f13 wpa_supplicant[78864]: Successfully initialized 
wpa_supplicant
Apr 11 00:11:48 f13 wpa_supplicant[78864]: ioctl[SIOCS80211, op=20, 
val=0, arg_len=7]: Invalid argument
Apr 11 00:11:48 f13 syslogd: last message repeated 1 times
Apr 11 00:11:49 f13 ntpd[70227]: error resolving pool 
2.freebsd.pool.ntp.org: Non-recoverable failure in name resolution (4)
Apr 11 00:12:07 f13 wpa_supplicant[78865]: wlan0: Trying to associate 
with f0:9f:c2:ae:40:6e (SSID='Casa di Mau' freq=5200 MHz)
Apr 11 00:12:07 f13 wpa_supplicant[78865]: wlan0: Associated with 
f0:9f:c2:ae:40:6e
Apr 11 00:12:07 f13 wpa_supplicant[78865]: wlan0: WPA: Key negotiation 
completed with f0:9f:c2:ae:40:6e [PTK=CCMP GTK=CCMP]
Apr 11 00:12:07 f13 wpa_supplicant[78865]: wlan0: CTRL-EVENT-CONNECTED - 
Connection to f0:9f:c2:ae:40:6e completed [id=0 id_str=]
Apr 11 00:12:07 f13 dhclient[79030]: New IP Address (wlan0): 192.168.20.10
Apr 11 00:12:07 f13 dhclient[79034]: New Subnet Mask (wlan0): 255.255.255.0
Apr 11 00:12:07 f13 dhclient[79038]: New Broadcast Address (wlan0): 
192.168.20.255
Apr 11 00:12:07 f13 dhclient[79042]: New Routers (wlan0): 192.168.20.1
Apr 11 00:12:07 f13 kernel: wlan0: link state changed to UP
Apr 11 00:12:07 f13 kernel: WARNING ((__typeof(*mvm->baid_map[baid]) 
*)({ __typeof(mvm->baid_map[baid]) __var = ({ __asm__ __volatile__("": : 
:"memory"); (*(const volatile __typeof(mvm->baid_map[baid]) 
*)&(mvm->baid_map[baid])); }); __asm__ __volatile__("": : :"memory"); 
__var; })) failed at /usr/src/sys/contrib/dev/iwlwifi/mvm/sta.c:3004
Apr 11 00:12:18 f13 syslogd: last message repeated 2 times
Apr 11 00:14:09 f13 kernel: iwlwifi0: linuxkpi_ieee80211_beacon_loss: 
vif 0xfffffe016a595ec0 vap 0xfffffe016a595010 state RUN
Apr 11 00:14:09 f13 wpa_supplicant[78865]: wlan0: 
CTRL-EVENT-DISCONNECTED bssid=f0:9f:c2:ae:40:6e reason=0
Apr 11 00:14:09 f13 wpa_supplicant[78865]: wlan0: Added BSSID 
f0:9f:c2:ae:40:6e into ignore list, ignoring for 10 seconds
Apr 11 00:14:09 f13 wpa_supplicant[78865]: wlan0: BSSID 
f0:9f:c2:ae:40:6e ignore list count incremented to 2, ignoring for 10 
seconds
Apr 11 00:14:09 f13 kernel: iwlwifi0: linuxkpi_ieee80211_beacon_loss: 
vif 0xfffffe016a595ec0 vap 0xfffffe016a595010 state RUN
Apr 11 00:14:09 f13 kernel: wlan0: link state changed to DOWN
Apr 11 00:14:11 f13 wpa_supplicant[78865]: wlan0: Removed BSSID 
f0:9f:c2:ae:40:6e from ignore list (clear)
Apr 11 00:14:11 f13 wpa_supplicant[78865]: wlan0: Trying to associate 
with f0:9f:c2:ae:40:6e (SSID='Casa di Mau' freq=5200 MHz)
Apr 11 00:14:21 f13 wpa_supplicant[78865]: wlan0: Authentication with 
f0:9f:c2:ae:40:6e timed out.
Apr 11 00:14:21 f13 wpa_supplicant[78865]: wlan0: Added BSSID 
f0:9f:c2:ae:40:6e into ignore list, ignoring for 10 seconds
Apr 11 00:14:21 f13 wpa_supplicant[78865]: wlan0: 
CTRL-EVENT-DISCONNECTED bssid=f0:9f:c2:ae:40:6e reason=3 locally_generated=1
Apr 11 00:14:21 f13 wpa_supplicant[78865]: wlan0: BSSID 
f0:9f:c2:ae:40:6e ignore list count incremented to 2, ignoring for 10 
seconds
Apr 11 00:14:21 f13 wpa_supplicant[78865]: wlan0: CTRL-EVENT-DSCP-POLICY 
clear_all
Apr 11 00:14:46 f13 wpa_supplicant[78865]: wlan0: Authentication with 
f0:9f:c2:04:c7:51 timed out.
Apr 11 00:14:46 f13 wpa_supplicant[78865]: wlan0: Added BSSID 
f0:9f:c2:04:c7:51 into ignore list, ignoring for 10 seconds
Apr 11 00:14:46 f13 wpa_supplicant[78865]: wlan0: 
CTRL-EVENT-DISCONNECTED bssid=f0:9f:c2:04:c7:51 reason=3 locally_generated=1
Apr 11 00:14:46 f13 wpa_supplicant[78865]: wlan0: BSSID 
f0:9f:c2:04:c7:51 ignore list count incremented to 2, ignoring for 10 
seconds
Apr 11 00:14:46 f13 wpa_supplicant[78865]: wlan0: CTRL-EVENT-DSCP-POLICY 
clear_all
Apr 11 00:14:58 f13 wpa_supplicant[78865]: wlan0: Trying to associate 
with f0:9f:c2:ae:40:6e (SSID='Casa di Mau' freq=5200 MHz)
Apr 11 00:14:58 f13 wpa_supplicant[78865]: wlan0: Associated with 
f0:9f:c2:ae:40:6e
Apr 11 00:14:58 f13 wpa_supplicant[78865]: wlan0: WPA: Key negotiation 
completed with f0:9f:c2:ae:40:6e [PTK=CCMP GTK=CCMP]
Apr 11 00:14:58 f13 wpa_supplicant[78865]: wlan0: CTRL-EVENT-CONNECTED - 
Connection to f0:9f:c2:ae:40:6e completed [id=0 id_str=]
Apr 11 00:14:58 f13 kernel: wlan0: link state changed to UP
Apr 11 00:14:58 f13 dhclient[79800]: New IP Address (wlan0): 192.168.20.10
Apr 11 00:14:58 f13 dhclient[79804]: New Subnet Mask (wlan0): 255.255.255.0
Apr 11 00:14:58 f13 dhclient[79808]: New Broadcast Address (wlan0): 
192.168.20.255
Apr 11 00:14:58 f13 dhclient[79812]: New Routers (wlan0): 192.168.20.1