fio --filename=/dev/zvol/sata/test --sync=1 --rw=randwrite --bs=4k --numjobs=1 --iodepth=1 --runtime=120 --time_based --group_reporting --name=journal-test
journal-test: (g=0): rw=randwrite, bs=4K-4K/4K-4K/4K-4K, ioengine=psync, iodepth=1
fio-2.16
Starting 1 process
Jobs: 1 (f=1): [f(1)] [100.0% done] [0KB/0KB/0KB /s] [0/0/0 iops] [eta 00m:00s]
journal-test: (groupid=0, jobs=1): err= 0: pid=50229: Wed Sep 13 10:09:48 2017
write: io=22512KB, bw=192078B/s, iops=46, runt=120015msec
clat (usec): min=165, max=1472.2K, avg=21319.90, stdev=59420.45
lat (usec): min=165, max=1472.2K, avg=21320.30, stdev=59420.47
clat percentiles (usec):
| 1.00th=[ 223], 5.00th=[ 366], 10.00th=[ 426], 20.00th=[ 3664],
| 30.00th=[ 7456], 40.00th=[10176], 50.00th=[12864], 60.00th=[15424],
| 70.00th=[18304], 80.00th=[22144], 90.00th=[29056], 95.00th=[45824],
| 99.00th=[252928], 99.50th=[440320], 99.90th=[897024], 99.95th=[1044480],
| 99.99th=[1466368]
lat (usec) : 250=1.53%, 500=11.51%, 750=3.46%, 1000=0.16%
lat (msec) : 2=0.57%, 4=3.45%, 10=18.57%, 20=35.38%, 50=20.74%
lat (msec) : 100=2.03%, 250=1.58%, 500=0.66%, 750=0.23%, 1000=0.09%
lat (msec) : 2000=0.05%
cpu : usr=0.03%, sys=0.79%, ctx=21159, majf=0, minf=10
IO depths : 1=100.0%, 2=0.0%, 4=0.0%, 8=0.0%, 16=0.0%, 32=0.0%, >=64=0.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.0%
issued : total=r=0/w=5628/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=1
Run status group 0 (all jobs):
WRITE: io=22512KB, aggrb=187KB/s, minb=187KB/s, maxb=187KB/s, mint=120015msec, maxt=120015msec
fio --filename=/dev/zvol/sata/test --sync=1 --rw=randread --bs=4k --numjobs=1 --iodepth=1 --runtime=120 --time_based --group_reporting --name=journal-test
journal-test: (g=0): rw=randread, bs=4K-4K/4K-4K/4K-4K, ioengine=psync, iodepth=1
fio-2.16
Starting 1 process
Jobs: 1 (f=1): [r(1)] [100.0% done] [412KB/0KB/0KB /s] [103/0/0 iops] [eta 00m:00s]
journal-test: (groupid=0, jobs=1): err= 0: pid=51264: Wed Sep 13 10:12:59 2017
read : io=51816KB, bw=442137B/s, iops=107, runt=120007msec
clat (usec): min=7, max=1459.3K, avg=9261.55, stdev=37367.30
lat (usec): min=7, max=1459.3K, avg=9261.71, stdev=37367.33
clat percentiles (usec):
| 1.00th=[ 9], 5.00th=[ 11], 10.00th=[ 12], 20.00th=[ 13],
| 30.00th=[ 14], 40.00th=[ 16], 50.00th=[ 211], 60.00th=[ 4448],
| 70.00th=[ 9152], 80.00th=[13760], 90.00th=[19840], 95.00th=[25728],
| 99.00th=[90624], 99.50th=[252928], 99.90th=[536576], 99.95th=[675840],
| 99.99th=[1044480]
lat (usec) : 10=1.65%, 20=40.57%, 50=3.93%, 100=0.08%, 250=6.73%
lat (usec) : 500=2.60%, 750=0.05%, 1000=0.02%
lat (msec) : 2=0.36%, 4=3.06%, 10=13.68%, 20=17.46%, 50=8.14%
lat (msec) : 100=0.73%, 250=0.44%, 500=0.38%, 750=0.08%, 1000=0.02%
lat (msec) : 2000=0.02%
cpu : usr=0.03%, sys=0.64%, ctx=11051, majf=0, minf=6
IO depths : 1=100.0%, 2=0.0%, 4=0.0%, 8=0.0%, 16=0.0%, 32=0.0%, >=64=0.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.0%
issued : total=r=12954/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=1
Run status group 0 (all jobs):
READ: io=51816KB, aggrb=431KB/s, minb=431KB/s, maxb=431KB/s, mint=120007msec, maxt=120007msec