Bug 215697
| Summary: | iwlwifi firmware is reloading several times 67.8f59b80b.0 cc-a0-67.ucode | ||
|---|---|---|---|
| Product: | Drivers | Reporter: | Christian (info) |
| Component: | network-wireless-intel | Assignee: | Default virtual assignee for network-wireless-intel (drivers_network-wireless-intel) |
| Status: | CLOSED OBSOLETE | ||
| Severity: | high | CC: | regressions, s.viper.z |
| Priority: | P1 | ||
| Hardware: | All | ||
| OS: | Linux | ||
| Kernel Version: | 5.16.14-arch1-1 | Subsystem: | |
| Regression: | No | Bisected commit-id: | |
|
Description
Christian
2022-03-17 19:55:48 UTC
I'm not one of the developers that needs to take care of this, but was that just a one time problem or did it occur multiple times? and does it still happen with 5.16.16 version? There was a fix that might be related. (In reply to The Linux kernel's regression tracker (Thorsten Leemhuis) from comment #1) > I'm not one of the developers that needs to take care of this, but was that > just a one time problem or did it occur multiple times? It happened several times that one day. It was the first time I recognised it / had such a problem with always reconnecting and network off. So maybe it was because of this specific kernel release. However I changed some settings these days to fix it. I changed from 2.4Ghz to 5Ghz then the error was gone. I will do some tries and see what the response is. I just changed back to 2.4Ghz as the connection is more strong on this frequency. If the error appears again I will post it here. See below. This happens when switching to 2.4 Ghz (and obviously force the adapter to use 2.4 Ghz). The Firmware unload thing did not happen yet, but I did not have time to do experiments with it at the moment. I switched back to A (5 Ghz) and it works fine again. But I will try again in next days. [ 6.629142] iwlwifi 0000:01:00.0: enabling device (0000 -> 0002) [ 6.666091] iwlwifi 0000:01:00.0: api flags index 2 larger than supported by driver [ 6.666106] iwlwifi 0000:01:00.0: TLV_FW_FSEQ_VERSION: FSEQ Version: 89.3.35.37 [ 6.666344] iwlwifi 0000:01:00.0: loaded firmware version 67.8f59b80b.0 cc-a0-67.ucode op_mode iwlmvm [ 6.824404] iwlwifi 0000:01:00.0: Detected Intel(R) Wi-Fi 6 AX200 160MHz, REV=0x340 [ 6.990308] iwlwifi 0000:01:00.0: Detected RF HR B3, rfid=0x10a100 [ 7.063333] iwlwifi 0000:01:00.0: base HW address: 64:bc:58:54:9b:83 [ 7.343601] iwlwifi 0000:01:00.0 wlp1s0: renamed from wlan0 [ 11.838640] wlp1s0: authenticate with 34:2c:c4:18:de:fe [ 11.863206] wlp1s0: send auth to 34:2c:c4:18:de:fe (try 1/3) [ 11.933776] wlp1s0: authenticated [ 11.934188] wlp1s0: associate with 34:2c:c4:18:de:fe (try 1/3) [ 11.952177] wlp1s0: RX AssocResp from 34:2c:c4:18:de:fe (capab=0x1411 status=0 aid=2) [ 11.993797] wlp1s0: associated [ 11.993856] wlp1s0: Limiting TX power to 20 (20 - 0) dBm as advertised by 34:2c:c4:18:de:fe [ 92.034931] wlp1s0: Connection to AP 34:2c:c4:18:de:fe lost [ 104.757596] wlp1s0: authenticate with 34:2c:c4:18:de:fe [ 104.765101] wlp1s0: send auth to 34:2c:c4:18:de:fe (try 1/3) [ 104.805934] wlp1s0: authenticated [ 104.807513] wlp1s0: associate with 34:2c:c4:18:de:fe (try 1/3) [ 105.400875] wlp1s0: associate with 34:2c:c4:18:de:fe (try 2/3) [ 106.300884] iwlwifi 0000:01:00.0: Not associated and the session protection is over already... [ 106.300944] wlp1s0: Connection to AP 34:2c:c4:18:de:fe lost [ 106.441119] wlp1s0: associate with 34:2c:c4:18:de:fe (try 3/3) [ 107.341111] iwlwifi 0000:01:00.0: Not associated and the session protection is over already... [ 107.341176] wlp1s0: Connection to AP 34:2c:c4:18:de:fe lost [ 107.427852] wlp1s0: association with 34:2c:c4:18:de:fe timed out [ 122.744444] wlp1s0: authenticate with 34:2c:c4:18:de:fe [ 122.750860] wlp1s0: send auth to 34:2c:c4:18:de:fe (try 1/3) [ 123.428726] wlp1s0: send auth to 34:2c:c4:18:de:fe (try 2/3) [ 124.327741] iwlwifi 0000:01:00.0: Not associated and the session protection is over already... [ 124.327849] wlp1s0: Connection to AP 34:2c:c4:18:de:fe lost [ 124.415356] wlp1s0: send auth to 34:2c:c4:18:de:fe (try 3/3) [ 125.314468] iwlwifi 0000:01:00.0: Not associated and the session protection is over already... [ 125.314545] wlp1s0: Connection to AP 34:2c:c4:18:de:fe lost [ 125.427816] wlp1s0: authentication with 34:2c:c4:18:de:fe timed out [ 143.352040] wlp1s0: authenticate with 34:2c:c4:18:de:fe [ 143.357732] wlp1s0: send auth to 34:2c:c4:18:de:fe (try 1/3) [ 144.256787] iwlwifi 0000:01:00.0: Not associated and the session protection is over already... [ 144.256831] wlp1s0: Connection to AP 34:2c:c4:18:de:fe lost [ 144.468566] wlp1s0: send auth to 34:2c:c4:18:de:fe (try 2/3) [ 144.650103] wlp1s0: authenticated [ 144.657798] wlp1s0: associate with 34:2c:c4:18:de:fe (try 1/3) [ 145.367804] iwlwifi 0000:01:00.0: Not associated and the session protection is over already... [ 145.367874] wlp1s0: Connection to AP 34:2c:c4:18:de:fe lost [ 145.454517] wlp1s0: associate with 34:2c:c4:18:de:fe (try 2/3) [ 146.354633] iwlwifi 0000:01:00.0: Not associated and the session protection is over already... [ 146.354705] wlp1s0: Connection to AP 34:2c:c4:18:de:fe lost [ 146.414450] wlp1s0: associate with 34:2c:c4:18:de:fe (try 3/3) [ 147.314305] iwlwifi 0000:01:00.0: Not associated and the session protection is over already... [ 147.314351] wlp1s0: Connection to AP 34:2c:c4:18:de:fe lost [ 147.427575] wlp1s0: association with 34:2c:c4:18:de:fe timed out [ 160.032662] wlp1s0: authenticate with 34:2c:c4:18:de:fe [ 160.038616] wlp1s0: send auth to 34:2c:c4:18:de:fe (try 1/3) [ 160.937664] iwlwifi 0000:01:00.0: Not associated and the session protection is over already... [ 160.937734] wlp1s0: Connection to AP 34:2c:c4:18:de:fe lost [ 161.428552] wlp1s0: send auth to 34:2c:c4:18:de:fe (try 2/3) [ 161.514478] wlp1s0: authenticated [ 161.517734] wlp1s0: associate with 34:2c:c4:18:de:fe (try 1/3) [ 162.327606] iwlwifi 0000:01:00.0: Not associated and the session protection is over already... [ 162.327675] wlp1s0: Connection to AP 34:2c:c4:18:de:fe lost [ 162.444402] wlp1s0: associate with 34:2c:c4:18:de:fe (try 2/3) [ 163.344300] iwlwifi 0000:01:00.0: Not associated and the session protection is over already... [ 163.344344] wlp1s0: Connection to AP 34:2c:c4:18:de:fe lost [ 163.427799] wlp1s0: associate with 34:2c:c4:18:de:fe (try 3/3) [ 164.327895] iwlwifi 0000:01:00.0: Not associated and the session protection is over already... [ 164.327967] wlp1s0: Connection to AP 34:2c:c4:18:de:fe lost [ 164.467752] wlp1s0: association with 34:2c:c4:18:de:fe timed out [ 180.489761] wlp1s0: authenticate with 34:2c:c4:18:de:fe [ 180.494685] wlp1s0: send auth to 34:2c:c4:18:de:fe (try 1/3) [ 181.347519] wlp1s0: authenticated [ 181.354196] wlp1s0: associate with 34:2c:c4:18:de:fe (try 1/3) [ 181.803362] wlp1s0: RX AssocResp from 34:2c:c4:18:de:fe (capab=0x1411 status=17 aid=4) [ 181.803382] wlp1s0: 34:2c:c4:18:de:fe denied association (code=17) [ 185.022600] wlp1s0: authenticate with 34:2c:c4:18:de:fe [ 185.027904] wlp1s0: send auth to 34:2c:c4:18:de:fe (try 1/3) [ 185.927161] iwlwifi 0000:01:00.0: Not associated and the session protection is over already... [ 185.927230] wlp1s0: Connection to AP 34:2c:c4:18:de:fe lost [ 186.441788] wlp1s0: send auth to 34:2c:c4:18:de:fe (try 2/3) [ 187.341008] iwlwifi 0000:01:00.0: Not associated and the session protection is over already... [ 187.341078] wlp1s0: Connection to AP 34:2c:c4:18:de:fe lost [ 187.428817] wlp1s0: send auth to 34:2c:c4:18:de:fe (try 3/3) [ 188.269752] wlp1s0: authenticated [ 188.270857] wlp1s0: associate with 34:2c:c4:18:de:fe (try 1/3) [ 189.170859] iwlwifi 0000:01:00.0: Not associated and the session protection is over already... [ 189.170917] wlp1s0: Connection to AP 34:2c:c4:18:de:fe lost [ 189.427793] wlp1s0: associate with 34:2c:c4:18:de:fe (try 2/3) [ 189.745228] wlp1s0: RX AssocResp from 34:2c:c4:18:de:fe (capab=0x1411 status=0 aid=4) [ 189.777828] wlp1s0: associated [ 189.927806] wlp1s0: Limiting TX power to 20 (20 - 0) dBm as advertised by 34:2c:c4:18:de:fe [ 200.643611] wlp1s0: deauthenticated from 34:2c:c4:18:de:fe (Reason: 15=4WAY_HANDSHAKE_TIMEOUT) [ 224.869641] wlp1s0: authenticate with 34:2c:c4:18:de:fe [ 224.878094] wlp1s0: send auth to 34:2c:c4:18:de:fe (try 1/3) [ 225.428619] wlp1s0: send auth to 34:2c:c4:18:de:fe (try 2/3) [ 226.327659] iwlwifi 0000:01:00.0: Not associated and the session protection is over already... [ 226.327766] wlp1s0: Connection to AP 34:2c:c4:18:de:fe lost [ 226.388895] wlp1s0: send auth to 34:2c:c4:18:de:fe (try 3/3) [ 227.287776] iwlwifi 0000:01:00.0: Not associated and the session protection is over already... [ 227.287814] wlp1s0: Connection to AP 34:2c:c4:18:de:fe lost [ 227.427802] wlp1s0: authentication with 34:2c:c4:18:de:fe timed out [ 235.278196] wlp1s0: authenticate with 34:2c:c4:18:de:fe [ 235.284081] wlp1s0: send auth to 34:2c:c4:18:de:fe (try 1/3) [ 235.440780] wlp1s0: authenticated [ 235.444399] wlp1s0: associate with 34:2c:c4:18:de:fe (try 1/3) [ 235.487673] wlp1s0: RX AssocResp from 34:2c:c4:18:de:fe (capab=0x1411 status=17 aid=4) [ 235.487695] wlp1s0: 34:2c:c4:18:de:fe denied association (code=17) [ 236.577817] wlp1s0: authenticate with 34:2c:c4:18:de:fe [ 236.584066] wlp1s0: send auth to 34:2c:c4:18:de:fe (try 1/3) [ 236.762783] wlp1s0: authenticated [ 236.764168] wlp1s0: associate with 34:2c:c4:18:de:fe (try 1/3) [ 237.063801] wlp1s0: RX AssocResp from 34:2c:c4:18:de:fe (capab=0x1411 status=17 aid=4) [ 237.063814] wlp1s0: 34:2c:c4:18:de:fe denied association (code=17) [ 247.971228] wlp1s0: authenticate with 34:2c:c4:18:de:fe [ 247.978209] wlp1s0: send auth to 34:2c:c4:18:de:fe (try 1/3) [ 248.069693] wlp1s0: authenticated [ 248.070839] wlp1s0: associate with 34:2c:c4:18:de:fe (try 1/3) [ 248.877235] iwlwifi 0000:01:00.0: Not associated and the session protection is over already... [ 248.877331] wlp1s0: Connection to AP 34:2c:c4:18:de:fe lost [ 249.002447] wlp1s0: aborting association with 34:2c:c4:18:de:fe by local choice (Reason: 3=DEAUTH_LEAVING) [ 254.765861] wlp1s0: authenticate with 34:2c:c4:18:de:7a [ 254.774495] wlp1s0: send auth to 34:2c:c4:18:de:7a (try 1/3) [ 254.826626] wlp1s0: authenticated [ 254.827513] wlp1s0: associate with 34:2c:c4:18:de:7a (try 1/3) [ 254.870286] wlp1s0: RX AssocResp from 34:2c:c4:18:de:7a (capab=0x511 status=0 aid=5) [ 254.893183] wlp1s0: associated [ 254.959650] wlp1s0: Limiting TX power to 17 (17 - 0) dBm as advertised by 34:2c:c4:18:de:7a Have the same issue on 5.16.19 on Intel(R) Wi-Fi 6 AX201 160MHz, REV=0x354. Occurs several times a day. See a typical output from journalctl below. Please let me know what additional info I can provide to help identify the issue. May 02 11:15:19 ZenBook kernel: iwlwifi 0000:00:14.3: Microcode SW error detected. Restarting 0x0. May 02 11:15:19 ZenBook kernel: iwlwifi 0000:00:14.3: Start IWL Error Log Dump: May 02 11:15:19 ZenBook kernel: iwlwifi 0000:00:14.3: Transport status: 0x0000004A, valid: 6 May 02 11:15:19 ZenBook kernel: iwlwifi 0000:00:14.3: Loaded firmware version: 67.8f59b80b.0 QuZ-a0-hr-b0-67.ucode May 02 11:15:19 ZenBook kernel: iwlwifi 0000:00:14.3: 0x00000942 | ADVANCED_SYSASSERT May 02 11:15:19 ZenBook kernel: iwlwifi 0000:00:14.3: 0x0000A2B0 | trm_hw_status0 May 02 11:15:19 ZenBook kernel: iwlwifi 0000:00:14.3: 0x00000000 | trm_hw_status1 May 02 11:15:19 ZenBook kernel: iwlwifi 0000:00:14.3: 0x004CB2DE | branchlink2 May 02 11:15:19 ZenBook kernel: iwlwifi 0000:00:14.3: 0x004C1A5A | interruptlink1 May 02 11:15:19 ZenBook kernel: iwlwifi 0000:00:14.3: 0x004C1A5A | interruptlink2 May 02 11:15:19 ZenBook kernel: iwlwifi 0000:00:14.3: 0xFFF4FFC0 | data1 May 02 11:15:19 ZenBook kernel: iwlwifi 0000:00:14.3: 0x50010102 | data2 May 02 11:15:19 ZenBook kernel: iwlwifi 0000:00:14.3: 0x010C0606 | data3 May 02 11:15:19 ZenBook kernel: iwlwifi 0000:00:14.3: 0x00000000 | beacon time May 02 11:15:19 ZenBook kernel: iwlwifi 0000:00:14.3: 0x0001EBEB | tsf low May 02 11:15:19 ZenBook kernel: iwlwifi 0000:00:14.3: 0x00000000 | tsf hi May 02 11:15:19 ZenBook kernel: iwlwifi 0000:00:14.3: 0x00000000 | time gp1 May 02 11:15:19 ZenBook kernel: iwlwifi 0000:00:14.3: 0x00024ABC | time gp2 May 02 11:15:19 ZenBook kernel: iwlwifi 0000:00:14.3: 0x00000001 | uCode revision type May 02 11:15:19 ZenBook kernel: iwlwifi 0000:00:14.3: 0x00000043 | uCode version major May 02 11:15:19 ZenBook kernel: iwlwifi 0000:00:14.3: 0x8F59B80B | uCode version minor May 02 11:15:19 ZenBook kernel: iwlwifi 0000:00:14.3: 0x00000351 | hw version May 02 11:15:19 ZenBook kernel: iwlwifi 0000:00:14.3: 0x00C89004 | board version May 02 11:15:19 ZenBook kernel: iwlwifi 0000:00:14.3: 0x801CFD25 | hcmd May 02 11:15:19 ZenBook kernel: iwlwifi 0000:00:14.3: 0xA0121000 | isr0 May 02 11:15:19 ZenBook kernel: iwlwifi 0000:00:14.3: 0x00000000 | isr1 May 02 11:15:19 ZenBook kernel: iwlwifi 0000:00:14.3: 0x08F00002 | isr2 May 02 11:15:19 ZenBook kernel: iwlwifi 0000:00:14.3: 0x04C0001C | isr3 May 02 11:15:19 ZenBook kernel: iwlwifi 0000:00:14.3: 0x00000000 | isr4 May 02 11:15:19 ZenBook kernel: iwlwifi 0000:00:14.3: 0x00000000 | last cmd Id May 02 11:15:19 ZenBook kernel: iwlwifi 0000:00:14.3: 0x00012CDA | wait_event May 02 11:15:19 ZenBook kernel: iwlwifi 0000:00:14.3: 0x00000000 | l2p_control May 02 11:15:19 ZenBook kernel: iwlwifi 0000:00:14.3: 0x00010000 | l2p_duration May 02 11:15:19 ZenBook kernel: iwlwifi 0000:00:14.3: 0x00000000 | l2p_mhvalid May 02 11:15:19 ZenBook kernel: iwlwifi 0000:00:14.3: 0x00000000 | l2p_addr_match May 02 11:15:19 ZenBook kernel: iwlwifi 0000:00:14.3: 0x00000009 | lmpm_pmg_sel May 02 11:15:19 ZenBook kernel: iwlwifi 0000:00:14.3: 0x00000000 | timestamp May 02 11:15:19 ZenBook kernel: iwlwifi 0000:00:14.3: 0x00000020 | flow_handler May 02 11:15:19 ZenBook kernel: iwlwifi 0000:00:14.3: Start IWL Error Log Dump: May 02 11:15:19 ZenBook kernel: iwlwifi 0000:00:14.3: Transport status: 0x0000004A, valid: 7 May 02 11:15:19 ZenBook kernel: iwlwifi 0000:00:14.3: 0x20000070 | NMI_INTERRUPT_LMAC_FATAL May 02 11:15:19 ZenBook kernel: iwlwifi 0000:00:14.3: 0x00000000 | umac branchlink1 May 02 11:15:19 ZenBook kernel: iwlwifi 0000:00:14.3: 0x80455E52 | umac branchlink2 May 02 11:15:19 ZenBook kernel: iwlwifi 0000:00:14.3: 0x804745FA | umac interruptlink1 May 02 11:15:19 ZenBook kernel: iwlwifi 0000:00:14.3: 0x804745FA | umac interruptlink2 May 02 11:15:19 ZenBook kernel: iwlwifi 0000:00:14.3: 0x00000400 | umac data1 May 02 11:15:19 ZenBook kernel: iwlwifi 0000:00:14.3: 0x804745FA | umac data2 May 02 11:15:19 ZenBook kernel: iwlwifi 0000:00:14.3: 0x00000000 | umac data3 May 02 11:15:19 ZenBook kernel: iwlwifi 0000:00:14.3: 0x00000043 | umac major May 02 11:15:19 ZenBook kernel: iwlwifi 0000:00:14.3: 0x8F59B80B | umac minor May 02 11:15:19 ZenBook kernel: iwlwifi 0000:00:14.3: 0x00024AE3 | frame pointer May 02 11:15:19 ZenBook kernel: iwlwifi 0000:00:14.3: 0xC0886264 | stack pointer May 02 11:15:19 ZenBook kernel: iwlwifi 0000:00:14.3: 0x00010C00 | last host cmd May 02 11:15:19 ZenBook kernel: iwlwifi 0000:00:14.3: 0x00000000 | isr status reg May 02 11:15:19 ZenBook kernel: iwlwifi 0000:00:14.3: IML/ROM dump: May 02 11:15:19 ZenBook kernel: iwlwifi 0000:00:14.3: 0x00000003 | IML/ROM error/state May 02 11:15:19 ZenBook kernel: iwlwifi 0000:00:14.3: 0x000059FA | IML/ROM data1 May 02 11:15:19 ZenBook kernel: iwlwifi 0000:00:14.3: 0x00000080 | IML/ROM WFPM_AUTH_KEY_0 May 02 11:15:19 ZenBook kernel: iwlwifi 0000:00:14.3: Fseq Registers: May 02 11:15:19 ZenBook kernel: iwlwifi 0000:00:14.3: 0x60000000 | FSEQ_ERROR_CODE May 02 11:15:19 ZenBook kernel: iwlwifi 0000:00:14.3: 0x80290033 | FSEQ_TOP_INIT_VERSION May 02 11:15:19 ZenBook kernel: iwlwifi 0000:00:14.3: 0x00090006 | FSEQ_CNVIO_INIT_VERSION May 02 11:15:19 ZenBook kernel: iwlwifi 0000:00:14.3: 0x0000A482 | FSEQ_OTP_VERSION May 02 11:15:19 ZenBook kernel: iwlwifi 0000:00:14.3: 0x00000003 | FSEQ_TOP_CONTENT_VERSION May 02 11:15:19 ZenBook kernel: iwlwifi 0000:00:14.3: 0x4552414E | FSEQ_ALIVE_TOKEN May 02 11:15:19 ZenBook kernel: iwlwifi 0000:00:14.3: 0x20000302 | FSEQ_CNVI_ID May 02 11:15:19 ZenBook kernel: iwlwifi 0000:00:14.3: 0x01300504 | FSEQ_CNVR_ID May 02 11:15:19 ZenBook kernel: iwlwifi 0000:00:14.3: 0x20000302 | CNVI_AUX_MISC_CHIP May 02 11:15:19 ZenBook kernel: iwlwifi 0000:00:14.3: 0x01300504 | CNVR_AUX_MISC_CHIP May 02 11:15:19 ZenBook kernel: iwlwifi 0000:00:14.3: 0x05B0905B | CNVR_SCU_SD_REGS_SD_REG_DIG_DCDC_VTRIM May 02 11:15:19 ZenBook kernel: iwlwifi 0000:00:14.3: 0x0000025B | CNVR_SCU_SD_REGS_SD_REG_ACTIVE_VDIG_MIRROR May 02 11:15:19 ZenBook kernel: iwlwifi 0000:00:14.3: Starting mac, retry will be triggered anyway May 02 11:15:19 ZenBook kernel: iwlwifi 0000:00:14.3: Failed to start RT ucode: -5 May 02 11:15:19 ZenBook kernel: iwlwifi 0000:00:14.3: WRT: Collecting data: ini trigger 13 fired (delay=0ms). May 02 11:15:19 ZenBook kernel: iwlwifi 0000:00:14.3: mac start retry 0 FWIW, 5.16.y is EOL, most developer won't care about it anymore. I also have no idea if any iwlwifi developer is looking at this bug or even getting things mailed -- maybe they are, but bugzilla.kernel.org is not the official place for this subsystem, as explained by https://www.kernel.org/doc/html/latest/admin-guide/reporting-issues.html I could pick this up and poke them, if somebody tells me - if the problem doesn't occur with an older Linux version (say 5.15) - if somebody confirms the problem is still present with the latest 5.17.y kernel; testing with mainline would be even better note, there is already another ticket about a problem like this; note sure if they are duplicates: https://bugzilla.kernel.org/show_bug.cgi?id=215635 |