[2024-12-09 17:19:57.940770] Starting SPDK v25.01-pre git sha1 6584139bf / DPDK 24.03.0 initialization... [2024-12-09 17:19:57.941077] [ 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_pid804854 ] Set seed to 1733761197933568 because --seed was 0 EAL: No free 2048 kB hugepages reported on node 1 [2024-12-09 17:19:58.091771] app.c: 919:spdk_app_start: *NOTICE*: Total cores available: 1 [2024-12-09 17:19:58.200693] app.c: 612:app_setup_trace: *NOTICE*: Tracepoint Group Mask 0x80 specified. [2024-12-09 17:19:58.200751] app.c: 613:app_setup_trace: *NOTICE*: Use 'spdk_trace -s rocksdb -p 804854' to capture a snapshot of events at runtime. [2024-12-09 17:19:58.200765] app.c: 618:app_setup_trace: *NOTICE*: 'spdk_trace' without parameters will also work if this is the only [2024-12-09 17:19:58.200778] app.c: 619:app_setup_trace: *NOTICE*: SPDK application currently running. [2024-12-09 17:19:58.200788] app.c: 620:app_setup_trace: *NOTICE*: Or copy /dev/shm/rocksdb_trace.pid804854 for offline analysis/debug. [2024-12-09 17:19:58.202120] reactor.c:1005:reactor_run: *NOTICE*: Reactor started on core 0 RocksDB: version 8.1.1 Date: Mon Dec 9 17:20:01 2024 CPU: 72 * Intel(R) Xeon(R) Gold 6140 CPU @ 2.30GHz CPUCache: 25344 KB 2024/12/09-17:20:05 ... thread 0: (1000000,1000000) ops and (218961.0,218961.0) ops/second in (4.567023,4.567023) 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.51 GB 87.7 0.7 0.0 0.7 1.6 0.9 0.0 1.9 124.1 268.2 6.09 5.56 17 0.358 766K 3626 0.0 0.0 L1 4/4 412.80 MB 0.0 0.0 0.0 0.0 0.0 0.0 0.0 0.0 0.0 0.0 0.00 0.00 0 0.000 0 0 0.0 0.0 L3 15/0 861.38 MB 0.9 0.3 0.1 0.2 0.3 0.1 0.0 2.6 198.2 197.2 1.70 1.56 1 1.703 342K 1726 0.0 0.0 L4 125/0 9.65 GB 1.0 0.8 0.6 0.1 0.8 0.6 0.1 1.2 183.1 181.7 4.27 3.75 4 1.067 472K 0 0.0 0.0 L5 133/0 10.58 GB 0.1 0.0 0.0 0.0 0.0 0.0 0.8 0.0 0.0 0.0 0.00 0.00 0 0.000 0 0 0.0 0.0 Sum 294/16 24.99 GB 0.0 1.8 0.8 1.1 2.7 1.6 0.9 3.1 155.4 227.5 12.06 10.87 22 0.548 1581K 5352 0.0 0.0 Int 0/0 0.00 KB 0.0 1.8 0.8 1.1 2.7 1.6 0.9 3.1 155.4 227.5 12.06 10.87 22 0.548 1581K 5352 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.8 0.8 1.1 1.8 0.8 0.0 0.0 207.9 206.6 9.02 8.25 8 1.127 1581K 5352 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 289.6 3.04 2.62 14 0.217 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.7 total, 4.6 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: 2.68 GB write, 587.28 MB/s write, 1.83 GB read, 401.23 MB/s read, 12.1 seconds Interval compaction: 2.68 GB write, 600.49 MB/s write, 1.83 GB read, 410.25 MB/s read, 12.1 seconds Write Stall (count): cf-l0-file-count-limit-delays-with-ongoing-compaction: 3, cf-l0-file-count-limit-stops-with-ongoing-compaction: 0, l0-file-count-limit-delays: 3, 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: 3, total-stops: 0, interval: 3 total count ** File Read Latency Histogram By Level [default] ** ** Level 0 read latency histogram (micros): Count: 227250 Average: 35.8760 StdDev: 162.04 Min: 1 Median: 1.6171 Max: 18092 Percentiles: P50: 1.62 P75: 2.48 P99: 376.31 P99.9: 829.20 P99.99: 1268.09 ------------------------------------------------------ [ 0, 1 ] 39461 17.365% 17.365% ### ( 1, 2 ] 120182 52.885% 70.250% ########### ( 2, 3 ] 22480 9.892% 80.142% ## ( 3, 4 ] 6641 2.922% 83.064% # ( 4, 6 ] 4730 2.081% 85.146% ( 6, 10 ] 1512 0.665% 85.811% ( 10, 15 ] 338 0.149% 85.960% ( 15, 22 ] 46 0.020% 85.980% ( 22, 34 ] 7 0.003% 85.983% ( 34, 51 ] 6 0.003% 85.986% ( 51, 76 ] 2 0.001% 85.987% ( 76, 110 ] 354 0.156% 86.143% ( 110, 170 ] 8176 3.598% 89.740% # ( 170, 250 ] 16812 7.398% 97.138% # ( 250, 380 ] 4354 1.916% 99.054% ( 380, 580 ] 1566 0.689% 99.743% ( 580, 870 ] 414 0.182% 99.926% ( 870, 1300 ] 158 0.070% 99.995% ( 1300, 1900 ] 91 0.040% 100.035% ( 1900, 2900 ] 44 0.019% 100.055% ( 2900, 4400 ] 16 0.007% 100.062% ( 4400, 6600 ] 17 0.007% 100.069% ( 6600, 9900 ] 7 0.003% 100.072% ( 9900, 14000 ] 7 0.003% 100.075% ( 14000, 22000 ] 7 0.003% 100.078% ** Level 1 read latency histogram (micros): Count: 1127 Average: 373.4410 StdDev: 1208.46 Min: 93 Median: 206.7203 Max: 13747 Percentiles: P50: 206.72 P75: 242.96 P99: 7741.80 P99.9: 13422.41 P99.99: 13747.00 ------------------------------------------------------ ( 76, 110 ] 9 0.799% 0.799% ( 110, 170 ] 269 23.869% 24.667% ##### ( 170, 250 ] 622 55.191% 79.858% ########### ( 250, 380 ] 135 11.979% 91.837% ## ( 380, 580 ] 45 3.993% 95.830% # ( 580, 870 ] 19 1.686% 97.516% ( 870, 1300 ] 5 0.444% 97.959% ( 1300, 1900 ] 3 0.266% 98.225% ( 2900, 4400 ] 3 0.266% 98.492% ( 4400, 6600 ] 4 0.355% 98.846% ( 6600, 9900 ] 5 0.444% 99.290% ( 9900, 14000 ] 8 0.710% 100.000% ** Level 3 read latency histogram (micros): Count: 129775 Average: 8.0671 StdDev: 64.93 Min: 0 Median: 1.7712 Max: 15562 Percentiles: P50: 1.77 P75: 2.52 P99: 211.44 P99.9: 454.66 P99.99: 668.40 ------------------------------------------------------ [ 0, 1 ] 5159 3.975% 3.975% # ( 1, 2 ] 77452 59.682% 63.657% ############ ( 2, 3 ] 28540 21.992% 85.649% #### ( 3, 4 ] 7607 5.862% 91.511% # ( 4, 6 ] 5821 4.485% 95.996% # ( 6, 10 ] 1881 1.449% 97.446% ( 10, 15 ] 486 0.374% 97.820% ( 15, 22 ] 62 0.048% 97.868% ( 22, 34 ] 7 0.005% 97.873% ( 34, 51 ] 1 0.001% 97.874% ( 76, 110 ] 29 0.022% 97.896% ( 110, 170 ] 661 0.509% 98.406% ( 170, 250 ] 1489 1.147% 99.553% ( 250, 380 ] 389 0.300% 99.853% ( 380, 580 ] 164 0.126% 99.979% ( 580, 870 ] 46 0.035% 100.015% ( 870, 1300 ] 20 0.015% 100.030% ( 1300, 1900 ] 14 0.011% 100.041% ( 1900, 2900 ] 12 0.009% 100.050% ( 2900, 4400 ] 1 0.001% 100.051% ( 14000, 22000 ] 1 0.001% 100.052% ** Level 4 read latency histogram (micros): Count: 47244 Average: 119.4854 StdDev: 230.87 Min: 1 Median: 5.7879 Max: 15997 Percentiles: P50: 5.79 P75: 203.23 P99: 750.67 P99.9: 2474.31 P99.99: 3816.54 ------------------------------------------------------ [ 0, 1 ] 1339 2.834% 2.834% # ( 1, 2 ] 14771 31.265% 34.100% ###### ( 2, 3 ] 4796 10.152% 44.251% ## ( 3, 4 ] 1553 3.287% 47.538% # ( 4, 6 ] 1301 2.754% 50.292% # ( 6, 10 ] 484 1.024% 51.317% ( 10, 15 ] 151 0.320% 51.636% ( 15, 22 ] 43 0.091% 51.727% ( 22, 34 ] 9 0.019% 51.746% ( 34, 51 ] 2 0.004% 51.750% ( 76, 110 ] 227 0.480% 52.231% ( 110, 170 ] 5774 12.222% 64.453% ## ( 170, 250 ] 11997 25.394% 89.846% ##### ( 250, 380 ] 2991 6.331% 96.177% # ( 380, 580 ] 1147 2.428% 98.605% ( 580, 870 ] 317 0.671% 99.276% ( 870, 1300 ] 159 0.337% 99.613% ( 1300, 1900 ] 99 0.210% 99.822% ( 1900, 2900 ] 64 0.135% 99.958% ( 2900, 4400 ] 25 0.053% 100.011% ( 4400, 6600 ] 3 0.006% 100.017% ( 6600, 9900 ] 4 0.008% 100.025% ( 9900, 14000 ] 2 0.004% 100.030% ( 14000, 22000 ] 1 0.002% 100.032% ** Level 5 read latency histogram (micros): Count: 22668 Average: 242.2815 StdDev: 229.40 Min: 90 Median: 206.3600 Max: 11195 Percentiles: P50: 206.36 P75: 244.38 P99: 1240.82 P99.9: 2852.36 P99.99: 6013.04 ------------------------------------------------------ ( 76, 110 ] 254 1.121% 1.121% ( 110, 170 ] 5661 24.974% 26.094% ##### ( 170, 250 ] 11923 52.598% 78.692% ########### ( 250, 380 ] 3032 13.376% 92.068% ### ( 380, 580 ] 1159 5.113% 97.181% # ( 580, 870 ] 289 1.275% 98.456% ( 870, 1300 ] 143 0.631% 99.087% ( 1300, 1900 ] 111 0.490% 99.576% ( 1900, 2900 ] 77 0.340% 99.916% ( 2900, 4400 ] 16 0.071% 99.987% ( 4400, 6600 ] 1 0.004% 99.991% ( 6600, 9900 ] 1 0.004% 99.996% ( 9900, 14000 ] 1 0.004% 100.000% ** DB Stats ** Uptime(secs): 4.7 total, 4.6 interval Cumulative writes: 0 writes, 1000K keys, 0 commit groups, 0.0 writes per commit group, ingest: 0.96 GB, 210.64 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.717 H:M:S, 15.3 percent Interval writes: 0 writes, 1000K keys, 0 commit groups, 0.0 writes per commit group, ingest: 984.19 MB, 215.33 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.717 H:M:S, 15.7 percent Write Stall (count): write-buffer-manager-limit-stops: 0, num-running-compactions: 1 num-running-flushes: 1 2024/12/09-17:20:14 ... thread 0: (1000000,2000000) ops and (118874.4,154092.0) ops/second in (8.412240,12.979263) 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/12 4.53 GB 192.0 2.8 0.0 2.8 4.6 1.9 0.0 2.4 162.9 273.6 17.34 16.24 39 0.445 2870K 31K 0.0 0.0 L1 4/4 412.80 MB 0.0 0.0 0.0 0.0 0.0 0.0 0.0 0.0 0.0 0.0 0.00 0.00 0 0.000 0 0 0.0 0.0 L3 15/0 861.38 MB 0.9 0.3 0.1 0.2 0.3 0.1 0.0 2.6 198.2 197.2 1.70 1.56 1 1.703 342K 1726 0.0 0.0 L4 125/0 9.65 GB 1.0 0.8 0.6 0.1 0.8 0.6 0.1 1.2 183.1 181.7 4.27 3.75 4 1.067 472K 0 0.0 0.0 L5 133/0 10.58 GB 0.1 0.0 0.0 0.0 0.0 0.0 0.8 0.0 0.0 0.0 0.00 0.00 0 0.000 0 0 0.0 0.0 Sum 295/16 26.01 GB 0.0 3.9 0.8 3.1 5.7 2.6 0.9 3.0 169.2 251.2 23.32 21.55 44 0.530 3685K 32K 0.0 0.0 Int 0/0 0.00 KB 0.0 3.9 0.8 3.1 5.7 2.6 0.9 3.0 169.2 251.2 23.32 21.55 44 0.530 3685K 32K 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.9 0.8 3.1 3.8 0.7 0.0 0.0 231.6 229.4 17.04 16.05 13 1.310 3685K 32K 0.0 0.0 High 0/0 0.00 KB 0.0 0.0 0.0 0.0 1.9 1.9 0.0 0.0 0.0 310.4 6.28 5.49 31 0.203 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): 13.1 total, 13.0 interval Flush(GB): cumulative 1.905, interval 1.905 AddFile(GB): cumulative 0.000, interval 0.000 AddFile(Total Files): cumulative 0, interval 0 AddFile(L0 Files): cumulative 0, interval 0 AddFile(Keys): cumulative 0, interval 0 Cumulative compaction: 5.72 GB write, 447.67 MB/s write, 3.85 GB read, 301.57 MB/s read, 23.3 seconds Interval compaction: 5.72 GB write, 451.22 MB/s write, 3.85 GB read, 303.96 MB/s read, 23.3 seconds Write Stall (count): cf-l0-file-count-limit-delays-with-ongoing-compaction: 12, cf-l0-file-count-limit-stops-with-ongoing-compaction: 0, l0-file-count-limit-delays: 12, l0-file-count-limit-stops: 0, memtable-limit-delays: 0, memtable-limit-stops: 0, pending-compaction-bytes-delays: 0, pending-compaction-bytes-stops: 0, total-delays: 12, total-stops: 0, interval: 12 total count ** File Read Latency Histogram By Level [default] ** ** Level 0 read latency histogram (micros): Count: 840567 Average: 28.4517 StdDev: 101.73 Min: 0 Median: 1.5385 Max: 18092 Percentiles: P50: 1.54 P75: 2.05 P99: 303.64 P99.9: 533.05 P99.99: 625.97 ------------------------------------------------------ [ 0, 1 ] 179076 21.304% 21.304% #### ( 1, 2 ] 447904 53.286% 74.590% ########### ( 2, 3 ] 68032 8.094% 82.684% ## ( 3, 4 ] 19381 2.306% 84.989% ( 4, 6 ] 12690 1.510% 86.499% ( 6, 10 ] 4134 0.492% 86.991% ( 10, 15 ] 703 0.084% 87.075% ( 15, 22 ] 124 0.015% 87.089% ( 22, 34 ] 28 0.003% 87.093% ( 34, 51 ] 11 0.001% 87.094% ( 51, 76 ] 5 0.001% 87.095% ( 76, 110 ] 1681 0.200% 87.295% ( 110, 170 ] 34472 4.101% 91.396% # ( 170, 250 ] 60129 7.153% 98.549% # ( 250, 380 ] 9191 1.093% 99.642% ( 380, 580 ] 2831 0.337% 99.979% ( 580, 870 ] 580 0.069% 100.048% ( 870, 1300 ] 173 0.021% 100.069% ( 1300, 1900 ] 99 0.012% 100.081% ( 1900, 2900 ] 44 0.005% 100.086% ( 2900, 4400 ] 18 0.002% 100.088% ( 4400, 6600 ] 17 0.002% 100.090% ( 6600, 9900 ] 7 0.001% 100.091% ( 9900, 14000 ] 7 0.001% 100.092% ( 14000, 22000 ] 7 0.001% 100.092% ** Level 1 read latency histogram (micros): Count: 3654 Average: 247.7657 StdDev: 678.01 Min: 92 Median: 195.7003 Max: 13747 Percentiles: P50: 195.70 P75: 231.49 P99: 762.32 P99.9: 12127.33 P99.99: 13747.00 ------------------------------------------------------ ( 76, 110 ] 44 1.204% 1.204% ( 110, 170 ] 1127 30.843% 32.047% ###### ( 170, 250 ] 2042 55.884% 87.931% ########### ( 250, 380 ] 304 8.320% 96.251% ## ( 380, 580 ] 86 2.354% 98.604% ( 580, 870 ] 23 0.629% 99.234% ( 870, 1300 ] 5 0.137% 99.371% ( 1300, 1900 ] 3 0.082% 99.453% ( 2900, 4400 ] 3 0.082% 99.535% ( 4400, 6600 ] 4 0.109% 99.644% ( 6600, 9900 ] 5 0.137% 99.781% ( 9900, 14000 ] 8 0.219% 100.000% ** Level 3 read latency histogram (micros): Count: 135191 Average: 15.4168 StdDev: 74.04 Min: 0 Median: 1.8061 Max: 15562 Percentiles: P50: 1.81 P75: 2.66 P99: 243.95 P99.9: 507.61 P99.99: 710.16 ------------------------------------------------------ [ 0, 1 ] 5159 3.816% 3.816% # ( 1, 2 ] 77452 57.291% 61.107% ########### ( 2, 3 ] 28540 21.111% 82.218% #### ( 3, 4 ] 7607 5.627% 87.845% # ( 4, 6 ] 5821 4.306% 92.150% # ( 6, 10 ] 1881 1.391% 93.542% ( 10, 15 ] 486 0.359% 93.901% ( 15, 22 ] 62 0.046% 93.947% ( 22, 34 ] 7 0.005% 93.952% ( 34, 51 ] 1 0.001% 93.953% ( 76, 110 ] 120 0.089% 94.042% ( 110, 170 ] 2459 1.819% 95.861% ( 170, 250 ] 4591 3.396% 99.257% # ( 250, 380 ] 702 0.519% 99.776% ( 380, 580 ] 263 0.195% 99.970% ( 580, 870 ] 59 0.044% 100.014% ( 870, 1300 ] 20 0.015% 100.029% ( 1300, 1900 ] 14 0.010% 100.039% ( 1900, 2900 ] 12 0.009% 100.048% ( 2900, 4400 ] 1 0.001% 100.049% ( 14000, 22000 ] 1 0.001% 100.050% ** Level 4 read latency histogram (micros): Count: 100870 Average: 154.7575 StdDev: 169.06 Min: 1 Median: 169.4137 Max: 15997 Percentiles: P50: 169.41 P75: 218.40 P99: 556.88 P99.9: 1815.03 P99.99: 3561.83 ------------------------------------------------------ [ 0, 1 ] 1339 1.327% 1.327% ( 1, 2 ] 14913 14.784% 16.112% ### ( 2, 3 ] 5246 5.201% 21.313% # ( 3, 4 ] 1865 1.849% 23.161% ( 4, 6 ] 1792 1.777% 24.938% ( 6, 10 ] 517 0.513% 25.451% ( 10, 15 ] 170 0.169% 25.619% ( 15, 22 ] 47 0.047% 25.666% ( 22, 34 ] 11 0.011% 25.677% ( 34, 51 ] 2 0.002% 25.679% ( 76, 110 ] 1123 1.113% 26.792% ( 110, 170 ] 23641 23.437% 50.229% ##### ( 170, 250 ] 41304 40.948% 91.177% ######## ( 250, 380 ] 6126 6.073% 97.250% # ( 380, 580 ] 1996 1.979% 99.229% ( 580, 870 ] 420 0.416% 99.645% ( 870, 1300 ] 167 0.166% 99.811% ( 1300, 1900 ] 105 0.104% 99.915% ( 1900, 2900 ] 64 0.063% 99.978% ( 2900, 4400 ] 27 0.027% 100.005% ( 4400, 6600 ] 3 0.003% 100.008% ( 6600, 9900 ] 4 0.004% 100.012% ( 9900, 14000 ] 2 0.002% 100.014% ( 14000, 22000 ] 1 0.001% 100.015% ** Level 5 read latency histogram (micros): Count: 71584 Average: 207.2807 StdDev: 141.48 Min: 90 Median: 195.0274 Max: 11195 Percentiles: P50: 195.03 P75: 231.55 P99: 598.29 P99.9: 2230.08 P99.99: 3913.81 ------------------------------------------------------ ( 76, 110 ] 1110 1.551% 1.551% ( 110, 170 ] 22417 31.316% 32.866% ###### ( 170, 250 ] 39205 54.768% 87.634% ########### ( 250, 380 ] 6130 8.563% 96.197% ## ( 380, 580 ] 1982 2.769% 98.966% # ( 580, 870 ] 383 0.535% 99.501% ( 870, 1300 ] 146 0.204% 99.705% ( 1300, 1900 ] 114 0.159% 99.864% ( 1900, 2900 ] 77 0.108% 99.972% ( 2900, 4400 ] 19 0.027% 99.999% ( 4400, 6600 ] 1 0.001% 100.000% ( 6600, 9900 ] 1 0.001% 100.001% ( 9900, 14000 ] 1 0.001% 100.003% ** DB Stats ** Uptime(secs): 13.1 total, 8.4 interval Cumulative writes: 0 writes, 2000K keys, 0 commit groups, 0.0 writes per commit group, ingest: 1.92 GB, 150.43 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.315 H:M:S, 40.6 percent Interval writes: 0 writes, 1000K keys, 0 commit groups, 0.0 writes per commit group, ingest: 984.19 MB, 117.00 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.598 H:M:S, 54.7 percent Write Stall (count): write-buffer-manager-limit-stops: 0, num-running-compactions: 1 num-running-flushes: 0 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 : 260.600 micros/op 15293 ops/sec 20.139 seconds 307996 operations; 14.8 MB/s (75999 of 75999 found) Microseconds per read: Count: 307996 Average: 260.6110 StdDev: 391.56 Min: 2 Median: 223.2036 Max: 29619 Percentiles: P50: 223.20 P75: 296.94 P99: 791.59 P99.9: 4018.29 P99.99: 20704.84 ------------------------------------------------------ ( 1, 2 ] 8 0.003% 0.003% ( 2, 3 ] 60 0.019% 0.022% ( 3, 4 ] 111 0.036% 0.058% ( 4, 6 ] 211 0.069% 0.127% ( 6, 10 ] 420 0.136% 0.263% ( 10, 15 ] 2258 0.733% 0.996% ( 15, 22 ] 2932 0.952% 1.948% ( 22, 34 ] 5283 1.715% 3.663% ( 34, 51 ] 1795 0.583% 4.246% ( 51, 76 ] 281 0.091% 4.337% ( 76, 110 ] 162 0.053% 4.390% ( 110, 170 ] 30132 9.783% 14.173% ## ( 170, 250 ] 165921 53.871% 68.044% ########### ( 250, 380 ] 59335 19.265% 87.309% #### ( 380, 580 ] 31215 10.135% 97.444% ## ( 580, 870 ] 6568 2.132% 99.577% ( 870, 1300 ] 792 0.257% 99.834% ( 1300, 1900 ] 104 0.034% 99.868% ( 1900, 2900 ] 59 0.019% 99.887% ( 2900, 4400 ] 55 0.018% 99.905% ( 4400, 6600 ] 93 0.030% 99.935% ( 6600, 9900 ] 100 0.032% 99.967% ( 9900, 14000 ] 35 0.011% 99.979% ( 14000, 22000 ] 42 0.014% 99.992% ( 22000, 33000 ] 24 0.008% 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 : 5539272 rocksdb.bloom.filter.full.positive COUNT : 361237 rocksdb.bloom.filter.full.true.positive COUNT : 307071 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 : 925 rocksdb.memtable.miss COUNT : 307071 rocksdb.l0.hit COUNT : 60537 rocksdb.l1.hit COUNT : 5199 rocksdb.l2andup.hit COUNT : 241335 rocksdb.compaction.key.drop.new COUNT : 81044 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 : 2863021 rocksdb.number.keys.read COUNT : 307996 rocksdb.number.keys.updated COUNT : 0 rocksdb.bytes.written COUNT : 2954637672 rocksdb.bytes.read COUNT : 307996000 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 : 359 rocksdb.no.file.errors COUNT : 0 rocksdb.stall.micros COUNT : 9103709 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 : 6340181337 rocksdb.compact.write.bytes COUNT : 6253234080 rocksdb.flush.write.bytes COUNT : 2836703268 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 : 13 rocksdb.number.superversion_acquires COUNT : 446 rocksdb.number.superversion_releases COUNT : 427 rocksdb.number.superversion_cleanups COUNT : 108 rocksdb.number.block.compressed COUNT : 0 rocksdb.number.block.decompressed COUNT : 0 rocksdb.number.block.not_compressed COUNT : 0 rocksdb.merge.operation.time.nanos COUNT : 0 rocksdb.filter.operation.time.nanos COUNT : 0 rocksdb.row.cache.hit COUNT : 0 rocksdb.row.cache.miss COUNT : 0 rocksdb.read.amp.estimate.useful.bytes COUNT : 0 rocksdb.read.amp.total.read.bytes COUNT : 0 rocksdb.number.rate_limiter.drains COUNT : 0 rocksdb.number.iter.skip COUNT : 0 rocksdb.blobdb.num.put COUNT : 0 rocksdb.blobdb.num.write COUNT : 0 rocksdb.blobdb.num.get COUNT : 0 rocksdb.blobdb.num.multiget COUNT : 0 rocksdb.blobdb.num.seek COUNT : 0 rocksdb.blobdb.num.next COUNT : 0 rocksdb.blobdb.num.prev COUNT : 0 rocksdb.blobdb.num.keys.written COUNT : 0 rocksdb.blobdb.num.keys.read COUNT : 0 rocksdb.blobdb.bytes.written COUNT : 0 rocksdb.blobdb.bytes.read COUNT : 0 rocksdb.blobdb.write.inlined COUNT : 0 rocksdb.blobdb.write.inlined.ttl COUNT : 0 rocksdb.blobdb.write.blob COUNT : 0 rocksdb.blobdb.write.blob.ttl COUNT : 0 rocksdb.blobdb.blob.file.bytes.written COUNT : 0 rocksdb.blobdb.blob.file.bytes.read COUNT : 0 rocksdb.blobdb.blob.file.synced COUNT : 0 rocksdb.blobdb.blob.index.expired.count COUNT : 0 rocksdb.blobdb.blob.index.expired.size COUNT : 0 rocksdb.blobdb.blob.index.evicted.count COUNT : 0 rocksdb.blobdb.blob.index.evicted.size COUNT : 0 rocksdb.blobdb.gc.num.files COUNT : 0 rocksdb.blobdb.gc.num.new.files COUNT : 0 rocksdb.blobdb.gc.failures COUNT : 0 rocksdb.blobdb.gc.num.keys.relocated COUNT : 0 rocksdb.blobdb.gc.bytes.relocated COUNT : 0 rocksdb.blobdb.fifo.num.files.evicted COUNT : 0 rocksdb.blobdb.fifo.num.keys.evicted COUNT : 0 rocksdb.blobdb.fifo.bytes.evicted COUNT : 0 rocksdb.txn.overhead.mutex.prepare COUNT : 0 rocksdb.txn.overhead.mutex.old.commit.map COUNT : 0 rocksdb.txn.overhead.duplicate.key COUNT : 0 rocksdb.txn.overhead.mutex.snapshot COUNT : 0 rocksdb.txn.get.tryagain COUNT : 0 rocksdb.number.multiget.keys.found COUNT : 0 rocksdb.num.iterator.created COUNT : 0 rocksdb.num.iterator.deleted COUNT : 0 rocksdb.block.cache.compression.dict.miss COUNT : 0 rocksdb.block.cache.compression.dict.hit COUNT : 0 rocksdb.block.cache.compression.dict.add COUNT : 0 rocksdb.block.cache.compression.dict.bytes.insert COUNT : 0 rocksdb.block.cache.add.redundant COUNT : 0 rocksdb.block.cache.index.add.redundant COUNT : 0 rocksdb.block.cache.filter.add.redundant COUNT : 0 rocksdb.block.cache.data.add.redundant COUNT : 0 rocksdb.block.cache.compression.dict.add.redundant COUNT : 0 rocksdb.files.marked.trash COUNT : 0 rocksdb.files.deleted.immediately COUNT : 0 rocksdb.error.handler.bg.errro.count COUNT : 0 rocksdb.error.handler.bg.io.errro.count COUNT : 0 rocksdb.error.handler.bg.retryable.io.errro.count COUNT : 0 rocksdb.error.handler.autoresume.count COUNT : 0 rocksdb.error.handler.autoresume.retry.total.count COUNT : 0 rocksdb.error.handler.autoresume.success.count COUNT : 0 rocksdb.memtable.payload.bytes.at.flush COUNT : 2817582080 rocksdb.memtable.garbage.bytes.at.flush COUNT : 4519936 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 : 7453194376 rocksdb.non.last.level.read.count COUNT : 1767076 rocksdb.block.checksum.compute.count COUNT : 1900642 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 : 222.617740 P95 : 531.157190 P99 : 790.575311 P100 : 29618.000000 COUNT : 307996 SUM : 79996189 rocksdb.db.write.micros P50 : 1.512974 P95 : 5.182381 P99 : 9.580304 P100 : 4473.000000 COUNT : 2863021 SUM : 16534181 rocksdb.compaction.times.micros P50 : 1082307.692308 P95 : 3705000.000000 P99 : 4181000.000000 P100 : 4181249.000000 COUNT : 17 SUM : 23736409 rocksdb.compaction.times.cpu_micros P50 : 1056153.846154 P95 : 3705000.000000 P99 : 4023772.000000 P100 : 4023772.000000 COUNT : 17 SUM : 22664106 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 : 575.454545 P95 : 1294.136364 P99 : 1486.000000 P100 : 1486.000000 COUNT : 43 SUM : 32565 rocksdb.compaction.outfile.sync.micros P50 : 937.187500 P95 : 2350.000000 P99 : 2387.000000 P100 : 2387.000000 COUNT : 33 SUM : 32494 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 : 166.785714 P95 : 353.409091 P99 : 450.000000 P100 : 543.000000 COUNT : 65 SUM : 12572 rocksdb.table.open.io.micros P50 : 4816.428571 P95 : 9941.000000 P99 : 43218.888889 P100 : 46641.000000 COUNT : 359 SUM : 2006030 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.581341 P95 : 135.294054 P99 : 242.676293 P100 : 18974.000000 COUNT : 1535118 SUM : 24656582 rocksdb.read.block.get.micros P50 : 193.091882 P95 : 343.544867 P99 : 542.376549 P100 : 27966.000000 COUNT : 365172 SUM : 69907094 rocksdb.write.raw.block.micros P50 : 0.525654 P95 : 0.998742 P99 : 4.090506 P100 : 16433.000000 COUNT : 2217652 SUM : 4884537 rocksdb.numfiles.in.singlecompaction P50 : 3.307692 P95 : 5.800000 P99 : 16.000000 P100 : 16.000000 COUNT : 22 SUM : 90 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.561118 P95 : 1132.213680 P99 : 2130.195652 P100 : 3485.000000 COUNT : 70922 SUM : 9110375 rocksdb.sst.read.micros P50 : 1.712378 P95 : 235.170009 P99 : 360.180624 P100 : 18092.000000 COUNT : 1767107 SUM : 88575676 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 : 307996 SUM : 307996000 rocksdb.bytes.per.write P50 : 1032.000000 P95 : 1032.000000 P99 : 1032.000000 P100 : 1032.000000 COUNT : 2863021 SUM : 2954637672 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 : 212926.829268 P95 : 281547.000000 P99 : 281547.000000 P100 : 281547.000000 COUNT : 44 SUM : 8763709 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 : 64 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