[2024-07-25 16:04:04.931412] Starting SPDK v24.09-pre git sha1 e03c4a941 / DPDK 24.03.0 initialization... [2024-07-25 16:04:04.931746] [ 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_pid2115048 ] Set seed to 1721916244923771 because --seed was 0 EAL: No free 2048 kB hugepages reported on node 1 [2024-07-25 16:04:05.247319] app.c: 909:spdk_app_start: *NOTICE*: Total cores available: 1 [2024-07-25 16:04:05.551568] app.c: 603:app_setup_trace: *NOTICE*: Tracepoint Group Mask 0x80 specified. [2024-07-25 16:04:05.551628] app.c: 604:app_setup_trace: *NOTICE*: Use 'spdk_trace -s rocksdb -p 2115048' to capture a snapshot of events at runtime. [2024-07-25 16:04:05.551647] app.c: 609:app_setup_trace: *NOTICE*: 'spdk_trace' without parameters will also work if this is the only [2024-07-25 16:04:05.551667] app.c: 610:app_setup_trace: *NOTICE*: SPDK application currently running. [2024-07-25 16:04:05.551682] app.c: 611:app_setup_trace: *NOTICE*: Or copy /dev/shm/rocksdb_trace.pid2115048 for offline analysis/debug. [2024-07-25 16:04:05.551820] reactor.c: 941:reactor_run: *NOTICE*: Reactor started on core 0 RocksDB: version 8.1.1 Date: Thu Jul 25 16:04:13 2024 CPU: 72 * Intel(R) Xeon(R) Gold 6140 CPU @ 2.30GHz CPUCache: 25344 KB 2024/07/25-16:04:18 ... thread 0: (1000000,1000000) ops and (204721.0,204721.0) ops/second in (4.884696,4.884696) 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 9/5 753.76 MB 43.9 0.2 0.0 0.2 1.0 0.8 0.0 1.3 51.4 218.0 4.90 4.55 14 0.350 255K 1188 0.0 0.0 L2 1/1 100.86 MB 0.0 0.2 0.1 0.1 0.2 0.1 0.0 2.5 100.2 100.0 1.60 1.53 1 1.604 163K 335 0.0 0.0 L3 22/15 1004.48 MB 0.1 0.1 0.1 0.0 0.1 0.1 0.0 1.0 142.5 142.5 0.42 0.36 1 0.418 60K 0 0.0 0.0 L4 152/0 9.54 GB 1.0 0.0 0.0 0.0 0.0 0.0 0.0 0.0 0.0 0.0 0.00 0.00 0 0.000 0 0 0.0 0.0 L5 140/0 8.67 GB 0.1 0.0 0.0 0.0 0.0 0.0 0.0 0.0 0.0 0.0 0.00 0.00 0 0.000 0 0 0.0 0.0 Sum 324/21 20.02 GB 0.0 0.5 0.1 0.3 1.3 0.9 0.0 1.6 68.2 186.1 6.92 6.44 16 0.433 479K 1523 0.0 0.0 Int 0/0 0.00 KB 0.0 0.5 0.1 0.3 1.3 0.9 0.0 1.6 68.2 186.1 6.92 6.44 16 0.433 479K 1523 0.0 0.0 ** Compaction Stats [default] ** Priority Files Size Score Read(GB) Rn(GB) Rnp1(GB) Write(GB) Wnew(GB) Moved(GB) W-Amp Rd(MB/s) Wr(MB/s) Comp(sec) CompMergeCPU(sec) Comp(cnt) Avg(sec) KeyIn KeyDrop Rblob(GB) Wblob(GB) --------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------- Low 0/0 0.00 KB 0.0 0.5 0.1 0.3 0.5 0.1 0.0 0.0 145.7 145.2 3.24 3.08 3 1.080 479K 1523 0.0 0.0 High 0/0 0.00 KB 0.0 0.0 0.0 0.0 0.8 0.8 0.0 0.0 0.0 222.1 3.68 3.35 13 0.283 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.0 total, 4.9 interval Flush(GB): cumulative 0.799, interval 0.799 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.26 GB write, 257.13 MB/s write, 0.46 GB read, 94.20 MB/s read, 6.9 seconds Interval compaction: 1.26 GB write, 263.58 MB/s write, 0.46 GB read, 96.56 MB/s read, 6.9 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: 0, pending-compaction-bytes-delays: 0, pending-compaction-bytes-stops: 0, total-delays: 0, total-stops: 0, ** File Read Latency Histogram By Level [default] ** ** Level 0 read latency histogram (micros): Count: 142509 Average: 4.3769 StdDev: 20.92 Min: 1 Median: 3.3807 Max: 3318 Percentiles: P50: 3.38 P75: 4.61 P99: 12.04 P99.9: 21.82 P99.99: 28.99 ------------------------------------------------------ [ 0, 1 ] 202 0.142% 0.142% ( 1, 2 ] 23672 16.611% 16.753% ### ( 2, 3 ] 31762 22.288% 39.040% #### ( 3, 4 ] 41027 28.789% 67.829% ###### ( 4, 6 ] 33737 23.674% 91.503% ##### ( 6, 10 ] 10053 7.054% 98.557% # ( 10, 15 ] 1549 1.087% 99.644% ( 15, 22 ] 375 0.263% 99.907% ( 22, 34 ] 204 0.143% 100.051% ( 34, 51 ] 16 0.011% 100.062% ( 76, 110 ] 1 0.001% 100.062% ( 250, 380 ] 8 0.006% 100.068% ( 380, 580 ] 10 0.007% 100.075% ( 580, 870 ] 1 0.001% 100.076% ( 870, 1300 ] 2 0.001% 100.077% ( 1300, 1900 ] 1 0.001% 100.078% ( 1900, 2900 ] 2 0.001% 100.079% ( 2900, 4400 ] 4 0.003% 100.082% ** Level 1 read latency histogram (micros): Count: 8847 Average: 6.2725 StdDev: 50.76 Min: 3 Median: 3.6530 Max: 3627 Percentiles: P50: 3.65 P75: 4.17 P99: 27.07 P99.9: 225.37 P99.99: 3072.95 ------------------------------------------------------ ( 2, 3 ] 536 6.059% 6.059% # ( 3, 4 ] 5953 67.288% 73.347% ############# ( 4, 6 ] 1753 19.815% 93.162% #### ( 6, 10 ] 272 3.074% 96.236% # ( 10, 15 ] 181 2.046% 98.282% ( 15, 22 ] 50 0.565% 98.847% ( 22, 34 ] 32 0.362% 99.209% ( 34, 51 ] 1 0.011% 99.220% ( 51, 76 ] 13 0.147% 99.367% ( 76, 110 ] 39 0.441% 99.808% ( 110, 170 ] 4 0.045% 99.853% ( 170, 250 ] 6 0.068% 99.921% ( 250, 380 ] 3 0.034% 99.955% ( 380, 580 ] 1 0.011% 99.966% ( 870, 1300 ] 1 0.011% 99.977% ( 1900, 2900 ] 1 0.011% 99.989% ( 2900, 4400 ] 1 0.011% 100.000% ** Level 2 read latency histogram (micros): Count: 31402 Average: 4.5551 StdDev: 26.21 Min: 1 Median: 3.2012 Max: 3009 Percentiles: P50: 3.20 P75: 3.85 P99: 15.55 P99.9: 105.83 P99.99: 856.45 ------------------------------------------------------ [ 0, 1 ] 54 0.172% 0.172% ( 1, 2 ] 5145 16.384% 16.556% ### ( 2, 3 ] 8073 25.709% 42.265% ##### ( 3, 4 ] 12072 38.443% 80.708% ######## ( 4, 6 ] 4207 13.397% 94.105% ### ( 6, 10 ] 1134 3.611% 97.717% # ( 10, 15 ] 391 1.245% 98.962% ( 15, 22 ] 153 0.487% 99.449% ( 22, 34 ] 89 0.283% 99.733% ( 34, 51 ] 7 0.022% 99.755% ( 51, 76 ] 7 0.022% 99.777% ( 76, 110 ] 44 0.140% 99.917% ( 110, 170 ] 6 0.019% 99.936% ( 170, 250 ] 6 0.019% 99.955% ( 250, 380 ] 6 0.019% 99.975% ( 380, 580 ] 2 0.006% 99.981% ( 580, 870 ] 3 0.010% 99.990% ( 1300, 1900 ] 1 0.003% 99.994% ( 1900, 2900 ] 1 0.003% 99.997% ( 2900, 4400 ] 1 0.003% 100.000% ** Level 3 read latency histogram (micros): Count: 70181 Average: 7.3666 StdDev: 141.43 Min: 1 Median: 2.2592 Max: 11871 Percentiles: P50: 2.26 P75: 2.85 P99: 12.74 P99.9: 555.76 P99.99: 8567.99 ------------------------------------------------------ [ 0, 1 ] 365 0.520% 0.520% ( 1, 2 ] 27033 38.519% 39.039% ######## ( 2, 3 ] 29680 42.291% 81.330% ######## ( 3, 4 ] 4453 6.345% 87.675% # ( 4, 6 ] 5027 7.163% 94.838% # ( 6, 10 ] 2656 3.785% 98.622% # ( 10, 15 ] 485 0.691% 99.313% ( 15, 22 ] 79 0.113% 99.426% ( 22, 34 ] 10 0.014% 99.440% ( 34, 51 ] 1 0.001% 99.441% ( 76, 110 ] 31 0.044% 99.486% ( 110, 170 ] 202 0.288% 99.773% ( 170, 250 ] 53 0.076% 99.849% ( 250, 380 ] 21 0.030% 99.879% ( 380, 580 ] 18 0.026% 99.905% ( 580, 870 ] 9 0.013% 99.917% ( 870, 1300 ] 4 0.006% 99.923% ( 1300, 1900 ] 5 0.007% 99.930% ( 1900, 2900 ] 21 0.030% 99.960% ( 2900, 4400 ] 13 0.019% 99.979% ( 4400, 6600 ] 6 0.009% 99.987% ( 6600, 9900 ] 5 0.007% 99.994% ( 9900, 14000 ] 5 0.007% 100.001% ** DB Stats ** Uptime(secs): 5.0 total, 4.9 interval Cumulative writes: 0 writes, 1000K keys, 0 commit groups, 0.0 writes per commit group, ingest: 0.96 GB, 196.41 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.000 H:M:S, 0.0 percent Interval writes: 0 writes, 1000K keys, 0 commit groups, 0.0 writes per commit group, ingest: 984.19 MB, 201.30 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: 2 num-running-flushes: 1 2024/07/25-16:04:26 ... thread 0: (1000000,2000000) ops and (116250.4,148292.9) ops/second in (8.602122,13.486818) 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 14/12 1.47 GB 31.3 0.7 0.0 0.7 2.6 1.8 0.0 1.4 55.8 194.9 13.54 12.64 32 0.423 766K 3636 0.0 0.0 L1 1/1 16.70 MB 0.0 0.3 0.3 0.0 0.3 0.3 0.1 1.0 98.1 97.5 3.21 3.11 1 3.206 319K 2032 0.0 0.0 L2 0/0 0.00 KB 0.0 0.2 0.1 0.1 0.2 0.1 0.3 2.5 100.2 100.0 1.60 1.53 1 1.604 163K 335 0.0 0.0 L3 21/0 970.75 MB 1.0 1.5 0.4 1.0 1.5 0.4 0.0 3.3 177.9 177.4 8.47 7.79 5 1.693 1529K 4046 0.0 0.0 L4 159/0 9.71 GB 1.0 0.2 0.2 0.0 0.2 0.2 0.3 1.0 170.8 170.8 0.99 0.93 2 0.494 171K 0 0.0 0.0 L5 144/0 8.92 GB 0.1 0.0 0.0 0.0 0.0 0.0 0.2 0.0 0.0 0.0 0.00 0.00 0 0.000 0 0 0.0 0.0 Sum 339/13 21.06 GB 0.0 2.8 1.0 1.9 4.7 2.8 0.9 2.5 104.5 172.0 27.81 25.99 41 0.678 2950K 10K 0.0 0.0 Int 0/0 0.00 KB 0.0 2.8 1.0 1.9 4.7 2.8 0.9 2.5 104.5 172.0 27.81 25.99 41 0.678 2950K 10K 0.0 0.0 ** Compaction Stats [default] ** Priority Files Size Score Read(GB) Rn(GB) Rnp1(GB) Write(GB) Wnew(GB) Moved(GB) W-Amp Rd(MB/s) Wr(MB/s) Comp(sec) CompMergeCPU(sec) Comp(cnt) Avg(sec) KeyIn KeyDrop Rblob(GB) Wblob(GB) --------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------- Low 0/0 0.00 KB 0.0 2.8 1.0 1.9 2.8 1.0 0.0 0.0 152.6 152.1 19.03 18.00 12 1.586 2950K 10K 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 215.1 8.77 7.99 29 0.302 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.6 total, 13.5 interval Flush(GB): cumulative 1.843, interval 1.843 AddFile(GB): cumulative 0.000, interval 0.000 AddFile(Total Files): cumulative 0, interval 0 AddFile(L0 Files): cumulative 0, interval 0 AddFile(Keys): cumulative 0, interval 0 Cumulative compaction: 4.67 GB write, 351.30 MB/s write, 2.84 GB read, 213.41 MB/s read, 27.8 seconds Interval compaction: 4.67 GB write, 354.49 MB/s write, 2.84 GB read, 215.35 MB/s read, 27.8 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: 4, pending-compaction-bytes-delays: 0, pending-compaction-bytes-stops: 0, total-delays: 0, total-stops: 4, interval: 4 total count ** File Read Latency Histogram By Level [default] ** ** Level 0 read latency histogram (micros): Count: 456124 Average: 4.7018 StdDev: 18.46 Min: 1 Median: 3.6610 Max: 4196 Percentiles: P50: 3.66 P75: 4.97 P99: 13.73 P99.9: 30.33 P99.99: 139.66 ------------------------------------------------------ [ 0, 1 ] 319 0.070% 0.070% ( 1, 2 ] 54796 12.013% 12.083% ## ( 2, 3 ] 89979 19.727% 31.810% #### ( 3, 4 ] 125517 27.518% 59.328% ###### ( 4, 6 ] 147184 32.268% 91.597% ###### ( 6, 10 ] 29305 6.425% 98.022% # ( 10, 15 ] 5993 1.314% 99.335% ( 15, 22 ] 2106 0.462% 99.797% ( 22, 34 ] 680 0.149% 99.946% ( 34, 51 ] 51 0.011% 99.957% ( 51, 76 ] 9 0.002% 99.959% ( 76, 110 ] 44 0.010% 99.969% ( 110, 170 ] 199 0.044% 100.013% ( 170, 250 ] 75 0.016% 100.029% ( 250, 380 ] 45 0.010% 100.039% ( 380, 580 ] 46 0.010% 100.049% ( 580, 870 ] 8 0.002% 100.051% ( 870, 1300 ] 6 0.001% 100.052% ( 1300, 1900 ] 3 0.001% 100.053% ( 1900, 2900 ] 5 0.001% 100.054% ( 2900, 4400 ] 7 0.002% 100.055% ** Level 1 read latency histogram (micros): Count: 83948 Average: 4.1368 StdDev: 22.53 Min: 1 Median: 2.6709 Max: 3627 Percentiles: P50: 2.67 P75: 3.47 P99: 12.46 P99.9: 108.53 P99.99: 393.45 ------------------------------------------------------ [ 0, 1 ] 31 0.037% 0.037% ( 1, 2 ] 13782 16.417% 16.454% ### ( 2, 3 ] 41973 49.999% 66.453% ########## ( 3, 4 ] 15157 18.055% 84.508% #### ( 4, 6 ] 8389 9.993% 94.501% ## ( 6, 10 ] 3381 4.027% 98.529% # ( 10, 15 ] 803 0.957% 99.485% ( 15, 22 ] 242 0.288% 99.774% ( 22, 34 ] 47 0.056% 99.830% ( 34, 51 ] 2 0.002% 99.832% ( 51, 76 ] 14 0.017% 99.849% ( 76, 110 ] 45 0.054% 99.902% ( 110, 170 ] 31 0.037% 99.939% ( 170, 250 ] 22 0.026% 99.965% ( 250, 380 ] 20 0.024% 99.989% ( 380, 580 ] 9 0.011% 100.000% ( 580, 870 ] 8 0.010% 100.010% ( 870, 1300 ] 7 0.008% 100.018% ( 1300, 1900 ] 1 0.001% 100.019% ( 1900, 2900 ] 1 0.001% 100.020% ( 2900, 4400 ] 1 0.001% 100.021% ** Level 2 read latency histogram (micros): Count: 56745 Average: 3.7178 StdDev: 20.51 Min: 1 Median: 2.3408 Max: 3009 Percentiles: P50: 2.34 P75: 3.45 P99: 12.56 P99.9: 104.02 P99.99: 714.88 ------------------------------------------------------ [ 0, 1 ] 736 1.297% 1.297% ( 1, 2 ] 23199 40.883% 42.180% ######## ( 2, 3 ] 13019 22.943% 65.123% ##### ( 3, 4 ] 12498 22.025% 87.148% #### ( 4, 6 ] 4851 8.549% 95.697% ## ( 6, 10 ] 1651 2.910% 98.606% # ( 10, 15 ] 437 0.770% 99.376% ( 15, 22 ] 157 0.277% 99.653% ( 22, 34 ] 90 0.159% 99.811% ( 34, 51 ] 7 0.012% 99.824% ( 51, 76 ] 7 0.012% 99.836% ( 76, 110 ] 44 0.078% 99.914% ( 110, 170 ] 15 0.026% 99.940% ( 170, 250 ] 11 0.019% 99.959% ( 250, 380 ] 9 0.016% 99.975% ( 380, 580 ] 6 0.011% 99.986% ( 580, 870 ] 5 0.009% 99.995% ( 1300, 1900 ] 1 0.002% 99.996% ( 1900, 2900 ] 1 0.002% 99.998% ( 2900, 4400 ] 1 0.002% 100.000% ** Level 3 read latency histogram (micros): Count: 293640 Average: 4.4510 StdDev: 70.48 Min: 1 Median: 2.0304 Max: 11871 Percentiles: P50: 2.03 P75: 2.71 P99: 9.96 P99.9: 208.67 P99.99: 2247.09 ------------------------------------------------------ [ 0, 1 ] 3521 1.199% 1.199% ( 1, 2 ] 140025 47.686% 48.885% ########## ( 2, 3 ] 107664 36.665% 85.550% ####### ( 3, 4 ] 13958 4.753% 90.304% # ( 4, 6 ] 16977 5.782% 96.085% # ( 6, 10 ] 8637 2.941% 99.027% # ( 10, 15 ] 1409 0.480% 99.507% ( 15, 22 ] 199 0.068% 99.574% ( 22, 34 ] 27 0.009% 99.584% ( 34, 51 ] 3 0.001% 99.585% ( 51, 76 ] 1 0.000% 99.585% ( 76, 110 ] 264 0.090% 99.675% ( 110, 170 ] 599 0.204% 99.879% ( 170, 250 ] 129 0.044% 99.923% ( 250, 380 ] 82 0.028% 99.951% ( 380, 580 ] 63 0.021% 99.972% ( 580, 870 ] 32 0.011% 99.983% ( 870, 1300 ] 8 0.003% 99.986% ( 1300, 1900 ] 5 0.002% 99.987% ( 1900, 2900 ] 22 0.007% 99.995% ( 2900, 4400 ] 13 0.004% 99.999% ( 4400, 6600 ] 6 0.002% 100.001% ( 6600, 9900 ] 5 0.002% 100.003% ( 9900, 14000 ] 5 0.002% 100.005% ** Level 4 read latency histogram (micros): Count: 35 Average: 80.4000 StdDev: 211.10 Min: 1 Median: 3.5000 Max: 1193 Percentiles: P50: 3.50 P75: 29.50 P99: 1149.50 P99.9: 1193.00 P99.99: 1193.00 ------------------------------------------------------ [ 0, 1 ] 6 17.143% 17.143% ### ( 1, 2 ] 6 17.143% 34.286% ### ( 2, 3 ] 4 11.429% 45.714% ## ( 3, 4 ] 3 8.571% 54.286% ## ( 4, 6 ] 3 8.571% 62.857% ## ( 6, 10 ] 2 5.714% 68.571% # ( 10, 15 ] 1 2.857% 71.429% # ( 22, 34 ] 2 5.714% 77.143% # ( 110, 170 ] 4 11.429% 88.571% ## ( 170, 250 ] 1 2.857% 91.429% # ( 250, 380 ] 2 5.714% 97.143% # ( 870, 1300 ] 1 2.857% 100.000% # ** Level 5 read latency histogram (micros): Count: 20 Average: 1009.8000 StdDev: 1105.39 Min: 125 Median: 380.0000 Max: 3929 Percentiles: P50: 380.00 P75: 1700.00 P99: 3929.00 P99.9: 3929.00 P99.99: 3929.00 ------------------------------------------------------ ( 110, 170 ] 5 25.000% 25.000% ##### ( 170, 250 ] 4 20.000% 45.000% #### ( 250, 380 ] 1 5.000% 50.000% # ( 580, 870 ] 2 10.000% 60.000% ## ( 870, 1300 ] 1 5.000% 65.000% # ( 1300, 1900 ] 3 15.000% 80.000% ### ( 1900, 2900 ] 2 10.000% 90.000% ## ( 2900, 4400 ] 2 10.000% 100.000% ## ** DB Stats ** Uptime(secs): 13.6 total, 8.6 interval Cumulative writes: 0 writes, 2000K keys, 0 commit groups, 0.0 writes per commit group, ingest: 1.92 GB, 144.59 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.518 H:M:S, 3.8 percent Interval writes: 0 writes, 1000K keys, 0 commit groups, 0.0 writes per commit group, ingest: 984.19 MB, 114.40 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.518 H:M:S, 6.0 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] overwrite : 7.209 micros/op 138714 ops/sec 20.005 seconds 2774999 operations; 134.4 MB/s Microseconds per write: Count: 2774999 Average: 7.2090 StdDev: 301.46 Min: 1 Median: 5.2907 Max: 501170 Percentiles: P50: 5.29 P75: 8.38 P99: 20.64 P99.9: 173.92 P99.99: 371.33 ------------------------------------------------------ [ 0, 1 ] 1 0.000% 0.000% ( 1, 2 ] 32759 1.181% 1.181% ( 2, 3 ] 404796 14.587% 15.768% ### ( 3, 4 ] 316126 11.392% 27.160% ## ( 4, 6 ] 982164 35.393% 62.553% ####### ( 6, 10 ] 581455 20.953% 83.506% #### ( 10, 15 ] 376231 13.558% 97.064% ### ( 15, 22 ] 66728 2.405% 99.469% ( 22, 34 ] 9926 0.358% 99.827% ( 34, 51 ] 1673 0.060% 99.887% ( 51, 76 ] 249 0.009% 99.896% ( 76, 110 ] 63 0.002% 99.898% ( 110, 170 ] 4 0.000% 99.898% ( 170, 250 ] 1000 0.036% 99.934% ( 250, 380 ] 1657 0.060% 99.994% ( 380, 580 ] 140 0.005% 99.999% ( 580, 870 ] 21 0.001% 100.000% ( 870, 1300 ] 2 0.000% 100.000% ( 1300, 1900 ] 1 0.000% 100.000% ( 14000, 22000 ] 2 0.000% 100.000% ( 380000, 570000 ] 1 0.000% 100.000% STATISTICS: rocksdb.block.cache.miss COUNT : 0 rocksdb.block.cache.hit COUNT : 0 rocksdb.block.cache.add COUNT : 0 rocksdb.block.cache.add.failures COUNT : 0 rocksdb.block.cache.index.miss COUNT : 0 rocksdb.block.cache.index.hit COUNT : 0 rocksdb.block.cache.index.add COUNT : 0 rocksdb.block.cache.index.bytes.insert COUNT : 0 rocksdb.block.cache.filter.miss COUNT : 0 rocksdb.block.cache.filter.hit COUNT : 0 rocksdb.block.cache.filter.add COUNT : 0 rocksdb.block.cache.filter.bytes.insert COUNT : 0 rocksdb.block.cache.data.miss COUNT : 0 rocksdb.block.cache.data.hit COUNT : 0 rocksdb.block.cache.data.add COUNT : 0 rocksdb.block.cache.data.bytes.insert COUNT : 0 rocksdb.block.cache.bytes.read COUNT : 0 rocksdb.block.cache.bytes.write COUNT : 0 rocksdb.bloom.filter.useful COUNT : 0 rocksdb.bloom.filter.full.positive COUNT : 0 rocksdb.bloom.filter.full.true.positive COUNT : 0 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 : 0 rocksdb.memtable.miss COUNT : 0 rocksdb.l0.hit COUNT : 0 rocksdb.l1.hit COUNT : 0 rocksdb.l2andup.hit COUNT : 0 rocksdb.compaction.key.drop.new COUNT : 42206 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 : 2774999 rocksdb.number.keys.read COUNT : 0 rocksdb.number.keys.updated COUNT : 0 rocksdb.bytes.written COUNT : 2863798968 rocksdb.bytes.read COUNT : 0 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 : 125 rocksdb.no.file.errors COUNT : 0 rocksdb.stall.micros COUNT : 518024 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 : 5084740944 rocksdb.compact.write.bytes COUNT : 5040861015 rocksdb.flush.write.bytes COUNT : 2770598956 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 : 9 rocksdb.number.superversion_acquires COUNT : 0 rocksdb.number.superversion_releases COUNT : 0 rocksdb.number.superversion_cleanups COUNT : 0 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 : 72 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 : 2752075776 rocksdb.memtable.garbage.bytes.at.flush COUNT : 4555776 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 : 5043667953 rocksdb.non.last.level.read.count COUNT : 1216299 rocksdb.block.checksum.compute.count COUNT : 1232060 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 : 0.000000 P95 : 0.000000 P99 : 0.000000 P100 : 0.000000 COUNT : 0 SUM : 0 rocksdb.db.write.micros P50 : 4.028416 P95 : 12.735254 P99 : 17.678278 P100 : 501167.000000 COUNT : 2774999 SUM : 16553731 rocksdb.compaction.times.micros P50 : 1500000.000000 P95 : 10094997.000000 P99 : 10094997.000000 P100 : 10094997.000000 COUNT : 16 SUM : 33000346 rocksdb.compaction.times.cpu_micros P50 : 1200000.000000 P95 : 7160000.000000 P99 : 9272000.000000 P100 : 9711243.000000 COUNT : 16 SUM : 31512595 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 : 600.714286 P95 : 1562.000000 P99 : 1562.000000 P100 : 1562.000000 COUNT : 41 SUM : 33526 rocksdb.compaction.outfile.sync.micros P50 : 801.764706 P95 : 1276.545455 P99 : 1430.000000 P100 : 1430.000000 COUNT : 64 SUM : 53209 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 : 130.400000 P95 : 286.833333 P99 : 454.000000 P100 : 485.000000 COUNT : 63 SUM : 9297 rocksdb.table.open.io.micros P50 : 1804.545455 P95 : 17750.000000 P99 : 24476.000000 P100 : 24476.000000 COUNT : 125 SUM : 492853 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 : 5.769699 P95 : 11.626852 P99 : 20.267156 P100 : 8059.000000 COUNT : 1231823 SUM : 9182764 rocksdb.read.block.get.micros P50 : 684.102564 P95 : 5500.000000 P99 : 11950.000000 P100 : 12022.000000 COUNT : 250 SUM : 365411 rocksdb.write.raw.block.micros P50 : 0.738746 P95 : 2.199960 P99 : 8.323834 P100 : 7883.000000 COUNT : 1906192 SUM : 5623587 rocksdb.numfiles.in.singlecompaction P50 : 3.333333 P95 : 14.500000 P99 : 16.000000 P100 : 16.000000 COUNT : 22 SUM : 100 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 : 22000.000000 P95 : 500848.000000 P99 : 500848.000000 P100 : 500848.000000 COUNT : 2 SUM : 518024 rocksdb.sst.read.micros P50 : 2.799931 P95 : 6.858959 P99 : 12.982298 P100 : 11871.000000 COUNT : 1216531 SUM : 5354406 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 : 0.000000 P95 : 0.000000 P99 : 0.000000 P100 : 0.000000 COUNT : 0 SUM : 0 rocksdb.bytes.per.write P50 : 1032.000000 P95 : 1032.000000 P99 : 1032.000000 P100 : 1032.000000 COUNT : 2774999 SUM : 2863798968 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 : 238333.333333 P95 : 379566.666667 P99 : 703945.000000 P100 : 703945.000000 COUNT : 41 SUM : 11567976 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 : 72 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