System: o01 filesystem_utilization=172.21.64.1:6789:/ 462495375360 188416 462495186944 1% /tmp/cbt/mnt/cbt-cephfs-kernel/0 date=2020.07.13-17.30.55 io500_clean_cache=True io500_clean_cache_cmd=drop_cache io500_cleanup_workdir= io500_find_cmd=/tmp/cbt/mnt/cbt-cephfs-kernel/0/io500/io500-app/bin/pfind io500_find_cmd_args= io500_find_mpi=True io500_ini=./config-run.ini io500_ior_cmd=/tmp/cbt/mnt/cbt-cephfs-kernel/0/io500/io500-app/bin/ior io500_ior_easy_params=' -a CEPHFS --cephfs.user=admin --cephfs.conf=/etc/ceph/ceph.conf --cephfs.prefix=/tmp/cbt/mnt/cbt-cephfs-kernel/0 -t 2m -b 9920000m -F' io500_ior_easy_size=9920000 io500_ior_hard_api='CEPHFS --cephfs.user=admin --cephfs.conf=/etc/ceph/ceph.conf --cephfs.prefix=/tmp/cbt/mnt/cbt-cephfs-kernel/0' io500_ior_hard_api_specific_options= io500_ior_hard_writes_per_proc=1000000 io500_mdreal_cmd=/tmp/cbt/mnt/cbt-cephfs-kernel/0/io500/io500-app/bin/md-real-io io500_mdreal_params='-P=5000 -I=1000' io500_mdtest_cmd=/tmp/cbt/mnt/cbt-cephfs-kernel/0/io500/io500-app/bin/mdtest io500_mdtest_easy_files_per_proc=400000 io500_mdtest_easy_params='-u -L -a CEPHFS --cephfs.user=admin --cephfs.conf=/etc/ceph/ceph.conf --cephfs.prefix=/tmp/cbt/mnt/cbt-cephfs-kernel/0' io500_mdtest_hard_api='CEPHFS --cephfs.user=admin --cephfs.conf=/etc/ceph/ceph.conf --cephfs.prefix=/tmp/cbt/mnt/cbt-cephfs-kernel/0' io500_mdtest_hard_api_specific_options= io500_mdtest_hard_files_per_proc=100000 io500_mpiargs='-npernode 112 --hostfile /home/nhm/io500-app/hosts' io500_mpirun=mpirun io500_result_dir=/tmp/cbt/mnt/cbt-cephfs-kernel/0/io500/io500-app/results/2020.07.13-17.30.55-scr io500_rules=regular io500_run_find=True io500_run_ior_easy=True io500_run_ior_easy_read=True io500_run_ior_hard=True io500_run_ior_hard_read=True io500_run_md_easy=True io500_run_md_easy_delete=True io500_run_md_easy_stat=True io500_run_md_hard=True io500_run_md_hard_delete=True io500_run_md_hard_read=True io500_run_md_hard_stat=True io500_run_mdreal=False io500_stonewall_timer=300 io500_workdir=/tmp/cbt/mnt/cbt-cephfs-kernel/0/io500/io500-app/datafiles/2020.07.13-17.30.55-scr Running the IO500 Benchmark now [Creating] directories Synchronizing and cleaning the cache total used free shared buff/cache available Mem: 385336 4961 375744 3786 4629 374548 Swap: 4095 0 4095 total used free shared buff/cache available Mem: 385336 4949 375914 3786 4471 374639 Swap: 4095 0 4095 [Starting] ior_easy_write [Exec] mpirun -npernode 112 --hostfile /home/nhm/io500-app/hosts /tmp/cbt/mnt/cbt-cephfs-kernel/0/io500/io500-app/bin/ior -w -a CEPHFS --cephfs.user=admin --cephfs.conf=/etc/ceph/ceph.conf --cephfs.prefix=/tmp/cbt/mnt/cbt-cephfs-kernel/0 -t 2m -b 9920000m -F -i 1 -C -Q 1 -g -G 27 -k -e -o /tmp/cbt/mnt/cbt-cephfs-kernel/0/io500/io500-app/datafiles/2020.07.13-17.30.55-scr/ior_easy/ior_file_easy -O stoneWallingStatusFile=/tmp/cbt/mnt/cbt-cephfs-kernel/0/io500/io500-app/datafiles/2020.07.13-17.30.55-scr/ior_easy/stonewall -O stoneWallingWearOut=1 -D 300 [Results] in /tmp/cbt/mnt/cbt-cephfs-kernel/0/io500/io500-app/results/2020.07.13-17.30.55-scr/ior_easy_write.txt. [RESULT] BW phase 1 ior_easy_write 10.253 GiB/s : time 602.95 seconds Synchronizing and cleaning the cache total used free shared buff/cache available Mem: 385336 5066 375709 3786 4559 374478 Swap: 4095 0 4095 total used free shared buff/cache available Mem: 385336 5023 375833 3786 4479 374562 Swap: 4095 0 4095 [Starting] mdtest_easy_write [Exec] mpirun -npernode 112 --hostfile /home/nhm/io500-app/hosts /tmp/cbt/mnt/cbt-cephfs-kernel/0/io500/io500-app/bin/mdtest -Y -C -F -P -d /tmp/cbt/mnt/cbt-cephfs-kernel/0/io500/io500-app/datafiles/2020.07.13-17.30.55-scr/mdt_easy -n 400000 -u -L -a CEPHFS --cephfs.user=admin --cephfs.conf=/etc/ceph/ceph.conf --cephfs.prefix=/tmp/cbt/mnt/cbt-cephfs-kernel/0 -x /tmp/cbt/mnt/cbt-cephfs-kernel/0/io500/io500-app/datafiles/2020.07.13-17.30.55-scr/mdt_easy-stonewall -N 1 -W 300 [Results] in /tmp/cbt/mnt/cbt-cephfs-kernel/0/io500/io500-app/results/2020.07.13-17.30.55-scr/mdtest_easy_write.txt. [RESULT] IOPS phase 1 mdtest_easy_write 123.683 kiops : time 362.23 seconds Synchronizing and cleaning the cache total used free shared buff/cache available Mem: 385336 5097 375673 3786 4565 374444 Swap: 4095 0 4095 total used free shared buff/cache available Mem: 385336 5078 375764 3786 4492 374499 Swap: 4095 0 4095 [Starting] ior_hard_write [Exec] mpirun -npernode 112 --hostfile /home/nhm/io500-app/hosts /tmp/cbt/mnt/cbt-cephfs-kernel/0/io500/io500-app/bin/ior -w -s 1000000 -a CEPHFS --cephfs.user=admin --cephfs.conf=/etc/ceph/ceph.conf --cephfs.prefix=/tmp/cbt/mnt/cbt-cephfs-kernel/0 -i 1 -C -Q 1 -g -G 27 -k -e -t 47008 -b 47008 -o /tmp/cbt/mnt/cbt-cephfs-kernel/0/io500/io500-app/datafiles/2020.07.13-17.30.55-scr/ior_hard/IOR_file -O stoneWallingStatusFile=/tmp/cbt/mnt/cbt-cephfs-kernel/0/io500/io500-app/datafiles/2020.07.13-17.30.55-scr/ior_hard/stonewall -O stoneWallingWearOut=1 -D 300 [Results] in /tmp/cbt/mnt/cbt-cephfs-kernel/0/io500/io500-app/results/2020.07.13-17.30.55-scr/ior_hard_write.txt. [RESULT] BW phase 2 ior_hard_write 7.630 GiB/s : time 300.37 seconds Synchronizing and cleaning the cache total used free shared buff/cache available Mem: 385336 5130 375618 3786 4587 374400 Swap: 4095 0 4095 total used free shared buff/cache available Mem: 385336 5085 375759 3786 4491 374493 Swap: 4095 0 4095 [Starting] mdtest_hard_write [Exec] mpirun -npernode 112 --hostfile /home/nhm/io500-app/hosts /tmp/cbt/mnt/cbt-cephfs-kernel/0/io500/io500-app/bin/mdtest -Y -C -t -F -P -w 3901 -e 3901 -d /tmp/cbt/mnt/cbt-cephfs-kernel/0/io500/io500-app/datafiles/2020.07.13-17.30.55-scr/mdt_hard -n 100000 -x /tmp/cbt/mnt/cbt-cephfs-kernel/0/io500/io500-app/datafiles/2020.07.13-17.30.55-scr/mdt_hard-stonewall -a CEPHFS --cephfs.user=admin --cephfs.conf=/etc/ceph/ceph.conf --cephfs.prefix=/tmp/cbt/mnt/cbt-cephfs-kernel/0 -N 1 -W 300 [Results] in /tmp/cbt/mnt/cbt-cephfs-kernel/0/io500/io500-app/results/2020.07.13-17.30.55-scr/mdtest_hard_write.txt. [RESULT] IOPS phase 2 mdtest_hard_write 16.188 kiops : time 320.83 seconds Synchronizing and cleaning the cache total used free shared buff/cache available Mem: 385336 5173 375578 3786 4583 374359 Swap: 4095 0 4095 total used free shared buff/cache available Mem: 385336 5115 375727 3786 4493 374463 Swap: 4095 0 4095 [Starting] find [Exec] mpirun -npernode 112 --hostfile /home/nhm/io500-app/hosts /tmp/cbt/mnt/cbt-cephfs-kernel/0/io500/io500-app/bin/pfind /tmp/cbt/mnt/cbt-cephfs-kernel/0/io500/io500-app/datafiles/2020.07.13-17.30.55-scr -newer /tmp/cbt/mnt/cbt-cephfs-kernel/0/io500/io500-app/datafiles/2020.07.13-17.30.55-scr/timestampfile -size 3901c -name "*01*" [Results] in /tmp/cbt/mnt/cbt-cephfs-kernel/0/io500/io500-app/results/2020.07.13-17.30.55-scr/find.txt. [FIND] MATCHED 192780/49993670 in 195.5535 seconds [RESULT] IOPS phase 3 find 255.650 kiops : time 195.55 seconds [Starting] ior_easy_read [Exec] mpirun -npernode 112 --hostfile /home/nhm/io500-app/hosts /tmp/cbt/mnt/cbt-cephfs-kernel/0/io500/io500-app/bin/ior -r -R -a CEPHFS --cephfs.user=admin --cephfs.conf=/etc/ceph/ceph.conf --cephfs.prefix=/tmp/cbt/mnt/cbt-cephfs-kernel/0 -t 2m -b 9920000m -F -i 1 -C -Q 1 -g -G 27 -k -e -o /tmp/cbt/mnt/cbt-cephfs-kernel/0/io500/io500-app/datafiles/2020.07.13-17.30.55-scr/ior_easy/ior_file_easy -O stoneWallingStatusFile=/tmp/cbt/mnt/cbt-cephfs-kernel/0/io500/io500-app/datafiles/2020.07.13-17.30.55-scr/ior_easy/stonewall [Results] in /tmp/cbt/mnt/cbt-cephfs-kernel/0/io500/io500-app/results/2020.07.13-17.30.55-scr/ior_easy_read.txt. [RESULT] BW phase 3 ior_easy_read 10.228 GiB/s : time 604.36 seconds Synchronizing and cleaning the cache total used free shared buff/cache available Mem: 385336 12015 288245 3770 85075 367169 Swap: 4095 20 4075 total used free shared buff/cache available Mem: 385336 6898 373567 3770 4869 372498 Swap: 4095 20 4075 [Starting] mdtest_easy_stat [Exec] mpirun -npernode 112 --hostfile /home/nhm/io500-app/hosts /tmp/cbt/mnt/cbt-cephfs-kernel/0/io500/io500-app/bin/mdtest -T -F -P -d /tmp/cbt/mnt/cbt-cephfs-kernel/0/io500/io500-app/datafiles/2020.07.13-17.30.55-scr/mdt_easy -n 400000 -u -L -a CEPHFS --cephfs.user=admin --cephfs.conf=/etc/ceph/ceph.conf --cephfs.prefix=/tmp/cbt/mnt/cbt-cephfs-kernel/0 -x /tmp/cbt/mnt/cbt-cephfs-kernel/0/io500/io500-app/datafiles/2020.07.13-17.30.55-scr/mdt_easy-stonewall -N 1 [Results] in /tmp/cbt/mnt/cbt-cephfs-kernel/0/io500/io500-app/results/2020.07.13-17.30.55-scr/mdtest_easy_stat.txt. [RESULT] IOPS phase 4 mdtest_easy_stat 359.294 kiops : time 124.71 seconds Synchronizing and cleaning the cache total used free shared buff/cache available Mem: 385336 5186 375579 3771 4570 374360 Swap: 4095 20 4075 total used free shared buff/cache available Mem: 385336 5195 375649 3770 4491 374391 Swap: 4095 20 4075 [Starting] ior_hard_read [Exec] mpirun -npernode 112 --hostfile /home/nhm/io500-app/hosts /tmp/cbt/mnt/cbt-cephfs-kernel/0/io500/io500-app/bin/ior -r -R -s 1000000 -a CEPHFS --cephfs.user=admin --cephfs.conf=/etc/ceph/ceph.conf --cephfs.prefix=/tmp/cbt/mnt/cbt-cephfs-kernel/0 -i 1 -C -Q 1 -g -G 27 -k -e -t 47008 -b 47008 -o /tmp/cbt/mnt/cbt-cephfs-kernel/0/io500/io500-app/datafiles/2020.07.13-17.30.55-scr/ior_hard/IOR_file -O stoneWallingStatusFile=/tmp/cbt/mnt/cbt-cephfs-kernel/0/io500/io500-app/datafiles/2020.07.13-17.30.55-scr/ior_hard/stonewall [Results] in /tmp/cbt/mnt/cbt-cephfs-kernel/0/io500/io500-app/results/2020.07.13-17.30.55-scr/ior_hard_read.txt. [RESULT] BW phase 4 ior_hard_read 7.512 GiB/s : time 305.14 seconds Synchronizing and cleaning the cache total used free shared buff/cache available Mem: 385336 5196 375557 3771 4581 374344 Swap: 4095 20 4075 total used free shared buff/cache available Mem: 385336 5172 375665 3770 4497 374411 Swap: 4095 20 4075 [Starting] mdtest_hard_stat [Exec] mpirun -npernode 112 --hostfile /home/nhm/io500-app/hosts /tmp/cbt/mnt/cbt-cephfs-kernel/0/io500/io500-app/bin/mdtest -T -t -F -P -w 3901 -e 3901 -d /tmp/cbt/mnt/cbt-cephfs-kernel/0/io500/io500-app/datafiles/2020.07.13-17.30.55-scr/mdt_hard -n 100000 -x /tmp/cbt/mnt/cbt-cephfs-kernel/0/io500/io500-app/datafiles/2020.07.13-17.30.55-scr/mdt_hard-stonewall -a CEPHFS --cephfs.user=admin --cephfs.conf=/etc/ceph/ceph.conf --cephfs.prefix=/tmp/cbt/mnt/cbt-cephfs-kernel/0 -N 1 [Results] in /tmp/cbt/mnt/cbt-cephfs-kernel/0/io500/io500-app/results/2020.07.13-17.30.55-scr/mdtest_hard_stat.txt. [RESULT] IOPS phase 5 mdtest_hard_stat 101.852 kiops : time 51.00 seconds Synchronizing and cleaning the cache total used free shared buff/cache available Mem: 385336 5213 375588 3771 4533 374351 Swap: 4095 20 4075 total used free shared buff/cache available Mem: 385336 5173 375661 3770 4500 374408 Swap: 4095 20 4075 [Starting] mdtest_easy_delete [Exec] mpirun -npernode 112 --hostfile /home/nhm/io500-app/hosts /tmp/cbt/mnt/cbt-cephfs-kernel/0/io500/io500-app/bin/mdtest -r -F -P -d /tmp/cbt/mnt/cbt-cephfs-kernel/0/io500/io500-app/datafiles/2020.07.13-17.30.55-scr/mdt_easy -n 400000 -u -L -a CEPHFS --cephfs.user=admin --cephfs.conf=/etc/ceph/ceph.conf --cephfs.prefix=/tmp/cbt/mnt/cbt-cephfs-kernel/0 -x /tmp/cbt/mnt/cbt-cephfs-kernel/0/io500/io500-app/datafiles/2020.07.13-17.30.55-scr/mdt_easy-stonewall -N 1 [Results] in /tmp/cbt/mnt/cbt-cephfs-kernel/0/io500/io500-app/results/2020.07.13-17.30.55-scr/mdtest_easy_delete.txt. [RESULT] IOPS phase 6 mdtest_easy_delete 100.239 kiops : time 446.95 seconds Synchronizing and cleaning the cache total used free shared buff/cache available Mem: 385336 5238 375498 3770 4598 374294 Swap: 4095 20 4075 total used free shared buff/cache available Mem: 385336 5218 375613 3770 4504 374361 Swap: 4095 20 4075 [Starting] mdtest_hard_read [Exec] mpirun -npernode 112 --hostfile /home/nhm/io500-app/hosts /tmp/cbt/mnt/cbt-cephfs-kernel/0/io500/io500-app/bin/mdtest -X -E -t -F -P -w 3901 -e 3901 -d /tmp/cbt/mnt/cbt-cephfs-kernel/0/io500/io500-app/datafiles/2020.07.13-17.30.55-scr/mdt_hard -n 100000 -x /tmp/cbt/mnt/cbt-cephfs-kernel/0/io500/io500-app/datafiles/2020.07.13-17.30.55-scr/mdt_hard-stonewall -a CEPHFS --cephfs.user=admin --cephfs.conf=/etc/ceph/ceph.conf --cephfs.prefix=/tmp/cbt/mnt/cbt-cephfs-kernel/0 -N 1 [Results] in /tmp/cbt/mnt/cbt-cephfs-kernel/0/io500/io500-app/results/2020.07.13-17.30.55-scr/mdtest_hard_read.txt. [RESULT] IOPS phase 7 mdtest_hard_read 92.434 kiops : time 56.19 seconds Synchronizing and cleaning the cache total used free shared buff/cache available Mem: 385336 5243 375549 3770 4543 374317 Swap: 4095 20 4075 total used free shared buff/cache available Mem: 385336 5224 375609 3770 4502 374356 Swap: 4095 20 4075 [Starting] mdtest_hard_delete [Exec] mpirun -npernode 112 --hostfile /home/nhm/io500-app/hosts /tmp/cbt/mnt/cbt-cephfs-kernel/0/io500/io500-app/bin/mdtest -r -t -F -P -w 3901 -e 3901 -d /tmp/cbt/mnt/cbt-cephfs-kernel/0/io500/io500-app/datafiles/2020.07.13-17.30.55-scr/mdt_hard -n 100000 -x /tmp/cbt/mnt/cbt-cephfs-kernel/0/io500/io500-app/datafiles/2020.07.13-17.30.55-scr/mdt_hard-stonewall -a CEPHFS --cephfs.user=admin --cephfs.conf=/etc/ceph/ceph.conf --cephfs.prefix=/tmp/cbt/mnt/cbt-cephfs-kernel/0 -N 1 [Results] in /tmp/cbt/mnt/cbt-cephfs-kernel/0/io500/io500-app/results/2020.07.13-17.30.55-scr/mdtest_hard_delete.txt. [RESULT] IOPS phase 8 mdtest_hard_delete 16.422 kiops : time 348.49 seconds [Skipping] mdreal [Leaving] datafiles in /tmp/cbt/mnt/cbt-cephfs-kernel/0/io500/io500-app/datafiles/2020.07.13-17.30.55-scr [Summary] Results files in /tmp/cbt/mnt/cbt-cephfs-kernel/0/io500/io500-app/results/2020.07.13-17.30.55-scr [Summary] Data files in /tmp/cbt/mnt/cbt-cephfs-kernel/0/io500/io500-app/datafiles/2020.07.13-17.30.55-scr [RESULT] BW phase 1 ior_easy_write 10.253 GiB/s : time 602.95 seconds [RESULT] BW phase 2 ior_hard_write 7.630 GiB/s : time 300.37 seconds [RESULT] BW phase 3 ior_easy_read 10.228 GiB/s : time 604.36 seconds [RESULT] BW phase 4 ior_hard_read 7.512 GiB/s : time 305.14 seconds [RESULT] IOPS phase 1 mdtest_easy_write 123.683 kiops : time 362.23 seconds [RESULT] IOPS phase 2 mdtest_hard_write 16.188 kiops : time 320.83 seconds [RESULT] IOPS phase 3 find 255.650 kiops : time 195.55 seconds [RESULT] IOPS phase 4 mdtest_easy_stat 359.294 kiops : time 124.71 seconds [RESULT] IOPS phase 5 mdtest_hard_stat 101.852 kiops : time 51.00 seconds [RESULT] IOPS phase 6 mdtest_easy_delete 100.239 kiops : time 446.95 seconds [RESULT] IOPS phase 7 mdtest_hard_read 92.434 kiops : time 56.19 seconds [RESULT] IOPS phase 8 mdtest_hard_delete 16.422 kiops : time 348.49 seconds [SCORE] Bandwidth 8.80499 GiB/s : IOPS 85.4789 kiops : TOTAL 27.4343