2024/11/23-06:10:28.969277 140025230210624 DEBG dcompact_worker.cpp:2214: /storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-20785/att-00: fork to child time = 0.028871 sec 2024/11/23-06:10:28.970324 140025230210624 DEBG dcompact_worker.cpp:1013: Beg SerDeRead: /storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-20785/att-00 2024/11/23-06:10:28.979763 140025230210624 DEBG dcompact_worker.cpp:1033: End SerDeRead: /storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-20785/att-00 2024/11/23-06:10:28.981281 140025230210624 TRAC dcompact_worker.cpp:1083: INFO_LOG_LEVEL: rpc = INFO_LEVEL, env_var = undefined 2024/11/23-06:10:28.981682 140025230210624 [WARN] [column_family.cc:386] multiple cf_paths/db_paths and level_compaction_dynamic_level_bytes can't be used together 2024/11/23-06:10:29.039918 140025230210624 [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-20785/att-00/MANIFEST-206347 2024/11/23-06:10:29.073797 140025230210624 [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-20785/att-00/MANIFEST-206347 succeeded,manifest_file_number is 206347, next_file_number is 247985, last_sequence is 3828430069, log_number is 0,prev_log_number is 0,max_column_family is 0,min_log_number_to_keep is 247831 2024/11/23-06:10:29.073810 140025230210624 [version_set.cc:6266] Column family [default] (ID 0), log number is 0 2024/11/23-06:10:29.073951 140025230210624 [version_set.cc:5721] Creating manifest 247985 2024/11/23-06:10:29.110650 140025230210624 DEBG dcompact_worker.cpp:1218: /storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-20785/att-00: bottommost_level: fake = 1, rpc = 0 2024/11/23-06:10:29.134223 140025230210624 [compaction_job.cc:2437] [default] [JOB 20785] Compacting 1@2 + 9@3 files to L3, score 1.00, subcompactions 1 : 1 2024/11/23-06:10:29.134244 140025230210624 [compaction_job.cc:2445] [default]: Compaction start summary: Base version 15551 Base level 2, inputs: [247978(31MB)], [247916(40MB) 247917(40MB) 247918(40MB) 247919(40MB) 247920(40MB) 247921(40MB) 247922(40MB) 247923(40MB) 247924(40MB)] 2024/11/23-06:10:29.148663 140025230210624 [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-06:10:29.148706 140025230210624 [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-06:10:29.148734 140025230210624 [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-06:10:29.148743 140025230210624 [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-06:10:29.148759 140025230210624 [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-06:10:29.737272 140025230210624 [top_zip_table_builder.cc:934] ToplingZipTableBuilder(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-20785/att-00/247986.sst) /dev/shm/ToplingTemp/Topling-430742-e84xAS 2024/11/23-06:10:30.103530 140025230210624 [top_zip_table_builder.cc:1352] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-20785/att-00/247986.sst): first pass time = 0.37's, 366.506'MB/sec 2024/11/23-06:10:32.479772 140025230210624 [top_zip_table_builder.cc:2772] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-20785/att-00/247986.sst): old prealloc_size = 147639500, real_size = 40780144 2024/11/23-06:10:32.479903 140025230210624 [top_zip_table_builder.cc:1773] BuildReorderMap(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-20785/att-00/247986.sst): index type = NestLoudsTrieDAWG_Mixed_XL_256_32_FL, store type = DictZipBlobStore usrkeys = 129432 prefix = 4 raw-key = 0.0010 GB zip-key = 0.0002 GB avg-key = 8.00 avg-zkey = 1.80 raw-val = 0.1316 GB zip-val = 0.0405 GB avg-val =1016.98 avg-zval = 313.01 2024/11/23-06:10:32.480077 140025230210624 [top_zip_table_builder.cc:1299] /storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-20785/att-00/247986.sst: sumWaitingMem = 0.000 GB, sumWorkingMem = 0.000 GB, reorder workingMem = 0.0003 GB, waited 0.000 sec, Key+Value = 0.134 GB 2024/11/23-06:10:32.639527 140025230210624 [top_zip_table_builder.cc:3100] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-20785/att-00/247986.sst): second pass time = 2.38's, 55.412'MB/sec, value only(98.1% of KV) wait indexing time = 0.00's, remap KeyValue time = 0.16's, 266.755'MB/sec (all stages of remap) Get OrderMap time = 0.00's, 2307.674'MB/sec (index lex order gen) rebuild zvType time = 0.11's, 0.300'MB/sec write SST data time = 0.05's, 821.463'MB/sec dict compress time = 4356233619.02's, 0.000'MB/sec z-dict build time = 0.00's, sample length = 1.378'MB, throughput = 39797.989'MB/sec zip my value time = 2.38's, unzip length = 0.132'GB zip my value throughput = 55.412'MB/sec zip pipeline throughput = 132.761'MB/sec entries = 129432 avg-key = 20.00 avg-zkey = 1.80 avg-val = 1016.98 avg-zval = 313.01 usrkeys = 129432 avg-key = 12.00 avg-zkey = 1.80 avg-val = 1023.98 avg-zval = 313.01 TagRS{ kinds = RS_Key0_Tag1 bytes = 0 } TagArray{ size = 129432 bytes = 485392 } seq expand size = 906024 multi value expand size = 0 cnt WriteAppend = 13 UnZipSize{ index = 0.0016 GB value = 0.1316 GB dict = 1.38 MB all = 0.1342 GB } __ZipSize{ index = 0.0002 GB value = 0.0405 GB dict = 1.38 MB all = 0.0426 GB } UnZip/Zip{ index = 6.6525 value = 3.2490 dict = 1.00 all = 3.1497 } Zip/UnZip{ index = 0.1503 value = 0.3078 dict = 1.00 all = 0.3175 } ---------------------------- total value len = 0.132536 GB avg = 1.024 KB (by entry num) total key len = 0.002589 GB avg = 0.020 KB total ukey len = 0.001553 GB avg = 0.012 KB total ukey num = 0.000129432 Billion total entry num = 0.000129432 Billion write speed all = 0.001761964 MB/sec (with seq num) write speed all = 0.001748462 MB/sec (without seq num) 2024/11/23-06:10:32.682390 140025230210624 [compaction_job.cc:1948] [default] [JOB 20785] Generated table #247986: 129432 keys, 42612630 bytes, temperature: kWarm 2024/11/23-06:10:32.682473 140025230210624 EVENT_LOG_v1 {"time_micros": 1732313432682420, "cf_name": "default", "job": 20785, "event": "table_file_creation", "file_number": 247986, "file_size": 42612630, "file_checksum": "", "file_checksum_func_name": "Unknown", "smallest_seqno": 3237868195, "largest_seqno": 3828395082, "table_properties": {"data_size": 40514144, "index_size": 233472, "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": 2588640, "raw_average_key_size": 20, "raw_value_size": 131629570, "raw_average_value_size": 1016, "num_data_blocks": 1, "num_entries": 129432, "num_filter_entries": 0, "num_deletions": 0, "num_merge_operands": 0, "num_range_deletions": 0, "format_version": 0, "fixed_key_len": 20, "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": 1732313429, "oldest_key_time": 0, "file_creation_time": 1732313429, "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": 247986, "seqno_to_time_mapping": "N/A", "__indexstats__": "[...1 records...]"}} 2024/11/23-06:10:32.720240 140025230210624 [top_zip_table_builder.cc:934] ToplingZipTableBuilder(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-20785/att-00/247987.sst) /dev/shm/ToplingTemp/Topling-430742-9bm5mV 2024/11/23-06:10:33.214379 140025230210624 [top_zip_table_builder.cc:1352] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-20785/att-00/247987.sst): first pass time = 0.49's, 271.639'MB/sec 2024/11/23-06:10:34.793302 140025230210624 [top_zip_table_builder.cc:2772] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-20785/att-00/247987.sst): old prealloc_size = 147639500, real_size = 40730832 2024/11/23-06:10:34.793446 140025230210624 [top_zip_table_builder.cc:1773] BuildReorderMap(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-20785/att-00/247987.sst): index type = NestLoudsTrieDAWG_Mixed_XL_256_32_FL, store type = DictZipBlobStore usrkeys = 129433 prefix = 4 raw-key = 0.0010 GB zip-key = 0.0002 GB avg-key = 8.00 avg-zkey = 1.82 raw-val = 0.1316 GB zip-val = 0.0405 GB avg-val =1016.97 avg-zval = 312.62 2024/11/23-06:10:34.793621 140025230210624 [top_zip_table_builder.cc:1299] /storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-20785/att-00/247987.sst: sumWaitingMem = 0.000 GB, sumWorkingMem = 0.000 GB, reorder workingMem = 0.0003 GB, waited 0.000 sec, Key+Value = 0.134 GB 2024/11/23-06:10:34.975268 140025230210624 [top_zip_table_builder.cc:3100] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-20785/att-00/247987.sst): second pass time = 1.58's, 83.408'MB/sec, value only(98.1% of KV) wait indexing time = 0.00's, remap KeyValue time = 0.18's, 233.916'MB/sec (all stages of remap) Get OrderMap time = 0.00's, 2006.927'MB/sec (index lex order gen) rebuild zvType time = 0.13's, 0.255'MB/sec write SST data time = 0.05's, 776.303'MB/sec dict compress time = 4356233619.02's, 0.000'MB/sec z-dict build time = 0.00's, sample length = 1.378'MB, throughput = 41474.690'MB/sec zip my value time = 1.58's, unzip length = 0.132'GB zip my value throughput = 83.408'MB/sec zip pipeline throughput = 202.459'MB/sec entries = 129433 avg-key = 20.00 avg-zkey = 1.82 avg-val = 1016.97 avg-zval = 312.62 usrkeys = 129433 avg-key = 12.00 avg-zkey = 1.82 avg-val = 1023.97 avg-zval = 312.62 TagRS{ kinds = RS_Key0_Tag1 bytes = 0 } TagArray{ size = 129433 bytes = 485392 } seq expand size = 906031 multi value expand size = 0 cnt WriteAppend = 13 UnZipSize{ index = 0.0016 GB value = 0.1316 GB dict = 1.38 MB all = 0.1342 GB } __ZipSize{ index = 0.0002 GB value = 0.0405 GB dict = 1.38 MB all = 0.0426 GB } UnZip/Zip{ index = 6.6019 value = 3.2531 dict = 1.00 all = 3.1534 } Zip/UnZip{ index = 0.1515 value = 0.3074 dict = 1.00 all = 0.3171 } ---------------------------- total value len = 0.265072 GB avg = 1.024 KB (by entry num) total key len = 0.005177 GB avg = 0.020 KB total ukey len = 0.003106 GB avg = 0.012 KB total ukey num = 0.000258865 Billion total entry num = 0.000258865 Billion write speed all = 0.003523827 MB/sec (with seq num) write speed all = 0.003496824 MB/sec (without seq num) 2024/11/23-06:10:35.013473 140025230210624 [compaction_job.cc:1948] [default] [JOB 20785] Generated table #247987: 129433 keys, 42563720 bytes, temperature: kWarm 2024/11/23-06:10:35.013546 140025230210624 EVENT_LOG_v1 {"time_micros": 1732313435013505, "cf_name": "default", "job": 20785, "event": "table_file_creation", "file_number": 247987, "file_size": 42563720, "file_checksum": "", "file_checksum_func_name": "Unknown", "smallest_seqno": 3237874654, "largest_seqno": 3828392358, "table_properties": {"data_size": 40463040, "index_size": 235264, "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": 2588660, "raw_average_key_size": 20, "raw_value_size": 131629974, "raw_average_value_size": 1016, "num_data_blocks": 1, "num_entries": 129433, "num_filter_entries": 0, "num_deletions": 0, "num_merge_operands": 0, "num_range_deletions": 0, "format_version": 0, "fixed_key_len": 20, "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": 1732313432, "oldest_key_time": 0, "file_creation_time": 1732313432, "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": 247987, "seqno_to_time_mapping": "N/A", "__indexstats__": "[...1 records...]"}} 2024/11/23-06:10:35.053764 140025230210624 [top_zip_table_builder.cc:934] ToplingZipTableBuilder(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-20785/att-00/247988.sst) /dev/shm/ToplingTemp/Topling-430742-2DMBQT 2024/11/23-06:10:35.546544 140025230210624 [top_zip_table_builder.cc:1352] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-20785/att-00/247988.sst): first pass time = 0.49's, 272.396'MB/sec 2024/11/23-06:10:36.811857 140025230210624 [top_zip_table_builder.cc:2772] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-20785/att-00/247988.sst): old prealloc_size = 147639500, real_size = 40870736 2024/11/23-06:10:36.811997 140025230210624 [top_zip_table_builder.cc:1773] BuildReorderMap(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-20785/att-00/247988.sst): index type = NestLoudsTrieDAWG_Mixed_XL_256_32_FL, store type = DictZipBlobStore usrkeys = 129434 prefix = 4 raw-key = 0.0010 GB zip-key = 0.0002 GB avg-key = 8.00 avg-zkey = 1.76 raw-val = 0.1316 GB zip-val = 0.0406 GB avg-val =1016.96 avg-zval = 313.75 2024/11/23-06:10:36.812156 140025230210624 [top_zip_table_builder.cc:1299] /storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-20785/att-00/247988.sst: sumWaitingMem = 0.000 GB, sumWorkingMem = 0.000 GB, reorder workingMem = 0.0003 GB, waited 0.000 sec, Key+Value = 0.134 GB 2024/11/23-06:10:36.971448 140025230210624 [top_zip_table_builder.cc:3100] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-20785/att-00/247988.sst): second pass time = 1.26's, 104.084'MB/sec, value only(98.1% of KV) wait indexing time = 0.00's, remap KeyValue time = 0.16's, 267.619'MB/sec (all stages of remap) Get OrderMap time = 0.00's, 1868.460'MB/sec (index lex order gen) rebuild zvType time = 0.11's, 0.298'MB/sec write SST data time = 0.05's, 839.917'MB/sec dict compress time = 4356233619.02's, 0.000'MB/sec z-dict build time = 0.00's, sample length = 1.378'MB, throughput = 36007.552'MB/sec zip my value time = 1.26's, unzip length = 0.132'GB zip my value throughput = 104.084'MB/sec zip pipeline throughput = 180.681'MB/sec entries = 129434 avg-key = 20.00 avg-zkey = 1.76 avg-val = 1016.96 avg-zval = 313.75 usrkeys = 129434 avg-key = 12.00 avg-zkey = 1.76 avg-val = 1023.96 avg-zval = 313.75 TagRS{ kinds = RS_Key0_Tag1 bytes = 0 } TagArray{ size = 129434 bytes = 485392 } seq expand size = 906038 multi value expand size = 0 cnt WriteAppend = 13 UnZipSize{ index = 0.0016 GB value = 0.1316 GB dict = 1.38 MB all = 0.1342 GB } __ZipSize{ index = 0.0002 GB value = 0.0406 GB dict = 1.38 MB all = 0.0427 GB } UnZip/Zip{ index = 6.8171 value = 3.2413 dict = 1.00 all = 3.1430 } Zip/UnZip{ index = 0.1467 value = 0.3085 dict = 1.00 all = 0.3182 } ---------------------------- total value len = 0.397607 GB avg = 1.024 KB (by entry num) total key len = 0.007766 GB avg = 0.020 KB total ukey len = 0.004660 GB avg = 0.012 KB total ukey num = 0.000388299 Billion total entry num = 0.000388299 Billion write speed all = 0.005285594 MB/sec (with seq num) write speed all = 0.005245091 MB/sec (without seq num) 2024/11/23-06:10:37.009140 140025230210624 [compaction_job.cc:1948] [default] [JOB 20785] Generated table #247988: 129434 keys, 42703404 bytes, temperature: kWarm 2024/11/23-06:10:37.009214 140025230210624 EVENT_LOG_v1 {"time_micros": 1732313437009179, "cf_name": "default", "job": 20785, "event": "table_file_creation", "file_number": 247988, "file_size": 42703404, "file_checksum": "", "file_checksum_func_name": "Unknown", "smallest_seqno": 3237868596, "largest_seqno": 3828390165, "table_properties": {"data_size": 40610368, "index_size": 227840, "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": 2588680, "raw_average_key_size": 20, "raw_value_size": 131629096, "raw_average_value_size": 1016, "num_data_blocks": 1, "num_entries": 129434, "num_filter_entries": 0, "num_deletions": 0, "num_merge_operands": 0, "num_range_deletions": 0, "format_version": 0, "fixed_key_len": 20, "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": 1732313435, "oldest_key_time": 0, "file_creation_time": 1732313435, "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": 247988, "seqno_to_time_mapping": "N/A", "__indexstats__": "[...1 records...]"}} 2024/11/23-06:10:37.045002 140025230210624 [top_zip_table_builder.cc:934] ToplingZipTableBuilder(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-20785/att-00/247989.sst) /dev/shm/ToplingTemp/Topling-430742-XQj7WQ 2024/11/23-06:10:37.500887 140025230210624 [top_zip_table_builder.cc:1352] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-20785/att-00/247989.sst): first pass time = 0.46's, 294.443'MB/sec 2024/11/23-06:10:38.999394 140025230210624 [top_zip_table_builder.cc:2772] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-20785/att-00/247989.sst): old prealloc_size = 147639500, real_size = 40586192 2024/11/23-06:10:38.999534 140025230210624 [top_zip_table_builder.cc:1773] BuildReorderMap(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-20785/att-00/247989.sst): index type = NestLoudsTrieDAWG_Mixed_XL_256_32_FL, store type = DictZipBlobStore usrkeys = 129430 prefix = 4 raw-key = 0.0010 GB zip-key = 0.0002 GB avg-key = 8.00 avg-zkey = 1.86 raw-val = 0.1316 GB zip-val = 0.0403 GB avg-val =1017.00 avg-zval = 311.46 2024/11/23-06:10:38.999704 140025230210624 [top_zip_table_builder.cc:1299] /storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-20785/att-00/247989.sst: sumWaitingMem = 0.000 GB, sumWorkingMem = 0.000 GB, reorder workingMem = 0.0003 GB, waited 0.000 sec, Key+Value = 0.134 GB 2024/11/23-06:10:39.219114 140025230210624 [top_zip_table_builder.cc:3100] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-20785/att-00/247989.sst): second pass time = 1.50's, 87.883'MB/sec, value only(98.1% of KV) wait indexing time = 0.00's, remap KeyValue time = 0.22's, 193.065'MB/sec (all stages of remap) Get OrderMap time = 0.00's, 2026.226'MB/sec (index lex order gen) rebuild zvType time = 0.17's, 0.196'MB/sec write SST data time = 0.05's, 782.907'MB/sec dict compress time = 4356233619.02's, 0.000'MB/sec z-dict build time = 0.00's, sample length = 1.378'MB, throughput = 871.549'MB/sec zip my value time = 1.50's, unzip length = 0.132'GB zip my value throughput = 87.883'MB/sec zip pipeline throughput = 192.101'MB/sec entries = 129430 avg-key = 20.00 avg-zkey = 1.86 avg-val = 1017.00 avg-zval = 311.46 usrkeys = 129430 avg-key = 12.00 avg-zkey = 1.86 avg-val = 1024.00 avg-zval = 311.46 TagRS{ kinds = RS_Key0_Tag1 bytes = 0 } TagArray{ size = 129430 bytes = 485376 } seq expand size = 906010 multi value expand size = 0 cnt WriteAppend = 13 UnZipSize{ index = 0.0016 GB value = 0.1316 GB dict = 1.38 MB all = 0.1342 GB } __ZipSize{ index = 0.0002 GB value = 0.0403 GB dict = 1.38 MB all = 0.0424 GB } UnZip/Zip{ index = 6.4474 value = 3.2652 dict = 1.00 all = 3.1641 } Zip/UnZip{ index = 0.1551 value = 0.3063 dict = 1.00 all = 0.3160 } ---------------------------- total value len = 0.530142 GB avg = 1.024 KB (by entry num) total key len = 0.010355 GB avg = 0.020 KB total ukey len = 0.006213 GB avg = 0.012 KB total ukey num = 0.000517729 Billion total entry num = 0.000517729 Billion write speed all = 0.007047254 MB/sec (with seq num) write speed all = 0.006993251 MB/sec (without seq num) 2024/11/23-06:10:39.256292 140025230210624 [compaction_job.cc:1948] [default] [JOB 20785] Generated table #247989: 129430 keys, 42418611 bytes, temperature: kWarm 2024/11/23-06:10:39.256355 140025230210624 EVENT_LOG_v1 {"time_micros": 1732313439256323, "cf_name": "default", "job": 20785, "event": "table_file_creation", "file_number": 247989, "file_size": 42418611, "file_checksum": "", "file_checksum_func_name": "Unknown", "smallest_seqno": 3237869923, "largest_seqno": 3828397687, "table_properties": {"data_size": 40312768, "index_size": 240896, "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": 2588600, "raw_average_key_size": 20, "raw_value_size": 131629742, "raw_average_value_size": 1016, "num_data_blocks": 1, "num_entries": 129430, "num_filter_entries": 0, "num_deletions": 0, "num_merge_operands": 0, "num_range_deletions": 0, "format_version": 0, "fixed_key_len": 20, "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": 1732313437, "oldest_key_time": 0, "file_creation_time": 1732313437, "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": 247989, "seqno_to_time_mapping": "N/A", "__indexstats__": "[...1 records...]"}} 2024/11/23-06:10:39.293936 140025230210624 [top_zip_table_builder.cc:934] ToplingZipTableBuilder(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-20785/att-00/247990.sst) /dev/shm/ToplingTemp/Topling-430742-3FC0yS 2024/11/23-06:10:39.757063 140025230210624 [top_zip_table_builder.cc:1352] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-20785/att-00/247990.sst): first pass time = 0.46's, 289.832'MB/sec 2024/11/23-06:10:40.948448 140025230210624 [top_zip_table_builder.cc:2772] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-20785/att-00/247990.sst): old prealloc_size = 147639500, real_size = 40923360 2024/11/23-06:10:40.948571 140025230210624 [top_zip_table_builder.cc:1773] BuildReorderMap(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-20785/att-00/247990.sst): index type = NestLoudsTrieDAWG_Mixed_XL_256_32_FL, store type = DictZipBlobStore usrkeys = 129429 prefix = 4 raw-key = 0.0010 GB zip-key = 0.0002 GB avg-key = 8.00 avg-zkey = 1.78 raw-val = 0.1316 GB zip-val = 0.0407 GB avg-val =1017.00 avg-zval = 314.15 2024/11/23-06:10:40.948721 140025230210624 [top_zip_table_builder.cc:1299] /storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-20785/att-00/247990.sst: sumWaitingMem = 0.000 GB, sumWorkingMem = 0.000 GB, reorder workingMem = 0.0003 GB, waited 0.000 sec, Key+Value = 0.134 GB 2024/11/23-06:10:41.104367 140025230210624 [top_zip_table_builder.cc:3100] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-20785/att-00/247990.sst): second pass time = 1.19's, 110.543'MB/sec, value only(98.1% of KV) wait indexing time = 0.00's, remap KeyValue time = 0.16's, 274.245'MB/sec (all stages of remap) Get OrderMap time = 0.00's, 2370.785'MB/sec (index lex order gen) rebuild zvType time = 0.11's, 0.305'MB/sec write SST data time = 0.05's, 860.123'MB/sec dict compress time = 4356233619.02's, 0.000'MB/sec z-dict build time = 0.00's, sample length = 1.378'MB, throughput = 45002.710'MB/sec zip my value time = 1.19's, unzip length = 0.132'GB zip my value throughput = 110.543'MB/sec zip pipeline throughput = 192.108'MB/sec entries = 129429 avg-key = 20.00 avg-zkey = 1.78 avg-val = 1017.00 avg-zval = 314.15 usrkeys = 129429 avg-key = 12.00 avg-zkey = 1.78 avg-val = 1024.00 avg-zval = 314.15 TagRS{ kinds = RS_Key0_Tag1 bytes = 0 } TagArray{ size = 129429 bytes = 485376 } seq expand size = 906003 multi value expand size = 0 cnt WriteAppend = 12 UnZipSize{ index = 0.0016 GB value = 0.1316 GB dict = 1.38 MB all = 0.1342 GB } __ZipSize{ index = 0.0002 GB value = 0.0407 GB dict = 1.38 MB all = 0.0428 GB } UnZip/Zip{ index = 6.7430 value = 3.2373 dict = 1.00 all = 3.1391 } Zip/UnZip{ index = 0.1483 value = 0.3089 dict = 1.00 all = 0.3186 } ---------------------------- total value len = 0.662678 GB avg = 1.024 KB (by entry num) total key len = 0.012943 GB avg = 0.020 KB total ukey len = 0.007766 GB avg = 0.012 KB total ukey num = 0.000647158 Billion total entry num = 0.000647158 Billion write speed all = 0.008808848 MB/sec (with seq num) write speed all = 0.008741346 MB/sec (without seq num) 2024/11/23-06:10:41.145171 140025230210624 [compaction_job.cc:1948] [default] [JOB 20785] Generated table #247990: 129429 keys, 42756184 bytes, temperature: kWarm 2024/11/23-06:10:41.145234 140025230210624 EVENT_LOG_v1 {"time_micros": 1732313441145202, "cf_name": "default", "job": 20785, "event": "table_file_creation", "file_number": 247990, "file_size": 42756184, "file_checksum": "", "file_checksum_func_name": "Unknown", "smallest_seqno": 3237881756, "largest_seqno": 3828398011, "table_properties": {"data_size": 40660496, "index_size": 230336, "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": 2588580, "raw_average_key_size": 20, "raw_value_size": 131629473, "raw_average_value_size": 1017, "num_data_blocks": 1, "num_entries": 129429, "num_filter_entries": 0, "num_deletions": 0, "num_merge_operands": 0, "num_range_deletions": 0, "format_version": 0, "fixed_key_len": 20, "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": 1732313439, "oldest_key_time": 0, "file_creation_time": 1732313439, "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": 247990, "seqno_to_time_mapping": "N/A", "__indexstats__": "[...1 records...]"}} 2024/11/23-06:10:41.183682 140025230210624 [top_zip_table_builder.cc:934] ToplingZipTableBuilder(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-20785/att-00/247991.sst) /dev/shm/ToplingTemp/Topling-430742-xyM0bT 2024/11/23-06:10:41.772769 140025230210624 [top_zip_table_builder.cc:1352] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-20785/att-00/247991.sst): first pass time = 0.59's, 227.859'MB/sec 2024/11/23-06:10:42.979369 140025230210624 [top_zip_table_builder.cc:2772] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-20785/att-00/247991.sst): old prealloc_size = 147639500, real_size = 41000384 2024/11/23-06:10:42.979511 140025230210624 [top_zip_table_builder.cc:1773] BuildReorderMap(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-20785/att-00/247991.sst): index type = NestLoudsTrieDAWG_Mixed_XL_256_32_FL, store type = DictZipBlobStore usrkeys = 129428 prefix = 4 raw-key = 0.0010 GB zip-key = 0.0002 GB avg-key = 8.00 avg-zkey = 1.73 raw-val = 0.1316 GB zip-val = 0.0407 GB avg-val =1017.01 avg-zval = 314.80 2024/11/23-06:10:42.979692 140025230210624 [top_zip_table_builder.cc:1299] /storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-20785/att-00/247991.sst: sumWaitingMem = 0.000 GB, sumWorkingMem = 0.000 GB, reorder workingMem = 0.0003 GB, waited 0.000 sec, Key+Value = 0.134 GB 2024/11/23-06:10:43.178213 140025230210624 [top_zip_table_builder.cc:3100] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-20785/att-00/247991.sst): second pass time = 1.21's, 109.168'MB/sec, value only(98.1% of KV) wait indexing time = 0.00's, remap KeyValue time = 0.20's, 215.417'MB/sec (all stages of remap) Get OrderMap time = 0.00's, 1965.607'MB/sec (index lex order gen) rebuild zvType time = 0.15's, 0.221'MB/sec write SST data time = 0.05's, 819.595'MB/sec dict compress time = 4356233619.02's, 0.000'MB/sec z-dict build time = 0.00's, sample length = 1.378'MB, throughput = 44871.345'MB/sec zip my value time = 1.21's, unzip length = 0.132'GB zip my value throughput = 109.168'MB/sec zip pipeline throughput = 168.591'MB/sec entries = 129428 avg-key = 20.00 avg-zkey = 1.73 avg-val = 1017.01 avg-zval = 314.80 usrkeys = 129428 avg-key = 12.00 avg-zkey = 1.73 avg-val = 1024.01 avg-zval = 314.80 TagRS{ kinds = RS_Key0_Tag1 bytes = 0 } TagArray{ size = 129428 bytes = 485376 } seq expand size = 905996 multi value expand size = 0 cnt WriteAppend = 13 UnZipSize{ index = 0.0016 GB value = 0.1316 GB dict = 1.38 MB all = 0.1342 GB } __ZipSize{ index = 0.0002 GB value = 0.0407 GB dict = 1.38 MB all = 0.0428 GB } UnZip/Zip{ index = 6.9396 value = 3.2306 dict = 1.00 all = 3.1335 } Zip/UnZip{ index = 0.1441 value = 0.3095 dict = 1.00 all = 0.3191 } ---------------------------- total value len = 0.795214 GB avg = 1.024 KB (by entry num) total key len = 0.015532 GB avg = 0.020 KB total ukey len = 0.009319 GB avg = 0.012 KB total ukey num = 0.000776586 Billion total entry num = 0.000776586 Billion write speed all = 0.010570333 MB/sec (with seq num) write speed all = 0.010489333 MB/sec (without seq num) 2024/11/23-06:10:43.219549 140025230210624 [compaction_job.cc:1948] [default] [JOB 20785] Generated table #247991: 129428 keys, 42833166 bytes, temperature: kWarm 2024/11/23-06:10:43.219613 140025230210624 EVENT_LOG_v1 {"time_micros": 1732313443219582, "cf_name": "default", "job": 20785, "event": "table_file_creation", "file_number": 247991, "file_size": 42833166, "file_checksum": "", "file_checksum_func_name": "Unknown", "smallest_seqno": 3237868290, "largest_seqno": 3828398014, "table_properties": {"data_size": 40744048, "index_size": 223808, "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": 2588560, "raw_average_key_size": 20, "raw_value_size": 131629721, "raw_average_value_size": 1017, "num_data_blocks": 1, "num_entries": 129428, "num_filter_entries": 0, "num_deletions": 0, "num_merge_operands": 0, "num_range_deletions": 0, "format_version": 0, "fixed_key_len": 20, "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": 1732313441, "oldest_key_time": 0, "file_creation_time": 1732313441, "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": 247991, "seqno_to_time_mapping": "N/A", "__indexstats__": "[...1 records...]"}} 2024/11/23-06:10:43.257270 140025230210624 [top_zip_table_builder.cc:934] ToplingZipTableBuilder(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-20785/att-00/247992.sst) /dev/shm/ToplingTemp/Topling-430742-deMtlT 2024/11/23-06:10:43.754193 140025230210624 [top_zip_table_builder.cc:1352] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-20785/att-00/247992.sst): first pass time = 0.50's, 270.116'MB/sec 2024/11/23-06:10:44.531045 140025230210624 [top_zip_table_builder.cc:2772] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-20785/att-00/247992.sst): old prealloc_size = 147639500, real_size = 40651648 2024/11/23-06:10:44.531169 140025230210624 [top_zip_table_builder.cc:1773] BuildReorderMap(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-20785/att-00/247992.sst): index type = NestLoudsTrieDAWG_Mixed_XL_256_32_FL, store type = DictZipBlobStore usrkeys = 129432 prefix = 4 raw-key = 0.0010 GB zip-key = 0.0002 GB avg-key = 8.00 avg-zkey = 1.84 raw-val = 0.1316 GB zip-val = 0.0404 GB avg-val =1016.98 avg-zval = 311.98 2024/11/23-06:10:44.531317 140025230210624 [top_zip_table_builder.cc:1299] /storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-20785/att-00/247992.sst: sumWaitingMem = 0.000 GB, sumWorkingMem = 0.000 GB, reorder workingMem = 0.0003 GB, waited 0.000 sec, Key+Value = 0.134 GB 2024/11/23-06:10:44.692284 140025230210624 [top_zip_table_builder.cc:3100] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-20785/att-00/247992.sst): second pass time = 0.78's, 169.586'MB/sec, value only(98.1% of KV) wait indexing time = 0.00's, remap KeyValue time = 0.16's, 263.538'MB/sec (all stages of remap) Get OrderMap time = 0.00's, 2323.817'MB/sec (index lex order gen) rebuild zvType time = 0.11's, 0.298'MB/sec write SST data time = 0.05's, 807.188'MB/sec dict compress time = 4356233619.02's, 0.000'MB/sec z-dict build time = 0.00's, sample length = 1.378'MB, throughput = 40866.449'MB/sec zip my value time = 0.78's, unzip length = 0.132'GB zip my value throughput = 169.586'MB/sec zip pipeline throughput = 169.586'MB/sec entries = 129432 avg-key = 20.00 avg-zkey = 1.84 avg-val = 1016.98 avg-zval = 311.98 usrkeys = 129432 avg-key = 12.00 avg-zkey = 1.84 avg-val = 1023.98 avg-zval = 311.98 TagRS{ kinds = RS_Key0_Tag1 bytes = 0 } TagArray{ size = 129432 bytes = 485392 } seq expand size = 906024 multi value expand size = 0 cnt WriteAppend = 12 UnZipSize{ index = 0.0016 GB value = 0.1316 GB dict = 1.38 MB all = 0.1342 GB } __ZipSize{ index = 0.0002 GB value = 0.0404 GB dict = 1.38 MB all = 0.0425 GB } UnZip/Zip{ index = 6.5080 value = 3.2597 dict = 1.00 all = 3.1592 } Zip/UnZip{ index = 0.1537 value = 0.3068 dict = 1.00 all = 0.3165 } ---------------------------- total value len = 0.927749 GB avg = 1.024 KB (by entry num) total key len = 0.018120 GB avg = 0.020 KB total ukey len = 0.010872 GB avg = 0.012 KB total ukey num = 0.000906018 Billion total entry num = 0.000906018 Billion write speed all = 0.012331807 MB/sec (with seq num) write speed all = 0.012237309 MB/sec (without seq num) 2024/11/23-06:10:44.742136 140025230210624 [compaction_job.cc:1948] [default] [JOB 20785] Generated table #247992: 129432 keys, 42484652 bytes, temperature: kWarm 2024/11/23-06:10:44.742201 140025230210624 EVENT_LOG_v1 {"time_micros": 1732313444742170, "cf_name": "default", "job": 20785, "event": "table_file_creation", "file_number": 247992, "file_size": 42484652, "file_checksum": "", "file_checksum_func_name": "Unknown", "smallest_seqno": 3237873877, "largest_seqno": 3828397633, "table_properties": {"data_size": 40380464, "index_size": 238656, "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": 2588640, "raw_average_key_size": 20, "raw_value_size": 131629188, "raw_average_value_size": 1016, "num_data_blocks": 1, "num_entries": 129432, "num_filter_entries": 0, "num_deletions": 0, "num_merge_operands": 0, "num_range_deletions": 0, "format_version": 0, "fixed_key_len": 20, "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": 1732313443, "oldest_key_time": 0, "file_creation_time": 1732313443, "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": 247992, "seqno_to_time_mapping": "N/A", "__indexstats__": "[...1 records...]"}} 2024/11/23-06:10:44.782134 140025230210624 [top_zip_table_builder.cc:934] ToplingZipTableBuilder(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-20785/att-00/247993.sst) /dev/shm/ToplingTemp/Topling-430742-5k8bxR 2024/11/23-06:10:45.238848 140025230210624 [top_zip_table_builder.cc:1352] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-20785/att-00/247993.sst): first pass time = 0.46's, 293.907'MB/sec 2024/11/23-06:10:46.341466 140025230210624 [top_zip_table_builder.cc:2772] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-20785/att-00/247993.sst): old prealloc_size = 147639500, real_size = 40589024 2024/11/23-06:10:46.341609 140025230210624 [top_zip_table_builder.cc:1773] BuildReorderMap(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-20785/att-00/247993.sst): index type = NestLoudsTrieDAWG_Mixed_XL_256_32_FL, store type = DictZipBlobStore usrkeys = 129430 prefix = 4 raw-key = 0.0010 GB zip-key = 0.0002 GB avg-key = 8.00 avg-zkey = 1.86 raw-val = 0.1316 GB zip-val = 0.0403 GB avg-val =1017.00 avg-zval = 311.49 2024/11/23-06:10:46.341775 140025230210624 [top_zip_table_builder.cc:1299] /storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-20785/att-00/247993.sst: sumWaitingMem = 0.000 GB, sumWorkingMem = 0.000 GB, reorder workingMem = 0.0003 GB, waited 0.000 sec, Key+Value = 0.134 GB 2024/11/23-06:10:46.616065 140025230210624 [top_zip_table_builder.cc:3100] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-20785/att-00/247993.sst): second pass time = 1.10's, 119.459'MB/sec, value only(98.1% of KV) wait indexing time = 0.00's, remap KeyValue time = 0.27's, 154.505'MB/sec (all stages of remap) Get OrderMap time = 0.00's, 2030.555'MB/sec (index lex order gen) rebuild zvType time = 0.20's, 0.159'MB/sec write SST data time = 0.07's, 597.314'MB/sec dict compress time = 4356233619.02's, 0.000'MB/sec z-dict build time = 0.00's, sample length = 1.378'MB, throughput = 42711.722'MB/sec zip my value time = 1.10's, unzip length = 0.132'GB zip my value throughput = 119.459'MB/sec zip pipeline throughput = 169.277'MB/sec entries = 129430 avg-key = 20.00 avg-zkey = 1.86 avg-val = 1017.00 avg-zval = 311.49 usrkeys = 129430 avg-key = 12.00 avg-zkey = 1.86 avg-val = 1024.00 avg-zval = 311.49 TagRS{ kinds = RS_Key0_Tag1 bytes = 0 } TagArray{ size = 129430 bytes = 485376 } seq expand size = 906010 multi value expand size = 0 cnt WriteAppend = 12 UnZipSize{ index = 0.0016 GB value = 0.1316 GB dict = 1.38 MB all = 0.1342 GB } __ZipSize{ index = 0.0002 GB value = 0.0403 GB dict = 1.38 MB all = 0.0424 GB } UnZip/Zip{ index = 6.4491 value = 3.2650 dict = 1.00 all = 3.1639 } Zip/UnZip{ index = 0.1551 value = 0.3063 dict = 1.00 all = 0.3161 } ---------------------------- total value len = 1.060285 GB avg = 1.024 KB (by entry num) total key len = 0.020709 GB avg = 0.020 KB total ukey len = 0.012425 GB avg = 0.012 KB total ukey num = 0.001035448 Billion total entry num = 0.001035448 Billion write speed all = 0.014093145 MB/sec (with seq num) write speed all = 0.013985151 MB/sec (without seq num) 2024/11/23-06:10:46.657441 140025230210624 [compaction_job.cc:1948] [default] [JOB 20785] Generated table #247993: 129430 keys, 42421826 bytes, temperature: kWarm 2024/11/23-06:10:46.657523 140025230210624 EVENT_LOG_v1 {"time_micros": 1732313446657480, "cf_name": "default", "job": 20785, "event": "table_file_creation", "file_number": 247993, "file_size": 42421826, "file_checksum": "", "file_checksum_func_name": "Unknown", "smallest_seqno": 3237872082, "largest_seqno": 3828395735, "table_properties": {"data_size": 40315664, "index_size": 240832, "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": 2588600, "raw_average_key_size": 20, "raw_value_size": 131629995, "raw_average_value_size": 1016, "num_data_blocks": 1, "num_entries": 129430, "num_filter_entries": 0, "num_deletions": 0, "num_merge_operands": 0, "num_range_deletions": 0, "format_version": 0, "fixed_key_len": 20, "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": 1732313444, "oldest_key_time": 0, "file_creation_time": 1732313444, "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": 247993, "seqno_to_time_mapping": "N/A", "__indexstats__": "[...1 records...]"}} 2024/11/23-06:10:46.707878 140025230210624 [top_zip_table_builder.cc:934] ToplingZipTableBuilder(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-20785/att-00/247994.sst) /dev/shm/ToplingTemp/Topling-430742-8SypmV 2024/11/23-06:10:47.290065 140025230210624 [top_zip_table_builder.cc:1352] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-20785/att-00/247994.sst): first pass time = 0.58's, 230.564'MB/sec 2024/11/23-06:10:48.332256 140025230210624 [top_zip_table_builder.cc:2772] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-20785/att-00/247994.sst): old prealloc_size = 147639500, real_size = 41058352 2024/11/23-06:10:48.332388 140025230210624 [top_zip_table_builder.cc:1773] BuildReorderMap(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-20785/att-00/247994.sst): index type = NestLoudsTrieDAWG_Mixed_XL_256_32_FL, store type = DictZipBlobStore usrkeys = 129430 prefix = 4 raw-key = 0.0010 GB zip-key = 0.0002 GB avg-key = 8.00 avg-zkey = 1.73 raw-val = 0.1316 GB zip-val = 0.0408 GB avg-val =1017.00 avg-zval = 315.24 2024/11/23-06:10:48.332555 140025230210624 [top_zip_table_builder.cc:1299] /storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-20785/att-00/247994.sst: sumWaitingMem = 0.000 GB, sumWorkingMem = 0.000 GB, reorder workingMem = 0.0003 GB, waited 0.000 sec, Key+Value = 0.134 GB 2024/11/23-06:10:48.573349 140025230210624 [top_zip_table_builder.cc:3100] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-20785/att-00/247994.sst): second pass time = 1.04's, 126.388'MB/sec, value only(98.1% of KV) wait indexing time = 0.00's, remap KeyValue time = 0.24's, 177.922'MB/sec (all stages of remap) Get OrderMap time = 0.00's, 2067.237'MB/sec (index lex order gen) rebuild zvType time = 0.17's, 0.187'MB/sec write SST data time = 0.07's, 627.204'MB/sec dict compress time = 4356233619.02's, 0.000'MB/sec z-dict build time = 0.00's, sample length = 1.378'MB, throughput = 47088.237'MB/sec zip my value time = 1.04's, unzip length = 0.132'GB zip my value throughput = 126.388'MB/sec zip pipeline throughput = 147.470'MB/sec entries = 129430 avg-key = 20.00 avg-zkey = 1.73 avg-val = 1017.00 avg-zval = 315.24 usrkeys = 129430 avg-key = 12.00 avg-zkey = 1.73 avg-val = 1024.00 avg-zval = 315.24 TagRS{ kinds = RS_Key0_Tag1 bytes = 0 } TagArray{ size = 129430 bytes = 485376 } seq expand size = 906010 multi value expand size = 0 cnt WriteAppend = 12 UnZipSize{ index = 0.0016 GB value = 0.1316 GB dict = 1.38 MB all = 0.1342 GB } __ZipSize{ index = 0.0002 GB value = 0.0408 GB dict = 1.38 MB all = 0.0429 GB } UnZip/Zip{ index = 6.9239 value = 3.2261 dict = 1.00 all = 3.1293 } Zip/UnZip{ index = 0.1444 value = 0.3100 dict = 1.00 all = 0.3196 } ---------------------------- total value len = 1.192821 GB avg = 1.024 KB (by entry num) total key len = 0.023298 GB avg = 0.020 KB total ukey len = 0.013979 GB avg = 0.012 KB total ukey num = 0.001164878 Billion total entry num = 0.001164878 Billion write speed all = 0.015854385 MB/sec (with seq num) write speed all = 0.015732894 MB/sec (without seq num) 2024/11/23-06:10:48.615444 140025230210624 [compaction_job.cc:1948] [default] [JOB 20785] Generated table #247994: 129430 keys, 42891047 bytes, temperature: kWarm 2024/11/23-06:10:48.615526 140025230210624 EVENT_LOG_v1 {"time_micros": 1732313448615483, "cf_name": "default", "job": 20785, "event": "table_file_creation", "file_number": 247994, "file_size": 42891047, "file_checksum": "", "file_checksum_func_name": "Unknown", "smallest_seqno": 3237916889, "largest_seqno": 3828398299, "table_properties": {"data_size": 40801504, "index_size": 224320, "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": 2588600, "raw_average_key_size": 20, "raw_value_size": 131629725, "raw_average_value_size": 1016, "num_data_blocks": 1, "num_entries": 129430, "num_filter_entries": 0, "num_deletions": 0, "num_merge_operands": 0, "num_range_deletions": 0, "format_version": 0, "fixed_key_len": 20, "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": 1732313446, "oldest_key_time": 0, "file_creation_time": 1732313446, "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": 247994, "seqno_to_time_mapping": "N/A", "__indexstats__": "[...1 records...]"}} 2024/11/23-06:10:48.657036 140025230210624 [top_zip_table_builder.cc:934] ToplingZipTableBuilder(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-20785/att-00/247995.sst) /dev/shm/ToplingTemp/Topling-430742-J6ZRcU 2024/11/23-06:10:48.663382 140025230210624 [top_zip_table_builder.cc:1352] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-20785/att-00/247995.sst): first pass time = 0.01's, 257.049'MB/sec 2024/11/23-06:10:48.690394 140025230210624 [top_zip_table_builder.cc:2772] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-20785/att-00/247995.sst): old prealloc_size = 147639500, real_size = 526016 2024/11/23-06:10:48.690451 140025230210624 [top_zip_table_builder.cc:1773] BuildReorderMap(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-20785/att-00/247995.sst): index type = FixedLenKeyIndex , store type = DictZipBlobStore usrkeys = 1564 prefix = 4 raw-key = 0.0000 GB zip-key = 0.0000 GB avg-key = 8.00 avg-zkey = 12.56 raw-val = 0.0016 GB zip-val = 0.0005 GB avg-val =1016.70 avg-zval = 323.39 2024/11/23-06:10:48.692433 140025230210624 [top_zip_table_builder.cc:3100] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-20785/att-00/247995.sst): second pass time = 0.03's, 59.782'MB/sec, value only(98.1% of KV) wait indexing time = 0.00's, remap KeyValue time = 0.00's, 274.553'MB/sec (all stages of remap) Get OrderMap time = 0.00's, 510.722'MB/sec (index lex order gen) rebuild zvType time = 0.00's, 0.298'MB/sec write SST data time = 0.00's, 818.249'MB/sec dict compress time = 4356233619.02's, 0.000'MB/sec z-dict build time = 0.00's, sample length = 0.024'MB, throughput = 851.590'MB/sec zip my value time = 0.03's, unzip length = 0.002'GB zip my value throughput = 59.782'MB/sec zip pipeline throughput = 59.782'MB/sec entries = 1564 avg-key = 20.00 avg-zkey = 12.56 avg-val = 1016.70 avg-zval = 323.39 usrkeys = 1564 avg-key = 12.00 avg-zkey = 12.56 avg-val = 1023.70 avg-zval = 323.39 TagRS{ kinds = RS_Key0_Tag1 bytes = 0 } TagArray{ size = 1564 bytes = 5872 } seq expand size = 10948 multi value expand size = 0 cnt WriteAppend = 11 UnZipSize{ index = 0.0000 GB value = 0.0016 GB dict = 0.02 MB all = 0.0016 GB } __ZipSize{ index = 0.0000 GB value = 0.0005 GB dict = 0.02 MB all = 0.0006 GB } UnZip/Zip{ index = 0.9552 value = 3.1439 dict = 1.00 all = 2.9119 } Zip/UnZip{ index = 1.0469 value = 0.3181 dict = 1.00 all = 0.3434 } ---------------------------- total value len = 1.194422 GB avg = 1.024 KB (by entry num) total key len = 0.023329 GB avg = 0.020 KB total ukey len = 0.013997 GB avg = 0.012 KB total ukey num = 0.001166442 Billion total entry num = 0.001166442 Billion write speed all = 0.015875641 MB/sec (with seq num) write speed all = 0.015753987 MB/sec (without seq num) 2024/11/23-06:10:48.698183 140025230210624 [compaction_job.cc:1948] [default] [JOB 20785] Generated table #247995: 1564 keys, 556819 bytes, temperature: kWarm 2024/11/23-06:10:48.698231 140025230210624 EVENT_LOG_v1 {"time_micros": 1732313448698204, "cf_name": "default", "job": 20785, "event": "table_file_creation", "file_number": 247995, "file_size": 556819, "file_checksum": "", "file_checksum_func_name": "Unknown", "smallest_seqno": 3237923059, "largest_seqno": 3826871699, "table_properties": {"data_size": 505776, "index_size": 19648, "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": 31280, "raw_average_key_size": 20, "raw_value_size": 1590115, "raw_average_value_size": 1016, "num_data_blocks": 1, "num_entries": 1564, "num_filter_entries": 0, "num_deletions": 0, "num_merge_operands": 0, "num_range_deletions": 0, "format_version": 0, "fixed_key_len": 20, "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-FixedLenKeyIndex-DictZipBlobStore", "creation_time": 1732313448, "oldest_key_time": 0, "file_creation_time": 1732313448, "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": 247995, "seqno_to_time_mapping": "N/A", "__indexstats__": "[...1 records...]"}} 2024/11/23-06:10:48.707177 140025230210624 [compaction_job.cc:703] job-20785: subcompact[0], size: 0.384242 G, files: /storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-20785/att-00 [247986,247987,247988,247989,247990,247991,247992,247993,247994,247995] 2024/11/23-06:10:48.774703 140025230210624 (Original Log Time 2024/11/23-06:10:29.134324) EVENT_LOG_v1 {"time_micros": 1732313429134265, "job": 20785, "event": "compaction_started", "compaction_reason": "FilesMarkedForCompaction", "files_L2": [247978], "files_L3": [247916, 247917, 247918, 247919, 247920, 247921, 247922, 247923, 247924], "score": 0.999869, "input_data_size": 416365215, "oldest_snapshot_seqno": 3828430043} 2024/11/23-06:10:48.774707 140025230210624 (Original Log Time 2024/11/23-06:10:48.774119) [compaction_job.cc:2022] [default] [JOB 20785] Compacted 1@2 + 9@3 files to L3 => 384242059 bytes 2024/11/23-06:10:48.774708 140025230210624 (Original Log Time 2024/11/23-06:10:48.774636) [compaction_job.cc:1224] [default] compacted to: files[0 0 0 10 0 0 0] max score 0.01, MB/sec: 21.3 rd, 19.6 wr, level 3, files in(1, 9) out(10 +0 blob) MB in(31.2, 365.9 +0.0 blob) out(366.4 +0.0 blob), read-write-amplify(24.5) write-amplify(11.8) OK, records in: 1196778, records dropped: 30336 output_compression: Snappy 2024/11/23-06:10:48.774714 140025230210624 (Original Log Time 2024/11/23-06:10:48.774681) EVENT_LOG_v1 {"time_micros": 1732313448774661, "cf": "default", "job": 20785, "event": "compaction_finished", "compaction_time_micros": 19572856, "compaction_time_cpu_micros": 3522964, "output_level": 3, "num_output_files": 10, "total_output_size": 384242059, "num_input_records": 1196778, "num_output_records": 1166442, "num_subcompactions": 1, "output_compression": "Snappy", "num_single_delete_mismatches": 0, "num_single_delete_fallthrough": 0, "file_write_nanos": 316315829, "file_range_sync_nanos": 0, "file_fsync_nanos": 8605, "file_prepare_write_nanos": 26767, "lsm_state": [0, 0, 0, 10, 0, 0, 0]} 2024/11/23-06:10:48.880330 140025230210624 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":1732313428,"executesMs":19798,"compactionJobId":20785,"attempt":0,"compactionInputRawBytes":1241044613,"compactionInputZipBytes":416365215,"compactionOutputRawBytes":0,"compactionOutputZipBytes":384242059,"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-a5cfd48b5437e033c6509699693ef6bc-92b5e23e4a7187aa-00"}}, response = 2024/11/23-06:10:48.880399 140025230210624 INFO dcompact_worker.cpp:1459: finish /worker/dcompact-log/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/2024-11-22T14.14.14/.rocksdb/job-20785/att-00: olev 3, work 19.798 s, result 6.261 ms, install 0.611 ms, input{raw 1.156 GiB zip 397.077 MiB}