Hi benno, hi Stefan,
* Sebastian Benoit wrote:
>
> and then running your test - with current, and maybe with the older "fast"
> kernel as well.
Sure, here you go. First run with the kernel from Dec 8, second run
with a fresh kernel from Jan 1. I also run netstat -W/-I for the second
run.
Cheers
Matthias
------------------------------------------------------------------------------
- First run with the fast kernel -
------------------------------------------------------------------------------
OpenBSD 6.2-current (GENERIC.MP) #261: Fri Dec 8 11:22:29 MST 2017
% Total % Received % Xferd Average Speed Time Time Time Current
Dload Upload Total Spent Left Speed
100 100M 100 100M 0 0 2844k 0 0:00:36 0:00:36 --:--:-- 3068k
100 100M 100 100M 0 0 3200k 0 0:00:32 0:00:32 --:--:-- 3107k
100 100M 100 100M 0 0 3103k 0 0:00:33 0:00:33 --:--:-- 3287k
100 100M 100 100M 0 0 3103k 0 0:00:33 0:00:33 --:--:-- 3089k
100 100M 100 100M 0 0 2925k 0 0:00:35 0:00:35 --:--:-- 3146k
100 100M 100 100M 0 0 2767k 0 0:00:37 0:00:37 --:--:-- 2369k
100 100M 100 100M 0 0 3303k 0 0:00:31 0:00:31 --:--:-- 2768k
100 100M 100 100M 0 0 3011k 0 0:00:34 0:00:34 --:--:-- 3017k
100 100M 100 100M 0 0 3303k 0 0:00:31 0:00:31 --:--:-- 2869k
100 100M 100 100M 0 0 2925k 0 0:00:35 0:00:35 --:--:-- 2896k
2018-01-03T16:49:20.236Z sigma /bsd: iwm0: RUN -> INIT
2018-01-03T16:49:20.323Z sigma /bsd: iwm0: begin active scan
2018-01-03T16:49:20.375Z sigma /bsd: iwm0: SCAN -> INIT
2018-01-03T16:49:20.462Z sigma /bsd: iwm0: begin active scan
2018-01-03T16:49:20.466Z sigma /bsd: iwm0: SCAN -> INIT
2018-01-03T16:49:20.553Z sigma /bsd: iwm0: begin active scan
2018-01-03T16:49:20.560Z sigma /bsd: iwm0: SCAN -> INIT
2018-01-03T16:49:20.648Z sigma /bsd: iwm0: begin active scan
2018-01-03T16:49:20.652Z sigma /bsd: iwm0: SCAN -> INIT
2018-01-03T16:49:20.739Z sigma /bsd: iwm0: begin active scan
2018-01-03T16:49:20.748Z sigma /bsd: iwm0: SCAN -> INIT
2018-01-03T16:49:20.749Z sigma dhclient[43144]: fatal in iwm0: down
2018-01-03T16:49:20.846Z sigma /bsd: iwm0: begin active scan
2018-01-03T16:49:25.316Z sigma /bsd: iwm0: end active scan
2018-01-03T16:49:25.317Z sigma /bsd: - 00:0e:c6:03:xx:xx 1! +9 54M ess
no! rsn! "WiFi_OBDII"!
[ Cut APs from neighbours ]
2018-01-03T16:49:25.327Z sigma /bsd: - d4:40:f0:b0:xx:xx 1! +11 54M ess
privacy rsn "WLAN-Y2DX74"!
2018-01-03T16:49:25.327Z sigma /bsd: iwm0: SCAN -> AUTH
2018-01-03T16:49:25.328Z sigma /bsd: iwm0: sending auth to cc:ce:1e:8b:cf:d2 on
channel 6 mode 11g
2018-01-03T16:49:25.328Z sigma /bsd: iwm0: AUTH -> ASSOC
2018-01-03T16:49:25.329Z sigma /bsd: iwm0: sending assoc_req to
cc:ce:1e:8b:cf:d2 on channel 6 mode 11g
2018-01-03T16:49:25.339Z sigma /bsd: iwm0: received msg 1/4 of the 4-way
handshake from cc:ce:1e:8b:cf:d2
2018-01-03T16:49:25.340Z sigma /bsd: iwm0: sending msg 2/4 of the 4-way
handshake to cc:ce:1e:8b:cf:d2
2018-01-03T16:49:25.342Z sigma /bsd: iwm0: ASSOC -> RUN
2018-01-03T16:49:25.342Z sigma /bsd: iwm0: associated with cc:ce:1e:8b:cf:d2
ssid "home" channel 6 start MCS
0 short preamble short slot time HT enabled
2018-01-03T16:49:25.343Z sigma /bsd: iwm0: missed beacon threshold set to 7
beacons, beacon interval is 100 TU
2018-01-03T16:49:25.350Z sigma /bsd: iwm0: received msg 3/4 of the 4-way
handshake from cc:ce:1e:8b:cf:d2
2018-01-03T16:49:25.351Z sigma /bsd: iwm0: sending msg 4/4 of the 4-way
handshake to cc:ce:1e:8b:cf:d2
2018-01-03T16:49:26.301Z sigma /bsd: iwm0: sending action to cc:ce:1e:8b:cf:d2
on channel 6 mode 11n
2018-01-03T16:55:47.987Z sigma /bsd: iwm0: received msg 1/2 of the group key
handshake from cc:ce:1e:8b:cf:d2
2018-01-03T16:55:47.988Z sigma /bsd: iwm0: sending msg 2/2 of the group key
handshake to cc:ce:1e:8b:cf:d2
[ Stopped here ]
-----------------------------------------------------------------------------
- Second run where performance drops after a while -
-----------------------------------------------------------------------------
OpenBSD 6.2-current (GENERIC.MP) #313: Mon Jan 1 17:51:21 MST 2018
% Total % Received % Xferd Average Speed Time Time Time Current
Dload Upload Total Spent Left Speed
100 100M 100 100M 0 0 2625k 0 0:00:39 0:00:39 --:--:-- 3075k
100 100M 100 100M 0 0 2844k 0 0:00:36 0:00:36 --:--:-- 2677k
100 100M 100 100M 0 0 2844k 0 0:00:36 0:00:36 --:--:-- 2766k
100 100M 100 100M 0 0 2497k 0 0:00:41 0:00:41 --:--:-- 2824k
100 100M 100 100M 0 0 3657k 0 0:00:28 0:00:28 --:--:-- 2919k
100 100M 100 100M 0 0 2438k 0 0:00:42 0:00:42 --:--:-- 3012k
100 100M 100 100M 0 0 2844k 0 0:00:36 0:00:36 --:--:-- 2931k
100 100M 100 100M 0 0 2844k 0 0:00:36 0:00:36 --:--:-- 2480k
100 100M 100 100M 0 0 325k 0 0:05:15 0:05:15 --:--:-- 157k
(drops begin here)
53 100M 53 53.7M 0 0 145k 0 0:11:45 0:06:19 0:05:26 147k^C
2018-01-03T17:07:03.891Z sigma /bsd: iwm0: SCAN -> INIT
2018-01-03T17:07:03.892Z sigma dhclient[56053]: fatal in iwm0: down
2018-01-03T17:07:03.985Z sigma /bsd: iwm0: begin active scan
2018-01-03T17:07:08.437Z sigma /bsd: iwm0: end active scan
2018-01-03T17:07:08.438Z sigma /bsd: - 00:0e:c6:03:xx:xx 1! +6 54M ess
no! rsn! "WiFi_OBDII"!
[Cut APs from neighbours]
2018-01-03T17:07:08.452Z sigma /bsd: - ce:ce:1e:8b:cf:d2 6 +49 54M ess
privacy rsn "untrusted.ka.v01d"!
2018-01-03T17:07:08.453Z sigma /bsd: iwm0: SCAN -> AUTH
2018-01-03T17:07:08.453Z sigma /bsd: iwm0: sending auth to cc:ce:1e:8b:cf:d2 on
channel 6 mode 11g
2018-01-03T17:07:08.454Z sigma /bsd: iwm0: AUTH -> ASSOC
2018-01-03T17:07:08.454Z sigma /bsd: iwm0: sending assoc_req to
cc:ce:1e:8b:cf:d2 on channel 6 mode 11g
2018-01-03T17:07:08.468Z sigma /bsd: iwm0: ASSOC -> RUN
2018-01-03T17:07:08.468Z sigma /bsd: iwm0: associated with cc:ce:1e:8b:cf:d2
ssid "home" channel 6 start MCS
0 short preamble short slot time HT enabled
2018-01-03T17:07:08.468Z sigma /bsd: iwm0: missed beacon threshold set to 7
beacons, beacon interval is 100 TU
2018-01-03T17:07:09.465Z sigma /bsd: iwm0: received msg 1/4 of the 4-way
handshake from cc:ce:1e:8b:cf:d2
2018-01-03T17:07:09.465Z sigma /bsd: iwm0: sending msg 2/4 of the 4-way
handshake to cc:ce:1e:8b:cf:d2
2018-01-03T17:07:09.474Z sigma /bsd: iwm0: received msg 3/4 of the 4-way
handshake from cc:ce:1e:8b:cf:d2
2018-01-03T17:07:09.474Z sigma /bsd: iwm0: sending msg 4/4 of the 4-way
handshake to cc:ce:1e:8b:cf:d2
2018-01-03T17:07:10.070Z sigma /bsd: iwm0: sending action to cc:ce:1e:8b:cf:d2
on channel 6 mode 11n
2018-01-03T17:15:48.141Z sigma /bsd: iwm0: received msg 1/2 of the group key
handshake from cc:ce:1e:8b:cf:d2
2018-01-03T17:15:48.141Z sigma /bsd: iwm0: sending msg 2/2 of the group key
handshake to cc:ce:1e:8b:cf:d2
[I interrypted the second slow transfer]
netstat -n -I iwm0
Name Mtu Network Address Ipkts Ierrs Opkts Oerrs Colls
iwm0 1500 <Link> 18:5e:0f:16:65:cf 629233 1614 361284 1039 0
iwm0 1500 172.23.5/24 172.23.5.79 629233 1614 361284 1039 0
iwm0 1500 fe80::%iwm0 fe80::1a5e:fff:fe 629233 1614 361284 1039 0
iwm0 1500 2001:16b8:2 2001:16b8:22fc:cb 629233 1614 361284 1039 0
iwm0 1500 2001:16b8:2 2001:16b8:22fc:cb 629233 1614 361284 1039 0
I recorded some more netstat -I snaps and while Ierrs stay constant Oerrs
increase (1039. 1131. 11831, 1322). Colls stay at zero.
I also recorded some netstat -W and filtered out all 0 values. Here's the
first, the next ones are only diffs to the first one or following ones:
ieee80211 on iwm0:
3 input packet duplicates discarded
7 input multicast echo packets discarded
258 input wep/wpa packets processing failed
8 input control packets discarded
5 input eapol-key packets
1 output packet on unauthenticated port
8 active scans started
258 ccmp replayed frames
2 new input block ack agreements
11494 input frames below block ack window start
357 input frames above block ack window end
357 input block ack window slides
1356 duplicate input block ack frames
2185 expected input block ack frames never arrived
29 input block ack window gaps timed out
ieee80211 on iwm0:
6 input eapol-key packets
2195 expected input block ack frames never arrived
36 input block ack window gaps timed out
ieee80211 on iwm0:
11503 input frames below block ack window start
2209 expected input block ack frames never arrived
46 input block ack window gaps timed out
