Hi Stefan,
* Stefan Sperling wrote:
> On Tue, May 11, 2021 at 11:44:17AM +0200, Stefan Sperling wrote:
> > Can you please run with this and let me know if it changes anything?
>
> I have finally managed to reproduce the problem locally by playing around
> with forced background scans and roaming. This patch is a superset of the
> previous patch. It should fix the 'add sta' problem and also fixes a couple
> of small bugs I found along the way.
I am now running
OpenBSD 6.9-current (GENERIC.MP) #17: Wed May 12 11:14:50 MDT 2021
[email protected]:/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.
Cheers
Matthias
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
