[2024-07-15 13:15:45.200016] Starting SPDK v24.01.1-pre git sha1 4b94202c6 / DPDK 22.11.4 initialization... [2024-07-15 13:15:45.200142] [ DPDK EAL parameters: rocksdb --no-shconf -c 0x1 --huge-unlink --no-telemetry --log-level=lib.eal:6 --log-level=lib.cryptodev:5 --log-level=user1:6 --base-virtaddr=0x200000000000 --match-allocations --file-prefix=spdk_pid1306225 ] Set seed to 1721042145197408 because --seed was 0 EAL: No free 2048 kB hugepages reported on node 1 [2024-07-15 13:15:45.313830] app.c: 798:spdk_app_start: *NOTICE*: Total cores available: 1 [2024-07-15 13:15:45.356106] app.c: 488:app_setup_trace: *NOTICE*: Tracepoint Group Mask 0x80 specified. [2024-07-15 13:15:45.356160] app.c: 489:app_setup_trace: *NOTICE*: Use 'spdk_trace -s rocksdb -p 1306225' to capture a snapshot of events at runtime. [2024-07-15 13:15:45.356175] app.c: 494:app_setup_trace: *NOTICE*: Or copy /dev/shm/rocksdb_trace.pid1306225 for offline analysis/debug. [2024-07-15 13:15:45.356204] reactor.c: 937:reactor_run: *NOTICE*: Reactor started on core 0 RocksDB: version 8.1.1 Date: Mon Jul 15 13:15:47 2024 CPU: 72 * Intel(R) Xeon(R) Gold 6140 CPU @ 2.30GHz CPUCache: 25344 KB 2024/07/15-13:15:51 ... thread 0: (1000000,1000000) ops and (252039.6,252039.6) ops/second in (3.967630,3.967630) 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/14 3.44 GB 62.9 0.5 0.0 0.5 1.4 0.9 0.0 1.6 102.7 282.3 4.91 4.32 16 0.307 511K 479 0.0 0.0 L1 11/11 1.19 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 19/0 961.83 MB 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 L4 161/0 9.54 GB 1.0 0.8 0.3 0.5 0.8 0.3 0.0 2.4 153.8 151.5 5.50 4.40 5 1.099 0 0 0.0 0.0 L5 1156/0 91.54 GB 0.9 0.0 0.0 0.0 0.0 0.0 1.0 0.0 0.0 0.0 0.00 0.00 0 0.000 0 0 0.0 0.0 Sum 1365/25 106.65 GB 0.0 1.3 0.3 1.0 2.2 1.2 1.0 2.5 129.7 213.2 10.40 8.72 21 0.495 511K 479 0.0 0.0 Int 0/0 0.00 KB 0.0 1.3 0.3 1.0 2.2 1.2 1.0 2.5 129.7 213.2 10.40 8.72 21 0.495 511K 479 0.0 0.0 ** Compaction Stats [default] ** Priority Files Size Score Read(GB) Rn(GB) Rnp1(GB) Write(GB) Wnew(GB) Moved(GB) W-Amp Rd(MB/s) Wr(MB/s) Comp(sec) CompMergeCPU(sec) Comp(cnt) Avg(sec) KeyIn KeyDrop Rblob(GB) Wblob(GB) --------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------- Low 0/0 0.00 KB 0.0 1.3 0.3 1.0 1.3 0.3 0.0 0.0 182.0 180.2 7.41 6.29 7 1.059 511K 479 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 295.1 2.99 2.43 14 0.213 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.1 total, 4.0 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.17 GB write, 535.48 MB/s write, 1.32 GB read, 325.76 MB/s read, 10.4 seconds Interval compaction: 2.17 GB write, 558.91 MB/s write, 1.32 GB read, 340.00 MB/s read, 10.4 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: 270267 Average: 13.9538 StdDev: 103.59 Min: 1 Median: 1.5272 Max: 28162 Percentiles: P50: 1.53 P75: 2.00 P99: 241.86 P99.9: 629.91 P99.99: 885.67 ------------------------------------------------------ [ 0, 1 ] 59439 21.993% 21.993% #### ( 1, 2 ] 143572 53.122% 75.115% ########### ( 2, 3 ] 27902 10.324% 85.439% ## ( 3, 4 ] 12341 4.566% 90.005% # ( 4, 6 ] 8049 2.978% 92.983% # ( 6, 10 ] 3576 1.323% 94.306% ( 10, 15 ] 817 0.302% 94.609% ( 15, 22 ] 189 0.070% 94.679% ( 22, 34 ] 67 0.025% 94.703% ( 34, 51 ] 32 0.012% 94.715% ( 51, 76 ] 16 0.006% 94.721% ( 76, 110 ] 705 0.261% 94.982% ( 110, 170 ] 6075 2.248% 97.230% ( 170, 250 ] 5326 1.971% 99.200% ( 250, 380 ] 1259 0.466% 99.666% ( 380, 580 ] 582 0.215% 99.882% ( 580, 870 ] 289 0.107% 99.989% ( 870, 1300 ] 109 0.040% 100.029% ( 1300, 1900 ] 34 0.013% 100.041% ( 1900, 2900 ] 9 0.003% 100.045% ( 2900, 4400 ] 19 0.007% 100.052% ( 4400, 6600 ] 1 0.000% 100.052% ( 9900, 14000 ] 1 0.000% 100.053% ( 14000, 22000 ] 1 0.000% 100.053% ( 22000, 33000 ] 2 0.001% 100.054% ** Level 1 read latency histogram (micros): Count: 523 Average: 353.0115 StdDev: 573.17 Min: 88 Median: 191.4365 Max: 3885 Percentiles: P50: 191.44 P75: 249.23 P99: 3419.37 P99.9: 3885.00 P99.99: 3885.00 ------------------------------------------------------ ( 76, 110 ] 21 4.015% 4.015% # ( 110, 170 ] 192 36.711% 40.727% ####### ( 170, 250 ] 181 34.608% 75.335% ####### ( 250, 380 ] 57 10.899% 86.233% ## ( 380, 580 ] 23 4.398% 90.631% # ( 580, 870 ] 13 2.486% 93.117% ( 870, 1300 ] 9 1.721% 94.837% ( 1300, 1900 ] 5 0.956% 95.793% ( 1900, 2900 ] 14 2.677% 98.470% # ( 2900, 4400 ] 8 1.530% 100.000% ** Level 3 read latency histogram (micros): Count: 29058 Average: 9.6565 StdDev: 63.62 Min: 1 Median: 1.7349 Max: 1930 Percentiles: P50: 1.73 P75: 2.68 P99: 218.88 P99.9: 1071.62 P99.99: 1785.65 ------------------------------------------------------ [ 0, 1 ] 3053 10.507% 10.507% ## ( 1, 2 ] 15616 53.741% 64.247% ########### ( 2, 3 ] 4574 15.741% 79.988% ### ( 3, 4 ] 2370 8.156% 88.144% ## ( 4, 6 ] 1696 5.837% 93.981% # ( 6, 10 ] 819 2.819% 96.800% # ( 10, 15 ] 205 0.705% 97.505% ( 15, 22 ] 47 0.162% 97.667% ( 22, 34 ] 23 0.079% 97.746% ( 34, 51 ] 15 0.052% 97.798% ( 51, 76 ] 5 0.017% 97.815% ( 76, 110 ] 12 0.041% 97.856% ( 110, 170 ] 209 0.719% 98.575% ( 170, 250 ] 202 0.695% 99.270% ( 250, 380 ] 74 0.255% 99.525% ( 380, 580 ] 50 0.172% 99.697% ( 580, 870 ] 43 0.148% 99.845% ( 870, 1300 ] 34 0.117% 99.962% ( 1300, 1900 ] 10 0.034% 99.997% ( 1900, 2900 ] 1 0.003% 100.000% ** Level 4 read latency histogram (micros): Count: 92388 Average: 21.9985 StdDev: 246.50 Min: 1 Median: 1.8803 Max: 25148 Percentiles: P50: 1.88 P75: 3.23 P99: 403.15 P99.9: 1113.33 P99.99: 1293.00 ------------------------------------------------------ [ 0, 1 ] 7105 7.690% 7.690% ## ( 1, 2 ] 44403 48.061% 55.752% ########## ( 2, 3 ] 15702 16.996% 72.748% ### ( 3, 4 ] 9054 9.800% 82.548% ## ( 4, 6 ] 7549 8.171% 90.718% ## ( 6, 10 ] 2872 3.109% 93.827% # ( 10, 15 ] 836 0.905% 94.732% ( 15, 22 ] 134 0.145% 94.877% ( 22, 34 ] 55 0.060% 94.937% ( 34, 51 ] 26 0.028% 94.965% ( 51, 76 ] 11 0.012% 94.977% ( 76, 110 ] 164 0.178% 95.154% ( 110, 170 ] 1491 1.614% 96.768% ( 170, 250 ] 1502 1.626% 98.394% ( 250, 380 ] 507 0.549% 98.943% ( 380, 580 ] 459 0.497% 99.439% ( 580, 870 ] 313 0.339% 99.778% ( 870, 1300 ] 199 0.215% 99.994% ( 1300, 1900 ] 73 0.079% 100.073% ( 1900, 2900 ] 23 0.025% 100.097% ( 2900, 4400 ] 10 0.011% 100.108% ( 4400, 6600 ] 2 0.002% 100.110% ( 6600, 9900 ] 3 0.003% 100.114% ( 9900, 14000 ] 1 0.001% 100.115% ( 14000, 22000 ] 2 0.002% 100.117% ( 22000, 33000 ] 7 0.008% 100.124% ** Level 5 read latency histogram (micros): Count: 35538 Average: 280.8931 StdDev: 513.21 Min: 85 Median: 192.2874 Max: 35985 Percentiles: P50: 192.29 P75: 249.53 P99: 1559.64 P99.9: 3648.84 P99.99: 6038.16 ------------------------------------------------------ ( 76, 110 ] 1401 3.942% 3.942% # ( 110, 170 ] 12909 36.324% 40.267% ####### ( 170, 250 ] 12416 34.937% 75.204% ####### ( 250, 380 ] 3504 9.860% 85.064% ## ( 380, 580 ] 2288 6.438% 91.502% # ( 580, 870 ] 1480 4.165% 95.667% # ( 870, 1300 ] 1031 2.901% 98.568% # ( 1300, 1900 ] 355 0.999% 99.567% ( 1900, 2900 ] 94 0.265% 99.831% ( 2900, 4400 ] 49 0.138% 99.969% ( 4400, 6600 ] 10 0.028% 99.997% ( 6600, 9900 ] 2 0.006% 100.003% ( 9900, 14000 ] 2 0.006% 100.008% ( 14000, 22000 ] 2 0.006% 100.014% ( 22000, 33000 ] 4 0.011% 100.025% ( 33000, 50000 ] 2 0.006% 100.031% ** DB Stats ** Uptime(secs): 4.1 total, 4.0 interval Cumulative writes: 0 writes, 1000K keys, 0 commit groups, 0.0 writes per commit group, ingest: 0.96 GB, 237.56 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, 247.89 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/15-13:15:58 ... thread 0: (1000000,2000000) ops and (141801.0,181492.1) ops/second in (7.052134,11.019764) seconds ** Compaction Stats [default] ** Level Files Size Score Read(GB) Rn(GB) Rnp1(GB) Write(GB) Wnew(GB) Moved(GB) W-Amp Rd(MB/s) Wr(MB/s) Comp(sec) CompMergeCPU(sec) Comp(cnt) Avg(sec) KeyIn KeyDrop Rblob(GB) Wblob(GB) ------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------ L0 19/17 4.42 GB 31.5 1.7 0.0 1.7 3.6 1.8 0.0 1.9 140.4 290.7 12.56 11.60 37 0.340 1791K 1722 0.0 0.0 L1 11/11 1.19 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 19/0 961.83 MB 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 L4 161/0 9.54 GB 1.0 0.8 0.3 0.5 0.8 0.3 0.0 2.4 153.8 151.5 5.50 4.40 5 1.099 0 0 0.0 0.0 L5 1156/0 91.54 GB 0.9 0.0 0.0 0.0 0.0 0.0 1.0 0.0 0.0 0.0 0.00 0.00 0 0.000 0 0 0.0 0.0 Sum 1366/28 107.63 GB 0.0 2.5 0.3 2.2 4.4 2.2 1.0 2.4 144.5 248.3 18.06 16.01 42 0.430 1791K 1722 0.0 0.0 Int 0/0 0.00 KB 0.0 2.5 0.3 2.2 4.4 2.2 1.0 2.4 144.5 248.3 18.06 16.01 42 0.430 1791K 1722 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.5 0.3 2.2 2.5 0.3 0.0 0.0 216.5 215.3 12.05 10.86 12 1.004 1791K 1722 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 314.6 6.01 5.15 30 0.200 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.2 total, 11.0 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: 4.38 GB write, 400.63 MB/s write, 2.55 GB read, 233.10 MB/s read, 18.1 seconds Interval compaction: 4.38 GB write, 406.94 MB/s write, 2.55 GB read, 236.77 MB/s read, 18.1 seconds Write Stall (count): cf-l0-file-count-limit-delays-with-ongoing-compaction: 7, cf-l0-file-count-limit-stops-with-ongoing-compaction: 0, l0-file-count-limit-delays: 7, 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: 7, total-stops: 0, interval: 7 total count ** File Read Latency Histogram By Level [default] ** ** Level 0 read latency histogram (micros): Count: 838586 Average: 16.2259 StdDev: 73.08 Min: 0 Median: 1.4416 Max: 28162 Percentiles: P50: 1.44 P75: 1.92 P99: 238.80 P99.9: 443.22 P99.99: 555.11 ------------------------------------------------------ [ 0, 1 ] 225087 26.841% 26.841% ##### ( 1, 2 ] 439739 52.438% 79.279% ########## ( 2, 3 ] 60377 7.200% 86.479% # ( 3, 4 ] 25880 3.086% 89.565% # ( 4, 6 ] 15875 1.893% 91.458% ( 6, 10 ] 5441 0.649% 92.107% ( 10, 15 ] 1328 0.158% 92.266% ( 15, 22 ] 274 0.033% 92.298% ( 22, 34 ] 91 0.011% 92.309% ( 34, 51 ] 36 0.004% 92.313% ( 51, 76 ] 17 0.002% 92.316% ( 76, 110 ] 3680 0.439% 92.754% ( 110, 170 ] 31907 3.805% 96.559% # ( 170, 250 ] 23801 2.838% 99.397% # ( 250, 380 ] 3788 0.452% 99.849% ( 380, 580 ] 1349 0.161% 100.010% ( 580, 870 ] 392 0.047% 100.057% ( 870, 1300 ] 119 0.014% 100.071% ( 1300, 1900 ] 37 0.004% 100.075% ( 1900, 2900 ] 9 0.001% 100.076% ( 2900, 4400 ] 19 0.002% 100.079% ( 4400, 6600 ] 1 0.000% 100.079% ( 9900, 14000 ] 1 0.000% 100.079% ( 14000, 22000 ] 1 0.000% 100.079% ( 22000, 33000 ] 2 0.000% 100.079% ** Level 1 read latency histogram (micros): Count: 2207 Average: 216.2157 StdDev: 293.93 Min: 87 Median: 165.1222 Max: 3885 Percentiles: P50: 165.12 P75: 217.35 P99: 1891.60 P99.9: 3885.00 P99.99: 3885.00 ------------------------------------------------------ ( 76, 110 ] 126 5.709% 5.709% # ( 110, 170 ] 1064 48.210% 53.919% ########## ( 170, 250 ] 786 35.614% 89.533% ####### ( 250, 380 ] 130 5.890% 95.424% # ( 380, 580 ] 49 2.220% 97.644% ( 580, 870 ] 15 0.680% 98.324% ( 870, 1300 ] 10 0.453% 98.777% ( 1300, 1900 ] 5 0.227% 99.003% ( 1900, 2900 ] 14 0.634% 99.638% ( 2900, 4400 ] 8 0.362% 100.000% ** Level 3 read latency histogram (micros): Count: 30590 Average: 17.8934 StdDev: 72.87 Min: 1 Median: 1.7839 Max: 1930 Percentiles: P50: 1.78 P75: 2.93 P99: 249.91 P99.9: 1052.24 P99.99: 1776.46 ------------------------------------------------------ [ 0, 1 ] 3053 9.980% 9.980% ## ( 1, 2 ] 15616 51.049% 61.030% ########## ( 2, 3 ] 4574 14.953% 75.982% ### ( 3, 4 ] 2370 7.748% 83.730% ## ( 4, 6 ] 1696 5.544% 89.274% # ( 6, 10 ] 819 2.677% 91.952% # ( 10, 15 ] 205 0.670% 92.622% ( 15, 22 ] 47 0.154% 92.775% ( 22, 34 ] 23 0.075% 92.851% ( 34, 51 ] 15 0.049% 92.900% ( 51, 76 ] 5 0.016% 92.916% ( 76, 110 ] 100 0.327% 93.243% ( 110, 170 ] 1000 3.269% 96.512% # ( 170, 250 ] 762 2.491% 99.003% ( 250, 380 ] 143 0.467% 99.470% ( 380, 580 ] 69 0.226% 99.696% ( 580, 870 ] 48 0.157% 99.853% ( 870, 1300 ] 34 0.111% 99.964% ( 1300, 1900 ] 10 0.033% 99.997% ( 1900, 2900 ] 1 0.003% 100.000% ** Level 4 read latency histogram (micros): Count: 104884 Average: 38.6512 StdDev: 237.11 Min: 1 Median: 2.0539 Max: 25148 Percentiles: P50: 2.05 P75: 4.47 P99: 419.15 P99.9: 1088.46 P99.99: 1290.40 ------------------------------------------------------ [ 0, 1 ] 7106 6.775% 6.775% # ( 1, 2 ] 44473 42.402% 49.177% ######## ( 2, 3 ] 16006 15.261% 64.438% ### ( 3, 4 ] 9212 8.783% 73.221% ## ( 4, 6 ] 7901 7.533% 80.754% ## ( 6, 10 ] 2913 2.777% 83.531% # ( 10, 15 ] 844 0.805% 84.336% ( 15, 22 ] 136 0.130% 84.466% ( 22, 34 ] 55 0.052% 84.518% ( 34, 51 ] 26 0.025% 84.543% ( 51, 76 ] 11 0.010% 84.553% ( 76, 110 ] 851 0.811% 85.365% ( 110, 170 ] 7411 7.066% 92.431% # ( 170, 250 ] 5728 5.461% 97.892% # ( 250, 380 ] 1040 0.992% 98.884% ( 380, 580 ] 624 0.595% 99.478% ( 580, 870 ] 340 0.324% 99.803% ( 870, 1300 ] 201 0.192% 99.994% ( 1300, 1900 ] 73 0.070% 100.064% ( 1900, 2900 ] 23 0.022% 100.086% ( 2900, 4400 ] 10 0.010% 100.095% ( 4400, 6600 ] 2 0.002% 100.097% ( 6600, 9900 ] 3 0.003% 100.100% ( 9900, 14000 ] 1 0.001% 100.101% ( 14000, 22000 ] 2 0.002% 100.103% ( 22000, 33000 ] 7 0.007% 100.110% ** Level 5 read latency histogram (micros): Count: 135524 Average: 202.3877 StdDev: 271.91 Min: 84 Median: 166.3340 Max: 35985 Percentiles: P50: 166.33 P75: 219.27 P99: 940.21 P99.9: 1870.55 P99.99: 3219.82 ------------------------------------------------------ ( 76, 110 ] 7309 5.393% 5.393% # ( 110, 170 ] 64387 47.510% 52.903% ########## ( 170, 250 ] 48625 35.879% 88.782% ####### ( 250, 380 ] 8156 6.018% 94.800% # ( 380, 580 ] 3850 2.841% 97.641% # ( 580, 870 ] 1670 1.232% 98.873% ( 870, 1300 ] 1052 0.776% 99.650% ( 1300, 1900 ] 357 0.263% 99.913% ( 1900, 2900 ] 94 0.069% 99.982% ( 2900, 4400 ] 49 0.036% 100.018% ( 4400, 6600 ] 10 0.007% 100.026% ( 6600, 9900 ] 2 0.001% 100.027% ( 9900, 14000 ] 2 0.001% 100.029% ( 14000, 22000 ] 2 0.001% 100.030% ( 22000, 33000 ] 4 0.003% 100.033% ( 33000, 50000 ] 2 0.001% 100.035% ** DB Stats ** Uptime(secs): 11.2 total, 7.1 interval Cumulative writes: 0 writes, 2000K keys, 0 commit groups, 0.0 writes per commit group, ingest: 1.92 GB, 175.83 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:3.191 H:M:S, 28.5 percent Interval writes: 0 writes, 1000K keys, 0 commit groups, 0.0 writes per commit group, ingest: 984.19 MB, 139.56 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.191 H:M:S, 45.2 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 : 208.543 micros/op 19165 ops/sec 60.160 seconds 1152996 operations; 18.6 MB/s (298999 of 298999 found) Microseconds per read: Count: 1152996 Average: 208.5456 StdDev: 294.74 Min: 2 Median: 194.8276 Max: 90380 Percentiles: P50: 194.83 P75: 233.61 P99: 557.76 P99.9: 3296.29 P99.99: 10643.59 ------------------------------------------------------ ( 1, 2 ] 11 0.001% 0.001% ( 2, 3 ] 75 0.007% 0.007% ( 3, 4 ] 68 0.006% 0.013% ( 4, 6 ] 76 0.007% 0.020% ( 6, 10 ] 556 0.048% 0.068% ( 10, 15 ] 8425 0.731% 0.799% ( 15, 22 ] 3175 0.275% 1.074% ( 22, 34 ] 9820 0.852% 1.926% ( 34, 51 ] 1691 0.147% 2.073% ( 51, 76 ] 205 0.018% 2.090% ( 76, 110 ] 12223 1.060% 3.150% ( 110, 170 ] 355658 30.846% 33.997% ###### ( 170, 250 ] 594547 51.565% 85.562% ########## ( 250, 380 ] 117688 10.207% 95.769% ## ( 380, 580 ] 41909 3.635% 99.404% # ( 580, 870 ] 4846 0.420% 99.825% ( 870, 1300 ] 476 0.041% 99.866% ( 1300, 1900 ] 69 0.006% 99.872% ( 1900, 2900 ] 190 0.016% 99.888% ( 2900, 4400 ] 511 0.044% 99.933% ( 4400, 6600 ] 467 0.041% 99.973% ( 6600, 9900 ] 184 0.016% 99.989% ( 9900, 14000 ] 59 0.005% 99.994% ( 14000, 22000 ] 36 0.003% 99.997% ( 22000, 33000 ] 22 0.002% 99.999% ( 33000, 50000 ] 5 0.000% 100.000% ( 50000, 75000 ] 2 0.000% 100.000% ( 75000, 110000 ] 2 0.000% 100.000% STATISTICS: rocksdb.block.cache.miss COUNT : 0 rocksdb.block.cache.hit COUNT : 0 rocksdb.block.cache.add COUNT : 0 rocksdb.block.cache.add.failures COUNT : 0 rocksdb.block.cache.index.miss COUNT : 0 rocksdb.block.cache.index.hit COUNT : 0 rocksdb.block.cache.index.add COUNT : 0 rocksdb.block.cache.index.bytes.insert COUNT : 0 rocksdb.block.cache.filter.miss COUNT : 0 rocksdb.block.cache.filter.hit COUNT : 0 rocksdb.block.cache.filter.add COUNT : 0 rocksdb.block.cache.filter.bytes.insert COUNT : 0 rocksdb.block.cache.data.miss COUNT : 0 rocksdb.block.cache.data.hit COUNT : 0 rocksdb.block.cache.data.add COUNT : 0 rocksdb.block.cache.data.bytes.insert COUNT : 0 rocksdb.block.cache.bytes.read COUNT : 0 rocksdb.block.cache.bytes.write COUNT : 0 rocksdb.bloom.filter.useful COUNT : 19227913 rocksdb.bloom.filter.full.positive COUNT : 1339478 rocksdb.bloom.filter.full.true.positive COUNT : 1152586 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 : 410 rocksdb.memtable.miss COUNT : 1152586 rocksdb.l0.hit COUNT : 53903 rocksdb.l1.hit COUNT : 13540 rocksdb.l2andup.hit COUNT : 1085143 rocksdb.compaction.key.drop.new COUNT : 41356 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 : 2330822 rocksdb.number.keys.read COUNT : 1152996 rocksdb.number.keys.updated COUNT : 0 rocksdb.bytes.written COUNT : 2405408304 rocksdb.bytes.read COUNT : 1152996000 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 : 1419 rocksdb.no.file.errors COUNT : 0 rocksdb.stall.micros COUNT : 50049945 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 : 6333362441 rocksdb.compact.write.bytes COUNT : 6295295699 rocksdb.flush.write.bytes COUNT : 2378216958 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 : 33 rocksdb.number.superversion_acquires COUNT : 357 rocksdb.number.superversion_releases COUNT : 344 rocksdb.number.superversion_cleanups COUNT : 89 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 : 2358912000 rocksdb.memtable.garbage.bytes.at.flush COUNT : 809984 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 : 12140983821 rocksdb.non.last.level.read.count COUNT : 2787450 rocksdb.block.checksum.compute.count COUNT : 2886197 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 : 194.253718 P95 : 369.297938 P99 : 557.108218 P100 : 90379.000000 COUNT : 1152996 SUM : 239598374 rocksdb.db.write.micros P50 : 1.651131 P95 : 5.875519 P99 : 1092.931977 P100 : 5917.000000 COUNT : 2330822 SUM : 56872727 rocksdb.compaction.times.micros P50 : 1030000.000000 P95 : 4960000.000000 P99 : 5802557.000000 P100 : 5802557.000000 COUNT : 14 SUM : 18739826 rocksdb.compaction.times.cpu_micros P50 : 1011111.111111 P95 : 4960000.000000 P99 : 5715123.000000 P100 : 5715123.000000 COUNT : 14 SUM : 17454230 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 : 696.000000 P95 : 2000.000000 P99 : 2643.000000 P100 : 2643.000000 COUNT : 36 SUM : 30609 rocksdb.compaction.outfile.sync.micros P50 : 960.526316 P95 : 3350.000000 P99 : 7917.000000 P100 : 7917.000000 COUNT : 48 SUM : 58542 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.200000 P95 : 819.250000 P99 : 952.000000 P100 : 952.000000 COUNT : 54 SUM : 12823 rocksdb.table.open.io.micros P50 : 3214.640411 P95 : 7174.326923 P99 : 12596.238095 P100 : 81303.000000 COUNT : 1419 SUM : 5101523 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.672833 P95 : 195.244420 P99 : 243.933949 P100 : 28167.000000 COUNT : 1535120 SUM : 55046103 rocksdb.read.block.get.micros P50 : 148.457812 P95 : 241.454459 P99 : 354.355483 P100 : 36048.000000 COUNT : 1349658 SUM : 207124470 rocksdb.write.raw.block.micros P50 : 0.570162 P95 : 1.741365 P99 : 5.581348 P100 : 37129.000000 COUNT : 2100836 SUM : 4953406 rocksdb.numfiles.in.singlecompaction P50 : 3.454545 P95 : 15.700000 P99 : 20.740000 P100 : 21.000000 COUNT : 18 SUM : 89 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.591588 P95 : 2195.387876 P99 : 2759.131400 P100 : 3377.000000 COUNT : 209473 SUM : 50069157 rocksdb.sst.read.micros P50 : 111.213000 P95 : 227.280715 P99 : 293.961432 P100 : 35985.000000 COUNT : 2787474 SUM : 251996618 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 : 1152996 SUM : 1152996000 rocksdb.bytes.per.write P50 : 1032.000000 P95 : 1032.000000 P99 : 1032.000000 P100 : 1032.000000 COUNT : 2330822 SUM : 2405408304 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 : 211379.310345 P95 : 321500.000000 P99 : 345409.000000 P100 : 345409.000000 COUNT : 36 SUM : 7265588 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 : 56 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