[2024-11-20 22:43:32.882666] Starting SPDK v25.01-pre git sha1 557f022f6 / DPDK 23.11.0 initialization... [2024-11-20 22:43:32.882810] [ 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_pid380291 ] Set seed to 1732139012879149 because --seed was 0 EAL: No free 2048 kB hugepages reported on node 1 [2024-11-20 22:43:32.993180] app.c: 919:spdk_app_start: *NOTICE*: Total cores available: 1 [2024-11-20 22:43:33.016424] app.c: 612:app_setup_trace: *NOTICE*: Tracepoint Group Mask 0x80 specified. [2024-11-20 22:43:33.016472] app.c: 613:app_setup_trace: *NOTICE*: Use 'spdk_trace -s rocksdb -p 380291' to capture a snapshot of events at runtime. [2024-11-20 22:43:33.016497] app.c: 618:app_setup_trace: *NOTICE*: 'spdk_trace' without parameters will also work if this is the only [2024-11-20 22:43:33.016508] app.c: 619:app_setup_trace: *NOTICE*: SPDK application currently running. [2024-11-20 22:43:33.016517] app.c: 620:app_setup_trace: *NOTICE*: Or copy /dev/shm/rocksdb_trace.pid380291 for offline analysis/debug. [2024-11-20 22:43:33.017074] reactor.c:1005:reactor_run: *NOTICE*: Reactor started on core 0 RocksDB: version 8.1.1 Date: Wed Nov 20 22:43:37 2024 CPU: 96 * Intel(R) Xeon(R) Gold 6252 CPU @ 2.10GHz CPUCache: 36608 KB 2024/11/20-22:43:41 ... thread 0: (1000000,1000000) ops and (245685.7,245685.7) ops/second in (4.070241,4.070241) 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 2.70 GB 62.9 0.6 0.0 0.6 1.4 0.9 0.0 1.6 102.5 261.9 5.53 4.53 15 0.369 575K 588 0.0 0.0 L1 16/16 1.74 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 18/0 879.97 MB 0.9 0.2 0.1 0.1 0.2 0.1 0.0 1.5 163.6 162.2 1.14 0.83 1 1.142 0 0 0.0 0.0 L4 161/0 9.53 GB 1.0 1.4 0.7 0.6 1.3 0.7 0.0 1.8 157.8 155.4 8.76 6.39 8 1.096 126K 0 0.0 0.0 L5 1116/0 91.31 GB 0.9 0.0 0.0 0.0 0.0 0.0 1.5 0.0 0.0 0.0 0.00 0.00 0 0.000 0 0 0.0 0.0 Sum 1329/31 106.16 GB 0.0 2.1 0.9 1.2 2.9 1.7 1.5 3.4 138.4 194.1 15.44 11.75 24 0.643 701K 588 0.0 0.0 Int 0/0 0.00 KB 0.0 2.1 0.9 1.2 2.9 1.7 1.5 3.4 138.4 194.1 15.44 11.75 24 0.643 701K 588 0.0 0.0 ** Compaction Stats [default] ** Priority Files Size Score Read(GB) Rn(GB) Rnp1(GB) Write(GB) Wnew(GB) Moved(GB) W-Amp Rd(MB/s) Wr(MB/s) Comp(sec) CompMergeCPU(sec) Comp(cnt) Avg(sec) KeyIn KeyDrop Rblob(GB) Wblob(GB) --------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------- Low 0/0 0.00 KB 0.0 2.1 0.9 1.2 2.1 0.8 0.0 0.0 177.0 175.1 12.07 9.34 11 1.098 701K 588 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 262.2 3.36 2.42 13 0.259 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.2 total, 4.1 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: 2.93 GB write, 707.33 MB/s write, 2.09 GB read, 504.39 MB/s read, 15.4 seconds Interval compaction: 2.93 GB write, 735.73 MB/s write, 2.09 GB read, 524.64 MB/s read, 15.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: 3, pending-compaction-bytes-delays: 0, pending-compaction-bytes-stops: 0, total-delays: 2, total-stops: 3, interval: 5 total count ** File Read Latency Histogram By Level [default] ** ** Level 0 read latency histogram (micros): Count: 311335 Average: 9.6662 StdDev: 90.17 Min: 1 Median: 1.5980 Max: 7035 Percentiles: P50: 1.60 P75: 2.05 P99: 172.98 P99.9: 1044.75 P99.99: 1749.52 ------------------------------------------------------ [ 0, 1 ] 43381 13.934% 13.934% ### ( 1, 2 ] 187762 60.309% 74.243% ############ ( 2, 3 ] 44532 14.304% 88.546% ### ( 3, 4 ] 15249 4.898% 93.444% # ( 4, 6 ] 10360 3.328% 96.772% # ( 6, 10 ] 3239 1.040% 97.812% ( 10, 15 ] 544 0.175% 97.987% ( 15, 22 ] 124 0.040% 98.027% ( 22, 34 ] 29 0.009% 98.036% ( 34, 51 ] 10 0.003% 98.039% ( 51, 76 ] 7 0.002% 98.041% ( 76, 110 ] 580 0.186% 98.228% ( 110, 170 ] 2361 0.758% 98.986% ( 170, 250 ] 1199 0.385% 99.371% ( 250, 380 ] 614 0.197% 99.568% ( 380, 580 ] 559 0.180% 99.748% ( 580, 870 ] 369 0.119% 99.866% ( 870, 1300 ] 260 0.084% 99.950% ( 1300, 1900 ] 168 0.054% 100.004% ( 1900, 2900 ] 92 0.030% 100.033% ( 2900, 4400 ] 40 0.013% 100.046% ( 4400, 6600 ] 15 0.005% 100.051% ( 6600, 9900 ] 1 0.000% 100.051% ** Level 1 read latency histogram (micros): Count: 346 Average: 538.1358 StdDev: 809.78 Min: 86 Median: 219.3333 Max: 5226 Percentiles: P50: 219.33 P75: 507.03 P99: 4697.00 P99.9: 5226.00 P99.99: 5226.00 ------------------------------------------------------ ( 76, 110 ] 20 5.780% 5.780% # ( 110, 170 ] 116 33.526% 39.306% ####### ( 170, 250 ] 60 17.341% 56.647% ### ( 250, 380 ] 40 11.561% 68.208% ## ( 380, 580 ] 37 10.694% 78.902% ## ( 580, 870 ] 21 6.069% 84.971% # ( 870, 1300 ] 19 5.491% 90.462% # ( 1300, 1900 ] 8 2.312% 92.775% ( 1900, 2900 ] 12 3.468% 96.243% # ( 2900, 4400 ] 9 2.601% 98.844% # ( 4400, 6600 ] 4 1.156% 100.000% ** Level 3 read latency histogram (micros): Count: 86022 Average: 6.8801 StdDev: 72.70 Min: 1 Median: 1.8105 Max: 4867 Percentiles: P50: 1.81 P75: 2.69 P99: 10.94 P99.9: 1005.96 P99.99: 1619.06 ------------------------------------------------------ [ 0, 1 ] 4170 4.848% 4.848% # ( 1, 2 ] 47921 55.708% 60.555% ########### ( 2, 3 ] 17947 20.863% 81.419% #### ( 3, 4 ] 6895 8.015% 89.434% ## ( 4, 6 ] 6243 7.257% 96.692% # ( 6, 10 ] 1926 2.239% 98.931% ( 10, 15 ] 317 0.369% 99.299% ( 15, 22 ] 62 0.072% 99.371% ( 22, 34 ] 15 0.017% 99.389% ( 34, 51 ] 7 0.008% 99.397% ( 51, 76 ] 5 0.006% 99.402% ( 76, 110 ] 25 0.029% 99.432% ( 110, 170 ] 103 0.120% 99.551% ( 170, 250 ] 68 0.079% 99.630% ( 250, 380 ] 53 0.062% 99.692% ( 380, 580 ] 77 0.090% 99.781% ( 580, 870 ] 77 0.090% 99.871% ( 870, 1300 ] 79 0.092% 99.963% ( 1300, 1900 ] 44 0.051% 100.014% ( 1900, 2900 ] 17 0.020% 100.034% ( 2900, 4400 ] 6 0.007% 100.041% ( 4400, 6600 ] 1 0.001% 100.042% ** Level 4 read latency histogram (micros): Count: 98659 Average: 23.8534 StdDev: 187.39 Min: 1 Median: 1.8645 Max: 7059 Percentiles: P50: 1.86 P75: 2.84 P99: 633.10 P99.9: 2264.16 P99.99: 3012.21 ------------------------------------------------------ [ 0, 1 ] 4785 4.850% 4.850% # ( 1, 2 ] 51527 52.227% 57.077% ########## ( 2, 3 ] 21039 21.325% 78.402% #### ( 3, 4 ] 8363 8.477% 86.879% ## ( 4, 6 ] 7049 7.145% 94.024% # ( 6, 10 ] 2422 2.455% 96.479% ( 10, 15 ] 438 0.444% 96.923% ( 15, 22 ] 73 0.074% 96.997% ( 22, 34 ] 23 0.023% 97.020% ( 34, 51 ] 7 0.007% 97.027% ( 51, 76 ] 4 0.004% 97.031% ( 76, 110 ] 153 0.155% 97.186% ( 110, 170 ] 649 0.658% 97.844% ( 170, 250 ] 386 0.391% 98.235% ( 250, 380 ] 330 0.334% 98.570% ( 380, 580 ] 353 0.358% 98.928% ( 580, 870 ] 390 0.395% 99.323% ( 870, 1300 ] 308 0.312% 99.635% ( 1300, 1900 ] 214 0.217% 99.852% ( 1900, 2900 ] 130 0.132% 99.984% ( 2900, 4400 ] 82 0.083% 100.067% ( 4400, 6600 ] 18 0.018% 100.085% ( 6600, 9900 ] 1 0.001% 100.086% ** Level 5 read latency histogram (micros): Count: 18853 Average: 588.6281 StdDev: 888.13 Min: 75 Median: 226.8701 Max: 10239 Percentiles: P50: 226.87 P75: 567.04 P99: 4354.58 P99.9: 6491.34 P99.99: 9334.41 ------------------------------------------------------ ( 51, 76 ] 2 0.011% 0.011% ( 76, 110 ] 1545 8.195% 8.206% ## ( 110, 170 ] 5690 30.181% 38.386% ###### ( 170, 250 ] 3080 16.337% 54.723% ### ( 250, 380 ] 2029 10.762% 65.486% ## ( 380, 580 ] 1918 10.173% 75.659% ## ( 580, 870 ] 1365 7.240% 82.899% # ( 870, 1300 ] 940 4.986% 87.885% # ( 1300, 1900 ] 713 3.782% 91.667% # ( 1900, 2900 ] 789 4.185% 95.852% # ( 2900, 4400 ] 612 3.246% 99.098% # ( 4400, 6600 ] 159 0.843% 99.942% ( 6600, 9900 ] 11 0.058% 100.000% ( 9900, 14000 ] 1 0.005% 100.005% ** DB Stats ** Uptime(secs): 4.2 total, 4.1 interval Cumulative writes: 0 writes, 1000K keys, 0 commit groups, 0.0 writes per commit group, ingest: 0.96 GB, 232.34 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.390 H:M:S, 9.2 percent Interval writes: 0 writes, 1000K keys, 0 commit groups, 0.0 writes per commit group, ingest: 984.19 MB, 241.64 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.390 H:M:S, 9.6 percent Write Stall (count): write-buffer-manager-limit-stops: 0, num-running-compactions: 2 num-running-flushes: 1 2024/11/20-22:43:48 ... thread 0: (1000000,2000000) ops and (135407.7,174591.0) ops/second in (7.385104,11.455345) 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.68 GB 163.2 2.6 0.0 2.6 4.4 1.8 0.0 2.4 161.7 276.8 16.36 14.84 36 0.454 2685K 6002 0.0 0.0 L1 16/16 1.74 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 18/0 879.97 MB 0.9 0.2 0.1 0.1 0.2 0.1 0.0 1.5 163.6 162.2 1.14 0.83 1 1.142 0 0 0.0 0.0 L4 161/0 9.53 GB 1.0 1.4 0.7 0.6 1.3 0.7 0.0 1.8 157.8 155.4 8.76 6.39 8 1.096 126K 0 0.0 0.0 L5 1116/0 91.31 GB 0.9 0.0 0.0 0.0 0.0 0.0 1.5 0.0 0.0 0.0 0.00 0.00 0 0.000 0 0 0.0 0.0 Sum 1329/31 107.14 GB 0.0 4.1 0.9 3.3 5.9 2.7 1.5 3.2 160.4 231.3 26.27 22.06 45 0.584 2811K 6002 0.0 0.0 Int 0/0 0.00 KB 0.0 4.1 0.9 3.3 5.9 2.7 1.5 3.2 160.4 231.3 26.27 22.06 45 0.584 2811K 6002 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.1 0.9 3.3 4.1 0.8 0.0 0.0 213.5 212.1 19.74 16.90 16 1.234 2811K 6002 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 289.6 6.53 5.16 29 0.225 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): 11.6 total, 11.5 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.93 GB write, 522.92 MB/s write, 4.12 GB read, 362.67 MB/s read, 26.3 seconds Interval compaction: 5.93 GB write, 530.38 MB/s write, 4.12 GB read, 367.85 MB/s read, 26.3 seconds Write Stall (count): cf-l0-file-count-limit-delays-with-ongoing-compaction: 12, cf-l0-file-count-limit-stops-with-ongoing-compaction: 0, l0-file-count-limit-delays: 12, l0-file-count-limit-stops: 0, memtable-limit-delays: 0, memtable-limit-stops: 3, pending-compaction-bytes-delays: 0, pending-compaction-bytes-stops: 0, total-delays: 12, total-stops: 3, interval: 15 total count ** File Read Latency Histogram By Level [default] ** ** Level 0 read latency histogram (micros): Count: 880950 Average: 15.5001 StdDev: 75.03 Min: 0 Median: 1.4799 Max: 7035 Percentiles: P50: 1.48 P75: 1.93 P99: 241.52 P99.9: 655.28 P99.99: 936.99 ------------------------------------------------------ [ 0, 1 ] 205350 23.310% 23.310% ##### ( 1, 2 ] 489924 55.613% 78.923% ########### ( 2, 3 ] 76946 8.734% 87.658% ## ( 3, 4 ] 26342 2.990% 90.648% # ( 4, 6 ] 15937 1.809% 92.457% ( 6, 10 ] 4436 0.504% 92.960% ( 10, 15 ] 749 0.085% 93.045% ( 15, 22 ] 154 0.017% 93.063% ( 22, 34 ] 34 0.004% 93.067% ( 34, 51 ] 12 0.001% 93.068% ( 51, 76 ] 12 0.001% 93.070% ( 76, 110 ] 9949 1.129% 94.199% ( 110, 170 ] 32491 3.688% 97.887% # ( 170, 250 ] 10968 1.245% 99.132% ( 250, 380 ] 3662 0.416% 99.548% ( 380, 580 ] 2845 0.323% 99.871% ( 580, 870 ] 998 0.113% 99.984% ( 870, 1300 ] 346 0.039% 100.023% ( 1300, 1900 ] 184 0.021% 100.044% ( 1900, 2900 ] 114 0.013% 100.057% ( 2900, 4400 ] 58 0.007% 100.064% ( 4400, 6600 ] 15 0.002% 100.065% ( 6600, 9900 ] 1 0.000% 100.065% ** Level 1 read latency histogram (micros): Count: 2646 Average: 225.8927 StdDev: 339.72 Min: 78 Median: 151.0641 Max: 5226 Percentiles: P50: 151.06 P75: 209.14 P99: 2002.67 P99.9: 5144.70 P99.99: 5226.00 ------------------------------------------------------ ( 76, 110 ] 384 14.512% 14.512% ### ( 110, 170 ] 1372 51.852% 66.364% ########## ( 170, 250 ] 467 17.649% 84.014% #### ( 250, 380 ] 176 6.652% 90.665% # ( 380, 580 ] 140 5.291% 95.956% # ( 580, 870 ] 51 1.927% 97.884% ( 870, 1300 ] 20 0.756% 98.639% ( 1300, 1900 ] 8 0.302% 98.942% ( 1900, 2900 ] 15 0.567% 99.509% ( 2900, 4400 ] 9 0.340% 99.849% ( 4400, 6600 ] 4 0.151% 100.000% ** Level 3 read latency histogram (micros): Count: 87448 Average: 9.5104 StdDev: 76.52 Min: 1 Median: 1.8253 Max: 4867 Percentiles: P50: 1.83 P75: 2.75 P99: 162.84 P99.9: 1012.72 P99.99: 1636.74 ------------------------------------------------------ [ 0, 1 ] 4170 4.769% 4.769% # ( 1, 2 ] 47924 54.803% 59.571% ########### ( 2, 3 ] 17962 20.540% 80.112% #### ( 3, 4 ] 6912 7.904% 88.016% ## ( 4, 6 ] 6257 7.155% 95.171% # ( 6, 10 ] 1927 2.204% 97.374% ( 10, 15 ] 317 0.363% 97.737% ( 15, 22 ] 62 0.071% 97.808% ( 22, 34 ] 15 0.017% 97.825% ( 34, 51 ] 7 0.008% 97.833% ( 51, 76 ] 6 0.007% 97.840% ( 76, 110 ] 251 0.287% 98.127% ( 110, 170 ] 867 0.991% 99.118% ( 170, 250 ] 303 0.346% 99.465% ( 250, 380 ] 124 0.142% 99.607% ( 380, 580 ] 140 0.160% 99.767% ( 580, 870 ] 90 0.103% 99.870% ( 870, 1300 ] 80 0.091% 99.961% ( 1300, 1900 ] 45 0.051% 100.013% ( 1900, 2900 ] 18 0.021% 100.033% ( 2900, 4400 ] 6 0.007% 100.040% ( 4400, 6600 ] 1 0.001% 100.041% ** Level 4 read latency histogram (micros): Count: 111527 Average: 39.7552 StdDev: 186.42 Min: 1 Median: 1.9880 Max: 7059 Percentiles: P50: 1.99 P75: 3.69 P99: 636.87 P99.9: 2194.57 P99.99: 3005.67 ------------------------------------------------------ [ 0, 1 ] 4785 4.290% 4.290% # ( 1, 2 ] 51597 46.264% 50.555% ######### ( 2, 3 ] 21343 19.137% 69.692% #### ( 3, 4 ] 8640 7.747% 77.439% ## ( 4, 6 ] 7457 6.686% 84.125% # ( 6, 10 ] 2461 2.207% 86.332% ( 10, 15 ] 452 0.405% 86.737% ( 15, 22 ] 73 0.065% 86.802% ( 22, 34 ] 23 0.021% 86.823% ( 34, 51 ] 7 0.006% 86.829% ( 51, 76 ] 6 0.005% 86.835% ( 76, 110 ] 2029 1.819% 88.654% ( 110, 170 ] 7092 6.359% 95.013% # ( 170, 250 ] 2433 2.182% 97.194% ( 250, 380 ] 1033 0.926% 98.121% ( 380, 580 ] 877 0.786% 98.907% ( 580, 870 ] 529 0.474% 99.381% ( 870, 1300 ] 321 0.288% 99.669% ( 1300, 1900 ] 218 0.195% 99.865% ( 1900, 2900 ] 134 0.120% 99.985% ( 2900, 4400 ] 83 0.074% 100.059% ( 4400, 6600 ] 18 0.016% 100.075% ( 6600, 9900 ] 1 0.001% 100.076% ** Level 5 read latency histogram (micros): Count: 123150 Average: 238.0800 StdDev: 392.60 Min: 72 Median: 151.0122 Max: 10239 Percentiles: P50: 151.01 P75: 210.06 P99: 2356.26 P99.9: 4992.89 P99.99: 6526.46 ------------------------------------------------------ ( 51, 76 ] 9 0.007% 0.007% ( 76, 110 ] 18361 14.909% 14.917% ### ( 110, 170 ] 63208 51.326% 66.243% ########## ( 170, 250 ] 21538 17.489% 83.732% ### ( 250, 380 ] 7742 6.287% 90.019% # ( 380, 580 ] 6325 5.136% 95.155% # ( 580, 870 ] 2529 2.054% 97.208% ( 870, 1300 ] 1086 0.882% 98.090% ( 1300, 1900 ] 745 0.605% 98.695% ( 1900, 2900 ] 823 0.668% 99.363% ( 2900, 4400 ] 618 0.502% 99.865% ( 4400, 6600 ] 159 0.129% 99.994% ( 6600, 9900 ] 11 0.009% 100.003% ( 9900, 14000 ] 1 0.001% 100.004% ** DB Stats ** Uptime(secs): 11.6 total, 7.4 interval Cumulative writes: 0 writes, 2000K keys, 0 commit groups, 0.0 writes per commit group, ingest: 1.92 GB, 169.38 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:4.132 H:M:S, 35.6 percent Interval writes: 0 writes, 1000K keys, 0 commit groups, 0.0 writes per commit group, ingest: 984.19 MB, 133.27 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:3.742 H:M:S, 50.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: 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 : 201.953 micros/op 19783 ops/sec 60.152 seconds 1189996 operations; 19.2 MB/s (291999 of 291999 found) Microseconds per read: Count: 1189996 Average: 201.9548 StdDev: 386.38 Min: 2 Median: 165.3880 Max: 48781 Percentiles: P50: 165.39 P75: 225.23 P99: 622.34 P99.9: 4478.36 P99.99: 19423.75 ------------------------------------------------------ ( 1, 2 ] 28 0.002% 0.002% ( 2, 3 ] 85 0.007% 0.009% ( 3, 4 ] 62 0.005% 0.015% ( 4, 6 ] 71 0.006% 0.021% ( 6, 10 ] 920 0.077% 0.098% ( 10, 15 ] 7318 0.615% 0.713% ( 15, 22 ] 2886 0.243% 0.955% ( 22, 34 ] 10353 0.870% 1.825% ( 34, 51 ] 1629 0.137% 1.962% ( 51, 76 ] 205 0.017% 1.980% ( 76, 110 ] 36326 3.053% 5.032% # ( 110, 170 ] 579673 48.712% 53.744% ########## ( 170, 250 ] 366376 30.788% 84.532% ###### ( 250, 380 ] 132411 11.127% 95.659% ## ( 380, 580 ] 38395 3.226% 98.886% # ( 580, 870 ] 9302 0.782% 99.668% ( 870, 1300 ] 1814 0.152% 99.820% ( 1300, 1900 ] 347 0.029% 99.849% ( 1900, 2900 ] 317 0.027% 99.876% ( 2900, 4400 ] 276 0.023% 99.899% ( 4400, 6600 ] 337 0.028% 99.927% ( 6600, 9900 ] 385 0.032% 99.960% ( 9900, 14000 ] 241 0.020% 99.980% ( 14000, 22000 ] 177 0.015% 99.995% ( 22000, 33000 ] 53 0.004% 99.999% ( 33000, 50000 ] 9 0.001% 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 : 20337593 rocksdb.bloom.filter.full.positive COUNT : 1387567 rocksdb.bloom.filter.full.true.positive COUNT : 1189569 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 : 427 rocksdb.memtable.miss COUNT : 1189569 rocksdb.l0.hit COUNT : 50054 rocksdb.l1.hit COUNT : 20563 rocksdb.l2andup.hit COUNT : 1118952 rocksdb.compaction.key.drop.new COUNT : 58880 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 : 2635129 rocksdb.number.keys.read COUNT : 1189996 rocksdb.number.keys.updated COUNT : 0 rocksdb.bytes.written COUNT : 2719453128 rocksdb.bytes.read COUNT : 1189996000 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 : 1400 rocksdb.no.file.errors COUNT : 0 rocksdb.stall.micros COUNT : 49288384 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 : 8640602636 rocksdb.compact.write.bytes COUNT : 8584777553 rocksdb.flush.write.bytes COUNT : 2708515806 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 : 56 rocksdb.number.superversion_acquires COUNT : 417 rocksdb.number.superversion_releases COUNT : 398 rocksdb.number.superversion_cleanups COUNT : 105 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 : 2686512128 rocksdb.memtable.garbage.bytes.at.flush COUNT : 899072 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 : 14028409537 rocksdb.non.last.level.read.count COUNT : 3245763 rocksdb.block.checksum.compute.count COUNT : 3494016 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 : 164.593871 P95 : 371.801501 P99 : 620.547985 P100 : 48779.000000 COUNT : 1189996 SUM : 239322766 rocksdb.db.write.micros P50 : 1.601519 P95 : 5.690653 P99 : 1020.739429 P100 : 308103.000000 COUNT : 2635129 SUM : 56440821 rocksdb.compaction.times.micros P50 : 1076363.636364 P95 : 3600000.000000 P99 : 3947715.000000 P100 : 3947715.000000 COUNT : 20 SUM : 26138962 rocksdb.compaction.times.cpu_micros P50 : 973333.333333 P95 : 3600000.000000 P99 : 3903174.000000 P100 : 3903174.000000 COUNT : 20 SUM : 23214093 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 : 761.250000 P95 : 8250.000000 P99 : 12360.000000 P100 : 13245.000000 COUNT : 40 SUM : 58759 rocksdb.compaction.outfile.sync.micros P50 : 1642.857143 P95 : 19120.000000 P99 : 26840.000000 P100 : 30858.000000 COUNT : 56 SUM : 258397 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 : 153.333333 P95 : 1830.000000 P99 : 2670.000000 P100 : 2670.000000 COUNT : 67 SUM : 25560 rocksdb.table.open.io.micros P50 : 6164.835165 P95 : 13012.048193 P99 : 18266.666667 P100 : 19856.000000 COUNT : 1400 SUM : 9216692 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.728473 P95 : 154.393779 P99 : 236.396203 P100 : 10042.000000 COUNT : 2093937 SUM : 53474225 rocksdb.read.block.get.micros P50 : 136.819580 P95 : 240.574007 P99 : 517.200946 P100 : 26759.000000 COUNT : 1398641 SUM : 204771569 rocksdb.write.raw.block.micros P50 : 0.580871 P95 : 1.790270 P99 : 3.745129 P100 : 7090.000000 COUNT : 2735266 SUM : 6335284 rocksdb.numfiles.in.singlecompaction P50 : 3.416667 P95 : 5.650000 P99 : 27.000000 P100 : 27.000000 COUNT : 27 SUM : 123 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.581546 P95 : 1909.885048 P99 : 2702.107451 P100 : 307924.000000 COUNT : 242935 SUM : 49313149 rocksdb.sst.read.micros P50 : 7.306010 P95 : 202.056988 P99 : 388.541621 P100 : 10239.000000 COUNT : 3245791 SUM : 246273677 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 : 1189996 SUM : 1189996000 rocksdb.bytes.per.write P50 : 1032.000000 P95 : 1032.000000 P99 : 1032.000000 P100 : 1032.000000 COUNT : 2635129 SUM : 2719453128 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 : 213243.243243 P95 : 380000.000000 P99 : 532000.000000 P100 : 535778.000000 COUNT : 40 SUM : 8809656 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 : 77 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