2024/11/23-02:16:02.350071 140025079141952 DEBG dcompact_worker.cpp:2214: /storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-17197/att-00: fork to child time = 0.010951 sec 2024/11/23-02:16:02.350986 140025079141952 DEBG dcompact_worker.cpp:1013: Beg SerDeRead: /storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-17197/att-00 2024/11/23-02:16:02.357939 140025079141952 DEBG dcompact_worker.cpp:1033: End SerDeRead: /storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-17197/att-00 2024/11/23-02:16:02.359040 140025079141952 TRAC dcompact_worker.cpp:1083: INFO_LOG_LEVEL: rpc = INFO_LEVEL, env_var = undefined 2024/11/23-02:16:02.359314 140025079141952 [WARN] [column_family.cc:386] multiple cf_paths/db_paths and level_compaction_dynamic_level_bytes can't be used together 2024/11/23-02:16:02.406405 140025079141952 [version_set.cc:6208] Recovering from manifest file: /worker/dcompact-log/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/2024-11-22T14.14.14/.rocksdb/job-17197/att-00/MANIFEST-206347 2024/11/23-02:16:02.440624 140025079141952 [version_set.cc:6251] Recovered from manifest file:/worker/dcompact-log/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/2024-11-22T14.14.14/.rocksdb/job-17197/att-00/MANIFEST-206347 succeeded,manifest_file_number is 206347, next_file_number is 240362, last_sequence is 3728228333, log_number is 0,prev_log_number is 0,max_column_family is 0,min_log_number_to_keep is 240154 2024/11/23-02:16:02.440630 140025079141952 [version_set.cc:6266] Column family [default] (ID 0), log number is 0 2024/11/23-02:16:02.440728 140025079141952 [version_set.cc:5721] Creating manifest 240362 2024/11/23-02:16:02.472306 140025079141952 DEBG dcompact_worker.cpp:1218: /storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-17197/att-00: bottommost_level: fake = 1, rpc = 0 2024/11/23-02:16:02.496058 140025079141952 [compaction_job.cc:2437] [default] [JOB 17197] Compacting 1@2 + 15@3 files to L3, score 1.00, subcompactions 1 : 1 2024/11/23-02:16:02.496071 140025079141952 [compaction_job.cc:2445] [default]: Compaction start summary: Base version 12854 Base level 2, inputs: [240290(27MB)], [240359(34MB) 240360(6220KB) 240258(36MB) 240259(33MB) 240260(33MB) 240261(17MB) 240246(35MB) 240247(34MB) 240248(34MB) 240249(34MB) 240250(35MB) 240251(36MB) 240252(36MB) 240253(34MB) 240331(36MB)] 2024/11/23-02:16:02.503206 140025079141952 [top_zip_table.cc:59] core git_version_hash_info_is:commit fc4ab4f2fbfdcdfa23a955d69e26d2fc533b902d Author: leipeng Date: 2024-04-16 20:29:43 +0800 test_sso.cpp: test swap g++ (GCC) 12.1.0 Copyright (C) 2022 Free Software Foundation, Inc. This is free software; see the source for copying conditions. There is NO warranty; not even for MERCHANTABILITY or FITNESS FOR A PARTICULAR PURPOSE. INCS = -Isrc -I3rdparty/re2 -I3rdparty/zstd -Iboost-include CXXFLAGS = -D TOPLING_IO_WITH_URING=1 -fPIC -time -Wno-class-memaccess -fdiagnostics-color -Wformat=2 -Wcomment -Wall -Wextra -Wno-unused-parameter -Wno-alloc-size-larger-than -mcx16 -Wno-deprecated-declarations -Wstrict-aliasing=3 -DCLIENT_LICENSE_ID="public" -DNO_THREADS -ftls-model=initial-exec -DDIVSUFSORT_API= -fno-stack-protector RLS_FLAGS = -O3 -DNDEBUG -g3 WITH_BMI2 = 1 compile_cpu_flag: -march=haswell -mbmi -mbmi2 2024/11/23-02:16:02.503235 140025079141952 [top_zip_table.cc:60] fsa git_version_hash_info_is:commit fc4ab4f2fbfdcdfa23a955d69e26d2fc533b902d Author: leipeng Date: 2024-04-16 20:29:43 +0800 test_sso.cpp: test swap g++ (GCC) 12.1.0 Copyright (C) 2022 Free Software Foundation, Inc. This is free software; see the source for copying conditions. There is NO warranty; not even for MERCHANTABILITY or FITNESS FOR A PARTICULAR PURPOSE. INCS = -Isrc -I3rdparty/re2 -I3rdparty/zstd -Iboost-include CXXFLAGS = -D TOPLING_IO_WITH_URING=1 -fPIC -time -Wno-class-memaccess -fdiagnostics-color -Wformat=2 -Wcomment -Wall -Wextra -Wno-unused-parameter -Wno-alloc-size-larger-than -mcx16 -Wno-deprecated-declarations -Wstrict-aliasing=3 -DCLIENT_LICENSE_ID="public" -DNO_THREADS -ftls-model=initial-exec -DDIVSUFSORT_API= -fno-stack-protector RLS_FLAGS = -O3 -DNDEBUG -g3 WITH_BMI2 = 1 compile_cpu_flag: -march=haswell -mbmi -mbmi2 2024/11/23-02:16:02.503253 140025079141952 [top_zip_table.cc:61] zbs git_version_hash_info_is:commit fc4ab4f2fbfdcdfa23a955d69e26d2fc533b902d Author: leipeng Date: 2024-04-16 20:29:43 +0800 test_sso.cpp: test swap g++ (GCC) 12.1.0 Copyright (C) 2022 Free Software Foundation, Inc. This is free software; see the source for copying conditions. There is NO warranty; not even for MERCHANTABILITY or FITNESS FOR A PARTICULAR PURPOSE. INCS = -Isrc -I3rdparty/re2 -I3rdparty/zstd -Iboost-include CXXFLAGS = -D TOPLING_IO_WITH_URING=1 -fPIC -time -Wno-class-memaccess -fdiagnostics-color -Wformat=2 -Wcomment -Wall -Wextra -Wno-unused-parameter -Wno-alloc-size-larger-than -mcx16 -Wno-deprecated-declarations -Wstrict-aliasing=3 -DCLIENT_LICENSE_ID="public" -DNO_THREADS -ftls-model=initial-exec -DDIVSUFSORT_API= -fno-stack-protector RLS_FLAGS = -O3 -DNDEBUG -g3 WITH_BMI2 = 1 compile_cpu_flag: -march=haswell -mbmi -mbmi2 2024/11/23-02:16:02.503258 140025079141952 [top_zip_table.cc:62] topling-zip_table_reader: git_version_hash_info_is: commit a4a382006cb82713acf5b3425835f22711877681 Author: leipeng Date: 2024-04-11 19:44:14 +0800 fixed_len_hole_index.cc: Add env bool FixedLenHoleIndexUseAVX512 for ut & debug 1. FixedLenHoleIndexUseAVX512 is only in unit tests or debug 2. In release mode, use avx512 by compile options(-march=) g++ (GCC) 12.1.0 Copyright (C) 2022 Free Software Foundation, Inc. This is free software; see the source for copying conditions. There is NO warranty; not even for MERCHANTABILITY or FITNESS FOR A PARTICULAR PURPOSE. cpu_flag: -march=native -mbmi -mbmi2 2024/11/23-02:16:02.503268 140025079141952 [top_zip_table.cc:64] topling-rocks git_version_hash_info_is: commit feab587e468621ba0204d47b4ffacc52e7c4290d Author: leipeng Date: 2024-03-22 13:35:06 +0800 ToplingZipTableBuilder::Add: immediately return after error This function returns void, and caller does not check builder->status() after calling this function and graceful abandon. So immediately return after error, and return error status in Finish(). g++ (GCC) 12.1.0 Copyright (C) 2022 Free Software Foundation, Inc. This is free software; see the source for copying conditions. There is NO warranty; not even for MERCHANTABILITY or FITNESS FOR A PARTICULAR PURPOSE. cpu_flag: -march=native -mbmi -mbmi2 2024/11/23-02:16:02.995244 140025079141952 [top_zip_table_builder.cc:934] ToplingZipTableBuilder(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-17197/att-00/240363.sst) /dev/shm/ToplingTemp/Topling-378544-DrEviW 2024/11/23-02:16:03.533947 140025079141952 [top_zip_table_builder.cc:1352] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-17197/att-00/240363.sst): first pass time = 0.54's, 249.174'MB/sec 2024/11/23-02:16:04.853018 140025079141952 [top_zip_table_builder.cc:2772] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-17197/att-00/240363.sst): old prealloc_size = 147639500, real_size = 32704560 2024/11/23-02:16:04.853603 140025079141952 [top_zip_table_builder.cc:1773] BuildReorderMap(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-17197/att-00/240363.sst): index type = NestLoudsTrieDAWG_Mixed_XL_256_32_FL, store type = DictZipBlobStore usrkeys = 926917 prefix = 4 raw-key = 0.0148 GB zip-key = 0.0018 GB avg-key = 16.00 avg-zkey = 1.90 raw-val = 0.1083 GB zip-val = 0.0307 GB avg-val = 116.80 avg-zval = 33.13 2024/11/23-02:16:04.854365 140025079141952 [top_zip_table_builder.cc:1299] /storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-17197/att-00/240363.sst: sumWaitingMem = 0.002 GB, sumWorkingMem = 0.000 GB, reorder workingMem = 0.0023 GB, waited 0.000 sec, Key+Value = 0.134 GB 2024/11/23-02:16:05.765787 140025079141952 [top_zip_table_builder.cc:3100] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-17197/att-00/240363.sst): second pass time = 1.32's, 82.135'MB/sec, value only(80.7% of KV) wait indexing time = 0.00's, remap KeyValue time = 0.91's, 40.194'MB/sec (all stages of remap) Get OrderMap time = 0.00's, 3119.736'MB/sec (index lex order gen) rebuild zvType time = 0.79's, 0.295'MB/sec write SST data time = 0.13's, 288.595'MB/sec dict compress time = 4356233619.02's, 0.000'MB/sec z-dict build time = 0.00's, sample length = 1.080'MB, throughput = 32363.092'MB/sec zip my value time = 1.32's, unzip length = 0.108'GB zip my value throughput = 82.135'MB/sec zip pipeline throughput = 82.135'MB/sec entries = 926917 avg-key = 28.00 avg-zkey = 1.90 avg-val = 116.80 avg-zval = 33.13 usrkeys = 926917 avg-key = 20.00 avg-zkey = 1.90 avg-val = 123.80 avg-zval = 33.13 TagRS{ kinds = RS_Key0_Tag1 bytes = 0 } TagArray{ size = 926917 bytes = 3128352 } seq expand size = 6488419 multi value expand size = 0 cnt WriteAppend = 13 UnZipSize{ index = 0.0185 GB value = 0.1083 GB dict = 1.08 MB all = 0.1342 GB } __ZipSize{ index = 0.0018 GB value = 0.0307 GB dict = 1.08 MB all = 0.0367 GB } UnZip/Zip{ index = 10.5156 value = 3.5254 dict = 1.00 all = 3.6584 } Zip/UnZip{ index = 0.0951 value = 0.2837 dict = 1.00 all = 0.2733 } ---------------------------- total value len = 0.114753 GB avg = 0.124 KB (by entry num) total key len = 0.025954 GB avg = 0.028 KB total ukey len = 0.018538 GB avg = 0.020 KB total ukey num = 0.000926917 Billion total entry num = 0.000926917 Billion write speed all = 0.002246891 MB/sec (with seq num) write speed all = 0.002128478 MB/sec (without seq num) 2024/11/23-02:16:05.799553 140025079141952 [compaction_job.cc:1948] [default] [JOB 17197] Generated table #240363: 926917 keys, 36687336 bytes, temperature: kWarm 2024/11/23-02:16:05.799638 140025079141952 EVENT_LOG_v1 {"time_micros": 1732299365799585, "cf_name": "default", "job": 17197, "event": "table_file_creation", "file_number": 240363, "file_size": 36687336, "file_checksum": "", "file_checksum_func_name": "Unknown", "smallest_seqno": 3621075956, "largest_seqno": 3727820506, "table_properties": {"data_size": 30709728, "index_size": 1762944, "index_partitions": 0, "top_level_index_size": 0, "index_key_is_user_key": 0, "index_value_is_delta_encoded": 0, "filter_size": 0, "raw_key_size": 25953676, "raw_average_key_size": 28, "raw_value_size": 108264190, "raw_average_value_size": 116, "num_data_blocks": 1, "num_entries": 926917, "num_filter_entries": 0, "num_deletions": 0, "num_merge_operands": 0, "num_range_deletions": 0, "format_version": 0, "fixed_key_len": 28, "fixed_value_len": 18446744073709551615, "filter_policy": "", "column_family_name": "default", "column_family_id": 0, "comparator": "leveldb.BytewiseComparator", "merge_operator": "nullptr", "prefix_extractor_name": "nullptr", "property_collectors": "[Rdb_tbl_prop_coll_factory]", "compression": "FlatZip", "compression_options": "RS_Key0_Tag1-NestLoudsTrieDAWG_Mixed_XL_256_32_FL-DictZipBlobStore", "creation_time": 1732299362, "oldest_key_time": 0, "file_creation_time": 1732299362, "slow_compression_estimated_data_size": 0, "fast_compression_estimated_data_size": 0, "db_id": "d89b4006-2803-486e-b7a2-759bcf348921", "db_session_id": "U1ECJGOXSO4Y1P7ZZXV1", "orig_file_number": 240363, "seqno_to_time_mapping": "N/A", "__indexstats__": "[...1 records...]"}} 2024/11/23-02:16:05.833564 140025079141952 [top_zip_table_builder.cc:934] ToplingZipTableBuilder(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-17197/att-00/240364.sst) /dev/shm/ToplingTemp/Topling-378544-sWs2iU 2024/11/23-02:16:06.706681 140025079141952 [top_zip_table_builder.cc:1352] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-17197/att-00/240364.sst): first pass time = 0.87's, 153.728'MB/sec 2024/11/23-02:16:08.048175 140025079141952 [top_zip_table_builder.cc:2772] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-17197/att-00/240364.sst): old prealloc_size = 147639500, real_size = 33083312 2024/11/23-02:16:08.048755 140025079141952 [top_zip_table_builder.cc:1773] BuildReorderMap(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-17197/att-00/240364.sst): index type = NestLoudsTrieDAWG_Mixed_XL_256_32_FL, store type = DictZipBlobStore usrkeys = 931091 prefix = 4 raw-key = 0.0149 GB zip-key = 0.0018 GB avg-key = 16.00 avg-zkey = 1.90 raw-val = 0.1081 GB zip-val = 0.0311 GB avg-val = 116.15 avg-zval = 33.38 2024/11/23-02:16:08.049545 140025079141952 [top_zip_table_builder.cc:1299] /storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-17197/att-00/240364.sst: sumWaitingMem = 0.002 GB, sumWorkingMem = 0.000 GB, reorder workingMem = 0.0023 GB, waited 0.000 sec, Key+Value = 0.134 GB 2024/11/23-02:16:08.975165 140025079141952 [top_zip_table_builder.cc:3100] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-17197/att-00/240364.sst): second pass time = 1.34's, 80.660'MB/sec, value only(80.6% of KV) wait indexing time = 0.00's, remap KeyValue time = 0.93's, 40.250'MB/sec (all stages of remap) Get OrderMap time = 0.00's, 3181.647'MB/sec (index lex order gen) rebuild zvType time = 0.80's, 0.291'MB/sec write SST data time = 0.13's, 292.531'MB/sec dict compress time = 4356233619.02's, 0.000'MB/sec z-dict build time = 0.00's, sample length = 1.079'MB, throughput = 32190.175'MB/sec zip my value time = 1.34's, unzip length = 0.108'GB zip my value throughput = 80.660'MB/sec zip pipeline throughput = 80.660'MB/sec entries = 931091 avg-key = 28.00 avg-zkey = 1.90 avg-val = 116.15 avg-zval = 33.38 usrkeys = 931091 avg-key = 20.00 avg-zkey = 1.90 avg-val = 123.15 avg-zval = 33.38 TagRS{ kinds = RS_Key0_Tag1 bytes = 0 } TagArray{ size = 931091 bytes = 3375216 } seq expand size = 6517637 multi value expand size = 0 cnt WriteAppend = 13 UnZipSize{ index = 0.0186 GB value = 0.1081 GB dict = 1.08 MB all = 0.1342 GB } __ZipSize{ index = 0.0018 GB value = 0.0311 GB dict = 1.08 MB all = 0.0373 GB } UnZip/Zip{ index = 10.5129 value = 3.4798 dict = 1.00 all = 3.5974 } Zip/UnZip{ index = 0.0951 value = 0.2874 dict = 1.00 all = 0.2780 } ---------------------------- total value len = 0.229417 GB avg = 0.123 KB (by entry num) total key len = 0.052024 GB avg = 0.028 KB total ukey len = 0.037160 GB avg = 0.020 KB total ukey num = 0.001858008 Billion total entry num = 0.001858008 Billion write speed all = 0.004494016 MB/sec (with seq num) write speed all = 0.004256669 MB/sec (without seq num) 2024/11/23-02:16:09.008863 140025079141952 [compaction_job.cc:1948] [default] [JOB 17197] Generated table #240364: 931091 keys, 37310132 bytes, temperature: kWarm 2024/11/23-02:16:09.008929 140025079141952 EVENT_LOG_v1 {"time_micros": 1732299369008894, "cf_name": "default", "job": 17197, "event": "table_file_creation", "file_number": 240364, "file_size": 37310132, "file_checksum": "", "file_checksum_func_name": "Unknown", "smallest_seqno": 3376774746, "largest_seqno": 3727820970, "table_properties": {"data_size": 31079008, "index_size": 1771328, "index_partitions": 0, "top_level_index_size": 0, "index_key_is_user_key": 0, "index_value_is_delta_encoded": 0, "filter_size": 0, "raw_key_size": 26070548, "raw_average_key_size": 28, "raw_value_size": 108147242, "raw_average_value_size": 116, "num_data_blocks": 1, "num_entries": 931091, "num_filter_entries": 0, "num_deletions": 0, "num_merge_operands": 0, "num_range_deletions": 0, "format_version": 0, "fixed_key_len": 28, "fixed_value_len": 18446744073709551615, "filter_policy": "", "column_family_name": "default", "column_family_id": 0, "comparator": "leveldb.BytewiseComparator", "merge_operator": "nullptr", "prefix_extractor_name": "nullptr", "property_collectors": "[Rdb_tbl_prop_coll_factory]", "compression": "FlatZip", "compression_options": "RS_Key0_Tag1-NestLoudsTrieDAWG_Mixed_XL_256_32_FL-DictZipBlobStore", "creation_time": 1732299365, "oldest_key_time": 0, "file_creation_time": 1732299365, "slow_compression_estimated_data_size": 0, "fast_compression_estimated_data_size": 0, "db_id": "d89b4006-2803-486e-b7a2-759bcf348921", "db_session_id": "U1ECJGOXSO4Y1P7ZZXV1", "orig_file_number": 240364, "seqno_to_time_mapping": "N/A", "__indexstats__": "[...1 records...]"}} 2024/11/23-02:16:09.051815 140025079141952 [top_zip_table_builder.cc:934] ToplingZipTableBuilder(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-17197/att-00/240365.sst) /dev/shm/ToplingTemp/Topling-378544-yciqcW 2024/11/23-02:16:09.887799 140025079141952 [top_zip_table_builder.cc:1352] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-17197/att-00/240365.sst): first pass time = 0.84's, 160.557'MB/sec 2024/11/23-02:16:11.183536 140025079141952 [top_zip_table_builder.cc:2772] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-17197/att-00/240365.sst): old prealloc_size = 147639500, real_size = 31043088 2024/11/23-02:16:11.184113 140025079141952 [top_zip_table_builder.cc:1773] BuildReorderMap(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-17197/att-00/240365.sst): index type = NestLoudsTrieDAWG_Mixed_XL_256_32_FL, store type = DictZipBlobStore usrkeys = 923736 prefix = 4 raw-key = 0.0148 GB zip-key = 0.0018 GB avg-key = 16.00 avg-zkey = 1.91 raw-val = 0.1084 GB zip-val = 0.0290 GB avg-val = 117.30 avg-zval = 31.44 2024/11/23-02:16:11.184877 140025079141952 [top_zip_table_builder.cc:1299] /storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-17197/att-00/240365.sst: sumWaitingMem = 0.002 GB, sumWorkingMem = 0.000 GB, reorder workingMem = 0.0023 GB, waited 0.000 sec, Key+Value = 0.134 GB 2024/11/23-02:16:12.042438 140025079141952 [top_zip_table_builder.cc:3100] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-17197/att-00/240365.sst): second pass time = 1.30's, 83.669'MB/sec, value only(80.7% of KV) wait indexing time = 0.00's, remap KeyValue time = 0.86's, 40.904'MB/sec (all stages of remap) Get OrderMap time = 0.00's, 3174.482'MB/sec (index lex order gen) rebuild zvType time = 0.73's, 0.316'MB/sec write SST data time = 0.13's, 276.326'MB/sec dict compress time = 4356233619.02's, 0.000'MB/sec z-dict build time = 0.00's, sample length = 1.083'MB, throughput = 31490.546'MB/sec zip my value time = 1.30's, unzip length = 0.108'GB zip my value throughput = 83.669'MB/sec zip pipeline throughput = 83.669'MB/sec entries = 923736 avg-key = 28.00 avg-zkey = 1.91 avg-val = 117.30 avg-zval = 31.44 usrkeys = 923736 avg-key = 20.00 avg-zkey = 1.91 avg-val = 124.30 avg-zval = 31.44 TagRS{ kinds = RS_Key0_Tag1 bytes = 0 } TagArray{ size = 923736 bytes = 3233088 } seq expand size = 6466152 multi value expand size = 0 cnt WriteAppend = 13 UnZipSize{ index = 0.0185 GB value = 0.1084 GB dict = 1.08 MB all = 0.1342 GB } __ZipSize{ index = 0.0018 GB value = 0.0290 GB dict = 1.08 MB all = 0.0351 GB } UnZip/Zip{ index = 10.4453 value = 3.7308 dict = 1.00 all = 3.8204 } Zip/UnZip{ index = 0.0957 value = 0.2680 dict = 1.00 all = 0.2618 } ---------------------------- total value len = 0.344237 GB avg = 0.124 KB (by entry num) total key len = 0.077889 GB avg = 0.028 KB total ukey len = 0.055635 GB avg = 0.020 KB total ukey num = 0.002781744 Billion total entry num = 0.002781744 Billion write speed all = 0.006740106 MB/sec (with seq num) write speed all = 0.006384776 MB/sec (without seq num) 2024/11/23-02:16:12.072220 140025079141952 [compaction_job.cc:1948] [default] [JOB 17197] Generated table #240365: 923736 keys, 35131814 bytes, temperature: kWarm 2024/11/23-02:16:12.072292 140025079141952 EVENT_LOG_v1 {"time_micros": 1732299372072260, "cf_name": "default", "job": 17197, "event": "table_file_creation", "file_number": 240365, "file_size": 35131814, "file_checksum": "", "file_checksum_func_name": "Unknown", "smallest_seqno": 3573692116, "largest_seqno": 3727819690, "table_properties": {"data_size": 29043264, "index_size": 1768704, "index_partitions": 0, "top_level_index_size": 0, "index_key_is_user_key": 0, "index_value_is_delta_encoded": 0, "filter_size": 0, "raw_key_size": 25864608, "raw_average_key_size": 28, "raw_value_size": 108353248, "raw_average_value_size": 117, "num_data_blocks": 1, "num_entries": 923736, "num_filter_entries": 0, "num_deletions": 0, "num_merge_operands": 0, "num_range_deletions": 0, "format_version": 0, "fixed_key_len": 28, "fixed_value_len": 18446744073709551615, "filter_policy": "", "column_family_name": "default", "column_family_id": 0, "comparator": "leveldb.BytewiseComparator", "merge_operator": "nullptr", "prefix_extractor_name": "nullptr", "property_collectors": "[Rdb_tbl_prop_coll_factory]", "compression": "FlatZip", "compression_options": "RS_Key0_Tag1-NestLoudsTrieDAWG_Mixed_XL_256_32_FL-DictZipBlobStore", "creation_time": 1732299369, "oldest_key_time": 0, "file_creation_time": 1732299369, "slow_compression_estimated_data_size": 0, "fast_compression_estimated_data_size": 0, "db_id": "d89b4006-2803-486e-b7a2-759bcf348921", "db_session_id": "U1ECJGOXSO4Y1P7ZZXV1", "orig_file_number": 240365, "seqno_to_time_mapping": "N/A", "__indexstats__": "[...1 records...]"}} 2024/11/23-02:16:12.109506 140025079141952 [top_zip_table_builder.cc:934] ToplingZipTableBuilder(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-17197/att-00/240366.sst) /dev/shm/ToplingTemp/Topling-378544-VMlAST 2024/11/23-02:16:12.898112 140025079141952 [top_zip_table_builder.cc:1352] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-17197/att-00/240366.sst): first pass time = 0.79's, 170.204'MB/sec 2024/11/23-02:16:14.236466 140025079141952 [top_zip_table_builder.cc:2772] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-17197/att-00/240366.sst): old prealloc_size = 147639500, real_size = 32328112 2024/11/23-02:16:14.237018 140025079141952 [top_zip_table_builder.cc:1773] BuildReorderMap(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-17197/att-00/240366.sst): index type = NestLoudsTrieDAWG_Mixed_XL_256_32_FL, store type = DictZipBlobStore usrkeys = 924598 prefix = 4 raw-key = 0.0148 GB zip-key = 0.0018 GB avg-key = 16.00 avg-zkey = 1.90 raw-val = 0.1083 GB zip-val = 0.0303 GB avg-val = 117.16 avg-zval = 32.81 2024/11/23-02:16:14.237753 140025079141952 [top_zip_table_builder.cc:1299] /storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-17197/att-00/240366.sst: sumWaitingMem = 0.002 GB, sumWorkingMem = 0.000 GB, reorder workingMem = 0.0023 GB, waited 0.000 sec, Key+Value = 0.134 GB 2024/11/23-02:16:15.133022 140025079141952 [top_zip_table_builder.cc:3100] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-17197/att-00/240366.sst): second pass time = 1.34's, 81.010'MB/sec, value only(80.7% of KV) wait indexing time = 0.00's, remap KeyValue time = 0.90's, 40.623'MB/sec (all stages of remap) Get OrderMap time = 0.00's, 3314.894'MB/sec (index lex order gen) rebuild zvType time = 0.77's, 0.300'MB/sec write SST data time = 0.13's, 289.581'MB/sec dict compress time = 4356233619.02's, 0.000'MB/sec z-dict build time = 0.00's, sample length = 1.081'MB, throughput = 32196.755'MB/sec zip my value time = 1.34's, unzip length = 0.108'GB zip my value throughput = 81.010'MB/sec zip pipeline throughput = 81.010'MB/sec entries = 924598 avg-key = 28.00 avg-zkey = 1.90 avg-val = 117.16 avg-zval = 32.81 usrkeys = 924598 avg-key = 20.00 avg-zkey = 1.90 avg-val = 124.16 avg-zval = 32.81 TagRS{ kinds = RS_Key0_Tag1 bytes = 0 } TagArray{ size = 924598 bytes = 3236112 } seq expand size = 6472186 multi value expand size = 0 cnt WriteAppend = 13 UnZipSize{ index = 0.0185 GB value = 0.1083 GB dict = 1.08 MB all = 0.1342 GB } __ZipSize{ index = 0.0018 GB value = 0.0303 GB dict = 1.08 MB all = 0.0364 GB } UnZip/Zip{ index = 10.5167 value = 3.5707 dict = 1.00 all = 3.6852 } Zip/UnZip{ index = 0.0951 value = 0.2801 dict = 1.00 all = 0.2714 } ---------------------------- total value len = 0.459038 GB avg = 0.124 KB (by entry num) total key len = 0.103778 GB avg = 0.028 KB total ukey len = 0.074127 GB avg = 0.020 KB total ukey num = 0.003706342 Billion total entry num = 0.003706342 Billion write speed all = 0.008986066 MB/sec (with seq num) write speed all = 0.008512655 MB/sec (without seq num) 2024/11/23-02:16:15.171234 140025079141952 [compaction_job.cc:1948] [default] [JOB 17197] Generated table #240366: 924598 keys, 36420288 bytes, temperature: kWarm 2024/11/23-02:16:15.171297 140025079141952 EVENT_LOG_v1 {"time_micros": 1732299375171265, "cf_name": "default", "job": 17197, "event": "table_file_creation", "file_number": 240366, "file_size": 36420288, "file_checksum": "", "file_checksum_func_name": "Unknown", "smallest_seqno": 3504428200, "largest_seqno": 3727821065, "table_properties": {"data_size": 30338464, "index_size": 1758336, "index_partitions": 0, "top_level_index_size": 0, "index_key_is_user_key": 0, "index_value_is_delta_encoded": 0, "filter_size": 0, "raw_key_size": 25888744, "raw_average_key_size": 28, "raw_value_size": 108328988, "raw_average_value_size": 117, "num_data_blocks": 1, "num_entries": 924598, "num_filter_entries": 0, "num_deletions": 0, "num_merge_operands": 0, "num_range_deletions": 0, "format_version": 0, "fixed_key_len": 28, "fixed_value_len": 18446744073709551615, "filter_policy": "", "column_family_name": "default", "column_family_id": 0, "comparator": "leveldb.BytewiseComparator", "merge_operator": "nullptr", "prefix_extractor_name": "nullptr", "property_collectors": "[Rdb_tbl_prop_coll_factory]", "compression": "FlatZip", "compression_options": "RS_Key0_Tag1-NestLoudsTrieDAWG_Mixed_XL_256_32_FL-DictZipBlobStore", "creation_time": 1732299372, "oldest_key_time": 0, "file_creation_time": 1732299372, "slow_compression_estimated_data_size": 0, "fast_compression_estimated_data_size": 0, "db_id": "d89b4006-2803-486e-b7a2-759bcf348921", "db_session_id": "U1ECJGOXSO4Y1P7ZZXV1", "orig_file_number": 240366, "seqno_to_time_mapping": "N/A", "__indexstats__": "[...1 records...]"}} 2024/11/23-02:16:15.209732 140025079141952 [top_zip_table_builder.cc:934] ToplingZipTableBuilder(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-17197/att-00/240367.sst) /dev/shm/ToplingTemp/Topling-378544-UHOjfX 2024/11/23-02:16:16.190441 140025079141952 [top_zip_table_builder.cc:1352] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-17197/att-00/240367.sst): first pass time = 0.98's, 136.863'MB/sec 2024/11/23-02:16:17.531863 140025079141952 [top_zip_table_builder.cc:2772] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-17197/att-00/240367.sst): old prealloc_size = 147639500, real_size = 32734800 2024/11/23-02:16:17.532398 140025079141952 [top_zip_table_builder.cc:1773] BuildReorderMap(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-17197/att-00/240367.sst): index type = NestLoudsTrieDAWG_Mixed_XL_256_32_FL, store type = DictZipBlobStore usrkeys = 925648 prefix = 4 raw-key = 0.0148 GB zip-key = 0.0018 GB avg-key = 16.00 avg-zkey = 1.90 raw-val = 0.1083 GB zip-val = 0.0307 GB avg-val = 117.00 avg-zval = 33.21 2024/11/23-02:16:17.533123 140025079141952 [top_zip_table_builder.cc:1299] /storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-17197/att-00/240367.sst: sumWaitingMem = 0.002 GB, sumWorkingMem = 0.000 GB, reorder workingMem = 0.0023 GB, waited 0.000 sec, Key+Value = 0.134 GB 2024/11/23-02:16:18.453122 140025079141952 [top_zip_table_builder.cc:3100] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-17197/att-00/240367.sst): second pass time = 1.34's, 80.779'MB/sec, value only(80.7% of KV) wait indexing time = 0.00's, remap KeyValue time = 0.92's, 39.978'MB/sec (all stages of remap) Get OrderMap time = 0.00's, 3398.979'MB/sec (index lex order gen) rebuild zvType time = 0.79's, 0.291'MB/sec write SST data time = 0.13's, 292.341'MB/sec dict compress time = 4356233619.02's, 0.000'MB/sec z-dict build time = 0.00's, sample length = 1.080'MB, throughput = 24325.309'MB/sec zip my value time = 1.34's, unzip length = 0.108'GB zip my value throughput = 80.779'MB/sec zip pipeline throughput = 80.779'MB/sec entries = 925648 avg-key = 28.00 avg-zkey = 1.90 avg-val = 117.00 avg-zval = 33.21 usrkeys = 925648 avg-key = 20.00 avg-zkey = 1.90 avg-val = 124.00 avg-zval = 33.21 TagRS{ kinds = RS_Key0_Tag1 bytes = 0 } TagArray{ size = 925648 bytes = 3239776 } seq expand size = 6479536 multi value expand size = 0 cnt WriteAppend = 13 UnZipSize{ index = 0.0185 GB value = 0.1083 GB dict = 1.08 MB all = 0.1342 GB } __ZipSize{ index = 0.0018 GB value = 0.0307 GB dict = 1.08 MB all = 0.0368 GB } UnZip/Zip{ index = 10.5153 value = 3.5228 dict = 1.00 all = 3.6443 } Zip/UnZip{ index = 0.0951 value = 0.2839 dict = 1.00 all = 0.2744 } ---------------------------- total value len = 0.573817 GB avg = 0.124 KB (by entry num) total key len = 0.129696 GB avg = 0.028 KB total ukey len = 0.092640 GB avg = 0.020 KB total ukey num = 0.004631990 Billion total entry num = 0.004631990 Billion write speed all = 0.011231881 MB/sec (with seq num) write speed all = 0.010640268 MB/sec (without seq num) 2024/11/23-02:16:18.485069 140025079141952 [compaction_job.cc:1948] [default] [JOB 17197] Generated table #240367: 925648 keys, 36829222 bytes, temperature: kWarm 2024/11/23-02:16:18.485132 140025079141952 EVENT_LOG_v1 {"time_micros": 1732299378485100, "cf_name": "default", "job": 17197, "event": "table_file_creation", "file_number": 240367, "file_size": 36829222, "file_checksum": "", "file_checksum_func_name": "Unknown", "smallest_seqno": 3587898319, "largest_seqno": 3727819727, "table_properties": {"data_size": 30742656, "index_size": 1760576, "index_partitions": 0, "top_level_index_size": 0, "index_key_is_user_key": 0, "index_value_is_delta_encoded": 0, "filter_size": 0, "raw_key_size": 25918144, "raw_average_key_size": 28, "raw_value_size": 108299584, "raw_average_value_size": 116, "num_data_blocks": 1, "num_entries": 925648, "num_filter_entries": 0, "num_deletions": 0, "num_merge_operands": 0, "num_range_deletions": 0, "format_version": 0, "fixed_key_len": 28, "fixed_value_len": 18446744073709551615, "filter_policy": "", "column_family_name": "default", "column_family_id": 0, "comparator": "leveldb.BytewiseComparator", "merge_operator": "nullptr", "prefix_extractor_name": "nullptr", "property_collectors": "[Rdb_tbl_prop_coll_factory]", "compression": "FlatZip", "compression_options": "RS_Key0_Tag1-NestLoudsTrieDAWG_Mixed_XL_256_32_FL-DictZipBlobStore", "creation_time": 1732299375, "oldest_key_time": 0, "file_creation_time": 1732299375, "slow_compression_estimated_data_size": 0, "fast_compression_estimated_data_size": 0, "db_id": "d89b4006-2803-486e-b7a2-759bcf348921", "db_session_id": "U1ECJGOXSO4Y1P7ZZXV1", "orig_file_number": 240367, "seqno_to_time_mapping": "N/A", "__indexstats__": "[...1 records...]"}} 2024/11/23-02:16:18.528996 140025079141952 [top_zip_table_builder.cc:934] ToplingZipTableBuilder(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-17197/att-00/240368.sst) /dev/shm/ToplingTemp/Topling-378544-2fLnXU 2024/11/23-02:16:19.364938 140025079141952 [top_zip_table_builder.cc:1352] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-17197/att-00/240368.sst): first pass time = 0.84's, 160.566'MB/sec 2024/11/23-02:16:20.704566 140025079141952 [top_zip_table_builder.cc:2772] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-17197/att-00/240368.sst): old prealloc_size = 147639500, real_size = 34450048 2024/11/23-02:16:20.705215 140025079141952 [top_zip_table_builder.cc:1773] BuildReorderMap(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-17197/att-00/240368.sst): index type = NestLoudsTrieDAWG_Mixed_XL_256_32_FL, store type = DictZipBlobStore usrkeys = 1086443 prefix = 4 raw-key = 0.0174 GB zip-key = 0.0021 GB avg-key = 16.00 avg-zkey = 1.93 raw-val = 0.1038 GB zip-val = 0.0321 GB avg-val = 95.54 avg-zval = 29.53 2024/11/23-02:16:20.705525 140025079141952 [top_zip_table_builder.cc:1299] /storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-17197/att-00/240368.sst: sumWaitingMem = 0.003 GB, sumWorkingMem = 0.000 GB, reorder workingMem = 0.0029 GB, waited 0.000 sec, Key+Value = 0.134 GB 2024/11/23-02:16:21.403046 140025079141952 [top_zip_table_builder.cc:3100] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-17197/att-00/240368.sst): second pass time = 1.34's, 77.526'MB/sec, value only(77.3% of KV) wait indexing time = 0.00's, remap KeyValue time = 0.70's, 51.812'MB/sec (all stages of remap) Get OrderMap time = 0.00's, 3351.011'MB/sec (index lex order gen) rebuild zvType time = 0.56's, 0.487'MB/sec write SST data time = 0.14's, 257.478'MB/sec dict compress time = 4356233619.02's, 0.000'MB/sec z-dict build time = 0.00's, sample length = 1.036'MB, throughput = 31146.478'MB/sec zip my value time = 1.34's, unzip length = 0.104'GB zip my value throughput = 77.526'MB/sec zip pipeline throughput = 77.526'MB/sec entries = 1086443 avg-key = 28.00 avg-zkey = 1.93 avg-val = 95.54 avg-zval = 29.53 usrkeys = 1086443 avg-key = 20.00 avg-zkey = 1.93 avg-val = 97.11 avg-zval = 29.53 TagRS{ kinds = RS_Key0_TagN bytes = 169840 } TagArray{ size = 244213 bytes = 793712 } seq expand size = 1709491 multi value expand size = 0 cnt WriteAppend = 16 UnZipSize{ index = 0.0217 GB value = 0.1038 GB dict = 1.04 MB all = 0.1342 GB } __ZipSize{ index = 0.0021 GB value = 0.0321 GB dict = 1.04 MB all = 0.0362 GB } UnZip/Zip{ index = 10.3795 value = 3.2351 dict = 1.00 all = 3.7088 } Zip/UnZip{ index = 0.0963 value = 0.3091 dict = 1.00 all = 0.2696 } ---------------------------- total value len = 0.679324 GB avg = 0.119 KB (by entry num) total key len = 0.160116 GB avg = 0.028 KB total ukey len = 0.114369 GB avg = 0.020 KB total ukey num = 0.005718433 Billion total entry num = 0.005718433 Billion write speed all = 0.013401388 MB/sec (with seq num) write speed all = 0.012671045 MB/sec (without seq num) 2024/11/23-02:16:21.438753 140025079141952 [compaction_job.cc:1948] [default] [JOB 17197] Generated table #240368: 1086443 keys, 36188810 bytes, temperature: kWarm 2024/11/23-02:16:21.438824 140025079141952 EVENT_LOG_v1 {"time_micros": 1732299381438790, "cf_name": "default", "job": 17197, "event": "table_file_creation", "file_number": 240368, "file_size": 36188810, "file_checksum": "", "file_checksum_func_name": "Unknown", "smallest_seqno": 0, "largest_seqno": 3727818116, "table_properties": {"data_size": 32084784, "index_size": 2093440, "index_partitions": 0, "top_level_index_size": 0, "index_key_is_user_key": 0, "index_value_is_delta_encoded": 0, "filter_size": 0, "raw_key_size": 30420404, "raw_average_key_size": 28, "raw_value_size": 103797463, "raw_average_value_size": 95, "num_data_blocks": 1, "num_entries": 1086443, "num_filter_entries": 0, "num_deletions": 0, "num_merge_operands": 0, "num_range_deletions": 0, "format_version": 0, "fixed_key_len": 28, "fixed_value_len": 18446744073709551615, "filter_policy": "", "column_family_name": "default", "column_family_id": 0, "comparator": "leveldb.BytewiseComparator", "merge_operator": "nullptr", "prefix_extractor_name": "nullptr", "property_collectors": "[Rdb_tbl_prop_coll_factory]", "compression": "FlatZip", "compression_options": "RS_Key0_TagN-NestLoudsTrieDAWG_Mixed_XL_256_32_FL-DictZipBlobStore", "creation_time": 1732299378, "oldest_key_time": 0, "file_creation_time": 1732299378, "slow_compression_estimated_data_size": 0, "fast_compression_estimated_data_size": 0, "db_id": "d89b4006-2803-486e-b7a2-759bcf348921", "db_session_id": "U1ECJGOXSO4Y1P7ZZXV1", "orig_file_number": 240368, "seqno_to_time_mapping": "N/A", "__indexstats__": "[...1 records...]"}} 2024/11/23-02:16:21.472173 140025079141952 [top_zip_table_builder.cc:934] ToplingZipTableBuilder(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-17197/att-00/240369.sst) /dev/shm/ToplingTemp/Topling-378544-XdlWkX 2024/11/23-02:16:22.271241 140025079141952 [top_zip_table_builder.cc:1352] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-17197/att-00/240369.sst): first pass time = 0.80's, 167.975'MB/sec 2024/11/23-02:16:23.695387 140025079141952 [top_zip_table_builder.cc:2772] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-17197/att-00/240369.sst): old prealloc_size = 147639500, real_size = 36105728 2024/11/23-02:16:23.695965 140025079141952 [top_zip_table_builder.cc:1773] BuildReorderMap(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-17197/att-00/240369.sst): index type = NestLoudsTrieDAWG_Mixed_XL_256_32_FL, store type = DictZipBlobStore usrkeys = 923669 prefix = 4 raw-key = 0.0148 GB zip-key = 0.0018 GB avg-key = 16.00 avg-zkey = 1.91 raw-val = 0.1084 GB zip-val = 0.0341 GB avg-val = 117.31 avg-zval = 36.92 2024/11/23-02:16:23.696370 140025079141952 [top_zip_table_builder.cc:1299] /storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-17197/att-00/240369.sst: sumWaitingMem = 0.002 GB, sumWorkingMem = 0.000 GB, reorder workingMem = 0.0023 GB, waited 0.000 sec, Key+Value = 0.134 GB 2024/11/23-02:16:24.455004 140025079141952 [top_zip_table_builder.cc:3100] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-17197/att-00/240369.sst): second pass time = 1.42's, 76.146'MB/sec, value only(80.7% of KV) wait indexing time = 0.00's, remap KeyValue time = 0.76's, 50.658'MB/sec (all stages of remap) Get OrderMap time = 0.00's, 3169.401'MB/sec (index lex order gen) rebuild zvType time = 0.63's, 0.365'MB/sec write SST data time = 0.13's, 304.788'MB/sec dict compress time = 4356233619.02's, 0.000'MB/sec z-dict build time = 0.00's, sample length = 1.082'MB, throughput = 33805.122'MB/sec zip my value time = 1.42's, unzip length = 0.108'GB zip my value throughput = 76.146'MB/sec zip pipeline throughput = 76.146'MB/sec entries = 923669 avg-key = 28.00 avg-zkey = 1.91 avg-val = 117.31 avg-zval = 36.92 usrkeys = 923669 avg-key = 20.00 avg-zkey = 1.91 avg-val = 120.17 avg-zval = 36.92 TagRS{ kinds = RS_Key0_TagN bytes = 144440 } TagArray{ size = 377496 bytes = 1368432 } seq expand size = 2642472 multi value expand size = 0 cnt WriteAppend = 15 UnZipSize{ index = 0.0185 GB value = 0.1084 GB dict = 1.08 MB all = 0.1342 GB } __ZipSize{ index = 0.0018 GB value = 0.0341 GB dict = 1.08 MB all = 0.0385 GB } UnZip/Zip{ index = 10.4450 value = 3.1770 dict = 1.00 all = 3.4880 } Zip/UnZip{ index = 0.0957 value = 0.3148 dict = 1.00 all = 0.2867 } ---------------------------- total value len = 0.790322 GB avg = 0.119 KB (by entry num) total key len = 0.185979 GB avg = 0.028 KB total ukey len = 0.132842 GB avg = 0.020 KB total ukey num = 0.006642102 Billion total entry num = 0.006642102 Billion write speed all = 0.015585557 MB/sec (with seq num) write speed all = 0.014737287 MB/sec (without seq num) 2024/11/23-02:16:24.486701 140025079141952 [compaction_job.cc:1948] [default] [JOB 17197] Generated table #240369: 923669 keys, 38479831 bytes, temperature: kWarm 2024/11/23-02:16:24.486763 140025079141952 EVENT_LOG_v1 {"time_micros": 1732299384486731, "cf_name": "default", "job": 17197, "event": "table_file_creation", "file_number": 240369, "file_size": 38479831, "file_checksum": "", "file_checksum_func_name": "Unknown", "smallest_seqno": 0, "largest_seqno": 3682896872, "table_properties": {"data_size": 34105968, "index_size": 1768640, "index_partitions": 0, "top_level_index_size": 0, "index_key_is_user_key": 0, "index_value_is_delta_encoded": 0, "filter_size": 0, "raw_key_size": 25862732, "raw_average_key_size": 28, "raw_value_size": 108355018, "raw_average_value_size": 117, "num_data_blocks": 1, "num_entries": 923669, "num_filter_entries": 0, "num_deletions": 0, "num_merge_operands": 0, "num_range_deletions": 0, "format_version": 0, "fixed_key_len": 28, "fixed_value_len": 18446744073709551615, "filter_policy": "", "column_family_name": "default", "column_family_id": 0, "comparator": "leveldb.BytewiseComparator", "merge_operator": "nullptr", "prefix_extractor_name": "nullptr", "property_collectors": "[Rdb_tbl_prop_coll_factory]", "compression": "FlatZip", "compression_options": "RS_Key0_TagN-NestLoudsTrieDAWG_Mixed_XL_256_32_FL-DictZipBlobStore", "creation_time": 1732299381, "oldest_key_time": 0, "file_creation_time": 1732299381, "slow_compression_estimated_data_size": 0, "fast_compression_estimated_data_size": 0, "db_id": "d89b4006-2803-486e-b7a2-759bcf348921", "db_session_id": "U1ECJGOXSO4Y1P7ZZXV1", "orig_file_number": 240369, "seqno_to_time_mapping": "N/A", "__indexstats__": "[...1 records...]"}} 2024/11/23-02:16:24.521870 140025079141952 [top_zip_table_builder.cc:934] ToplingZipTableBuilder(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-17197/att-00/240370.sst) /dev/shm/ToplingTemp/Topling-378544-2D7rSU 2024/11/23-02:16:25.351092 140025079141952 [top_zip_table_builder.cc:1352] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-17197/att-00/240370.sst): first pass time = 0.83's, 161.866'MB/sec 2024/11/23-02:16:26.690514 140025079141952 [top_zip_table_builder.cc:2772] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-17197/att-00/240370.sst): old prealloc_size = 147639500, real_size = 33388896 2024/11/23-02:16:26.691117 140025079141952 [top_zip_table_builder.cc:1773] BuildReorderMap(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-17197/att-00/240370.sst): index type = NestLoudsTrieDAWG_Mixed_XL_256_32_FL, store type = DictZipBlobStore usrkeys = 926887 prefix = 4 raw-key = 0.0148 GB zip-key = 0.0018 GB avg-key = 16.00 avg-zkey = 1.90 raw-val = 0.1083 GB zip-val = 0.0314 GB avg-val = 116.80 avg-zval = 33.87 2024/11/23-02:16:26.691871 140025079141952 [top_zip_table_builder.cc:1299] /storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-17197/att-00/240370.sst: sumWaitingMem = 0.002 GB, sumWorkingMem = 0.000 GB, reorder workingMem = 0.0023 GB, waited 0.000 sec, Key+Value = 0.134 GB 2024/11/23-02:16:27.638138 140025079141952 [top_zip_table_builder.cc:3100] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-17197/att-00/240370.sst): second pass time = 1.34's, 80.868'MB/sec, value only(80.7% of KV) wait indexing time = 0.00's, remap KeyValue time = 0.95's, 39.560'MB/sec (all stages of remap) Get OrderMap time = 0.00's, 3043.543'MB/sec (index lex order gen) rebuild zvType time = 0.82's, 0.283'MB/sec write SST data time = 0.13's, 294.256'MB/sec dict compress time = 4356233619.02's, 0.000'MB/sec z-dict build time = 0.00's, sample length = 1.080'MB, throughput = 32136.228'MB/sec zip my value time = 1.34's, unzip length = 0.108'GB zip my value throughput = 80.868'MB/sec zip pipeline throughput = 80.868'MB/sec entries = 926887 avg-key = 28.00 avg-zkey = 1.90 avg-val = 116.80 avg-zval = 33.87 usrkeys = 926887 avg-key = 20.00 avg-zkey = 1.90 avg-val = 123.80 avg-zval = 33.87 TagRS{ kinds = RS_Key0_Tag1 bytes = 0 } TagArray{ size = 926887 bytes = 3244112 } seq expand size = 6488209 multi value expand size = 0 cnt WriteAppend = 12 UnZipSize{ index = 0.0185 GB value = 0.1083 GB dict = 1.08 MB all = 0.1342 GB } __ZipSize{ index = 0.0018 GB value = 0.0314 GB dict = 1.08 MB all = 0.0375 GB } UnZip/Zip{ index = 10.5118 value = 3.4486 dict = 1.00 all = 3.5803 } Zip/UnZip{ index = 0.0951 value = 0.2900 dict = 1.00 all = 0.2793 } ---------------------------- total value len = 0.905075 GB avg = 0.120 KB (by entry num) total key len = 0.211932 GB avg = 0.028 KB total ukey len = 0.151380 GB avg = 0.020 KB total ukey num = 0.007568989 Billion total entry num = 0.007568989 Billion write speed all = 0.017830867 MB/sec (with seq num) write speed all = 0.016864272 MB/sec (without seq num) 2024/11/23-02:16:27.675055 140025079141952 [compaction_job.cc:1948] [default] [JOB 17197] Generated table #240370: 926887 keys, 37487540 bytes, temperature: kWarm 2024/11/23-02:16:27.675117 140025079141952 EVENT_LOG_v1 {"time_micros": 1732299387675087, "cf_name": "default", "job": 17197, "event": "table_file_creation", "file_number": 240370, "file_size": 37487540, "file_checksum": "", "file_checksum_func_name": "Unknown", "smallest_seqno": 3487497056, "largest_seqno": 3727821120, "table_properties": {"data_size": 31393488, "index_size": 1763520, "index_partitions": 0, "top_level_index_size": 0, "index_key_is_user_key": 0, "index_value_is_delta_encoded": 0, "filter_size": 0, "raw_key_size": 25952836, "raw_average_key_size": 28, "raw_value_size": 108264990, "raw_average_value_size": 116, "num_data_blocks": 1, "num_entries": 926887, "num_filter_entries": 0, "num_deletions": 0, "num_merge_operands": 0, "num_range_deletions": 0, "format_version": 0, "fixed_key_len": 28, "fixed_value_len": 18446744073709551615, "filter_policy": "", "column_family_name": "default", "column_family_id": 0, "comparator": "leveldb.BytewiseComparator", "merge_operator": "nullptr", "prefix_extractor_name": "nullptr", "property_collectors": "[Rdb_tbl_prop_coll_factory]", "compression": "FlatZip", "compression_options": "RS_Key0_Tag1-NestLoudsTrieDAWG_Mixed_XL_256_32_FL-DictZipBlobStore", "creation_time": 1732299384, "oldest_key_time": 0, "file_creation_time": 1732299384, "slow_compression_estimated_data_size": 0, "fast_compression_estimated_data_size": 0, "db_id": "d89b4006-2803-486e-b7a2-759bcf348921", "db_session_id": "U1ECJGOXSO4Y1P7ZZXV1", "orig_file_number": 240370, "seqno_to_time_mapping": "N/A", "__indexstats__": "[...1 records...]"}} 2024/11/23-02:16:27.717914 140025079141952 [top_zip_table_builder.cc:934] ToplingZipTableBuilder(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-17197/att-00/240371.sst) /dev/shm/ToplingTemp/Topling-378544-b95DIU 2024/11/23-02:16:28.558943 140025079141952 [top_zip_table_builder.cc:1352] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-17197/att-00/240371.sst): first pass time = 0.84's, 159.595'MB/sec 2024/11/23-02:16:29.866998 140025079141952 [top_zip_table_builder.cc:2772] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-17197/att-00/240371.sst): old prealloc_size = 147639500, real_size = 32846096 2024/11/23-02:16:29.867554 140025079141952 [top_zip_table_builder.cc:1773] BuildReorderMap(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-17197/att-00/240371.sst): index type = NestLoudsTrieDAWG_Mixed_XL_256_32_FL, store type = DictZipBlobStore usrkeys = 934504 prefix = 4 raw-key = 0.0150 GB zip-key = 0.0018 GB avg-key = 16.00 avg-zkey = 1.90 raw-val = 0.1081 GB zip-val = 0.0308 GB avg-val = 115.62 avg-zval = 33.00 2024/11/23-02:16:29.869722 140025079141952 [top_zip_table_builder.cc:1299] /storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-17197/att-00/240371.sst: sumWaitingMem = 0.002 GB, sumWorkingMem = 0.000 GB, reorder workingMem = 0.0023 GB, waited 0.000 sec, Key+Value = 0.134 GB 2024/11/23-02:16:30.814706 140025079141952 [top_zip_table_builder.cc:3100] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-17197/att-00/240371.sst): second pass time = 1.31's, 82.646'MB/sec, value only(80.5% of KV) wait indexing time = 0.00's, remap KeyValue time = 0.95's, 39.131'MB/sec (all stages of remap) Get OrderMap time = 0.00's, 3393.390'MB/sec (index lex order gen) rebuild zvType time = 0.82's, 0.286'MB/sec write SST data time = 0.13's, 285.490'MB/sec dict compress time = 4356233619.02's, 0.000'MB/sec z-dict build time = 0.00's, sample length = 1.078'MB, throughput = 27985.208'MB/sec zip my value time = 1.31's, unzip length = 0.108'GB zip my value throughput = 82.646'MB/sec zip pipeline throughput = 82.646'MB/sec entries = 934504 avg-key = 28.00 avg-zkey = 1.90 avg-val = 115.62 avg-zval = 33.00 usrkeys = 934504 avg-key = 20.00 avg-zkey = 1.90 avg-val = 122.62 avg-zval = 33.00 TagRS{ kinds = RS_Key0_Tag1 bytes = 0 } TagArray{ size = 934504 bytes = 3387584 } seq expand size = 6541528 multi value expand size = 0 cnt WriteAppend = 13 UnZipSize{ index = 0.0187 GB value = 0.1081 GB dict = 1.08 MB all = 0.1342 GB } __ZipSize{ index = 0.0018 GB value = 0.0308 GB dict = 1.08 MB all = 0.0371 GB } UnZip/Zip{ index = 10.5116 value = 3.5043 dict = 1.00 all = 3.6193 } Zip/UnZip{ index = 0.0951 value = 0.2854 dict = 1.00 all = 0.2763 } ---------------------------- total value len = 1.019668 GB avg = 0.120 KB (by entry num) total key len = 0.238098 GB avg = 0.028 KB total ukey len = 0.170070 GB avg = 0.020 KB total ukey num = 0.008503493 Billion total entry num = 0.008503493 Billion write speed all = 0.020076801 MB/sec (with seq num) write speed all = 0.018990921 MB/sec (without seq num) 2024/11/23-02:16:30.846921 140025079141952 [compaction_job.cc:1948] [default] [JOB 17197] Generated table #240371: 934504 keys, 37084350 bytes, temperature: kWarm 2024/11/23-02:16:30.846983 140025079141952 EVENT_LOG_v1 {"time_micros": 1732299390846951, "cf_name": "default", "job": 17197, "event": "table_file_creation", "file_number": 240371, "file_size": 37084350, "file_checksum": "", "file_checksum_func_name": "Unknown", "smallest_seqno": 3273701103, "largest_seqno": 3727821151, "table_properties": {"data_size": 30834240, "index_size": 1778048, "index_partitions": 0, "top_level_index_size": 0, "index_key_is_user_key": 0, "index_value_is_delta_encoded": 0, "filter_size": 0, "raw_key_size": 26166112, "raw_average_key_size": 28, "raw_value_size": 108051648, "raw_average_value_size": 115, "num_data_blocks": 1, "num_entries": 934504, "num_filter_entries": 0, "num_deletions": 0, "num_merge_operands": 0, "num_range_deletions": 0, "format_version": 0, "fixed_key_len": 28, "fixed_value_len": 18446744073709551615, "filter_policy": "", "column_family_name": "default", "column_family_id": 0, "comparator": "leveldb.BytewiseComparator", "merge_operator": "nullptr", "prefix_extractor_name": "nullptr", "property_collectors": "[Rdb_tbl_prop_coll_factory]", "compression": "FlatZip", "compression_options": "RS_Key0_Tag1-NestLoudsTrieDAWG_Mixed_XL_256_32_FL-DictZipBlobStore", "creation_time": 1732299387, "oldest_key_time": 0, "file_creation_time": 1732299387, "slow_compression_estimated_data_size": 0, "fast_compression_estimated_data_size": 0, "db_id": "d89b4006-2803-486e-b7a2-759bcf348921", "db_session_id": "U1ECJGOXSO4Y1P7ZZXV1", "orig_file_number": 240371, "seqno_to_time_mapping": "N/A", "__indexstats__": "[...1 records...]"}} 2024/11/23-02:16:30.882109 140025079141952 [top_zip_table_builder.cc:934] ToplingZipTableBuilder(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-17197/att-00/240372.sst) /dev/shm/ToplingTemp/Topling-378544-53VJsT 2024/11/23-02:16:31.777576 140025079141952 [top_zip_table_builder.cc:1352] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-17197/att-00/240372.sst): first pass time = 0.90's, 149.892'MB/sec 2024/11/23-02:16:33.107252 140025079141952 [top_zip_table_builder.cc:2772] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-17197/att-00/240372.sst): old prealloc_size = 147639500, real_size = 32659728 2024/11/23-02:16:33.107840 140025079141952 [top_zip_table_builder.cc:1773] BuildReorderMap(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-17197/att-00/240372.sst): index type = NestLoudsTrieDAWG_Mixed_XL_256_32_FL, store type = DictZipBlobStore usrkeys = 926740 prefix = 4 raw-key = 0.0148 GB zip-key = 0.0018 GB avg-key = 16.00 avg-zkey = 1.93 raw-val = 0.1083 GB zip-val = 0.0306 GB avg-val = 116.83 avg-zval = 33.06 2024/11/23-02:16:33.108633 140025079141952 [top_zip_table_builder.cc:1299] /storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-17197/att-00/240372.sst: sumWaitingMem = 0.002 GB, sumWorkingMem = 0.000 GB, reorder workingMem = 0.0023 GB, waited 0.000 sec, Key+Value = 0.134 GB 2024/11/23-02:16:34.015708 140025079141952 [top_zip_table_builder.cc:3100] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-17197/att-00/240372.sst): second pass time = 1.33's, 81.470'MB/sec, value only(80.7% of KV) wait indexing time = 0.00's, remap KeyValue time = 0.91's, 40.590'MB/sec (all stages of remap) Get OrderMap time = 0.00's, 3147.975'MB/sec (index lex order gen) rebuild zvType time = 0.78's, 0.298'MB/sec write SST data time = 0.13's, 285.301'MB/sec dict compress time = 4356233619.02's, 0.000'MB/sec z-dict build time = 0.00's, sample length = 1.080'MB, throughput = 32611.368'MB/sec zip my value time = 1.33's, unzip length = 0.108'GB zip my value throughput = 81.470'MB/sec zip pipeline throughput = 81.470'MB/sec entries = 926740 avg-key = 28.00 avg-zkey = 1.93 avg-val = 116.83 avg-zval = 33.06 usrkeys = 926740 avg-key = 20.00 avg-zkey = 1.93 avg-val = 123.83 avg-zval = 33.06 TagRS{ kinds = RS_Key0_Tag1 bytes = 0 } TagArray{ size = 926740 bytes = 3359440 } seq expand size = 6487180 multi value expand size = 0 cnt WriteAppend = 16 UnZipSize{ index = 0.0185 GB value = 0.1083 GB dict = 1.08 MB all = 0.1342 GB } __ZipSize{ index = 0.0018 GB value = 0.0306 GB dict = 1.08 MB all = 0.0369 GB } UnZip/Zip{ index = 10.3753 value = 3.5334 dict = 1.00 all = 3.6399 } Zip/UnZip{ index = 0.0964 value = 0.2830 dict = 1.00 all = 0.2747 } ---------------------------- total value len = 1.134424 GB avg = 0.120 KB (by entry num) total key len = 0.264047 GB avg = 0.028 KB total ukey len = 0.188605 GB avg = 0.020 KB total ukey num = 0.009430233 Billion total entry num = 0.009430233 Billion write speed all = 0.022321631 MB/sec (with seq num) write speed all = 0.021117469 MB/sec (without seq num) 2024/11/23-02:16:34.047850 140025079141952 [compaction_job.cc:1948] [default] [JOB 17197] Generated table #240372: 926740 keys, 36873678 bytes, temperature: kWarm 2024/11/23-02:16:34.047912 140025079141952 EVENT_LOG_v1 {"time_micros": 1732299394047880, "cf_name": "default", "job": 17197, "event": "table_file_creation", "file_number": 240372, "file_size": 36873678, "file_checksum": "", "file_checksum_func_name": "Unknown", "smallest_seqno": 3281127126, "largest_seqno": 3727821171, "table_properties": {"data_size": 30641408, "index_size": 1786432, "index_partitions": 0, "top_level_index_size": 0, "index_key_is_user_key": 0, "index_value_is_delta_encoded": 0, "filter_size": 0, "raw_key_size": 25948720, "raw_average_key_size": 28, "raw_value_size": 108269016, "raw_average_value_size": 116, "num_data_blocks": 1, "num_entries": 926740, "num_filter_entries": 0, "num_deletions": 0, "num_merge_operands": 0, "num_range_deletions": 0, "format_version": 0, "fixed_key_len": 28, "fixed_value_len": 18446744073709551615, "filter_policy": "", "column_family_name": "default", "column_family_id": 0, "comparator": "leveldb.BytewiseComparator", "merge_operator": "nullptr", "prefix_extractor_name": "nullptr", "property_collectors": "[Rdb_tbl_prop_coll_factory]", "compression": "FlatZip", "compression_options": "RS_Key0_Tag1-NestLoudsTrieDAWG_Mixed_XL_256_32_FL-DictZipBlobStore", "creation_time": 1732299390, "oldest_key_time": 0, "file_creation_time": 1732299390, "slow_compression_estimated_data_size": 0, "fast_compression_estimated_data_size": 0, "db_id": "d89b4006-2803-486e-b7a2-759bcf348921", "db_session_id": "U1ECJGOXSO4Y1P7ZZXV1", "orig_file_number": 240372, "seqno_to_time_mapping": "N/A", "__indexstats__": "[...1 records...]"}} 2024/11/23-02:16:34.088189 140025079141952 [top_zip_table_builder.cc:934] ToplingZipTableBuilder(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-17197/att-00/240373.sst) /dev/shm/ToplingTemp/Topling-378544-7sV1xX 2024/11/23-02:16:35.005353 140025079141952 [top_zip_table_builder.cc:1352] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-17197/att-00/240373.sst): first pass time = 0.92's, 146.346'MB/sec 2024/11/23-02:16:36.329907 140025079141952 [top_zip_table_builder.cc:2772] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-17197/att-00/240373.sst): old prealloc_size = 147639500, real_size = 32893024 2024/11/23-02:16:36.330450 140025079141952 [top_zip_table_builder.cc:1773] BuildReorderMap(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-17197/att-00/240373.sst): index type = NestLoudsTrieDAWG_Mixed_XL_256_32_FL, store type = DictZipBlobStore usrkeys = 929247 prefix = 4 raw-key = 0.0149 GB zip-key = 0.0018 GB avg-key = 16.00 avg-zkey = 1.93 raw-val = 0.1082 GB zip-val = 0.0309 GB avg-val = 116.44 avg-zval = 33.22 2024/11/23-02:16:36.332614 140025079141952 [top_zip_table_builder.cc:1299] /storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-17197/att-00/240373.sst: sumWaitingMem = 0.002 GB, sumWorkingMem = 0.000 GB, reorder workingMem = 0.0023 GB, waited 0.000 sec, Key+Value = 0.134 GB 2024/11/23-02:16:37.289409 140025079141952 [top_zip_table_builder.cc:3100] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-17197/att-00/240373.sst): second pass time = 1.32's, 81.726'MB/sec, value only(80.6% of KV) wait indexing time = 0.00's, remap KeyValue time = 0.96's, 38.681'MB/sec (all stages of remap) Get OrderMap time = 0.00's, 3520.564'MB/sec (index lex order gen) rebuild zvType time = 0.83's, 0.280'MB/sec write SST data time = 0.13's, 283.894'MB/sec dict compress time = 4356233619.02's, 0.000'MB/sec z-dict build time = 0.00's, sample length = 1.079'MB, throughput = 35146.337'MB/sec zip my value time = 1.32's, unzip length = 0.108'GB zip my value throughput = 81.726'MB/sec zip pipeline throughput = 81.726'MB/sec entries = 929247 avg-key = 28.00 avg-zkey = 1.93 avg-val = 116.44 avg-zval = 33.22 usrkeys = 929247 avg-key = 20.00 avg-zkey = 1.93 avg-val = 123.44 avg-zval = 33.22 TagRS{ kinds = RS_Key0_Tag1 bytes = 0 } TagArray{ size = 929247 bytes = 3368528 } seq expand size = 6504729 multi value expand size = 0 cnt WriteAppend = 15 UnZipSize{ index = 0.0186 GB value = 0.1082 GB dict = 1.08 MB all = 0.1342 GB } __ZipSize{ index = 0.0018 GB value = 0.0309 GB dict = 1.08 MB all = 0.0371 GB } UnZip/Zip{ index = 10.3759 value = 3.5051 dict = 1.00 all = 3.6163 } Zip/UnZip{ index = 0.0964 value = 0.2853 dict = 1.00 all = 0.2765 } ---------------------------- total value len = 1.249128 GB avg = 0.121 KB (by entry num) total key len = 0.290065 GB avg = 0.028 KB total ukey len = 0.207190 GB avg = 0.020 KB total ukey num = 0.010359480 Billion total entry num = 0.010359480 Billion write speed all = 0.024566484 MB/sec (with seq num) write speed all = 0.023243734 MB/sec (without seq num) 2024/11/23-02:16:37.321873 140025079141952 [compaction_job.cc:1948] [default] [JOB 17197] Generated table #240373: 929247 keys, 37114250 bytes, temperature: kWarm 2024/11/23-02:16:37.321945 140025079141952 EVENT_LOG_v1 {"time_micros": 1732299397321904, "cf_name": "default", "job": 17197, "event": "table_file_creation", "file_number": 240373, "file_size": 37114250, "file_checksum": "", "file_checksum_func_name": "Unknown", "smallest_seqno": 3369194706, "largest_seqno": 3727821204, "table_properties": {"data_size": 30869392, "index_size": 1791168, "index_partitions": 0, "top_level_index_size": 0, "index_key_is_user_key": 0, "index_value_is_delta_encoded": 0, "filter_size": 0, "raw_key_size": 26018916, "raw_average_key_size": 28, "raw_value_size": 108198882, "raw_average_value_size": 116, "num_data_blocks": 1, "num_entries": 929247, "num_filter_entries": 0, "num_deletions": 0, "num_merge_operands": 0, "num_range_deletions": 0, "format_version": 0, "fixed_key_len": 28, "fixed_value_len": 18446744073709551615, "filter_policy": "", "column_family_name": "default", "column_family_id": 0, "comparator": "leveldb.BytewiseComparator", "merge_operator": "nullptr", "prefix_extractor_name": "nullptr", "property_collectors": "[Rdb_tbl_prop_coll_factory]", "compression": "FlatZip", "compression_options": "RS_Key0_Tag1-NestLoudsTrieDAWG_Mixed_XL_256_32_FL-DictZipBlobStore", "creation_time": 1732299394, "oldest_key_time": 0, "file_creation_time": 1732299394, "slow_compression_estimated_data_size": 0, "fast_compression_estimated_data_size": 0, "db_id": "d89b4006-2803-486e-b7a2-759bcf348921", "db_session_id": "U1ECJGOXSO4Y1P7ZZXV1", "orig_file_number": 240373, "seqno_to_time_mapping": "N/A", "__indexstats__": "[...1 records...]"}} 2024/11/23-02:16:37.362316 140025079141952 [top_zip_table_builder.cc:934] ToplingZipTableBuilder(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-17197/att-00/240374.sst) /dev/shm/ToplingTemp/Topling-378544-Myz8kX 2024/11/23-02:16:38.232232 140025079141952 [top_zip_table_builder.cc:1352] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-17197/att-00/240374.sst): first pass time = 0.87's, 154.295'MB/sec 2024/11/23-02:16:39.563278 140025079141952 [top_zip_table_builder.cc:2772] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-17197/att-00/240374.sst): old prealloc_size = 147639500, real_size = 32706800 2024/11/23-02:16:39.563807 140025079141952 [top_zip_table_builder.cc:1773] BuildReorderMap(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-17197/att-00/240374.sst): index type = NestLoudsTrieDAWG_Mixed_XL_256_32_FL, store type = DictZipBlobStore usrkeys = 923429 prefix = 4 raw-key = 0.0148 GB zip-key = 0.0018 GB avg-key = 16.00 avg-zkey = 1.91 raw-val = 0.1084 GB zip-val = 0.0307 GB avg-val = 117.35 avg-zval = 33.25 2024/11/23-02:16:39.565893 140025079141952 [top_zip_table_builder.cc:1299] /storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-17197/att-00/240374.sst: sumWaitingMem = 0.002 GB, sumWorkingMem = 0.000 GB, reorder workingMem = 0.0023 GB, waited 0.000 sec, Key+Value = 0.134 GB 2024/11/23-02:16:40.478620 140025079141952 [top_zip_table_builder.cc:3100] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-17197/att-00/240374.sst): second pass time = 1.33's, 81.449'MB/sec, value only(80.7% of KV) wait indexing time = 0.00's, remap KeyValue time = 0.92's, 40.326'MB/sec (all stages of remap) Get OrderMap time = 0.00's, 3456.467'MB/sec (index lex order gen) rebuild zvType time = 0.79's, 0.294'MB/sec write SST data time = 0.13's, 285.240'MB/sec dict compress time = 4356233619.02's, 0.000'MB/sec z-dict build time = 0.00's, sample length = 1.082'MB, throughput = 34572.533'MB/sec zip my value time = 1.33's, unzip length = 0.108'GB zip my value throughput = 81.449'MB/sec zip pipeline throughput = 81.449'MB/sec entries = 923429 avg-key = 28.00 avg-zkey = 1.91 avg-val = 117.35 avg-zval = 33.25 usrkeys = 923429 avg-key = 20.00 avg-zkey = 1.91 avg-val = 124.35 avg-zval = 33.25 TagRS{ kinds = RS_Key0_Tag1 bytes = 0 } TagArray{ size = 923429 bytes = 3347440 } seq expand size = 6464003 multi value expand size = 0 cnt WriteAppend = 15 UnZipSize{ index = 0.0185 GB value = 0.1084 GB dict = 1.08 MB all = 0.1342 GB } __ZipSize{ index = 0.0018 GB value = 0.0307 GB dict = 1.08 MB all = 0.0369 GB } UnZip/Zip{ index = 10.4445 value = 3.5288 dict = 1.00 all = 3.6362 } Zip/UnZip{ index = 0.0957 value = 0.2834 dict = 1.00 all = 0.2750 } ---------------------------- total value len = 1.363954 GB avg = 0.121 KB (by entry num) total key len = 0.315921 GB avg = 0.028 KB total ukey len = 0.225658 GB avg = 0.020 KB total ukey num = 0.011282909 Billion total entry num = 0.011282909 Billion write speed all = 0.026810489 MB/sec (with seq num) write speed all = 0.025369905 MB/sec (without seq num) 2024/11/23-02:16:40.512125 140025079141952 [compaction_job.cc:1948] [default] [JOB 17197] Generated table #240374: 923429 keys, 36911666 bytes, temperature: kWarm 2024/11/23-02:16:40.512186 140025079141952 EVENT_LOG_v1 {"time_micros": 1732299400512154, "cf_name": "default", "job": 17197, "event": "table_file_creation", "file_number": 240374, "file_size": 36911666, "file_checksum": "", "file_checksum_func_name": "Unknown", "smallest_seqno": 3339428666, "largest_seqno": 3727820213, "table_properties": {"data_size": 30707488, "index_size": 1768256, "index_partitions": 0, "top_level_index_size": 0, "index_key_is_user_key": 0, "index_value_is_delta_encoded": 0, "filter_size": 0, "raw_key_size": 25856012, "raw_average_key_size": 28, "raw_value_size": 108361826, "raw_average_value_size": 117, "num_data_blocks": 1, "num_entries": 923429, "num_filter_entries": 0, "num_deletions": 0, "num_merge_operands": 0, "num_range_deletions": 0, "format_version": 0, "fixed_key_len": 28, "fixed_value_len": 18446744073709551615, "filter_policy": "", "column_family_name": "default", "column_family_id": 0, "comparator": "leveldb.BytewiseComparator", "merge_operator": "nullptr", "prefix_extractor_name": "nullptr", "property_collectors": "[Rdb_tbl_prop_coll_factory]", "compression": "FlatZip", "compression_options": "RS_Key0_Tag1-NestLoudsTrieDAWG_Mixed_XL_256_32_FL-DictZipBlobStore", "creation_time": 1732299397, "oldest_key_time": 0, "file_creation_time": 1732299397, "slow_compression_estimated_data_size": 0, "fast_compression_estimated_data_size": 0, "db_id": "d89b4006-2803-486e-b7a2-759bcf348921", "db_session_id": "U1ECJGOXSO4Y1P7ZZXV1", "orig_file_number": 240374, "seqno_to_time_mapping": "N/A", "__indexstats__": "[...1 records...]"}} 2024/11/23-02:16:40.551186 140025079141952 [top_zip_table_builder.cc:934] ToplingZipTableBuilder(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-17197/att-00/240375.sst) /dev/shm/ToplingTemp/Topling-378544-9H5OuV 2024/11/23-02:16:41.406382 140025079141952 [top_zip_table_builder.cc:1352] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-17197/att-00/240375.sst): first pass time = 0.86's, 156.950'MB/sec 2024/11/23-02:16:42.741348 140025079141952 [top_zip_table_builder.cc:2772] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-17197/att-00/240375.sst): old prealloc_size = 147639500, real_size = 34309040 2024/11/23-02:16:42.741913 140025079141952 [top_zip_table_builder.cc:1773] BuildReorderMap(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-17197/att-00/240375.sst): index type = NestLoudsTrieDAWG_Mixed_XL_256_32_FL, store type = DictZipBlobStore usrkeys = 931983 prefix = 4 raw-key = 0.0149 GB zip-key = 0.0018 GB avg-key = 16.00 avg-zkey = 1.90 raw-val = 0.1081 GB zip-val = 0.0323 GB avg-val = 116.01 avg-zval = 34.66 2024/11/23-02:16:42.744018 140025079141952 [top_zip_table_builder.cc:1299] /storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-17197/att-00/240375.sst: sumWaitingMem = 0.002 GB, sumWorkingMem = 0.000 GB, reorder workingMem = 0.0023 GB, waited 0.000 sec, Key+Value = 0.134 GB 2024/11/23-02:16:43.650152 140025079141952 [top_zip_table_builder.cc:3100] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-17197/att-00/240375.sst): second pass time = 1.33's, 81.071'MB/sec, value only(80.6% of KV) wait indexing time = 0.00's, remap KeyValue time = 0.91's, 42.406'MB/sec (all stages of remap) Get OrderMap time = 0.00's, 3260.730'MB/sec (index lex order gen) rebuild zvType time = 0.78's, 0.299'MB/sec write SST data time = 0.13's, 296.613'MB/sec dict compress time = 4356233619.02's, 0.000'MB/sec z-dict build time = 0.00's, sample length = 1.078'MB, throughput = 32073.010'MB/sec zip my value time = 1.33's, unzip length = 0.108'GB zip my value throughput = 81.071'MB/sec zip pipeline throughput = 81.071'MB/sec entries = 931983 avg-key = 28.00 avg-zkey = 1.90 avg-val = 116.01 avg-zval = 34.66 usrkeys = 931983 avg-key = 20.00 avg-zkey = 1.90 avg-val = 123.01 avg-zval = 34.66 TagRS{ kinds = RS_Key0_Tag1 bytes = 0 } TagArray{ size = 931983 bytes = 3378448 } seq expand size = 6523881 multi value expand size = 0 cnt WriteAppend = 13 UnZipSize{ index = 0.0186 GB value = 0.1081 GB dict = 1.08 MB all = 0.1342 GB } __ZipSize{ index = 0.0018 GB value = 0.0323 GB dict = 1.08 MB all = 0.0385 GB } UnZip/Zip{ index = 10.5124 value = 3.3472 dict = 1.00 all = 3.4827 } Zip/UnZip{ index = 0.0951 value = 0.2988 dict = 1.00 all = 0.2871 } ---------------------------- total value len = 1.478600 GB avg = 0.121 KB (by entry num) total key len = 0.342017 GB avg = 0.028 KB total ukey len = 0.244298 GB avg = 0.020 KB total ukey num = 0.012214892 Billion total entry num = 0.012214892 Billion write speed all = 0.029055229 MB/sec (with seq num) write speed all = 0.027495729 MB/sec (without seq num) 2024/11/23-02:16:43.684325 140025079141952 [compaction_job.cc:1948] [default] [JOB 17197] Generated table #240375: 931983 keys, 38538406 bytes, temperature: kWarm 2024/11/23-02:16:43.684386 140025079141952 EVENT_LOG_v1 {"time_micros": 1732299403684354, "cf_name": "default", "job": 17197, "event": "table_file_creation", "file_number": 240375, "file_size": 38538406, "file_checksum": "", "file_checksum_func_name": "Unknown", "smallest_seqno": 3369353416, "largest_seqno": 3727821255, "table_properties": {"data_size": 32302752, "index_size": 1773120, "index_partitions": 0, "top_level_index_size": 0, "index_key_is_user_key": 0, "index_value_is_delta_encoded": 0, "filter_size": 0, "raw_key_size": 26095524, "raw_average_key_size": 28, "raw_value_size": 108122270, "raw_average_value_size": 116, "num_data_blocks": 1, "num_entries": 931983, "num_filter_entries": 0, "num_deletions": 0, "num_merge_operands": 0, "num_range_deletions": 0, "format_version": 0, "fixed_key_len": 28, "fixed_value_len": 18446744073709551615, "filter_policy": "", "column_family_name": "default", "column_family_id": 0, "comparator": "leveldb.BytewiseComparator", "merge_operator": "nullptr", "prefix_extractor_name": "nullptr", "property_collectors": "[Rdb_tbl_prop_coll_factory]", "compression": "FlatZip", "compression_options": "RS_Key0_Tag1-NestLoudsTrieDAWG_Mixed_XL_256_32_FL-DictZipBlobStore", "creation_time": 1732299400, "oldest_key_time": 0, "file_creation_time": 1732299400, "slow_compression_estimated_data_size": 0, "fast_compression_estimated_data_size": 0, "db_id": "d89b4006-2803-486e-b7a2-759bcf348921", "db_session_id": "U1ECJGOXSO4Y1P7ZZXV1", "orig_file_number": 240375, "seqno_to_time_mapping": "N/A", "__indexstats__": "[...1 records...]"}} 2024/11/23-02:16:43.722978 140025079141952 [top_zip_table_builder.cc:934] ToplingZipTableBuilder(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-17197/att-00/240376.sst) /dev/shm/ToplingTemp/Topling-378544-9seavT 2024/11/23-02:16:44.137599 140025079141952 [top_zip_table_builder.cc:1352] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-17197/att-00/240376.sst): first pass time = 0.41's, 252.229'MB/sec 2024/11/23-02:16:45.183794 140025079141952 [top_zip_table_builder.cc:2772] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-17197/att-00/240376.sst): old prealloc_size = 147639500, real_size = 26629568 2024/11/23-02:16:45.184263 140025079141952 [top_zip_table_builder.cc:1773] BuildReorderMap(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-17197/att-00/240376.sst): index type = NestLoudsTrieDAWG_Mixed_XL_256_32_FL, store type = DictZipBlobStore usrkeys = 725737 prefix = 4 raw-key = 0.0116 GB zip-key = 0.0014 GB avg-key = 16.00 avg-zkey = 1.92 raw-val = 0.0842 GB zip-val = 0.0251 GB avg-val = 116.09 avg-zval = 34.53 2024/11/23-02:16:45.184767 140025079141952 [top_zip_table_builder.cc:1299] /storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-17197/att-00/240376.sst: sumWaitingMem = 0.002 GB, sumWorkingMem = 0.000 GB, reorder workingMem = 0.0018 GB, waited 0.000 sec, Key+Value = 0.105 GB 2024/11/23-02:16:45.872364 140025079141952 [top_zip_table_builder.cc:3100] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-17197/att-00/240376.sst): second pass time = 1.05's, 80.581'MB/sec, value only(80.6% of KV) wait indexing time = 0.00's, remap KeyValue time = 0.69's, 42.671'MB/sec (all stages of remap) Get OrderMap time = 0.00's, 3213.047'MB/sec (index lex order gen) rebuild zvType time = 0.59's, 0.309'MB/sec write SST data time = 0.10's, 288.406'MB/sec dict compress time = 4356233619.02's, 0.000'MB/sec z-dict build time = 0.00's, sample length = 0.845'MB, throughput = 26995.815'MB/sec zip my value time = 1.05's, unzip length = 0.084'GB zip my value throughput = 80.581'MB/sec zip pipeline throughput = 80.581'MB/sec entries = 725737 avg-key = 28.00 avg-zkey = 1.92 avg-val = 116.09 avg-zval = 34.53 usrkeys = 725737 avg-key = 20.00 avg-zkey = 1.92 avg-val = 121.32 avg-zval = 34.53 TagRS{ kinds = RS_Key0_TagN bytes = 113480 } TagArray{ size = 542740 bytes = 1967440 } seq expand size = 3799180 multi value expand size = 0 cnt WriteAppend = 17 UnZipSize{ index = 0.0145 GB value = 0.0842 GB dict = 0.85 MB all = 0.1046 GB } __ZipSize{ index = 0.0014 GB value = 0.0251 GB dict = 0.85 MB all = 0.0294 GB } UnZip/Zip{ index = 10.4417 value = 3.3622 dict = 1.00 all = 3.5590 } Zip/UnZip{ index = 0.0958 value = 0.2974 dict = 1.00 all = 0.2810 } ---------------------------- total value len = 1.566649 GB avg = 0.121 KB (by entry num) total key len = 0.362338 GB avg = 0.028 KB total ukey len = 0.258813 GB avg = 0.020 KB total ukey num = 0.012940629 Billion total entry num = 0.012940629 Billion write speed all = 0.030783608 MB/sec (with seq num) write speed all = 0.029131511 MB/sec (without seq num) 2024/11/23-02:16:45.899962 140025079141952 [compaction_job.cc:1948] [default] [JOB 17197] Generated table #240376: 725737 keys, 29381951 bytes, temperature: kWarm 2024/11/23-02:16:45.900022 140025079141952 EVENT_LOG_v1 {"time_micros": 1732299405899990, "cf_name": "default", "job": 17197, "event": "table_file_creation", "file_number": 240376, "file_size": 29381951, "file_checksum": "", "file_checksum_func_name": "Unknown", "smallest_seqno": 0, "largest_seqno": 3727821273, "table_properties": {"data_size": 25057840, "index_size": 1390080, "index_partitions": 0, "top_level_index_size": 0, "index_key_is_user_key": 0, "index_value_is_delta_encoded": 0, "filter_size": 0, "raw_key_size": 20320636, "raw_average_key_size": 28, "raw_value_size": 84249790, "raw_average_value_size": 116, "num_data_blocks": 1, "num_entries": 725737, "num_filter_entries": 0, "num_deletions": 0, "num_merge_operands": 0, "num_range_deletions": 0, "format_version": 0, "fixed_key_len": 28, "fixed_value_len": 18446744073709551615, "filter_policy": "", "column_family_name": "default", "column_family_id": 0, "comparator": "leveldb.BytewiseComparator", "merge_operator": "nullptr", "prefix_extractor_name": "nullptr", "property_collectors": "[Rdb_tbl_prop_coll_factory]", "compression": "FlatZip", "compression_options": "RS_Key0_TagN-NestLoudsTrieDAWG_Mixed_XL_256_32_FL-DictZipBlobStore", "creation_time": 1732299403, "oldest_key_time": 0, "file_creation_time": 1732299403, "slow_compression_estimated_data_size": 0, "fast_compression_estimated_data_size": 0, "db_id": "d89b4006-2803-486e-b7a2-759bcf348921", "db_session_id": "U1ECJGOXSO4Y1P7ZZXV1", "orig_file_number": 240376, "seqno_to_time_mapping": "N/A", "__indexstats__": "[...1 records...]"}} 2024/11/23-02:16:45.925324 140025079141952 [compaction_job.cc:703] job-17197: subcompact[0], size: 0.510439 G, files: /storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-17197/att-00 [240363,240364,240365,240366,240367,240368,240369,240370,240371,240372,240373,240374,240375,240376] 2024/11/23-02:16:46.012720 140025079141952 (Original Log Time 2024/11/23-02:16:02.496113) EVENT_LOG_v1 {"time_micros": 1732299362496082, "job": 17197, "event": "compaction_started", "compaction_reason": "FilesMarkedForCompaction", "files_L2": [240290], "files_L3": [240359, 240360, 240258, 240259, 240260, 240261, 240246, 240247, 240248, 240249, 240250, 240251, 240252, 240253, 240331], "score": 0.999951, "input_data_size": 531733417, "oldest_snapshot_seqno": 3728228280} 2024/11/23-02:16:46.012724 140025079141952 (Original Log Time 2024/11/23-02:16:46.011593) [compaction_job.cc:2022] [default] [JOB 17197] Compacted 1@2 + 15@3 files to L3 => 510439274 bytes 2024/11/23-02:16:46.012725 140025079141952 (Original Log Time 2024/11/23-02:16:46.012272) [compaction_job.cc:1224] [default] compacted to: files[0 0 0 14 0 0 0] max score 0.02, MB/sec: 12.2 rd, 11.8 wr, level 3, files in(1, 15) out(14 +0 blob) MB in(27.1, 480.0 +0.0 blob) out(486.8 +0.0 blob), read-write-amplify(36.7) write-amplify(18.0) OK, records in: 13007911, records dropped: 67282 output_compression: Snappy 2024/11/23-02:16:46.012731 140025079141952 (Original Log Time 2024/11/23-02:16:46.012699) EVENT_LOG_v1 {"time_micros": 1732299406012296, "cf": "default", "job": 17197, "event": "compaction_finished", "compaction_time_micros": 43429221, "compaction_time_cpu_micros": 19732331, "output_level": 3, "num_output_files": 14, "total_output_size": 510439274, "num_input_records": 13007911, "num_output_records": 12940629, "num_subcompactions": 1, "output_compression": "Snappy", "num_single_delete_mismatches": 0, "num_single_delete_fallthrough": 0, "file_write_nanos": 314588884, "file_range_sync_nanos": 0, "file_fsync_nanos": 13893, "file_prepare_write_nanos": 31846, "lsm_state": [0, 0, 0, 14, 0, 0, 0]} 2024/11/23-02:16:46.147050 140025079141952 DEBG dcompact_worker.cpp:240: HttpPost: 200 OK: url = http://nlb-7zny4kaa1poal01o64.cn-shenzhen.nlb.aliyuncs.com:8443, body = {"instanceId":"nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137","labourId":"","dbId":"d89b4006-2803-486e-b7a2-759bcf348921","dbStarts":1732256054,"starts":1732299362,"executesMs":43654,"compactionJobId":17197,"attempt":0,"compactionInputRawBytes":1858955811,"compactionInputZipBytes":531733417,"compactionOutputRawBytes":0,"compactionOutputZipBytes":510439274,"headers":{"Accept":"*/*","Connection":"close","Host":"aliyun-topling-saas.topling.cn:18443","RemoteIp":"120.24.192.169","X-Forwarded-For":"120.24.192.169","region":"cn-shenzhen","token":"e6474c481bd4415abc537135cc03626b","instance-token":"e6474c481bd4415abc537135cc03626b","provider":"aliyun","CloudMarketInstanceId":"si-59c0ae5c955845588903","MeteringAssit":"","Transfer-Encoding":"chunked","traceparent":"00-d3895e27546e4e9359e45dd775f3a32e-468ce0b3bf3bc7f1-00"}}, response = 2024/11/23-02:16:46.147118 140025079141952 INFO dcompact_worker.cpp:1459: finish /worker/dcompact-log/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/2024-11-22T14.14.14/.rocksdb/job-17197/att-00: olev 3, work 43.655 s, result 6.395 ms, install 1.159 ms, input{raw 1.731 GiB zip 507.101 MiB}