Project

General

Profile

Bug #3737 » test.log

/tmp/test.log - Chris Dunlop, 02/20/2013 11:20 PM

 
----------------------------------------------------------------------
rbd_cache=0
----------------------------------------------------------------------
# virsh dumpxml test1 | grpe rbd
<source protocol='rbd' name='rbd/test1:rbd_cache=0'/>
# virsh test1 start
# ssh test1 'date; dd if=/dev/zero of=/tmp/foo bs=4k count=256000; date'
Thu Feb 21 17:41:46 EST 2013
256000+0 records in
256000+0 records out
1048576000 bytes (1.0 GB) copied, 61.9846 s, 16.9 MB/s
Thu Feb 21 17:42:48 EST 2013

...concurrent with the 'dd' test, pinging from external box:

# ping -n test1 | tsprefix
...
2013-02-21-17:41:46 64 bytes from 10.200.63.137: icmp_req=244 ttl=64 time=0.529 ms
2013-02-21-17:41:47 64 bytes from 10.200.63.137: icmp_req=245 ttl=64 time=0.462 ms
2013-02-21-17:41:48 64 bytes from 10.200.63.137: icmp_req=246 ttl=64 time=0.330 ms
2013-02-21-17:41:49 64 bytes from 10.200.63.137: icmp_req=247 ttl=64 time=0.437 ms
2013-02-21-17:41:50 64 bytes from 10.200.63.137: icmp_req=248 ttl=64 time=0.380 ms
2013-02-21-17:41:51 64 bytes from 10.200.63.137: icmp_req=249 ttl=64 time=0.423 ms
2013-02-21-17:41:52 64 bytes from 10.200.63.137: icmp_req=250 ttl=64 time=0.457 ms
2013-02-21-17:41:53 64 bytes from 10.200.63.137: icmp_req=251 ttl=64 time=0.409 ms
2013-02-21-17:41:54 64 bytes from 10.200.63.137: icmp_req=252 ttl=64 time=0.479 ms
2013-02-21-17:41:55 64 bytes from 10.200.63.137: icmp_req=253 ttl=64 time=0.499 ms
2013-02-21-17:41:56 64 bytes from 10.200.63.137: icmp_req=254 ttl=64 time=0.411 ms
2013-02-21-17:41:57 64 bytes from 10.200.63.137: icmp_req=255 ttl=64 time=0.884 ms
2013-02-21-17:41:58 64 bytes from 10.200.63.137: icmp_req=256 ttl=64 time=0.496 ms
2013-02-21-17:41:59 64 bytes from 10.200.63.137: icmp_req=257 ttl=64 time=0.570 ms
2013-02-21-17:42:00 64 bytes from 10.200.63.137: icmp_req=258 ttl=64 time=0.492 ms
2013-02-21-17:42:01 64 bytes from 10.200.63.137: icmp_req=259 ttl=64 time=0.504 ms
2013-02-21-17:42:02 64 bytes from 10.200.63.137: icmp_req=260 ttl=64 time=0.622 ms
2013-02-21-17:42:03 64 bytes from 10.200.63.137: icmp_req=261 ttl=64 time=0.470 ms
2013-02-21-17:42:04 64 bytes from 10.200.63.137: icmp_req=262 ttl=64 time=0.428 ms
2013-02-21-17:42:05 64 bytes from 10.200.63.137: icmp_req=263 ttl=64 time=0.589 ms
2013-02-21-17:42:06 64 bytes from 10.200.63.137: icmp_req=264 ttl=64 time=0.410 ms
2013-02-21-17:42:07 64 bytes from 10.200.63.137: icmp_req=265 ttl=64 time=0.482 ms
2013-02-21-17:42:08 64 bytes from 10.200.63.137: icmp_req=266 ttl=64 time=0.568 ms
2013-02-21-17:42:09 64 bytes from 10.200.63.137: icmp_req=267 ttl=64 time=0.556 ms
2013-02-21-17:42:10 64 bytes from 10.200.63.137: icmp_req=268 ttl=64 time=0.550 ms
2013-02-21-17:42:11 64 bytes from 10.200.63.137: icmp_req=269 ttl=64 time=0.539 ms
2013-02-21-17:42:12 64 bytes from 10.200.63.137: icmp_req=270 ttl=64 time=0.533 ms
2013-02-21-17:42:13 64 bytes from 10.200.63.137: icmp_req=271 ttl=64 time=0.443 ms
2013-02-21-17:42:14 64 bytes from 10.200.63.137: icmp_req=272 ttl=64 time=0.462 ms
2013-02-21-17:42:15 64 bytes from 10.200.63.137: icmp_req=273 ttl=64 time=0.492 ms
2013-02-21-17:42:16 64 bytes from 10.200.63.137: icmp_req=274 ttl=64 time=0.456 ms
2013-02-21-17:42:17 64 bytes from 10.200.63.137: icmp_req=275 ttl=64 time=0.312 ms
2013-02-21-17:42:18 64 bytes from 10.200.63.137: icmp_req=276 ttl=64 time=0.538 ms
2013-02-21-17:42:19 64 bytes from 10.200.63.137: icmp_req=277 ttl=64 time=0.454 ms
2013-02-21-17:42:20 64 bytes from 10.200.63.137: icmp_req=278 ttl=64 time=0.473 ms
2013-02-21-17:42:21 64 bytes from 10.200.63.137: icmp_req=279 ttl=64 time=0.591 ms
2013-02-21-17:42:22 64 bytes from 10.200.63.137: icmp_req=280 ttl=64 time=0.576 ms
2013-02-21-17:42:23 64 bytes from 10.200.63.137: icmp_req=281 ttl=64 time=0.489 ms
2013-02-21-17:42:24 64 bytes from 10.200.63.137: icmp_req=282 ttl=64 time=0.404 ms
2013-02-21-17:42:25 64 bytes from 10.200.63.137: icmp_req=283 ttl=64 time=0.358 ms
2013-02-21-17:42:26 64 bytes from 10.200.63.137: icmp_req=284 ttl=64 time=0.459 ms
2013-02-21-17:42:27 64 bytes from 10.200.63.137: icmp_req=285 ttl=64 time=0.474 ms
2013-02-21-17:42:28 64 bytes from 10.200.63.137: icmp_req=286 ttl=64 time=0.514 ms
2013-02-21-17:42:29 64 bytes from 10.200.63.137: icmp_req=287 ttl=64 time=0.537 ms
2013-02-21-17:42:30 64 bytes from 10.200.63.137: icmp_req=288 ttl=64 time=0.496 ms
2013-02-21-17:42:31 64 bytes from 10.200.63.137: icmp_req=289 ttl=64 time=0.432 ms
2013-02-21-17:42:32 64 bytes from 10.200.63.137: icmp_req=290 ttl=64 time=0.438 ms
2013-02-21-17:42:33 64 bytes from 10.200.63.137: icmp_req=291 ttl=64 time=0.696 ms
2013-02-21-17:42:34 64 bytes from 10.200.63.137: icmp_req=292 ttl=64 time=0.466 ms
2013-02-21-17:42:35 64 bytes from 10.200.63.137: icmp_req=293 ttl=64 time=0.566 ms
2013-02-21-17:42:36 64 bytes from 10.200.63.137: icmp_req=294 ttl=64 time=0.464 ms
2013-02-21-17:42:37 64 bytes from 10.200.63.137: icmp_req=295 ttl=64 time=0.589 ms
2013-02-21-17:42:38 64 bytes from 10.200.63.137: icmp_req=296 ttl=64 time=0.483 ms
2013-02-21-17:42:39 64 bytes from 10.200.63.137: icmp_req=297 ttl=64 time=0.950 ms
2013-02-21-17:42:40 64 bytes from 10.200.63.137: icmp_req=298 ttl=64 time=0.318 ms
2013-02-21-17:42:41 64 bytes from 10.200.63.137: icmp_req=299 ttl=64 time=0.360 ms
2013-02-21-17:42:42 64 bytes from 10.200.63.137: icmp_req=300 ttl=64 time=0.507 ms
2013-02-21-17:42:43 64 bytes from 10.200.63.137: icmp_req=301 ttl=64 time=0.493 ms
2013-02-21-17:42:44 64 bytes from 10.200.63.137: icmp_req=302 ttl=64 time=0.369 ms
2013-02-21-17:42:45 64 bytes from 10.200.63.137: icmp_req=303 ttl=64 time=0.423 ms
2013-02-21-17:42:46 64 bytes from 10.200.63.137: icmp_req=304 ttl=64 time=0.407 ms
2013-02-21-17:42:47 64 bytes from 10.200.63.137: icmp_req=305 ttl=64 time=0.384 ms
2013-02-21-17:42:48 64 bytes from 10.200.63.137: icmp_req=306 ttl=64 time=0.457 ms
2013-02-21-17:42:49 64 bytes from 10.200.63.137: icmp_req=307 ttl=64 time=0.373 ms
2013-02-21-17:42:50 64 bytes from 10.200.63.137: icmp_req=308 ttl=64 time=0.500 ms
2013-02-21-17:42:51 64 bytes from 10.200.63.137: icmp_req=309 ttl=64 time=0.444 ms
2013-02-21-17:42:52 64 bytes from 10.200.63.137: icmp_req=310 ttl=64 time=0.504 ms
2013-02-21-17:42:53 64 bytes from 10.200.63.137: icmp_req=311 ttl=64 time=0.756 ms
2013-02-21-17:42:54 64 bytes from 10.200.63.137: icmp_req=312 ttl=64 time=0.432 ms
2013-02-21-17:42:55 64 bytes from 10.200.63.137: icmp_req=313 ttl=64 time=0.600 ms
2013-02-21-17:42:56 64 bytes from 10.200.63.137: icmp_req=314 ttl=64 time=0.574 ms
2013-02-21-17:42:57 64 bytes from 10.200.63.137: icmp_req=315 ttl=64 time=0.488 ms

----------------------------------------------------------------------
rbd_cache=1
----------------------------------------------------------------------
# virsh dumpxml test1 | grpe rbd
<source protocol='rbd' name='rbd/test1:rbd_cache=1'/>
# virsh start test1
# ssh test1 'date; dd if=/dev/zero of=/tmp/foo bs=4k count=256000; date'
Thu Feb 21 17:45:28 EST 2013
256000+0 records in
256000+0 records out
1048576000 bytes (1.0 GB) copied, 55.8669 s, 18.8 MB/s
Thu Feb 21 17:46:24 EST 2013

...concurrent with the 'dd' test, pinging from external box:

# ping -n test | tsprefix
2013-02-21-17:45:14 PING 10.200.63.137 (10.200.63.137) 56(84) bytes of data.
2013-02-21-17:45:14 64 bytes from 10.200.63.137: icmp_req=1 ttl=64 time=3.85 ms
2013-02-21-17:45:15 64 bytes from 10.200.63.137: icmp_req=2 ttl=64 time=0.468 ms
2013-02-21-17:45:16 64 bytes from 10.200.63.137: icmp_req=3 ttl=64 time=0.399 ms
2013-02-21-17:45:17 64 bytes from 10.200.63.137: icmp_req=4 ttl=64 time=0.436 ms
2013-02-21-17:45:18 64 bytes from 10.200.63.137: icmp_req=5 ttl=64 time=0.556 ms
2013-02-21-17:45:19 64 bytes from 10.200.63.137: icmp_req=6 ttl=64 time=0.421 ms
2013-02-21-17:45:20 64 bytes from 10.200.63.137: icmp_req=7 ttl=64 time=0.442 ms
2013-02-21-17:45:21 64 bytes from 10.200.63.137: icmp_req=8 ttl=64 time=0.366 ms
2013-02-21-17:45:22 64 bytes from 10.200.63.137: icmp_req=9 ttl=64 time=0.414 ms
2013-02-21-17:45:23 64 bytes from 10.200.63.137: icmp_req=10 ttl=64 time=0.421 ms
2013-02-21-17:45:24 64 bytes from 10.200.63.137: icmp_req=11 ttl=64 time=0.333 ms
2013-02-21-17:45:25 64 bytes from 10.200.63.137: icmp_req=12 ttl=64 time=0.416 ms
2013-02-21-17:45:26 64 bytes from 10.200.63.137: icmp_req=13 ttl=64 time=0.413 ms
2013-02-21-17:45:27 64 bytes from 10.200.63.137: icmp_req=14 ttl=64 time=0.469 ms
<<< dd starts here >>>
2013-02-21-17:45:28 64 bytes from 10.200.63.137: icmp_req=15 ttl=64 time=0.535 ms
2013-02-21-17:45:29 64 bytes from 10.200.63.137: icmp_req=16 ttl=64 time=0.398 ms
2013-02-21-17:45:30 64 bytes from 10.200.63.137: icmp_req=17 ttl=64 time=110 ms
2013-02-21-17:45:31 64 bytes from 10.200.63.137: icmp_req=18 ttl=64 time=225 ms
2013-02-21-17:45:32 64 bytes from 10.200.63.137: icmp_req=19 ttl=64 time=0.415 ms
2013-02-21-17:45:33 64 bytes from 10.200.63.137: icmp_req=20 ttl=64 time=0.516 ms
2013-02-21-17:45:34 64 bytes from 10.200.63.137: icmp_req=21 ttl=64 time=44.0 ms
2013-02-21-17:45:35 64 bytes from 10.200.63.137: icmp_req=22 ttl=64 time=168 ms
2013-02-21-17:45:36 64 bytes from 10.200.63.137: icmp_req=23 ttl=64 time=358 ms
2013-02-21-17:45:38 64 bytes from 10.200.63.137: icmp_req=24 ttl=64 time=541 ms
2013-02-21-17:45:38 64 bytes from 10.200.63.137: icmp_req=25 ttl=64 time=0.895 ms
2013-02-21-17:45:40 64 bytes from 10.200.63.137: icmp_req=26 ttl=64 time=1072 ms
2013-02-21-17:45:40 64 bytes from 10.200.63.137: icmp_req=27 ttl=64 time=72.6 ms
2013-02-21-17:45:41 64 bytes from 10.200.63.137: icmp_req=28 ttl=64 time=0.416 ms
2013-02-21-17:45:42 64 bytes from 10.200.63.137: icmp_req=29 ttl=64 time=167 ms
2013-02-21-17:45:43 64 bytes from 10.200.63.137: icmp_req=30 ttl=64 time=82.3 ms
2013-02-21-17:45:44 64 bytes from 10.200.63.137: icmp_req=31 ttl=64 time=4.51 ms
2013-02-21-17:45:45 64 bytes from 10.200.63.137: icmp_req=32 ttl=64 time=0.447 ms
2013-02-21-17:45:46 64 bytes from 10.200.63.137: icmp_req=33 ttl=64 time=254 ms
2013-02-21-17:45:48 64 bytes from 10.200.63.137: icmp_req=34 ttl=64 time=388 ms
2013-02-21-17:45:48 64 bytes from 10.200.63.137: icmp_req=35 ttl=64 time=311 ms
2013-02-21-17:45:49 64 bytes from 10.200.63.137: icmp_req=36 ttl=64 time=36.4 ms
2013-02-21-17:45:50 64 bytes from 10.200.63.137: icmp_req=37 ttl=64 time=226 ms
2013-02-21-17:45:51 64 bytes from 10.200.63.137: icmp_req=38 ttl=64 time=199 ms
2013-02-21-17:45:52 64 bytes from 10.200.63.137: icmp_req=39 ttl=64 time=54.2 ms
2013-02-21-17:45:54 64 bytes from 10.200.63.137: icmp_req=40 ttl=64 time=376 ms
2013-02-21-17:45:54 64 bytes from 10.200.63.137: icmp_req=41 ttl=64 time=0.447 ms
2013-02-21-17:45:55 64 bytes from 10.200.63.137: icmp_req=42 ttl=64 time=25.9 ms
2013-02-21-17:45:56 64 bytes from 10.200.63.137: icmp_req=43 ttl=64 time=317 ms
2013-02-21-17:45:57 64 bytes from 10.200.63.137: icmp_req=44 ttl=64 time=0.271 ms
2013-02-21-17:45:58 64 bytes from 10.200.63.137: icmp_req=45 ttl=64 time=0.369 ms
2013-02-21-17:46:00 64 bytes from 10.200.63.137: icmp_req=46 ttl=64 time=450 ms
2013-02-21-17:46:00 64 bytes from 10.200.63.137: icmp_req=47 ttl=64 time=108 ms
2013-02-21-17:46:01 64 bytes from 10.200.63.137: icmp_req=48 ttl=64 time=0.512 ms
2013-02-21-17:46:02 64 bytes from 10.200.63.137: icmp_req=49 ttl=64 time=0.391 ms
2013-02-21-17:46:04 64 bytes from 10.200.63.137: icmp_req=50 ttl=64 time=492 ms
2013-02-21-17:46:04 64 bytes from 10.200.63.137: icmp_req=51 ttl=64 time=82.5 ms
2013-02-21-17:46:05 64 bytes from 10.200.63.137: icmp_req=52 ttl=64 time=172 ms
2013-02-21-17:46:06 64 bytes from 10.200.63.137: icmp_req=53 ttl=64 time=340 ms
2013-02-21-17:46:07 64 bytes from 10.200.63.137: icmp_req=54 ttl=64 time=0.903 ms
2013-02-21-17:46:09 64 bytes from 10.200.63.137: icmp_req=55 ttl=64 time=528 ms
2013-02-21-17:46:09 64 bytes from 10.200.63.137: icmp_req=56 ttl=64 time=87.2 ms
2013-02-21-17:46:10 64 bytes from 10.200.63.137: icmp_req=57 ttl=64 time=314 ms
2013-02-21-17:46:12 64 bytes from 10.200.63.137: icmp_req=58 ttl=64 time=559 ms
2013-02-21-17:46:12 64 bytes from 10.200.63.137: icmp_req=59 ttl=64 time=0.317 ms
2013-02-21-17:46:13 64 bytes from 10.200.63.137: icmp_req=60 ttl=64 time=0.590 ms
2013-02-21-17:46:15 64 bytes from 10.200.63.137: icmp_req=61 ttl=64 time=519 ms
2013-02-21-17:46:15 64 bytes from 10.200.63.137: icmp_req=62 ttl=64 time=186 ms
2013-02-21-17:46:17 64 bytes from 10.200.63.137: icmp_req=63 ttl=64 time=374 ms
2013-02-21-17:46:17 64 bytes from 10.200.63.137: icmp_req=64 ttl=64 time=0.469 ms
2013-02-21-17:46:18 64 bytes from 10.200.63.137: icmp_req=65 ttl=64 time=183 ms
2013-02-21-17:46:19 64 bytes from 10.200.63.137: icmp_req=66 ttl=64 time=0.378 ms
2013-02-21-17:46:20 64 bytes from 10.200.63.137: icmp_req=67 ttl=64 time=139 ms
2013-02-21-17:46:22 64 bytes from 10.200.63.137: icmp_req=68 ttl=64 time=1259 ms
2013-02-21-17:46:22 64 bytes from 10.200.63.137: icmp_req=69 ttl=64 time=251 ms
2013-02-21-17:46:23 64 bytes from 10.200.63.137: icmp_req=70 ttl=64 time=0.936 ms
2013-02-21-17:46:24 64 bytes from 10.200.63.137: icmp_req=71 ttl=64 time=0.388 ms
<<< dd stops here >>>
2013-02-21-17:46:25 64 bytes from 10.200.63.137: icmp_req=72 ttl=64 time=179 ms
2013-02-21-17:46:26 64 bytes from 10.200.63.137: icmp_req=73 ttl=64 time=58.2 ms
2013-02-21-17:46:27 64 bytes from 10.200.63.137: icmp_req=74 ttl=64 time=43.0 ms
2013-02-21-17:46:28 64 bytes from 10.200.63.137: icmp_req=75 ttl=64 time=96.1 ms
2013-02-21-17:46:29 64 bytes from 10.200.63.137: icmp_req=76 ttl=64 time=302 ms
2013-02-21-17:46:30 64 bytes from 10.200.63.137: icmp_req=77 ttl=64 time=0.500 ms
2013-02-21-17:46:32 64 bytes from 10.200.63.137: icmp_req=78 ttl=64 time=413 ms
2013-02-21-17:46:32 64 bytes from 10.200.63.137: icmp_req=79 ttl=64 time=329 ms
2013-02-21-17:46:33 64 bytes from 10.200.63.137: icmp_req=80 ttl=64 time=97.3 ms
2013-02-21-17:46:34 64 bytes from 10.200.63.137: icmp_req=81 ttl=64 time=146 ms
2013-02-21-17:46:35 64 bytes from 10.200.63.137: icmp_req=82 ttl=64 time=263 ms
2013-02-21-17:46:36 64 bytes from 10.200.63.137: icmp_req=83 ttl=64 time=268 ms
2013-02-21-17:46:37 64 bytes from 10.200.63.137: icmp_req=84 ttl=64 time=248 ms
2013-02-21-17:46:39 64 bytes from 10.200.63.137: icmp_req=85 ttl=64 time=426 ms
2013-02-21-17:46:39 64 bytes from 10.200.63.137: icmp_req=86 ttl=64 time=158 ms
2013-02-21-17:46:40 64 bytes from 10.200.63.137: icmp_req=87 ttl=64 time=70.0 ms
2013-02-21-17:46:42 64 bytes from 10.200.63.137: icmp_req=88 ttl=64 time=598 ms
2013-02-21-17:46:42 64 bytes from 10.200.63.137: icmp_req=89 ttl=64 time=98.7 ms
2013-02-21-17:46:43 64 bytes from 10.200.63.137: icmp_req=90 ttl=64 time=168 ms
2013-02-21-17:46:44 64 bytes from 10.200.63.137: icmp_req=91 ttl=64 time=94.1 ms
2013-02-21-17:46:45 64 bytes from 10.200.63.137: icmp_req=92 ttl=64 time=0.375 ms
2013-02-21-17:46:47 64 bytes from 10.200.63.137: icmp_req=93 ttl=64 time=629 ms
2013-02-21-17:46:47 64 bytes from 10.200.63.137: icmp_req=94 ttl=64 time=61.5 ms
2013-02-21-17:46:48 64 bytes from 10.200.63.137: icmp_req=95 ttl=64 time=0.630 ms
2013-02-21-17:46:49 64 bytes from 10.200.63.137: icmp_req=96 ttl=64 time=0.491 ms
2013-02-21-17:46:50 64 bytes from 10.200.63.137: icmp_req=97 ttl=64 time=0.397 ms
2013-02-21-17:46:51 64 bytes from 10.200.63.137: icmp_req=98 ttl=64 time=0.462 ms
2013-02-21-17:46:52 64 bytes from 10.200.63.137: icmp_req=99 ttl=64 time=0.471 ms
2013-02-21-17:46:53 64 bytes from 10.200.63.137: icmp_req=100 ttl=64 time=0.528 ms
2013-02-21-17:46:54 64 bytes from 10.200.63.137: icmp_req=101 ttl=64 time=0.415 ms
2013-02-21-17:46:55 64 bytes from 10.200.63.137: icmp_req=102 ttl=64 time=0.469 ms
2013-02-21-17:46:56 64 bytes from 10.200.63.137: icmp_req=103 ttl=64 time=0.338 ms
2013-02-21-17:46:57 64 bytes from 10.200.63.137: icmp_req=104 ttl=64 time=0.433 ms
2013-02-21-17:46:58 64 bytes from 10.200.63.137: icmp_req=105 ttl=64 time=0.628 ms
2013-02-21-17:46:59 64 bytes from 10.200.63.137: icmp_req=106 ttl=64 time=0.434 ms
2013-02-21-17:47:00 64 bytes from 10.200.63.137: icmp_req=107 ttl=64 time=0.392 ms
(3-3/3)