[2024-11-20 20:17:31.293883] Starting SPDK v24.01.1-pre git sha1 c13c99a5e / DPDK 23.11.0 initialization... [2024-11-20 20:17:31.294145] [ DPDK EAL parameters: rocksdb --no-shconf -c 0x1 --huge-unlink --no-telemetry --log-level=lib.eal:6 --log-level=lib.cryptodev:5 --log-level=user1:6 --base-virtaddr=0x200000000000 --match-allocations --file-prefix=spdk_pid3824996 ] Set seed to 1732130251287199 because --seed was 0 EAL: No free 2048 kB hugepages reported on node 1 [2024-11-20 20:17:31.414942] app.c: 798:spdk_app_start: *NOTICE*: Total cores available: 1 [2024-11-20 20:17:31.624505] app.c: 488:app_setup_trace: *NOTICE*: Tracepoint Group Mask 0x80 specified. [2024-11-20 20:17:31.624569] app.c: 489:app_setup_trace: *NOTICE*: Use 'spdk_trace -s rocksdb -p 3824996' to capture a snapshot of events at runtime. [2024-11-20 20:17:31.624579] app.c: 494:app_setup_trace: *NOTICE*: Or copy /dev/shm/rocksdb_trace.pid3824996 for offline analysis/debug. [2024-11-20 20:17:31.624608] reactor.c: 937:reactor_run: *NOTICE*: Reactor started on core 0 RocksDB: version 8.1.1 Date: Wed Nov 20 20:17:40 2024 CPU: 96 * Intel(R) Xeon(R) Gold 6252 CPU @ 2.10GHz CPUCache: 36608 KB 2024/11/20-20:17:44 ... thread 0: (1000000,1000000) ops and (248531.2,248531.2) ops/second in (4.023639,4.023639) 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/15 3.81 GB 62.9 0.6 0.0 0.6 1.4 0.9 0.0 1.6 100.6 256.9 5.64 4.52 15 0.376 575K 633 0.0 0.0 L1 9/9 1.08 GB 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 19/0 998.06 MB 1.0 0.3 0.1 0.1 0.3 0.1 0.0 2.1 155.6 155.3 1.76 1.30 1 1.763 0 0 0.0 0.0 L4 172/0 9.50 GB 1.0 0.2 0.2 0.0 0.2 0.2 0.0 1.0 185.8 185.3 1.02 0.86 2 0.512 185K 0 0.0 0.0 L5 1100/0 90.61 GB 0.9 0.0 0.0 0.0 0.0 0.0 0.4 0.0 0.0 0.0 0.00 0.00 0 0.000 0 0 0.0 0.0 Sum 1318/24 105.98 GB 0.0 1.0 0.3 0.7 1.9 1.2 0.4 2.2 122.4 226.9 8.43 6.68 18 0.468 761K 633 0.0 0.0 Int 0/0 0.00 KB 0.0 1.0 0.3 0.7 1.9 1.2 0.4 2.2 122.4 226.9 8.43 6.68 18 0.468 761K 633 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 1.0 0.3 0.7 1.0 0.3 0.0 0.0 207.8 207.5 4.96 4.28 5 0.993 761K 633 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 254.7 3.46 2.40 13 0.266 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): 4.3 total, 4.0 interval Flush(GB): cumulative 0.861, interval 0.861 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: 1.87 GB write, 446.14 MB/s write, 1.01 GB read, 240.70 MB/s read, 8.4 seconds Interval compaction: 1.87 GB write, 474.95 MB/s write, 1.01 GB read, 256.25 MB/s read, 8.4 seconds Write Stall (count): cf-l0-file-count-limit-delays-with-ongoing-compaction: 2, cf-l0-file-count-limit-stops-with-ongoing-compaction: 0, l0-file-count-limit-delays: 2, l0-file-count-limit-stops: 0, memtable-limit-delays: 0, memtable-limit-stops: 6, pending-compaction-bytes-delays: 0, pending-compaction-bytes-stops: 0, total-delays: 2, total-stops: 6, interval: 8 total count ** File Read Latency Histogram By Level [default] ** ** Level 0 read latency histogram (micros): Count: 350175 Average: 7.3410 StdDev: 107.85 Min: 1 Median: 1.5851 Max: 23384 Percentiles: P50: 1.59 P75: 1.98 P99: 13.52 P99.9: 851.59 P99.99: 1628.24 ------------------------------------------------------ [ 0, 1 ] 46479 13.273% 13.273% ### ( 1, 2 ] 219825 62.776% 76.049% ############# ( 2, 3 ] 45811 13.082% 89.131% ### ( 3, 4 ] 15856 4.528% 93.659% # ( 4, 6 ] 13332 3.807% 97.466% # ( 6, 10 ] 4775 1.364% 98.830% ( 10, 15 ] 849 0.242% 99.072% ( 15, 22 ] 169 0.048% 99.121% ( 22, 34 ] 55 0.016% 99.136% ( 34, 51 ] 12 0.003% 99.140% ( 51, 76 ] 4 0.001% 99.141% ( 76, 110 ] 81 0.023% 99.164% ( 110, 170 ] 764 0.218% 99.382% ( 170, 250 ] 722 0.206% 99.588% ( 250, 380 ] 389 0.111% 99.700% ( 380, 580 ] 421 0.120% 99.820% ( 580, 870 ] 302 0.086% 99.906% ( 870, 1300 ] 209 0.060% 99.966% ( 1300, 1900 ] 159 0.045% 100.011% ( 1900, 2900 ] 90 0.026% 100.037% ( 2900, 4400 ] 27 0.008% 100.045% ( 4400, 6600 ] 7 0.002% 100.047% ( 6600, 9900 ] 8 0.002% 100.049% ( 9900, 14000 ] 3 0.001% 100.050% ( 14000, 22000 ] 3 0.001% 100.051% ( 22000, 33000 ] 1 0.000% 100.051% ** Level 1 read latency histogram (micros): Count: 147 Average: 1713.0680 StdDev: 3697.43 Min: 88 Median: 356.0526 Max: 21187 Percentiles: P50: 356.05 P75: 887.92 P99: 19648.00 P99.9: 21187.00 P99.99: 21187.00 ------------------------------------------------------ ( 76, 110 ] 2 1.361% 1.361% ( 110, 170 ] 32 21.769% 23.129% #### ( 170, 250 ] 24 16.327% 39.456% ### ( 250, 380 ] 19 12.925% 52.381% ### ( 380, 580 ] 19 12.925% 65.306% ### ( 580, 870 ] 14 9.524% 74.830% ## ( 870, 1300 ] 6 4.082% 78.912% # ( 1300, 1900 ] 7 4.762% 83.673% # ( 1900, 2900 ] 3 2.041% 85.714% ( 2900, 4400 ] 5 3.401% 89.116% # ( 4400, 6600 ] 4 2.721% 91.837% # ( 6600, 9900 ] 4 2.721% 94.558% # ( 9900, 14000 ] 3 2.041% 96.599% ( 14000, 22000 ] 5 3.401% 100.000% # ** Level 3 read latency histogram (micros): Count: 79274 Average: 7.3941 StdDev: 86.85 Min: 1 Median: 1.6828 Max: 4108 Percentiles: P50: 1.68 P75: 2.42 P99: 9.96 P99.9: 1389.15 P99.99: 2560.31 ------------------------------------------------------ [ 0, 1 ] 7385 9.316% 9.316% ## ( 1, 2 ] 47235 59.584% 68.900% ############ ( 2, 3 ] 11591 14.621% 83.522% ### ( 3, 4 ] 5895 7.436% 90.958% # ( 4, 6 ] 4897 6.177% 97.135% # ( 6, 10 ] 1495 1.886% 99.021% ( 10, 15 ] 268 0.338% 99.359% ( 15, 22 ] 51 0.064% 99.424% ( 22, 34 ] 8 0.010% 99.434% ( 76, 110 ] 6 0.008% 99.441% ( 110, 170 ] 59 0.074% 99.516% ( 170, 250 ] 61 0.077% 99.593% ( 250, 380 ] 55 0.069% 99.662% ( 380, 580 ] 65 0.082% 99.744% ( 580, 870 ] 58 0.073% 99.817% ( 870, 1300 ] 58 0.073% 99.890% ( 1300, 1900 ] 52 0.066% 99.956% ( 1900, 2900 ] 41 0.052% 100.008% ( 2900, 4400 ] 9 0.011% 100.019% ** Level 4 read latency histogram (micros): Count: 3924 Average: 370.4348 StdDev: 703.85 Min: 0 Median: 115.8942 Max: 6722 Percentiles: P50: 115.89 P75: 396.72 P99: 3789.35 P99.9: 6105.17 P99.99: 6722.00 ------------------------------------------------------ [ 0, 1 ] 168 4.281% 4.281% # ( 1, 2 ] 1059 26.988% 31.269% ##### ( 2, 3 ] 349 8.894% 40.163% ## ( 3, 4 ] 149 3.797% 43.960% # ( 4, 6 ] 100 2.548% 46.509% # ( 6, 10 ] 27 0.688% 47.197% ( 10, 15 ] 16 0.408% 47.604% ( 15, 22 ] 4 0.102% 47.706% ( 22, 34 ] 1 0.025% 47.732% ( 51, 76 ] 2 0.051% 47.783% ( 76, 110 ] 48 1.223% 49.006% ( 110, 170 ] 397 10.117% 59.123% ## ( 170, 250 ] 336 8.563% 67.686% ## ( 250, 380 ] 263 6.702% 74.388% # ( 380, 580 ] 287 7.314% 81.702% # ( 580, 870 ] 211 5.377% 87.080% # ( 870, 1300 ] 184 4.689% 91.769% # ( 1300, 1900 ] 132 3.364% 95.133% # ( 1900, 2900 ] 115 2.931% 98.063% # ( 2900, 4400 ] 62 1.580% 99.643% ( 4400, 6600 ] 13 0.331% 99.975% ( 6600, 9900 ] 1 0.025% 100.000% ** Level 5 read latency histogram (micros): Count: 14225 Average: 773.0956 StdDev: 1024.69 Min: 84 Median: 346.9164 Max: 7627 Percentiles: P50: 346.92 P75: 844.85 P99: 5343.20 P99.9: 6578.53 P99.99: 7627.00 ------------------------------------------------------ ( 76, 110 ] 344 2.418% 2.418% ( 110, 170 ] 2772 19.487% 21.905% #### ( 170, 250 ] 2668 18.756% 40.661% #### ( 250, 380 ] 1782 12.527% 53.188% ### ( 380, 580 ] 1806 12.696% 65.884% ### ( 580, 870 ] 1421 9.989% 75.873% ## ( 870, 1300 ] 998 7.016% 82.889% # ( 1300, 1900 ] 762 5.357% 88.246% # ( 1900, 2900 ] 794 5.582% 93.828% # ( 2900, 4400 ] 639 4.492% 98.320% # ( 4400, 6600 ] 228 1.603% 99.923% ( 6600, 9900 ] 12 0.084% 100.007% ** DB Stats ** Uptime(secs): 4.3 total, 4.0 interval Cumulative writes: 0 writes, 1000K keys, 0 commit groups, 0.0 writes per commit group, ingest: 0.96 GB, 229.66 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:0.568 H:M:S, 13.3 percent Interval writes: 0 writes, 1000K keys, 0 commit groups, 0.0 writes per commit group, ingest: 984.19 MB, 244.44 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:0.568 H:M:S, 14.1 percent Write Stall (count): write-buffer-manager-limit-stops: 0, num-running-compactions: 2 num-running-flushes: 1 2024/11/20-20:18:20 ... thread 0: (1000000,2000000) ops and (27482.1,49491.5) ops/second in (36.387357,40.410996) 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 16/11 4.85 GB 194.7 2.8 0.0 2.8 4.7 1.9 0.0 2.5 164.0 274.2 17.66 15.92 38 0.465 2941K 6398 0.0 0.0 L1 9/9 1.08 GB 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 19/0 998.06 MB 1.0 0.3 0.1 0.1 0.3 0.1 0.0 2.1 155.6 155.3 1.76 1.30 1 1.763 0 0 0.0 0.0 L4 172/0 9.50 GB 1.0 0.2 0.2 0.0 0.2 0.2 0.0 1.0 185.8 185.3 1.02 0.86 2 0.512 185K 0 0.0 0.0 L5 1100/0 90.61 GB 0.9 0.0 0.0 0.0 0.0 0.0 0.4 0.0 0.0 0.0 0.00 0.00 0 0.000 0 0 0.0 0.0 Sum 1316/20 107.02 GB 0.0 3.3 0.3 3.0 5.2 2.2 0.4 2.7 164.4 259.5 20.45 18.08 41 0.499 3126K 6398 0.0 0.0 Int 0/0 0.00 KB 0.0 3.3 0.3 3.0 5.2 2.2 0.4 2.7 164.4 259.5 20.45 18.08 41 0.499 3126K 6398 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 3.3 0.3 3.0 3.3 0.3 0.0 0.0 247.7 247.1 13.57 12.76 11 1.234 3126K 6398 0.0 0.0 High 0/0 0.00 KB 0.0 0.0 0.0 0.0 1.9 1.9 0.0 0.0 0.0 284.0 6.88 5.31 30 0.229 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): 40.7 total, 40.4 interval Flush(GB): cumulative 1.907, interval 1.907 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.18 GB write, 130.48 MB/s write, 3.28 GB read, 82.65 MB/s read, 20.4 seconds Interval compaction: 5.18 GB write, 131.32 MB/s write, 3.28 GB read, 83.18 MB/s read, 20.4 seconds Write Stall (count): cf-l0-file-count-limit-delays-with-ongoing-compaction: 13, cf-l0-file-count-limit-stops-with-ongoing-compaction: 0, l0-file-count-limit-delays: 13, l0-file-count-limit-stops: 0, memtable-limit-delays: 0, memtable-limit-stops: 6, pending-compaction-bytes-delays: 6, pending-compaction-bytes-stops: 0, total-delays: 19, total-stops: 6, interval: 25 total count ** File Read Latency Histogram By Level [default] ** ** Level 0 read latency histogram (micros): Count: 1257168 Average: 40.2081 StdDev: 99.96 Min: 0 Median: 1.7056 Max: 23384 Percentiles: P50: 1.71 P75: 4.90 P99: 249.21 P99.9: 626.43 P99.99: 982.65 ------------------------------------------------------ [ 0, 1 ] 229201 18.232% 18.232% #### ( 1, 2 ] 566033 45.024% 63.256% ######### ( 2, 3 ] 92313 7.343% 70.599% # ( 3, 4 ] 40000 3.182% 73.781% # ( 4, 6 ] 33997 2.704% 76.485% # ( 6, 10 ] 7836 0.623% 77.108% ( 10, 15 ] 1696 0.135% 77.243% ( 15, 22 ] 323 0.026% 77.269% ( 22, 34 ] 89 0.007% 77.276% ( 34, 51 ] 15 0.001% 77.277% ( 51, 76 ] 10 0.001% 77.278% ( 76, 110 ] 16064 1.278% 78.556% ( 110, 170 ] 178704 14.215% 92.770% ### ( 170, 250 ] 79098 6.292% 99.062% # ( 250, 380 ] 6544 0.521% 99.583% ( 380, 580 ] 3790 0.301% 99.884% ( 580, 870 ] 1242 0.099% 99.983% ( 870, 1300 ] 337 0.027% 100.010% ( 1300, 1900 ] 188 0.015% 100.025% ( 1900, 2900 ] 159 0.013% 100.037% ( 2900, 4400 ] 154 0.012% 100.050% ( 4400, 6600 ] 7 0.001% 100.050% ( 6600, 9900 ] 8 0.001% 100.051% ( 9900, 14000 ] 3 0.000% 100.051% ( 14000, 22000 ] 3 0.000% 100.051% ( 22000, 33000 ] 1 0.000% 100.051% ** Level 1 read latency histogram (micros): Count: 7553 Average: 191.6721 StdDev: 563.84 Min: 81 Median: 151.5004 Max: 21187 Percentiles: P50: 151.50 P75: 186.76 P99: 609.60 P99.9: 10510.90 P99.99: 20791.52 ------------------------------------------------------ ( 76, 110 ] 462 6.117% 6.117% # ( 110, 170 ] 4792 63.445% 69.562% ############# ( 170, 250 ] 1961 25.963% 95.525% ##### ( 250, 380 ] 159 2.105% 97.630% ( 380, 580 ] 100 1.324% 98.954% ( 580, 870 ] 34 0.450% 99.404% ( 870, 1300 ] 9 0.119% 99.523% ( 1300, 1900 ] 9 0.119% 99.643% ( 1900, 2900 ] 6 0.079% 99.722% ( 2900, 4400 ] 5 0.066% 99.788% ( 4400, 6600 ] 4 0.053% 99.841% ( 6600, 9900 ] 4 0.053% 99.894% ( 9900, 14000 ] 3 0.040% 99.934% ( 14000, 22000 ] 5 0.066% 100.000% ** Level 3 read latency histogram (micros): Count: 88198 Average: 22.4781 StdDev: 95.96 Min: 1 Median: 1.7769 Max: 4108 Percentiles: P50: 1.78 P75: 2.99 P99: 241.27 P99.9: 1291.56 P99.99: 2538.54 ------------------------------------------------------ [ 0, 1 ] 7385 8.373% 8.373% ## ( 1, 2 ] 47258 53.582% 61.955% ########### ( 2, 3 ] 11656 13.216% 75.171% ### ( 3, 4 ] 5965 6.763% 81.934% # ( 4, 6 ] 5025 5.697% 87.631% # ( 6, 10 ] 1502 1.703% 89.334% ( 10, 15 ] 273 0.310% 89.644% ( 15, 22 ] 52 0.059% 89.703% ( 22, 34 ] 8 0.009% 89.712% ( 76, 110 ] 508 0.576% 90.288% ( 110, 170 ] 5513 6.251% 96.538% # ( 170, 250 ] 2437 2.763% 99.302% # ( 250, 380 ] 232 0.263% 99.565% ( 380, 580 ] 157 0.178% 99.743% ( 580, 870 ] 79 0.090% 99.832% ( 870, 1300 ] 61 0.069% 99.901% ( 1300, 1900 ] 52 0.059% 99.960% ( 1900, 2900 ] 41 0.046% 100.007% ( 2900, 4400 ] 9 0.010% 100.017% ** Level 4 read latency histogram (micros): Count: 71696 Average: 170.9233 StdDev: 186.90 Min: 0 Median: 150.5116 Max: 6722 Percentiles: P50: 150.51 P75: 188.12 P99: 763.11 P99.9: 3108.30 P99.99: 5555.91 ------------------------------------------------------ [ 0, 1 ] 169 0.236% 0.236% ( 1, 2 ] 1151 1.605% 1.841% ( 2, 3 ] 728 1.015% 2.857% ( 3, 4 ] 524 0.731% 3.587% ( 4, 6 ] 614 0.856% 4.444% ( 6, 10 ] 69 0.096% 4.540% ( 10, 15 ] 40 0.056% 4.596% ( 15, 22 ] 5 0.007% 4.603% ( 22, 34 ] 2 0.003% 4.606% ( 51, 76 ] 2 0.003% 4.608% ( 76, 110 ] 3982 5.554% 10.162% # ( 110, 170 ] 42302 59.002% 69.164% ############ ( 170, 250 ] 18468 25.759% 94.923% ##### ( 250, 380 ] 1644 2.293% 97.216% ( 380, 580 ] 1017 1.418% 98.635% ( 580, 870 ] 415 0.579% 99.213% ( 870, 1300 ] 217 0.303% 99.516% ( 1300, 1900 ] 141 0.197% 99.713% ( 1900, 2900 ] 125 0.174% 99.887% ( 2900, 4400 ] 67 0.093% 99.980% ( 4400, 6600 ] 13 0.018% 99.999% ( 6600, 9900 ] 1 0.001% 100.000% ** Level 5 read latency histogram (micros): Count: 581310 Average: 176.8835 StdDev: 199.16 Min: 79 Median: 152.1104 Max: 7627 Percentiles: P50: 152.11 P75: 189.16 P99: 683.16 P99.9: 3638.18 P99.99: 6137.61 ------------------------------------------------------ ( 76, 110 ] 35270 6.067% 6.067% # ( 110, 170 ] 363880 62.597% 68.664% ############# ( 170, 250 ] 153822 26.461% 95.125% ##### ( 250, 380 ] 13093 2.252% 97.377% ( 380, 580 ] 8333 1.433% 98.811% ( 580, 870 ] 3092 0.532% 99.343% ( 870, 1300 ] 1208 0.208% 99.551% ( 1300, 1900 ] 832 0.143% 99.694% ( 1900, 2900 ] 868 0.149% 99.843% ( 2900, 4400 ] 674 0.116% 99.959% ( 4400, 6600 ] 229 0.039% 99.998% ( 6600, 9900 ] 13 0.002% 100.001% ** DB Stats ** Uptime(secs): 40.7 total, 36.4 interval Cumulative writes: 0 writes, 2000K keys, 0 commit groups, 0.0 writes per commit group, ingest: 1.92 GB, 48.40 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:32.570 H:M:S, 80.1 percent Interval writes: 0 writes, 1000K keys, 0 commit groups, 0.0 writes per commit group, ingest: 984.19 MB, 27.05 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:32.002 H:M:S, 87.9 percent Write Stall (count): write-buffer-manager-limit-stops: 0, num-running-compactions: 1 num-running-flushes: 0 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: 100000000 Prefix: 0 bytes Keys per prefix: 0 RawSize: 96893.3 MB (estimated) FileSize: 96893.3 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 : 223.533 micros/op 17866 ops/sec 60.169 seconds 1074996 operations; 17.3 MB/s (269999 of 269999 found) Microseconds per read: Count: 1074996 Average: 223.5345 StdDev: 391.20 Min: 2 Median: 196.0136 Max: 49510 Percentiles: P50: 196.01 P75: 237.16 P99: 646.59 P99.9: 6449.36 P99.99: 17313.46 ------------------------------------------------------ ( 1, 2 ] 11 0.001% 0.001% ( 2, 3 ] 60 0.006% 0.007% ( 3, 4 ] 58 0.005% 0.012% ( 4, 6 ] 63 0.006% 0.018% ( 6, 10 ] 595 0.055% 0.073% ( 10, 15 ] 5524 0.514% 0.587% ( 15, 22 ] 2358 0.219% 0.806% ( 22, 34 ] 8535 0.794% 1.600% ( 34, 51 ] 1533 0.143% 1.743% ( 51, 76 ] 127 0.012% 1.755% ( 76, 110 ] 3618 0.337% 2.091% ( 110, 170 ] 345095 32.102% 34.193% ###### ( 170, 250 ] 522560 48.610% 82.804% ########## ( 250, 380 ] 123614 11.499% 94.303% ## ( 380, 580 ] 48472 4.509% 98.812% # ( 580, 870 ] 8810 0.820% 99.631% ( 870, 1300 ] 1755 0.163% 99.795% ( 1300, 1900 ] 406 0.038% 99.832% ( 1900, 2900 ] 330 0.031% 99.863% ( 2900, 4400 ] 193 0.018% 99.881% ( 4400, 6600 ] 219 0.020% 99.901% ( 6600, 9900 ] 522 0.049% 99.950% ( 9900, 14000 ] 375 0.035% 99.985% ( 14000, 22000 ] 134 0.012% 99.997% ( 22000, 33000 ] 24 0.002% 100.000% ( 33000, 50000 ] 5 0.000% 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 : 18627294 rocksdb.bloom.filter.full.positive COUNT : 1256465 rocksdb.bloom.filter.full.true.positive COUNT : 1074603 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 : 393 rocksdb.memtable.miss COUNT : 1074603 rocksdb.l0.hit COUNT : 52126 rocksdb.l1.hit COUNT : 11626 rocksdb.l2andup.hit COUNT : 1010851 rocksdb.compaction.key.drop.new COUNT : 27849 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 : 2054995 rocksdb.number.keys.read COUNT : 1074996 rocksdb.number.keys.updated COUNT : 0 rocksdb.bytes.written COUNT : 2120754840 rocksdb.bytes.read COUNT : 1074996000 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 : 1357 rocksdb.no.file.errors COUNT : 0 rocksdb.stall.micros COUNT : 51985402 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 : 5506734513 rocksdb.compact.write.bytes COUNT : 5485790978 rocksdb.flush.write.bytes COUNT : 2113960491 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 : 13 rocksdb.number.superversion_acquires COUNT : 305 rocksdb.number.superversion_releases COUNT : 291 rocksdb.number.superversion_cleanups COUNT : 75 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 : 2096777216 rocksdb.memtable.garbage.bytes.at.flush COUNT : 692224 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 : 11228894894 rocksdb.non.last.level.read.count COUNT : 2567734 rocksdb.block.checksum.compute.count COUNT : 2603580 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 : 194.960953 P95 : 407.986099 P99 : 643.750757 P100 : 49507.000000 COUNT : 1074996 SUM : 239320147 rocksdb.db.write.micros P50 : 1.556387 P95 : 5.724177 P99 : 1197.942741 P100 : 317700.000000 COUNT : 2054995 SUM : 57416194 rocksdb.compaction.times.micros P50 : 1030000.000000 P95 : 3530000.000000 P99 : 3942874.000000 P100 : 3942874.000000 COUNT : 11 SUM : 13571602 rocksdb.compaction.times.cpu_micros P50 : 1030000.000000 P95 : 3530000.000000 P99 : 3905245.000000 P100 : 3905245.000000 COUNT : 11 SUM : 12764661 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 : 941.666667 P95 : 2716.666667 P99 : 3935.000000 P100 : 4129.000000 COUNT : 31 SUM : 38559 rocksdb.compaction.outfile.sync.micros P50 : 1300.000000 P95 : 4025.000000 P99 : 4853.000000 P100 : 4853.000000 COUNT : 30 SUM : 48700 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 : 217.058824 P95 : 1660.000000 P99 : 2410.000000 P100 : 2410.000000 COUNT : 44 SUM : 18893 rocksdb.table.open.io.micros P50 : 6950.082237 P95 : 12233.177966 P99 : 17920.000000 P100 : 56805.000000 COUNT : 1357 SUM : 9736254 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.691350 P95 : 201.085446 P99 : 247.167116 P100 : 4693.000000 COUNT : 1335634 SUM : 52974291 rocksdb.read.block.get.micros P50 : 152.058543 P95 : 245.581667 P99 : 503.522939 P100 : 31081.000000 COUNT : 1266582 SUM : 207089875 rocksdb.write.raw.block.micros P50 : 0.567492 P95 : 1.726556 P99 : 3.401170 P100 : 5337.000000 COUNT : 1840658 SUM : 4561499 rocksdb.numfiles.in.singlecompaction P50 : 3.437500 P95 : 17.450000 P99 : 20.000000 P100 : 20.000000 COUNT : 13 SUM : 64 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.584605 P95 : 2254.132934 P99 : 2770.920451 P100 : 317529.000000 COUNT : 220229 SUM : 52005468 rocksdb.sst.read.micros P50 : 116.983867 P95 : 234.428471 P99 : 380.775936 P100 : 23384.000000 COUNT : 2567741 SUM : 251651408 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 : 1074996 SUM : 1074996000 rocksdb.bytes.per.write P50 : 1032.000000 P95 : 1032.000000 P99 : 1032.000000 P100 : 1032.000000 COUNT : 2054995 SUM : 2120754840 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 : 217692.307692 P95 : 362125.000000 P99 : 511100.000000 P100 : 546657.000000 COUNT : 31 SUM : 7129716 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 : 40 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