[2024-10-21 11:55:20.671353] Starting SPDK v25.01-pre git sha1 1042d663d / DPDK 23.11.0 initialization... [2024-10-21 11:55:20.671453] [ 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_pid1443554 ] Set seed to 1729504520668935 because --seed was 0 EAL: No free 2048 kB hugepages reported on node 1 [2024-10-21 11:55:20.802048] app.c: 919:spdk_app_start: *NOTICE*: Total cores available: 1 [2024-10-21 11:55:20.972413] app.c: 612:app_setup_trace: *NOTICE*: Tracepoint Group Mask 0x80 specified. [2024-10-21 11:55:20.972468] app.c: 613:app_setup_trace: *NOTICE*: Use 'spdk_trace -s rocksdb -p 1443554' to capture a snapshot of events at runtime. [2024-10-21 11:55:20.972484] app.c: 618:app_setup_trace: *NOTICE*: 'spdk_trace' without parameters will also work if this is the only [2024-10-21 11:55:20.972500] app.c: 619:app_setup_trace: *NOTICE*: SPDK application currently running. [2024-10-21 11:55:20.972512] app.c: 620:app_setup_trace: *NOTICE*: Or copy /dev/shm/rocksdb_trace.pid1443554 for offline analysis/debug. [2024-10-21 11:55:20.974713] reactor.c:1005:reactor_run: *NOTICE*: Reactor started on core 0 RocksDB: version 8.1.1 Date: Mon Oct 21 11:55:28 2024 CPU: 96 * Intel(R) Xeon(R) Gold 6252 CPU @ 2.10GHz CPUCache: 36608 KB 2024/10/21-11:55:34 ... thread 0: (1000000,1000000) ops and (168195.5,168195.5) ops/second in (5.945464,5.945464) 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.10 GB 87.7 0.7 0.0 0.7 1.6 0.9 0.0 1.9 122.7 265.2 6.15 5.30 17 0.362 766K 3787 0.0 0.0 L1 11/11 1.22 GB 0.0 0.0 0.0 0.0 0.0 0.0 0.0 0.0 0.0 0.0 0.00 0.00 0 0.000 0 0 0.0 0.0 L2 2/0 57.11 MB 0.6 0.0 0.0 0.0 0.0 0.0 0.0 0.0 0.0 0.0 0.00 0.00 0 0.000 0 0 0.0 0.0 L3 10/0 772.57 MB 0.8 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 147/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 152/0 9.90 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 340/24 24.57 GB 0.0 0.7 0.0 0.7 1.6 0.9 0.0 1.9 122.7 265.2 6.15 5.30 17 0.362 766K 3787 0.0 0.0 Int 0/0 0.00 KB 0.0 0.7 0.0 0.7 1.6 0.9 0.0 1.9 122.7 265.2 6.15 5.30 17 0.362 766K 3787 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.7 0.0 0.7 0.7 -0.0 0.0 0.0 246.3 245.0 3.07 3.00 3 1.022 766K 3787 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 285.3 3.09 2.29 14 0.221 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): 6.1 total, 5.9 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: 1.59 GB write, 269.23 MB/s write, 0.74 GB read, 124.55 MB/s read, 6.2 seconds Interval compaction: 1.59 GB write, 274.40 MB/s write, 0.74 GB read, 126.95 MB/s read, 6.2 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: 229826 Average: 45.8689 StdDev: 237.48 Min: 0 Median: 1.5896 Max: 32100 Percentiles: P50: 1.59 P75: 2.34 P99: 512.05 P99.9: 1219.35 P99.99: 2092.98 ------------------------------------------------------ [ 0, 1 ] 44089 19.184% 19.184% #### ( 1, 2 ] 120119 52.265% 71.449% ########## ( 2, 3 ] 24358 10.598% 82.047% ## ( 3, 4 ] 3244 1.412% 83.459% ( 4, 6 ] 2743 1.194% 84.652% ( 6, 10 ] 1005 0.437% 85.090% ( 10, 15 ] 167 0.073% 85.162% ( 15, 22 ] 14 0.006% 85.168% ( 22, 34 ] 5 0.002% 85.171% ( 34, 51 ] 1 0.000% 85.171% ( 51, 76 ] 1 0.000% 85.171% ( 76, 110 ] 40 0.017% 85.189% ( 110, 170 ] 3555 1.547% 86.736% ( 170, 250 ] 16560 7.205% 93.941% # ( 250, 380 ] 9955 4.332% 98.273% # ( 380, 580 ] 2532 1.102% 99.374% ( 580, 870 ] 1001 0.436% 99.810% ( 870, 1300 ] 255 0.111% 99.921% ( 1300, 1900 ] 143 0.062% 99.983% ( 1900, 2900 ] 83 0.036% 100.019% ( 2900, 4400 ] 48 0.021% 100.040% ( 4400, 6600 ] 13 0.006% 100.046% ( 6600, 9900 ] 5 0.002% 100.048% ( 9900, 14000 ] 3 0.001% 100.049% ( 14000, 22000 ] 7 0.003% 100.052% ( 22000, 33000 ] 8 0.003% 100.056% ** Level 1 read latency histogram (micros): Count: 3868 Average: 328.0070 StdDev: 909.32 Min: 100 Median: 232.8002 Max: 27256 Percentiles: P50: 232.80 P75: 316.55 P99: 1664.24 P99.9: 18528.00 P99.99: 27256.00 ------------------------------------------------------ ( 76, 110 ] 6 0.155% 0.155% ( 110, 170 ] 431 11.143% 11.298% ## ( 170, 250 ] 1907 49.302% 60.600% ########## ( 250, 380 ] 1088 28.128% 88.728% ###### ( 380, 580 ] 267 6.903% 95.631% # ( 580, 870 ] 88 2.275% 97.906% ( 870, 1300 ] 32 0.827% 98.733% ( 1300, 1900 ] 17 0.440% 99.173% ( 1900, 2900 ] 8 0.207% 99.380% ( 2900, 4400 ] 7 0.181% 99.560% ( 4400, 6600 ] 8 0.207% 99.767% ( 6600, 9900 ] 2 0.052% 99.819% ( 9900, 14000 ] 2 0.052% 99.871% ( 14000, 22000 ] 2 0.052% 99.922% ( 22000, 33000 ] 3 0.078% 100.000% ** Level 2 read latency histogram (micros): Count: 180 Average: 323.1778 StdDev: 504.60 Min: 125 Median: 225.4455 Max: 5551 Percentiles: P50: 225.45 P75: 297.89 P99: 2633.33 P99.9: 5551.00 P99.99: 5551.00 ------------------------------------------------------ ( 110, 170 ] 20 11.111% 11.111% ## ( 170, 250 ] 101 56.111% 67.222% ########### ( 250, 380 ] 38 21.111% 88.333% #### ( 380, 580 ] 11 6.111% 94.444% # ( 580, 870 ] 5 2.778% 97.222% # ( 870, 1300 ] 1 0.556% 97.778% ( 1900, 2900 ] 3 1.667% 99.444% ( 4400, 6600 ] 1 0.556% 100.000% ** Level 3 read latency histogram (micros): Count: 2453 Average: 289.5067 StdDev: 312.01 Min: 101 Median: 232.8618 Max: 4832 Percentiles: P50: 232.86 P75: 315.14 P99: 1547.00 P99.9: 4801.13 P99.99: 4832.00 ------------------------------------------------------ ( 76, 110 ] 3 0.122% 0.122% ( 110, 170 ] 257 10.477% 10.599% ## ( 170, 250 ] 1230 50.143% 60.742% ########## ( 250, 380 ] 698 28.455% 89.197% ###### ( 380, 580 ] 159 6.482% 95.679% # ( 580, 870 ] 68 2.772% 98.451% # ( 870, 1300 ] 11 0.448% 98.899% ( 1300, 1900 ] 6 0.245% 99.144% ( 1900, 2900 ] 11 0.448% 99.592% ( 2900, 4400 ] 7 0.285% 99.878% ( 4400, 6600 ] 3 0.122% 100.000% ** Level 4 read latency histogram (micros): Count: 24983 Average: 295.2941 StdDev: 323.80 Min: 93 Median: 232.5171 Max: 7105 Percentiles: P50: 232.52 P75: 318.01 P99: 1661.13 P99.9: 4570.67 P99.99: 6473.22 ------------------------------------------------------ ( 76, 110 ] 36 0.144% 0.144% ( 110, 170 ] 2881 11.532% 11.676% ## ( 170, 250 ] 12252 49.041% 60.717% ########## ( 250, 380 ] 6821 27.303% 88.020% ##### ( 380, 580 ] 1778 7.117% 95.137% # ( 580, 870 ] 704 2.818% 97.955% # ( 870, 1300 ] 207 0.829% 98.783% ( 1300, 1900 ] 90 0.360% 99.143% ( 1900, 2900 ] 95 0.380% 99.524% ( 2900, 4400 ] 92 0.368% 99.892% ( 4400, 6600 ] 26 0.104% 99.996% ( 6600, 9900 ] 1 0.004% 100.000% ** Level 5 read latency histogram (micros): Count: 25643 Average: 300.7929 StdDev: 334.99 Min: 91 Median: 234.3681 Max: 7052 Percentiles: P50: 234.37 P75: 321.47 P99: 1791.86 P99.9: 4806.39 P99.99: 6557.19 ------------------------------------------------------ ( 76, 110 ] 43 0.168% 0.168% ( 110, 170 ] 2795 10.900% 11.067% ## ( 170, 250 ] 12408 48.387% 59.455% ########## ( 250, 380 ] 7251 28.277% 87.732% ###### ( 380, 580 ] 1809 7.055% 94.786% # ( 580, 870 ] 771 3.007% 97.793% # ( 870, 1300 ] 203 0.792% 98.584% ( 1300, 1900 ] 130 0.507% 99.091% ( 1900, 2900 ] 111 0.433% 99.524% ( 2900, 4400 ] 91 0.355% 99.879% ( 4400, 6600 ] 29 0.113% 99.992% ( 6600, 9900 ] 2 0.008% 100.000% ** DB Stats ** Uptime(secs): 6.1 total, 5.9 interval Cumulative writes: 0 writes, 1000K keys, 0 commit groups, 0.0 writes per commit group, ingest: 0.96 GB, 162.36 MB/s Cumulative WAL: 0 writes, 0 syncs, 0.00 writes per sync, written: 0.00 GB, 0.00 MB/s Cumulative stall: 00:00:1.286 H:M:S, 21.2 percent Interval writes: 0 writes, 1000K keys, 0 commit groups, 0.0 writes per commit group, ingest: 984.19 MB, 165.46 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.286 H:M:S, 21.6 percent Write Stall (count): write-buffer-manager-limit-stops: 0, num-running-compactions: 1 num-running-flushes: 1 2024/10/21-11:55:43 ... thread 0: (1000000,2000000) ops and (108698.3,132054.7) ops/second in (9.199774,15.145238) 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/17 4.06 GB 104.6 2.7 0.0 2.7 4.5 1.8 0.0 2.4 148.9 249.2 18.55 16.69 38 0.488 2805K 27K 0.0 0.0 L1 11/11 1.22 GB 0.0 0.0 0.0 0.0 0.0 0.0 0.0 0.0 0.0 0.0 0.00 0.00 0 0.000 0 0 0.0 0.0 L2 2/0 57.11 MB 0.6 0.0 0.0 0.0 0.0 0.0 0.0 0.0 0.0 0.0 0.00 0.00 0 0.000 0 0 0.0 0.0 L3 10/0 772.57 MB 0.8 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 147/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 152/0 9.90 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 341/28 25.53 GB 0.0 2.7 0.0 2.7 4.5 1.8 0.0 2.4 148.9 249.2 18.55 16.69 38 0.488 2805K 27K 0.0 0.0 Int 0/0 0.00 KB 0.0 2.7 0.0 2.7 4.5 1.8 0.0 2.4 148.9 249.2 18.55 16.69 38 0.488 2805K 27K 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.7 0.0 2.7 2.7 -0.0 0.0 0.0 232.9 230.5 11.87 11.64 8 1.483 2805K 27K 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 282.3 6.69 5.05 30 0.223 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): 15.3 total, 15.1 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.51 GB write, 302.92 MB/s write, 2.70 GB read, 181.05 MB/s read, 18.6 seconds Interval compaction: 4.51 GB write, 305.20 MB/s write, 2.70 GB read, 182.42 MB/s read, 18.6 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: 842866 Average: 33.4898 StdDev: 146.03 Min: 0 Median: 1.6955 Max: 32100 Percentiles: P50: 1.70 P75: 2.50 P99: 413.07 P99.9: 794.12 P99.99: 975.95 ------------------------------------------------------ [ 0, 1 ] 120024 14.240% 14.240% ### ( 1, 2 ] 433345 51.413% 65.653% ########## ( 2, 3 ] 156815 18.605% 84.258% #### ( 3, 4 ] 17301 2.053% 86.311% ( 4, 6 ] 13905 1.650% 87.961% ( 6, 10 ] 3749 0.445% 88.405% ( 10, 15 ] 643 0.076% 88.482% ( 15, 22 ] 75 0.009% 88.491% ( 22, 34 ] 16 0.002% 88.492% ( 34, 51 ] 2 0.000% 88.493% ( 51, 76 ] 7 0.001% 88.494% ( 76, 110 ] 150 0.018% 88.511% ( 110, 170 ] 10819 1.284% 89.795% ( 170, 250 ] 48891 5.801% 95.596% # ( 250, 380 ] 27558 3.270% 98.865% # ( 380, 580 ] 6878 0.816% 99.681% ( 580, 870 ] 2499 0.296% 99.978% ( 870, 1300 ] 425 0.050% 100.028% ( 1300, 1900 ] 168 0.020% 100.048% ( 1900, 2900 ] 112 0.013% 100.061% ( 2900, 4400 ] 73 0.009% 100.070% ( 4400, 6600 ] 14 0.002% 100.072% ( 6600, 9900 ] 5 0.001% 100.072% ( 9900, 14000 ] 3 0.000% 100.072% ( 14000, 22000 ] 7 0.001% 100.073% ( 22000, 33000 ] 8 0.001% 100.074% ** Level 1 read latency histogram (micros): Count: 10535 Average: 284.7539 StdDev: 559.36 Min: 98 Median: 231.6018 Max: 27256 Percentiles: P50: 231.60 P75: 312.90 P99: 869.58 P99.9: 6177.88 P99.99: 27256.00 ------------------------------------------------------ ( 76, 110 ] 17 0.161% 0.161% ( 110, 170 ] 1174 11.144% 11.305% ## ( 170, 250 ] 5294 50.252% 61.557% ########## ( 250, 380 ] 2927 27.784% 89.340% ###### ( 380, 580 ] 778 7.385% 96.725% # ( 580, 870 ] 240 2.278% 99.003% ( 870, 1300 ] 54 0.513% 99.516% ( 1300, 1900 ] 19 0.180% 99.696% ( 1900, 2900 ] 8 0.076% 99.772% ( 2900, 4400 ] 7 0.066% 99.839% ( 4400, 6600 ] 8 0.076% 99.915% ( 6600, 9900 ] 2 0.019% 99.934% ( 9900, 14000 ] 2 0.019% 99.953% ( 14000, 22000 ] 2 0.019% 99.972% ( 22000, 33000 ] 3 0.028% 100.000% ** Level 2 read latency histogram (micros): Count: 478 Average: 287.5607 StdDev: 338.54 Min: 113 Median: 228.2946 Max: 5551 Percentiles: P50: 228.29 P75: 306.45 P99: 1955.00 P99.9: 5548.40 P99.99: 5551.00 ------------------------------------------------------ ( 110, 170 ] 51 10.669% 10.669% ## ( 170, 250 ] 258 53.975% 64.644% ########### ( 250, 380 ] 114 23.849% 88.494% ##### ( 380, 580 ] 36 7.531% 96.025% ## ( 580, 870 ] 12 2.510% 98.536% # ( 870, 1300 ] 2 0.418% 98.954% ( 1900, 2900 ] 4 0.837% 99.791% ( 4400, 6600 ] 1 0.209% 100.000% ** Level 3 read latency histogram (micros): Count: 6761 Average: 269.8908 StdDev: 216.81 Min: 101 Median: 231.2533 Max: 4832 Percentiles: P50: 231.25 P75: 310.67 P99: 840.02 P99.9: 3773.17 P99.99: 4832.00 ------------------------------------------------------ ( 76, 110 ] 8 0.118% 0.118% ( 110, 170 ] 770 11.389% 11.507% ## ( 170, 250 ] 3399 50.274% 61.781% ########## ( 250, 380 ] 1915 28.324% 90.105% ###### ( 380, 580 ] 466 6.892% 96.997% # ( 580, 870 ] 151 2.233% 99.231% ( 870, 1300 ] 21 0.311% 99.541% ( 1300, 1900 ] 7 0.104% 99.645% ( 1900, 2900 ] 12 0.177% 99.823% ( 2900, 4400 ] 9 0.133% 99.956% ( 4400, 6600 ] 3 0.044% 100.000% ** Level 4 read latency histogram (micros): Count: 67061 Average: 272.6842 StdDev: 220.56 Min: 93 Median: 231.1522 Max: 7105 Percentiles: P50: 231.15 P75: 312.23 P99: 864.23 P99.9: 3789.67 P99.99: 6135.06 ------------------------------------------------------ ( 76, 110 ] 105 0.157% 0.157% ( 110, 170 ] 7724 11.518% 11.674% ## ( 170, 250 ] 33623 50.138% 61.812% ########## ( 250, 380 ] 18476 27.551% 89.363% ###### ( 380, 580 ] 4758 7.095% 96.458% # ( 580, 870 ] 1739 2.593% 99.052% # ( 870, 1300 ] 309 0.461% 99.512% ( 1300, 1900 ] 100 0.149% 99.662% ( 1900, 2900 ] 103 0.154% 99.815% ( 2900, 4400 ] 96 0.143% 99.958% ( 4400, 6600 ] 27 0.040% 99.999% ( 6600, 9900 ] 1 0.001% 100.000% ** Level 5 read latency histogram (micros): Count: 69089 Average: 274.5879 StdDev: 225.02 Min: 91 Median: 231.7058 Max: 7052 Percentiles: P50: 231.71 P75: 313.46 P99: 868.63 P99.9: 3778.98 P99.99: 6227.60 ------------------------------------------------------ ( 76, 110 ] 104 0.151% 0.151% ( 110, 170 ] 7816 11.313% 11.463% ## ( 170, 250 ] 34518 49.962% 61.425% ########## ( 250, 380 ] 19212 27.808% 89.233% ###### ( 380, 580 ] 4871 7.050% 96.283% # ( 580, 870 ] 1886 2.730% 99.013% # ( 870, 1300 ] 303 0.439% 99.451% ( 1300, 1900 ] 137 0.198% 99.650% ( 1900, 2900 ] 119 0.172% 99.822% ( 2900, 4400 ] 92 0.133% 99.955% ( 4400, 6600 ] 29 0.042% 99.997% ( 6600, 9900 ] 2 0.003% 100.000% ** DB Stats ** Uptime(secs): 15.3 total, 9.2 interval Cumulative writes: 0 writes, 2000K keys, 0 commit groups, 0.0 writes per commit group, ingest: 1.92 GB, 128.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:7.048 H:M:S, 46.2 percent Interval writes: 0 writes, 1000K keys, 0 commit groups, 0.0 writes per commit group, ingest: 984.19 MB, 106.98 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.762 H:M:S, 62.6 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 : 333.922 micros/op 11953 ops/sec 20.245 seconds 241996 operations; 11.6 MB/s (59999 of 59999 found) Microseconds per read: Count: 241996 Average: 333.9294 StdDev: 478.69 Min: 1 Median: 283.6079 Max: 54979 Percentiles: P50: 283.61 P75: 372.97 P99: 1135.27 P99.9: 7754.07 P99.99: 19950.10 ------------------------------------------------------ [ 0, 1 ] 2 0.001% 0.001% ( 1, 2 ] 38 0.016% 0.017% ( 2, 3 ] 120 0.050% 0.066% ( 3, 4 ] 85 0.035% 0.101% ( 4, 6 ] 198 0.082% 0.183% ( 6, 10 ] 438 0.181% 0.364% ( 10, 15 ] 3996 1.651% 2.015% ( 15, 22 ] 927 0.383% 2.398% ( 22, 34 ] 2991 1.236% 3.634% ( 34, 51 ] 580 0.240% 3.874% ( 51, 76 ] 49 0.020% 3.894% ( 76, 110 ] 5 0.002% 3.896% ( 110, 170 ] 8217 3.396% 7.292% # ( 170, 250 ] 80600 33.306% 40.598% ####### ( 250, 380 ] 88008 36.368% 76.966% ####### ( 380, 580 ] 36094 14.915% 91.881% ### ( 580, 870 ] 15001 6.199% 98.080% # ( 870, 1300 ] 3610 1.492% 99.571% ( 1300, 1900 ] 480 0.198% 99.770% ( 1900, 2900 ] 149 0.062% 99.831% ( 2900, 4400 ] 62 0.026% 99.857% ( 4400, 6600 ] 47 0.019% 99.876% ( 6600, 9900 ] 163 0.067% 99.944% ( 9900, 14000 ] 88 0.036% 99.980% ( 14000, 22000 ] 32 0.013% 99.993% ( 22000, 33000 ] 15 0.006% 100.000% ( 50000, 75000 ] 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 : 4634303 rocksdb.bloom.filter.full.positive COUNT : 286445 rocksdb.bloom.filter.full.true.positive COUNT : 241324 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 : 672 rocksdb.memtable.miss COUNT : 241324 rocksdb.l0.hit COUNT : 40859 rocksdb.l1.hit COUNT : 12803 rocksdb.l2andup.hit COUNT : 187662 rocksdb.compaction.key.drop.new COUNT : 55674 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 : 2467243 rocksdb.number.keys.read COUNT : 241996 rocksdb.number.keys.updated COUNT : 0 rocksdb.bytes.written COUNT : 2546194776 rocksdb.bytes.read COUNT : 241996000 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 : 383 rocksdb.no.file.errors COUNT : 0 rocksdb.stall.micros COUNT : 10558674 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 : 4374566141 rocksdb.compact.write.bytes COUNT : 4317617961 rocksdb.flush.write.bytes COUNT : 2506930679 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 : 15 rocksdb.number.superversion_acquires COUNT : 348 rocksdb.number.superversion_releases COUNT : 333 rocksdb.number.superversion_cleanups COUNT : 86 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 : 2489927680 rocksdb.memtable.garbage.bytes.at.flush COUNT : 3891200 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 : 5729770105 rocksdb.non.last.level.read.count COUNT : 1350613 rocksdb.block.checksum.compute.count COUNT : 1349862 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 : 282.523084 P95 : 724.848493 P99 : 1134.058765 P100 : 54978.000000 COUNT : 241996 SUM : 80639934 rocksdb.db.write.micros P50 : 1.626416 P95 : 5.031242 P99 : 8.640928 P100 : 2253.000000 COUNT : 2467243 SUM : 16858183 rocksdb.compaction.times.micros P50 : 1200000.000000 P95 : 4862908.000000 P99 : 4862908.000000 P100 : 4862908.000000 COUNT : 10 SUM : 16151326 rocksdb.compaction.times.cpu_micros P50 : 1200000.000000 P95 : 4768744.000000 P99 : 4768744.000000 P100 : 4768744.000000 COUNT : 10 SUM : 15812017 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 : 1163.181818 P95 : 2122.000000 P99 : 2122.000000 P100 : 2122.000000 COUNT : 38 SUM : 48272 rocksdb.compaction.outfile.sync.micros P50 : 1600.000000 P95 : 3950.000000 P99 : 4139.000000 P100 : 4139.000000 COUNT : 12 SUM : 22018 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 : 319.814815 P95 : 571.000000 P99 : 617.000000 P100 : 617.000000 COUNT : 49 SUM : 16017 rocksdb.table.open.io.micros P50 : 6811.280488 P95 : 13260.217391 P99 : 60141.000000 P100 : 60141.000000 COUNT : 383 SUM : 3084849 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.844241 P95 : 191.501779 P99 : 358.240455 P100 : 19658.000000 COUNT : 1059818 SUM : 23129513 rocksdb.read.block.get.micros P50 : 228.626034 P95 : 519.408644 P99 : 826.678014 P100 : 32232.000000 COUNT : 289739 SUM : 73815093 rocksdb.write.raw.block.micros P50 : 0.524785 P95 : 0.997091 P99 : 2.865140 P100 : 7243.000000 COUNT : 1653050 SUM : 4520301 rocksdb.numfiles.in.singlecompaction P50 : 4.000000 P95 : 24.000000 P99 : 24.000000 P100 : 24.000000 COUNT : 12 SUM : 68 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.558921 P95 : 1186.082729 P99 : 2174.000000 P100 : 2174.000000 COUNT : 74057 SUM : 10562734 rocksdb.sst.read.micros P50 : 1.828539 P95 : 334.337297 P99 : 558.357802 P100 : 32100.000000 COUNT : 1350757 SUM : 93745758 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 : 241996 SUM : 241996000 rocksdb.bytes.per.write P50 : 1032.000000 P95 : 1032.000000 P99 : 1032.000000 P100 : 1032.000000 COUNT : 2467243 SUM : 2546194776 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 : 228461.538462 P95 : 369363.636364 P99 : 392805.000000 P100 : 392805.000000 COUNT : 38 SUM : 8720205 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 : 40 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