blantons /mnt/btrfs # df -h /mnt/btrfs
Filesystem Size Used Avail Use% Mounted on
/dev/sda 913G 17M 895G 1% /mnt/btrfs
blantons /mnt/btrfs # btrfs filesystem df /mnt/btrfs
Data, RAID10: total=24.00GiB, used=768.00KiB
System, RAID1: total=8.00MiB, used=16.00KiB
Metadata, RAID1: total=1.00GiB, used=112.00KiB
GlobalReserve, single: total=16.00MiB, used=0.00B
blantons /mnt/btrfs # fio -rw=read -bs=4k -size=100m -numjobs=40 -runtime=60 -direct=1 -invalidate=1 -ioengine=libaio -iodepth=32 -iodepth_batch=32 -group_reporting -name=seqread
seqread: (g=0): rw=read, bs=4K-4K/4K-4K/4K-4K, ioengine=libaio, iodepth=32
...
fio-2.15
Starting 40 processes
Jobs: 20 (f=17): [_(1),R(1),_(1),R(1),_(1),R(1),_(1),R(1),_(1),R(1),_(1),R(1),_(1),R(1),_(1),R(1),_(1),R(1),_(1),R(1),_(1),R(1),_(1),R(1),_(1),R(1),_(1),R(1),_(1),f(1),_(1),R(1),_(1),f(1),_(1),f(1),_(1),R(1),_(1),R(1)] [100.0% done] [471.3MB/0KB/0KB /s] [121K/0/0 iops] [eta 00m:00s]
seqread: (groupid=0, jobs=40): err= 0: pid=9860: Sun Apr 5 10:36:58 2020
read : io=4000.0MB, bw=825307KB/s, iops=206326, runt= 4963msec
slat (usec): min=2, max=889, avg= 5.07, stdev=12.48
clat (usec): min=21, max=21036, avg=5470.14, stdev=4550.88
lat (usec): min=32, max=21039, avg=5475.21, stdev=4550.68
clat percentiles (usec):
| 1.00th=[ 378], 5.00th=[ 604], 10.00th=[ 748], 20.00th=[ 980],
| 30.00th=[ 1192], 40.00th=[ 1464], 50.00th=[ 2512], 60.00th=[ 8256],
| 70.00th=[ 9024], 80.00th=[10688], 90.00th=[11328], 95.00th=[11712],
| 99.00th=[13248], 99.50th=[14400], 99.90th=[16192], 99.95th=[17024],
| 99.99th=[18816]
lat (usec) : 50=0.01%, 100=0.03%, 250=0.18%, 500=2.61%, 750=7.23%
lat (usec) : 1000=10.98%
lat (msec) : 2=27.64%, 4=1.81%, 10=24.45%, 20=25.05%, 50=0.01%
cpu : usr=0.55%, sys=2.72%, ctx=252834, majf=0, minf=1656
IO depths : 1=0.0%, 2=0.0%, 4=0.0%, 8=0.0%, 16=0.0%, 32=100.0%, >=64=0.0%
submit : 0=0.0%, 4=100.0%, 8=0.0%, 16=0.0%, 32=0.1%, 64=0.0%, >=64=0.0%
complete : 0=0.0%, 4=100.0%, 8=0.0%, 16=0.0%, 32=0.1%, 64=0.0%, >=64=0.0%
issued : total=r=1024000/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=32
Run status group 0 (all jobs):
READ: io=4000.0MB, aggrb=825307KB/s, minb=825307KB/s, maxb=825307KB/s, mint=4963msec, maxt=4963msec
blantons /mnt/btrfs # fio -rw=write -bs=4k -size=100m -numjobs=40 -runtime=60 -direct=1 -invalidate=1 -ioengine=libaio -iodepth=32 -iodepth_batch=32 -group_reporting -name=seqwrite
seqwrite: (g=0): rw=write, bs=4K-4K/4K-4K/4K-4K, ioengine=libaio, iodepth=32
...
fio-2.15
Starting 40 processes
Jobs: 21 (f=18): [W(6),_(2),W(1),f(1),_(5),W(2),_(2),W(1),f(1),W(3),f(1),_(4),W(1),_(3),W(1),_(2),W(1),_(1),W(2)] [76.9% done] [0KB/306.7MB/0KB /s] [0/78.5K/0 iops] [eta 00m:03s]
seqwrite: (groupid=0, jobs=40): err= 0: pid=9906: Sun Apr 5 10:37:35 2020
write: io=4000.0MB, bw=401057KB/s, iops=100264, runt= 10213msec
slat (usec): min=8, max=51019, avg=162.47, stdev=728.51
clat (usec): min=0, max=98169, avg=12145.34, stdev=11495.90
lat (usec): min=36, max=98179, avg=12307.81, stdev=11564.41
clat percentiles (usec):
| 1.00th=[ 110], 5.00th=[ 258], 10.00th=[ 358], 20.00th=[ 596],
| 30.00th=[ 1336], 40.00th=[ 7392], 50.00th=[12992], 60.00th=[15168],
| 70.00th=[16512], 80.00th=[18304], 90.00th=[26496], 95.00th=[34560],
| 99.00th=[49408], 99.50th=[54016], 99.90th=[63232], 99.95th=[68096],
| 99.99th=[79360]
lat (usec) : 2=0.01%, 10=0.01%, 20=0.01%, 50=0.29%, 100=0.58%
lat (usec) : 250=3.63%, 500=11.24%, 750=8.35%, 1000=3.36%
lat (msec) : 2=5.47%, 4=3.53%, 10=6.44%, 20=41.18%, 50=15.02%
lat (msec) : 100=0.91%
cpu : usr=0.30%, sys=4.84%, ctx=497270, majf=0, minf=342
IO depths : 1=0.0%, 2=0.0%, 4=0.0%, 8=0.0%, 16=0.0%, 32=100.0%, >=64=0.0%
submit : 0=0.0%, 4=100.0%, 8=0.0%, 16=0.0%, 32=0.1%, 64=0.0%, >=64=0.0%
complete : 0=0.0%, 4=100.0%, 8=0.0%, 16=0.0%, 32=0.1%, 64=0.0%, >=64=0.0%
issued : total=r=0/w=1024000/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=32
Run status group 0 (all jobs):
WRITE: io=4000.0MB, aggrb=401057KB/s, minb=401057KB/s, maxb=401057KB/s, mint=10213msec, maxt=10213msec
blantons /mnt/btrfs # fio -rw=randread -bs=4k -size=100m -numjobs=40 -runtime=60 -direct=1 -invalidate=1 -ioengine=libaio -iodepth=32 -iodepth_batch=32 -group_reporting -name=randread
randread: (g=0): rw=randread, bs=4K-4K/4K-4K/4K-4K, ioengine=libaio, iodepth=32
...
fio-2.15
Starting 40 processes
Jobs: 8 (f=6): [_(6),f(1),_(1),r(1),_(7),r(1),_(1),r(1),_(1),r(1),_(5),f(1),_(9),r(1),_(1),r(1),_(1)] [100.0% done] [595.5MB/0KB/0KB /s] [152K/0/0 iops] [eta 00m:00s]
randread: (groupid=0, jobs=40): err= 0: pid=9955: Sun Apr 5 10:38:42 2020
read : io=4000.0MB, bw=680173KB/s, iops=170043, runt= 6022msec
slat (usec): min=2, max=38434, avg=188.68, stdev=668.37
clat (usec): min=2, max=88514, avg=6783.72, stdev=3880.93
lat (usec): min=75, max=89075, avg=6972.39, stdev=4015.51
clat percentiles (usec):
| 1.00th=[ 668], 5.00th=[ 1688], 10.00th=[ 2608], 20.00th=[ 3952],
| 30.00th=[ 5024], 40.00th=[ 5792], 50.00th=[ 6304], 60.00th=[ 6560],
| 70.00th=[ 7712], 80.00th=[ 9152], 90.00th=[11328], 95.00th=[13376],
| 99.00th=[18304], 99.50th=[21632], 99.90th=[40192], 99.95th=[45312],
| 99.99th=[54528]
lat (usec) : 4=0.01%, 10=0.01%, 20=0.01%, 50=0.01%, 100=0.01%
lat (usec) : 250=0.12%, 500=0.45%, 750=0.66%, 1000=0.80%
lat (msec) : 2=4.47%, 4=13.99%, 10=63.97%, 20=14.87%, 50=0.66%
lat (msec) : 100=0.02%
cpu : usr=0.60%, sys=3.41%, ctx=586263, majf=0, minf=1607
IO depths : 1=0.0%, 2=0.0%, 4=0.0%, 8=0.0%, 16=0.0%, 32=100.0%, >=64=0.0%
submit : 0=0.0%, 4=100.0%, 8=0.0%, 16=0.0%, 32=0.1%, 64=0.0%, >=64=0.0%
complete : 0=0.0%, 4=100.0%, 8=0.0%, 16=0.0%, 32=0.1%, 64=0.0%, >=64=0.0%
issued : total=r=1024000/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=32
Run status group 0 (all jobs):
READ: io=4000.0MB, aggrb=680172KB/s, minb=680172KB/s, maxb=680172KB/s, mint=6022msec, maxt=6022msec
blantons /mnt/btrfs # fio -rw=randwrite -bs=4k -size=100m -numjobs=40 -runtime=60 -direct=1 -invalidate=1 -ioengine=libaio -iodepth=32 -iodepth_batch=32 -group_reporting -name=randwrite
randwrite: (g=0): rw=randwrite, bs=4K-4K/4K-4K/4K-4K, ioengine=libaio, iodepth=32
...
fio-2.15
Starting 40 processes
Jobs: 34 (f=34): [w(22),_(1),w(4),_(1),w(3),_(1),w(1),_(2),w(3),_(1),w(1)] [86.7% done] [0KB/380.7MB/0KB /s] [0/97.5K/0 iops] [eta 00m:02s]
randwrite: (groupid=0, jobs=40): err= 0: pid=10001: Sun Apr 5 10:39:33 2020
write: io=4000.0MB, bw=301332KB/s, iops=75332, runt= 13593msec
slat (usec): min=10, max=159396, avg=518.10, stdev=2916.75
clat (usec): min=3, max=300901, avg=15975.43, stdev=19470.17
lat (usec): min=46, max=300918, avg=16493.53, stdev=19855.24
clat percentiles (usec):
| 1.00th=[ 330], 5.00th=[ 548], 10.00th=[ 668], 20.00th=[ 868],
| 30.00th=[ 1736], 40.00th=[ 6560], 50.00th=[10048], 60.00th=[13376],
| 70.00th=[18048], 80.00th=[26752], 90.00th=[42240], 95.00th=[56576],
| 99.00th=[86528], 99.50th=[98816], 99.90th=[130560], 99.95th=[144384],
| 99.99th=[220160]
lat (usec) : 4=0.01%, 10=0.01%, 50=0.01%, 100=0.03%, 250=0.38%
lat (usec) : 500=3.36%, 750=10.98%, 1000=8.88%
lat (msec) : 2=7.02%, 4=3.40%, 10=15.94%, 20=23.13%, 50=19.68%
lat (msec) : 100=6.73%, 250=0.46%, 500=0.01%
cpu : usr=0.31%, sys=4.55%, ctx=547576, majf=0, minf=341
IO depths : 1=0.0%, 2=0.0%, 4=0.0%, 8=0.0%, 16=0.0%, 32=100.0%, >=64=0.0%
submit : 0=0.0%, 4=100.0%, 8=0.0%, 16=0.0%, 32=0.1%, 64=0.0%, >=64=0.0%
complete : 0=0.0%, 4=100.0%, 8=0.0%, 16=0.0%, 32=0.1%, 64=0.0%, >=64=0.0%
issued : total=r=0/w=1024000/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=32
Run status group 0 (all jobs):
WRITE: io=4000.0MB, aggrb=301331KB/s, minb=301331KB/s, maxb=301331KB/s, mint=13593msec, maxt=13593msec