== sanity test 127e: client IO latency histograms by size ============================================ 10:04:12 (1762769052)
CMD: onyx-147vm1 /usr/sbin/lctl set_param osc.lustre-OST0000-*.io_latency_stats=clear
osc.lustre-OST0000-osc-ffff98ac825e0800.io_latency_stats=clear
Generating I/O with size 4K
10+0 records in
10+0 records out
40960 bytes (41 kB, 40 KiB) copied, 2.10334 s, 19.5 kB/s
10+0 records in
10+0 records out
40960 bytes (41 kB, 40 KiB) copied, 0.00698501 s, 5.9 MB/s
Generating I/O with size 64K
10+0 records in
10+0 records out
655360 bytes (655 kB, 640 KiB) copied, 0.264878 s, 2.5 MB/s
10+0 records in
10+0 records out
655360 bytes (655 kB, 640 KiB) copied, 0.0152253 s, 43.0 MB/s
Generating I/O with size 1024K
10+0 records in
10+0 records out
10485760 bytes (10 MB, 10 MiB) copied, 0.549039 s, 19.1 MB/s
10+0 records in
10+0 records out
10485760 bytes (10 MB, 10 MiB) copied, 0.116865 s, 89.7 MB/s
CMD: onyx-147vm1 /usr/sbin/lctl get_param osc.lustre-OST0000-*.io_latency_stats
osc.lustre-OST0000-*.io_latency_stats stats after I/O:
osc.lustre-OST0000-osc-ffff98ac825e0800.io_latency_stats=
io_latency_by_size:
snapshot_time: 1762769055.802982511
start_time: 1762769052.657515069
elapsed_time: 3.145467442
rd_4K: { 256us: 10, }
rd_64K: { 256us: 6, 512us: 4, }
rd_1024K: { 512us: 8, 1024us: 2, }
wr_4K: { 16384us: 9, 1048576us: 1, }
wr_64K: { 16384us: 9, 32768us: 1, }
wr_512K: { 1024us: 1, }
wr_1024K: { 32768us: 10, }
sanity test_127e: @@@@@@ FAIL: Expected 10 entries, found 1
Trace dump:
= /usr/lib64/lustre/tests/test-framework.sh:6884:error()
= /usr/lib64/lustre/tests/sanity.sh:14618:test_127_io_latency_test()
= /usr/lib64/lustre/tests/sanity.sh:14630:test_127e()
= /usr/lib64/lustre/tests/test-framework.sh:7241:run_one()
= /usr/lib64/lustre/tests/test-framework.sh:7302:run_one_logged()
= /usr/lib64/lustre/tests/test-framework.sh:7125:run_test()
= /usr/lib64/lustre/tests/sanity.sh:14632:main()
Bug
Medium