[2024-07-21 07:13:43.764298] Starting SPDK v24.09-pre git sha1 89fd17309 / DPDK 24.07.0-rc2 initialization... [2024-07-21 07:13:43.764591] [ 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_pid170113 ] Set seed to 1721538823757877 because --seed was 0 EAL: No free 2048 kB hugepages reported on node 1 [2024-07-21 07:13:43.832047] pci_dpdk.c: 37:dpdk_pci_init: *NOTICE*: In-development DPDK 24.07.0-rc2 is used. There is no support for it in SPDK. Enabled only for validation. [2024-07-21 07:13:43.873945] app.c: 909:spdk_app_start: *NOTICE*: Total cores available: 1 [2024-07-21 07:13:43.913833] app.c: 603:app_setup_trace: *NOTICE*: Tracepoint Group Mask 0x80 specified. [2024-07-21 07:13:43.913879] app.c: 604:app_setup_trace: *NOTICE*: Use 'spdk_trace -s rocksdb -p 170113' to capture a snapshot of events at runtime. [2024-07-21 07:13:43.913893] app.c: 609:app_setup_trace: *NOTICE*: 'spdk_trace' without parameters will also work if this is the only [2024-07-21 07:13:43.913902] app.c: 610:app_setup_trace: *NOTICE*: SPDK application currently running. [2024-07-21 07:13:43.913912] app.c: 611:app_setup_trace: *NOTICE*: Or copy /dev/shm/rocksdb_trace.pid170113 for offline analysis/debug. [2024-07-21 07:13:43.913991] reactor.c: 941:reactor_run: *NOTICE*: Reactor started on core 0 RocksDB: version 8.1.1 Date: Sun Jul 21 07:13:48 2024 CPU: 96 * Intel(R) Xeon(R) Gold 6252 CPU @ 2.10GHz CPUCache: 36608 KB 2024/07/21-07:13:53 ... thread 0: (1000000,1000000) ops and (193490.8,193490.8) ops/second in (5.168205,5.168205) 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 15/12 3.58 GB 62.9 0.6 0.0 0.6 1.4 0.9 0.0 1.6 100.9 257.9 5.62 4.58 15 0.374 575K 600 0.0 0.0 L1 8/8 924.54 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 937.19 MB 0.9 0.1 0.1 0.0 0.1 0.1 0.0 1.1 104.3 104.1 1.33 0.69 1 1.333 130K 0 0.0 0.0 L4 160/0 9.47 GB 1.0 2.5 1.0 1.5 2.4 0.9 0.0 2.6 125.4 123.3 20.32 11.70 13 1.563 152K 0 0.0 0.0 L5 1124/0 92.46 GB 0.9 0.0 0.0 0.0 0.0 0.0 2.3 0.0 0.0 0.0 0.00 0.00 0 0.000 0 0 0.0 0.0 Sum 1324/20 107.33 GB 0.0 3.2 1.1 2.1 4.0 1.9 2.3 4.6 119.3 150.1 27.27 16.97 29 0.940 858K 600 0.0 0.0 Int 0/0 0.00 KB 0.0 3.2 1.1 2.1 4.0 1.9 2.3 4.6 119.3 150.1 27.27 16.97 29 0.940 858K 600 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.2 1.1 2.1 3.1 1.0 0.0 0.0 136.6 134.8 23.81 14.52 16 1.488 858K 600 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 255.5 3.45 2.45 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): 5.3 total, 5.2 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: 4.00 GB write, 768.14 MB/s write, 3.18 GB read, 610.74 MB/s read, 27.3 seconds Interval compaction: 4.00 GB write, 791.48 MB/s write, 3.18 GB read, 629.29 MB/s read, 27.3 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: 4, pending-compaction-bytes-delays: 3, pending-compaction-bytes-stops: 0, total-delays: 3, total-stops: 4, interval: 6 total count ** File Read Latency Histogram By Level [default] ** ** Level 0 read latency histogram (micros): Count: 331203 Average: 10.7812 StdDev: 109.32 Min: 1 Median: 1.6839 Max: 10885 Percentiles: P50: 1.68 P75: 2.42 P99: 203.97 P99.9: 895.35 P99.99: 1736.71 ------------------------------------------------------ [ 0, 1 ] 37880 11.437% 11.437% ## ( 1, 2 ] 186763 56.389% 67.826% ########### ( 2, 3 ] 56651 17.105% 84.931% ### ( 3, 4 ] 19320 5.833% 90.764% # ( 4, 6 ] 17550 5.299% 96.063% # ( 6, 10 ] 4856 1.466% 97.529% ( 10, 15 ] 718 0.217% 97.746% ( 15, 22 ] 112 0.034% 97.780% ( 22, 34 ] 22 0.007% 97.787% ( 34, 51 ] 8 0.002% 97.789% ( 51, 76 ] 3 0.001% 97.790% ( 76, 110 ] 601 0.181% 97.971% ( 110, 170 ] 2752 0.831% 98.802% ( 170, 250 ] 1545 0.466% 99.269% ( 250, 380 ] 847 0.256% 99.524% ( 380, 580 ] 796 0.240% 99.765% ( 580, 870 ] 435 0.131% 99.896% ( 870, 1300 ] 234 0.071% 99.967% ( 1300, 1900 ] 107 0.032% 99.999% ( 1900, 2900 ] 101 0.030% 100.030% ( 2900, 4400 ] 56 0.017% 100.046% ( 4400, 6600 ] 21 0.006% 100.053% ( 6600, 9900 ] 14 0.004% 100.057% ( 9900, 14000 ] 2 0.001% 100.058% ** Level 1 read latency histogram (micros): Count: 276 Average: 798.6413 StdDev: 1718.72 Min: 82 Median: 212.8571 Max: 9057 Percentiles: P50: 212.86 P75: 460.00 P99: 8888.00 P99.9: 9057.00 P99.99: 9057.00 ------------------------------------------------------ ( 76, 110 ] 24 8.696% 8.696% ## ( 110, 170 ] 84 30.435% 39.130% ###### ( 170, 250 ] 56 20.290% 59.420% #### ( 250, 380 ] 33 11.957% 71.377% ## ( 380, 580 ] 25 9.058% 80.435% ## ( 580, 870 ] 8 2.899% 83.333% # ( 870, 1300 ] 18 6.522% 89.855% # ( 1300, 1900 ] 5 1.812% 91.667% ( 1900, 2900 ] 2 0.725% 92.391% ( 2900, 4400 ] 1 0.362% 92.754% ( 4400, 6600 ] 11 3.986% 96.739% # ( 6600, 9900 ] 9 3.261% 100.000% # ** Level 3 read latency histogram (micros): Count: 115591 Average: 8.0287 StdDev: 98.97 Min: 1 Median: 1.8539 Max: 8000 Percentiles: P50: 1.85 P75: 2.79 P99: 9.96 P99.9: 1162.79 P99.99: 2100.79 ------------------------------------------------------ [ 0, 1 ] 4769 4.126% 4.126% # ( 1, 2 ] 62096 53.720% 57.846% ########### ( 2, 3 ] 24951 21.586% 79.432% #### ( 3, 4 ] 10065 8.707% 88.139% ## ( 4, 6 ] 9888 8.554% 96.694% ## ( 6, 10 ] 2693 2.330% 99.023% ( 10, 15 ] 363 0.314% 99.337% ( 15, 22 ] 63 0.055% 99.392% ( 22, 34 ] 13 0.011% 99.403% ( 34, 51 ] 6 0.005% 99.408% ( 51, 76 ] 4 0.003% 99.412% ( 76, 110 ] 27 0.023% 99.435% ( 110, 170 ] 125 0.108% 99.543% ( 170, 250 ] 89 0.077% 99.620% ( 250, 380 ] 78 0.067% 99.688% ( 380, 580 ] 78 0.067% 99.755% ( 580, 870 ] 100 0.087% 99.842% ( 870, 1300 ] 99 0.086% 99.927% ( 1300, 1900 ] 62 0.054% 99.981% ( 1900, 2900 ] 52 0.045% 100.026% ( 2900, 4400 ] 17 0.015% 100.041% ( 4400, 6600 ] 8 0.007% 100.048% ( 6600, 9900 ] 1 0.001% 100.048% ** Level 4 read latency histogram (micros): Count: 308933 Average: 17.9811 StdDev: 177.60 Min: 0 Median: 1.8928 Max: 10195 Percentiles: P50: 1.89 P75: 2.89 P99: 267.67 P99.9: 1820.49 P99.99: 2574.39 ------------------------------------------------------ [ 0, 1 ] 11922 3.859% 3.859% # ( 1, 2 ] 159667 51.683% 55.542% ########## ( 2, 3 ] 67519 21.856% 77.398% #### ( 3, 4 ] 27868 9.021% 86.419% ## ( 4, 6 ] 27208 8.807% 95.226% ## ( 6, 10 ] 8300 2.687% 97.912% # ( 10, 15 ] 1294 0.419% 98.331% ( 15, 22 ] 224 0.073% 98.404% ( 22, 34 ] 51 0.017% 98.420% ( 34, 51 ] 16 0.005% 98.426% ( 51, 76 ] 22 0.007% 98.433% ( 76, 110 ] 232 0.075% 98.508% ( 110, 170 ] 853 0.276% 98.784% ( 170, 250 ] 608 0.197% 98.981% ( 250, 380 ] 439 0.142% 99.123% ( 380, 580 ] 658 0.213% 99.336% ( 580, 870 ] 694 0.225% 99.560% ( 870, 1300 ] 624 0.202% 99.762% ( 1300, 1900 ] 490 0.159% 99.921% ( 1900, 2900 ] 316 0.102% 100.023% ( 2900, 4400 ] 233 0.075% 100.099% ( 4400, 6600 ] 81 0.026% 100.125% ( 6600, 9900 ] 9 0.003% 100.128% ( 9900, 14000 ] 1 0.000% 100.128% ** Level 5 read latency histogram (micros): Count: 22986 Average: 617.2731 StdDev: 993.23 Min: 75 Median: 228.4146 Max: 14138 Percentiles: P50: 228.41 P75: 566.76 P99: 5313.53 P99.9: 8394.48 P99.99: 9878.58 ------------------------------------------------------ ( 51, 76 ] 1 0.004% 0.004% ( 76, 110 ] 1710 7.439% 7.444% # ( 110, 170 ] 6816 29.653% 37.096% ###### ( 170, 250 ] 4062 17.672% 54.768% #### ( 250, 380 ] 2414 10.502% 65.270% ## ( 380, 580 ] 2395 10.419% 75.690% ## ( 580, 870 ] 1703 7.409% 83.098% # ( 870, 1300 ] 1124 4.890% 87.988% # ( 1300, 1900 ] 810 3.524% 91.512% # ( 1900, 2900 ] 837 3.641% 95.154% # ( 2900, 4400 ] 755 3.285% 98.438% # ( 4400, 6600 ] 311 1.353% 99.791% ( 6600, 9900 ] 46 0.200% 99.991% ( 9900, 14000 ] 4 0.017% 100.009% ( 14000, 22000 ] 1 0.004% 100.013% ** DB Stats ** Uptime(secs): 5.3 total, 5.2 interval Cumulative writes: 0 writes, 1000K keys, 0 commit groups, 0.0 writes per commit group, ingest: 0.96 GB, 184.72 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.577 H:M:S, 29.6 percent Interval writes: 0 writes, 1000K keys, 0 commit groups, 0.0 writes per commit group, ingest: 984.19 MB, 190.32 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.577 H:M:S, 30.5 percent Write Stall (count): write-buffer-manager-limit-stops: 0, num-running-compactions: 2 num-running-flushes: 1 2024/07/21-07:13:57 ... thread 0: (1000000,2000000) ops and (267300.0,224484.0) ops/second in (3.741115,8.909320) 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/12 4.57 GB 163.6 1.5 0.0 1.5 3.4 1.8 0.0 1.8 125.7 276.4 12.53 10.97 35 0.358 1599K 1614 0.0 0.0 L1 8/8 924.54 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 937.19 MB 0.9 0.1 0.1 0.0 0.1 0.1 0.0 1.1 104.3 104.1 1.33 0.69 1 1.333 130K 0 0.0 0.0 L4 160/0 9.47 GB 1.0 2.5 1.0 1.5 2.4 0.9 0.0 2.6 125.4 123.3 20.32 11.70 13 1.563 152K 0 0.0 0.0 L5 1124/0 92.46 GB 0.9 0.0 0.0 0.0 0.0 0.0 2.3 0.0 0.0 0.0 0.00 0.00 0 0.000 0 0 0.0 0.0 Sum 1328/20 108.31 GB 0.0 4.2 1.1 3.1 6.0 2.9 2.3 3.2 124.7 178.7 34.18 23.36 49 0.697 1881K 1614 0.0 0.0 Int 0/0 0.00 KB 0.0 4.2 1.1 3.1 6.0 2.9 2.3 3.2 124.7 178.7 34.18 23.36 49 0.697 1881K 1614 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.2 1.1 3.1 4.1 1.0 0.0 0.0 154.4 152.7 27.61 18.23 20 1.381 1881K 1614 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.9 6.57 5.14 29 0.226 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.1 total, 8.9 interval Flush(GB): cumulative 1.846, interval 1.846 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.96 GB write, 673.45 MB/s write, 4.16 GB read, 469.95 MB/s read, 34.2 seconds Interval compaction: 5.96 GB write, 685.32 MB/s write, 4.16 GB read, 478.23 MB/s read, 34.2 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: 4, pending-compaction-bytes-delays: 3, pending-compaction-bytes-stops: 0, total-delays: 5, total-stops: 4, interval: 8 total count ** File Read Latency Histogram By Level [default] ** ** Level 0 read latency histogram (micros): Count: 615465 Average: 14.1547 StdDev: 92.81 Min: 0 Median: 1.5600 Max: 10885 Percentiles: P50: 1.56 P75: 2.06 P99: 248.55 P99.9: 743.14 P99.99: 1129.47 ------------------------------------------------------ [ 0, 1 ] 117382 19.072% 19.072% #### ( 1, 2 ] 339885 55.224% 74.296% ########### ( 2, 3 ] 74689 12.135% 86.432% ## ( 3, 4 ] 24949 4.054% 90.485% # ( 4, 6 ] 20897 3.395% 93.881% # ( 6, 10 ] 5562 0.904% 94.784% ( 10, 15 ] 971 0.158% 94.942% ( 15, 22 ] 153 0.025% 94.967% ( 22, 34 ] 26 0.004% 94.971% ( 34, 51 ] 10 0.002% 94.973% ( 51, 76 ] 4 0.001% 94.973% ( 76, 110 ] 3609 0.586% 95.560% ( 110, 170 ] 14538 2.362% 97.922% ( 170, 250 ] 6759 1.098% 99.020% ( 250, 380 ] 2665 0.433% 99.453% ( 380, 580 ] 2279 0.370% 99.823% ( 580, 870 ] 840 0.136% 99.960% ( 870, 1300 ] 309 0.050% 100.010% ( 1300, 1900 ] 113 0.018% 100.028% ( 1900, 2900 ] 117 0.019% 100.047% ( 2900, 4400 ] 65 0.011% 100.058% ( 4400, 6600 ] 21 0.003% 100.061% ( 6600, 9900 ] 14 0.002% 100.064% ( 9900, 14000 ] 2 0.000% 100.064% ** Level 1 read latency histogram (micros): Count: 880 Average: 383.5080 StdDev: 1012.48 Min: 82 Median: 163.2787 Max: 9057 Percentiles: P50: 163.28 P75: 243.06 P99: 6673.33 P99.9: 9057.00 P99.99: 9057.00 ------------------------------------------------------ ( 76, 110 ] 115 13.068% 13.068% ### ( 110, 170 ] 366 41.591% 54.659% ######## ( 170, 250 ] 196 22.273% 76.932% #### ( 250, 380 ] 80 9.091% 86.023% ## ( 380, 580 ] 58 6.591% 92.614% # ( 580, 870 ] 16 1.818% 94.432% ( 870, 1300 ] 19 2.159% 96.591% ( 1300, 1900 ] 5 0.568% 97.159% ( 1900, 2900 ] 4 0.455% 97.614% ( 2900, 4400 ] 1 0.114% 97.727% ( 4400, 6600 ] 11 1.250% 98.977% ( 6600, 9900 ] 9 1.023% 100.000% ** Level 3 read latency histogram (micros): Count: 116334 Average: 9.1599 StdDev: 100.38 Min: 1 Median: 1.8599 Max: 8000 Percentiles: P50: 1.86 P75: 2.82 P99: 123.15 P99.9: 1163.90 P99.99: 2118.59 ------------------------------------------------------ [ 0, 1 ] 4769 4.099% 4.099% # ( 1, 2 ] 62096 53.377% 57.477% ########### ( 2, 3 ] 24951 21.448% 78.924% #### ( 3, 4 ] 10065 8.652% 87.576% ## ( 4, 6 ] 9888 8.500% 96.076% ## ( 6, 10 ] 2693 2.315% 98.391% ( 10, 15 ] 363 0.312% 98.703% ( 15, 22 ] 63 0.054% 98.757% ( 22, 34 ] 13 0.011% 98.768% ( 34, 51 ] 6 0.005% 98.773% ( 51, 76 ] 4 0.003% 98.777% ( 76, 110 ] 149 0.128% 98.905% ( 110, 170 ] 505 0.434% 99.339% ( 170, 250 ] 227 0.195% 99.534% ( 250, 380 ] 129 0.111% 99.645% ( 380, 580 ] 118 0.101% 99.746% ( 580, 870 ] 111 0.095% 99.842% ( 870, 1300 ] 99 0.085% 99.927% ( 1300, 1900 ] 62 0.053% 99.980% ( 1900, 2900 ] 52 0.045% 100.025% ( 2900, 4400 ] 18 0.015% 100.040% ( 4400, 6600 ] 8 0.007% 100.047% ( 6600, 9900 ] 1 0.001% 100.048% ** Level 4 read latency histogram (micros): Count: 315565 Average: 21.2074 StdDev: 178.19 Min: 0 Median: 1.9131 Max: 10195 Percentiles: P50: 1.91 P75: 2.96 P99: 387.31 P99.9: 1814.12 P99.99: 2571.21 ------------------------------------------------------ [ 0, 1 ] 11922 3.778% 3.778% # ( 1, 2 ] 159746 50.622% 54.400% ########## ( 2, 3 ] 67714 21.458% 75.858% #### ( 3, 4 ] 28007 8.875% 84.733% ## ( 4, 6 ] 27426 8.691% 93.424% ## ( 6, 10 ] 8323 2.637% 96.062% # ( 10, 15 ] 1300 0.412% 96.474% ( 15, 22 ] 224 0.071% 96.545% ( 22, 34 ] 51 0.016% 96.561% ( 34, 51 ] 16 0.005% 96.566% ( 51, 76 ] 22 0.007% 96.573% ( 76, 110 ] 1094 0.347% 96.920% ( 110, 170 ] 3865 1.225% 98.145% ( 170, 250 ] 1792 0.568% 98.712% ( 250, 380 ] 870 0.276% 98.988% ( 380, 580 ] 1022 0.324% 99.312% ( 580, 870 ] 796 0.252% 99.564% ( 870, 1300 ] 637 0.202% 99.766% ( 1300, 1900 ] 493 0.156% 99.922% ( 1900, 2900 ] 318 0.101% 100.023% ( 2900, 4400 ] 233 0.074% 100.097% ( 4400, 6600 ] 81 0.026% 100.123% ( 6600, 9900 ] 9 0.003% 100.125% ( 9900, 14000 ] 1 0.000% 100.126% ** Level 5 read latency histogram (micros): Count: 72615 Average: 326.1211 StdDev: 603.67 Min: 73 Median: 161.8829 Max: 14138 Percentiles: P50: 161.88 P75: 249.08 P99: 3666.55 P99.9: 6404.65 P99.99: 9307.33 ------------------------------------------------------ ( 51, 76 ] 12 0.017% 0.017% ( 76, 110 ] 8686 11.962% 11.978% ## ( 110, 170 ] 31929 43.970% 55.948% ######### ( 170, 250 ] 13995 19.273% 75.221% #### ( 250, 380 ] 6044 8.323% 83.545% ## ( 380, 580 ] 5321 7.328% 90.872% # ( 580, 870 ] 2565 3.532% 94.405% # ( 870, 1300 ] 1251 1.723% 96.128% ( 1300, 1900 ] 827 1.139% 97.266% ( 1900, 2900 ] 872 1.201% 98.467% ( 2900, 4400 ] 757 1.042% 99.510% ( 4400, 6600 ] 311 0.428% 99.938% ( 6600, 9900 ] 46 0.063% 100.001% ( 9900, 14000 ] 4 0.006% 100.007% ( 14000, 22000 ] 1 0.001% 100.008% ** DB Stats ** Uptime(secs): 9.1 total, 3.7 interval Cumulative writes: 0 writes, 2000K keys, 0 commit groups, 0.0 writes per commit group, ingest: 1.92 GB, 217.05 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.878 H:M:S, 20.7 percent Interval writes: 0 writes, 1000K keys, 0 commit groups, 0.0 writes per commit group, ingest: 984.19 MB, 263.08 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.302 H:M:S, 8.1 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: 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 : 202.721 micros/op 19713 ops/sec 60.111 seconds 1184996 operations; 19.1 MB/s (310999 of 310999 found) Microseconds per read: Count: 1184996 Average: 202.7231 StdDev: 458.04 Min: 2 Median: 167.4037 Max: 58120 Percentiles: P50: 167.40 P75: 223.04 P99: 592.72 P99.9: 4878.29 P99.99: 21583.02 ------------------------------------------------------ ( 1, 2 ] 5 0.000% 0.000% ( 2, 3 ] 55 0.005% 0.005% ( 3, 4 ] 55 0.005% 0.010% ( 4, 6 ] 143 0.012% 0.022% ( 6, 10 ] 594 0.050% 0.072% ( 10, 15 ] 5093 0.430% 0.502% ( 15, 22 ] 2737 0.231% 0.733% ( 22, 34 ] 15237 1.286% 2.018% ( 34, 51 ] 1696 0.143% 2.162% ( 51, 76 ] 160 0.014% 2.175% ( 76, 110 ] 19515 1.647% 3.822% ( 110, 170 ] 571958 48.267% 52.089% ########## ( 170, 250 ] 409495 34.557% 86.645% ####### ( 250, 380 ] 113046 9.540% 96.185% ## ( 380, 580 ] 32995 2.784% 98.969% # ( 580, 870 ] 8251 0.696% 99.666% ( 870, 1300 ] 1702 0.144% 99.809% ( 1300, 1900 ] 392 0.033% 99.842% ( 1900, 2900 ] 322 0.027% 99.870% ( 2900, 4400 ] 300 0.025% 99.895% ( 4400, 6600 ] 276 0.023% 99.918% ( 6600, 9900 ] 304 0.026% 99.944% ( 9900, 14000 ] 301 0.025% 99.969% ( 14000, 22000 ] 259 0.022% 99.991% ( 22000, 33000 ] 84 0.007% 99.998% ( 33000, 50000 ] 19 0.002% 100.000% ( 50000, 75000 ] 2 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 : 18064067 rocksdb.bloom.filter.full.positive COUNT : 1361158 rocksdb.bloom.filter.full.true.positive COUNT : 1184567 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 : 429 rocksdb.memtable.miss COUNT : 1184567 rocksdb.l0.hit COUNT : 60183 rocksdb.l1.hit COUNT : 10598 rocksdb.l2andup.hit COUNT : 1113786 rocksdb.compaction.key.drop.new COUNT : 76215 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 : 2563788 rocksdb.number.keys.read COUNT : 1184996 rocksdb.number.keys.updated COUNT : 0 rocksdb.bytes.written COUNT : 2645829216 rocksdb.bytes.read COUNT : 1184996000 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 : 1418 rocksdb.no.file.errors COUNT : 0 rocksdb.stall.micros COUNT : 50037471 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 : 9023004621 rocksdb.compact.write.bytes COUNT : 8949235645 rocksdb.flush.write.bytes COUNT : 2642426706 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 : 46 rocksdb.number.superversion_acquires COUNT : 437 rocksdb.number.superversion_releases COUNT : 415 rocksdb.number.superversion_cleanups COUNT : 108 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 : 2620957696 rocksdb.memtable.garbage.bytes.at.flush COUNT : 875520 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 : 14267493263 rocksdb.non.last.level.read.count COUNT : 3301576 rocksdb.block.checksum.compute.count COUNT : 3559543 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 : 166.439997 P95 : 363.318539 P99 : 590.090527 P100 : 58119.000000 COUNT : 1184996 SUM : 239155280 rocksdb.db.write.micros P50 : 1.581439 P95 : 5.343302 P99 : 1023.995136 P100 : 334175.000000 COUNT : 2563788 SUM : 56719826 rocksdb.compaction.times.micros P50 : 1171666.666667 P95 : 2833333.333333 P99 : 5407565.000000 P100 : 5407565.000000 COUNT : 24 SUM : 35758341 rocksdb.compaction.times.cpu_micros P50 : 952727.272727 P95 : 1853333.333333 P99 : 5361631.000000 P100 : 5361631.000000 COUNT : 24 SUM : 26273656 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 : 765.277778 P95 : 10105.000000 P99 : 96350.000000 P100 : 103704.000000 COUNT : 39 SUM : 144123 rocksdb.compaction.outfile.sync.micros P50 : 10925.000000 P95 : 162000.000000 P99 : 229733.333333 P100 : 236836.000000 COUNT : 76 SUM : 2848449 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.888889 P95 : 5197.500000 P99 : 7263.000000 P100 : 7263.000000 COUNT : 71 SUM : 63596 rocksdb.table.open.io.micros P50 : 7207.462687 P95 : 18142.372881 P99 : 21987.796610 P100 : 24843.000000 COUNT : 1418 SUM : 11201768 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.767141 P95 : 154.646024 P99 : 237.229913 P100 : 12633.000000 COUNT : 2186267 SUM : 63020931 rocksdb.read.block.get.micros P50 : 138.267669 P95 : 240.190254 P99 : 522.129927 P100 : 14598.000000 COUNT : 1371822 SUM : 202545586 rocksdb.write.raw.block.micros P50 : 0.533202 P95 : 1.283037 P99 : 3.375598 P100 : 8872.000000 COUNT : 2807347 SUM : 6093172 rocksdb.numfiles.in.singlecompaction P50 : 3.416667 P95 : 7.400000 P99 : 16.000000 P100 : 16.000000 COUNT : 33 SUM : 139 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.580342 P95 : 2170.221230 P99 : 2756.372987 P100 : 333991.000000 COUNT : 226533 SUM : 50055705 rocksdb.sst.read.micros P50 : 5.917893 P95 : 200.355858 P99 : 395.332195 P100 : 14138.000000 COUNT : 3301591 SUM : 253266678 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 : 1184996 SUM : 1184996000 rocksdb.bytes.per.write P50 : 1032.000000 P95 : 1032.000000 P99 : 1032.000000 P100 : 1032.000000 COUNT : 2563788 SUM : 2645829216 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 : 213333.333333 P95 : 479750.000000 P99 : 571538.000000 P100 : 571538.000000 COUNT : 39 SUM : 8599591 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 : 94 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