fio: time_based requires a runtime/timeout setting
benchmarkrandread: (g=0): rw=randread, bs=4K-4K/4K-4K/4K-4K, ioengine=libaio, iodepth=128
...
fio-2.2.8
Starting 4 processes
Jobs: 3 (f=3): [r(2),_(1),r(1)] [99.9% done] [201.6MB/0KB/0KB /s] [51.6K/0/0 iops] [eta 00m:02s]
benchmarkrandread: (groupid=0, jobs=4): err= 0: pid=241808: Sat Jul 2 19:18:40 2016
read : io=409600MB, bw=172663KB/s, iops=43165, runt=2429187msec
slat (usec): min=2, max=354302, avg=90.83, stdev=486.42
clat (usec): min=665, max=442792, avg=11766.30, stdev=2695.06
lat (usec): min=670, max=444290, avg=11857.27, stdev=2740.17
clat percentiles (usec):
| 1.00th=[ 7712], 5.00th=[ 8768], 10.00th=[ 8896], 20.00th=[ 9152],
| 30.00th=[11200], 40.00th=[11712], 50.00th=[11840], 60.00th=[11840],
| 70.00th=[11968], 80.00th=[13120], 90.00th=[14656], 95.00th=[14912],
| 99.00th=[19072], 99.50th=[22400], 99.90th=[29824], 99.95th=[33536],
| 99.99th=[48384]
bw (KB /s): min= 4808, max=161264, per=25.03%, avg=43213.88, stdev=1921.20
lat (usec) : 750=0.01%, 1000=0.01%
lat (msec) : 2=0.01%, 4=0.01%, 10=23.03%, 20=76.06%, 50=0.88%
lat (msec) : 100=0.01%, 250=0.01%, 500=0.01%
cpu : usr=1.82%, sys=4.94%, ctx=3523958, majf=0, minf=648
IO depths : 1=0.1%, 2=0.1%, 4=0.1%, 8=0.1%, 16=0.1%, 32=0.1%, >=64=100.0%
submit : 0=0.0%, 4=100.0%, 8=0.0%, 16=0.0%, 32=0.0%, 64=0.0%, >=64=0.0%
complete : 0=0.0%, 4=100.0%, 8=0.0%, 16=0.0%, 32=0.0%, 64=0.0%, >=64=0.1%
issued : total=r=104857600/w=0/d=0, short=r=0/w=0/d=0, drop=r=0/w=0/d=0
latency : target=0, window=0, percentile=100.00%, depth=128
Run status group 0 (all jobs):
READ: io=409600MB, aggrb=172662KB/s, minb=172662KB/s, maxb=172662KB/s, mint=2429187msec, maxt=2429187msec
Disk stats (read/write):
sdd: ios=104735835/0, merge=108102/0, ticks=359444650/0, in_queue=359463798, util=100.00%
fio: time_based requires a runtime/timeout setting
benchmarkrandwrite: (g=0): rw=randwrite, bs=4K-4K/4K-4K/4K-4K, ioengine=libaio, iodepth=128
...
fio-2.2.8
Starting 4 processes
Jobs: 2 (f=2): [w(1),_(2),w(1)] [100.0% done] [0KB/98.71MB/0KB /s] [0/25.3K/0 iops] [eta 00m:01s]
benchmarkrandwrite: (groupid=0, jobs=4): err= 0: pid=243525: Sat Jul 2 20:36:51 2016
write: io=409600MB, bw=89409KB/s, iops=22352, runt=4691121msec
slat (usec): min=2, max=794286, avg=177.03, stdev=959.81
clat (msec): min=1, max=910, avg=22.73, stdev= 5.33
lat (msec): min=1, max=910, avg=22.90, stdev= 5.42
clat percentiles (usec):
| 1.00th=[14400], 5.00th=[16512], 10.00th=[17280], 20.00th=[17536],
| 30.00th=[21376], 40.00th=[22144], 50.00th=[22912], 60.00th=[22912],
| 70.00th=[23168], 80.00th=[25728], 90.00th=[28544], 95.00th=[29056],
| 99.00th=[37632], 99.50th=[43776], 99.90th=[60160], 99.95th=[67072],
| 99.99th=[99840]
bw (KB /s): min= 319, max=32272, per=25.03%, avg=22375.37, stdev=984.71
lat (msec) : 2=0.01%, 4=0.01%, 10=0.01%, 20=24.91%, 50=74.80%
lat (msec) : 100=0.27%, 250=0.01%, 500=0.01%, 750=0.01%, 1000=0.01%
cpu : usr=1.05%, sys=2.62%, ctx=3782846, majf=0, minf=127
IO depths : 1=0.1%, 2=0.1%, 4=0.1%, 8=0.1%, 16=0.1%, 32=0.1%, >=64=100.0%
submit : 0=0.0%, 4=100.0%, 8=0.0%, 16=0.0%, 32=0.0%, 64=0.0%, >=64=0.0%
complete : 0=0.0%, 4=100.0%, 8=0.0%, 16=0.0%, 32=0.0%, 64=0.0%, >=64=0.1%
issued : total=r=0/w=104857600/d=0, short=r=0/w=0/d=0, drop=r=0/w=0/d=0
latency : target=0, window=0, percentile=100.00%, depth=128
Run status group 0 (all jobs):
WRITE: io=409600MB, aggrb=89409KB/s, minb=89409KB/s, maxb=89409KB/s, mint=4691121msec, maxt=4691121msec
Disk stats (read/write):
sdd: ios=0/104745084, merge=0/111255, ticks=0/705202758, in_queue=705204190, util=100.00%
fio: time_based requires a runtime/timeout setting
benchmarkrw: (g=0): rw=randrw, bs=4K-4K/4K-4K/4K-4K, ioengine=libaio, iodepth=128
...
fio-2.2.8
Starting 4 processes
Jobs: 1 (f=1): [_(2),m(1),_(1)] [100.0% done] [99.45MB/43808KB/0KB /s] [25.5K/10.1K/0 iops] [eta 00m:00s]
benchmarkrw: (groupid=0, jobs=4): err= 0: pid=1697: Sat Jul 2 21:30:43 2016
read : io=286721MB, bw=90860KB/s, iops=22715, runt=3231368msec
slat (usec): min=2, max=448432, avg=121.12, stdev=643.21
clat (usec): min=681, max=474987, avg=15892.53, stdev=4492.17
lat (usec): min=685, max=476472, avg=16013.80, stdev=4538.32
clat percentiles (usec):
| 1.00th=[ 9280], 5.00th=[10816], 10.00th=[11328], 20.00th=[11968],
| 30.00th=[13376], 40.00th=[14784], 50.00th=[15424], 60.00th=[15936],
| 70.00th=[17536], 80.00th=[19328], 90.00th=[21376], 95.00th=[23424],
| 99.00th=[27008], 99.50th=[28544], 99.90th=[33024], 99.95th=[36096],
| 99.99th=[75264]
bw (KB /s): min= 5748, max=89504, per=25.03%, avg=22742.92, stdev=1344.26
write: io=122879MB, bw=38939KB/s, iops=9734, runt=3231368msec
slat (usec): min=2, max=446873, avg=121.79, stdev=646.27
clat (usec): min=645, max=476299, avg=15090.65, stdev=4177.14
lat (usec): min=650, max=476323, avg=15212.60, stdev=4225.38
clat percentiles (usec):
| 1.00th=[ 9024], 5.00th=[10432], 10.00th=[11200], 20.00th=[11712],
| 30.00th=[12224], 40.00th=[13760], 50.00th=[14912], 60.00th=[15552],
| 70.00th=[16192], 80.00th=[18048], 90.00th=[19840], 95.00th=[22144],
| 99.00th=[26240], 99.50th=[27776], 99.90th=[32128], 99.95th=[34560],
| 99.99th=[65280]
bw (KB /s): min= 2517, max=38384, per=25.03%, avg=9746.64, stdev=607.02
lat (usec) : 750=0.01%, 1000=0.01%
lat (msec) : 2=0.01%, 4=0.01%, 10=2.72%, 20=84.38%, 50=12.86%
lat (msec) : 100=0.01%, 250=0.01%, 500=0.01%
cpu : usr=1.50%, sys=3.96%, ctx=4506495, majf=0, minf=129
IO depths : 1=0.1%, 2=0.1%, 4=0.1%, 8=0.1%, 16=0.1%, 32=0.1%, >=64=100.0%
submit : 0=0.0%, 4=100.0%, 8=0.0%, 16=0.0%, 32=0.0%, 64=0.0%, >=64=0.0%
complete : 0=0.0%, 4=100.0%, 8=0.0%, 16=0.0%, 32=0.0%, 64=0.0%, >=64=0.1%
issued : total=r=73400654/w=31456946/d=0, short=r=0/w=0/d=0, drop=r=0/w=0/d=0
latency : target=0, window=0, percentile=100.00%, depth=128
Run status group 0 (all jobs):
READ: io=286721MB, aggrb=90860KB/s, minb=90860KB/s, maxb=90860KB/s, mint=3231368msec, maxt=3231368msec
WRITE: io=122879MB, aggrb=38939KB/s, minb=38939KB/s, maxb=38939KB/s, mint=3231368msec, maxt=3231368msec
Disk stats (read/write):
sdd: ios=73344824/31446879, merge=54564/9735, ticks=357066836/127345210, in_queue=484425785, util=100.00%