[2024-12-05 12:42:31.140065] Starting SPDK v25.01-pre git sha1 1906bbd92 / DPDK 24.03.0 initialization... [2024-12-05 12:42:31.140333] [ 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_pid148959 ] Set seed to 1733398951133244 because --seed was 0 EAL: No free 2048 kB hugepages reported on node 1 [2024-12-05 12:42:31.274461] app.c: 919:spdk_app_start: *NOTICE*: Total cores available: 1 [2024-12-05 12:42:31.374632] app.c: 612:app_setup_trace: *NOTICE*: Tracepoint Group Mask 0x80 specified. [2024-12-05 12:42:31.374685] app.c: 613:app_setup_trace: *NOTICE*: Use 'spdk_trace -s rocksdb -p 148959' to capture a snapshot of events at runtime. [2024-12-05 12:42:31.374696] app.c: 618:app_setup_trace: *NOTICE*: 'spdk_trace' without parameters will also work if this is the only [2024-12-05 12:42:31.374707] app.c: 619:app_setup_trace: *NOTICE*: SPDK application currently running. [2024-12-05 12:42:31.374715] app.c: 620:app_setup_trace: *NOTICE*: Or copy /dev/shm/rocksdb_trace.pid148959 for offline analysis/debug. [2024-12-05 12:42:31.376065] reactor.c:1005:reactor_run: *NOTICE*: Reactor started on core 0 RocksDB: version 8.1.1 Date: Thu Dec 5 12:42:37 2024 CPU: 96 * Intel(R) Xeon(R) Gold 6252 CPU @ 2.10GHz CPUCache: 36608 KB 2024/12/05-12:42:40 ... thread 0: (1000000,1000000) ops and (279813.8,279813.8) ops/second in (3.573805,3.573805) 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 17/14 2.90 GB 56.4 0.5 0.0 0.5 1.3 0.8 0.0 1.6 99.4 260.3 5.07 4.42 15 0.338 511K 2463 0.0 0.0 L1 7/7 548.56 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/1 1.01 GB 1.0 0.4 0.3 0.2 0.4 0.2 0.0 1.7 175.5 173.0 2.60 2.20 2 1.299 261K 0 0.0 0.0 L4 131/6 9.91 GB 1.0 0.4 0.3 0.1 0.4 0.2 0.1 1.5 185.5 182.5 2.14 1.88 2 1.068 0 0 0.0 0.0 L5 124/0 10.15 GB 0.1 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 Sum 297/28 24.51 GB 0.0 1.3 0.5 0.8 2.1 1.3 0.1 2.6 138.3 220.2 9.80 8.49 19 0.516 772K 2463 0.0 0.0 Int 0/0 0.00 KB 0.0 1.3 0.5 0.8 2.1 1.3 0.1 2.6 138.3 220.2 9.80 8.49 19 0.516 772K 2463 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.3 0.5 0.8 1.3 0.5 0.0 0.0 200.7 198.4 6.75 6.04 6 1.126 772K 2463 0.0 0.0 High 0/0 0.00 KB 0.0 0.0 0.0 0.0 0.8 0.8 0.0 0.0 0.0 268.5 3.05 2.45 13 0.234 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): 3.7 total, 3.6 interval Flush(GB): cumulative 0.799, interval 0.799 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.11 GB write, 585.64 MB/s write, 1.32 GB read, 367.87 MB/s read, 9.8 seconds Interval compaction: 2.11 GB write, 603.15 MB/s write, 1.32 GB read, 378.87 MB/s read, 9.8 seconds Write Stall (count): cf-l0-file-count-limit-delays-with-ongoing-compaction: 0, cf-l0-file-count-limit-stops-with-ongoing-compaction: 0, l0-file-count-limit-delays: 0, 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: 0, total-stops: 0, ** File Read Latency Histogram By Level [default] ** ** Level 0 read latency histogram (micros): Count: 169531 Average: 35.2150 StdDev: 230.07 Min: 1 Median: 1.7126 Max: 24648 Percentiles: P50: 1.71 P75: 2.58 P99: 643.15 P99.9: 1401.25 P99.99: 2351.36 ------------------------------------------------------ [ 0, 1 ] 16396 9.671% 9.671% ## ( 1, 2 ] 95949 56.597% 66.268% ########### ( 2, 3 ] 25550 15.071% 81.339% ### ( 3, 4 ] 7803 4.603% 85.942% # ( 4, 6 ] 7197 4.245% 90.187% # ( 6, 10 ] 1502 0.886% 91.073% ( 10, 15 ] 253 0.149% 91.222% ( 15, 22 ] 42 0.025% 91.247% ( 22, 34 ] 16 0.009% 91.256% ( 34, 51 ] 5 0.003% 91.259% ( 51, 76 ] 1 0.001% 91.260% ( 76, 110 ] 169 0.100% 91.360% ( 110, 170 ] 2870 1.693% 93.053% ( 170, 250 ] 4504 2.657% 95.709% # ( 250, 380 ] 3033 1.789% 97.498% ( 380, 580 ] 2255 1.330% 98.829% ( 580, 870 ] 1335 0.787% 99.616% ( 870, 1300 ] 457 0.270% 99.886% ( 1300, 1900 ] 145 0.086% 99.971% ( 1900, 2900 ] 71 0.042% 100.013% ( 2900, 4400 ] 25 0.015% 100.028% ( 4400, 6600 ] 10 0.006% 100.034% ( 6600, 9900 ] 8 0.005% 100.038% ( 9900, 14000 ] 5 0.003% 100.041% ( 14000, 22000 ] 5 0.003% 100.044% ( 22000, 33000 ] 5 0.003% 100.047% ** Level 1 read latency histogram (micros): Count: 767 Average: 568.3481 StdDev: 1776.65 Min: 95 Median: 254.1813 Max: 22859 Percentiles: P50: 254.18 P75: 433.03 P99: 7881.50 P99.9: 22859.00 P99.99: 22859.00 ------------------------------------------------------ ( 76, 110 ] 8 1.043% 1.043% ( 110, 170 ] 158 20.600% 21.643% #### ( 170, 250 ] 212 27.640% 49.283% ###### ( 250, 380 ] 171 22.295% 71.578% #### ( 380, 580 ] 99 12.907% 84.485% ### ( 580, 870 ] 67 8.735% 93.220% ## ( 870, 1300 ] 22 2.868% 96.089% # ( 1300, 1900 ] 8 1.043% 97.132% ( 1900, 2900 ] 2 0.261% 97.392% ( 2900, 4400 ] 3 0.391% 97.784% ( 4400, 6600 ] 7 0.913% 98.696% ( 6600, 9900 ] 6 0.782% 99.478% ( 14000, 22000 ] 2 0.261% 99.739% ( 22000, 33000 ] 2 0.261% 100.000% ** Level 3 read latency histogram (micros): Count: 107033 Average: 9.7780 StdDev: 133.29 Min: 0 Median: 1.7665 Max: 21760 Percentiles: P50: 1.77 P75: 2.56 P99: 221.83 P99.9: 796.52 P99.99: 1456.36 ------------------------------------------------------ [ 0, 1 ] 6394 5.974% 5.974% # ( 1, 2 ] 61478 57.438% 63.412% ########### ( 2, 3 ] 22001 20.555% 83.968% #### ( 3, 4 ] 6602 6.168% 90.136% # ( 4, 6 ] 6190 5.783% 95.919% # ( 6, 10 ] 2140 1.999% 97.918% ( 10, 15 ] 355 0.332% 98.250% ( 15, 22 ] 50 0.047% 98.297% ( 22, 34 ] 2 0.002% 98.299% ( 34, 51 ] 1 0.001% 98.300% ( 51, 76 ] 1 0.001% 98.301% ( 76, 110 ] 30 0.028% 98.329% ( 110, 170 ] 363 0.339% 98.668% ( 170, 250 ] 549 0.513% 99.181% ( 250, 380 ] 370 0.346% 99.526% ( 380, 580 ] 282 0.263% 99.790% ( 580, 870 ] 158 0.148% 99.937% ( 870, 1300 ] 49 0.046% 99.983% ( 1300, 1900 ] 28 0.026% 100.009% ( 1900, 2900 ] 16 0.015% 100.024% ( 2900, 4400 ] 8 0.007% 100.032% ( 4400, 6600 ] 5 0.005% 100.036% ( 14000, 22000 ] 3 0.003% 100.039% ** Level 4 read latency histogram (micros): Count: 159284 Average: 32.9574 StdDev: 209.45 Min: 1 Median: 1.7976 Max: 34536 Percentiles: P50: 1.80 P75: 2.77 P99: 616.46 P99.9: 1675.98 P99.99: 2795.92 ------------------------------------------------------ [ 0, 1 ] 9563 6.004% 6.004% # ( 1, 2 ] 87867 55.164% 61.167% ########### ( 2, 3 ] 28757 18.054% 79.221% #### ( 3, 4 ] 8754 5.496% 84.717% # ( 4, 6 ] 7897 4.958% 89.675% # ( 6, 10 ] 3305 2.075% 91.750% ( 10, 15 ] 606 0.380% 92.130% ( 15, 22 ] 92 0.058% 92.188% ( 22, 34 ] 13 0.008% 92.196% ( 76, 110 ] 172 0.108% 92.304% ( 110, 170 ] 2563 1.609% 93.913% ( 170, 250 ] 3724 2.338% 96.251% ( 250, 380 ] 2409 1.512% 97.764% ( 380, 580 ] 1833 1.151% 98.915% ( 580, 870 ] 1083 0.680% 99.594% ( 870, 1300 ] 404 0.254% 99.848% ( 1300, 1900 ] 132 0.083% 99.931% ( 1900, 2900 ] 105 0.066% 99.997% ( 2900, 4400 ] 73 0.046% 100.043% ( 4400, 6600 ] 20 0.013% 100.055% ( 6600, 9900 ] 1 0.001% 100.056% ( 9900, 14000 ] 3 0.002% 100.058% ( 22000, 33000 ] 2 0.001% 100.059% ( 33000, 50000 ] 1 0.001% 100.060% ** Level 5 read latency histogram (micros): Count: 12296 Average: 389.9107 StdDev: 663.38 Min: 83 Median: 245.3617 Max: 35058 Percentiles: P50: 245.36 P75: 430.77 P99: 2737.95 P99.9: 6189.08 P99.99: 30474.40 ------------------------------------------------------ ( 76, 110 ] 152 1.236% 1.236% ( 110, 170 ] 2584 21.015% 22.251% #### ( 170, 250 ] 3622 29.457% 51.708% ###### ( 250, 380 ] 2403 19.543% 71.251% #### ( 380, 580 ] 1816 14.769% 86.020% ### ( 580, 870 ] 1021 8.304% 94.323% ## ( 870, 1300 ] 361 2.936% 97.259% # ( 1300, 1900 ] 116 0.943% 98.203% ( 1900, 2900 ] 117 0.952% 99.154% ( 2900, 4400 ] 73 0.594% 99.748% ( 4400, 6600 ] 23 0.187% 99.935% ( 6600, 9900 ] 1 0.008% 99.943% ( 9900, 14000 ] 2 0.016% 99.959% ( 14000, 22000 ] 3 0.024% 99.984% ( 22000, 33000 ] 1 0.008% 99.992% ( 33000, 50000 ] 1 0.008% 100.000% ** DB Stats ** Uptime(secs): 3.7 total, 3.6 interval Cumulative writes: 0 writes, 1000K keys, 0 commit groups, 0.0 writes per commit group, ingest: 0.96 GB, 267.09 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.000 H:M:S, 0.0 percent Interval writes: 0 writes, 1000K keys, 0 commit groups, 0.0 writes per commit group, ingest: 984.19 MB, 275.02 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.000 H:M:S, 0.0 percent Write Stall (count): write-buffer-manager-limit-stops: 0, num-running-compactions: 3 num-running-flushes: 1 2024/12/05-12:42:46 ... thread 0: (1000000,2000000) ops and (170012.1,211511.8) ops/second in (5.881934,9.455739) 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 19/17 3.94 GB 31.3 1.7 0.0 1.7 3.6 1.8 0.0 1.9 131.4 271.6 13.40 12.12 37 0.362 1788K 8599 0.0 0.0 L1 7/7 548.56 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 17/0 969.47 MB 1.0 0.4 0.3 0.2 0.4 0.2 0.0 1.7 175.5 173.0 2.60 2.20 2 1.299 261K 0 0.0 0.0 L4 129/0 9.66 GB 1.0 0.9 0.3 0.6 0.9 0.2 0.1 2.8 211.8 196.7 4.54 4.03 3 1.514 63K 0 0.0 0.0 L5 128/0 10.40 GB 0.1 0.0 0.0 0.0 0.0 0.0 0.3 0.0 0.0 0.0 0.00 0.00 0 0.000 0 0 0.0 0.0 Sum 300/24 25.48 GB 0.0 3.1 0.6 2.5 4.9 2.3 0.4 2.6 154.8 242.6 20.54 18.35 42 0.489 2113K 8599 0.0 0.0 Int 0/0 0.00 KB 0.0 3.1 0.6 2.5 4.9 2.3 0.4 2.6 154.8 242.6 20.54 18.35 42 0.489 2113K 8599 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.1 0.6 2.5 3.0 0.5 0.0 0.0 227.4 221.4 13.99 12.91 12 1.165 2113K 8599 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 287.8 6.56 5.44 30 0.219 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): 9.6 total, 9.5 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: 4.87 GB write, 520.92 MB/s write, 3.11 GB read, 332.40 MB/s read, 20.5 seconds Interval compaction: 4.87 GB write, 526.81 MB/s write, 3.11 GB read, 336.16 MB/s read, 20.5 seconds Write Stall (count): cf-l0-file-count-limit-delays-with-ongoing-compaction: 10, cf-l0-file-count-limit-stops-with-ongoing-compaction: 0, l0-file-count-limit-delays: 10, 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: 10, total-stops: 0, interval: 10 total count ** File Read Latency Histogram By Level [default] ** ** Level 0 read latency histogram (micros): Count: 540908 Average: 29.6510 StdDev: 145.85 Min: 0 Median: 1.5893 Max: 24648 Percentiles: P50: 1.59 P75: 2.33 P99: 457.00 P99.9: 880.00 P99.99: 1251.81 ------------------------------------------------------ [ 0, 1 ] 101653 18.793% 18.793% #### ( 1, 2 ] 286443 52.956% 71.749% ########### ( 2, 3 ] 53826 9.951% 81.700% ## ( 3, 4 ] 18761 3.468% 85.168% # ( 4, 6 ] 15263 2.822% 87.990% # ( 6, 10 ] 3060 0.566% 88.556% ( 10, 15 ] 738 0.136% 88.692% ( 15, 22 ] 123 0.023% 88.715% ( 22, 34 ] 23 0.004% 88.719% ( 34, 51 ] 7 0.001% 88.721% ( 51, 76 ] 2 0.000% 88.721% ( 76, 110 ] 1503 0.278% 88.999% ( 110, 170 ] 25215 4.662% 93.660% # ( 170, 250 ] 20373 3.766% 97.427% # ( 250, 380 ] 6742 1.246% 98.673% ( 380, 580 ] 4592 0.849% 99.522% ( 580, 870 ] 2031 0.375% 99.898% ( 870, 1300 ] 563 0.104% 100.002% ( 1300, 1900 ] 157 0.029% 100.031% ( 1900, 2900 ] 96 0.018% 100.049% ( 2900, 4400 ] 43 0.008% 100.057% ( 4400, 6600 ] 10 0.002% 100.058% ( 6600, 9900 ] 8 0.001% 100.060% ( 9900, 14000 ] 5 0.001% 100.061% ( 14000, 22000 ] 5 0.001% 100.062% ( 22000, 33000 ] 5 0.001% 100.063% ** Level 1 read latency histogram (micros): Count: 2896 Average: 297.5394 StdDev: 936.45 Min: 89 Median: 185.0055 Max: 22859 Percentiles: P50: 185.01 P75: 248.17 P99: 1569.33 P99.9: 18416.00 P99.99: 22859.00 ------------------------------------------------------ ( 76, 110 ] 77 2.659% 2.659% # ( 110, 170 ] 1199 41.402% 44.061% ######## ( 170, 250 ] 917 31.664% 75.725% ###### ( 250, 380 ] 344 11.878% 87.604% ## ( 380, 580 ] 206 7.113% 94.717% # ( 580, 870 ] 95 3.280% 97.997% # ( 870, 1300 ] 25 0.863% 98.860% ( 1300, 1900 ] 9 0.311% 99.171% ( 1900, 2900 ] 3 0.104% 99.275% ( 2900, 4400 ] 4 0.138% 99.413% ( 4400, 6600 ] 7 0.242% 99.655% ( 6600, 9900 ] 6 0.207% 99.862% ( 14000, 22000 ] 2 0.069% 99.931% ( 22000, 33000 ] 2 0.069% 100.000% ** Level 3 read latency histogram (micros): Count: 113585 Average: 17.2457 StdDev: 136.67 Min: 0 Median: 1.8002 Max: 21760 Percentiles: P50: 1.80 P75: 2.71 P99: 320.48 P99.9: 824.82 P99.99: 1505.96 ------------------------------------------------------ [ 0, 1 ] 6639 5.845% 5.845% # ( 1, 2 ] 62674 55.178% 61.023% ########### ( 2, 3 ] 22301 19.634% 80.657% #### ( 3, 4 ] 6693 5.893% 86.549% # ( 4, 6 ] 6244 5.497% 92.046% # ( 6, 10 ] 2173 1.913% 93.960% ( 10, 15 ] 360 0.317% 94.277% ( 15, 22 ] 50 0.044% 94.321% ( 22, 34 ] 2 0.002% 94.322% ( 34, 51 ] 1 0.001% 94.323% ( 51, 76 ] 1 0.001% 94.324% ( 76, 110 ] 229 0.202% 94.526% ( 110, 170 ] 2712 2.388% 96.913% ( 170, 250 ] 1976 1.740% 98.653% ( 250, 380 ] 727 0.640% 99.293% ( 380, 580 ] 502 0.442% 99.735% ( 580, 870 ] 222 0.195% 99.930% ( 870, 1300 ] 57 0.050% 99.981% ( 1300, 1900 ] 31 0.027% 100.008% ( 1900, 2900 ] 17 0.015% 100.023% ( 2900, 4400 ] 8 0.007% 100.030% ( 4400, 6600 ] 5 0.004% 100.034% ( 14000, 22000 ] 3 0.003% 100.037% ** Level 4 read latency histogram (micros): Count: 209253 Average: 56.3476 StdDev: 198.67 Min: 0 Median: 1.9616 Max: 34536 Percentiles: P50: 1.96 P75: 5.44 P99: 613.95 P99.9: 1493.57 P99.99: 2679.42 ------------------------------------------------------ [ 0, 1 ] 10967 5.241% 5.241% # ( 1, 2 ] 97400 46.547% 51.788% ######### ( 2, 3 ] 31875 15.233% 67.020% ### ( 3, 4 ] 9960 4.760% 71.780% # ( 4, 6 ] 9328 4.458% 76.238% # ( 6, 10 ] 3706 1.771% 78.009% ( 10, 15 ] 675 0.323% 78.331% ( 15, 22 ] 112 0.054% 78.385% ( 22, 34 ] 14 0.007% 78.392% ( 76, 110 ] 1335 0.638% 79.030% ( 110, 170 ] 18803 8.986% 88.015% ## ( 170, 250 ] 14487 6.923% 94.939% # ( 250, 380 ] 4835 2.311% 97.249% ( 380, 580 ] 3482 1.664% 98.913% ( 580, 870 ] 1550 0.741% 99.654% ( 870, 1300 ] 467 0.223% 99.877% ( 1300, 1900 ] 148 0.071% 99.948% ( 1900, 2900 ] 113 0.054% 100.002% ( 2900, 4400 ] 74 0.035% 100.037% ( 4400, 6600 ] 20 0.010% 100.047% ( 6600, 9900 ] 1 0.000% 100.047% ( 9900, 14000 ] 3 0.001% 100.049% ( 22000, 33000 ] 2 0.001% 100.050% ( 33000, 50000 ] 1 0.000% 100.050% ** Level 5 read latency histogram (micros): Count: 46940 Average: 247.9749 StdDev: 364.72 Min: 83 Median: 181.9677 Max: 35058 Percentiles: P50: 181.97 P75: 245.23 P99: 1199.17 P99.9: 4089.46 P99.99: 14815.73 ------------------------------------------------------ ( 76, 110 ] 1433 3.053% 3.053% # ( 110, 170 ] 19817 42.218% 45.271% ######## ( 170, 250 ] 14841 31.617% 76.888% ###### ( 250, 380 ] 5066 10.793% 87.680% ## ( 380, 580 ] 3485 7.424% 95.104% # ( 580, 870 ] 1492 3.179% 98.283% # ( 870, 1300 ] 441 0.939% 99.222% ( 1300, 1900 ] 135 0.288% 99.510% ( 1900, 2900 ] 123 0.262% 99.772% ( 2900, 4400 ] 77 0.164% 99.936% ( 4400, 6600 ] 23 0.049% 99.985% ( 6600, 9900 ] 1 0.002% 99.987% ( 9900, 14000 ] 2 0.004% 99.991% ( 14000, 22000 ] 3 0.006% 99.998% ( 22000, 33000 ] 1 0.002% 100.000% ( 33000, 50000 ] 1 0.002% 100.002% ** DB Stats ** Uptime(secs): 9.6 total, 5.9 interval Cumulative writes: 0 writes, 2000K keys, 0 commit groups, 0.0 writes per commit group, ingest: 1.92 GB, 205.76 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:2.216 H:M:S, 23.2 percent Interval writes: 0 writes, 1000K keys, 0 commit groups, 0.0 writes per commit group, ingest: 984.19 MB, 167.33 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:2.216 H:M:S, 37.7 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 : 261.817 micros/op 15228 ops/sec 20.224 seconds 307996 operations; 14.8 MB/s (76999 of 76999 found) Microseconds per read: Count: 307996 Average: 261.8255 StdDev: 434.43 Min: 2 Median: 216.8679 Max: 39124 Percentiles: P50: 216.87 P75: 275.94 P99: 933.92 P99.9: 4282.41 P99.99: 22084.78 ------------------------------------------------------ ( 1, 2 ] 1 0.000% 0.000% ( 2, 3 ] 9 0.003% 0.003% ( 3, 4 ] 15 0.005% 0.008% ( 4, 6 ] 148 0.048% 0.056% ( 6, 10 ] 418 0.136% 0.192% ( 10, 15 ] 241 0.078% 0.270% ( 15, 22 ] 457 0.148% 0.419% ( 22, 34 ] 11622 3.773% 4.192% # ( 34, 51 ] 3568 1.158% 5.350% ( 51, 76 ] 531 0.172% 5.523% ( 76, 110 ] 67 0.022% 5.545% ( 110, 170 ] 41140 13.357% 18.902% ### ( 170, 250 ] 163491 53.082% 71.984% ########### ( 250, 380 ] 46550 15.114% 87.098% ### ( 380, 580 ] 27718 8.999% 96.097% ## ( 580, 870 ] 8594 2.790% 98.888% # ( 870, 1300 ] 2328 0.756% 99.644% ( 1300, 1900 ] 524 0.170% 99.814% ( 1900, 2900 ] 172 0.056% 99.869% ( 2900, 4400 ] 102 0.033% 99.903% ( 4400, 6600 ] 45 0.015% 99.917% ( 6600, 9900 ] 137 0.044% 99.962% ( 9900, 14000 ] 55 0.018% 99.980% ( 14000, 22000 ] 32 0.010% 99.990% ( 22000, 33000 ] 26 0.008% 99.998% ( 33000, 50000 ] 5 0.002% 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 : 5883084 rocksdb.bloom.filter.full.positive COUNT : 364293 rocksdb.bloom.filter.full.true.positive COUNT : 307173 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 : 823 rocksdb.memtable.miss COUNT : 307173 rocksdb.l0.hit COUNT : 54822 rocksdb.l1.hit COUNT : 7008 rocksdb.l2andup.hit COUNT : 245343 rocksdb.compaction.key.drop.new COUNT : 117199 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 : 2110359 rocksdb.number.keys.read COUNT : 307996 rocksdb.number.keys.updated COUNT : 0 rocksdb.bytes.written COUNT : 2177890488 rocksdb.bytes.read COUNT : 307996000 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 : 349 rocksdb.no.file.errors COUNT : 0 rocksdb.stall.micros COUNT : 12108723 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 : 4256041835 rocksdb.compact.write.bytes COUNT : 4133767541 rocksdb.flush.write.bytes COUNT : 2111088530 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 : 11 rocksdb.number.superversion_acquires COUNT : 325 rocksdb.number.superversion_releases COUNT : 309 rocksdb.number.superversion_cleanups COUNT : 79 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 : 2096808960 rocksdb.memtable.garbage.bytes.at.flush COUNT : 3315712 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 : 5557224029 rocksdb.non.last.level.read.count COUNT : 1310724 rocksdb.block.checksum.compute.count COUNT : 1400544 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 : 216.042693 P95 : 554.845646 P99 : 931.435995 P100 : 39123.000000 COUNT : 307996 SUM : 80272636 rocksdb.db.write.micros P50 : 1.494803 P95 : 4.752959 P99 : 11.023670 P100 : 3901.000000 COUNT : 2110359 SUM : 17336464 rocksdb.compaction.times.micros P50 : 1064000.000000 P95 : 2300000.000000 P99 : 2407273.000000 P100 : 2407273.000000 COUNT : 12 SUM : 13985459 rocksdb.compaction.times.cpu_micros P50 : 1064000.000000 P95 : 2156000.000000 P99 : 2156000.000000 P100 : 2156000.000000 COUNT : 12 SUM : 12910476 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 : 792.666667 P95 : 1855.000000 P99 : 2580.000000 P100 : 2690.000000 COUNT : 32 SUM : 31876 rocksdb.compaction.outfile.sync.micros P50 : 1051.923077 P95 : 3912.500000 P99 : 6715.000000 P100 : 6715.000000 COUNT : 33 SUM : 46914 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 : 158.461538 P95 : 460.000000 P99 : 470.000000 P100 : 470.000000 COUNT : 48 SUM : 9255 rocksdb.table.open.io.micros P50 : 6240.186916 P95 : 12986.388889 P99 : 43407.777778 P100 : 44498.000000 COUNT : 349 SUM : 2480192 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.814424 P95 : 192.089567 P99 : 297.802150 P100 : 24653.000000 COUNT : 1031941 SUM : 29723585 rocksdb.read.block.get.micros P50 : 165.607242 P95 : 378.396877 P99 : 776.868103 P100 : 35061.000000 COUNT : 368398 SUM : 68742534 rocksdb.write.raw.block.micros P50 : 0.591490 P95 : 1.880042 P99 : 5.074134 P100 : 35381.000000 COUNT : 1514019 SUM : 3998506 rocksdb.numfiles.in.singlecompaction P50 : 3.312500 P95 : 16.050000 P99 : 17.000000 P100 : 17.000000 COUNT : 17 SUM : 77 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.580732 P95 : 1195.296348 P99 : 2177.000000 P100 : 2177.000000 COUNT : 71401 SUM : 12117970 rocksdb.sst.read.micros P50 : 2.618389 P95 : 241.723216 P99 : 523.785476 P100 : 35058.000000 COUNT : 1310980 SUM : 93305508 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 : 307996 SUM : 307996000 rocksdb.bytes.per.write P50 : 1032.000000 P95 : 1032.000000 P99 : 1032.000000 P100 : 1032.000000 COUNT : 2110359 SUM : 2177890488 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 : 214137.931034 P95 : 278066.000000 P99 : 278066.000000 P100 : 278066.000000 COUNT : 32 SUM : 6969385 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 : 47 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