[2024-11-15 10:50:19.774815] Starting SPDK v25.01-pre git sha1 8c4dec1aa / DPDK 24.03.0 initialization... [2024-11-15 10:50:19.775125] [ 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_pid1266429 ] Set seed to 1731664219767550 because --seed was 0 EAL: No free 2048 kB hugepages reported on node 1 [2024-11-15 10:50:19.927223] app.c: 919:spdk_app_start: *NOTICE*: Total cores available: 1 [2024-11-15 10:50:20.036353] app.c: 612:app_setup_trace: *NOTICE*: Tracepoint Group Mask 0x80 specified. [2024-11-15 10:50:20.036408] app.c: 613:app_setup_trace: *NOTICE*: Use 'spdk_trace -s rocksdb -p 1266429' to capture a snapshot of events at runtime. [2024-11-15 10:50:20.036430] app.c: 618:app_setup_trace: *NOTICE*: 'spdk_trace' without parameters will also work if this is the only [2024-11-15 10:50:20.036443] app.c: 619:app_setup_trace: *NOTICE*: SPDK application currently running. [2024-11-15 10:50:20.036454] app.c: 620:app_setup_trace: *NOTICE*: Or copy /dev/shm/rocksdb_trace.pid1266429 for offline analysis/debug. [2024-11-15 10:50:20.037689] reactor.c:1005:reactor_run: *NOTICE*: Reactor started on core 0 RocksDB: version 8.1.1 Date: Fri Nov 15 10:50:23 2024 CPU: 72 * Intel(R) Xeon(R) Gold 6140 CPU @ 2.30GHz CPUCache: 25344 KB 2024/11/15-10:50:28 ... thread 0: (1000000,1000000) ops and (195738.7,195738.7) ops/second in (5.108851,5.108851) 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/16 2.93 GB 62.6 0.6 0.0 0.6 1.4 0.9 0.0 1.6 87.6 223.5 6.46 4.73 15 0.431 574K 3089 0.0 0.0 L2 1/0 66.40 MB 0.7 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 916.04 MB 0.9 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 L4 135/0 9.68 GB 1.0 3.7 0.9 2.8 3.4 0.6 0.5 3.7 147.8 136.0 25.85 16.96 11 2.350 584K 0 0.0 0.0 L5 136/0 10.58 GB 0.1 0.0 0.0 0.0 0.0 0.0 1.6 0.0 0.0 0.0 0.00 0.00 0 0.000 0 0 0.0 0.0 Sum 309/16 24.15 GB 0.0 4.3 0.9 3.4 4.8 1.5 2.1 5.6 135.8 153.5 32.31 21.69 26 1.243 1159K 3089 0.0 0.0 Int 0/0 0.00 KB 0.0 4.3 0.9 3.4 4.8 1.5 2.1 5.6 135.8 153.5 32.31 21.69 26 1.243 1159K 3089 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.3 0.9 3.4 4.0 0.6 0.0 0.0 154.8 143.9 28.34 19.23 13 2.180 1159K 3089 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 221.7 3.97 2.47 13 0.306 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.2 total, 5.1 interval Flush(GB): cumulative 0.860, interval 0.860 AddFile(GB): cumulative 0.000, interval 0.000 AddFile(Total Files): cumulative 0, interval 0 AddFile(L0 Files): cumulative 0, interval 0 AddFile(Keys): cumulative 0, interval 0 Cumulative compaction: 4.84 GB write, 955.26 MB/s write, 4.28 GB read, 845.21 MB/s read, 32.3 seconds Interval compaction: 4.84 GB write, 969.26 MB/s write, 4.28 GB read, 857.59 MB/s read, 32.3 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: 6, pending-compaction-bytes-delays: 0, pending-compaction-bytes-stops: 0, total-delays: 10, total-stops: 6, interval: 16 total count ** File Read Latency Histogram By Level [default] ** ** Level 0 read latency histogram (micros): Count: 192404 Average: 47.0368 StdDev: 288.21 Min: 1 Median: 1.8214 Max: 39778 Percentiles: P50: 1.82 P75: 2.85 P99: 783.27 P99.9: 3210.97 P99.99: 4162.42 ------------------------------------------------------ [ 0, 1 ] 14893 7.740% 7.740% ## ( 1, 2 ] 98990 51.449% 59.190% ########## ( 2, 3 ] 35612 18.509% 77.698% #### ( 3, 4 ] 10938 5.685% 83.383% # ( 4, 6 ] 10226 5.315% 88.698% # ( 6, 10 ] 2630 1.367% 90.065% ( 10, 15 ] 318 0.165% 90.230% ( 15, 22 ] 31 0.016% 90.247% ( 22, 34 ] 12 0.006% 90.253% ( 34, 51 ] 13 0.007% 90.260% ( 51, 76 ] 6 0.003% 90.263% ( 76, 110 ] 113 0.059% 90.321% ( 110, 170 ] 2781 1.445% 91.767% ( 170, 250 ] 7162 3.722% 95.489% # ( 250, 380 ] 3036 1.578% 97.067% ( 380, 580 ] 2609 1.356% 98.423% ( 580, 870 ] 1585 0.824% 99.247% ( 870, 1300 ] 641 0.333% 99.580% ( 1300, 1900 ] 254 0.132% 99.712% ( 1900, 2900 ] 306 0.159% 99.871% ( 2900, 4400 ] 273 0.142% 100.013% ( 4400, 6600 ] 61 0.032% 100.045% ( 6600, 9900 ] 13 0.007% 100.051% ( 9900, 14000 ] 11 0.006% 100.057% ( 14000, 22000 ] 6 0.003% 100.060% ( 22000, 33000 ] 1 0.001% 100.061% ( 33000, 50000 ] 1 0.001% 100.061% ** Level 2 read latency histogram (micros): Count: 115 Average: 582.7652 StdDev: 1566.53 Min: 109 Median: 234.1860 Max: 12436 Percentiles: P50: 234.19 P75: 375.36 P99: 11642.50 P99.9: 12436.00 P99.99: 12436.00 ------------------------------------------------------ ( 76, 110 ] 1 0.870% 0.870% ( 110, 170 ] 22 19.130% 20.000% #### ( 170, 250 ] 43 37.391% 57.391% ####### ( 250, 380 ] 21 18.261% 75.652% #### ( 380, 580 ] 14 12.174% 87.826% ## ( 580, 870 ] 4 3.478% 91.304% # ( 870, 1300 ] 5 4.348% 95.652% # ( 2900, 4400 ] 2 1.739% 97.391% ( 4400, 6600 ] 1 0.870% 98.261% ( 9900, 14000 ] 2 1.739% 100.000% ** Level 3 read latency histogram (micros): Count: 49846 Average: 24.3708 StdDev: 232.74 Min: 1 Median: 2.1935 Max: 15343 Percentiles: P50: 2.19 P75: 3.16 P99: 518.21 P99.9: 2949.46 P99.99: 8808.47 ------------------------------------------------------ [ 0, 1 ] 905 1.816% 1.816% ( 1, 2 ] 21212 42.555% 44.371% ######### ( 2, 3 ] 14502 29.094% 73.464% ###### ( 3, 4 ] 4762 9.553% 83.018% ## ( 4, 6 ] 5059 10.149% 93.167% ## ( 6, 10 ] 1328 2.664% 95.831% # ( 10, 15 ] 160 0.321% 96.152% ( 15, 22 ] 14 0.028% 96.180% ( 22, 34 ] 2 0.004% 96.184% ( 34, 51 ] 3 0.006% 96.190% ( 76, 110 ] 10 0.020% 96.210% ( 110, 170 ] 256 0.514% 96.724% ( 170, 250 ] 622 1.248% 97.972% ( 250, 380 ] 299 0.600% 98.572% ( 380, 580 ] 309 0.620% 99.192% ( 580, 870 ] 209 0.419% 99.611% ( 870, 1300 ] 62 0.124% 99.735% ( 1300, 1900 ] 36 0.072% 99.807% ( 1900, 2900 ] 45 0.090% 99.898% ( 2900, 4400 ] 35 0.070% 99.968% ( 4400, 6600 ] 7 0.014% 99.982% ( 6600, 9900 ] 6 0.012% 99.994% ( 9900, 14000 ] 4 0.008% 100.002% ( 14000, 22000 ] 2 0.004% 100.006% ** Level 4 read latency histogram (micros): Count: 589576 Average: 20.4940 StdDev: 196.92 Min: 1 Median: 2.2349 Max: 40138 Percentiles: P50: 2.23 P75: 3.29 P99: 358.31 P99.9: 1117.54 P99.99: 1572.54 ------------------------------------------------------ [ 0, 1 ] 7277 1.234% 1.234% ( 1, 2 ] 247812 42.032% 43.267% ######## ( 2, 3 ] 169039 28.671% 71.938% ###### ( 3, 4 ] 63307 10.738% 82.676% ## ( 4, 6 ] 65342 11.083% 93.758% ## ( 6, 10 ] 16306 2.766% 96.524% # ( 10, 15 ] 3161 0.536% 97.060% ( 15, 22 ] 428 0.073% 97.133% ( 22, 34 ] 40 0.007% 97.140% ( 34, 51 ] 92 0.016% 97.155% ( 51, 76 ] 85 0.014% 97.170% ( 76, 110 ] 125 0.021% 97.191% ( 110, 170 ] 2476 0.420% 97.611% ( 170, 250 ] 5830 0.989% 98.600% ( 250, 380 ] 2833 0.481% 99.080% ( 380, 580 ] 2619 0.444% 99.524% ( 580, 870 ] 1770 0.300% 99.825% ( 870, 1300 ] 772 0.131% 99.956% ( 1300, 1900 ] 447 0.076% 100.031% ( 1900, 2900 ] 524 0.089% 100.120% ( 2900, 4400 ] 463 0.079% 100.199% ( 4400, 6600 ] 97 0.016% 100.215% ( 6600, 9900 ] 26 0.004% 100.220% ( 9900, 14000 ] 9 0.002% 100.221% ( 14000, 22000 ] 8 0.001% 100.223% ( 33000, 50000 ] 1 0.000% 100.223% ** Level 5 read latency histogram (micros): Count: 12860 Average: 492.7291 StdDev: 889.07 Min: 93 Median: 242.9067 Max: 40123 Percentiles: P50: 242.91 P75: 478.65 P99: 4136.36 P99.9: 8851.33 P99.99: 21673.14 ------------------------------------------------------ ( 76, 110 ] 76 0.591% 0.591% ( 110, 170 ] 1996 15.521% 16.112% ### ( 170, 250 ] 4782 37.185% 53.297% ####### ( 250, 380 ] 1950 15.163% 68.460% ### ( 380, 580 ] 1705 13.258% 81.719% ### ( 580, 870 ] 1094 8.507% 90.226% ## ( 870, 1300 ] 420 3.266% 93.491% # ( 1300, 1900 ] 246 1.913% 95.404% ( 1900, 2900 ] 272 2.115% 97.519% ( 2900, 4400 ] 231 1.796% 99.316% ( 4400, 6600 ] 69 0.537% 99.852% ( 6600, 9900 ] 9 0.070% 99.922% ( 9900, 14000 ] 2 0.016% 99.938% ( 14000, 22000 ] 7 0.054% 99.992% ( 33000, 50000 ] 1 0.008% 100.000% ** DB Stats ** Uptime(secs): 5.2 total, 5.1 interval Cumulative writes: 0 writes, 1000K keys, 0 commit groups, 0.0 writes per commit group, ingest: 0.96 GB, 189.59 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.958 H:M:S, 18.5 percent Interval writes: 0 writes, 1000K keys, 0 commit groups, 0.0 writes per commit group, ingest: 984.19 MB, 192.34 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.958 H:M:S, 18.7 percent Write Stall (count): write-buffer-manager-limit-stops: 0, num-running-compactions: 2 num-running-flushes: 1 2024/11/15-10:50:37 ... thread 0: (1000000,2000000) ops and (112564.1,142931.9) ops/second in (8.883830,13.992681) 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/12 3.89 GB 186.6 2.6 0.0 2.6 4.4 1.8 0.0 2.4 151.6 258.8 17.41 15.21 37 0.470 2679K 21K 0.0 0.0 L2 1/0 66.40 MB 0.7 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 916.04 MB 0.9 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 L4 135/0 9.68 GB 1.0 3.7 0.9 2.8 3.4 0.6 0.5 3.7 147.8 136.0 25.85 16.96 11 2.350 584K 0 0.0 0.0 L5 136/0 10.58 GB 0.1 0.0 0.0 0.0 0.0 0.0 1.6 0.0 0.0 0.0 0.00 0.00 0 0.000 0 0 0.0 0.0 Sum 307/12 25.11 GB 0.0 6.3 0.9 5.4 7.8 2.4 2.1 4.2 149.3 185.4 43.26 32.17 48 0.901 3264K 21K 0.0 0.0 Int 0/0 0.00 KB 0.0 6.3 0.9 5.4 7.8 2.4 2.1 4.2 149.3 185.4 43.26 32.17 48 0.901 3264K 21K 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 6.3 0.9 5.4 6.0 0.6 0.0 0.0 178.0 169.0 36.28 27.05 19 1.910 3264K 21K 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 270.5 6.98 5.11 29 0.241 0 0 0.0 0.0 Blob file count: 0, total size: 0.0 GB, garbage size: 0.0 GB, space amp: 0.0 Uptime(secs): 14.1 total, 14.0 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: 7.83 GB write, 569.77 MB/s write, 6.31 GB read, 458.96 MB/s read, 43.3 seconds Interval compaction: 7.83 GB write, 572.82 MB/s write, 6.31 GB read, 461.42 MB/s read, 43.3 seconds Write Stall (count): cf-l0-file-count-limit-delays-with-ongoing-compaction: 17, cf-l0-file-count-limit-stops-with-ongoing-compaction: 0, l0-file-count-limit-delays: 17, l0-file-count-limit-stops: 0, memtable-limit-delays: 0, memtable-limit-stops: 6, pending-compaction-bytes-delays: 0, pending-compaction-bytes-stops: 0, total-delays: 17, total-stops: 6, interval: 23 total count ** File Read Latency Histogram By Level [default] ** ** Level 0 read latency histogram (micros): Count: 781067 Average: 32.2111 StdDev: 154.91 Min: 0 Median: 1.6053 Max: 39778 Percentiles: P50: 1.61 P75: 2.36 P99: 358.41 P99.9: 995.18 P99.99: 1867.07 ------------------------------------------------------ [ 0, 1 ] 134977 17.281% 17.281% ### ( 1, 2 ] 422166 54.050% 71.331% ########### ( 2, 3 ] 80293 10.280% 81.611% ## ( 3, 4 ] 22550 2.887% 84.498% # ( 4, 6 ] 17802 2.279% 86.777% ( 6, 10 ] 4254 0.545% 87.322% ( 10, 15 ] 589 0.075% 87.397% ( 15, 22 ] 86 0.011% 87.408% ( 22, 34 ] 24 0.003% 87.411% ( 34, 51 ] 16 0.002% 87.413% ( 51, 76 ] 9 0.001% 87.415% ( 76, 110 ] 1606 0.206% 87.620% ( 110, 170 ] 30873 3.953% 91.573% # ( 170, 250 ] 51898 6.645% 98.217% # ( 250, 380 ] 7331 0.939% 99.156% ( 380, 580 ] 3866 0.495% 99.651% ( 580, 870 ] 1757 0.225% 99.876% ( 870, 1300 ] 649 0.083% 99.959% ( 1300, 1900 ] 257 0.033% 99.992% ( 1900, 2900 ] 308 0.039% 100.031% ( 2900, 4400 ] 274 0.035% 100.066% ( 4400, 6600 ] 61 0.008% 100.074% ( 6600, 9900 ] 13 0.002% 100.076% ( 9900, 14000 ] 11 0.001% 100.077% ( 14000, 22000 ] 6 0.001% 100.078% ( 22000, 33000 ] 1 0.000% 100.078% ( 33000, 50000 ] 1 0.000% 100.078% ** Level 2 read latency histogram (micros): Count: 566 Average: 270.3799 StdDev: 725.57 Min: 93 Median: 195.0704 Max: 12436 Percentiles: P50: 195.07 P75: 234.93 P99: 1243.24 P99.9: 12436.00 P99.99: 12436.00 ------------------------------------------------------ ( 76, 110 ] 13 2.297% 2.297% ( 110, 170 ] 181 31.979% 34.276% ###### ( 170, 250 ] 284 50.177% 84.452% ########## ( 250, 380 ] 49 8.657% 93.110% ## ( 380, 580 ] 24 4.240% 97.350% # ( 580, 870 ] 5 0.883% 98.233% ( 870, 1300 ] 5 0.883% 99.117% ( 2900, 4400 ] 2 0.353% 99.470% ( 4400, 6600 ] 1 0.177% 99.647% ( 9900, 14000 ] 2 0.353% 100.000% ** Level 3 read latency histogram (micros): Count: 55881 Average: 42.1941 StdDev: 226.48 Min: 1 Median: 2.4016 Max: 15343 Percentiles: P50: 2.40 P75: 4.21 P99: 509.77 P99.9: 2791.53 P99.99: 8476.54 ------------------------------------------------------ [ 0, 1 ] 905 1.620% 1.620% ( 1, 2 ] 21212 37.959% 39.579% ######## ( 2, 3 ] 14502 25.952% 65.530% ##### ( 3, 4 ] 4762 8.522% 74.052% ## ( 4, 6 ] 5059 9.053% 83.105% ## ( 6, 10 ] 1328 2.376% 85.482% ( 10, 15 ] 160 0.286% 85.768% ( 15, 22 ] 14 0.025% 85.793% ( 22, 34 ] 2 0.004% 85.797% ( 34, 51 ] 3 0.005% 85.802% ( 76, 110 ] 119 0.213% 86.015% ( 110, 170 ] 2373 4.247% 90.261% # ( 170, 250 ] 3982 7.126% 97.387% # ( 250, 380 ] 641 1.147% 98.534% ( 380, 580 ] 401 0.718% 99.252% ( 580, 870 ] 224 0.401% 99.653% ( 870, 1300 ] 62 0.111% 99.764% ( 1300, 1900 ] 36 0.064% 99.828% ( 1900, 2900 ] 45 0.081% 99.909% ( 2900, 4400 ] 35 0.063% 99.971% ( 4400, 6600 ] 7 0.013% 99.984% ( 6600, 9900 ] 6 0.011% 99.995% ( 9900, 14000 ] 4 0.007% 100.002% ( 14000, 22000 ] 2 0.004% 100.005% ** Level 4 read latency histogram (micros): Count: 656069 Average: 36.8680 StdDev: 194.14 Min: 1 Median: 2.4277 Max: 40138 Percentiles: P50: 2.43 P75: 4.09 P99: 379.05 P99.9: 1081.63 P99.99: 1563.78 ------------------------------------------------------ [ 0, 1 ] 7277 1.109% 1.109% ( 1, 2 ] 248043 37.807% 38.917% ######## ( 2, 3 ] 170024 25.916% 64.832% ##### ( 3, 4 ] 63793 9.724% 74.556% ## ( 4, 6 ] 66166 10.085% 84.641% ## ( 6, 10 ] 16371 2.495% 87.136% ( 10, 15 ] 3187 0.486% 87.622% ( 15, 22 ] 436 0.066% 87.688% ( 22, 34 ] 41 0.006% 87.695% ( 34, 51 ] 92 0.014% 87.709% ( 51, 76 ] 85 0.013% 87.722% ( 76, 110 ] 1218 0.186% 87.907% ( 110, 170 ] 24729 3.769% 91.677% # ( 170, 250 ] 41737 6.362% 98.038% # ( 250, 380 ] 6356 0.969% 99.007% ( 380, 580 ] 3582 0.546% 99.553% ( 580, 870 ] 1895 0.289% 99.842% ( 870, 1300 ] 774 0.118% 99.960% ( 1300, 1900 ] 449 0.068% 100.028% ( 1900, 2900 ] 526 0.080% 100.109% ( 2900, 4400 ] 463 0.071% 100.179% ( 4400, 6600 ] 97 0.015% 100.194% ( 6600, 9900 ] 26 0.004% 100.198% ( 9900, 14000 ] 9 0.001% 100.199% ( 14000, 22000 ] 8 0.001% 100.200% ( 33000, 50000 ] 1 0.000% 100.201% ** Level 5 read latency histogram (micros): Count: 63147 Average: 251.0565 StdDev: 422.59 Min: 91 Median: 196.8775 Max: 40123 Percentiles: P50: 196.88 P75: 235.06 P99: 1802.09 P99.9: 5160.53 P99.99: 14783.20 ------------------------------------------------------ ( 76, 110 ] 972 1.539% 1.539% ( 110, 170 ] 19489 30.863% 32.402% ###### ( 170, 250 ] 33076 52.379% 84.782% ########## ( 250, 380 ] 4683 7.416% 92.198% # ( 380, 580 ] 2478 3.924% 96.122% # ( 580, 870 ] 1188 1.881% 98.003% ( 870, 1300 ] 422 0.668% 98.671% ( 1300, 1900 ] 248 0.393% 99.064% ( 1900, 2900 ] 273 0.432% 99.496% ( 2900, 4400 ] 231 0.366% 99.862% ( 4400, 6600 ] 69 0.109% 99.971% ( 6600, 9900 ] 9 0.014% 99.986% ( 9900, 14000 ] 2 0.003% 99.989% ( 14000, 22000 ] 7 0.011% 100.000% ( 33000, 50000 ] 1 0.002% 100.002% ** DB Stats ** Uptime(secs): 14.1 total, 8.9 interval Cumulative writes: 0 writes, 2000K keys, 0 commit groups, 0.0 writes per commit group, ingest: 1.92 GB, 139.85 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:5.835 H:M:S, 41.5 percent Interval writes: 0 writes, 1000K keys, 0 commit groups, 0.0 writes per commit group, ingest: 984.19 MB, 110.78 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:4.877 H:M:S, 54.9 percent Write Stall (count): write-buffer-manager-limit-stops: 0, num-running-compactions: 1 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 : 284.523 micros/op 14027 ops/sec 20.174 seconds 282996 operations; 13.6 MB/s (69999 of 69999 found) Microseconds per read: Count: 282996 Average: 284.5301 StdDev: 609.92 Min: 2 Median: 222.0593 Max: 52920 Percentiles: P50: 222.06 P75: 299.05 P99: 1148.72 P99.9: 7642.22 P99.99: 28425.10 ------------------------------------------------------ ( 1, 2 ] 10 0.004% 0.004% ( 2, 3 ] 62 0.022% 0.025% ( 3, 4 ] 106 0.037% 0.063% ( 4, 6 ] 202 0.071% 0.134% ( 6, 10 ] 379 0.134% 0.268% ( 10, 15 ] 2458 0.869% 1.137% ( 15, 22 ] 3415 1.207% 2.343% ( 22, 34 ] 5047 1.783% 4.127% ( 34, 51 ] 2022 0.714% 4.841% ( 51, 76 ] 302 0.107% 4.948% ( 76, 110 ] 151 0.053% 5.001% ( 110, 170 ] 29766 10.518% 15.520% ## ( 170, 250 ] 149949 52.986% 68.506% ########### ( 250, 380 ] 48709 17.212% 85.718% ### ( 380, 580 ] 28619 10.113% 95.831% ## ( 580, 870 ] 7696 2.719% 98.550% # ( 870, 1300 ] 1964 0.694% 99.244% ( 1300, 1900 ] 646 0.228% 99.472% ( 1900, 2900 ] 542 0.192% 99.664% ( 2900, 4400 ] 491 0.174% 99.837% ( 4400, 6600 ] 141 0.050% 99.887% ( 6600, 9900 ] 114 0.040% 99.928% ( 9900, 14000 ] 77 0.027% 99.955% ( 14000, 22000 ] 74 0.026% 99.981% ( 22000, 33000 ] 44 0.016% 99.996% ( 33000, 50000 ] 8 0.003% 99.999% ( 50000, 75000 ] 2 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 : 5060447 rocksdb.bloom.filter.full.positive COUNT : 331016 rocksdb.bloom.filter.full.true.positive COUNT : 282114 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 : 882 rocksdb.memtable.miss COUNT : 282114 rocksdb.l0.hit COUNT : 48677 rocksdb.l1.hit COUNT : 0 rocksdb.l2andup.hit COUNT : 233437 rocksdb.compaction.key.drop.new COUNT : 361738 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 : 2558311 rocksdb.number.keys.read COUNT : 282996 rocksdb.number.keys.updated COUNT : 0 rocksdb.bytes.written COUNT : 2640176952 rocksdb.bytes.read COUNT : 282996000 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 : 403 rocksdb.no.file.errors COUNT : 0 rocksdb.stall.micros COUNT : 9762324 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 : 8468824002 rocksdb.compact.write.bytes COUNT : 8088604570 rocksdb.flush.write.bytes COUNT : 2572564147 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 : 52 rocksdb.number.superversion_acquires COUNT : 431 rocksdb.number.superversion_releases COUNT : 409 rocksdb.number.superversion_cleanups COUNT : 104 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 : 2555467776 rocksdb.memtable.garbage.bytes.at.flush COUNT : 4330496 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 : 8569735497 rocksdb.non.last.level.read.count COUNT : 2039557 rocksdb.block.checksum.compute.count COUNT : 2385408 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 : 221.488040 P95 : 563.145105 P99 : 1148.037385 P100 : 52918.000000 COUNT : 282996 SUM : 80280292 rocksdb.db.write.micros P50 : 1.548876 P95 : 5.278714 P99 : 9.957696 P100 : 438078.000000 COUNT : 2558311 SUM : 16616648 rocksdb.compaction.times.micros P50 : 1608333.333333 P95 : 3662195.000000 P99 : 3662195.000000 P100 : 3662195.000000 COUNT : 21 SUM : 40306685 rocksdb.compaction.times.cpu_micros P50 : 1184545.454545 P95 : 3054564.000000 P99 : 3054564.000000 P100 : 3054564.000000 COUNT : 21 SUM : 31020354 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 : 956.000000 P95 : 2675.000000 P99 : 3587.000000 P100 : 3587.000000 COUNT : 38 SUM : 44793 rocksdb.compaction.outfile.sync.micros P50 : 1174.583333 P95 : 4675.000000 P99 : 6215.000000 P100 : 6248.000000 COUNT : 70 SUM : 116221 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 : 161.176471 P95 : 783.000000 P99 : 2986.000000 P100 : 2986.000000 COUNT : 66 SUM : 18358 rocksdb.table.open.io.micros P50 : 6705.000000 P95 : 20556.521739 P99 : 31405.000000 P100 : 33339.000000 COUNT : 403 SUM : 3058304 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.845216 P95 : 13.386912 P99 : 240.863763 P100 : 39783.000000 COUNT : 2050292 SUM : 34817742 rocksdb.read.block.get.micros P50 : 192.838123 P95 : 378.059740 P99 : 886.749685 P100 : 40145.000000 COUNT : 334753 SUM : 70837404 rocksdb.write.raw.block.micros P50 : 0.528960 P95 : 1.144187 P99 : 3.729059 P100 : 54228.000000 COUNT : 2583149 SUM : 6961918 rocksdb.numfiles.in.singlecompaction P50 : 3.525000 P95 : 9.550000 P99 : 12.000000 P100 : 12.000000 COUNT : 29 SUM : 133 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.560999 P95 : 1128.882671 P99 : 2048.026159 P100 : 437881.000000 COUNT : 72251 SUM : 9769330 rocksdb.sst.read.micros P50 : 1.868819 P95 : 229.751145 P99 : 421.062325 P100 : 40138.000000 COUNT : 2039700 SUM : 94793771 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 : 282996 SUM : 282996000 rocksdb.bytes.per.write P50 : 1032.000000 P95 : 1032.000000 P99 : 1032.000000 P100 : 1032.000000 COUNT : 2558311 SUM : 2640176952 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 : 219032.258065 P95 : 484500.000000 P99 : 723741.000000 P100 : 723741.000000 COUNT : 38 SUM : 8675423 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 : 93 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