On Fri, May 14, 2021 at 02:39:15PM +0200, Matthias Schmidt wrote: > I am now running > > OpenBSD 6.9-current (GENERIC.MP) #17: Wed May 12 11:14:50 MDT 2021 > dera...@amd64.openbsd.org:/usr/src/sys/arch/amd64/compile/GENERIC.MP > > which contains your described fix. Since then I occasionally see a new > firmare error I haven't seen before. Most of the time the interface > recovers but sometimes I have to bring it down and up again.
This looks like your AP is disappearing for some reason. The AP may be switching channels or you may have moved out of range. And the driver doesn't handle the resulting state transitions correctly. Thanks for reporting this! I will look into it. > May 14 13:59:59 sigma /bsd: iwm0: received msg 1/2 of the group key handshake > from cc:ce:1e:8b:cf:d1 > May 14 13:59:59 sigma /bsd: iwm0: sending msg 2/2 of the group key handshake > to cc:ce:1e:8b:cf:d1 > May 14 14:01:20 sigma /bsd: iwm0: RUN -> ASSOC > May 14 14:01:20 sigma /bsd: iwm0: sending action to cc:ce:1e:8b:cf:d1 on > channel 100 mode 11n > May 14 14:01:20 sigma /bsd: iwm0: sending assoc_req to cc:ce:1e:8b:cf:d1 on > channel 100 mode 11n > May 14 14:01:24 sigma /bsd: iwm0: association timed out for cc:ce:1e:8b:cf:d1 > May 14 14:01:24 sigma /bsd: iwm0: dumping device error log > May 14 14:01:24 sigma /bsd: iwm0: Start Error Log Dump: > May 14 14:01:24 sigma /bsd: iwm0: Status: 0x9, count: 6 > May 14 14:01:24 sigma /bsd: iwm0: 0x00003421 | ADVANCED_SYSASSERT > May 14 14:01:24 sigma /bsd: iwm0: 00000220 | trm_hw_status0 > May 14 14:01:24 sigma /bsd: iwm0: 00000000 | trm_hw_status1 > May 14 14:01:24 sigma /bsd: iwm0: 00023FDC | branchlink2 > May 14 14:01:24 sigma /bsd: iwm0: 0003915A | interruptlink1 > May 14 14:01:24 sigma /bsd: iwm0: 00000000 | interruptlink2 > May 14 14:01:24 sigma /bsd: iwm0: 00000000 | data1 > May 14 14:01:24 sigma /bsd: iwm0: 00000001 | data2 > May 14 14:01:24 sigma /bsd: iwm0: DEADBEEF | data3 > May 14 14:01:24 sigma /bsd: iwm0: 00000000 | beacon time > May 14 14:01:24 sigma /bsd: iwm0: E8F0FA81 | tsf low > May 14 14:01:24 sigma /bsd: iwm0: 00000024 | tsf hi > May 14 14:01:24 sigma /bsd: iwm0: 00000000 | time gp1 > May 14 14:01:24 sigma /bsd: iwm0: 20010BB2 | time gp2 > May 14 14:01:24 sigma /bsd: iwm0: 00000001 | uCode revision type > May 14 14:01:24 sigma /bsd: iwm0: 00000022 | uCode version major > May 14 14:01:24 sigma /bsd: iwm0: 00000000 | uCode version minor > May 14 14:01:24 sigma /bsd: iwm0: 00000230 | hw version > May 14 14:01:24 sigma /bsd: iwm0: 18089000 | board version > May 14 14:01:24 sigma /bsd: iwm0: 007C0028 | hcmd > May 14 14:01:24 sigma /bsd: iwm0: 24022082 | isr0 > May 14 14:01:24 sigma /bsd: iwm0: 01000000 | isr1 > May 14 14:01:24 sigma /bsd: iwm0: 08201802 | isr2 > May 14 14:01:24 sigma /bsd: iwm0: 004140C0 | isr3 > May 14 14:01:24 sigma /bsd: iwm0: 00000000 | isr4 > May 14 14:01:24 sigma /bsd: iwm0: 007B002B | last cmd Id > May 14 14:01:24 sigma /bsd: iwm0: 00000000 | wait_event > May 14 14:01:24 sigma /bsd: iwm0: 00000080 | l2p_control > May 14 14:01:24 sigma /bsd: iwm0: 00018010 | l2p_duration > May 14 14:01:24 sigma /bsd: iwm0: 0000003F | l2p_mhvalid > May 14 14:01:24 sigma /bsd: iwm0: 00000000 | l2p_addr_match > May 14 14:01:24 sigma /bsd: iwm0: 0000000D | lmpm_pmg_sel > May 14 14:01:24 sigma /bsd: iwm0: 30101345 | timestamp > May 14 14:01:24 sigma /bsd: iwm0: 0000A8B8 | flow_handler > May 14 14:01:24 sigma /bsd: iwm0: Start UMAC Error Log Dump: > May 14 14:01:24 sigma /bsd: iwm0: Status: 0x9, count: 7 > May 14 14:01:24 sigma /bsd: iwm0: 0x00000070 | NMI_INTERRUPT_LMAC_FATAL > May 14 14:01:24 sigma /bsd: iwm0: 0x00000000 | umac branchlink1 > May 14 14:01:24 sigma /bsd: iwm0: 0xC0086964 | umac branchlink2 > May 14 14:01:24 sigma /bsd: iwm0: 0xC0083A94 | umac interruptlink1 > May 14 14:01:24 sigma /bsd: iwm0: 0xC0083A94 | umac interruptlink2 > May 14 14:01:24 sigma /bsd: iwm0: 0x00000800 | umac data1 > May 14 14:01:24 sigma /bsd: iwm0: 0xC0083A94 | umac data2 > May 14 14:01:24 sigma /bsd: iwm0: 0xDEADBEEF | umac data3 > May 14 14:01:24 sigma /bsd: iwm0: 0x00000022 | umac major > May 14 14:01:24 sigma /bsd: iwm0: 0x00000000 | umac minor > May 14 14:01:24 sigma /bsd: iwm0: 0xC088628C | frame pointer > May 14 14:01:24 sigma /bsd: iwm0: 0xC088628C | stack pointer > May 14 14:01:24 sigma /bsd: iwm0: 0x007C0028 | last host cmd > May 14 14:01:24 sigma /bsd: iwm0: 0x00000000 | isr status reg > May 14 14:01:24 sigma /bsd: driver status: > May 14 14:01:24 sigma /bsd: tx ring 0: qid=0 cur=125 queued=1 > May 14 14:01:24 sigma /bsd: tx ring 1: qid=1 cur=0 queued=0 > May 14 14:01:24 sigma /bsd: tx ring 2: qid=2 cur=0 queued=0 > May 14 14:01:24 sigma /bsd: tx ring 3: qid=3 cur=0 queued=0 > May 14 14:01:24 sigma /bsd: tx ring 4: qid=4 cur=0 queued=0 > May 14 14:01:24 sigma /bsd: tx ring 5: qid=5 cur=119 queued=2 > May 14 14:01:24 sigma /bsd: tx ring 6: qid=6 cur=0 queued=0 > May 14 14:01:24 sigma /bsd: tx ring 7: qid=7 cur=0 queued=0 > May 14 14:01:24 sigma /bsd: tx ring 8: qid=8 cur=0 queued=0 > May 14 14:01:24 sigma /bsd: tx ring 9: qid=9 cur=0 queued=0 > May 14 14:01:24 sigma /bsd: tx ring 10: qid=10 cur=16 queued=0 > May 14 14:01:24 sigma /bsd: tx ring 11: qid=11 cur=0 queued=0 > May 14 14:01:24 sigma /bsd: tx ring 12: qid=12 cur=0 queued=0 > May 14 14:01:24 sigma /bsd: tx ring 13: qid=13 cur=0 queued=0 > May 14 14:01:24 sigma /bsd: tx ring 14: qid=14 cur=0 queued=0 > May 14 14:01:24 sigma /bsd: tx ring 15: qid=15 cur=0 queued=0 > May 14 14:01:24 sigma /bsd: tx ring 16: qid=16 cur=0 queued=0 > May 14 14:01:24 sigma /bsd: tx ring 17: qid=17 cur=0 queued=0 > May 14 14:01:24 sigma /bsd: tx ring 18: qid=18 cur=0 queued=0 > May 14 14:01:24 sigma /bsd: tx ring 19: qid=19 cur=0 queued=0 > May 14 14:01:24 sigma /bsd: tx ring 20: qid=20 cur=0 queued=0 > May 14 14:01:24 sigma /bsd: tx ring 21: qid=21 cur=0 queued=0 > May 14 14:01:24 sigma /bsd: tx ring 22: qid=22 cur=0 queued=0 > May 14 14:01:24 sigma /bsd: tx ring 23: qid=23 cur=0 queued=0 > May 14 14:01:24 sigma /bsd: tx ring 24: qid=24 cur=0 queued=0 > May 14 14:01:24 sigma /bsd: tx ring 25: qid=25 cur=0 queued=0 > May 14 14:01:24 sigma /bsd: tx ring 26: qid=26 cur=0 queued=0 > May 14 14:01:24 sigma /bsd: tx ring 27: qid=27 cur=0 queued=0 > May 14 14:01:24 sigma /bsd: tx ring 28: qid=28 cur=0 queued=0 > May 14 14:01:24 sigma /bsd: tx ring 29: qid=29 cur=0 queued=0 > May 14 14:01:24 sigma /bsd: tx ring 30: qid=30 cur=0 queued=0 > May 14 14:01:24 sigma /bsd: rx ring: cur=173 > May 14 14:01:24 sigma /bsd: 802.11 state ASSOC > May 14 14:01:24 sigma /bsd: iwm0: fatal firmware error > May 14 14:01:25 sigma /bsd: iwm0: could not remove MAC context (error 35) > May 14 14:01:25 sigma /bsd: iwm0: ASSOC -> INIT > May 14 14:01:26 sigma /bsd: iwm0: begin active scan > May 14 14:01:26 sigma /bsd: iwm0: INIT -> SCAN > May 14 14:01:30 sigma /bsd: iwm0: end active scan > May 14 14:01:30 sigma /bsd: iwm0: best AP cc:ce:1e:8b:cf:d2 "karlsruhe.v01d" > score 73 > May 14 14:01:30 sigma /bsd: iwm0: switching to network "karlsruhe.v01d" > May 14 14:01:30 sigma /bsd: iwm0: - 04:a2:22:1a:c7:2a 1 +17 54M ess > privacy rsn "WLAN-800501"! > May 14 14:01:30 sigma /bsd: iwm0: - 04:a2:22:23:7e:e4 6 +17 54M ess > privacy rsn "WLAN-800501"! > May 14 14:01:30 sigma /bsd: iwm0: - 04:a2:22:23:7e:e6 6 +17 54M ess > no! rsn! "Telekom_FON"! > May 14 14:01:30 sigma /bsd: iwm0: - 06:24:fe:1c:dc:03 6 +20 54M ess > privacy rsn "Vodafone-8EE7"! > May 14 14:01:30 sigma /bsd: iwm0: - 36:2c:94:34:6d:ed 1 +17 54M ess > no! rsn! "Vodafone Hotspot"! > May 14 14:01:30 sigma /bsd: iwm0: - 40:2b:50:f4:a1:84 11 +21 54M ess > privacy rsn "Vodafone-6C13"! > May 14 14:01:30 sigma /bsd: iwm0: - 90:5c:44:cc:01:53 36 +29 54M ess > privacy rsn "FBI Surveillance Van"! > May 14 14:01:30 sigma /bsd: iwm0: - 90:5c:44:cc:01:73 6 +28 54M ess > privacy rsn "FBI Surveillance Van"! > May 14 14:01:30 sigma /bsd: iwm0: - 90:5c:44:db:c8:e5 11 +18 54M ess > privacy rsn "UPC877738E"! > May 14 14:01:30 sigma /bsd: iwm0: - 92:5c:14:cc:01:73 6 +29 54M ess > no! rsn! "Vodafone Hotspot"! > May 14 14:01:30 sigma /bsd: iwm0: + cc:ce:1e:8b:cf:d1 100 +31 54M ess > privacy rsn "karlsruhe.v01d" > May 14 14:01:30 sigma /bsd: iwm0: + cc:ce:1e:8b:cf:d2 11 +39 54M ess > privacy rsn "karlsruhe.v01d" > May 14 14:01:30 sigma /bsd: iwm0: - ce:ce:1e:8b:cf:d1 100 +30 54M ess > privacy rsn "untrusted.ka.v01d"! > May 14 14:01:30 sigma /bsd: iwm0: - ce:ce:1e:8b:cf:d2 11 +39 54M ess > privacy rsn "untrusted.ka.v01d"! > May 14 14:01:30 sigma /bsd: iwm0: - d4:3f:cb:9e:40:f6 11 +29 54M ess > privacy rsn "Drunken Donut Returns"! > May 14 14:01:30 sigma /bsd: iwm0: - d4:3f:cb:9e:40:f7 56 +21 54M ess > privacy rsn "Drunken Donut Returns"! > May 14 14:01:30 sigma /bsd: iwm0: - ec:a9:40:6a:97:77 6 +17 54M ess > privacy rsn "Vodafone-8EE7"! > May 14 14:01:30 sigma /bsd: iwm0: SCAN -> AUTH > May 14 14:01:30 sigma /bsd: iwm0: sending auth to cc:ce:1e:8b:cf:d2 on > channel 11 mode 11g > May 14 14:01:30 sigma /bsd: iwm0: AUTH -> ASSOC > May 14 14:01:30 sigma /bsd: iwm0: sending assoc_req to cc:ce:1e:8b:cf:d2 on > channel 11 mode 11g > May 14 14:01:30 sigma /bsd: iwm0: received msg 1/4 of the 4-way handshake > from cc:ce:1e:8b:cf:d2 > May 14 14:01:30 sigma /bsd: iwm0: sending msg 2/4 of the 4-way handshake to > cc:ce:1e:8b:cf:d2 > May 14 14:01:30 sigma /bsd: iwm0: ASSOC -> RUN > May 14 14:01:30 sigma /bsd: iwm0: associated with cc:ce:1e:8b:cf:d2 ssid > "karlsruhe.v01d" channel 11 start MCS 0 short preamble short slot time HT > enabled > May 14 14:01:30 sigma /bsd: iwm0: missed beacon threshold set to 30 beacons, > beacon interval is 100 TU > May 14 14:01:30 sigma /bsd: iwm0: received msg 3/4 of the 4-way handshake > from cc:ce:1e:8b:cf:d2 > May 14 14:01:30 sigma /bsd: iwm0: sending msg 4/4 of the 4-way handshake to > cc:ce:1e:8b:cf:d2 >
