Skip site navigation (1)Skip section navigation (2)
Date:      Sun, 1 Nov 2015 02:46:46 +0700
From:      Eugene Grosbein <eugen@grosbein.net>
To:        Adrian Chadd <adrian@freebsd.org>
Cc:        "freebsd-mips@freebsd.org" <freebsd-mips@freebsd.org>
Subject:   Re: CPU underload
Message-ID:  <56351AA6.80903@grosbein.net>
In-Reply-To: <CAJ-VmomzkG8ZB0h1Qc=Oeny2ZOzQg1UirUprM_gmMy64F5ZRYw@mail.gmail.com>
References:  <56348063.3090508@grosbein.net> <56348239.3050701@grosbein.net> <CAJ-Vmok%2BgKXcpzpxNCBhxX8sYSh%2BQO7OaeojB4MCufO%2BA4zceg@mail.gmail.com> <563500FC.8020201@grosbein.net> <CAJ-Vmo=EpmG6OJxq_v_mqKMF48h_B3K7vKqnnaKAi3x1s-uaWQ@mail.gmail.com> <5635148B.2070307@grosbein.net> <CAJ-VmomzkG8ZB0h1Qc=Oeny2ZOzQg1UirUprM_gmMy64F5ZRYw@mail.gmail.com>

next in thread | previous in thread | raw e-mail | index | archive | help
On 01.11.2015 02:24, Adrian Chadd wrote:
> OK, so those messages are expected when you run hwpmc. Sigh, I should
> just rate limit that message and make it a counter. (I had to comment
> it out here.)
> 
> Do you get those unaligned fixup messages when you're not running
> hwpmc?

No.

> During the test, what's the latency between your two test boxes? Try a
> ping from FTP client->server and see.

It takes 20 seconds now to transfer 1GB file over FTP.
"ping -c 150 -s 1472 -i 0.1" during the test results in:

150 packets transmitted, 150 packets received, 0.0% packet loss
round-trip min/avg/max/stddev = 0.250/0.735/7.362/0.775 ms

And with idle network it shows pretty same:

150 packets transmitted, 150 packets received, 0.0% packet loss
round-trip min/avg/max/stddev = 0.252/0.753/6.927/1.538 ms

Maximum delay is pretty hight and that bothers me.
Here is the output without -q flag. Note that high delay occured
for packets number 35, 45, 55, 65, 105.

Script started on Sun Nov  1 02:38:05 2015
command: ping -c 150 -s 1472 -i 0.1 192.168.3.1
PING 192.168.3.1 (192.168.3.1): 1472 data bytes
1480 bytes from 192.168.3.1: icmp_seq=0 ttl=64 time=0.307 ms
1480 bytes from 192.168.3.1: icmp_seq=1 ttl=64 time=0.692 ms
1480 bytes from 192.168.3.1: icmp_seq=2 ttl=64 time=0.266 ms
1480 bytes from 192.168.3.1: icmp_seq=3 ttl=64 time=0.268 ms
1480 bytes from 192.168.3.1: icmp_seq=4 ttl=64 time=0.267 ms
1480 bytes from 192.168.3.1: icmp_seq=5 ttl=64 time=0.283 ms
1480 bytes from 192.168.3.1: icmp_seq=6 ttl=64 time=0.285 ms
1480 bytes from 192.168.3.1: icmp_seq=7 ttl=64 time=0.270 ms
1480 bytes from 192.168.3.1: icmp_seq=8 ttl=64 time=0.264 ms
1480 bytes from 192.168.3.1: icmp_seq=9 ttl=64 time=0.264 ms
1480 bytes from 192.168.3.1: icmp_seq=10 ttl=64 time=0.290 ms
1480 bytes from 192.168.3.1: icmp_seq=11 ttl=64 time=0.693 ms
1480 bytes from 192.168.3.1: icmp_seq=12 ttl=64 time=0.261 ms
1480 bytes from 192.168.3.1: icmp_seq=13 ttl=64 time=0.277 ms
1480 bytes from 192.168.3.1: icmp_seq=14 ttl=64 time=0.265 ms
1480 bytes from 192.168.3.1: icmp_seq=15 ttl=64 time=0.296 ms
1480 bytes from 192.168.3.1: icmp_seq=16 ttl=64 time=0.266 ms
1480 bytes from 192.168.3.1: icmp_seq=17 ttl=64 time=0.265 ms
1480 bytes from 192.168.3.1: icmp_seq=18 ttl=64 time=0.270 ms
1480 bytes from 192.168.3.1: icmp_seq=19 ttl=64 time=0.276 ms
1480 bytes from 192.168.3.1: icmp_seq=20 ttl=64 time=0.267 ms
1480 bytes from 192.168.3.1: icmp_seq=21 ttl=64 time=0.697 ms
1480 bytes from 192.168.3.1: icmp_seq=22 ttl=64 time=0.274 ms
1480 bytes from 192.168.3.1: icmp_seq=23 ttl=64 time=0.271 ms
1480 bytes from 192.168.3.1: icmp_seq=24 ttl=64 time=0.264 ms
1480 bytes from 192.168.3.1: icmp_seq=25 ttl=64 time=0.320 ms
1480 bytes from 192.168.3.1: icmp_seq=26 ttl=64 time=0.275 ms
1480 bytes from 192.168.3.1: icmp_seq=27 ttl=64 time=0.268 ms
1480 bytes from 192.168.3.1: icmp_seq=28 ttl=64 time=0.258 ms
1480 bytes from 192.168.3.1: icmp_seq=29 ttl=64 time=0.265 ms
1480 bytes from 192.168.3.1: icmp_seq=30 ttl=64 time=0.263 ms
1480 bytes from 192.168.3.1: icmp_seq=31 ttl=64 time=0.689 ms
1480 bytes from 192.168.3.1: icmp_seq=32 ttl=64 time=0.263 ms
1480 bytes from 192.168.3.1: icmp_seq=33 ttl=64 time=0.274 ms
1480 bytes from 192.168.3.1: icmp_seq=34 ttl=64 time=0.257 ms
1480 bytes from 192.168.3.1: icmp_seq=35 ttl=64 time=6.195 ms
1480 bytes from 192.168.3.1: icmp_seq=36 ttl=64 time=0.267 ms
1480 bytes from 192.168.3.1: icmp_seq=37 ttl=64 time=0.264 ms
1480 bytes from 192.168.3.1: icmp_seq=38 ttl=64 time=0.259 ms
1480 bytes from 192.168.3.1: icmp_seq=39 ttl=64 time=0.345 ms
1480 bytes from 192.168.3.1: icmp_seq=40 ttl=64 time=0.270 ms
1480 bytes from 192.168.3.1: icmp_seq=41 ttl=64 time=0.690 ms
1480 bytes from 192.168.3.1: icmp_seq=42 ttl=64 time=0.264 ms
1480 bytes from 192.168.3.1: icmp_seq=43 ttl=64 time=0.271 ms
1480 bytes from 192.168.3.1: icmp_seq=44 ttl=64 time=0.330 ms
1480 bytes from 192.168.3.1: icmp_seq=45 ttl=64 time=6.913 ms
1480 bytes from 192.168.3.1: icmp_seq=46 ttl=64 time=0.293 ms
1480 bytes from 192.168.3.1: icmp_seq=47 ttl=64 time=0.259 ms
1480 bytes from 192.168.3.1: icmp_seq=48 ttl=64 time=0.259 ms
1480 bytes from 192.168.3.1: icmp_seq=49 ttl=64 time=0.267 ms
1480 bytes from 192.168.3.1: icmp_seq=50 ttl=64 time=0.274 ms
1480 bytes from 192.168.3.1: icmp_seq=51 ttl=64 time=0.690 ms
1480 bytes from 192.168.3.1: icmp_seq=52 ttl=64 time=0.282 ms
1480 bytes from 192.168.3.1: icmp_seq=53 ttl=64 time=0.268 ms
1480 bytes from 192.168.3.1: icmp_seq=54 ttl=64 time=0.256 ms
1480 bytes from 192.168.3.1: icmp_seq=55 ttl=64 time=6.875 ms
1480 bytes from 192.168.3.1: icmp_seq=56 ttl=64 time=0.307 ms
1480 bytes from 192.168.3.1: icmp_seq=57 ttl=64 time=0.265 ms
1480 bytes from 192.168.3.1: icmp_seq=58 ttl=64 time=0.259 ms
1480 bytes from 192.168.3.1: icmp_seq=59 ttl=64 time=0.266 ms
1480 bytes from 192.168.3.1: icmp_seq=60 ttl=64 time=0.277 ms
1480 bytes from 192.168.3.1: icmp_seq=61 ttl=64 time=0.693 ms
1480 bytes from 192.168.3.1: icmp_seq=62 ttl=64 time=0.281 ms
1480 bytes from 192.168.3.1: icmp_seq=63 ttl=64 time=0.268 ms
1480 bytes from 192.168.3.1: icmp_seq=64 ttl=64 time=0.257 ms
1480 bytes from 192.168.3.1: icmp_seq=65 ttl=64 time=6.877 ms
1480 bytes from 192.168.3.1: icmp_seq=66 ttl=64 time=0.281 ms
1480 bytes from 192.168.3.1: icmp_seq=67 ttl=64 time=0.259 ms
1480 bytes from 192.168.3.1: icmp_seq=68 ttl=64 time=0.281 ms
1480 bytes from 192.168.3.1: icmp_seq=69 ttl=64 time=0.269 ms
1480 bytes from 192.168.3.1: icmp_seq=70 ttl=64 time=0.264 ms
1480 bytes from 192.168.3.1: icmp_seq=71 ttl=64 time=0.264 ms
1480 bytes from 192.168.3.1: icmp_seq=72 ttl=64 time=0.262 ms
1480 bytes from 192.168.3.1: icmp_seq=73 ttl=64 time=0.266 ms
1480 bytes from 192.168.3.1: icmp_seq=74 ttl=64 time=0.261 ms
1480 bytes from 192.168.3.1: icmp_seq=75 ttl=64 time=0.280 ms
1480 bytes from 192.168.3.1: icmp_seq=76 ttl=64 time=0.264 ms
1480 bytes from 192.168.3.1: icmp_seq=77 ttl=64 time=0.265 ms
1480 bytes from 192.168.3.1: icmp_seq=78 ttl=64 time=0.267 ms
1480 bytes from 192.168.3.1: icmp_seq=79 ttl=64 time=0.266 ms
1480 bytes from 192.168.3.1: icmp_seq=80 ttl=64 time=0.263 ms
1480 bytes from 192.168.3.1: icmp_seq=81 ttl=64 time=0.684 ms
1480 bytes from 192.168.3.1: icmp_seq=82 ttl=64 time=0.263 ms
1480 bytes from 192.168.3.1: icmp_seq=83 ttl=64 time=0.318 ms
1480 bytes from 192.168.3.1: icmp_seq=84 ttl=64 time=0.287 ms
1480 bytes from 192.168.3.1: icmp_seq=85 ttl=64 time=0.277 ms
1480 bytes from 192.168.3.1: icmp_seq=86 ttl=64 time=0.260 ms
1480 bytes from 192.168.3.1: icmp_seq=87 ttl=64 time=0.269 ms
1480 bytes from 192.168.3.1: icmp_seq=88 ttl=64 time=0.262 ms
1480 bytes from 192.168.3.1: icmp_seq=89 ttl=64 time=0.266 ms
1480 bytes from 192.168.3.1: icmp_seq=90 ttl=64 time=0.266 ms
1480 bytes from 192.168.3.1: icmp_seq=91 ttl=64 time=0.689 ms
1480 bytes from 192.168.3.1: icmp_seq=92 ttl=64 time=0.265 ms
1480 bytes from 192.168.3.1: icmp_seq=93 ttl=64 time=0.265 ms
1480 bytes from 192.168.3.1: icmp_seq=94 ttl=64 time=0.262 ms
1480 bytes from 192.168.3.1: icmp_seq=95 ttl=64 time=0.277 ms
1480 bytes from 192.168.3.1: icmp_seq=96 ttl=64 time=0.263 ms
1480 bytes from 192.168.3.1: icmp_seq=97 ttl=64 time=0.296 ms
1480 bytes from 192.168.3.1: icmp_seq=98 ttl=64 time=0.282 ms
1480 bytes from 192.168.3.1: icmp_seq=99 ttl=64 time=0.269 ms
1480 bytes from 192.168.3.1: icmp_seq=100 ttl=64 time=0.264 ms
1480 bytes from 192.168.3.1: icmp_seq=101 ttl=64 time=0.691 ms
1480 bytes from 192.168.3.1: icmp_seq=102 ttl=64 time=0.263 ms
1480 bytes from 192.168.3.1: icmp_seq=103 ttl=64 time=0.267 ms
1480 bytes from 192.168.3.1: icmp_seq=104 ttl=64 time=0.261 ms
1480 bytes from 192.168.3.1: icmp_seq=105 ttl=64 time=6.101 ms
1480 bytes from 192.168.3.1: icmp_seq=106 ttl=64 time=0.274 ms
1480 bytes from 192.168.3.1: icmp_seq=107 ttl=64 time=0.264 ms
1480 bytes from 192.168.3.1: icmp_seq=108 ttl=64 time=0.259 ms
1480 bytes from 192.168.3.1: icmp_seq=109 ttl=64 time=0.271 ms
1480 bytes from 192.168.3.1: icmp_seq=110 ttl=64 time=0.264 ms
1480 bytes from 192.168.3.1: icmp_seq=111 ttl=64 time=0.691 ms
1480 bytes from 192.168.3.1: icmp_seq=112 ttl=64 time=0.263 ms
1480 bytes from 192.168.3.1: icmp_seq=113 ttl=64 time=0.267 ms
1480 bytes from 192.168.3.1: icmp_seq=114 ttl=64 time=0.262 ms
1480 bytes from 192.168.3.1: icmp_seq=115 ttl=64 time=0.274 ms
1480 bytes from 192.168.3.1: icmp_seq=116 ttl=64 time=0.262 ms
1480 bytes from 192.168.3.1: icmp_seq=117 ttl=64 time=0.261 ms
1480 bytes from 192.168.3.1: icmp_seq=118 ttl=64 time=0.263 ms
1480 bytes from 192.168.3.1: icmp_seq=119 ttl=64 time=0.266 ms
1480 bytes from 192.168.3.1: icmp_seq=120 ttl=64 time=0.264 ms
1480 bytes from 192.168.3.1: icmp_seq=121 ttl=64 time=0.271 ms
1480 bytes from 192.168.3.1: icmp_seq=122 ttl=64 time=0.277 ms
1480 bytes from 192.168.3.1: icmp_seq=123 ttl=64 time=0.268 ms
1480 bytes from 192.168.3.1: icmp_seq=124 ttl=64 time=0.262 ms
1480 bytes from 192.168.3.1: icmp_seq=125 ttl=64 time=0.279 ms
1480 bytes from 192.168.3.1: icmp_seq=126 ttl=64 time=0.289 ms
1480 bytes from 192.168.3.1: icmp_seq=127 ttl=64 time=0.269 ms
1480 bytes from 192.168.3.1: icmp_seq=128 ttl=64 time=0.261 ms
1480 bytes from 192.168.3.1: icmp_seq=129 ttl=64 time=0.265 ms
1480 bytes from 192.168.3.1: icmp_seq=130 ttl=64 time=0.263 ms
1480 bytes from 192.168.3.1: icmp_seq=131 ttl=64 time=0.257 ms
1480 bytes from 192.168.3.1: icmp_seq=132 ttl=64 time=0.264 ms
1480 bytes from 192.168.3.1: icmp_seq=133 ttl=64 time=0.266 ms
1480 bytes from 192.168.3.1: icmp_seq=134 ttl=64 time=0.260 ms
1480 bytes from 192.168.3.1: icmp_seq=135 ttl=64 time=2.064 ms
1480 bytes from 192.168.3.1: icmp_seq=136 ttl=64 time=0.273 ms
1480 bytes from 192.168.3.1: icmp_seq=137 ttl=64 time=0.270 ms
1480 bytes from 192.168.3.1: icmp_seq=138 ttl=64 time=0.261 ms
1480 bytes from 192.168.3.1: icmp_seq=139 ttl=64 time=0.267 ms
1480 bytes from 192.168.3.1: icmp_seq=140 ttl=64 time=0.261 ms
1480 bytes from 192.168.3.1: icmp_seq=141 ttl=64 time=0.685 ms
1480 bytes from 192.168.3.1: icmp_seq=142 ttl=64 time=0.261 ms
1480 bytes from 192.168.3.1: icmp_seq=143 ttl=64 time=0.264 ms
1480 bytes from 192.168.3.1: icmp_seq=144 ttl=64 time=0.257 ms
1480 bytes from 192.168.3.1: icmp_seq=145 ttl=64 time=0.274 ms
1480 bytes from 192.168.3.1: icmp_seq=146 ttl=64 time=0.257 ms
1480 bytes from 192.168.3.1: icmp_seq=147 ttl=64 time=0.270 ms
1480 bytes from 192.168.3.1: icmp_seq=148 ttl=64 time=0.255 ms
1480 bytes from 192.168.3.1: icmp_seq=149 ttl=64 time=0.264 ms

--- 192.168.3.1 ping statistics ---
150 packets transmitted, 150 packets received, 0.0% packet loss
round-trip min/avg/max/stddev = 0.255/0.527/6.913/1.143 ms

First I've supposed its some bridge's STP timer firing up but disabling STP does not help,
same periodic delays.



Want to link to this message? Use this URL: <https://mail-archive.FreeBSD.org/cgi/mid.cgi?56351AA6.80903>