[2024-11-15 14:31:08.672803] Starting SPDK v25.01-pre git sha1 c5a46eb79 / DPDK 24.03.0 initialization... [2024-11-15 14:31:08.672906] [ 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_pid376044 ] Set seed to 1731677468670421 because --seed was 0 EAL: No free 2048 kB hugepages reported on node 1 [2024-11-15 14:31:08.784005] app.c: 919:spdk_app_start: *NOTICE*: Total cores available: 1 [2024-11-15 14:31:08.884221] app.c: 612:app_setup_trace: *NOTICE*: Tracepoint Group Mask 0x80 specified. [2024-11-15 14:31:08.884267] app.c: 613:app_setup_trace: *NOTICE*: Use 'spdk_trace -s rocksdb -p 376044' to capture a snapshot of events at runtime. [2024-11-15 14:31:08.884276] app.c: 618:app_setup_trace: *NOTICE*: 'spdk_trace' without parameters will also work if this is the only [2024-11-15 14:31:08.884287] app.c: 619:app_setup_trace: *NOTICE*: SPDK application currently running. [2024-11-15 14:31:08.884294] app.c: 620:app_setup_trace: *NOTICE*: Or copy /dev/shm/rocksdb_trace.pid376044 for offline analysis/debug. [2024-11-15 14:31:08.885728] reactor.c:1005:reactor_run: *NOTICE*: Reactor started on core 0 RocksDB: version 8.1.1 Date: Fri Nov 15 14:31:14 2024 CPU: 96 * Intel(R) Xeon(R) Gold 6252 CPU @ 2.10GHz CPUCache: 36608 KB 2024/11/15-14:31:18 ... thread 0: (1000000,1000000) ops and (235916.4,235916.4) ops/second in (4.238790,4.238790) 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.10 GB 87.7 0.7 0.0 0.7 1.6 0.9 0.0 1.9 132.4 286.1 5.70 5.12 17 0.335 766K 3635 0.0 0.0 L1 18/18 1.73 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 13/0 959.23 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 118/0 9.49 GB 1.0 0.5 0.4 0.1 0.5 0.4 0.0 1.2 162.8 160.3 2.90 2.21 3 0.968 0 0 0.0 0.0 L5 124/0 10.15 GB 0.1 0.0 0.0 0.0 0.0 0.0 0.6 0.0 0.0 0.0 0.00 0.00 0 0.000 0 0 0.0 0.0 Sum 290/30 25.41 GB 0.0 1.2 0.4 0.8 2.0 1.2 0.6 2.4 142.6 243.7 8.61 7.33 20 0.430 766K 3635 0.0 0.0 Int 0/0 0.00 KB 0.0 1.2 0.4 0.8 2.0 1.2 0.6 2.4 142.6 243.7 8.61 7.33 20 0.430 766K 3635 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.2 0.4 0.8 1.2 0.4 0.0 0.0 213.4 211.5 5.75 5.01 6 0.959 766K 3635 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 308.6 2.85 2.32 14 0.204 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.3 total, 4.2 interval Flush(GB): cumulative 0.860, interval 0.860 AddFile(GB): cumulative 0.000, interval 0.000 AddFile(Total Files): cumulative 0, interval 0 AddFile(L0 Files): cumulative 0, interval 0 AddFile(Keys): cumulative 0, interval 0 Cumulative compaction: 2.05 GB write, 482.32 MB/s write, 1.20 GB read, 282.31 MB/s read, 8.6 seconds Interval compaction: 2.05 GB write, 494.38 MB/s write, 1.20 GB read, 289.37 MB/s read, 8.6 seconds Write Stall (count): cf-l0-file-count-limit-delays-with-ongoing-compaction: 4, cf-l0-file-count-limit-stops-with-ongoing-compaction: 0, l0-file-count-limit-delays: 4, 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: 4, total-stops: 0, interval: 4 total count ** File Read Latency Histogram By Level [default] ** ** Level 0 read latency histogram (micros): Count: 223387 Average: 32.3159 StdDev: 163.87 Min: 0 Median: 1.4919 Max: 16494 Percentiles: P50: 1.49 P75: 1.95 P99: 455.30 P99.9: 1051.92 P99.99: 1534.58 ------------------------------------------------------ [ 0, 1 ] 52374 23.445% 23.445% ##### ( 1, 2 ] 120601 53.987% 77.433% ########### ( 2, 3 ] 14959 6.696% 84.129% # ( 3, 4 ] 3970 1.777% 85.907% ( 4, 6 ] 2557 1.145% 87.051% ( 6, 10 ] 750 0.336% 87.387% ( 10, 15 ] 145 0.065% 87.452% ( 15, 22 ] 23 0.010% 87.462% ( 22, 34 ] 5 0.002% 87.464% ( 34, 51 ] 2 0.001% 87.465% ( 76, 110 ] 1251 0.560% 88.025% ( 110, 170 ] 12127 5.429% 93.454% # ( 170, 250 ] 9109 4.078% 97.532% # ( 250, 380 ] 2593 1.161% 98.692% ( 380, 580 ] 1825 0.817% 99.509% ( 580, 870 ] 755 0.338% 99.847% ( 870, 1300 ] 278 0.124% 99.972% ( 1300, 1900 ] 104 0.047% 100.018% ( 1900, 2900 ] 80 0.036% 100.054% ( 2900, 4400 ] 24 0.011% 100.065% ( 4400, 6600 ] 11 0.005% 100.070% ( 6600, 9900 ] 11 0.005% 100.075% ( 9900, 14000 ] 6 0.003% 100.077% ( 14000, 22000 ] 5 0.002% 100.080% ** Level 1 read latency histogram (micros): Count: 4096 Average: 269.0667 StdDev: 552.43 Min: 84 Median: 179.3119 Max: 14129 Percentiles: P50: 179.31 P75: 239.28 P99: 1957.78 P99.9: 8995.80 P99.99: 14129.00 ------------------------------------------------------ ( 76, 110 ] 149 3.638% 3.638% # ( 110, 170 ] 1740 42.480% 46.118% ######## ( 170, 250 ] 1366 33.350% 79.468% ####### ( 250, 380 ] 358 8.740% 88.208% ## ( 380, 580 ] 266 6.494% 94.702% # ( 580, 870 ] 121 2.954% 97.656% # ( 870, 1300 ] 40 0.977% 98.633% ( 1300, 1900 ] 14 0.342% 98.975% ( 1900, 2900 ] 18 0.439% 99.414% ( 2900, 4400 ] 10 0.244% 99.658% ( 4400, 6600 ] 7 0.171% 99.829% ( 6600, 9900 ] 4 0.098% 99.927% ( 9900, 14000 ] 2 0.049% 99.976% ( 14000, 22000 ] 1 0.024% 100.000% ** Level 3 read latency histogram (micros): Count: 2527 Average: 242.0491 StdDev: 280.82 Min: 87 Median: 177.8947 Max: 5693 Percentiles: P50: 177.89 P75: 238.35 P99: 1591.00 P99.9: 3636.50 P99.99: 5693.00 ------------------------------------------------------ ( 76, 110 ] 115 4.551% 4.551% # ( 110, 170 ] 1066 42.184% 46.735% ######## ( 170, 250 ] 836 33.083% 79.818% ####### ( 250, 380 ] 240 9.497% 89.315% ## ( 380, 580 ] 164 6.490% 95.805% # ( 580, 870 ] 47 1.860% 97.665% ( 870, 1300 ] 25 0.989% 98.655% ( 1300, 1900 ] 18 0.712% 99.367% ( 1900, 2900 ] 12 0.475% 99.842% ( 2900, 4400 ] 3 0.119% 99.960% ( 4400, 6600 ] 1 0.040% 100.000% ** Level 4 read latency histogram (micros): Count: 21010 Average: 241.8820 StdDev: 345.17 Min: 0 Median: 168.8438 Max: 6933 Percentiles: P50: 168.84 P75: 234.88 P99: 1853.71 P99.9: 4599.00 P99.99: 6489.90 ------------------------------------------------------ [ 0, 1 ] 17 0.081% 0.081% ( 1, 2 ] 111 0.528% 0.609% ( 2, 3 ] 394 1.875% 2.485% ( 3, 4 ] 201 0.957% 3.441% ( 4, 6 ] 327 1.556% 4.998% ( 6, 10 ] 34 0.162% 5.159% ( 10, 15 ] 14 0.067% 5.226% ( 15, 22 ] 2 0.010% 5.236% ( 34, 51 ] 1 0.005% 5.240% ( 51, 76 ] 1 0.005% 5.245% ( 76, 110 ] 853 4.060% 9.305% # ( 110, 170 ] 8718 41.495% 50.800% ######## ( 170, 250 ] 6270 29.843% 80.643% ###### ( 250, 380 ] 1767 8.410% 89.053% ## ( 380, 580 ] 1234 5.873% 94.926% # ( 580, 870 ] 557 2.651% 97.577% # ( 870, 1300 ] 203 0.966% 98.544% ( 1300, 1900 ] 105 0.500% 99.043% ( 1900, 2900 ] 105 0.500% 99.543% ( 2900, 4400 ] 74 0.352% 99.895% ( 4400, 6600 ] 22 0.105% 100.000% ( 6600, 9900 ] 1 0.005% 100.005% ** Level 5 read latency histogram (micros): Count: 19429 Average: 253.7926 StdDev: 356.11 Min: 84 Median: 176.2757 Max: 6565 Percentiles: P50: 176.28 P75: 239.06 P99: 1811.79 P99.9: 5073.44 P99.99: 6447.34 ------------------------------------------------------ ( 76, 110 ] 787 4.051% 4.051% # ( 110, 170 ] 8442 43.451% 47.501% ######### ( 170, 250 ] 6189 31.854% 79.356% ###### ( 250, 380 ] 1781 9.167% 88.522% ## ( 380, 580 ] 1257 6.470% 94.992% # ( 580, 870 ] 515 2.651% 97.643% # ( 870, 1300 ] 175 0.901% 98.543% ( 1300, 1900 ] 104 0.535% 99.079% ( 1900, 2900 ] 86 0.443% 99.521% ( 2900, 4400 ] 65 0.335% 99.856% ( 4400, 6600 ] 28 0.144% 100.000% ** DB Stats ** Uptime(secs): 4.3 total, 4.2 interval Cumulative writes: 0 writes, 1000K keys, 0 commit groups, 0.0 writes per commit group, ingest: 0.96 GB, 226.33 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.806 H:M:S, 18.5 percent Interval writes: 0 writes, 1000K keys, 0 commit groups, 0.0 writes per commit group, ingest: 984.19 MB, 231.94 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.806 H:M:S, 19.0 percent Write Stall (count): write-buffer-manager-limit-stops: 0, num-running-compactions: 1 num-running-flushes: 1 2024/11/15-14:31:28 ... thread 0: (1000000,2000000) ops and (104925.4,145250.0) ops/second in (9.530577,13.769367) 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/16 4.04 GB 159.3 2.8 0.0 2.8 4.6 1.8 0.0 2.5 170.9 282.4 16.53 15.33 37 0.447 2868K 43K 0.0 0.0 L1 18/18 1.73 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 13/0 959.23 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 118/0 9.49 GB 1.0 0.5 0.4 0.1 0.5 0.4 0.0 1.2 162.8 160.3 2.90 2.21 3 0.968 0 0 0.0 0.0 L5 124/0 10.15 GB 0.1 0.0 0.0 0.0 0.0 0.0 0.6 0.0 0.0 0.0 0.00 0.00 0 0.000 0 0 0.0 0.0 Sum 292/34 26.35 GB 0.0 3.2 0.4 2.8 5.0 2.2 0.6 2.7 169.7 264.2 19.43 17.55 40 0.486 2868K 43K 0.0 0.0 Int 0/0 0.00 KB 0.0 3.2 0.4 2.8 5.0 2.2 0.6 2.7 169.7 264.2 19.43 17.55 40 0.486 2868K 43K 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.4 2.8 3.2 0.3 0.0 0.0 246.2 242.5 13.39 12.54 10 1.339 2868K 43K 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 312.4 6.04 5.01 30 0.201 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.9 total, 13.8 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: 5.01 GB write, 369.96 MB/s write, 3.22 GB read, 237.59 MB/s read, 19.4 seconds Interval compaction: 5.01 GB write, 372.81 MB/s write, 3.22 GB read, 239.42 MB/s read, 19.4 seconds Write Stall (count): cf-l0-file-count-limit-delays-with-ongoing-compaction: 14, cf-l0-file-count-limit-stops-with-ongoing-compaction: 0, l0-file-count-limit-delays: 14, 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: 14, total-stops: 0, interval: 14 total count ** File Read Latency Histogram By Level [default] ** ** Level 0 read latency histogram (micros): Count: 885477 Average: 27.5818 StdDev: 105.44 Min: 0 Median: 1.4619 Max: 16494 Percentiles: P50: 1.46 P75: 1.94 P99: 347.43 P99.9: 712.52 P99.99: 852.67 ------------------------------------------------------ [ 0, 1 ] 228140 25.765% 25.765% ##### ( 1, 2 ] 464615 52.471% 78.235% ########## ( 2, 3 ] 51334 5.797% 84.033% # ( 3, 4 ] 15158 1.712% 85.744% ( 4, 6 ] 10613 1.199% 86.943% ( 6, 10 ] 2393 0.270% 87.213% ( 10, 15 ] 403 0.046% 87.259% ( 15, 22 ] 70 0.008% 87.267% ( 22, 34 ] 9 0.001% 87.268% ( 34, 51 ] 7 0.001% 87.268% ( 51, 76 ] 7 0.001% 87.269% ( 76, 110 ] 5696 0.643% 87.913% ( 110, 170 ] 55920 6.315% 94.228% # ( 170, 250 ] 36215 4.090% 98.318% # ( 250, 380 ] 8062 0.910% 99.228% ( 380, 580 ] 5196 0.587% 99.815% ( 580, 870 ] 1649 0.186% 100.001% ( 870, 1300 ] 388 0.044% 100.045% ( 1300, 1900 ] 123 0.014% 100.059% ( 1900, 2900 ] 124 0.014% 100.073% ( 2900, 4400 ] 42 0.005% 100.078% ( 4400, 6600 ] 11 0.001% 100.079% ( 6600, 9900 ] 11 0.001% 100.080% ( 9900, 14000 ] 6 0.001% 100.081% ( 14000, 22000 ] 5 0.001% 100.081% ** Level 1 read latency histogram (micros): Count: 15416 Average: 210.6391 StdDev: 302.42 Min: 81 Median: 166.4706 Max: 14129 Percentiles: P50: 166.47 P75: 223.15 P99: 824.80 P99.9: 4236.62 P99.99: 12889.72 ------------------------------------------------------ ( 76, 110 ] 620 4.022% 4.022% # ( 110, 170 ] 7531 48.852% 52.874% ########## ( 170, 250 ] 5134 33.303% 86.177% ####### ( 250, 380 ] 1080 7.006% 93.182% # ( 380, 580 ] 701 4.547% 97.730% # ( 580, 870 ] 232 1.505% 99.235% ( 870, 1300 ] 54 0.350% 99.585% ( 1300, 1900 ] 17 0.110% 99.695% ( 1900, 2900 ] 20 0.130% 99.825% ( 2900, 4400 ] 13 0.084% 99.909% ( 4400, 6600 ] 7 0.045% 99.955% ( 6600, 9900 ] 4 0.026% 99.981% ( 9900, 14000 ] 2 0.013% 99.994% ( 14000, 22000 ] 1 0.006% 100.000% ** Level 3 read latency histogram (micros): Count: 9482 Average: 201.0537 StdDev: 168.60 Min: 85 Median: 165.0952 Max: 5693 Percentiles: P50: 165.10 P75: 222.09 P99: 794.37 P99.9: 2478.31 P99.99: 4513.96 ------------------------------------------------------ ( 76, 110 ] 450 4.746% 4.746% # ( 110, 170 ] 4673 49.283% 54.029% ########## ( 170, 250 ] 3054 32.208% 86.237% ###### ( 250, 380 ] 699 7.372% 93.609% # ( 380, 580 ] 438 4.619% 98.228% # ( 580, 870 ] 99 1.044% 99.272% ( 870, 1300 ] 33 0.348% 99.620% ( 1300, 1900 ] 19 0.200% 99.821% ( 1900, 2900 ] 13 0.137% 99.958% ( 2900, 4400 ] 3 0.032% 99.989% ( 4400, 6600 ] 1 0.011% 100.000% ** Level 4 read latency histogram (micros): Count: 79195 Average: 199.8984 StdDev: 201.68 Min: 0 Median: 162.8854 Max: 6933 Percentiles: P50: 162.89 P75: 219.75 P99: 826.91 P99.9: 3271.15 P99.99: 5808.05 ------------------------------------------------------ [ 0, 1 ] 17 0.021% 0.021% ( 1, 2 ] 236 0.298% 0.319% ( 2, 3 ] 737 0.931% 1.250% ( 3, 4 ] 404 0.510% 1.760% ( 4, 6 ] 717 0.905% 2.666% ( 6, 10 ] 76 0.096% 2.762% ( 10, 15 ] 24 0.030% 2.792% ( 15, 22 ] 4 0.005% 2.797% ( 34, 51 ] 1 0.001% 2.798% ( 51, 76 ] 1 0.001% 2.799% ( 76, 110 ] 3607 4.555% 7.354% # ( 110, 170 ] 38317 48.383% 55.737% ########## ( 170, 250 ] 24533 30.978% 86.715% ###### ( 250, 380 ] 5295 6.686% 93.401% # ( 380, 580 ] 3443 4.347% 97.749% # ( 580, 870 ] 1164 1.470% 99.218% ( 870, 1300 ] 280 0.354% 99.572% ( 1300, 1900 ] 127 0.160% 99.732% ( 1900, 2900 ] 114 0.144% 99.876% ( 2900, 4400 ] 76 0.096% 99.972% ( 4400, 6600 ] 22 0.028% 100.000% ( 6600, 9900 ] 1 0.001% 100.001% ** Level 5 read latency histogram (micros): Count: 73638 Average: 205.5721 StdDev: 206.19 Min: 80 Median: 164.8624 Max: 6565 Percentiles: P50: 164.86 P75: 221.84 P99: 823.83 P99.9: 3362.77 P99.99: 6021.42 ------------------------------------------------------ ( 76, 110 ] 3406 4.625% 4.625% # ( 110, 170 ] 36542 49.624% 54.249% ########## ( 170, 250 ] 23581 32.023% 86.272% ###### ( 250, 380 ] 5078 6.896% 93.168% # ( 380, 580 ] 3379 4.589% 97.757% # ( 580, 870 ] 1089 1.479% 99.235% ( 870, 1300 ] 251 0.341% 99.576% ( 1300, 1900 ] 119 0.162% 99.738% ( 1900, 2900 ] 99 0.134% 99.872% ( 2900, 4400 ] 66 0.090% 99.962% ( 4400, 6600 ] 28 0.038% 100.000% ** DB Stats ** Uptime(secs): 13.9 total, 9.5 interval Cumulative writes: 0 writes, 2000K keys, 0 commit groups, 0.0 writes per commit group, ingest: 1.92 GB, 141.82 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.629 H:M:S, 47.8 percent Interval writes: 0 writes, 1000K keys, 0 commit groups, 0.0 writes per commit group, ingest: 984.19 MB, 103.27 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.822 H:M:S, 61.1 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 : 258.162 micros/op 15456 ops/sec 20.121 seconds 310996 operations; 15.0 MB/s (79999 of 79999 found) Microseconds per read: Count: 310996 Average: 258.1674 StdDev: 418.76 Min: 2 Median: 212.5330 Max: 38323 Percentiles: P50: 212.53 P75: 281.35 P99: 867.47 P99.9: 3937.11 P99.99: 19383.87 ------------------------------------------------------ ( 1, 2 ] 10 0.003% 0.003% ( 2, 3 ] 62 0.020% 0.023% ( 3, 4 ] 86 0.028% 0.051% ( 4, 6 ] 165 0.053% 0.104% ( 6, 10 ] 400 0.129% 0.232% ( 10, 15 ] 2719 0.874% 1.107% ( 15, 22 ] 3172 1.020% 2.127% ( 22, 34 ] 5779 1.858% 3.985% ( 34, 51 ] 1637 0.526% 4.511% ( 51, 76 ] 160 0.051% 4.563% ( 76, 110 ] 614 0.197% 4.760% ( 110, 170 ] 65678 21.119% 25.879% #### ( 170, 250 ] 141097 45.369% 71.248% ######### ( 250, 380 ] 48379 15.556% 86.804% ### ( 380, 580 ] 28390 9.129% 95.933% ## ( 580, 870 ] 9622 3.094% 99.027% # ( 870, 1300 ] 2172 0.698% 99.725% ( 1300, 1900 ] 308 0.099% 99.824% ( 1900, 2900 ] 179 0.058% 99.882% ( 2900, 4400 ] 81 0.026% 99.908% ( 4400, 6600 ] 30 0.010% 99.918% ( 6600, 9900 ] 99 0.032% 99.950% ( 9900, 14000 ] 101 0.032% 99.982% ( 14000, 22000 ] 37 0.012% 99.994% ( 22000, 33000 ] 17 0.005% 99.999% ( 33000, 50000 ] 2 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 : 5883950 rocksdb.bloom.filter.full.positive COUNT : 368139 rocksdb.bloom.filter.full.true.positive COUNT : 310266 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 : 730 rocksdb.memtable.miss COUNT : 310266 rocksdb.l0.hit COUNT : 55098 rocksdb.l1.hit COUNT : 22867 rocksdb.l2andup.hit COUNT : 232301 rocksdb.compaction.key.drop.new COUNT : 89171 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 : 2640979 rocksdb.number.keys.read COUNT : 310996 rocksdb.number.keys.updated COUNT : 0 rocksdb.bytes.written COUNT : 2725490328 rocksdb.bytes.read COUNT : 310996000 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 : 342 rocksdb.no.file.errors COUNT : 0 rocksdb.stall.micros COUNT : 10579387 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 : 5456087774 rocksdb.compact.write.bytes COUNT : 5360105386 rocksdb.flush.write.bytes COUNT : 2704743971 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 : 27 rocksdb.number.superversion_acquires COUNT : 403 rocksdb.number.superversion_releases COUNT : 378 rocksdb.number.superversion_cleanups COUNT : 97 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 : 2686516224 rocksdb.memtable.garbage.bytes.at.flush COUNT : 4314112 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 : 6650144150 rocksdb.non.last.level.read.count COUNT : 1573580 rocksdb.block.checksum.compute.count COUNT : 1694838 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 : 211.732415 P95 : 558.879307 P99 : 867.119113 P100 : 38322.000000 COUNT : 310996 SUM : 79988265 rocksdb.db.write.micros P50 : 1.437242 P95 : 3.982103 P99 : 8.872837 P100 : 2448.000000 COUNT : 2640979 SUM : 16766274 rocksdb.compaction.times.micros P50 : 1044166.666667 P95 : 4778060.000000 P99 : 4778060.000000 P100 : 4778060.000000 COUNT : 13 SUM : 16227365 rocksdb.compaction.times.cpu_micros P50 : 992222.222222 P95 : 4720223.000000 P99 : 4720223.000000 P100 : 4720223.000000 COUNT : 13 SUM : 15320862 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 : 803.541667 P95 : 1847.500000 P99 : 2209.000000 P100 : 2209.000000 COUNT : 41 SUM : 38529 rocksdb.compaction.outfile.sync.micros P50 : 1120.833333 P95 : 3575.000000 P99 : 3734.000000 P100 : 3734.000000 COUNT : 22 SUM : 29084 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 : 164.642857 P95 : 390.000000 P99 : 535.000000 P100 : 535.000000 COUNT : 57 SUM : 10691 rocksdb.table.open.io.micros P50 : 6954.362416 P95 : 13625.652174 P99 : 35465.000000 P100 : 37653.000000 COUNT : 342 SUM : 2516495 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.427285 P95 : 136.948856 P99 : 247.238239 P100 : 5105.000000 COUNT : 1322455 SUM : 23265173 rocksdb.read.block.get.micros P50 : 163.878736 P95 : 418.043521 P99 : 748.511896 P100 : 16569.000000 COUNT : 372262 SUM : 70184634 rocksdb.write.raw.block.micros P50 : 0.523898 P95 : 0.995407 P99 : 3.549599 P100 : 4703.000000 COUNT : 1955227 SUM : 4250287 rocksdb.numfiles.in.singlecompaction P50 : 3.500000 P95 : 23.800000 P99 : 30.000000 P100 : 30.000000 COUNT : 17 SUM : 91 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.571276 P95 : 1191.513672 P99 : 2188.000000 P100 : 2188.000000 COUNT : 67077 SUM : 10588308 rocksdb.sst.read.micros P50 : 1.658139 P95 : 236.668996 P99 : 498.947818 P100 : 16494.000000 COUNT : 1573870 SUM : 88005217 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 : 310996 SUM : 310996000 rocksdb.bytes.per.write P50 : 1032.000000 P95 : 1032.000000 P99 : 1032.000000 P100 : 1032.000000 COUNT : 2640979 SUM : 2725490328 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 : 211000.000000 P95 : 247900.000000 P99 : 287721.000000 P100 : 287721.000000 COUNT : 41 SUM : 8239927 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 : 48 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