[2024-10-14 21:38:06.697081] Starting SPDK v24.01.1-pre git sha1 726a04d70 / DPDK 22.11.4 initialization... [2024-10-14 21:38:06.697240] [ DPDK EAL parameters: rocksdb --no-shconf -c 0x1 --huge-unlink --no-telemetry --log-level=lib.eal:6 --log-level=lib.cryptodev:5 --log-level=user1:6 --base-virtaddr=0x200000000000 --match-allocations --file-prefix=spdk_pid119251 ] Set seed to 1728934686693575 because --seed was 0 EAL: No free 2048 kB hugepages reported on node 1 [2024-10-14 21:38:06.790507] app.c: 798:spdk_app_start: *NOTICE*: Total cores available: 1 [2024-10-14 21:38:06.830497] app.c: 488:app_setup_trace: *NOTICE*: Tracepoint Group Mask 0x80 specified. [2024-10-14 21:38:06.830548] app.c: 489:app_setup_trace: *NOTICE*: Use 'spdk_trace -s rocksdb -p 119251' to capture a snapshot of events at runtime. [2024-10-14 21:38:06.830560] app.c: 494:app_setup_trace: *NOTICE*: Or copy /dev/shm/rocksdb_trace.pid119251 for offline analysis/debug. [2024-10-14 21:38:06.830600] reactor.c: 937:reactor_run: *NOTICE*: Reactor started on core 0 RocksDB: version 8.1.1 Date: Mon Oct 14 21:38:11 2024 CPU: 96 * Intel(R) Xeon(R) Gold 6252 CPU @ 2.10GHz CPUCache: 36608 KB 2024/10/14-21:38:16 ... thread 0: (1000000,1000000) ops and (200238.6,200238.6) ops/second in (4.994041,4.994041) seconds ** Compaction Stats [default] ** Level Files Size Score Read(GB) Rn(GB) Rnp1(GB) Write(GB) Wnew(GB) Moved(GB) W-Amp Rd(MB/s) Wr(MB/s) Comp(sec) CompMergeCPU(sec) Comp(cnt) Avg(sec) KeyIn KeyDrop Rblob(GB) Wblob(GB) ------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------ L0 12/10 2.85 GB 62.9 0.6 0.0 0.6 1.5 0.9 0.0 1.7 94.6 226.8 6.66 4.70 14 0.476 639K 777 0.0 0.0 L1 12/12 1.46 GB 0.0 0.0 0.0 0.0 0.0 0.0 0.0 0.0 0.0 0.0 0.00 0.00 0 0.000 0 0 0.0 0.0 L3 19/0 993.15 MB 1.0 0.0 0.0 0.0 0.0 0.0 0.1 0.0 0.0 0.0 0.00 0.00 0 0.000 0 0 0.0 0.0 L4 175/0 9.55 GB 1.0 2.5 1.1 1.4 2.5 1.1 0.0 2.2 106.0 104.1 24.31 11.93 17 1.430 0 0 0.0 0.0 L5 1124/0 92.46 GB 0.9 0.0 0.0 0.0 0.0 0.0 2.3 0.0 0.0 0.0 0.00 0.00 0 0.000 0 0 0.0 0.0 Sum 1342/22 107.29 GB 0.0 3.1 1.1 2.0 3.9 2.0 2.5 4.6 103.5 130.5 30.97 16.63 31 0.999 639K 777 0.0 0.0 Int 0/0 0.00 KB 0.0 3.1 1.1 2.0 3.9 2.0 2.5 4.6 103.5 130.5 30.97 16.63 31 0.999 639K 777 0.0 0.0 ** Compaction Stats [default] ** Priority Files Size Score Read(GB) Rn(GB) Rnp1(GB) Write(GB) Wnew(GB) Moved(GB) W-Amp Rd(MB/s) Wr(MB/s) Comp(sec) CompMergeCPU(sec) Comp(cnt) Avg(sec) KeyIn KeyDrop Rblob(GB) Wblob(GB) --------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------- Low 0/0 0.00 KB 0.0 3.1 1.1 2.0 3.1 1.1 0.0 0.0 120.2 118.4 26.69 14.25 19 1.405 639K 777 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 206.0 4.28 2.38 12 0.357 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.2 total, 5.0 interval Flush(GB): cumulative 0.861, interval 0.861 AddFile(GB): cumulative 0.000, interval 0.000 AddFile(Total Files): cumulative 0, interval 0 AddFile(L0 Files): cumulative 0, interval 0 AddFile(Keys): cumulative 0, interval 0 Cumulative compaction: 3.95 GB write, 778.89 MB/s write, 3.13 GB read, 618.13 MB/s read, 31.0 seconds Interval compaction: 3.95 GB write, 808.79 MB/s write, 3.13 GB read, 641.85 MB/s read, 31.0 seconds Write Stall (count): cf-l0-file-count-limit-delays-with-ongoing-compaction: 0, cf-l0-file-count-limit-stops-with-ongoing-compaction: 0, l0-file-count-limit-delays: 0, l0-file-count-limit-stops: 0, memtable-limit-delays: 0, memtable-limit-stops: 13, pending-compaction-bytes-delays: 3, pending-compaction-bytes-stops: 0, total-delays: 3, total-stops: 13, interval: 16 total count ** File Read Latency Histogram By Level [default] ** ** Level 0 read latency histogram (micros): Count: 336108 Average: 8.1649 StdDev: 93.76 Min: 1 Median: 1.6120 Max: 13987 Percentiles: P50: 1.61 P75: 2.18 P99: 141.50 P99.9: 752.86 P99.99: 1257.21 ------------------------------------------------------ [ 0, 1 ] 49012 14.582% 14.582% ### ( 1, 2 ] 194509 57.871% 72.453% ############ ( 2, 3 ] 46995 13.982% 86.435% ### ( 3, 4 ] 18539 5.516% 91.951% # ( 4, 6 ] 16130 4.799% 96.750% # ( 6, 10 ] 5111 1.521% 98.271% ( 10, 15 ] 936 0.278% 98.549% ( 15, 22 ] 145 0.043% 98.592% ( 22, 34 ] 22 0.007% 98.599% ( 34, 51 ] 12 0.004% 98.603% ( 51, 76 ] 6 0.002% 98.604% ( 76, 110 ] 480 0.143% 98.747% ( 110, 170 ] 1619 0.482% 99.229% ( 170, 250 ] 952 0.283% 99.512% ( 250, 380 ] 577 0.172% 99.684% ( 380, 580 ] 529 0.157% 99.841% ( 580, 870 ] 332 0.099% 99.940% ( 870, 1300 ] 187 0.056% 99.996% ( 1300, 1900 ] 99 0.029% 100.025% ( 1900, 2900 ] 53 0.016% 100.041% ( 2900, 4400 ] 59 0.018% 100.058% ( 4400, 6600 ] 17 0.005% 100.063% ( 6600, 9900 ] 3 0.001% 100.064% ( 9900, 14000 ] 3 0.001% 100.065% ** Level 1 read latency histogram (micros): Count: 365 Average: 825.7342 StdDev: 1613.61 Min: 90 Median: 216.2069 Max: 9838 Percentiles: P50: 216.21 P75: 531.47 P99: 8561.67 P99.9: 9766.17 P99.99: 9838.00 ------------------------------------------------------ ( 76, 110 ] 34 9.315% 9.315% ## ( 110, 170 ] 115 31.507% 40.822% ###### ( 170, 250 ] 58 15.890% 56.712% ### ( 250, 380 ] 41 11.233% 67.945% ## ( 380, 580 ] 34 9.315% 77.260% ## ( 580, 870 ] 20 5.479% 82.740% # ( 870, 1300 ] 11 3.014% 85.753% # ( 1300, 1900 ] 10 2.740% 88.493% # ( 1900, 2900 ] 8 2.192% 90.685% ( 2900, 4400 ] 18 4.932% 95.616% # ( 4400, 6600 ] 7 1.918% 97.534% ( 6600, 9900 ] 9 2.466% 100.000% ** Level 2 read latency histogram (micros): Count: 37 Average: 1020.0000 StdDev: 4238.90 Min: 100 Median: 168.1250 Max: 26331 Percentiles: P50: 168.12 P75: 325.83 P99: 26331.00 P99.9: 26331.00 P99.99: 26331.00 ------------------------------------------------------ ( 76, 110 ] 3 8.108% 8.108% ## ( 110, 170 ] 16 43.243% 51.351% ######### ( 170, 250 ] 7 18.919% 70.270% #### ( 250, 380 ] 3 8.108% 78.378% ## ( 380, 580 ] 3 8.108% 86.486% ## ( 580, 870 ] 2 5.405% 91.892% # ( 1300, 1900 ] 1 2.703% 94.595% # ( 1900, 2900 ] 1 2.703% 97.297% # ( 22000, 33000 ] 1 2.703% 100.000% # ** Level 3 read latency histogram (micros): Count: 108821 Average: 12.5180 StdDev: 149.34 Min: 1 Median: 1.8121 Max: 8952 Percentiles: P50: 1.81 P75: 2.85 P99: 13.48 P99.9: 1728.76 P99.99: 2666.09 ------------------------------------------------------ [ 0, 1 ] 7719 7.093% 7.093% # ( 1, 2 ] 57496 52.835% 59.929% ########### ( 2, 3 ] 19331 17.764% 77.693% #### ( 3, 4 ] 9703 8.916% 86.609% ## ( 4, 6 ] 10179 9.354% 95.963% ## ( 6, 10 ] 2946 2.707% 98.670% # ( 10, 15 ] 516 0.474% 99.144% ( 15, 22 ] 87 0.080% 99.224% ( 22, 34 ] 15 0.014% 99.238% ( 34, 51 ] 3 0.003% 99.241% ( 51, 76 ] 2 0.002% 99.243% ( 76, 110 ] 29 0.027% 99.269% ( 110, 170 ] 80 0.074% 99.343% ( 170, 250 ] 50 0.046% 99.389% ( 250, 380 ] 63 0.058% 99.447% ( 380, 580 ] 107 0.098% 99.545% ( 580, 870 ] 138 0.127% 99.672% ( 870, 1300 ] 166 0.153% 99.824% ( 1300, 1900 ] 115 0.106% 99.930% ( 1900, 2900 ] 85 0.078% 100.008% ( 2900, 4400 ] 48 0.044% 100.052% ( 4400, 6600 ] 23 0.021% 100.074% ( 6600, 9900 ] 3 0.003% 100.076% ** Level 4 read latency histogram (micros): Count: 260366 Average: 20.2003 StdDev: 196.01 Min: 1 Median: 1.8296 Max: 8823 Percentiles: P50: 1.83 P75: 2.89 P99: 312.39 P99.9: 1987.53 P99.99: 2657.04 ------------------------------------------------------ [ 0, 1 ] 17994 6.911% 6.911% # ( 1, 2 ] 135230 51.938% 58.849% ########## ( 2, 3 ] 47382 18.198% 77.048% #### ( 3, 4 ] 23762 9.126% 86.174% ## ( 4, 6 ] 23411 8.992% 95.166% ## ( 6, 10 ] 6816 2.618% 97.784% # ( 10, 15 ] 1424 0.547% 98.330% ( 15, 22 ] 220 0.084% 98.415% ( 22, 34 ] 32 0.012% 98.427% ( 34, 51 ] 15 0.006% 98.433% ( 51, 76 ] 9 0.003% 98.436% ( 76, 110 ] 191 0.073% 98.510% ( 110, 170 ] 643 0.247% 98.757% ( 170, 250 ] 438 0.168% 98.925% ( 250, 380 ] 407 0.156% 99.081% ( 380, 580 ] 498 0.191% 99.273% ( 580, 870 ] 536 0.206% 99.478% ( 870, 1300 ] 571 0.219% 99.698% ( 1300, 1900 ] 496 0.191% 99.888% ( 1900, 2900 ] 350 0.134% 100.023% ( 2900, 4400 ] 266 0.102% 100.125% ( 4400, 6600 ] 80 0.031% 100.156% ( 6600, 9900 ] 8 0.003% 100.159% ** Level 5 read latency histogram (micros): Count: 20249 Average: 681.4566 StdDev: 1075.74 Min: 76 Median: 241.9716 Max: 19794 Percentiles: P50: 241.97 P75: 642.99 P99: 5676.73 P99.9: 8891.72 P99.99: 9846.31 ------------------------------------------------------ ( 51, 76 ] 3 0.015% 0.015% ( 76, 110 ] 1576 7.783% 7.798% ## ( 110, 170 ] 5502 27.172% 34.970% ##### ( 170, 250 ] 3383 16.707% 51.677% ### ( 250, 380 ] 2352 11.615% 63.292% ## ( 380, 580 ] 2053 10.139% 73.431% ## ( 580, 870 ] 1463 7.225% 80.656% # ( 870, 1300 ] 1047 5.171% 85.826% # ( 1300, 1900 ] 845 4.173% 90.000% # ( 1900, 2900 ] 830 4.099% 94.098% # ( 2900, 4400 ] 801 3.956% 98.054% # ( 4400, 6600 ] 330 1.630% 99.684% ( 6600, 9900 ] 63 0.311% 99.995% ( 9900, 14000 ] 1 0.005% 100.000% ( 14000, 22000 ] 1 0.005% 100.005% ** DB Stats ** Uptime(secs): 5.2 total, 5.0 interval Cumulative writes: 0 writes, 1000K keys, 0 commit groups, 0.0 writes per commit group, ingest: 0.96 GB, 189.69 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:1.261 H:M:S, 24.3 percent Interval writes: 0 writes, 1000K keys, 0 commit groups, 0.0 writes per commit group, ingest: 984.19 MB, 196.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:1.261 H:M:S, 25.2 percent Write Stall (count): write-buffer-manager-limit-stops: 0, num-running-compactions: 2 num-running-flushes: 1 2024/10/14-21:38:19 ... thread 0: (1000000,2000000) ops and (289612.1,236772.3) ops/second in (3.452894,8.446935) seconds ** Compaction Stats [default] ** Level Files Size Score Read(GB) Rn(GB) Rnp1(GB) Write(GB) Wnew(GB) Moved(GB) W-Amp Rd(MB/s) Wr(MB/s) Comp(sec) CompMergeCPU(sec) Comp(cnt) Avg(sec) KeyIn KeyDrop Rblob(GB) Wblob(GB) ------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------ L0 19/14 3.83 GB 138.4 1.4 0.0 1.4 3.2 1.8 0.0 1.7 109.0 257.5 12.71 10.25 33 0.385 1407K 1512 0.0 0.0 L1 12/12 1.46 GB 0.0 0.0 0.0 0.0 0.0 0.0 0.0 0.0 0.0 0.0 0.00 0.00 0 0.000 0 0 0.0 0.0 L3 19/0 993.15 MB 1.0 0.0 0.0 0.0 0.0 0.0 0.1 0.0 0.0 0.0 0.00 0.00 0 0.000 0 0 0.0 0.0 L4 175/0 9.55 GB 1.0 2.5 1.1 1.4 2.5 1.1 0.0 2.2 106.0 104.1 24.31 11.93 17 1.430 0 0 0.0 0.0 L5 1124/0 92.46 GB 0.9 0.0 0.0 0.0 0.0 0.0 2.3 0.0 0.0 0.0 0.00 0.00 0 0.000 0 0 0.0 0.0 Sum 1349/26 108.27 GB 0.0 3.9 1.1 2.7 5.7 2.9 2.5 3.1 107.0 156.8 37.02 22.18 50 0.740 1407K 1512 0.0 0.0 Int 0/0 0.00 KB 0.0 3.9 1.1 2.7 5.7 2.9 2.5 3.1 107.0 156.8 37.02 22.18 50 0.740 1407K 1512 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 1.1 2.7 3.8 1.1 0.0 0.0 134.1 132.4 29.55 17.06 22 1.343 1407K 1512 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 253.1 7.47 5.12 28 0.267 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): 8.6 total, 8.4 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.67 GB write, 671.72 MB/s write, 3.87 GB read, 458.63 MB/s read, 37.0 seconds Interval compaction: 5.67 GB write, 686.97 MB/s write, 3.87 GB read, 469.04 MB/s read, 37.0 seconds Write Stall (count): cf-l0-file-count-limit-delays-with-ongoing-compaction: 0, cf-l0-file-count-limit-stops-with-ongoing-compaction: 0, l0-file-count-limit-delays: 0, l0-file-count-limit-stops: 0, memtable-limit-delays: 0, memtable-limit-stops: 13, pending-compaction-bytes-delays: 3, pending-compaction-bytes-stops: 0, total-delays: 3, total-stops: 13, interval: 16 total count ** File Read Latency Histogram By Level [default] ** ** Level 0 read latency histogram (micros): Count: 612950 Average: 11.6040 StdDev: 82.23 Min: 0 Median: 1.5150 Max: 13987 Percentiles: P50: 1.51 P75: 1.96 P99: 230.49 P99.9: 685.65 P99.99: 1006.46 ------------------------------------------------------ [ 0, 1 ] 130906 21.357% 21.357% #### ( 1, 2 ] 340915 55.619% 76.975% ########### ( 2, 3 ] 63717 10.395% 87.371% ## ( 3, 4 ] 25652 4.185% 91.556% # ( 4, 6 ] 20343 3.319% 94.874% # ( 6, 10 ] 6083 0.992% 95.867% ( 10, 15 ] 1232 0.201% 96.068% ( 15, 22 ] 192 0.031% 96.099% ( 22, 34 ] 34 0.006% 96.105% ( 34, 51 ] 14 0.002% 96.107% ( 51, 76 ] 12 0.002% 96.109% ( 76, 110 ] 3038 0.496% 96.605% ( 110, 170 ] 10860 1.772% 98.376% ( 170, 250 ] 5057 0.825% 99.201% ( 250, 380 ] 2208 0.360% 99.562% ( 380, 580 ] 1804 0.294% 99.856% ( 580, 870 ] 744 0.121% 99.977% ( 870, 1300 ] 248 0.040% 100.018% ( 1300, 1900 ] 117 0.019% 100.037% ( 1900, 2900 ] 69 0.011% 100.048% ( 2900, 4400 ] 63 0.010% 100.058% ( 4400, 6600 ] 17 0.003% 100.061% ( 6600, 9900 ] 3 0.000% 100.062% ( 9900, 14000 ] 3 0.000% 100.062% ** Level 1 read latency histogram (micros): Count: 1207 Average: 386.2618 StdDev: 939.87 Min: 83 Median: 160.5937 Max: 9838 Percentiles: P50: 160.59 P75: 255.88 P99: 5635.14 P99.9: 9457.43 P99.99: 9838.00 ------------------------------------------------------ ( 76, 110 ] 149 12.345% 12.345% ## ( 110, 170 ] 539 44.656% 57.001% ######### ( 170, 250 ] 212 17.564% 74.565% #### ( 250, 380 ] 116 9.611% 84.176% ## ( 380, 580 ] 92 7.622% 91.798% ## ( 580, 870 ] 31 2.568% 94.366% # ( 870, 1300 ] 16 1.326% 95.692% ( 1300, 1900 ] 10 0.829% 96.520% ( 1900, 2900 ] 8 0.663% 97.183% ( 2900, 4400 ] 18 1.491% 98.674% ( 4400, 6600 ] 7 0.580% 99.254% ( 6600, 9900 ] 9 0.746% 100.000% ** Level 2 read latency histogram (micros): Count: 126 Average: 453.7857 StdDev: 2329.53 Min: 91 Median: 163.6842 Max: 26331 Percentiles: P50: 163.68 P75: 240.34 P99: 2640.00 P99.9: 26331.00 P99.99: 26331.00 ------------------------------------------------------ ( 76, 110 ] 12 9.524% 9.524% ## ( 110, 170 ] 57 45.238% 54.762% ######### ( 170, 250 ] 29 23.016% 77.778% ##### ( 250, 380 ] 9 7.143% 84.921% # ( 380, 580 ] 10 7.937% 92.857% ## ( 580, 870 ] 6 4.762% 97.619% # ( 1300, 1900 ] 1 0.794% 98.413% ( 1900, 2900 ] 1 0.794% 99.206% ( 22000, 33000 ] 1 0.794% 100.000% ** Level 3 read latency histogram (micros): Count: 109454 Average: 13.5873 StdDev: 149.87 Min: 1 Median: 1.8176 Max: 8952 Percentiles: P50: 1.82 P75: 2.87 P99: 149.19 P99.9: 1725.46 P99.99: 2665.35 ------------------------------------------------------ [ 0, 1 ] 7719 7.052% 7.052% # ( 1, 2 ] 57496 52.530% 59.582% ########### ( 2, 3 ] 19331 17.661% 77.243% #### ( 3, 4 ] 9703 8.865% 86.108% ## ( 4, 6 ] 10179 9.300% 95.408% ## ( 6, 10 ] 2946 2.692% 98.100% # ( 10, 15 ] 516 0.471% 98.571% ( 15, 22 ] 87 0.079% 98.651% ( 22, 34 ] 15 0.014% 98.664% ( 34, 51 ] 3 0.003% 98.667% ( 51, 76 ] 2 0.002% 98.669% ( 76, 110 ] 96 0.088% 98.757% ( 110, 170 ] 408 0.373% 99.129% ( 170, 250 ] 179 0.164% 99.293% ( 250, 380 ] 114 0.104% 99.397% ( 380, 580 ] 148 0.135% 99.532% ( 580, 870 ] 153 0.140% 99.672% ( 870, 1300 ] 168 0.153% 99.825% ( 1300, 1900 ] 115 0.105% 99.931% ( 1900, 2900 ] 85 0.078% 100.008% ( 2900, 4400 ] 48 0.044% 100.052% ( 4400, 6600 ] 23 0.021% 100.073% ( 6600, 9900 ] 3 0.003% 100.076% ** Level 4 read latency histogram (micros): Count: 266283 Average: 23.7246 StdDev: 196.54 Min: 1 Median: 1.8513 Max: 8823 Percentiles: P50: 1.85 P75: 2.98 P99: 430.98 P99.9: 1983.71 P99.99: 2658.79 ------------------------------------------------------ [ 0, 1 ] 17994 6.757% 6.757% # ( 1, 2 ] 135256 50.794% 57.552% ########## ( 2, 3 ] 47529 17.849% 75.401% #### ( 3, 4 ] 23889 8.971% 84.372% ## ( 4, 6 ] 23677 8.892% 93.264% ## ( 6, 10 ] 6862 2.577% 95.841% # ( 10, 15 ] 1437 0.540% 96.380% ( 15, 22 ] 223 0.084% 96.464% ( 22, 34 ] 32 0.012% 96.476% ( 34, 51 ] 15 0.006% 96.482% ( 51, 76 ] 9 0.003% 96.485% ( 76, 110 ] 864 0.324% 96.809% ( 110, 170 ] 3235 1.215% 98.024% ( 170, 250 ] 1561 0.586% 98.611% ( 250, 380 ] 820 0.308% 98.918% ( 380, 580 ] 852 0.320% 99.238% ( 580, 870 ] 644 0.242% 99.480% ( 870, 1300 ] 591 0.222% 99.702% ( 1300, 1900 ] 497 0.187% 99.889% ( 1900, 2900 ] 355 0.133% 100.022% ( 2900, 4400 ] 266 0.100% 100.122% ( 4400, 6600 ] 80 0.030% 100.152% ( 6600, 9900 ] 8 0.003% 100.155% ** Level 5 read latency histogram (micros): Count: 64424 Average: 350.8922 StdDev: 654.77 Min: 74 Median: 165.9083 Max: 19794 Percentiles: P50: 165.91 P75: 276.84 P99: 3936.46 P99.9: 6590.51 P99.99: 9562.54 ------------------------------------------------------ ( 51, 76 ] 7 0.011% 0.011% ( 76, 110 ] 6790 10.540% 10.550% ## ( 110, 170 ] 27275 42.337% 52.887% ######## ( 170, 250 ] 13025 20.218% 73.105% #### ( 250, 380 ] 5914 9.180% 82.285% ## ( 380, 580 ] 4962 7.702% 89.987% ## ( 580, 870 ] 2339 3.631% 93.617% # ( 870, 1300 ] 1184 1.838% 95.455% ( 1300, 1900 ] 873 1.355% 96.810% ( 1900, 2900 ] 849 1.318% 98.128% ( 2900, 4400 ] 813 1.262% 99.390% ( 4400, 6600 ] 330 0.512% 99.902% ( 6600, 9900 ] 63 0.098% 100.000% ( 9900, 14000 ] 1 0.002% 100.002% ( 14000, 22000 ] 1 0.002% 100.003% ** DB Stats ** Uptime(secs): 8.6 total, 3.5 interval Cumulative writes: 0 writes, 2000K keys, 0 commit groups, 0.0 writes per commit group, ingest: 1.92 GB, 227.80 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:1.261 H:M:S, 14.6 percent Interval writes: 0 writes, 1000K keys, 0 commit groups, 0.0 writes per commit group, ingest: 984.19 MB, 285.06 MB/s Interval WAL: 0 writes, 0 syncs, 0.00 writes per sync, written: 0.00 GB, 0.00 MB/s Interval stall: 00:00:0.000 H:M:S, 0.0 percent Write Stall (count): write-buffer-manager-limit-stops: 0, num-running-compactions: 3 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 : 214.099 micros/op 18570 ops/sec 60.742 seconds 1127996 operations; 18.0 MB/s (281999 of 281999 found) Microseconds per read: Count: 1127996 Average: 214.1009 StdDev: 510.47 Min: 2 Median: 171.0927 Max: 69191 Percentiles: P50: 171.09 P75: 227.41 P99: 779.37 P99.9: 5079.91 P99.99: 23916.18 ------------------------------------------------------ ( 1, 2 ] 4 0.000% 0.000% ( 2, 3 ] 25 0.002% 0.003% ( 3, 4 ] 32 0.003% 0.005% ( 4, 6 ] 73 0.006% 0.012% ( 6, 10 ] 497 0.044% 0.056% ( 10, 15 ] 2916 0.259% 0.314% ( 15, 22 ] 1806 0.160% 0.475% ( 22, 34 ] 10562 0.936% 1.411% ( 34, 51 ] 1595 0.141% 1.552% ( 51, 76 ] 221 0.020% 1.572% ( 76, 110 ] 18572 1.646% 3.218% ( 110, 170 ] 522223 46.297% 49.515% ######### ( 170, 250 ] 400609 35.515% 85.030% ####### ( 250, 380 ] 110224 9.772% 94.802% ## ( 380, 580 ] 38716 3.432% 98.234% # ( 580, 870 ] 12569 1.114% 99.348% ( 870, 1300 ] 3891 0.345% 99.693% ( 1300, 1900 ] 1226 0.109% 99.802% ( 1900, 2900 ] 551 0.049% 99.851% ( 2900, 4400 ] 450 0.040% 99.891% ( 4400, 6600 ] 343 0.030% 99.921% ( 6600, 9900 ] 234 0.021% 99.942% ( 9900, 14000 ] 257 0.023% 99.965% ( 14000, 22000 ] 271 0.024% 99.989% ( 22000, 33000 ] 93 0.008% 99.997% ( 33000, 50000 ] 30 0.003% 99.999% ( 50000, 75000 ] 6 0.001% 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 : 15058982 rocksdb.bloom.filter.full.positive COUNT : 1274501 rocksdb.bloom.filter.full.true.positive COUNT : 1127622 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 : 374 rocksdb.memtable.miss COUNT : 1127622 rocksdb.l0.hit COUNT : 49441 rocksdb.l1.hit COUNT : 16506 rocksdb.l2andup.hit COUNT : 1061675 rocksdb.compaction.key.drop.new COUNT : 157452 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 : 2654665 rocksdb.number.keys.read COUNT : 1127996 rocksdb.number.keys.updated COUNT : 0 rocksdb.bytes.written COUNT : 2739614280 rocksdb.bytes.read COUNT : 1127996000 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 : 1509 rocksdb.no.file.errors COUNT : 0 rocksdb.stall.micros COUNT : 50198480 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 : 13984412030 rocksdb.compact.write.bytes COUNT : 13814674154 rocksdb.flush.write.bytes COUNT : 2708536581 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 : 49 rocksdb.number.superversion_acquires COUNT : 517 rocksdb.number.superversion_releases COUNT : 497 rocksdb.number.superversion_cleanups COUNT : 133 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 : 112 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 : 2686538752 rocksdb.memtable.garbage.bytes.at.flush COUNT : 900096 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 : 18759270662 rocksdb.non.last.level.read.count COUNT : 4387464 rocksdb.block.checksum.compute.count COUNT : 4672139 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 : 169.626934 P95 : 389.429598 P99 : 778.305824 P100 : 69188.000000 COUNT : 1127996 SUM : 240446230 rocksdb.db.write.micros P50 : 1.613611 P95 : 5.443007 P99 : 1010.289237 P100 : 386065.000000 COUNT : 2654665 SUM : 57300226 rocksdb.compaction.times.micros P50 : 1200000.000000 P95 : 2866666.666667 P99 : 57797771.000000 P100 : 57797771.000000 COUNT : 42 SUM : 110249870 rocksdb.compaction.times.cpu_micros P50 : 808823.529412 P95 : 1865000.000000 P99 : 22164356.000000 P100 : 22164356.000000 COUNT : 42 SUM : 59776351 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 : 794.047619 P95 : 51250.000000 P99 : 111411.000000 P100 : 111411.000000 COUNT : 39 SUM : 235737 rocksdb.compaction.outfile.sync.micros P50 : 11950.000000 P95 : 165600.000000 P99 : 287700.000000 P100 : 372625.000000 COUNT : 142 SUM : 5920233 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 : 163.000000 P95 : 5261.666667 P99 : 6831.000000 P100 : 8419.000000 COUNT : 93 SUM : 94703 rocksdb.table.open.io.micros P50 : 6615.000000 P95 : 18473.202614 P99 : 21629.281046 P100 : 29819.000000 COUNT : 1509 SUM : 11561991 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.848118 P95 : 126.156619 P99 : 221.168487 P100 : 19052.000000 COUNT : 3386667 SUM : 64361853 rocksdb.read.block.get.micros P50 : 140.702540 P95 : 276.486441 P99 : 688.692240 P100 : 26495.000000 COUNT : 1284148 SUM : 204592918 rocksdb.write.raw.block.micros P50 : 0.527703 P95 : 1.077924 P99 : 3.658184 P100 : 15860.000000 COUNT : 4003727 SUM : 9335937 rocksdb.numfiles.in.singlecompaction P50 : 2.815789 P95 : 3.997917 P99 : 26.680000 P100 : 27.000000 COUNT : 61 SUM : 231 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.582557 P95 : 2059.458078 P99 : 2736.156880 P100 : 385878.000000 COUNT : 231634 SUM : 50220424 rocksdb.sst.read.micros P50 : 2.480343 P95 : 188.147199 P99 : 440.968625 P100 : 26331.000000 COUNT : 4387829 SUM : 253538864 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 : 1127996 SUM : 1127996000 rocksdb.bytes.per.write P50 : 1032.000000 P95 : 1032.000000 P99 : 1032.000000 P100 : 1032.000000 COUNT : 2654665 SUM : 2739614280 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 : 215882.352941 P95 : 584500.000000 P99 : 978428.000000 P100 : 978428.000000 COUNT : 39 SUM : 9760677 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 : 155 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