[2024-11-20 15:22:39.204891] Starting SPDK v25.01-pre git sha1 32c3f377c / DPDK 24.03.0 initialization... [2024-11-20 15:22:39.205037] [ 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_pid4116532 ] Set seed to 1732112559202252 because --seed was 0 EAL: No free 2048 kB hugepages reported on node 1 [2024-11-20 15:22:39.372742] app.c: 919:spdk_app_start: *NOTICE*: Total cores available: 1 [2024-11-20 15:22:39.514376] app.c: 612:app_setup_trace: *NOTICE*: Tracepoint Group Mask 0x80 specified. [2024-11-20 15:22:39.514432] app.c: 613:app_setup_trace: *NOTICE*: Use 'spdk_trace -s rocksdb -p 4116532' to capture a snapshot of events at runtime. [2024-11-20 15:22:39.514451] app.c: 618:app_setup_trace: *NOTICE*: 'spdk_trace' without parameters will also work if this is the only [2024-11-20 15:22:39.514470] app.c: 619:app_setup_trace: *NOTICE*: SPDK application currently running. [2024-11-20 15:22:39.514484] app.c: 620:app_setup_trace: *NOTICE*: Or copy /dev/shm/rocksdb_trace.pid4116532 for offline analysis/debug. [2024-11-20 15:22:39.516171] reactor.c:1005:reactor_run: *NOTICE*: Reactor started on core 0 RocksDB: version 8.1.1 Date: Wed Nov 20 15:22:46 2024 CPU: 72 * Intel(R) Xeon(R) Gold 6140 CPU @ 2.30GHz CPUCache: 25344 KB 2024/11/20-15:22:51 ... thread 0: (1000000,1000000) ops and (188019.9,188019.9) ops/second in (5.318587,5.318587) 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 2.84 GB 87.7 0.7 0.0 0.7 1.6 0.9 0.0 1.9 123.4 266.7 6.12 5.36 17 0.360 766K 3657 0.0 0.0 L1 11/11 1.33 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 L2 2/0 63.63 MB 0.6 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 10/0 769.27 MB 0.8 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 118/0 9.60 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 120/0 9.84 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 278/23 24.42 GB 0.0 0.7 0.0 0.7 1.6 0.9 0.0 1.9 123.4 266.7 6.12 5.36 17 0.360 766K 3657 0.0 0.0 Int 0/0 0.00 KB 0.0 0.7 0.0 0.7 1.6 0.9 0.0 1.9 123.4 266.7 6.12 5.36 17 0.360 766K 3657 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.7 0.0 0.7 0.7 -0.0 0.0 0.0 264.0 262.7 2.86 2.80 3 0.953 766K 3657 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 270.3 3.26 2.56 14 0.233 0 0 0.0 0.0 Blob file count: 0, total size: 0.0 GB, garbage size: 0.0 GB, space amp: 0.0 Uptime(secs): 5.4 total, 5.3 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: 1.59 GB write, 301.09 MB/s write, 0.74 GB read, 139.28 MB/s read, 6.1 seconds Interval compaction: 1.59 GB write, 306.74 MB/s write, 0.74 GB read, 141.90 MB/s read, 6.1 seconds Write Stall (count): cf-l0-file-count-limit-delays-with-ongoing-compaction: 2, cf-l0-file-count-limit-stops-with-ongoing-compaction: 0, l0-file-count-limit-delays: 2, l0-file-count-limit-stops: 0, memtable-limit-delays: 0, memtable-limit-stops: 0, pending-compaction-bytes-delays: 0, pending-compaction-bytes-stops: 0, total-delays: 2, total-stops: 0, interval: 2 total count ** File Read Latency Histogram By Level [default] ** ** Level 0 read latency histogram (micros): Count: 226585 Average: 41.4001 StdDev: 253.36 Min: 1 Median: 1.4846 Max: 32048 Percentiles: P50: 1.48 P75: 1.94 P99: 475.22 P99.9: 978.77 P99.99: 1915.78 ------------------------------------------------------ [ 0, 1 ] 53316 23.530% 23.530% ##### ( 1, 2 ] 123753 54.617% 78.147% ########### ( 2, 3 ] 12131 5.354% 83.501% # ( 3, 4 ] 2975 1.313% 84.814% ( 4, 6 ] 2196 0.969% 85.783% ( 6, 10 ] 1056 0.466% 86.249% ( 10, 15 ] 174 0.077% 86.326% ( 15, 22 ] 25 0.011% 86.337% ( 22, 34 ] 6 0.003% 86.339% ( 34, 51 ] 1 0.000% 86.340% ( 51, 76 ] 4 0.002% 86.342% ( 76, 110 ] 62 0.027% 86.369% ( 110, 170 ] 3627 1.601% 87.970% ( 170, 250 ] 15224 6.719% 94.689% # ( 250, 380 ] 8667 3.825% 98.514% # ( 380, 580 ] 2315 1.022% 99.535% ( 580, 870 ] 791 0.349% 99.884% ( 870, 1300 ] 140 0.062% 99.946% ( 1300, 1900 ] 98 0.043% 99.989% ( 1900, 2900 ] 85 0.038% 100.027% ( 2900, 4400 ] 32 0.014% 100.041% ( 4400, 6600 ] 5 0.002% 100.043% ( 6600, 9900 ] 2 0.001% 100.044% ( 9900, 14000 ] 5 0.002% 100.046% ( 14000, 22000 ] 6 0.003% 100.049% ( 22000, 33000 ] 11 0.005% 100.054% ** Level 1 read latency histogram (micros): Count: 4088 Average: 314.6847 StdDev: 1055.75 Min: 78 Median: 226.3455 Max: 30281 Percentiles: P50: 226.35 P75: 300.51 P99: 1123.09 P99.9: 21648.00 P99.99: 30281.00 ------------------------------------------------------ ( 76, 110 ] 13 0.318% 0.318% ( 110, 170 ] 547 13.381% 13.699% ### ( 170, 250 ] 2107 51.541% 65.240% ########## ( 250, 380 ] 1027 25.122% 90.362% ##### ( 380, 580 ] 253 6.189% 96.551% # ( 580, 870 ] 96 2.348% 98.899% ( 870, 1300 ] 7 0.171% 99.070% ( 1300, 1900 ] 12 0.294% 99.364% ( 1900, 2900 ] 6 0.147% 99.511% ( 2900, 4400 ] 6 0.147% 99.658% ( 4400, 6600 ] 3 0.073% 99.731% ( 6600, 9900 ] 1 0.024% 99.755% ( 9900, 14000 ] 4 0.098% 99.853% ( 14000, 22000 ] 2 0.049% 99.902% ( 22000, 33000 ] 4 0.098% 100.000% ** Level 2 read latency histogram (micros): Count: 225 Average: 268.2533 StdDev: 224.45 Min: 118 Median: 224.0458 Max: 2331 Percentiles: P50: 224.05 P75: 285.75 P99: 1650.00 P99.9: 2331.00 P99.99: 2331.00 ------------------------------------------------------ ( 110, 170 ] 24 10.667% 10.667% ## ( 170, 250 ] 131 58.222% 68.889% ############ ( 250, 380 ] 50 22.222% 91.111% #### ( 380, 580 ] 14 6.222% 97.333% # ( 580, 870 ] 2 0.889% 98.222% ( 1300, 1900 ] 3 1.333% 99.556% ( 1900, 2900 ] 1 0.444% 100.000% ** Level 3 read latency histogram (micros): Count: 2367 Average: 274.4837 StdDev: 281.24 Min: 101 Median: 223.7190 Max: 5311 Percentiles: P50: 223.72 P75: 296.94 P99: 1319.80 P99.9: 4289.90 P99.99: 5311.00 ------------------------------------------------------ ( 76, 110 ] 6 0.253% 0.253% ( 110, 170 ] 365 15.420% 15.674% ### ( 170, 250 ] 1210 51.120% 66.793% ########## ( 250, 380 ] 538 22.729% 89.523% ##### ( 380, 580 ] 174 7.351% 96.874% # ( 580, 870 ] 41 1.732% 98.606% ( 870, 1300 ] 9 0.380% 98.986% ( 1300, 1900 ] 10 0.422% 99.409% ( 1900, 2900 ] 7 0.296% 99.704% ( 2900, 4400 ] 5 0.211% 99.916% ( 4400, 6600 ] 2 0.084% 100.000% ** Level 4 read latency histogram (micros): Count: 23667 Average: 278.3537 StdDev: 323.34 Min: 93 Median: 222.3337 Max: 6101 Percentiles: P50: 222.33 P75: 292.43 P99: 1574.97 P99.9: 5112.36 P99.99: 6101.00 ------------------------------------------------------ ( 76, 110 ] 64 0.270% 0.270% ( 110, 170 ] 3733 15.773% 16.043% ### ( 170, 250 ] 12285 51.908% 67.951% ########## ( 250, 380 ] 5111 21.595% 89.547% #### ( 380, 580 ] 1616 6.828% 96.375% # ( 580, 870 ] 487 2.058% 98.432% ( 870, 1300 ] 105 0.444% 98.876% ( 1300, 1900 ] 64 0.270% 99.146% ( 1900, 2900 ] 91 0.385% 99.531% ( 2900, 4400 ] 76 0.321% 99.852% ( 4400, 6600 ] 35 0.148% 100.000% ** Level 5 read latency histogram (micros): Count: 23861 Average: 278.9134 StdDev: 335.41 Min: 92 Median: 221.8502 Max: 8243 Percentiles: P50: 221.85 P75: 291.04 P99: 1652.79 P99.9: 5245.03 P99.99: 8243.00 ------------------------------------------------------ ( 76, 110 ] 69 0.289% 0.289% ( 110, 170 ] 3850 16.135% 16.424% ### ( 170, 250 ] 12361 51.804% 68.228% ########## ( 250, 380 ] 5118 21.449% 89.678% #### ( 380, 580 ] 1557 6.525% 96.203% # ( 580, 870 ] 510 2.137% 98.340% ( 870, 1300 ] 108 0.453% 98.793% ( 1300, 1900 ] 84 0.352% 99.145% ( 1900, 2900 ] 98 0.411% 99.556% ( 2900, 4400 ] 71 0.298% 99.853% ( 4400, 6600 ] 29 0.122% 99.975% ( 6600, 9900 ] 6 0.025% 100.000% ** DB Stats ** Uptime(secs): 5.4 total, 5.3 interval Cumulative writes: 0 writes, 1000K keys, 0 commit groups, 0.0 writes per commit group, ingest: 0.96 GB, 181.55 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.326 H:M:S, 6.0 percent Interval writes: 0 writes, 1000K keys, 0 commit groups, 0.0 writes per commit group, ingest: 984.19 MB, 184.95 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.326 H:M:S, 6.1 percent Write Stall (count): write-buffer-manager-limit-stops: 0, num-running-compactions: 1 num-running-flushes: 1 2024/11/20-15:23:00 ... thread 0: (1000000,2000000) ops and (112801.6,141006.8) ops/second in (8.865125,14.183712) 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 20/16 3.79 GB 160.6 2.5 0.0 2.5 4.3 1.8 0.0 2.3 142.6 245.5 18.05 16.32 37 0.488 2614K 29K 0.0 0.0 L1 11/11 1.33 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 L2 2/0 63.63 MB 0.6 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 10/0 769.27 MB 0.8 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 118/0 9.60 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 120/0 9.84 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 281/27 25.38 GB 0.0 2.5 0.0 2.5 4.3 1.8 0.0 2.3 142.6 245.5 18.05 16.32 37 0.488 2614K 29K 0.0 0.0 Int 0/0 0.00 KB 0.0 2.5 0.0 2.5 4.3 1.8 0.0 2.3 142.6 245.5 18.05 16.32 37 0.488 2614K 29K 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.0 2.5 2.5 -0.0 0.0 0.0 238.0 235.3 10.82 10.58 7 1.546 2614K 29K 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 260.8 7.24 5.74 30 0.241 0 0 0.0 0.0 Blob file count: 0, total size: 0.0 GB, garbage size: 0.0 GB, space amp: 0.0 Uptime(secs): 14.3 total, 14.2 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.33 GB write, 310.28 MB/s write, 2.51 GB read, 180.22 MB/s read, 18.1 seconds Interval compaction: 4.33 GB write, 312.47 MB/s write, 2.51 GB read, 181.49 MB/s read, 18.1 seconds Write Stall (count): cf-l0-file-count-limit-delays-with-ongoing-compaction: 9, cf-l0-file-count-limit-stops-with-ongoing-compaction: 0, l0-file-count-limit-delays: 9, 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: 9, total-stops: 0, interval: 9 total count ** File Read Latency Histogram By Level [default] ** ** Level 0 read latency histogram (micros): Count: 788084 Average: 32.6607 StdDev: 154.53 Min: 0 Median: 1.6259 Max: 32048 Percentiles: P50: 1.63 P75: 2.32 P99: 379.12 P99.9: 728.19 P99.99: 844.73 ------------------------------------------------------ [ 0, 1 ] 122115 15.495% 15.495% ### ( 1, 2 ] 434471 55.130% 70.625% ########### ( 2, 3 ] 108223 13.732% 84.358% ### ( 3, 4 ] 14568 1.849% 86.206% ( 4, 6 ] 11371 1.443% 87.649% ( 6, 10 ] 3357 0.426% 88.075% ( 10, 15 ] 709 0.090% 88.165% ( 15, 22 ] 119 0.015% 88.180% ( 22, 34 ] 23 0.003% 88.183% ( 34, 51 ] 3 0.000% 88.183% ( 51, 76 ] 6 0.001% 88.184% ( 76, 110 ] 187 0.024% 88.208% ( 110, 170 ] 12409 1.575% 89.782% ( 170, 250 ] 50166 6.366% 96.148% # ( 250, 380 ] 22630 2.872% 99.020% # ( 380, 580 ] 6037 0.766% 99.786% ( 580, 870 ] 1765 0.224% 100.010% ( 870, 1300 ] 177 0.022% 100.032% ( 1300, 1900 ] 108 0.014% 100.046% ( 1900, 2900 ] 108 0.014% 100.059% ( 2900, 4400 ] 51 0.006% 100.066% ( 4400, 6600 ] 9 0.001% 100.067% ( 6600, 9900 ] 2 0.000% 100.067% ( 9900, 14000 ] 5 0.001% 100.068% ( 14000, 22000 ] 6 0.001% 100.069% ( 22000, 33000 ] 11 0.001% 100.070% ** Level 1 read latency histogram (micros): Count: 11673 Average: 265.2863 StdDev: 630.43 Min: 78 Median: 221.5537 Max: 30281 Percentiles: P50: 221.55 P75: 282.21 P99: 776.06 P99.9: 6106.47 P99.99: 29789.92 ------------------------------------------------------ ( 76, 110 ] 27 0.231% 0.231% ( 110, 170 ] 1691 14.486% 14.718% ### ( 170, 250 ] 6391 54.750% 69.468% ########### ( 250, 380 ] 2606 22.325% 91.793% #### ( 380, 580 ] 702 6.014% 97.807% # ( 580, 870 ] 206 1.765% 99.572% ( 870, 1300 ] 11 0.094% 99.666% ( 1300, 1900 ] 13 0.111% 99.777% ( 1900, 2900 ] 6 0.051% 99.829% ( 2900, 4400 ] 6 0.051% 99.880% ( 4400, 6600 ] 3 0.026% 99.906% ( 6600, 9900 ] 1 0.009% 99.914% ( 9900, 14000 ] 4 0.034% 99.949% ( 14000, 22000 ] 2 0.017% 99.966% ( 22000, 33000 ] 4 0.034% 100.000% ** Level 2 read latency histogram (micros): Count: 611 Average: 246.5499 StdDev: 155.57 Min: 108 Median: 219.4186 Max: 2331 Percentiles: P50: 219.42 P75: 271.09 P99: 802.01 P99.9: 2289.00 P99.99: 2331.00 ------------------------------------------------------ ( 76, 110 ] 1 0.164% 0.164% ( 110, 170 ] 92 15.057% 15.221% ### ( 170, 250 ] 344 56.301% 71.522% ########### ( 250, 380 ] 131 21.440% 92.962% #### ( 380, 580 ] 30 4.910% 97.872% # ( 580, 870 ] 9 1.473% 99.345% ( 1300, 1900 ] 3 0.491% 99.836% ( 1900, 2900 ] 1 0.164% 100.000% ** Level 3 read latency histogram (micros): Count: 6858 Average: 251.3972 StdDev: 184.72 Min: 95 Median: 220.6880 Max: 5311 Percentiles: P50: 220.69 P75: 279.77 P99: 780.02 P99.9: 2942.60 P99.99: 5311.00 ------------------------------------------------------ ( 76, 110 ] 17 0.248% 0.248% ( 110, 170 ] 1036 15.106% 15.354% ### ( 170, 250 ] 3750 54.681% 70.035% ########### ( 250, 380 ] 1487 21.683% 91.718% #### ( 380, 580 ] 427 6.226% 97.944% # ( 580, 870 ] 105 1.531% 99.475% ( 870, 1300 ] 11 0.160% 99.635% ( 1300, 1900 ] 10 0.146% 99.781% ( 1900, 2900 ] 8 0.117% 99.898% ( 2900, 4400 ] 5 0.073% 99.971% ( 4400, 6600 ] 2 0.029% 100.000% ** Level 4 read latency histogram (micros): Count: 68532 Average: 250.8069 StdDev: 206.31 Min: 93 Median: 218.8055 Max: 6101 Percentiles: P50: 218.81 P75: 274.34 P99: 796.53 P99.9: 3738.18 P99.99: 6101.00 ------------------------------------------------------ ( 76, 110 ] 188 0.274% 0.274% ( 110, 170 ] 11406 16.643% 16.918% ### ( 170, 250 ] 37163 54.227% 71.145% ########### ( 250, 380 ] 14112 20.592% 91.737% #### ( 380, 580 ] 4128 6.023% 97.760% # ( 580, 870 ] 1138 1.661% 99.421% ( 870, 1300 ] 126 0.184% 99.605% ( 1300, 1900 ] 66 0.096% 99.701% ( 1900, 2900 ] 94 0.137% 99.838% ( 2900, 4400 ] 76 0.111% 99.949% ( 4400, 6600 ] 35 0.051% 100.000% ** Level 5 read latency histogram (micros): Count: 68805 Average: 251.8994 StdDev: 214.15 Min: 92 Median: 219.0718 Max: 8243 Percentiles: P50: 219.07 P75: 276.25 P99: 805.52 P99.9: 3695.73 P99.99: 6533.20 ------------------------------------------------------ ( 76, 110 ] 200 0.291% 0.291% ( 110, 170 ] 11497 16.710% 17.000% ### ( 170, 250 ] 37016 53.798% 70.799% ########### ( 250, 380 ] 14315 20.805% 91.604% #### ( 380, 580 ] 4176 6.069% 97.673% # ( 580, 870 ] 1174 1.706% 99.379% ( 870, 1300 ] 129 0.187% 99.567% ( 1300, 1900 ] 88 0.128% 99.695% ( 1900, 2900 ] 103 0.150% 99.844% ( 2900, 4400 ] 72 0.105% 99.949% ( 4400, 6600 ] 29 0.042% 99.991% ( 6600, 9900 ] 6 0.009% 100.000% ** DB Stats ** Uptime(secs): 14.3 total, 8.9 interval Cumulative writes: 0 writes, 2000K keys, 0 commit groups, 0.0 writes per commit group, ingest: 1.92 GB, 137.79 MB/s Cumulative WAL: 0 writes, 0 syncs, 0.00 writes per sync, written: 0.00 GB, 0.00 MB/s Cumulative stall: 00:00:4.911 H:M:S, 34.4 percent Interval writes: 0 writes, 1000K keys, 0 commit groups, 0.0 writes per commit group, ingest: 984.19 MB, 111.02 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.584 H:M:S, 51.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] readwhilewriting : 307.879 micros/op 12935 ops/sec 20.254 seconds 261996 operations; 12.5 MB/s (64999 of 64999 found) Microseconds per read: Count: 261996 Average: 307.8866 StdDev: 472.03 Min: 1 Median: 249.3627 Max: 36462 Percentiles: P50: 249.36 P75: 356.82 P99: 958.65 P99.9: 6160.29 P99.99: 21964.52 ------------------------------------------------------ [ 0, 1 ] 1 0.000% 0.000% ( 1, 2 ] 28 0.011% 0.011% ( 2, 3 ] 127 0.048% 0.060% ( 3, 4 ] 147 0.056% 0.116% ( 4, 6 ] 177 0.068% 0.183% ( 6, 10 ] 329 0.126% 0.309% ( 10, 15 ] 4652 1.776% 2.084% ( 15, 22 ] 1769 0.675% 2.760% ( 22, 34 ] 2257 0.861% 3.621% ( 34, 51 ] 520 0.198% 3.820% ( 51, 76 ] 95 0.036% 3.856% ( 76, 110 ] 28 0.011% 3.866% ( 110, 170 ] 13404 5.116% 8.983% # ( 170, 250 ] 108327 41.347% 50.329% ######## ( 250, 380 ] 78665 30.025% 80.355% ###### ( 380, 580 ] 35530 13.561% 93.916% ### ( 580, 870 ] 12810 4.889% 98.805% # ( 870, 1300 ] 2474 0.944% 99.750% ( 1300, 1900 ] 244 0.093% 99.843% ( 1900, 2900 ] 88 0.034% 99.876% ( 2900, 4400 ] 38 0.015% 99.891% ( 4400, 6600 ] 30 0.011% 99.902% ( 6600, 9900 ] 89 0.034% 99.936% ( 9900, 14000 ] 96 0.037% 99.973% ( 14000, 22000 ] 45 0.017% 99.990% ( 22000, 33000 ] 25 0.010% 100.000% ( 33000, 50000 ] 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 : 4888880 rocksdb.bloom.filter.full.positive COUNT : 309026 rocksdb.bloom.filter.full.true.positive COUNT : 261181 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 : 815 rocksdb.memtable.miss COUNT : 261181 rocksdb.l0.hit COUNT : 41800 rocksdb.l1.hit COUNT : 15258 rocksdb.l2andup.hit COUNT : 204123 rocksdb.compaction.key.drop.new COUNT : 64692 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 : 2374701 rocksdb.number.keys.read COUNT : 261996 rocksdb.number.keys.updated COUNT : 0 rocksdb.bytes.written COUNT : 2450691432 rocksdb.bytes.read COUNT : 261996000 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 : 319 rocksdb.no.file.errors COUNT : 0 rocksdb.stall.micros COUNT : 9024345 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 : 4498219709 rocksdb.compact.write.bytes COUNT : 4431039994 rocksdb.flush.write.bytes COUNT : 2440968721 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 : 357 rocksdb.number.superversion_releases COUNT : 330 rocksdb.number.superversion_cleanups COUNT : 83 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 : 2424421376 rocksdb.memtable.garbage.bytes.at.flush COUNT : 3795968 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 : 5947415711 rocksdb.non.last.level.read.count COUNT : 1404826 rocksdb.block.checksum.compute.count COUNT : 1404201 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 : 248.607139 P95 : 642.930364 P99 : 956.566572 P100 : 36458.000000 COUNT : 261996 SUM : 80482140 rocksdb.db.write.micros P50 : 1.827858 P95 : 5.988609 P99 : 11.775947 P100 : 2198.000000 COUNT : 2374701 SUM : 16548676 rocksdb.compaction.times.micros P50 : 1078571.428571 P95 : 4842828.000000 P99 : 4842828.000000 P100 : 4842828.000000 COUNT : 9 SUM : 13081817 rocksdb.compaction.times.cpu_micros P50 : 1078571.428571 P95 : 4746567.000000 P99 : 4746567.000000 P100 : 4746567.000000 COUNT : 9 SUM : 12779655 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 : 1056.333333 P95 : 2092.000000 P99 : 2092.000000 P100 : 2092.000000 COUNT : 37 SUM : 43254 rocksdb.compaction.outfile.sync.micros P50 : 1450.000000 P95 : 3575.000000 P99 : 3683.000000 P100 : 3683.000000 COUNT : 11 SUM : 17524 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 : 305.441176 P95 : 490.000000 P99 : 638.000000 P100 : 638.000000 COUNT : 47 SUM : 14344 rocksdb.table.open.io.micros P50 : 7395.323741 P95 : 18200.000000 P99 : 56289.000000 P100 : 56289.000000 COUNT : 319 SUM : 2849122 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.709316 P95 : 188.308436 P99 : 343.175944 P100 : 27698.000000 COUNT : 1091405 SUM : 22986630 rocksdb.read.block.get.micros P50 : 216.768636 P95 : 465.223664 P99 : 739.631511 P100 : 32152.000000 COUNT : 312541 SUM : 73502789 rocksdb.write.raw.block.micros P50 : 0.520739 P95 : 0.989405 P99 : 2.967353 P100 : 8566.000000 COUNT : 1666394 SUM : 4289049 rocksdb.numfiles.in.singlecompaction P50 : 4.000000 P95 : 23.000000 P99 : 23.000000 P100 : 23.000000 COUNT : 11 SUM : 65 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.563440 P95 : 1145.570399 P99 : 2185.000000 P100 : 2185.000000 COUNT : 66842 SUM : 9031701 rocksdb.sst.read.micros P50 : 1.765285 P95 : 309.190685 P99 : 515.859778 P100 : 32048.000000 COUNT : 1404954 SUM : 92728121 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 : 261996 SUM : 261996000 rocksdb.bytes.per.write P50 : 1032.000000 P95 : 1032.000000 P99 : 1032.000000 P100 : 1032.000000 COUNT : 2374701 SUM : 2450691432 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 : 229200.000000 P95 : 369954.545455 P99 : 424077.000000 P100 : 424077.000000 COUNT : 37 SUM : 8861063 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 : 37 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