[2024-07-25 11:02:16.796175] Starting SPDK v24.09-pre git sha1 623b676ee / DPDK 24.03.0 initialization... [2024-07-25 11:02:16.796495] [ 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_pid851605 ] Set seed to 1721898136788913 because --seed was 0 EAL: No free 2048 kB hugepages reported on node 1 [2024-07-25 11:02:16.993102] app.c: 909:spdk_app_start: *NOTICE*: Total cores available: 1 [2024-07-25 11:02:17.296845] app.c: 603:app_setup_trace: *NOTICE*: Tracepoint Group Mask 0x80 specified. [2024-07-25 11:02:17.296905] app.c: 604:app_setup_trace: *NOTICE*: Use 'spdk_trace -s rocksdb -p 851605' to capture a snapshot of events at runtime. [2024-07-25 11:02:17.296924] app.c: 609:app_setup_trace: *NOTICE*: 'spdk_trace' without parameters will also work if this is the only [2024-07-25 11:02:17.296943] app.c: 610:app_setup_trace: *NOTICE*: SPDK application currently running. [2024-07-25 11:02:17.296959] app.c: 611:app_setup_trace: *NOTICE*: Or copy /dev/shm/rocksdb_trace.pid851605 for offline analysis/debug. [2024-07-25 11:02:17.297095] reactor.c: 941:reactor_run: *NOTICE*: Reactor started on core 0 RocksDB: version 8.1.1 Date: Thu Jul 25 11:02:24 2024 CPU: 72 * Intel(R) Xeon(R) Gold 6140 CPU @ 2.30GHz CPUCache: 25344 KB 2024/07/25-11:02:28 ... thread 0: (1000000,1000000) ops and (256488.6,256488.6) ops/second in (3.898808,3.898808) 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 6/5 377.49 MB 6.3 0.0 0.0 0.0 0.8 0.8 0.0 1.0 0.0 263.3 3.11 2.78 13 0.239 0 0 0.0 0.0 L1 4/0 374.44 MB 37.4 0.6 0.4 0.2 0.5 0.4 0.1 1.5 242.8 241.5 2.33 2.25 2 1.165 574K 3009 0.0 0.0 L2 1/1 100.32 MB 0.0 0.2 0.1 0.1 0.2 0.1 0.0 2.6 226.6 226.1 0.71 0.66 1 0.710 163K 348 0.0 0.0 L3 16/9 816.06 MB 0.1 0.1 0.1 0.0 0.1 0.1 0.0 1.0 154.1 154.1 0.39 0.34 1 0.390 61K 0 0.0 0.0 L4 124/0 9.54 GB 1.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 L5 108/0 8.86 GB 0.1 0.0 0.0 0.0 0.0 0.0 0.0 0.0 0.0 0.0 0.00 0.00 0 0.000 0 0 0.0 0.0 Sum 259/15 20.02 GB 0.0 0.8 0.5 0.3 1.6 1.3 0.1 2.0 120.3 245.0 6.54 6.03 17 0.384 798K 3357 0.0 0.0 Int 0/0 0.00 KB 0.0 0.8 0.5 0.3 1.6 1.3 0.1 2.0 120.3 245.0 6.54 6.03 17 0.384 798K 3357 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 0.8 0.5 0.3 0.8 0.5 0.0 0.0 229.4 228.4 3.43 3.25 4 0.857 798K 3357 0.0 0.0 High 0/0 0.00 KB 0.0 0.0 0.0 0.0 0.8 0.8 0.0 0.0 0.0 263.3 3.11 2.78 13 0.239 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.0 total, 3.9 interval Flush(GB): cumulative 0.799, interval 0.799 AddFile(GB): cumulative 0.000, interval 0.000 AddFile(Total Files): cumulative 0, interval 0 AddFile(L0 Files): cumulative 0, interval 0 AddFile(Keys): cumulative 0, interval 0 Cumulative compaction: 1.56 GB write, 400.79 MB/s write, 0.77 GB read, 196.90 MB/s read, 6.5 seconds Interval compaction: 1.56 GB write, 410.45 MB/s write, 0.77 GB read, 201.64 MB/s read, 6.5 seconds Write Stall (count): cf-l0-file-count-limit-delays-with-ongoing-compaction: 0, cf-l0-file-count-limit-stops-with-ongoing-compaction: 0, l0-file-count-limit-delays: 0, l0-file-count-limit-stops: 0, memtable-limit-delays: 0, memtable-limit-stops: 0, pending-compaction-bytes-delays: 0, pending-compaction-bytes-stops: 0, total-delays: 0, total-stops: 0, ** File Read Latency Histogram By Level [default] ** ** Level 0 read latency histogram (micros): Count: 144899 Average: 2.3526 StdDev: 16.22 Min: 1 Median: 1.5401 Max: 3211 Percentiles: P50: 1.54 P75: 1.99 P99: 6.05 P99.9: 12.99 P99.99: 28.01 ------------------------------------------------------ [ 0, 1 ] 28523 19.685% 19.685% #### ( 1, 2 ] 81326 56.126% 75.811% ########### ( 2, 3 ] 24425 16.857% 92.667% ### ( 3, 4 ] 4624 3.191% 95.858% # ( 4, 6 ] 4538 3.132% 98.990% # ( 6, 10 ] 1168 0.806% 99.796% ( 10, 15 ] 254 0.175% 99.972% ( 15, 22 ] 24 0.017% 99.988% ( 22, 34 ] 9 0.006% 99.994% ( 34, 51 ] 1 0.001% 99.995% ( 51, 76 ] 1 0.001% 99.996% ( 250, 380 ] 12 0.008% 100.004% ( 380, 580 ] 6 0.004% 100.008% ( 580, 870 ] 2 0.001% 100.010% ( 870, 1300 ] 2 0.001% 100.011% ( 1300, 1900 ] 1 0.001% 100.012% ( 2900, 4400 ] 3 0.002% 100.014% ** Level 1 read latency histogram (micros): Count: 47806 Average: 2.1871 StdDev: 12.44 Min: 0 Median: 1.4463 Max: 1781 Percentiles: P50: 1.45 P75: 1.84 P99: 5.51 P99.9: 9.47 P99.99: 540.97 ------------------------------------------------------ [ 0, 1 ] 10380 21.713% 21.713% #### ( 1, 2 ] 30299 63.379% 85.092% ############# ( 2, 3 ] 4372 9.145% 94.237% ## ( 3, 4 ] 1438 3.008% 97.245% # ( 4, 6 ] 1113 2.328% 99.573% ( 6, 10 ] 180 0.377% 99.950% ( 10, 15 ] 10 0.021% 99.971% ( 15, 22 ] 3 0.006% 99.977% ( 22, 34 ] 1 0.002% 99.979% ( 170, 250 ] 1 0.002% 99.981% ( 250, 380 ] 1 0.002% 99.983% ( 380, 580 ] 4 0.008% 99.992% ( 580, 870 ] 1 0.002% 99.994% ( 870, 1300 ] 2 0.004% 99.998% ( 1300, 1900 ] 1 0.002% 100.000% ** Level 2 read latency histogram (micros): Count: 40253 Average: 3.4904 StdDev: 56.11 Min: 1 Median: 1.6001 Max: 7859 Percentiles: P50: 1.60 P75: 2.34 P99: 9.62 P99.9: 127.29 P99.99: 1884.82 ------------------------------------------------------ [ 0, 1 ] 8962 22.264% 22.264% #### ( 1, 2 ] 18605 46.220% 68.484% ######### ( 2, 3 ] 7770 19.303% 87.787% #### ( 3, 4 ] 1587 3.943% 91.730% # ( 4, 6 ] 2170 5.391% 97.121% # ( 6, 10 ] 835 2.074% 99.195% ( 10, 15 ] 161 0.400% 99.595% ( 15, 22 ] 17 0.042% 99.637% ( 22, 34 ] 3 0.007% 99.645% ( 34, 51 ] 1 0.002% 99.647% ( 51, 76 ] 79 0.196% 99.843% ( 76, 110 ] 19 0.047% 99.891% ( 110, 170 ] 13 0.032% 99.923% ( 170, 250 ] 5 0.012% 99.935% ( 250, 380 ] 7 0.017% 99.953% ( 380, 580 ] 7 0.017% 99.970% ( 580, 870 ] 5 0.012% 99.983% ( 870, 1300 ] 2 0.005% 99.988% ( 1300, 1900 ] 1 0.002% 99.990% ( 2900, 4400 ] 3 0.007% 99.998% ( 4400, 6600 ] 1 0.002% 100.000% ( 6600, 9900 ] 1 0.002% 100.002% ** Level 3 read latency histogram (micros): Count: 32300 Average: 9.1605 StdDev: 171.09 Min: 1 Median: 2.3146 Max: 9304 Percentiles: P50: 2.31 P75: 2.87 P99: 14.10 P99.9: 1020.50 P99.99: 8123.50 ------------------------------------------------------ [ 0, 1 ] 35 0.108% 0.108% ( 1, 2 ] 11552 35.765% 35.873% ####### ( 2, 3 ] 14505 44.907% 80.780% ######### ( 3, 4 ] 1892 5.858% 86.638% # ( 4, 6 ] 2861 8.858% 95.495% ## ( 6, 10 ] 858 2.656% 98.152% # ( 10, 15 ] 334 1.034% 99.186% ( 15, 22 ] 75 0.232% 99.418% ( 22, 34 ] 8 0.025% 99.443% ( 34, 51 ] 1 0.003% 99.446% ( 76, 110 ] 38 0.118% 99.563% ( 110, 170 ] 40 0.124% 99.687% ( 170, 250 ] 36 0.111% 99.799% ( 250, 380 ] 14 0.043% 99.842% ( 380, 580 ] 12 0.037% 99.879% ( 580, 870 ] 6 0.019% 99.898% ( 870, 1300 ] 2 0.006% 99.904% ( 1300, 1900 ] 1 0.003% 99.907% ( 1900, 2900 ] 3 0.009% 99.916% ( 2900, 4400 ] 5 0.015% 99.932% ( 4400, 6600 ] 16 0.050% 99.981% ( 6600, 9900 ] 6 0.019% 100.000% ** Level 4 read latency histogram (micros): Count: 25 Average: 4717.6800 StdDev: 2019.14 Min: 336 Median: 5100.0000 Max: 7961 Percentiles: P50: 5100.00 P75: 6350.00 P99: 7961.00 P99.9: 7961.00 P99.99: 7961.00 ------------------------------------------------------ ( 250, 380 ] 1 4.000% 4.000% # ( 1300, 1900 ] 2 8.000% 12.000% ## ( 1900, 2900 ] 3 12.000% 24.000% ## ( 2900, 4400 ] 3 12.000% 36.000% ## ( 4400, 6600 ] 11 44.000% 80.000% ######### ( 6600, 9900 ] 5 20.000% 100.000% #### ** DB Stats ** Uptime(secs): 4.0 total, 3.9 interval Cumulative writes: 0 writes, 1000K keys, 0 commit groups, 0.0 writes per commit group, ingest: 0.96 GB, 246.35 MB/s Cumulative WAL: 0 writes, 0 syncs, 0.00 writes per sync, written: 0.00 GB, 0.00 MB/s Cumulative stall: 00:00:0.000 H:M:S, 0.0 percent Interval writes: 0 writes, 1000K keys, 0 commit groups, 0.0 writes per commit group, ingest: 984.19 MB, 252.23 MB/s Interval WAL: 0 writes, 0 syncs, 0.00 writes per sync, written: 0.00 GB, 0.00 MB/s Interval stall: 00:00:0.000 H:M:S, 0.0 percent Write Stall (count): write-buffer-manager-limit-stops: 0, num-running-compactions: 2 num-running-flushes: 1 2024/07/25-11:02:36 ... thread 0: (1000000,2000000) ops and (122495.3,165804.7) ops/second in (8.163577,12.062385) 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 12/11 1.41 GB 25.0 0.9 0.0 0.9 2.7 1.8 0.0 1.5 77.3 242.6 11.39 10.52 32 0.356 894K 5041 0.0 0.0 L1 1/1 16.16 MB 0.0 0.6 0.4 0.2 0.5 0.4 0.1 1.5 242.8 241.5 2.33 2.25 2 1.165 574K 3009 0.0 0.0 L2 0/0 0.00 KB 0.0 0.2 0.1 0.1 0.2 0.1 0.3 2.6 226.6 226.1 0.71 0.66 1 0.710 163K 348 0.0 0.0 L3 18/0 813.02 MB 0.8 1.3 0.5 0.8 1.3 0.5 0.0 2.6 190.7 190.2 7.22 6.77 5 1.444 1398K 3587 0.0 0.0 L4 131/0 9.67 GB 1.0 0.2 0.2 0.0 0.2 0.2 0.3 1.0 175.3 175.3 1.14 1.09 2 0.572 203K 0 0.0 0.0 L5 112/0 9.17 GB 0.1 0.0 0.0 0.0 0.0 0.0 0.3 0.0 0.0 0.0 0.00 0.00 0 0.000 0 0 0.0 0.0 Sum 274/12 21.06 GB 0.0 3.1 1.1 2.0 4.9 3.0 1.0 2.7 139.7 222.0 22.79 21.28 42 0.543 3233K 11K 0.0 0.0 Int 0/0 0.00 KB 0.0 3.1 1.1 2.0 4.9 3.0 1.0 2.7 139.7 222.0 22.79 21.28 42 0.543 3233K 11K 0.0 0.0 ** Compaction Stats [default] ** Priority Files Size Score Read(GB) Rn(GB) Rnp1(GB) Write(GB) Wnew(GB) Moved(GB) W-Amp Rd(MB/s) Wr(MB/s) Comp(sec) CompMergeCPU(sec) Comp(cnt) Avg(sec) KeyIn KeyDrop Rblob(GB) Wblob(GB) --------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------- Low 0/0 0.00 KB 0.0 3.1 1.1 2.0 3.1 1.1 0.0 0.0 202.9 202.2 15.69 14.96 13 1.207 3233K 11K 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 265.9 7.10 6.33 29 0.245 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): 12.2 total, 12.1 interval Flush(GB): cumulative 1.843, interval 1.843 AddFile(GB): cumulative 0.000, interval 0.000 AddFile(Total Files): cumulative 0, interval 0 AddFile(L0 Files): cumulative 0, interval 0 AddFile(Keys): cumulative 0, interval 0 Cumulative compaction: 4.94 GB write, 416.11 MB/s write, 3.11 GB read, 261.89 MB/s read, 22.8 seconds Interval compaction: 4.94 GB write, 419.35 MB/s write, 3.11 GB read, 263.93 MB/s read, 22.8 seconds Write Stall (count): cf-l0-file-count-limit-delays-with-ongoing-compaction: 0, cf-l0-file-count-limit-stops-with-ongoing-compaction: 0, l0-file-count-limit-delays: 0, l0-file-count-limit-stops: 0, memtable-limit-delays: 0, memtable-limit-stops: 3, pending-compaction-bytes-delays: 0, pending-compaction-bytes-stops: 0, total-delays: 0, total-stops: 3, interval: 3 total count ** File Read Latency Histogram By Level [default] ** ** Level 0 read latency histogram (micros): Count: 557794 Average: 2.8465 StdDev: 12.56 Min: 1 Median: 1.9737 Max: 3244 Percentiles: P50: 1.97 P75: 2.61 P99: 7.52 P99.9: 14.49 P99.99: 276.72 ------------------------------------------------------ [ 0, 1 ] 29085 5.214% 5.214% # ( 1, 2 ] 256563 45.996% 51.210% ######### ( 2, 3 ] 218171 39.113% 90.323% ######## ( 3, 4 ] 21257 3.811% 94.134% # ( 4, 6 ] 24660 4.421% 98.555% # ( 6, 10 ] 6528 1.170% 99.726% ( 10, 15 ] 1083 0.194% 99.920% ( 15, 22 ] 105 0.019% 99.939% ( 22, 34 ] 13 0.002% 99.941% ( 34, 51 ] 2 0.000% 99.941% ( 51, 76 ] 22 0.004% 99.945% ( 76, 110 ] 19 0.003% 99.949% ( 110, 170 ] 167 0.030% 99.979% ( 170, 250 ] 56 0.010% 99.989% ( 250, 380 ] 40 0.007% 99.996% ( 380, 580 ] 39 0.007% 100.003% ( 580, 870 ] 10 0.002% 100.005% ( 870, 1300 ] 6 0.001% 100.006% ( 1300, 1900 ] 7 0.001% 100.007% ( 2900, 4400 ] 4 0.001% 100.008% ** Level 1 read latency histogram (micros): Count: 138685 Average: 2.8560 StdDev: 8.78 Min: 0 Median: 1.9343 Max: 1781 Percentiles: P50: 1.93 P75: 2.67 P99: 8.49 P99.9: 14.81 P99.99: 81.57 ------------------------------------------------------ [ 0, 1 ] 10953 7.898% 7.898% ## ( 1, 2 ] 62495 45.063% 52.960% ######### ( 2, 3 ] 45708 32.958% 85.918% ####### ( 3, 4 ] 7634 5.505% 91.423% # ( 4, 6 ] 9174 6.615% 98.038% # ( 6, 10 ] 2142 1.545% 99.583% ( 10, 15 ] 458 0.330% 99.913% ( 15, 22 ] 94 0.068% 99.981% ( 22, 34 ] 6 0.004% 99.985% ( 34, 51 ] 3 0.002% 99.987% ( 51, 76 ] 2 0.001% 99.988% ( 76, 110 ] 13 0.009% 99.998% ( 110, 170 ] 30 0.022% 100.019% ( 170, 250 ] 15 0.011% 100.030% ( 250, 380 ] 5 0.004% 100.034% ( 380, 580 ] 6 0.004% 100.038% ( 580, 870 ] 3 0.002% 100.040% ( 870, 1300 ] 2 0.001% 100.042% ( 1300, 1900 ] 1 0.001% 100.043% ** Level 2 read latency histogram (micros): Count: 65617 Average: 3.1639 StdDev: 44.27 Min: 1 Median: 1.6045 Max: 7859 Percentiles: P50: 1.60 P75: 2.11 P99: 9.20 P99.9: 145.07 P99.99: 964.23 ------------------------------------------------------ [ 0, 1 ] 9588 14.612% 14.612% ### ( 1, 2 ] 38411 58.538% 73.150% ############ ( 2, 3 ] 11427 17.415% 90.565% ### ( 3, 4 ] 1958 2.984% 93.549% # ( 4, 6 ] 2552 3.889% 97.438% # ( 6, 10 ] 1280 1.951% 99.389% ( 10, 15 ] 186 0.283% 99.672% ( 15, 22 ] 23 0.035% 99.707% ( 22, 34 ] 3 0.005% 99.712% ( 34, 51 ] 1 0.002% 99.713% ( 51, 76 ] 79 0.120% 99.834% ( 76, 110 ] 20 0.030% 99.864% ( 110, 170 ] 40 0.061% 99.925% ( 170, 250 ] 15 0.023% 99.948% ( 250, 380 ] 13 0.020% 99.968% ( 380, 580 ] 9 0.014% 99.982% ( 580, 870 ] 5 0.008% 99.989% ( 870, 1300 ] 2 0.003% 99.992% ( 1300, 1900 ] 1 0.002% 99.994% ( 2900, 4400 ] 3 0.005% 99.998% ( 4400, 6600 ] 1 0.002% 100.000% ( 6600, 9900 ] 1 0.002% 100.002% ** Level 3 read latency histogram (micros): Count: 268979 Average: 4.1543 StdDev: 60.64 Min: 1 Median: 2.1428 Max: 9304 Percentiles: P50: 2.14 P75: 2.74 P99: 9.87 P99.9: 182.68 P99.99: 795.64 ------------------------------------------------------ [ 0, 1 ] 1704 0.634% 0.634% ( 1, 2 ] 116665 43.373% 44.007% ######### ( 2, 3 ] 112851 41.955% 85.962% ######## ( 3, 4 ] 13070 4.859% 90.821% # ( 4, 6 ] 16525 6.144% 96.965% # ( 6, 10 ] 5663 2.105% 99.070% ( 10, 15 ] 1395 0.519% 99.589% ( 15, 22 ] 226 0.084% 99.673% ( 22, 34 ] 22 0.008% 99.681% ( 34, 51 ] 4 0.001% 99.683% ( 76, 110 ] 129 0.048% 99.730% ( 110, 170 ] 437 0.162% 99.893% ( 170, 250 ] 120 0.045% 99.938% ( 250, 380 ] 69 0.026% 99.963% ( 380, 580 ] 55 0.020% 99.984% ( 580, 870 ] 23 0.009% 99.992% ( 870, 1300 ] 9 0.003% 99.996% ( 1300, 1900 ] 1 0.000% 99.996% ( 1900, 2900 ] 3 0.001% 99.997% ( 2900, 4400 ] 5 0.002% 99.999% ( 4400, 6600 ] 16 0.006% 100.005% ( 6600, 9900 ] 6 0.002% 100.007% ** Level 4 read latency histogram (micros): Count: 60 Average: 2026.4833 StdDev: 2626.53 Min: 1 Median: 160.0000 Max: 7961 Percentiles: P50: 160.00 P75: 4600.00 P99: 7961.00 P99.9: 7961.00 P99.99: 7961.00 ------------------------------------------------------ [ 0, 1 ] 5 8.333% 8.333% ## ( 1, 2 ] 14 23.333% 31.667% ##### ( 2, 3 ] 1 1.667% 33.333% ( 3, 4 ] 1 1.667% 35.000% ( 6, 10 ] 1 1.667% 36.667% ( 10, 15 ] 2 3.333% 40.000% # ( 22, 34 ] 1 1.667% 41.667% ( 110, 170 ] 6 10.000% 51.667% ## ( 250, 380 ] 1 1.667% 53.333% ( 380, 580 ] 2 3.333% 56.667% # ( 580, 870 ] 1 1.667% 58.333% ( 870, 1300 ] 1 1.667% 60.000% ( 1300, 1900 ] 2 3.333% 63.333% # ( 1900, 2900 ] 3 5.000% 68.333% # ( 2900, 4400 ] 3 5.000% 73.333% # ( 4400, 6600 ] 11 18.333% 91.667% #### ( 6600, 9900 ] 5 8.333% 100.000% ## ** Level 5 read latency histogram (micros): Count: 20 Average: 851.4000 StdDev: 1146.48 Min: 94 Median: 250.0000 Max: 4285 Percentiles: P50: 250.00 P75: 1085.00 P99: 4250.00 P99.9: 4285.00 P99.99: 4285.00 ------------------------------------------------------ ( 76, 110 ] 2 10.000% 10.000% ## ( 110, 170 ] 5 25.000% 35.000% ##### ( 170, 250 ] 3 15.000% 50.000% ### ( 250, 380 ] 2 10.000% 60.000% ## ( 580, 870 ] 2 10.000% 70.000% ## ( 870, 1300 ] 2 10.000% 80.000% ## ( 1300, 1900 ] 1 5.000% 85.000% # ( 1900, 2900 ] 1 5.000% 90.000% # ( 2900, 4400 ] 2 10.000% 100.000% ## ** DB Stats ** Uptime(secs): 12.2 total, 8.2 interval Cumulative writes: 0 writes, 2000K keys, 0 commit groups, 0.0 writes per commit group, ingest: 1.92 GB, 161.88 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.219 H:M:S, 1.8 percent Interval writes: 0 writes, 1000K keys, 0 commit groups, 0.0 writes per commit group, ingest: 984.19 MB, 120.55 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.219 H:M:S, 2.7 percent Write Stall (count): write-buffer-manager-limit-stops: 0, num-running-compactions: 2 num-running-flushes: 1 using bdev Nvme0n1 Initializing RocksDB Options from the specified file Initializing RocksDB Options from command-line flags Integrated BlobDB: blob cache disabled Keys: 16 bytes each (+ 0 bytes user-defined timestamp) Values: 1000 bytes each (1000 bytes after compression) Entries: 20000000 Prefix: 0 bytes Keys per prefix: 0 RawSize: 19378.7 MB (estimated) FileSize: 19378.7 MB (estimated) Write rate: 0 bytes/second Read rate: 0 ops/second Compression: NoCompression Compression sampling rate: 0 Memtablerep: SkipListFactory Perf Level: 1 ------------------------------------------------ DB path: [/mnt/rocksdb] overwrite : 6.751 micros/op 148123 ops/sec 20.004 seconds 2962999 operations; 143.5 MB/s Microseconds per write: Count: 2962999 Average: 6.7511 StdDev: 114.56 Min: 1 Median: 5.0826 Max: 192460 Percentiles: P50: 5.08 P75: 7.72 P99: 20.64 P99.9: 116.41 P99.99: 368.58 ------------------------------------------------------ [ 0, 1 ] 2078 0.070% 0.070% ( 1, 2 ] 284287 9.595% 9.665% ## ( 2, 3 ] 413239 13.947% 23.611% ### ( 3, 4 ] 134079 4.525% 28.136% # ( 4, 6 ] 1196809 40.392% 68.528% ######## ( 6, 10 ] 445728 15.043% 83.571% ### ( 10, 15 ] 403294 13.611% 97.182% ### ( 15, 22 ] 66845 2.256% 99.438% ( 22, 34 ] 11566 0.390% 99.829% ( 34, 51 ] 1873 0.063% 99.892% ( 51, 76 ] 168 0.006% 99.898% ( 76, 110 ] 20 0.001% 99.898% ( 110, 170 ] 468 0.016% 99.914% ( 170, 250 ] 615 0.021% 99.935% ( 250, 380 ] 1791 0.060% 99.995% ( 380, 580 ] 120 0.004% 99.999% ( 580, 870 ] 14 0.000% 100.000% ( 870, 1300 ] 1 0.000% 100.000% ( 1900, 2900 ] 1 0.000% 100.000% ( 22000, 33000 ] 2 0.000% 100.000% ( 170000, 250000 ] 1 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 : 0 rocksdb.bloom.filter.full.positive COUNT : 0 rocksdb.bloom.filter.full.true.positive COUNT : 0 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 : 0 rocksdb.memtable.miss COUNT : 0 rocksdb.l0.hit COUNT : 0 rocksdb.l1.hit COUNT : 0 rocksdb.l2andup.hit COUNT : 0 rocksdb.compaction.key.drop.new COUNT : 57059 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 : 2962999 rocksdb.number.keys.read COUNT : 0 rocksdb.number.keys.updated COUNT : 0 rocksdb.bytes.written COUNT : 3057814968 rocksdb.bytes.read COUNT : 0 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 : 150 rocksdb.no.file.errors COUNT : 0 rocksdb.stall.micros COUNT : 219207 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 : 6692057736 rocksdb.compact.write.bytes COUNT : 6631449950 rocksdb.flush.write.bytes COUNT : 2968394556 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 : 21 rocksdb.number.superversion_acquires COUNT : 0 rocksdb.number.superversion_releases COUNT : 0 rocksdb.number.superversion_cleanups COUNT : 0 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 : 72 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 : 2948626432 rocksdb.memtable.garbage.bytes.at.flush COUNT : 4958208 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 : 6724356373 rocksdb.non.last.level.read.count COUNT : 1621752 rocksdb.block.checksum.compute.count COUNT : 1621473 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 : 0.000000 P95 : 0.000000 P99 : 0.000000 P100 : 0.000000 COUNT : 0 SUM : 0 rocksdb.db.write.micros P50 : 3.893817 P95 : 12.701971 P99 : 17.507980 P100 : 192453.000000 COUNT : 2962999 SUM : 16534705 rocksdb.compaction.times.micros P50 : 1157500.000000 P95 : 5803333.333333 P99 : 6076006.000000 P100 : 6076006.000000 COUNT : 19 SUM : 31492182 rocksdb.compaction.times.cpu_micros P50 : 1058333.333333 P95 : 5455000.000000 P99 : 5906027.000000 P100 : 5906027.000000 COUNT : 19 SUM : 30268435 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 : 715.333333 P95 : 1693.000000 P99 : 1693.000000 P100 : 1693.000000 COUNT : 44 SUM : 36622 rocksdb.compaction.outfile.sync.micros P50 : 804.750000 P95 : 1270.821429 P99 : 1666.000000 P100 : 1826.000000 COUNT : 78 SUM : 65212 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 : 126.000000 P95 : 343.166667 P99 : 655.400000 P100 : 669.000000 COUNT : 74 SUM : 10905 rocksdb.table.open.io.micros P50 : 1460.000000 P95 : 21333.333333 P99 : 28401.000000 P100 : 28401.000000 COUNT : 150 SUM : 572153 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 : 4.790647 P95 : 8.338302 P99 : 12.574389 P100 : 15651.000000 COUNT : 1621062 SUM : 8846838 rocksdb.read.block.get.micros P50 : 567.755102 P95 : 5905.263158 P99 : 8800.000000 P100 : 9368.000000 COUNT : 300 SUM : 399606 rocksdb.write.raw.block.micros P50 : 0.530770 P95 : 1.218275 P99 : 5.067867 P100 : 3594.000000 COUNT : 2336947 SUM : 5365453 rocksdb.numfiles.in.singlecompaction P50 : 3.357143 P95 : 8.000000 P99 : 9.600000 P100 : 10.000000 COUNT : 30 SUM : 120 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 : 33000.000000 P95 : 192124.000000 P99 : 192124.000000 P100 : 192124.000000 COUNT : 2 SUM : 219207 rocksdb.sst.read.micros P50 : 1.949510 P95 : 4.574264 P99 : 8.202476 P100 : 9304.000000 COUNT : 1621933 SUM : 5196625 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 : 0.000000 P95 : 0.000000 P99 : 0.000000 P100 : 0.000000 COUNT : 0 SUM : 0 rocksdb.bytes.per.write P50 : 1032.000000 P95 : 1032.000000 P99 : 1032.000000 P100 : 1032.000000 COUNT : 2962999 SUM : 3057814968 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 : 215128.205128 P95 : 430666.666667 P99 : 455143.000000 P100 : 455143.000000 COUNT : 44 SUM : 10414778 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 : 81 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