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>

index | next in thread | previous in thread | raw e-mail

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.


help

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