[2024-11-06 14:19:36.445025] Starting SPDK v25.01-pre git sha1 32c6c4b3a / DPDK 24.03.0 initialization... [2024-11-06 14:19:36.445301] [ 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_pid769261 ] Set seed to 1730899176438285 because --seed was 0 EAL: No free 2048 kB hugepages reported on node 1 [2024-11-06 14:19:36.570240] app.c: 919:spdk_app_start: *NOTICE*: Total cores available: 1 [2024-11-06 14:19:36.682403] app.c: 612:app_setup_trace: *NOTICE*: Tracepoint Group Mask 0x80 specified. [2024-11-06 14:19:36.682446] app.c: 613:app_setup_trace: *NOTICE*: Use 'spdk_trace -s rocksdb -p 769261' to capture a snapshot of events at runtime. [2024-11-06 14:19:36.682456] app.c: 618:app_setup_trace: *NOTICE*: 'spdk_trace' without parameters will also work if this is the only [2024-11-06 14:19:36.682466] app.c: 619:app_setup_trace: *NOTICE*: SPDK application currently running. [2024-11-06 14:19:36.682474] app.c: 620:app_setup_trace: *NOTICE*: Or copy /dev/shm/rocksdb_trace.pid769261 for offline analysis/debug. [2024-11-06 14:19:36.683692] reactor.c:1005:reactor_run: *NOTICE*: Reactor started on core 0 RocksDB: version 8.1.1 Date: Wed Nov 6 14:19:42 2024 CPU: 96 * Intel(R) Xeon(R) Gold 6252 CPU @ 2.10GHz CPUCache: 36608 KB 2024/11/06-14:19:47 ... thread 0: (1000000,1000000) ops and (210418.1,210418.1) ops/second in (4.752442,4.752442) seconds ** Compaction Stats [default] ** Level Files Size Score Read(GB) Rn(GB) Rnp1(GB) Write(GB) Wnew(GB) Moved(GB) W-Amp Rd(MB/s) Wr(MB/s) Comp(sec) CompMergeCPU(sec) Comp(cnt) Avg(sec) KeyIn KeyDrop Rblob(GB) Wblob(GB) ------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------ L0 18/13 3.06 GB 87.7 0.7 0.0 0.7 1.6 0.9 0.0 1.9 130.1 281.2 5.80 5.19 17 0.341 766K 3746 0.0 0.0 L1 3/3 176.60 MB 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 17/0 889.81 MB 0.9 0.2 0.1 0.1 0.2 0.1 0.0 1.8 170.5 168.4 1.35 1.09 1 1.348 130K 0 0.0 0.0 L4 127/0 9.67 GB 1.0 0.7 0.4 0.3 0.6 0.4 0.1 1.7 179.2 176.9 3.74 3.14 3 1.245 408K 0 0.0 0.0 L5 132/0 10.83 GB 0.1 0.0 0.0 0.0 0.0 0.0 0.3 0.0 0.0 0.0 0.00 0.00 0 0.000 0 0 0.0 0.0 Sum 297/16 24.59 GB 0.0 1.6 0.5 1.1 2.5 1.3 0.4 2.9 151.9 231.4 10.89 9.42 21 0.518 1306K 3746 0.0 0.0 Int 0/0 0.00 KB 0.0 1.6 0.5 1.1 2.5 1.3 0.4 2.9 151.9 231.4 10.89 9.42 21 0.518 1306K 3746 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.6 0.5 1.1 1.6 0.5 0.0 0.0 208.6 206.6 7.93 7.02 7 1.133 1306K 3746 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 298.0 2.96 2.40 14 0.211 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.8 total, 4.8 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.46 GB write, 519.70 MB/s write, 1.62 GB read, 341.20 MB/s read, 10.9 seconds Interval compaction: 2.46 GB write, 529.93 MB/s write, 1.62 GB read, 347.91 MB/s read, 10.9 seconds Write Stall (count): cf-l0-file-count-limit-delays-with-ongoing-compaction: 6, cf-l0-file-count-limit-stops-with-ongoing-compaction: 0, l0-file-count-limit-delays: 6, 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: 6, total-stops: 0, interval: 6 total count ** File Read Latency Histogram By Level [default] ** ** Level 0 read latency histogram (micros): Count: 225672 Average: 36.0427 StdDev: 158.20 Min: 1 Median: 1.5631 Max: 12141 Percentiles: P50: 1.56 P75: 2.17 P99: 515.03 P99.9: 1181.44 P99.99: 1744.55 ------------------------------------------------------ [ 0, 1 ] 44289 19.625% 19.625% #### ( 1, 2 ] 121730 53.941% 73.567% ########### ( 2, 3 ] 18887 8.369% 81.936% ## ( 3, 4 ] 5398 2.392% 84.328% ( 4, 6 ] 4149 1.839% 86.166% ( 6, 10 ] 919 0.407% 86.573% ( 10, 15 ] 258 0.114% 86.688% ( 15, 22 ] 22 0.010% 86.698% ( 22, 34 ] 4 0.002% 86.699% ( 34, 51 ] 2 0.001% 86.700% ( 51, 76 ] 1 0.000% 86.701% ( 76, 110 ] 956 0.424% 87.124% ( 110, 170 ] 12157 5.387% 92.511% # ( 170, 250 ] 9693 4.295% 96.806% # ( 250, 380 ] 3417 1.514% 98.321% ( 380, 580 ] 2271 1.006% 99.327% ( 580, 870 ] 1042 0.462% 99.789% ( 870, 1300 ] 347 0.154% 99.942% ( 1300, 1900 ] 145 0.064% 100.007% ( 1900, 2900 ] 62 0.027% 100.034% ( 2900, 4400 ] 29 0.013% 100.047% ( 4400, 6600 ] 11 0.005% 100.052% ( 6600, 9900 ] 24 0.011% 100.062% ( 9900, 14000 ] 4 0.002% 100.064% ** Level 1 read latency histogram (micros): Count: 475 Average: 441.5074 StdDev: 1204.81 Min: 86 Median: 185.0355 Max: 10504 Percentiles: P50: 185.04 P75: 261.28 P99: 8132.14 P99.9: 10504.00 P99.99: 10504.00 ------------------------------------------------------ ( 76, 110 ] 14 2.947% 2.947% # ( 110, 170 ] 197 41.474% 44.421% ######## ( 170, 250 ] 141 29.684% 74.105% ###### ( 250, 380 ] 49 10.316% 84.421% ## ( 380, 580 ] 32 6.737% 91.158% # ( 580, 870 ] 22 4.632% 95.789% # ( 870, 1300 ] 3 0.632% 96.421% ( 1300, 1900 ] 3 0.632% 97.053% ( 2900, 4400 ] 1 0.211% 97.263% ( 4400, 6600 ] 5 1.053% 98.316% ( 6600, 9900 ] 7 1.474% 99.789% ( 9900, 14000 ] 1 0.211% 100.000% ** Level 3 read latency histogram (micros): Count: 61159 Average: 15.3314 StdDev: 92.93 Min: 1 Median: 1.7460 Max: 4251 Percentiles: P50: 1.75 P75: 2.52 P99: 327.60 P99.9: 1280.02 P99.99: 2764.53 ------------------------------------------------------ [ 0, 1 ] 3809 6.228% 6.228% # ( 1, 2 ] 35886 58.677% 64.905% ############ ( 2, 3 ] 11801 19.296% 84.200% #### ( 3, 4 ] 3006 4.915% 89.115% # ( 4, 6 ] 2567 4.197% 93.313% # ( 6, 10 ] 1165 1.905% 95.217% ( 10, 15 ] 290 0.474% 95.692% ( 15, 22 ] 37 0.060% 95.752% ( 22, 34 ] 9 0.015% 95.767% ( 51, 76 ] 1 0.002% 95.768% ( 76, 110 ] 84 0.137% 95.906% ( 110, 170 ] 935 1.529% 97.435% ( 170, 250 ] 767 1.254% 98.689% ( 250, 380 ] 319 0.522% 99.210% ( 380, 580 ] 212 0.347% 99.557% ( 580, 870 ] 145 0.237% 99.794% ( 870, 1300 ] 68 0.111% 99.905% ( 1300, 1900 ] 32 0.052% 99.957% ( 1900, 2900 ] 23 0.038% 99.995% ( 2900, 4400 ] 5 0.008% 100.003% ** Level 4 read latency histogram (micros): Count: 70438 Average: 85.9317 StdDev: 226.32 Min: 0 Median: 2.3345 Max: 5440 Percentiles: P50: 2.33 P75: 134.74 P99: 829.44 P99.9: 3004.29 P99.99: 4192.93 ------------------------------------------------------ [ 0, 1 ] 3171 4.502% 4.502% # ( 1, 2 ] 28852 40.961% 45.463% ######## ( 2, 3 ] 9554 13.564% 59.026% ### ( 3, 4 ] 3109 4.414% 63.440% # ( 4, 6 ] 2533 3.596% 67.036% # ( 6, 10 ] 962 1.366% 68.402% ( 10, 15 ] 156 0.221% 68.623% ( 15, 22 ] 22 0.031% 68.655% ( 22, 34 ] 9 0.013% 68.667% ( 34, 51 ] 2 0.003% 68.670% ( 76, 110 ] 794 1.127% 69.798% ( 110, 170 ] 8886 12.615% 82.413% ### ( 170, 250 ] 6873 9.758% 92.170% ## ( 250, 380 ] 2402 3.410% 95.581% # ( 380, 580 ] 1736 2.465% 98.045% ( 580, 870 ] 782 1.110% 99.155% ( 870, 1300 ] 299 0.424% 99.580% ( 1300, 1900 ] 114 0.162% 99.742% ( 1900, 2900 ] 106 0.150% 99.892% ( 2900, 4400 ] 80 0.114% 100.006% ( 4400, 6600 ] 20 0.028% 100.034% ** Level 5 read latency histogram (micros): Count: 24145 Average: 264.7102 StdDev: 348.08 Min: 85 Median: 183.4948 Max: 7970 Percentiles: P50: 183.49 P75: 249.21 P99: 1745.17 P99.9: 4781.46 P99.99: 6488.86 ------------------------------------------------------ ( 76, 110 ] 883 3.657% 3.657% # ( 110, 170 ] 9950 41.209% 44.866% ######## ( 170, 250 ] 7348 30.433% 75.299% ###### ( 250, 380 ] 2679 11.095% 86.395% ## ( 380, 580 ] 1832 7.587% 93.982% ## ( 580, 870 ] 845 3.500% 97.482% # ( 870, 1300 ] 279 1.156% 98.637% ( 1300, 1900 ] 118 0.489% 99.126% ( 1900, 2900 ] 92 0.381% 99.507% ( 2900, 4400 ] 90 0.373% 99.880% ( 4400, 6600 ] 28 0.116% 99.996% ( 6600, 9900 ] 1 0.004% 100.000% ** DB Stats ** Uptime(secs): 4.8 total, 4.8 interval Cumulative writes: 0 writes, 1000K keys, 0 commit groups, 0.0 writes per commit group, ingest: 0.96 GB, 202.98 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.302 H:M:S, 26.8 percent Interval writes: 0 writes, 1000K keys, 0 commit groups, 0.0 writes per commit group, ingest: 984.19 MB, 206.93 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.302 H:M:S, 27.4 percent Write Stall (count): write-buffer-manager-limit-stops: 0, num-running-compactions: 1 num-running-flushes: 1 2024/11/06-14:19:57 ... thread 0: (1000000,2000000) ops and (102433.8,137789.9) ops/second in (9.762406,14.514848) seconds ** Compaction Stats [default] ** Level Files Size Score Read(GB) Rn(GB) Rnp1(GB) Write(GB) Wnew(GB) Moved(GB) W-Amp Rd(MB/s) Wr(MB/s) Comp(sec) CompMergeCPU(sec) Comp(cnt) Avg(sec) KeyIn KeyDrop Rblob(GB) Wblob(GB) ------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------ L0 18/17 4.02 GB 104.3 2.8 0.0 2.8 4.6 1.8 0.0 2.5 168.5 279.3 16.77 15.51 38 0.441 2869K 30K 0.0 0.0 L1 3/3 176.60 MB 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 17/0 889.81 MB 0.9 0.2 0.1 0.1 0.2 0.1 0.0 1.8 170.5 168.4 1.35 1.09 1 1.348 130K 0 0.0 0.0 L4 127/0 9.67 GB 1.0 0.7 0.4 0.3 0.6 0.4 0.1 1.7 179.2 176.9 3.74 3.14 3 1.245 408K 0 0.0 0.0 L5 132/0 10.83 GB 0.1 0.0 0.0 0.0 0.0 0.0 0.3 0.0 0.0 0.0 0.00 0.00 0 0.000 0 0 0.0 0.0 Sum 297/20 25.55 GB 0.0 3.6 0.5 3.1 5.4 2.3 0.4 3.0 170.5 254.9 21.85 19.74 42 0.520 3409K 30K 0.0 0.0 Int 0/0 0.00 KB 0.0 3.6 0.5 3.1 5.4 2.3 0.4 3.0 170.5 254.9 21.85 19.74 42 0.520 3409K 30K 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.6 0.5 3.1 3.6 0.5 0.0 0.0 237.6 234.9 15.68 14.62 12 1.307 3409K 30K 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 305.7 6.17 5.12 30 0.206 0 0 0.0 0.0 Blob file count: 0, total size: 0.0 GB, garbage size: 0.0 GB, space amp: 0.0 Uptime(secs): 14.6 total, 14.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: 5.44 GB write, 381.26 MB/s write, 3.64 GB read, 254.97 MB/s read, 21.9 seconds Interval compaction: 5.44 GB write, 383.71 MB/s write, 3.64 GB read, 256.61 MB/s read, 21.9 seconds Write Stall (count): cf-l0-file-count-limit-delays-with-ongoing-compaction: 19, cf-l0-file-count-limit-stops-with-ongoing-compaction: 0, l0-file-count-limit-delays: 19, 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: 19, total-stops: 0, interval: 19 total count ** File Read Latency Histogram By Level [default] ** ** Level 0 read latency histogram (micros): Count: 867844 Average: 29.4808 StdDev: 104.73 Min: 0 Median: 1.4848 Max: 12141 Percentiles: P50: 1.48 P75: 1.97 P99: 366.40 P99.9: 768.99 P99.99: 968.85 ------------------------------------------------------ [ 0, 1 ] 215400 24.820% 24.820% ##### ( 1, 2 ] 450757 51.940% 76.760% ########## ( 2, 3 ] 54804 6.315% 83.075% # ( 3, 4 ] 16574 1.910% 84.985% ( 4, 6 ] 11283 1.300% 86.285% ( 6, 10 ] 2372 0.273% 86.558% ( 10, 15 ] 675 0.078% 86.636% ( 15, 22 ] 87 0.010% 86.646% ( 22, 34 ] 9 0.001% 86.647% ( 34, 51 ] 3 0.000% 86.647% ( 51, 76 ] 4 0.000% 86.648% ( 76, 110 ] 4331 0.499% 87.147% ( 110, 170 ] 56486 6.509% 93.656% # ( 170, 250 ] 38521 4.439% 98.094% # ( 250, 380 ] 8779 1.012% 99.106% ( 380, 580 ] 5624 0.648% 99.754% ( 580, 870 ] 1946 0.224% 99.978% ( 870, 1300 ] 449 0.052% 100.030% ( 1300, 1900 ] 163 0.019% 100.049% ( 1900, 2900 ] 97 0.011% 100.060% ( 2900, 4400 ] 50 0.006% 100.066% ( 4400, 6600 ] 11 0.001% 100.067% ( 6600, 9900 ] 24 0.003% 100.070% ( 9900, 14000 ] 4 0.000% 100.070% ** Level 1 read latency histogram (micros): Count: 1831 Average: 251.7488 StdDev: 630.11 Min: 86 Median: 164.7640 Max: 10504 Percentiles: P50: 164.76 P75: 221.90 P99: 1280.96 P99.9: 9508.24 P99.99: 10504.00 ------------------------------------------------------ ( 76, 110 ] 84 4.588% 4.588% # ( 110, 170 ] 911 49.754% 54.342% ########## ( 170, 250 ] 583 31.841% 86.182% ###### ( 250, 380 ] 115 6.281% 92.463% # ( 380, 580 ] 77 4.205% 96.668% # ( 580, 870 ] 36 1.966% 98.635% ( 870, 1300 ] 7 0.382% 99.017% ( 1300, 1900 ] 4 0.218% 99.235% ( 2900, 4400 ] 1 0.055% 99.290% ( 4400, 6600 ] 5 0.273% 99.563% ( 6600, 9900 ] 7 0.382% 99.945% ( 9900, 14000 ] 1 0.055% 100.000% ** Level 3 read latency histogram (micros): Count: 68516 Average: 32.7095 StdDev: 106.55 Min: 1 Median: 1.8480 Max: 4251 Percentiles: P50: 1.85 P75: 2.98 P99: 428.50 P99.9: 1247.54 P99.99: 2739.52 ------------------------------------------------------ [ 0, 1 ] 3810 5.561% 5.561% # ( 1, 2 ] 35907 52.407% 57.967% ########## ( 2, 3 ] 11890 17.354% 75.321% ### ( 3, 4 ] 3071 4.482% 79.803% # ( 4, 6 ] 2645 3.860% 83.664% # ( 6, 10 ] 1174 1.713% 85.377% ( 10, 15 ] 295 0.431% 85.808% ( 15, 22 ] 38 0.055% 85.863% ( 22, 34 ] 9 0.013% 85.876% ( 51, 76 ] 1 0.001% 85.878% ( 76, 110 ] 478 0.698% 86.575% ( 110, 170 ] 4705 6.867% 93.442% # ( 170, 250 ] 2989 4.362% 97.805% # ( 250, 380 ] 717 1.046% 98.851% ( 380, 580 ] 420 0.613% 99.464% ( 580, 870 ] 230 0.336% 99.800% ( 870, 1300 ] 78 0.114% 99.914% ( 1300, 1900 ] 32 0.047% 99.961% ( 1900, 2900 ] 24 0.035% 99.996% ( 2900, 4400 ] 5 0.007% 100.003% ** Level 4 read latency histogram (micros): Count: 133048 Average: 131.0387 StdDev: 187.52 Min: 0 Median: 128.4555 Max: 5440 Percentiles: P50: 128.46 P75: 184.53 P99: 741.74 P99.9: 2474.43 P99.99: 4087.26 ------------------------------------------------------ [ 0, 1 ] 3171 2.383% 2.383% ( 1, 2 ] 29013 21.806% 24.190% #### ( 2, 3 ] 10054 7.557% 31.746% ## ( 3, 4 ] 3465 2.604% 34.351% # ( 4, 6 ] 3010 2.262% 36.613% ( 6, 10 ] 1001 0.752% 37.365% ( 10, 15 ] 178 0.134% 37.499% ( 15, 22 ] 27 0.020% 37.520% ( 22, 34 ] 9 0.007% 37.526% ( 34, 51 ] 2 0.002% 37.528% ( 76, 110 ] 3904 2.934% 40.462% # ( 110, 170 ] 41256 31.008% 71.470% ###### ( 170, 250 ] 25855 19.433% 90.903% #### ( 250, 380 ] 6014 4.520% 95.423% # ( 380, 580 ] 3971 2.985% 98.408% # ( 580, 870 ] 1412 1.061% 99.469% ( 870, 1300 ] 372 0.280% 99.749% ( 1300, 1900 ] 128 0.096% 99.845% ( 1900, 2900 ] 127 0.095% 99.941% ( 2900, 4400 ] 83 0.062% 100.003% ( 4400, 6600 ] 20 0.015% 100.018% ** Level 5 read latency histogram (micros): Count: 89466 Average: 207.1165 StdDev: 204.84 Min: 81 Median: 164.5730 Max: 7970 Percentiles: P50: 164.57 P75: 222.93 P99: 836.22 P99.9: 3435.12 P99.99: 5975.62 ------------------------------------------------------ ( 76, 110 ] 4238 4.737% 4.737% # ( 110, 170 ] 44522 49.764% 54.501% ########## ( 170, 250 ] 27717 30.980% 85.482% ###### ( 250, 380 ] 6566 7.339% 92.821% # ( 380, 580 ] 4211 4.707% 97.528% # ( 580, 870 ] 1491 1.667% 99.194% ( 870, 1300 ] 358 0.400% 99.594% ( 1300, 1900 ] 130 0.145% 99.740% ( 1900, 2900 ] 110 0.123% 99.863% ( 2900, 4400 ] 94 0.105% 99.968% ( 4400, 6600 ] 28 0.031% 99.999% ( 6600, 9900 ] 1 0.001% 100.000% ** DB Stats ** Uptime(secs): 14.6 total, 9.8 interval Cumulative writes: 0 writes, 2000K keys, 0 commit groups, 0.0 writes per commit group, ingest: 1.92 GB, 134.72 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:7.641 H:M:S, 52.3 percent Interval writes: 0 writes, 1000K keys, 0 commit groups, 0.0 writes per commit group, ingest: 984.19 MB, 100.81 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:6.339 H:M:S, 64.9 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 : 256.889 micros/op 15536 ops/sec 20.210 seconds 313996 operations; 15.1 MB/s (78999 of 78999 found) Microseconds per read: Count: 313996 Average: 256.8999 StdDev: 417.88 Min: 2 Median: 210.5288 Max: 32102 Percentiles: P50: 210.53 P75: 279.69 P99: 901.08 P99.9: 4025.09 P99.99: 20085.77 ------------------------------------------------------ ( 1, 2 ] 12 0.004% 0.004% ( 2, 3 ] 59 0.019% 0.023% ( 3, 4 ] 91 0.029% 0.052% ( 4, 6 ] 151 0.048% 0.100% ( 6, 10 ] 405 0.129% 0.229% ( 10, 15 ] 3117 0.993% 1.221% ( 15, 22 ] 3452 1.099% 2.321% ( 22, 34 ] 5631 1.793% 4.114% ( 34, 51 ] 2227 0.709% 4.823% ( 51, 76 ] 234 0.075% 4.898% ( 76, 110 ] 707 0.225% 5.123% ( 110, 170 ] 71585 22.798% 27.921% ##### ( 170, 250 ] 136845 43.582% 71.503% ######### ( 250, 380 ] 48089 15.315% 86.818% ### ( 380, 580 ] 28109 8.952% 95.770% ## ( 580, 870 ] 9970 3.175% 98.945% # ( 870, 1300 ] 2380 0.758% 99.703% ( 1300, 1900 ] 374 0.119% 99.822% ( 1900, 2900 ] 196 0.062% 99.885% ( 2900, 4400 ] 64 0.020% 99.905% ( 4400, 6600 ] 26 0.008% 99.913% ( 6600, 9900 ] 131 0.042% 99.955% ( 9900, 14000 ] 67 0.021% 99.976% ( 14000, 22000 ] 56 0.018% 99.994% ( 22000, 33000 ] 18 0.006% 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 : 5799962 rocksdb.bloom.filter.full.positive COUNT : 369654 rocksdb.bloom.filter.full.true.positive COUNT : 313282 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 : 714 rocksdb.memtable.miss COUNT : 313282 rocksdb.l0.hit COUNT : 53776 rocksdb.l1.hit COUNT : 2373 rocksdb.l2andup.hit COUNT : 257133 rocksdb.compaction.key.drop.new COUNT : 73933 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 : 2497060 rocksdb.number.keys.read COUNT : 313996 rocksdb.number.keys.updated COUNT : 0 rocksdb.bytes.written COUNT : 2576965920 rocksdb.bytes.read COUNT : 313996000 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 : 354 rocksdb.no.file.errors COUNT : 0 rocksdb.stall.micros COUNT : 11438934 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 : 5620569268 rocksdb.compact.write.bytes COUNT : 5541482979 rocksdb.flush.write.bytes COUNT : 2572863681 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 : 14 rocksdb.number.superversion_acquires COUNT : 387 rocksdb.number.superversion_releases COUNT : 368 rocksdb.number.superversion_cleanups COUNT : 94 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 : 2555447296 rocksdb.memtable.garbage.bytes.at.flush COUNT : 4026368 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 : 6811371353 rocksdb.non.last.level.read.count COUNT : 1613082 rocksdb.block.checksum.compute.count COUNT : 1735286 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 : 209.660954 P95 : 562.028986 P99 : 899.049050 P100 : 31489.000000 COUNT : 313996 SUM : 80343572 rocksdb.db.write.micros P50 : 1.418412 P95 : 3.905882 P99 : 7.123116 P100 : 3994.000000 COUNT : 2497060 SUM : 17149524 rocksdb.compaction.times.micros P50 : 1124444.444444 P95 : 3810000.000000 P99 : 3866658.000000 P100 : 3866658.000000 COUNT : 14 SUM : 19621928 rocksdb.compaction.times.cpu_micros P50 : 1076363.636364 P95 : 3810000.000000 P99 : 3820804.000000 P100 : 3820804.000000 COUNT : 14 SUM : 18498369 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 : 752.187500 P95 : 1575.000000 P99 : 1575.000000 P100 : 1575.000000 COUNT : 39 SUM : 32703 rocksdb.compaction.outfile.sync.micros P50 : 1013.333333 P95 : 2200.000000 P99 : 2701.000000 P100 : 2701.000000 COUNT : 28 SUM : 31162 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 : 161.724138 P95 : 393.333333 P99 : 479.000000 P100 : 479.000000 COUNT : 56 SUM : 10476 rocksdb.table.open.io.micros P50 : 6579.245283 P95 : 13856.500000 P99 : 36403.000000 P100 : 36403.000000 COUNT : 354 SUM : 2546748 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.469612 P95 : 133.889345 P99 : 248.573984 P100 : 5094.000000 COUNT : 1361167 SUM : 23443283 rocksdb.read.block.get.micros P50 : 163.204374 P95 : 426.818389 P99 : 765.074680 P100 : 17601.000000 COUNT : 373761 SUM : 70781094 rocksdb.write.raw.block.micros P50 : 0.530564 P95 : 1.216821 P99 : 3.684756 P100 : 6362.000000 COUNT : 1965850 SUM : 4276336 rocksdb.numfiles.in.singlecompaction P50 : 3.416667 P95 : 15.700000 P99 : 16.000000 P100 : 16.000000 COUNT : 18 SUM : 79 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.569426 P95 : 2065.656425 P99 : 2733.354749 P100 : 3982.000000 COUNT : 59759 SUM : 11444007 rocksdb.sst.read.micros P50 : 1.688980 P95 : 236.753795 P99 : 506.185838 P100 : 12141.000000 COUNT : 1613104 SUM : 89031998 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 : 313996 SUM : 313996000 rocksdb.bytes.per.write P50 : 1032.000000 P95 : 1032.000000 P99 : 1032.000000 P100 : 1032.000000 COUNT : 2497060 SUM : 2576965920 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 : 211052.631579 P95 : 248000.000000 P99 : 262594.000000 P100 : 262594.000000 COUNT : 39 SUM : 7921968 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 : 54 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