[2024-11-27 11:19:49.415430] Starting SPDK v24.09.1-pre git sha1 b18e1bd62 / DPDK 22.11.4 initialization... [2024-11-27 11:19:49.415580] [ 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_pid1734616 ] Set seed to 1732702789411875 because --seed was 0 EAL: No free 2048 kB hugepages reported on node 1 [2024-11-27 11:19:49.505893] app.c: 917:spdk_app_start: *NOTICE*: Total cores available: 1 [2024-11-27 11:19:49.547433] app.c: 610:app_setup_trace: *NOTICE*: Tracepoint Group Mask 0x80 specified. [2024-11-27 11:19:49.547481] app.c: 611:app_setup_trace: *NOTICE*: Use 'spdk_trace -s rocksdb -p 1734616' to capture a snapshot of events at runtime. [2024-11-27 11:19:49.547492] app.c: 616:app_setup_trace: *NOTICE*: 'spdk_trace' without parameters will also work if this is the only [2024-11-27 11:19:49.547502] app.c: 617:app_setup_trace: *NOTICE*: SPDK application currently running. [2024-11-27 11:19:49.547510] app.c: 618:app_setup_trace: *NOTICE*: Or copy /dev/shm/rocksdb_trace.pid1734616 for offline analysis/debug. [2024-11-27 11:19:49.547594] reactor.c: 990:reactor_run: *NOTICE*: Reactor started on core 0 RocksDB: version 8.1.1 Date: Wed Nov 27 11:19:53 2024 CPU: 96 * Intel(R) Xeon(R) Gold 6252 CPU @ 2.10GHz CPUCache: 36608 KB 2024/11/27-11:19:58 ... thread 0: (1000000,1000000) ops and (236048.7,236048.7) ops/second in (4.236414,4.236414) 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 2.61 GB 94.4 0.7 0.0 0.7 1.7 0.9 0.0 1.8 128.4 288.8 5.89 5.31 18 0.327 767K 813 0.0 0.0 L1 13/13 1.05 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 1/0 57.34 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 14/0 965.05 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 164/0 9.47 GB 1.0 0.2 0.1 0.1 0.2 0.1 0.0 1.8 170.1 169.7 1.33 1.11 1 1.330 0 0 0.0 0.0 L5 1116/0 91.72 GB 0.9 0.0 0.0 0.0 0.0 0.0 0.3 0.0 0.0 0.0 0.00 0.00 0 0.000 0 0 0.0 0.0 Sum 1326/25 105.85 GB 0.0 1.0 0.1 0.8 1.9 1.0 0.3 2.0 136.1 266.9 7.22 6.42 19 0.380 767K 813 0.0 0.0 Int 0/0 0.00 KB 0.0 1.0 0.1 0.8 1.9 1.0 0.3 2.0 136.1 266.9 7.22 6.42 19 0.380 767K 813 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.0 0.1 0.8 1.0 0.1 0.0 0.0 235.5 235.2 4.17 3.89 4 1.043 767K 813 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 310.3 3.05 2.53 15 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): 4.4 total, 4.2 interval Flush(GB): cumulative 0.923, interval 0.923 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.88 GB write, 435.14 MB/s write, 0.96 GB read, 221.93 MB/s read, 7.2 seconds Interval compaction: 1.88 GB write, 454.45 MB/s write, 0.96 GB read, 231.77 MB/s read, 7.2 seconds Write Stall (count): cf-l0-file-count-limit-delays-with-ongoing-compaction: 5, cf-l0-file-count-limit-stops-with-ongoing-compaction: 0, l0-file-count-limit-delays: 5, 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: 5, total-stops: 0, interval: 5 total count ** File Read Latency Histogram By Level [default] ** ** Level 0 read latency histogram (micros): Count: 310585 Average: 12.2076 StdDev: 75.98 Min: 0 Median: 1.5130 Max: 6591 Percentiles: P50: 1.51 P75: 1.95 P99: 228.45 P99.9: 658.32 P99.99: 923.94 ------------------------------------------------------ [ 0, 1 ] 64397 20.734% 20.734% #### ( 1, 2 ] 177180 57.047% 77.781% ########### ( 2, 3 ] 32791 10.558% 88.339% ## ( 3, 4 ] 11027 3.550% 91.889% # ( 4, 6 ] 7186 2.314% 94.203% ( 6, 10 ] 3152 1.015% 95.218% ( 10, 15 ] 562 0.181% 95.399% ( 15, 22 ] 161 0.052% 95.451% ( 22, 34 ] 49 0.016% 95.467% ( 34, 51 ] 8 0.003% 95.469% ( 51, 76 ] 2 0.001% 95.470% ( 76, 110 ] 2097 0.675% 96.145% ( 110, 170 ] 6926 2.230% 98.375% ( 170, 250 ] 2657 0.855% 99.230% ( 250, 380 ] 1130 0.364% 99.594% ( 380, 580 ] 853 0.275% 99.869% ( 580, 870 ] 357 0.115% 99.984% ( 870, 1300 ] 151 0.049% 100.033% ( 1300, 1900 ] 51 0.016% 100.049% ( 1900, 2900 ] 39 0.013% 100.061% ( 2900, 4400 ] 23 0.007% 100.069% ( 4400, 6600 ] 12 0.004% 100.073% ** Level 1 read latency histogram (micros): Count: 584 Average: 304.7055 StdDev: 558.71 Min: 87 Median: 157.9562 Max: 5249 Percentiles: P50: 157.96 P75: 234.42 P99: 3824.00 P99.9: 5249.00 P99.99: 5249.00 ------------------------------------------------------ ( 76, 110 ] 73 12.500% 12.500% ### ( 110, 170 ] 274 46.918% 59.418% ######### ( 170, 250 ] 113 19.349% 78.767% #### ( 250, 380 ] 40 6.849% 85.616% # ( 380, 580 ] 34 5.822% 91.438% # ( 580, 870 ] 20 3.425% 94.863% # ( 870, 1300 ] 11 1.884% 96.747% ( 1300, 1900 ] 6 1.027% 97.774% ( 1900, 2900 ] 1 0.171% 97.945% ( 2900, 4400 ] 10 1.712% 99.658% ( 4400, 6600 ] 2 0.342% 100.000% ** Level 2 read latency histogram (micros): Count: 53 Average: 268.5660 StdDev: 364.00 Min: 88 Median: 157.1429 Max: 2052 Percentiles: P50: 157.14 P75: 240.00 P99: 2052.00 P99.9: 2052.00 P99.99: 2052.00 ------------------------------------------------------ ( 76, 110 ] 10 18.868% 18.868% #### ( 110, 170 ] 21 39.623% 58.491% ######## ( 170, 250 ] 10 18.868% 77.358% #### ( 250, 380 ] 6 11.321% 88.679% ## ( 380, 580 ] 2 3.774% 92.453% # ( 580, 870 ] 1 1.887% 94.340% ( 870, 1300 ] 1 1.887% 96.226% ( 1300, 1900 ] 1 1.887% 98.113% ( 1900, 2900 ] 1 1.887% 100.000% ** Level 3 read latency histogram (micros): Count: 504 Average: 243.1290 StdDev: 281.42 Min: 81 Median: 155.6198 Max: 2319 Percentiles: P50: 155.62 P75: 234.00 P99: 1896.00 P99.9: 2319.00 P99.99: 2319.00 ------------------------------------------------------ ( 76, 110 ] 68 13.492% 13.492% ### ( 110, 170 ] 242 48.016% 61.508% ########## ( 170, 250 ] 85 16.865% 78.373% ### ( 250, 380 ] 41 8.135% 86.508% ## ( 380, 580 ] 41 8.135% 94.643% ## ( 580, 870 ] 12 2.381% 97.024% ( 870, 1300 ] 4 0.794% 97.817% ( 1300, 1900 ] 6 1.190% 99.008% ( 1900, 2900 ] 5 0.992% 100.000% ** Level 4 read latency histogram (micros): Count: 20934 Average: 65.3630 StdDev: 232.43 Min: 1 Median: 1.8492 Max: 4156 Percentiles: P50: 1.85 P75: 3.99 P99: 1119.25 P99.9: 3143.96 P99.99: 4156.00 ------------------------------------------------------ [ 0, 1 ] 1852 8.847% 8.847% ## ( 1, 2 ] 10145 48.462% 57.309% ########## ( 2, 3 ] 2966 14.168% 71.477% ### ( 3, 4 ] 746 3.564% 75.041% # ( 4, 6 ] 590 2.818% 77.859% # ( 6, 10 ] 317 1.514% 79.373% ( 10, 15 ] 44 0.210% 79.583% ( 15, 22 ] 14 0.067% 79.650% ( 22, 34 ] 7 0.033% 79.684% ( 34, 51 ] 2 0.010% 79.693% ( 51, 76 ] 1 0.005% 79.698% ( 76, 110 ] 529 2.527% 82.225% # ( 110, 170 ] 1791 8.555% 90.781% ## ( 170, 250 ] 756 3.611% 94.392% # ( 250, 380 ] 364 1.739% 96.131% ( 380, 580 ] 339 1.619% 97.750% ( 580, 870 ] 195 0.931% 98.682% ( 870, 1300 ] 115 0.549% 99.231% ( 1300, 1900 ] 78 0.373% 99.604% ( 1900, 2900 ] 58 0.277% 99.881% ( 2900, 4400 ] 25 0.119% 100.000% ** Level 5 read latency histogram (micros): Count: 33722 Average: 331.4394 StdDev: 537.18 Min: 60 Median: 162.1664 Max: 6596 Percentiles: P50: 162.17 P75: 268.91 P99: 3286.62 P99.9: 5021.52 P99.99: 6442.15 ------------------------------------------------------ ( 51, 76 ] 7 0.021% 0.021% ( 76, 110 ] 4108 12.182% 12.203% ## ( 110, 170 ] 14661 43.476% 55.679% ######### ( 170, 250 ] 6084 18.042% 73.720% #### ( 250, 380 ] 2973 8.816% 82.537% ## ( 380, 580 ] 2487 7.375% 89.912% # ( 580, 870 ] 1151 3.413% 93.325% # ( 870, 1300 ] 622 1.844% 95.169% ( 1300, 1900 ] 553 1.640% 96.809% ( 1900, 2900 ] 639 1.895% 98.704% ( 2900, 4400 ] 391 1.159% 99.864% ( 4400, 6600 ] 47 0.139% 100.003% ** DB Stats ** Uptime(secs): 4.4 total, 4.2 interval Cumulative writes: 0 writes, 1000K keys, 0 commit groups, 0.0 writes per commit group, ingest: 0.96 GB, 222.36 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.712 H:M:S, 16.1 percent Interval writes: 0 writes, 1000K keys, 0 commit groups, 0.0 writes per commit group, ingest: 984.19 MB, 232.17 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.712 H:M:S, 16.8 percent Write Stall (count): write-buffer-manager-limit-stops: 0, num-running-compactions: 1 num-running-flushes: 0 2024/11/27-11:20:07 ... thread 0: (1000000,2000000) ops and (111949.6,151871.7) ops/second in (8.932593,13.169007) 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.53 GB 188.1 3.0 0.0 3.0 4.8 1.8 0.0 2.6 175.7 285.1 17.20 16.09 38 0.453 3069K 8468 0.0 0.0 L1 13/13 1.05 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 1/0 57.34 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 14/0 965.05 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 164/0 9.47 GB 1.0 0.2 0.1 0.1 0.2 0.1 0.0 1.8 170.1 169.7 1.33 1.11 1 1.330 0 0 0.0 0.0 L5 1116/0 91.72 GB 0.9 0.0 0.0 0.0 0.0 0.0 0.3 0.0 0.0 0.0 0.00 0.00 0 0.000 0 0 0.0 0.0 Sum 1325/25 106.76 GB 0.0 3.2 0.1 3.0 5.0 2.0 0.3 2.7 175.3 276.9 18.53 17.20 39 0.475 3069K 8468 0.0 0.0 Int 0/0 0.00 KB 0.0 3.2 0.1 3.0 5.0 2.0 0.3 2.7 175.3 276.9 18.53 17.20 39 0.475 3069K 8468 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.2 0.1 3.0 3.2 0.1 0.0 0.0 259.0 258.3 12.54 12.15 9 1.394 3069K 8468 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 315.8 5.98 5.05 30 0.199 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.4 total, 13.2 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: 5.01 GB write, 384.01 MB/s write, 3.17 GB read, 243.20 MB/s read, 18.5 seconds Interval compaction: 5.01 GB write, 389.50 MB/s write, 3.17 GB read, 246.68 MB/s read, 18.5 seconds Write Stall (count): cf-l0-file-count-limit-delays-with-ongoing-compaction: 15, cf-l0-file-count-limit-stops-with-ongoing-compaction: 0, l0-file-count-limit-delays: 15, 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: 15, total-stops: 0, interval: 15 total count ** File Read Latency Histogram By Level [default] ** ** Level 0 read latency histogram (micros): Count: 963976 Average: 16.9306 StdDev: 68.54 Min: 0 Median: 1.4488 Max: 6591 Percentiles: P50: 1.45 P75: 1.92 P99: 244.12 P99.9: 560.70 P99.99: 737.74 ------------------------------------------------------ [ 0, 1 ] 250348 25.970% 25.970% ##### ( 1, 2 ] 516173 53.546% 79.517% ########### ( 2, 3 ] 72032 7.472% 86.989% # ( 3, 4 ] 24077 2.498% 89.487% ( 4, 6 ] 14293 1.483% 90.969% ( 6, 10 ] 4966 0.515% 91.485% ( 10, 15 ] 920 0.095% 91.580% ( 15, 22 ] 214 0.022% 91.602% ( 22, 34 ] 53 0.005% 91.608% ( 34, 51 ] 10 0.001% 91.609% ( 51, 76 ] 27 0.003% 91.612% ( 76, 110 ] 14557 1.510% 93.122% ( 110, 170 ] 43874 4.551% 97.673% # ( 170, 250 ] 13807 1.432% 99.105% ( 250, 380 ] 4535 0.470% 99.576% ( 380, 580 ] 3460 0.359% 99.935% ( 580, 870 ] 981 0.102% 100.036% ( 870, 1300 ] 226 0.023% 100.060% ( 1300, 1900 ] 68 0.007% 100.067% ( 1900, 2900 ] 76 0.008% 100.075% ( 2900, 4400 ] 39 0.004% 100.079% ( 4400, 6600 ] 12 0.001% 100.080% ** Level 1 read latency histogram (micros): Count: 2979 Average: 196.3797 StdDev: 268.56 Min: 78 Median: 147.0332 Max: 5249 Percentiles: P50: 147.03 P75: 188.13 P99: 968.59 P99.9: 4253.15 P99.99: 5249.00 ------------------------------------------------------ ( 76, 110 ] 468 15.710% 15.710% ### ( 110, 170 ] 1655 55.556% 71.266% ########### ( 170, 250 ] 491 16.482% 87.748% ### ( 250, 380 ] 161 5.404% 93.152% # ( 380, 580 ] 122 4.095% 97.247% # ( 580, 870 ] 49 1.645% 98.892% ( 870, 1300 ] 14 0.470% 99.362% ( 1300, 1900 ] 6 0.201% 99.564% ( 1900, 2900 ] 1 0.034% 99.597% ( 2900, 4400 ] 10 0.336% 99.933% ( 4400, 6600 ] 2 0.067% 100.000% ** Level 2 read latency histogram (micros): Count: 221 Average: 205.7602 StdDev: 203.84 Min: 84 Median: 153.3636 Max: 2052 Percentiles: P50: 153.36 P75: 217.14 P99: 1209.70 P99.9: 2052.00 P99.99: 2052.00 ------------------------------------------------------ ( 76, 110 ] 31 14.027% 14.027% ### ( 110, 170 ] 110 49.774% 63.801% ########## ( 170, 250 ] 42 19.005% 82.805% #### ( 250, 380 ] 20 9.050% 91.855% ## ( 380, 580 ] 11 4.977% 96.833% # ( 580, 870 ] 4 1.810% 98.643% ( 870, 1300 ] 1 0.452% 99.095% ( 1300, 1900 ] 1 0.452% 99.548% ( 1900, 2900 ] 1 0.452% 100.000% ** Level 3 read latency histogram (micros): Count: 2474 Average: 188.0311 StdDev: 173.53 Min: 80 Median: 147.5898 Max: 3689 Percentiles: P50: 147.59 P75: 193.09 P99: 815.02 P99.9: 2605.20 P99.99: 3689.00 ------------------------------------------------------ ( 76, 110 ] 400 16.168% 16.168% ### ( 110, 170 ] 1336 54.002% 70.170% ########### ( 170, 250 ] 414 16.734% 86.904% ### ( 250, 380 ] 145 5.861% 92.765% # ( 380, 580 ] 134 5.416% 98.181% # ( 580, 870 ] 25 1.011% 99.192% ( 870, 1300 ] 8 0.323% 99.515% ( 1300, 1900 ] 6 0.243% 99.757% ( 1900, 2900 ] 5 0.202% 99.960% ( 2900, 4400 ] 1 0.040% 100.000% ** Level 4 read latency histogram (micros): Count: 36185 Average: 107.3226 StdDev: 196.80 Min: 1 Median: 86.9613 Max: 4156 Percentiles: P50: 86.96 P75: 151.19 P99: 816.91 P99.9: 2722.46 P99.99: 4156.00 ------------------------------------------------------ [ 0, 1 ] 1852 5.118% 5.118% # ( 1, 2 ] 10171 28.108% 33.226% ###### ( 2, 3 ] 3089 8.537% 41.763% ## ( 3, 4 ] 848 2.344% 44.107% ( 4, 6 ] 716 1.979% 46.085% ( 6, 10 ] 327 0.904% 46.989% ( 10, 15 ] 48 0.133% 47.122% ( 15, 22 ] 14 0.039% 47.160% ( 22, 34 ] 7 0.019% 47.180% ( 34, 51 ] 2 0.006% 47.185% ( 51, 76 ] 2 0.006% 47.191% ( 76, 110 ] 3153 8.714% 55.904% ## ( 110, 170 ] 10065 27.815% 83.720% ###### ( 170, 250 ] 3276 9.053% 92.773% ## ( 250, 380 ] 1111 3.070% 95.844% # ( 380, 580 ] 884 2.443% 98.287% ( 580, 870 ] 316 0.873% 99.160% ( 870, 1300 ] 133 0.368% 99.527% ( 1300, 1900 ] 83 0.229% 99.757% ( 1900, 2900 ] 63 0.174% 99.931% ( 2900, 4400 ] 25 0.069% 100.000% ** Level 5 read latency histogram (micros): Count: 163235 Average: 203.3579 StdDev: 270.37 Min: 60 Median: 147.9288 Max: 6596 Percentiles: P50: 147.93 P75: 195.02 P99: 1374.90 P99.9: 3948.72 P99.99: 5742.30 ------------------------------------------------------ ( 51, 76 ] 15 0.009% 0.009% ( 76, 110 ] 26291 16.106% 16.115% ### ( 110, 170 ] 87498 53.602% 69.718% ########### ( 170, 250 ] 27565 16.887% 86.605% ### ( 250, 380 ] 9531 5.839% 92.443% # ( 380, 580 ] 7540 4.619% 97.063% # ( 580, 870 ] 2352 1.441% 98.503% ( 870, 1300 ] 738 0.452% 98.955% ( 1300, 1900 ] 582 0.357% 99.312% ( 1900, 2900 ] 685 0.420% 99.732% ( 2900, 4400 ] 393 0.241% 99.972% ( 4400, 6600 ] 47 0.029% 100.001% ** DB Stats ** Uptime(secs): 13.4 total, 8.9 interval Cumulative writes: 0 writes, 2000K keys, 0 commit groups, 0.0 writes per commit group, ingest: 1.92 GB, 147.35 MB/s Cumulative WAL: 0 writes, 0 syncs, 0.00 writes per sync, written: 0.00 GB, 0.00 MB/s Cumulative stall: 00:00:6.012 H:M:S, 45.0 percent Interval writes: 0 writes, 1000K keys, 0 commit groups, 0.0 writes per commit group, ingest: 984.19 MB, 110.18 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:5.300 H:M:S, 59.3 percent Write Stall (count): write-buffer-manager-limit-stops: 0, num-running-compactions: 1 num-running-flushes: 1 using bdev Nvme0n1 Initializing RocksDB Options from the specified file Initializing RocksDB Options from command-line flags Integrated BlobDB: blob cache disabled Keys: 16 bytes each (+ 0 bytes user-defined timestamp) Values: 1000 bytes each (1000 bytes after compression) Entries: 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 : 206.960 micros/op 19291 ops/sec 60.183 seconds 1160996 operations; 18.7 MB/s (293999 of 293999 found) Microseconds per read: Count: 1160996 Average: 206.9615 StdDev: 289.05 Min: 1 Median: 172.7098 Max: 31651 Percentiles: P50: 172.71 P75: 231.52 P99: 663.93 P99.9: 4465.80 P99.99: 11985.50 ------------------------------------------------------ [ 0, 1 ] 1 0.000% 0.000% ( 1, 2 ] 14 0.001% 0.001% ( 2, 3 ] 43 0.004% 0.005% ( 3, 4 ] 30 0.003% 0.008% ( 4, 6 ] 93 0.008% 0.016% ( 6, 10 ] 695 0.060% 0.075% ( 10, 15 ] 4623 0.398% 0.474% ( 15, 22 ] 2236 0.193% 0.666% ( 22, 34 ] 10850 0.935% 1.601% ( 34, 51 ] 1030 0.089% 1.689% ( 51, 76 ] 101 0.009% 1.698% ( 76, 110 ] 23766 2.047% 3.745% ( 110, 170 ] 523642 45.103% 48.848% ######### ( 170, 250 ] 394837 34.008% 82.857% ####### ( 250, 380 ] 141305 12.171% 95.028% ## ( 380, 580 ] 43060 3.709% 98.736% # ( 580, 870 ] 10573 0.911% 99.647% ( 870, 1300 ] 1983 0.171% 99.818% ( 1300, 1900 ] 330 0.028% 99.846% ( 1900, 2900 ] 315 0.027% 99.873% ( 2900, 4400 ] 290 0.025% 99.898% ( 4400, 6600 ] 602 0.052% 99.950% ( 6600, 9900 ] 408 0.035% 99.985% ( 9900, 14000 ] 104 0.009% 99.994% ( 14000, 22000 ] 47 0.004% 99.998% ( 22000, 33000 ] 18 0.002% 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 : 21505195 rocksdb.bloom.filter.full.positive COUNT : 1370017 rocksdb.bloom.filter.full.true.positive COUNT : 1160603 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 : 393 rocksdb.memtable.miss COUNT : 1160603 rocksdb.l0.hit COUNT : 47258 rocksdb.l1.hit COUNT : 12078 rocksdb.l2andup.hit COUNT : 1101267 rocksdb.compaction.key.drop.new COUNT : 36205 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 : 2758108 rocksdb.number.keys.read COUNT : 1160996 rocksdb.number.keys.updated COUNT : 0 rocksdb.bytes.written COUNT : 2846367456 rocksdb.bytes.read COUNT : 1160996000 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 : 1378 rocksdb.no.file.errors COUNT : 0 rocksdb.stall.micros COUNT : 49329870 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 : 7550551819 rocksdb.compact.write.bytes COUNT : 7522733281 rocksdb.flush.write.bytes COUNT : 2840657648 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 : 23 rocksdb.number.superversion_acquires COUNT : 404 rocksdb.number.superversion_releases COUNT : 385 rocksdb.number.superversion_cleanups COUNT : 99 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 : 2817532928 rocksdb.memtable.garbage.bytes.at.flush COUNT : 902144 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 : 13675562741 rocksdb.non.last.level.read.count COUNT : 3160338 rocksdb.block.checksum.compute.count COUNT : 3213251 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 : 170.902091 P95 : 379.173219 P99 : 661.358449 P100 : 31648.000000 COUNT : 1160996 SUM : 239180930 rocksdb.db.write.micros P50 : 1.531250 P95 : 5.469311 P99 : 994.178511 P100 : 2572.000000 COUNT : 2758108 SUM : 56448243 rocksdb.compaction.times.micros P50 : 1081000.000000 P95 : 4622788.000000 P99 : 4622788.000000 P100 : 4622788.000000 COUNT : 13 SUM : 19059391 rocksdb.compaction.times.cpu_micros P50 : 1060909.090909 P95 : 4573073.000000 P99 : 4573073.000000 P100 : 4573073.000000 COUNT : 13 SUM : 18563668 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 : 775.000000 P95 : 1555.000000 P99 : 2470.000000 P100 : 2558.000000 COUNT : 43 SUM : 36741 rocksdb.compaction.outfile.sync.micros P50 : 952.692308 P95 : 2075.000000 P99 : 2735.000000 P100 : 2834.000000 COUNT : 33 SUM : 36259 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 : 148.709677 P95 : 400.000000 P99 : 877.000000 P100 : 877.000000 COUNT : 58 SUM : 10546 rocksdb.table.open.io.micros P50 : 4921.139430 P95 : 8822.000000 P99 : 10614.571429 P100 : 15387.000000 COUNT : 1378 SUM : 6789184 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.650635 P95 : 157.724106 P99 : 238.656339 P100 : 5730.000000 COUNT : 1830922 SUM : 52454415 rocksdb.read.block.get.micros P50 : 138.187005 P95 : 243.072403 P99 : 522.146242 P100 : 9140.000000 COUNT : 1381030 SUM : 203376633 rocksdb.write.raw.block.micros P50 : 0.559046 P95 : 1.673498 P99 : 3.328480 P100 : 2624.000000 COUNT : 2509867 SUM : 5299273 rocksdb.numfiles.in.singlecompaction P50 : 4.000000 P95 : 25.000000 P99 : 25.000000 P100 : 25.000000 COUNT : 15 SUM : 82 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.575233 P95 : 1925.326500 P99 : 2558.000000 P100 : 2558.000000 COUNT : 256729 SUM : 49351668 rocksdb.sst.read.micros P50 : 79.520037 P95 : 206.412677 P99 : 394.682201 P100 : 6596.000000 COUNT : 3160517 SUM : 244853124 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 : 1160996 SUM : 1160996000 rocksdb.bytes.per.write P50 : 1032.000000 P95 : 1032.000000 P99 : 1032.000000 P100 : 1032.000000 COUNT : 2758108 SUM : 2846367456 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 : 210000.000000 P95 : 244988.000000 P99 : 244988.000000 P100 : 244988.000000 COUNT : 43 SUM : 8524281 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 : 61 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