[2024-12-06 23:00:52.330624] Starting SPDK v25.01-pre git sha1 a2f5e1c2d / DPDK 24.03.0 initialization... [2024-12-06 23:00:52.330887] [ DPDK EAL parameters: rocksdb --no-shconf -c 0x1 --huge-unlink --no-telemetry --log-level=lib.eal:6 --log-level=lib.cryptodev:5 --log-level=lib.power:5 --log-level=user1:6 --base-virtaddr=0x200000000000 --match-allocations --file-prefix=spdk_pid187521 ] Set seed to 1733522452323817 because --seed was 0 EAL: No free 2048 kB hugepages reported on node 1 [2024-12-06 23:00:52.466420] app.c: 919:spdk_app_start: *NOTICE*: Total cores available: 1 [2024-12-06 23:00:52.573166] app.c: 612:app_setup_trace: *NOTICE*: Tracepoint Group Mask 0x80 specified. [2024-12-06 23:00:52.573205] app.c: 613:app_setup_trace: *NOTICE*: Use 'spdk_trace -s rocksdb -p 187521' to capture a snapshot of events at runtime. [2024-12-06 23:00:52.573216] app.c: 618:app_setup_trace: *NOTICE*: 'spdk_trace' without parameters will also work if this is the only [2024-12-06 23:00:52.573227] app.c: 619:app_setup_trace: *NOTICE*: SPDK application currently running. [2024-12-06 23:00:52.573234] app.c: 620:app_setup_trace: *NOTICE*: Or copy /dev/shm/rocksdb_trace.pid187521 for offline analysis/debug. [2024-12-06 23:00:52.574788] reactor.c:1005:reactor_run: *NOTICE*: Reactor started on core 0 RocksDB: version 8.1.1 Date: Fri Dec 6 23:00:58 2024 CPU: 96 * Intel(R) Xeon(R) Gold 6252 CPU @ 2.10GHz CPUCache: 36608 KB 2024/12/06-23:01:03 ... thread 0: (1000000,1000000) ops and (205914.2,205914.2) ops/second in (4.856391,4.856391) seconds ** Compaction Stats [default] ** Level Files Size Score Read(GB) Rn(GB) Rnp1(GB) Write(GB) Wnew(GB) Moved(GB) W-Amp Rd(MB/s) Wr(MB/s) Comp(sec) CompMergeCPU(sec) Comp(cnt) Avg(sec) KeyIn KeyDrop Rblob(GB) Wblob(GB) ------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------ L0 18/13 3.06 GB 87.7 0.7 0.0 0.7 1.6 0.9 0.0 1.9 129.4 279.8 5.83 5.22 17 0.343 766K 3674 0.0 0.0 L1 3/3 168.58 MB 0.0 0.0 0.0 0.0 0.0 0.0 0.0 0.0 0.0 0.0 0.00 0.00 0 0.000 0 0 0.0 0.0 L3 18/0 936.11 MB 0.9 0.2 0.1 0.1 0.2 0.1 0.0 1.9 167.4 164.9 1.49 1.23 1 1.488 130K 0 0.0 0.0 L4 129/0 9.74 GB 1.0 1.0 0.5 0.4 1.0 0.5 0.1 1.8 177.6 174.9 5.67 4.59 5 1.135 522K 3807 0.0 0.0 L5 132/0 10.83 GB 0.1 0.0 0.0 0.0 0.0 0.0 0.7 0.0 0.0 0.0 0.00 0.00 0 0.000 0 0 0.0 0.0 Sum 300/16 24.70 GB 0.0 2.0 0.7 1.3 2.8 1.5 0.8 3.3 154.8 220.9 13.00 11.05 23 0.565 1420K 7481 0.0 0.0 Int 0/0 0.00 KB 0.0 2.0 0.7 1.3 2.8 1.5 0.8 3.3 154.8 220.9 13.00 11.05 23 0.565 1420K 7481 0.0 0.0 ** Compaction Stats [default] ** Priority Files Size Score Read(GB) Rn(GB) Rnp1(GB) Write(GB) Wnew(GB) Moved(GB) W-Amp Rd(MB/s) Wr(MB/s) Comp(sec) CompMergeCPU(sec) Comp(cnt) Avg(sec) KeyIn KeyDrop Rblob(GB) Wblob(GB) --------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------- Low 0/0 0.00 KB 0.0 2.0 0.7 1.3 1.9 0.7 0.0 0.0 200.9 198.6 10.01 8.62 9 1.113 1420K 7481 0.0 0.0 High 0/0 0.00 KB 0.0 0.0 0.0 0.0 0.9 0.9 0.0 0.0 0.0 295.4 2.98 2.43 14 0.213 0 0 0.0 0.0 Blob file count: 0, total size: 0.0 GB, garbage size: 0.0 GB, space amp: 0.0 Uptime(secs): 5.0 total, 4.9 interval Flush(GB): cumulative 0.860, interval 0.860 AddFile(GB): cumulative 0.000, interval 0.000 AddFile(Total Files): cumulative 0, interval 0 AddFile(L0 Files): cumulative 0, interval 0 AddFile(Keys): cumulative 0, interval 0 Cumulative compaction: 2.80 GB write, 578.12 MB/s write, 1.96 GB read, 405.21 MB/s read, 13.0 seconds Interval compaction: 2.80 GB write, 590.63 MB/s write, 1.96 GB read, 413.98 MB/s read, 13.0 seconds Write Stall (count): cf-l0-file-count-limit-delays-with-ongoing-compaction: 6, cf-l0-file-count-limit-stops-with-ongoing-compaction: 0, l0-file-count-limit-delays: 6, l0-file-count-limit-stops: 0, memtable-limit-delays: 0, memtable-limit-stops: 0, pending-compaction-bytes-delays: 0, pending-compaction-bytes-stops: 0, total-delays: 6, total-stops: 0, interval: 6 total count ** File Read Latency Histogram By Level [default] ** ** Level 0 read latency histogram (micros): Count: 224275 Average: 36.2923 StdDev: 147.70 Min: 1 Median: 1.6097 Max: 16677 Percentiles: P50: 1.61 P75: 2.36 P99: 464.14 P99.9: 1117.82 P99.99: 1686.64 ------------------------------------------------------ [ 0, 1 ] 37510 16.725% 16.725% ### ( 1, 2 ] 122403 54.577% 71.302% ########### ( 2, 3 ] 22908 10.214% 81.516% ## ( 3, 4 ] 6076 2.709% 84.226% # ( 4, 6 ] 5154 2.298% 86.524% ( 6, 10 ] 974 0.434% 86.958% ( 10, 15 ] 204 0.091% 87.049% ( 15, 22 ] 30 0.013% 87.062% ( 22, 34 ] 4 0.002% 87.064% ( 34, 51 ] 1 0.000% 87.065% ( 51, 76 ] 84 0.037% 87.102% ( 76, 110 ] 758 0.338% 87.440% ( 110, 170 ] 7125 3.177% 90.617% # ( 170, 250 ] 11988 5.345% 95.962% # ( 250, 380 ] 5926 2.642% 98.604% # ( 380, 580 ] 2109 0.940% 99.545% ( 580, 870 ] 644 0.287% 99.832% ( 870, 1300 ] 265 0.118% 99.950% ( 1300, 1900 ] 139 0.062% 100.012% ( 1900, 2900 ] 74 0.033% 100.045% ( 2900, 4400 ] 32 0.014% 100.059% ( 4400, 6600 ] 13 0.006% 100.065% ( 6600, 9900 ] 4 0.002% 100.067% ( 9900, 14000 ] 3 0.001% 100.068% ( 14000, 22000 ] 3 0.001% 100.070% ** Level 1 read latency histogram (micros): Count: 402 Average: 406.1194 StdDev: 1185.05 Min: 97 Median: 212.3256 Max: 16051 Percentiles: P50: 212.33 P75: 289.61 P99: 4094.00 P99.9: 16051.00 P99.99: 16051.00 ------------------------------------------------------ ( 76, 110 ] 5 1.244% 1.244% ( 110, 170 ] 105 26.119% 27.363% ##### ( 170, 250 ] 172 42.786% 70.149% ######### ( 250, 380 ] 64 15.920% 86.070% ### ( 380, 580 ] 32 7.960% 94.030% ## ( 580, 870 ] 6 1.493% 95.522% ( 870, 1300 ] 3 0.746% 96.269% ( 1300, 1900 ] 3 0.746% 97.015% ( 1900, 2900 ] 4 0.995% 98.010% ( 2900, 4400 ] 5 1.244% 99.254% ( 4400, 6600 ] 1 0.249% 99.502% ( 14000, 22000 ] 2 0.498% 100.000% ** Level 3 read latency histogram (micros): Count: 84135 Average: 13.4943 StdDev: 130.30 Min: 1 Median: 1.7672 Max: 20147 Percentiles: P50: 1.77 P75: 2.58 P99: 295.14 P99.9: 1233.42 P99.99: 2549.44 ------------------------------------------------------ [ 0, 1 ] 4729 5.621% 5.621% # ( 1, 2 ] 48666 57.843% 63.463% ############ ( 2, 3 ] 16625 19.760% 83.223% #### ( 3, 4 ] 4738 5.631% 88.855% # ( 4, 6 ] 4376 5.201% 94.056% # ( 6, 10 ] 1850 2.199% 96.255% ( 10, 15 ] 360 0.428% 96.683% ( 15, 22 ] 61 0.073% 96.755% ( 22, 34 ] 8 0.010% 96.765% ( 34, 51 ] 2 0.002% 96.767% ( 76, 110 ] 42 0.050% 96.817% ( 110, 170 ] 584 0.694% 97.511% ( 170, 250 ] 1053 1.252% 98.763% ( 250, 380 ] 575 0.683% 99.446% ( 380, 580 ] 236 0.281% 99.727% ( 580, 870 ] 96 0.114% 99.841% ( 870, 1300 ] 59 0.070% 99.911% ( 1300, 1900 ] 51 0.061% 99.971% ( 1900, 2900 ] 24 0.029% 100.000% ( 2900, 4400 ] 5 0.006% 100.006% ( 4400, 6600 ] 2 0.002% 100.008% ( 14000, 22000 ] 2 0.002% 100.011% ** Level 4 read latency histogram (micros): Count: 119849 Average: 53.4586 StdDev: 169.84 Min: 1 Median: 1.9541 Max: 12724 Percentiles: P50: 1.95 P75: 4.00 P99: 564.18 P99.9: 1828.26 P99.99: 2577.04 ------------------------------------------------------ [ 0, 1 ] 5177 4.320% 4.320% # ( 1, 2 ] 57383 47.879% 52.199% ########## ( 2, 3 ] 20788 17.345% 69.544% ### ( 3, 4 ] 6571 5.483% 75.027% # ( 4, 6 ] 5761 4.807% 79.834% # ( 6, 10 ] 1856 1.549% 81.382% ( 10, 15 ] 430 0.359% 81.741% ( 15, 22 ] 56 0.047% 81.788% ( 22, 34 ] 7 0.006% 81.794% ( 34, 51 ] 6 0.005% 81.799% ( 51, 76 ] 3 0.003% 81.801% ( 76, 110 ] 246 0.205% 82.007% ( 110, 170 ] 5569 4.647% 86.653% # ( 170, 250 ] 8953 7.470% 94.123% # ( 250, 380 ] 4360 3.638% 97.761% # ( 380, 580 ] 1612 1.345% 99.106% ( 580, 870 ] 523 0.436% 99.543% ( 870, 1300 ] 282 0.235% 99.778% ( 1300, 1900 ] 166 0.139% 99.917% ( 1900, 2900 ] 130 0.108% 100.025% ( 2900, 4400 ] 46 0.038% 100.063% ( 4400, 6600 ] 6 0.005% 100.068% ( 9900, 14000 ] 1 0.001% 100.069% ** Level 5 read latency histogram (micros): Count: 22285 Average: 278.7926 StdDev: 320.95 Min: 63 Median: 214.7333 Max: 13329 Percentiles: P50: 214.73 P75: 293.80 P99: 1825.09 P99.9: 4050.39 P99.99: 7872.97 ------------------------------------------------------ ( 51, 76 ] 3 0.013% 0.013% ( 76, 110 ] 292 1.310% 1.324% ( 110, 170 ] 5659 25.394% 26.718% ##### ( 170, 250 ] 9279 41.638% 68.355% ######## ( 250, 380 ] 4395 19.722% 88.077% #### ( 380, 580 ] 1592 7.144% 95.221% # ( 580, 870 ] 485 2.176% 97.397% ( 870, 1300 ] 211 0.947% 98.344% ( 1300, 1900 ] 167 0.749% 99.094% ( 1900, 2900 ] 136 0.610% 99.704% ( 2900, 4400 ] 57 0.256% 99.960% ( 4400, 6600 ] 6 0.027% 99.987% ( 6600, 9900 ] 2 0.009% 99.996% ( 9900, 14000 ] 1 0.004% 100.000% ** DB Stats ** Uptime(secs): 5.0 total, 4.9 interval Cumulative writes: 0 writes, 1000K keys, 0 commit groups, 0.0 writes per commit group, ingest: 0.96 GB, 198.24 MB/s Cumulative WAL: 0 writes, 0 syncs, 0.00 writes per sync, written: 0.00 GB, 0.00 MB/s Cumulative stall: 00:00:1.201 H:M:S, 24.2 percent Interval writes: 0 writes, 1000K keys, 0 commit groups, 0.0 writes per commit group, ingest: 984.19 MB, 202.49 MB/s Interval WAL: 0 writes, 0 syncs, 0.00 writes per sync, written: 0.00 GB, 0.00 MB/s Interval stall: 00:00:1.201 H:M:S, 24.7 percent Write Stall (count): write-buffer-manager-limit-stops: 0, num-running-compactions: 1 num-running-flushes: 1 2024/12/06-23:01:12 ... thread 0: (1000000,2000000) ops and (101886.7,136321.3) ops/second in (9.814827,14.671218) seconds ** Compaction Stats [default] ** Level Files Size Score Read(GB) Rn(GB) Rnp1(GB) Write(GB) Wnew(GB) Moved(GB) W-Amp Rd(MB/s) Wr(MB/s) Comp(sec) CompMergeCPU(sec) Comp(cnt) Avg(sec) KeyIn KeyDrop Rblob(GB) Wblob(GB) ------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------ L0 18/17 4.02 GB 104.2 2.8 0.0 2.8 4.6 1.8 0.0 2.5 168.0 278.3 16.82 15.64 38 0.443 2870K 31K 0.0 0.0 L1 3/3 168.58 MB 0.0 0.0 0.0 0.0 0.0 0.0 0.0 0.0 0.0 0.0 0.00 0.00 0 0.000 0 0 0.0 0.0 L3 18/0 936.11 MB 0.9 0.2 0.1 0.1 0.2 0.1 0.0 1.9 167.4 164.9 1.49 1.23 1 1.488 130K 0 0.0 0.0 L4 129/0 9.74 GB 1.0 1.0 0.5 0.4 1.0 0.5 0.1 1.8 177.6 174.9 5.67 4.59 5 1.135 522K 3807 0.0 0.0 L5 132/0 10.83 GB 0.1 0.0 0.0 0.0 0.0 0.0 0.7 0.0 0.0 0.0 0.00 0.00 0 0.000 0 0 0.0 0.0 Sum 300/20 25.66 GB 0.0 4.0 0.7 3.3 5.8 2.5 0.8 3.1 170.2 246.8 23.99 21.46 44 0.545 3523K 35K 0.0 0.0 Int 0/0 0.00 KB 0.0 4.0 0.7 3.3 5.8 2.5 0.8 3.1 170.2 246.8 23.99 21.46 44 0.545 3523K 35K 0.0 0.0 ** Compaction Stats [default] ** Priority Files Size Score Read(GB) Rn(GB) Rnp1(GB) Write(GB) Wnew(GB) Moved(GB) W-Amp Rd(MB/s) Wr(MB/s) Comp(sec) CompMergeCPU(sec) Comp(cnt) Avg(sec) KeyIn KeyDrop Rblob(GB) Wblob(GB) --------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------- Low 0/0 0.00 KB 0.0 4.0 0.7 3.3 3.9 0.6 0.0 0.0 230.5 227.7 17.71 16.21 14 1.265 3523K 35K 0.0 0.0 High 0/0 0.00 KB 0.0 0.0 0.0 0.0 1.8 1.8 0.0 0.0 0.0 300.8 6.28 5.25 30 0.209 0 0 0.0 0.0 Blob file count: 0, total size: 0.0 GB, garbage size: 0.0 GB, space amp: 0.0 Uptime(secs): 14.8 total, 14.7 interval Flush(GB): cumulative 1.843, interval 1.843 AddFile(GB): cumulative 0.000, interval 0.000 AddFile(Total Files): cumulative 0, interval 0 AddFile(L0 Files): cumulative 0, interval 0 AddFile(Keys): cumulative 0, interval 0 Cumulative compaction: 5.78 GB write, 400.59 MB/s write, 3.99 GB read, 276.25 MB/s read, 24.0 seconds Interval compaction: 5.78 GB write, 403.46 MB/s write, 3.99 GB read, 278.23 MB/s read, 24.0 seconds Write Stall (count): cf-l0-file-count-limit-delays-with-ongoing-compaction: 19, cf-l0-file-count-limit-stops-with-ongoing-compaction: 0, l0-file-count-limit-delays: 19, l0-file-count-limit-stops: 0, memtable-limit-delays: 0, memtable-limit-stops: 0, pending-compaction-bytes-delays: 0, pending-compaction-bytes-stops: 0, total-delays: 19, total-stops: 0, interval: 19 total count ** File Read Latency Histogram By Level [default] ** ** Level 0 read latency histogram (micros): Count: 857695 Average: 29.5118 StdDev: 97.58 Min: 0 Median: 1.5408 Max: 16677 Percentiles: P50: 1.54 P75: 2.05 P99: 351.25 P99.9: 571.53 P99.99: 804.19 ------------------------------------------------------ [ 0, 1 ] 180633 21.060% 21.060% #### ( 1, 2 ] 458936 53.508% 74.568% ########### ( 2, 3 ] 69646 8.120% 82.688% ## ( 3, 4 ] 19102 2.227% 84.916% ( 4, 6 ] 16688 1.946% 86.861% ( 6, 10 ] 3313 0.386% 87.248% ( 10, 15 ] 730 0.085% 87.333% ( 15, 22 ] 116 0.014% 87.346% ( 22, 34 ] 9 0.001% 87.347% ( 34, 51 ] 2 0.000% 87.347% ( 51, 76 ] 288 0.034% 87.381% ( 76, 110 ] 3863 0.450% 87.831% ( 110, 170 ] 33068 3.855% 91.687% # ( 170, 250 ] 49047 5.718% 97.405% # ( 250, 380 ] 17560 2.047% 99.453% ( 380, 580 ] 4006 0.467% 99.920% ( 580, 870 ] 779 0.091% 100.011% ( 870, 1300 ] 278 0.032% 100.043% ( 1300, 1900 ] 147 0.017% 100.060% ( 1900, 2900 ] 87 0.010% 100.070% ( 2900, 4400 ] 34 0.004% 100.074% ( 4400, 6600 ] 13 0.002% 100.076% ( 6600, 9900 ] 4 0.000% 100.076% ( 9900, 14000 ] 3 0.000% 100.077% ( 14000, 22000 ] 3 0.000% 100.077% ** Level 1 read latency histogram (micros): Count: 1504 Average: 255.7520 StdDev: 622.07 Min: 96 Median: 196.9412 Max: 16051 Percentiles: P50: 196.94 P75: 241.18 P99: 1294.27 P99.9: 15984.00 P99.99: 16051.00 ------------------------------------------------------ ( 76, 110 ] 27 1.795% 1.795% ( 110, 170 ] 496 32.979% 34.774% ####### ( 170, 250 ] 680 45.213% 79.987% ######### ( 250, 380 ] 217 14.428% 94.415% ### ( 380, 580 ] 58 3.856% 98.271% # ( 580, 870 ] 8 0.532% 98.803% ( 870, 1300 ] 3 0.199% 99.003% ( 1300, 1900 ] 3 0.199% 99.202% ( 1900, 2900 ] 4 0.266% 99.468% ( 2900, 4400 ] 5 0.332% 99.801% ( 4400, 6600 ] 1 0.066% 99.867% ( 14000, 22000 ] 2 0.133% 100.000% ** Level 3 read latency histogram (micros): Count: 91202 Average: 27.6666 StdDev: 136.14 Min: 1 Median: 1.8398 Max: 20147 Percentiles: P50: 1.84 P75: 2.90 P99: 357.54 P99.9: 1189.21 P99.99: 2519.99 ------------------------------------------------------ [ 0, 1 ] 4729 5.185% 5.185% # ( 1, 2 ] 48666 53.361% 58.546% ########### ( 2, 3 ] 16625 18.229% 76.775% #### ( 3, 4 ] 4831 5.297% 82.072% # ( 4, 6 ] 4543 4.981% 87.053% # ( 6, 10 ] 1866 2.046% 89.099% ( 10, 15 ] 367 0.402% 89.501% ( 15, 22 ] 61 0.067% 89.568% ( 22, 34 ] 8 0.009% 89.577% ( 34, 51 ] 2 0.002% 89.579% ( 76, 110 ] 139 0.152% 89.732% ( 110, 170 ] 2841 3.115% 92.847% # ( 170, 250 ] 4300 4.715% 97.561% # ( 250, 380 ] 1586 1.739% 99.300% ( 380, 580 ] 393 0.431% 99.731% ( 580, 870 ] 110 0.121% 99.852% ( 870, 1300 ] 59 0.065% 99.917% ( 1300, 1900 ] 52 0.057% 99.974% ( 1900, 2900 ] 24 0.026% 100.000% ( 2900, 4400 ] 5 0.005% 100.005% ( 4400, 6600 ] 2 0.002% 100.008% ( 14000, 22000 ] 2 0.002% 100.010% ** Level 4 read latency histogram (micros): Count: 178239 Average: 101.0488 StdDev: 161.90 Min: 1 Median: 3.8271 Max: 12724 Percentiles: P50: 3.83 P75: 188.15 P99: 538.19 P99.9: 1663.96 P99.99: 2565.52 ------------------------------------------------------ [ 0, 1 ] 5177 2.905% 2.905% # ( 1, 2 ] 57386 32.196% 35.101% ###### ( 2, 3 ] 20808 11.674% 46.775% ## ( 3, 4 ] 6950 3.899% 50.674% # ( 4, 6 ] 6553 3.677% 54.351% # ( 6, 10 ] 1934 1.085% 55.436% ( 10, 15 ] 452 0.254% 55.689% ( 15, 22 ] 59 0.033% 55.722% ( 22, 34 ] 8 0.004% 55.727% ( 34, 51 ] 6 0.003% 55.730% ( 51, 76 ] 3 0.002% 55.732% ( 76, 110 ] 1179 0.661% 56.393% ( 110, 170 ] 24992 14.022% 70.415% ### ( 170, 250 ] 36029 20.214% 90.629% #### ( 250, 380 ] 12586 7.061% 97.690% # ( 380, 580 ] 2953 1.657% 99.347% ( 580, 870 ] 590 0.331% 99.678% ( 870, 1300 ] 290 0.163% 99.841% ( 1300, 1900 ] 176 0.099% 99.939% ( 1900, 2900 ] 137 0.077% 100.016% ( 2900, 4400 ] 50 0.028% 100.044% ( 4400, 6600 ] 6 0.003% 100.048% ( 9900, 14000 ] 1 0.001% 100.048% ** Level 5 read latency histogram (micros): Count: 77979 Average: 224.7863 StdDev: 186.25 Min: 63 Median: 199.5550 Max: 13329 Percentiles: P50: 199.56 P75: 243.26 P99: 777.41 P99.9: 2829.73 P99.99: 4474.10 ------------------------------------------------------ ( 51, 76 ] 3 0.004% 0.004% ( 76, 110 ] 1216 1.559% 1.563% ( 110, 170 ] 24586 31.529% 33.092% ###### ( 170, 250 ] 35688 45.766% 78.858% ######### ( 250, 380 ] 12384 15.881% 94.740% ### ( 380, 580 ] 2941 3.772% 98.511% # ( 580, 870 ] 560 0.718% 99.229% ( 870, 1300 ] 220 0.282% 99.511% ( 1300, 1900 ] 171 0.219% 99.731% ( 1900, 2900 ] 142 0.182% 99.913% ( 2900, 4400 ] 60 0.077% 99.990% ( 4400, 6600 ] 6 0.008% 99.997% ( 6600, 9900 ] 2 0.003% 100.000% ( 9900, 14000 ] 1 0.001% 100.001% ** DB Stats ** Uptime(secs): 14.8 total, 9.8 interval Cumulative writes: 0 writes, 2000K keys, 0 commit groups, 0.0 writes per commit group, ingest: 1.92 GB, 133.18 MB/s Cumulative WAL: 0 writes, 0 syncs, 0.00 writes per sync, written: 0.00 GB, 0.00 MB/s Cumulative stall: 00:00:7.220 H:M:S, 48.9 percent Interval writes: 0 writes, 1000K keys, 0 commit groups, 0.0 writes per commit group, ingest: 984.19 MB, 100.28 MB/s Interval WAL: 0 writes, 0 syncs, 0.00 writes per sync, written: 0.00 GB, 0.00 MB/s Interval stall: 00:00:6.018 H:M:S, 61.3 percent Write Stall (count): write-buffer-manager-limit-stops: 0, num-running-compactions: 2 num-running-flushes: 1 using bdev Nvme0n1 Initializing RocksDB Options from the specified file Initializing RocksDB Options from command-line flags Integrated BlobDB: blob cache disabled Keys: 16 bytes each (+ 0 bytes user-defined timestamp) Values: 1000 bytes each (1000 bytes after compression) Entries: 20000000 Prefix: 0 bytes Keys per prefix: 0 RawSize: 19378.7 MB (estimated) FileSize: 19378.7 MB (estimated) Write rate: 0 bytes/second Read rate: 0 ops/second Compression: NoCompression Compression sampling rate: 0 Memtablerep: SkipListFactory Perf Level: 1 ------------------------------------------------ DB path: [/mnt/rocksdb] readwhilewriting : 285.276 micros/op 13948 ops/sec 20.217 seconds 281996 operations; 13.5 MB/s (70999 of 70999 found) Microseconds per read: Count: 281996 Average: 285.2863 StdDev: 437.72 Min: 2 Median: 240.1493 Max: 32651 Percentiles: P50: 240.15 P75: 338.38 P99: 840.89 P99.9: 6105.22 P99.99: 20556.93 ------------------------------------------------------ ( 1, 2 ] 1 0.000% 0.000% ( 2, 3 ] 2 0.001% 0.001% ( 3, 4 ] 13 0.005% 0.006% ( 4, 6 ] 64 0.023% 0.028% ( 6, 10 ] 360 0.128% 0.156% ( 10, 15 ] 294 0.104% 0.260% ( 15, 22 ] 429 0.152% 0.412% ( 22, 34 ] 8297 2.942% 3.355% # ( 34, 51 ] 3884 1.377% 4.732% ( 51, 76 ] 469 0.166% 4.898% ( 76, 110 ] 48 0.017% 4.915% ( 110, 170 ] 17697 6.276% 11.191% # ( 170, 250 ] 124808 44.259% 55.450% ######### ( 250, 380 ] 81089 28.755% 84.205% ###### ( 380, 580 ] 34381 12.192% 96.397% ## ( 580, 870 ] 8159 2.893% 99.290% # ( 870, 1300 ] 1291 0.458% 99.748% ( 1300, 1900 ] 216 0.077% 99.825% ( 1900, 2900 ] 112 0.040% 99.865% ( 2900, 4400 ] 69 0.024% 99.889% ( 4400, 6600 ] 40 0.014% 99.903% ( 6600, 9900 ] 117 0.041% 99.945% ( 9900, 14000 ] 86 0.030% 99.975% ( 14000, 22000 ] 51 0.018% 99.993% ( 22000, 33000 ] 19 0.007% 100.000% STATISTICS: rocksdb.block.cache.miss COUNT : 0 rocksdb.block.cache.hit COUNT : 0 rocksdb.block.cache.add COUNT : 0 rocksdb.block.cache.add.failures COUNT : 0 rocksdb.block.cache.index.miss COUNT : 0 rocksdb.block.cache.index.hit COUNT : 0 rocksdb.block.cache.index.add COUNT : 0 rocksdb.block.cache.index.bytes.insert COUNT : 0 rocksdb.block.cache.filter.miss COUNT : 0 rocksdb.block.cache.filter.hit COUNT : 0 rocksdb.block.cache.filter.add COUNT : 0 rocksdb.block.cache.filter.bytes.insert COUNT : 0 rocksdb.block.cache.data.miss COUNT : 0 rocksdb.block.cache.data.hit COUNT : 0 rocksdb.block.cache.data.add COUNT : 0 rocksdb.block.cache.data.bytes.insert COUNT : 0 rocksdb.block.cache.bytes.read COUNT : 0 rocksdb.block.cache.bytes.write COUNT : 0 rocksdb.bloom.filter.useful COUNT : 5220408 rocksdb.bloom.filter.full.positive COUNT : 332158 rocksdb.bloom.filter.full.true.positive COUNT : 281323 rocksdb.persistent.cache.hit COUNT : 0 rocksdb.persistent.cache.miss COUNT : 0 rocksdb.sim.block.cache.hit COUNT : 0 rocksdb.sim.block.cache.miss COUNT : 0 rocksdb.memtable.hit COUNT : 673 rocksdb.memtable.miss COUNT : 281323 rocksdb.l0.hit COUNT : 48077 rocksdb.l1.hit COUNT : 1931 rocksdb.l2andup.hit COUNT : 231315 rocksdb.compaction.key.drop.new COUNT : 80577 rocksdb.compaction.key.drop.obsolete COUNT : 0 rocksdb.compaction.key.drop.range_del COUNT : 0 rocksdb.compaction.key.drop.user COUNT : 0 rocksdb.compaction.range_del.drop.obsolete COUNT : 0 rocksdb.compaction.optimized.del.drop.obsolete COUNT : 0 rocksdb.compaction.cancelled COUNT : 0 rocksdb.number.keys.written COUNT : 2493493 rocksdb.number.keys.read COUNT : 281996 rocksdb.number.keys.updated COUNT : 0 rocksdb.bytes.written COUNT : 2573284776 rocksdb.bytes.read COUNT : 281996000 rocksdb.number.db.seek COUNT : 0 rocksdb.number.db.next COUNT : 0 rocksdb.number.db.prev COUNT : 0 rocksdb.number.db.seek.found COUNT : 0 rocksdb.number.db.next.found COUNT : 0 rocksdb.number.db.prev.found COUNT : 0 rocksdb.db.iter.bytes.read COUNT : 0 rocksdb.no.file.opens COUNT : 361 rocksdb.no.file.errors COUNT : 0 rocksdb.stall.micros COUNT : 10869649 rocksdb.db.mutex.wait.micros COUNT : 0 rocksdb.number.multiget.get COUNT : 0 rocksdb.number.multiget.keys.read COUNT : 0 rocksdb.number.multiget.bytes.read COUNT : 0 rocksdb.number.merge.failures COUNT : 0 rocksdb.bloom.filter.prefix.checked COUNT : 0 rocksdb.bloom.filter.prefix.useful COUNT : 0 rocksdb.number.reseeks.iteration COUNT : 0 rocksdb.getupdatessince.calls COUNT : 0 rocksdb.wal.synced COUNT : 0 rocksdb.wal.bytes COUNT : 0 rocksdb.write.self COUNT : 0 rocksdb.write.other COUNT : 0 rocksdb.write.wal COUNT : 0 rocksdb.compact.read.bytes COUNT : 5947466127 rocksdb.compact.write.bytes COUNT : 5860191888 rocksdb.flush.write.bytes COUNT : 2506928768 rocksdb.compact.read.marked.bytes COUNT : 0 rocksdb.compact.read.periodic.bytes COUNT : 0 rocksdb.compact.read.ttl.bytes COUNT : 0 rocksdb.compact.write.marked.bytes COUNT : 0 rocksdb.compact.write.periodic.bytes COUNT : 0 rocksdb.compact.write.ttl.bytes COUNT : 0 rocksdb.number.direct.load.table.properties COUNT : 18 rocksdb.number.superversion_acquires COUNT : 394 rocksdb.number.superversion_releases COUNT : 368 rocksdb.number.superversion_cleanups COUNT : 95 rocksdb.number.block.compressed COUNT : 0 rocksdb.number.block.decompressed COUNT : 0 rocksdb.number.block.not_compressed COUNT : 0 rocksdb.merge.operation.time.nanos COUNT : 0 rocksdb.filter.operation.time.nanos COUNT : 0 rocksdb.row.cache.hit COUNT : 0 rocksdb.row.cache.miss COUNT : 0 rocksdb.read.amp.estimate.useful.bytes COUNT : 0 rocksdb.read.amp.total.read.bytes COUNT : 0 rocksdb.number.rate_limiter.drains COUNT : 0 rocksdb.number.iter.skip COUNT : 0 rocksdb.blobdb.num.put COUNT : 0 rocksdb.blobdb.num.write COUNT : 0 rocksdb.blobdb.num.get COUNT : 0 rocksdb.blobdb.num.multiget COUNT : 0 rocksdb.blobdb.num.seek COUNT : 0 rocksdb.blobdb.num.next COUNT : 0 rocksdb.blobdb.num.prev COUNT : 0 rocksdb.blobdb.num.keys.written COUNT : 0 rocksdb.blobdb.num.keys.read COUNT : 0 rocksdb.blobdb.bytes.written COUNT : 0 rocksdb.blobdb.bytes.read COUNT : 0 rocksdb.blobdb.write.inlined COUNT : 0 rocksdb.blobdb.write.inlined.ttl COUNT : 0 rocksdb.blobdb.write.blob COUNT : 0 rocksdb.blobdb.write.blob.ttl COUNT : 0 rocksdb.blobdb.blob.file.bytes.written COUNT : 0 rocksdb.blobdb.blob.file.bytes.read COUNT : 0 rocksdb.blobdb.blob.file.synced COUNT : 0 rocksdb.blobdb.blob.index.expired.count COUNT : 0 rocksdb.blobdb.blob.index.expired.size COUNT : 0 rocksdb.blobdb.blob.index.evicted.count COUNT : 0 rocksdb.blobdb.blob.index.evicted.size COUNT : 0 rocksdb.blobdb.gc.num.files COUNT : 0 rocksdb.blobdb.gc.num.new.files COUNT : 0 rocksdb.blobdb.gc.failures COUNT : 0 rocksdb.blobdb.gc.num.keys.relocated COUNT : 0 rocksdb.blobdb.gc.bytes.relocated COUNT : 0 rocksdb.blobdb.fifo.num.files.evicted COUNT : 0 rocksdb.blobdb.fifo.num.keys.evicted COUNT : 0 rocksdb.blobdb.fifo.bytes.evicted COUNT : 0 rocksdb.txn.overhead.mutex.prepare COUNT : 0 rocksdb.txn.overhead.mutex.old.commit.map COUNT : 0 rocksdb.txn.overhead.duplicate.key COUNT : 0 rocksdb.txn.overhead.mutex.snapshot COUNT : 0 rocksdb.txn.get.tryagain COUNT : 0 rocksdb.number.multiget.keys.found COUNT : 0 rocksdb.num.iterator.created COUNT : 0 rocksdb.num.iterator.deleted COUNT : 0 rocksdb.block.cache.compression.dict.miss COUNT : 0 rocksdb.block.cache.compression.dict.hit COUNT : 0 rocksdb.block.cache.compression.dict.add COUNT : 0 rocksdb.block.cache.compression.dict.bytes.insert COUNT : 0 rocksdb.block.cache.add.redundant COUNT : 0 rocksdb.block.cache.index.add.redundant COUNT : 0 rocksdb.block.cache.filter.add.redundant COUNT : 0 rocksdb.block.cache.data.add.redundant COUNT : 0 rocksdb.block.cache.compression.dict.add.redundant COUNT : 0 rocksdb.files.marked.trash COUNT : 0 rocksdb.files.deleted.immediately COUNT : 0 rocksdb.error.handler.bg.errro.count COUNT : 0 rocksdb.error.handler.bg.io.errro.count COUNT : 0 rocksdb.error.handler.bg.retryable.io.errro.count COUNT : 0 rocksdb.error.handler.autoresume.count COUNT : 0 rocksdb.error.handler.autoresume.retry.total.count COUNT : 0 rocksdb.error.handler.autoresume.success.count COUNT : 0 rocksdb.memtable.payload.bytes.at.flush COUNT : 2489991168 rocksdb.memtable.garbage.bytes.at.flush COUNT : 3955712 rocksdb.secondary.cache.hits COUNT : 0 rocksdb.verify_checksum.read.bytes COUNT : 0 rocksdb.backup.read.bytes COUNT : 0 rocksdb.backup.write.bytes COUNT : 0 rocksdb.remote.compact.read.bytes COUNT : 0 rocksdb.remote.compact.write.bytes COUNT : 0 rocksdb.hot.file.read.bytes COUNT : 0 rocksdb.warm.file.read.bytes COUNT : 0 rocksdb.cold.file.read.bytes COUNT : 0 rocksdb.hot.file.read.count COUNT : 0 rocksdb.warm.file.read.count COUNT : 0 rocksdb.cold.file.read.count COUNT : 0 rocksdb.last.level.read.bytes COUNT : 0 rocksdb.last.level.read.count COUNT : 0 rocksdb.non.last.level.read.bytes COUNT : 6907486480 rocksdb.non.last.level.read.count COUNT : 1636010 rocksdb.block.checksum.compute.count COUNT : 1776438 rocksdb.multiget.coroutine.count COUNT : 0 rocksdb.blobdb.cache.miss COUNT : 0 rocksdb.blobdb.cache.hit COUNT : 0 rocksdb.blobdb.cache.add COUNT : 0 rocksdb.blobdb.cache.add.failures COUNT : 0 rocksdb.blobdb.cache.bytes.read COUNT : 0 rocksdb.blobdb.cache.bytes.write COUNT : 0 rocksdb.read.async.micros COUNT : 0 rocksdb.async.read.error.count COUNT : 0 rocksdb.secondary.cache.filter.hits COUNT : 0 rocksdb.secondary.cache.index.hits COUNT : 0 rocksdb.secondary.cache.data.hits COUNT : 0 rocksdb.table.open.prefetch.tail.miss COUNT : 0 rocksdb.table.open.prefetch.tail.hit COUNT : 0 rocksdb.db.get.micros P50 : 239.074339 P95 : 556.458663 P99 : 840.223848 P100 : 32649.000000 COUNT : 281996 SUM : 80093927 rocksdb.db.write.micros P50 : 1.476602 P95 : 4.084424 P99 : 9.442017 P100 : 2413.000000 COUNT : 2493493 SUM : 16839576 rocksdb.compaction.times.micros P50 : 1124444.444444 P95 : 3740000.000000 P99 : 3837772.000000 P100 : 3837772.000000 COUNT : 16 SUM : 21683205 rocksdb.compaction.times.cpu_micros P50 : 1058333.333333 P95 : 3740000.000000 P99 : 3799876.000000 P100 : 3799876.000000 COUNT : 16 SUM : 20127787 rocksdb.subcompaction.setup.times.micros P50 : 0.000000 P95 : 0.000000 P99 : 0.000000 P100 : 0.000000 COUNT : 0 SUM : 0 rocksdb.table.sync.micros P50 : 801.764706 P95 : 1832.500000 P99 : 1915.000000 P100 : 1915.000000 COUNT : 38 SUM : 35586 rocksdb.compaction.outfile.sync.micros P50 : 1051.052632 P95 : 3350.000000 P99 : 5348.000000 P100 : 5348.000000 COUNT : 34 SUM : 44077 rocksdb.wal.file.sync.micros P50 : 0.000000 P95 : 0.000000 P99 : 0.000000 P100 : 0.000000 COUNT : 0 SUM : 0 rocksdb.manifest.file.sync.micros P50 : 148.000000 P95 : 490.000000 P99 : 688.000000 P100 : 688.000000 COUNT : 58 SUM : 11292 rocksdb.table.open.io.micros P50 : 6142.477876 P95 : 12144.750000 P99 : 29458.000000 P100 : 34005.000000 COUNT : 361 SUM : 2300062 rocksdb.db.multiget.micros P50 : 0.000000 P95 : 0.000000 P99 : 0.000000 P100 : 0.000000 COUNT : 0 SUM : 0 rocksdb.read.block.compaction.micros P50 : 3.504168 P95 : 124.450502 P99 : 288.239486 P100 : 19673.000000 COUNT : 1440420 SUM : 24141878 rocksdb.read.block.get.micros P50 : 196.900697 P95 : 372.041909 P99 : 568.385803 P100 : 20151.000000 COUNT : 335927 SUM : 68851041 rocksdb.write.raw.block.micros P50 : 0.537086 P95 : 1.425221 P99 : 4.755932 P100 : 20928.000000 COUNT : 2027549 SUM : 4398128 rocksdb.numfiles.in.singlecompaction P50 : 3.346154 P95 : 5.900000 P99 : 16.000000 P100 : 16.000000 COUNT : 21 SUM : 89 rocksdb.db.seek.micros P50 : 0.000000 P95 : 0.000000 P99 : 0.000000 P100 : 0.000000 COUNT : 0 SUM : 0 rocksdb.db.write.stall P50 : 0.577766 P95 : 1212.545925 P99 : 2337.000000 P100 : 2337.000000 COUNT : 63344 SUM : 10877705 rocksdb.sst.read.micros P50 : 1.722890 P95 : 247.938440 P99 : 393.076128 P100 : 20147.000000 COUNT : 1636434 SUM : 87246926 rocksdb.num.subcompactions.scheduled P50 : 0.000000 P95 : 0.000000 P99 : 0.000000 P100 : 0.000000 COUNT : 0 SUM : 0 rocksdb.bytes.per.read P50 : 1000.000000 P95 : 1000.000000 P99 : 1000.000000 P100 : 1000.000000 COUNT : 281996 SUM : 281996000 rocksdb.bytes.per.write P50 : 1032.000000 P95 : 1032.000000 P99 : 1032.000000 P100 : 1032.000000 COUNT : 2493493 SUM : 2573284776 rocksdb.bytes.per.multiget P50 : 0.000000 P95 : 0.000000 P99 : 0.000000 P100 : 0.000000 COUNT : 0 SUM : 0 rocksdb.bytes.compressed P50 : 0.000000 P95 : 0.000000 P99 : 0.000000 P100 : 0.000000 COUNT : 0 SUM : 0 rocksdb.bytes.decompressed P50 : 0.000000 P95 : 0.000000 P99 : 0.000000 P100 : 0.000000 COUNT : 0 SUM : 0 rocksdb.compression.times.nanos P50 : 0.000000 P95 : 0.000000 P99 : 0.000000 P100 : 0.000000 COUNT : 0 SUM : 0 rocksdb.decompression.times.nanos P50 : 0.000000 P95 : 0.000000 P99 : 0.000000 P100 : 0.000000 COUNT : 0 SUM : 0 rocksdb.read.num.merge_operands P50 : 0.000000 P95 : 0.000000 P99 : 0.000000 P100 : 0.000000 COUNT : 0 SUM : 0 rocksdb.blobdb.key.size P50 : 0.000000 P95 : 0.000000 P99 : 0.000000 P100 : 0.000000 COUNT : 0 SUM : 0 rocksdb.blobdb.value.size P50 : 0.000000 P95 : 0.000000 P99 : 0.000000 P100 : 0.000000 COUNT : 0 SUM : 0 rocksdb.blobdb.write.micros P50 : 0.000000 P95 : 0.000000 P99 : 0.000000 P100 : 0.000000 COUNT : 0 SUM : 0 rocksdb.blobdb.get.micros P50 : 0.000000 P95 : 0.000000 P99 : 0.000000 P100 : 0.000000 COUNT : 0 SUM : 0 rocksdb.blobdb.multiget.micros P50 : 0.000000 P95 : 0.000000 P99 : 0.000000 P100 : 0.000000 COUNT : 0 SUM : 0 rocksdb.blobdb.seek.micros P50 : 0.000000 P95 : 0.000000 P99 : 0.000000 P100 : 0.000000 COUNT : 0 SUM : 0 rocksdb.blobdb.next.micros P50 : 0.000000 P95 : 0.000000 P99 : 0.000000 P100 : 0.000000 COUNT : 0 SUM : 0 rocksdb.blobdb.prev.micros P50 : 0.000000 P95 : 0.000000 P99 : 0.000000 P100 : 0.000000 COUNT : 0 SUM : 0 rocksdb.blobdb.blob.file.write.micros P50 : 0.000000 P95 : 0.000000 P99 : 0.000000 P100 : 0.000000 COUNT : 0 SUM : 0 rocksdb.blobdb.blob.file.read.micros P50 : 0.000000 P95 : 0.000000 P99 : 0.000000 P100 : 0.000000 COUNT : 0 SUM : 0 rocksdb.blobdb.blob.file.sync.micros P50 : 0.000000 P95 : 0.000000 P99 : 0.000000 P100 : 0.000000 COUNT : 0 SUM : 0 rocksdb.blobdb.compression.micros P50 : 0.000000 P95 : 0.000000 P99 : 0.000000 P100 : 0.000000 COUNT : 0 SUM : 0 rocksdb.blobdb.decompression.micros P50 : 0.000000 P95 : 0.000000 P99 : 0.000000 P100 : 0.000000 COUNT : 0 SUM : 0 rocksdb.db.flush.micros P50 : 212222.222222 P95 : 256500.000000 P99 : 327710.000000 P100 : 327710.000000 COUNT : 38 SUM : 7848163 rocksdb.sst.batch.size P50 : 0.000000 P95 : 0.000000 P99 : 0.000000 P100 : 0.000000 COUNT : 0 SUM : 0 rocksdb.num.index.and.filter.blocks.read.per.level P50 : 0.000000 P95 : 0.000000 P99 : 0.000000 P100 : 0.000000 COUNT : 0 SUM : 0 rocksdb.num.sst.read.per.level P50 : 0.000000 P95 : 0.000000 P99 : 0.000000 P100 : 0.000000 COUNT : 0 SUM : 0 rocksdb.error.handler.autoresume.retry.count P50 : 0.000000 P95 : 0.000000 P99 : 0.000000 P100 : 0.000000 COUNT : 0 SUM : 0 rocksdb.async.read.bytes P50 : 0.000000 P95 : 0.000000 P99 : 0.000000 P100 : 0.000000 COUNT : 0 SUM : 0 rocksdb.poll.wait.micros P50 : 0.000000 P95 : 0.000000 P99 : 0.000000 P100 : 0.000000 COUNT : 0 SUM : 0 rocksdb.prefetched.bytes.discarded P50 : 0.000000 P95 : 0.000000 P99 : 0.000000 P100 : 0.000000 COUNT : 59 SUM : 0 rocksdb.multiget.io.batch.size P50 : 0.000000 P95 : 0.000000 P99 : 0.000000 P100 : 0.000000 COUNT : 0 SUM : 0 rocksdb.num.level.read.per.multiget P50 : 0.000000 P95 : 0.000000 P99 : 0.000000 P100 : 0.000000 COUNT : 0 SUM : 0 rocksdb.async.prefetch.abort.micros P50 : 0.000000 P95 : 0.000000 P99 : 0.000000 P100 : 0.000000 COUNT : 0 SUM : 0 rocksdb.table.open.prefetch.tail.read.bytes P50 : 0.000000 P95 : 0.000000 P99 : 0.000000 P100 : 0.000000 COUNT : 0 SUM : 0