2024/11/23-09:57:03.205506 139957676762688 DEBG dcompact_worker.cpp:2214: /storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-23921/att-00: fork to child time = 0.007116 sec 2024/11/23-09:57:03.206073 139957676762688 DEBG dcompact_worker.cpp:1013: Beg SerDeRead: /storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-23921/att-00 2024/11/23-09:57:03.212878 139957676762688 DEBG dcompact_worker.cpp:1033: End SerDeRead: /storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-23921/att-00 2024/11/23-09:57:03.213767 139957676762688 TRAC dcompact_worker.cpp:1083: INFO_LOG_LEVEL: rpc = INFO_LEVEL, env_var = undefined 2024/11/23-09:57:03.214024 139957676762688 [WARN] [column_family.cc:386] multiple cf_paths/db_paths and level_compaction_dynamic_level_bytes can't be used together 2024/11/23-09:57:03.257432 139957676762688 [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-23921/att-00/MANIFEST-206347 2024/11/23-09:57:03.291314 139957676762688 [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-23921/att-00/MANIFEST-206347 succeeded,manifest_file_number is 206347, next_file_number is 255050, last_sequence is 3918111367, log_number is 0,prev_log_number is 0,max_column_family is 0,min_log_number_to_keep is 254907 2024/11/23-09:57:03.291320 139957676762688 [version_set.cc:6266] Column family [default] (ID 0), log number is 0 2024/11/23-09:57:03.291424 139957676762688 [version_set.cc:5721] Creating manifest 255050 2024/11/23-09:57:03.323742 139957676762688 DEBG dcompact_worker.cpp:1218: /storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-23921/att-00: bottommost_level: fake = 1, rpc = 0 2024/11/23-09:57:03.346133 139957676762688 [compaction_job.cc:2437] [default] [JOB 23921] Compacting 1@2 + 9@3 files to L3, score 0.99, subcompactions 1 : 1 2024/11/23-09:57:03.346144 139957676762688 [compaction_job.cc:2445] [default]: Compaction start summary: Base version 17948 Base level 2, inputs: [255047(31MB)], [254962(40MB) 254963(40MB) 254964(40MB) 254965(40MB) 254966(40MB) 254967(40MB) 254968(40MB) 254969(40MB) 254978(40MB)] 2024/11/23-09:57:03.354526 139957676762688 [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-09:57:03.354552 139957676762688 [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-09:57:03.354567 139957676762688 [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-09:57:03.354571 139957676762688 [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-09:57:03.354584 139957676762688 [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-09:57:03.996627 139957676762688 [top_zip_table_builder.cc:934] ToplingZipTableBuilder(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-23921/att-00/255051.sst) /dev/shm/ToplingTemp/Topling-18927-FDvBim 2024/11/23-09:57:04.177069 139957676762688 [top_zip_table_builder.cc:1352] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-23921/att-00/255051.sst): first pass time = 0.18's, 743.992'MB/sec 2024/11/23-09:57:05.463332 139957676762688 [top_zip_table_builder.cc:2772] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-23921/att-00/255051.sst): old prealloc_size = 147639500, real_size = 40737520 2024/11/23-09:57:05.463496 139957676762688 [top_zip_table_builder.cc:1773] BuildReorderMap(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-23921/att-00/255051.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 = 312.69 2024/11/23-09:57:05.463713 139957676762688 [top_zip_table_builder.cc:1299] /storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-23921/att-00/255051.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-09:57:05.636701 139957676762688 [top_zip_table_builder.cc:3100] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-23921/att-00/255051.sst): second pass time = 1.28's, 102.991'MB/sec, value only(98.1% of KV) wait indexing time = 0.01's, remap KeyValue time = 0.17's, 245.581'MB/sec (all stages of remap) Get OrderMap time = 0.00's, 1595.856'MB/sec (index lex order gen) rebuild zvType time = 0.13's, 0.258'MB/sec write SST data time = 0.05's, 892.851'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 = 46383.823'MB/sec zip my value time = 1.28's, unzip length = 0.132'GB zip my value throughput = 102.991'MB/sec zip pipeline throughput = 170.890'MB/sec entries = 129432 avg-key = 20.00 avg-zkey = 1.80 avg-val = 1016.98 avg-zval = 312.69 usrkeys = 129432 avg-key = 12.00 avg-zkey = 1.80 avg-val = 1023.98 avg-zval = 312.69 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.6507 value = 3.2524 dict = 1.00 all = 3.1529 } Zip/UnZip{ index = 0.1504 value = 0.3075 dict = 1.00 all = 0.3172 } ---------------------------- total value len = 8.981155 GB avg = 0.171 KB (by entry num) total key len = 1.443410 GB avg = 0.028 KB total ukey len = 0.999431 GB avg = 0.015 KB total ukey num = 0.065679867 Billion total entry num = 0.052487596 Billion write speed all = 2.807266413 MB/sec (with seq num) write speed all = 2.694189912 MB/sec (without seq num) 2024/11/23-09:57:05.689821 139957676762688 [compaction_job.cc:1948] [default] [JOB 23921] Generated table #255051: 129432 keys, 42570336 bytes, temperature: kWarm 2024/11/23-09:57:05.689904 139957676762688 EVENT_LOG_v1 {"time_micros": 1732327025689851, "cf_name": "default", "job": 23921, "event": "table_file_creation", "file_number": 255051, "file_size": 42570336, "file_checksum": "", "file_checksum_func_name": "Unknown", "smallest_seqno": 3237868195, "largest_seqno": 3918098773, "table_properties": {"data_size": 40471456, "index_size": 233536, "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": 131629382, "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": 1732327023, "oldest_key_time": 0, "file_creation_time": 1732327023, "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": 255051, "seqno_to_time_mapping": "N/A", "__indexstats__": "[...1 records...]"}} 2024/11/23-09:57:05.743139 139957676762688 [top_zip_table_builder.cc:934] ToplingZipTableBuilder(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-23921/att-00/255052.sst) /dev/shm/ToplingTemp/Topling-18927-9URANn 2024/11/23-09:57:06.308608 139957676762688 [top_zip_table_builder.cc:1352] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-23921/att-00/255052.sst): first pass time = 0.57's, 237.378'MB/sec 2024/11/23-09:57:07.663063 139957676762688 [top_zip_table_builder.cc:2772] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-23921/att-00/255052.sst): old prealloc_size = 147639500, real_size = 40768816 2024/11/23-09:57:07.663227 139957676762688 [top_zip_table_builder.cc:1773] BuildReorderMap(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-23921/att-00/255052.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.79 raw-val = 0.1316 GB zip-val = 0.0405 GB avg-val =1016.98 avg-zval = 312.94 2024/11/23-09:57:07.663432 139957676762688 [top_zip_table_builder.cc:1299] /storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-23921/att-00/255052.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-09:57:07.973571 139957676762688 [top_zip_table_builder.cc:3100] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-23921/att-00/255052.sst): second pass time = 1.35's, 97.252'MB/sec, value only(98.1% of KV) wait indexing time = 0.00's, remap KeyValue time = 0.31's, 137.207'MB/sec (all stages of remap) Get OrderMap time = 0.00's, 1649.191'MB/sec (index lex order gen) rebuild zvType time = 0.23's, 0.138'MB/sec write SST data time = 0.08's, 561.352'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 = 45686.023'MB/sec zip my value time = 1.35's, unzip length = 0.132'GB zip my value throughput = 97.252'MB/sec zip pipeline throughput = 171.685'MB/sec entries = 129432 avg-key = 20.00 avg-zkey = 1.79 avg-val = 1016.98 avg-zval = 312.94 usrkeys = 129432 avg-key = 12.00 avg-zkey = 1.79 avg-val = 1023.98 avg-zval = 312.94 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.6948 value = 3.2498 dict = 1.00 all = 3.1505 } Zip/UnZip{ index = 0.1494 value = 0.3077 dict = 1.00 all = 0.3174 } ---------------------------- total value len = 9.113691 GB avg = 0.173 KB (by entry num) total key len = 1.445998 GB avg = 0.027 KB total ukey len = 1.000984 GB avg = 0.015 KB total ukey num = 0.065809299 Billion total entry num = 0.052617028 Billion write speed all = 2.841866114 MB/sec (with seq num) write speed all = 2.728582062 MB/sec (without seq num) 2024/11/23-09:57:08.031135 139957676762688 [compaction_job.cc:1948] [default] [JOB 23921] Generated table #255052: 129432 keys, 42602008 bytes, temperature: kWarm 2024/11/23-09:57:08.031216 139957676762688 EVENT_LOG_v1 {"time_micros": 1732327028031182, "cf_name": "default", "job": 23921, "event": "table_file_creation", "file_number": 255052, "file_size": 42602008, "file_checksum": "", "file_checksum_func_name": "Unknown", "smallest_seqno": 3237874654, "largest_seqno": 3918096937, "table_properties": {"data_size": 40504288, "index_size": 232000, "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": 131629781, "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": 1732327025, "oldest_key_time": 0, "file_creation_time": 1732327025, "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": 255052, "seqno_to_time_mapping": "N/A", "__indexstats__": "[...1 records...]"}} 2024/11/23-09:57:08.070336 139957676762688 [top_zip_table_builder.cc:934] ToplingZipTableBuilder(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-23921/att-00/255053.sst) /dev/shm/ToplingTemp/Topling-18927-H1FMvl 2024/11/23-09:57:08.650217 139957676762688 [top_zip_table_builder.cc:1352] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-23921/att-00/255053.sst): first pass time = 0.58's, 231.482'MB/sec 2024/11/23-09:57:09.665961 139957676762688 [top_zip_table_builder.cc:2772] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-23921/att-00/255053.sst): old prealloc_size = 147639500, real_size = 40830272 2024/11/23-09:57:09.666116 139957676762688 [top_zip_table_builder.cc:1773] BuildReorderMap(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-23921/att-00/255053.sst): index type = NestLoudsTrieDAWG_Mixed_XL_256_32_FL, store type = DictZipBlobStore usrkeys = 129435 prefix = 4 raw-key = 0.0010 GB zip-key = 0.0002 GB avg-key = 8.00 avg-zkey = 1.77 raw-val = 0.1316 GB zip-val = 0.0406 GB avg-val =1016.95 avg-zval = 313.43 2024/11/23-09:57:09.666296 139957676762688 [top_zip_table_builder.cc:1299] /storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-23921/att-00/255053.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-09:57:09.988254 139957676762688 [top_zip_table_builder.cc:3100] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-23921/att-00/255053.sst): second pass time = 1.01's, 129.695'MB/sec, value only(98.1% of KV) wait indexing time = 0.00's, remap KeyValue time = 0.32's, 132.375'MB/sec (all stages of remap) Get OrderMap time = 0.00's, 1917.637'MB/sec (index lex order gen) rebuild zvType time = 0.28's, 0.117'MB/sec write SST data time = 0.05's, 926.567'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 = 44696.899'MB/sec zip my value time = 1.01's, unzip length = 0.132'GB zip my value throughput = 129.695'MB/sec zip pipeline throughput = 149.009'MB/sec entries = 129435 avg-key = 20.00 avg-zkey = 1.77 avg-val = 1016.95 avg-zval = 313.43 usrkeys = 129435 avg-key = 12.00 avg-zkey = 1.77 avg-val = 1023.95 avg-zval = 313.43 TagRS{ kinds = RS_Key0_Tag1 bytes = 0 } TagArray{ size = 129435 bytes = 485392 } seq expand size = 906045 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.7810 value = 3.2446 dict = 1.00 all = 3.1460 } Zip/UnZip{ index = 0.1475 value = 0.3082 dict = 1.00 all = 0.3179 } ---------------------------- total value len = 9.246226 GB avg = 0.175 KB (by entry num) total key len = 1.448587 GB avg = 0.027 KB total ukey len = 1.002538 GB avg = 0.015 KB total ukey num = 0.065938734 Billion total entry num = 0.052746463 Billion write speed all = 2.876671455 MB/sec (with seq num) write speed all = 2.763170271 MB/sec (without seq num) 2024/11/23-09:57:10.043007 139957676762688 [compaction_job.cc:1948] [default] [JOB 23921] Generated table #255053: 129435 keys, 42662971 bytes, temperature: kWarm 2024/11/23-09:57:10.043071 139957676762688 EVENT_LOG_v1 {"time_micros": 1732327030043035, "cf_name": "default", "job": 23921, "event": "table_file_creation", "file_number": 255053, "file_size": 42662971, "file_checksum": "", "file_checksum_func_name": "Unknown", "smallest_seqno": 3237868596, "largest_seqno": 3918095870, "table_properties": {"data_size": 40568688, "index_size": 229056, "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": 2588700, "raw_average_key_size": 20, "raw_value_size": 131629098, "raw_average_value_size": 1016, "num_data_blocks": 1, "num_entries": 129435, "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": 1732327028, "oldest_key_time": 0, "file_creation_time": 1732327028, "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": 255053, "seqno_to_time_mapping": "N/A", "__indexstats__": "[...1 records...]"}} 2024/11/23-09:57:10.077999 139957676762688 [top_zip_table_builder.cc:934] ToplingZipTableBuilder(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-23921/att-00/255054.sst) /dev/shm/ToplingTemp/Topling-18927-i5kQBm 2024/11/23-09:57:10.628129 139957676762688 [top_zip_table_builder.cc:1352] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-23921/att-00/255054.sst): first pass time = 0.55's, 243.993'MB/sec 2024/11/23-09:57:11.426161 139957676762688 [top_zip_table_builder.cc:2772] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-23921/att-00/255054.sst): old prealloc_size = 147639500, real_size = 40659472 2024/11/23-09:57:11.426312 139957676762688 [top_zip_table_builder.cc:1773] BuildReorderMap(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-23921/att-00/255054.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.83 raw-val = 0.1316 GB zip-val = 0.0404 GB avg-val =1017.01 avg-zval = 312.07 2024/11/23-09:57:11.426508 139957676762688 [top_zip_table_builder.cc:1299] /storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-23921/att-00/255054.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-09:57:11.676189 139957676762688 [top_zip_table_builder.cc:3100] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-23921/att-00/255054.sst): second pass time = 0.80's, 165.131'MB/sec, value only(98.1% of KV) wait indexing time = 0.00's, remap KeyValue time = 0.25's, 169.960'MB/sec (all stages of remap) Get OrderMap time = 0.00's, 1884.650'MB/sec (index lex order gen) rebuild zvType time = 0.19's, 0.172'MB/sec write SST data time = 0.06's, 684.641'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 = 48296.443'MB/sec zip my value time = 0.80's, unzip length = 0.132'GB zip my value throughput = 165.131'MB/sec zip pipeline throughput = 165.131'MB/sec entries = 129429 avg-key = 20.00 avg-zkey = 1.83 avg-val = 1017.01 avg-zval = 312.07 usrkeys = 129429 avg-key = 12.00 avg-zkey = 1.83 avg-val = 1024.01 avg-zval = 312.07 TagRS{ kinds = RS_Key0_Tag1 bytes = 0 } TagArray{ size = 129429 bytes = 485376 } seq expand size = 906003 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.0404 GB dict = 1.38 MB all = 0.0425 GB } UnZip/Zip{ index = 6.5731 value = 3.2589 dict = 1.00 all = 3.1587 } Zip/UnZip{ index = 0.1521 value = 0.3068 dict = 1.00 all = 0.3166 } ---------------------------- total value len = 9.378762 GB avg = 0.177 KB (by entry num) total key len = 1.451176 GB avg = 0.027 KB total ukey len = 1.004091 GB avg = 0.015 KB total ukey num = 0.066068163 Billion total entry num = 0.052875892 Billion write speed all = 2.911695051 MB/sec (with seq num) write speed all = 2.797966993 MB/sec (without seq num) 2024/11/23-09:57:11.729393 139957676762688 [compaction_job.cc:1948] [default] [JOB 23921] Generated table #255054: 129429 keys, 42492378 bytes, temperature: kWarm 2024/11/23-09:57:11.729471 139957676762688 EVENT_LOG_v1 {"time_micros": 1732327031729431, "cf_name": "default", "job": 23921, "event": "table_file_creation", "file_number": 255054, "file_size": 42492378, "file_checksum": "", "file_checksum_func_name": "Unknown", "smallest_seqno": 3237869923, "largest_seqno": 3918095067, "table_properties": {"data_size": 40390656, "index_size": 236288, "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": 131629972, "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": 1732327030, "oldest_key_time": 0, "file_creation_time": 1732327030, "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": 255054, "seqno_to_time_mapping": "N/A", "__indexstats__": "[...1 records...]"}} 2024/11/23-09:57:11.764841 139957676762688 [top_zip_table_builder.cc:934] ToplingZipTableBuilder(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-23921/att-00/255055.sst) /dev/shm/ToplingTemp/Topling-18927-2GgMXk 2024/11/23-09:57:12.477902 139957676762688 [top_zip_table_builder.cc:1352] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-23921/att-00/255055.sst): first pass time = 0.71's, 188.237'MB/sec 2024/11/23-09:57:13.265431 139957676762688 [top_zip_table_builder.cc:2772] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-23921/att-00/255055.sst): old prealloc_size = 147639500, real_size = 40651088 2024/11/23-09:57:13.265550 139957676762688 [top_zip_table_builder.cc:1773] BuildReorderMap(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-23921/att-00/255055.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.83 raw-val = 0.1316 GB zip-val = 0.0404 GB avg-val =1016.99 avg-zval = 311.99 2024/11/23-09:57:13.265707 139957676762688 [top_zip_table_builder.cc:1299] /storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-23921/att-00/255055.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-09:57:13.424898 139957676762688 [top_zip_table_builder.cc:3100] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-23921/att-00/255055.sst): second pass time = 0.79's, 167.486'MB/sec, value only(98.1% of KV) wait indexing time = 0.00's, remap KeyValue time = 0.16's, 266.435'MB/sec (all stages of remap) Get OrderMap time = 0.00's, 2375.090'MB/sec (index lex order gen) rebuild zvType time = 0.12's, 0.277'MB/sec write SST data time = 0.04's, 998.444'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 = 50498.040'MB/sec zip my value time = 0.79's, unzip length = 0.132'GB zip my value throughput = 167.486'MB/sec zip pipeline throughput = 167.486'MB/sec entries = 129430 avg-key = 20.00 avg-zkey = 1.83 avg-val = 1016.99 avg-zval = 311.99 usrkeys = 129430 avg-key = 12.00 avg-zkey = 1.83 avg-val = 1023.99 avg-zval = 311.99 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.0404 GB dict = 1.38 MB all = 0.0425 GB } UnZip/Zip{ index = 6.5430 value = 3.2597 dict = 1.00 all = 3.1592 } Zip/UnZip{ index = 0.1528 value = 0.3068 dict = 1.00 all = 0.3165 } ---------------------------- total value len = 9.511298 GB avg = 0.179 KB (by entry num) total key len = 1.453764 GB avg = 0.027 KB total ukey len = 1.005644 GB avg = 0.015 KB total ukey num = 0.066197593 Billion total entry num = 0.053005322 Billion write speed all = 2.946638629 MB/sec (with seq num) write speed all = 2.832685762 MB/sec (without seq num) 2024/11/23-09:57:13.477289 139957676762688 [compaction_job.cc:1948] [default] [JOB 23921] Generated table #255055: 129430 keys, 42484199 bytes, temperature: kWarm 2024/11/23-09:57:13.477356 139957676762688 EVENT_LOG_v1 {"time_micros": 1732327033477321, "cf_name": "default", "job": 23921, "event": "table_file_creation", "file_number": 255055, "file_size": 42484199, "file_checksum": "", "file_checksum_func_name": "Unknown", "smallest_seqno": 3237872175, "largest_seqno": 3918098339, "table_properties": {"data_size": 40381184, "index_size": 237376, "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": 131629492, "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": 1732327031, "oldest_key_time": 0, "file_creation_time": 1732327031, "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": 255055, "seqno_to_time_mapping": "N/A", "__indexstats__": "[...1 records...]"}} 2024/11/23-09:57:13.516003 139957676762688 [top_zip_table_builder.cc:934] ToplingZipTableBuilder(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-23921/att-00/255056.sst) /dev/shm/ToplingTemp/Topling-18927-RCcvnp 2024/11/23-09:57:14.057638 139957676762688 [top_zip_table_builder.cc:1352] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-23921/att-00/255056.sst): first pass time = 0.54's, 247.822'MB/sec 2024/11/23-09:57:15.166808 139957676762688 [top_zip_table_builder.cc:2772] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-23921/att-00/255056.sst): old prealloc_size = 147639500, real_size = 41063296 2024/11/23-09:57:15.166958 139957676762688 [top_zip_table_builder.cc:1773] BuildReorderMap(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-23921/att-00/255056.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.73 raw-val = 0.1316 GB zip-val = 0.0408 GB avg-val =1017.01 avg-zval = 315.29 2024/11/23-09:57:15.167163 139957676762688 [top_zip_table_builder.cc:1299] /storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-23921/att-00/255056.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-09:57:15.423448 139957676762688 [top_zip_table_builder.cc:3100] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-23921/att-00/255056.sst): second pass time = 1.11's, 118.767'MB/sec, value only(98.1% of KV) wait indexing time = 0.00's, remap KeyValue time = 0.26's, 167.176'MB/sec (all stages of remap) Get OrderMap time = 0.00's, 1592.178'MB/sec (index lex order gen) rebuild zvType time = 0.19's, 0.168'MB/sec write SST data time = 0.06's, 676.516'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 = 44595.522'MB/sec zip my value time = 1.11's, unzip length = 0.132'GB zip my value throughput = 118.767'MB/sec zip pipeline throughput = 134.122'MB/sec entries = 129429 avg-key = 20.00 avg-zkey = 1.73 avg-val = 1017.01 avg-zval = 315.29 usrkeys = 129429 avg-key = 12.00 avg-zkey = 1.73 avg-val = 1024.01 avg-zval = 315.29 TagRS{ kinds = RS_Key0_Tag1 bytes = 0 } TagArray{ size = 129429 bytes = 485376 } seq expand size = 906003 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.0408 GB dict = 1.38 MB all = 0.0429 GB } UnZip/Zip{ index = 6.9416 value = 3.2257 dict = 1.00 all = 3.1289 } Zip/UnZip{ index = 0.1441 value = 0.3100 dict = 1.00 all = 0.3196 } ---------------------------- total value len = 9.643834 GB avg = 0.181 KB (by entry num) total key len = 1.456353 GB avg = 0.027 KB total ukey len = 1.007197 GB avg = 0.015 KB total ukey num = 0.066327022 Billion total entry num = 0.053134751 Billion write speed all = 2.981349502 MB/sec (with seq num) write speed all = 2.867179700 MB/sec (without seq num) 2024/11/23-09:57:15.479752 139957676762688 [compaction_job.cc:1948] [default] [JOB 23921] Generated table #255056: 129429 keys, 42896482 bytes, temperature: kWarm 2024/11/23-09:57:15.479845 139957676762688 EVENT_LOG_v1 {"time_micros": 1732327035479796, "cf_name": "default", "job": 23921, "event": "table_file_creation", "file_number": 255056, "file_size": 42896482, "file_checksum": "", "file_checksum_func_name": "Unknown", "smallest_seqno": 3237880987, "largest_seqno": 3918098596, "table_properties": {"data_size": 40807024, "index_size": 223744, "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": 131630159, "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": 1732327033, "oldest_key_time": 0, "file_creation_time": 1732327033, "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": 255056, "seqno_to_time_mapping": "N/A", "__indexstats__": "[...1 records...]"}} 2024/11/23-09:57:15.543941 139957676762688 [top_zip_table_builder.cc:934] ToplingZipTableBuilder(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-23921/att-00/255057.sst) /dev/shm/ToplingTemp/Topling-18927-pUDuzo 2024/11/23-09:57:16.375777 139957676762688 [top_zip_table_builder.cc:1352] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-23921/att-00/255057.sst): first pass time = 0.83's, 161.368'MB/sec 2024/11/23-09:57:17.206176 139957676762688 [top_zip_table_builder.cc:2772] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-23921/att-00/255057.sst): old prealloc_size = 147639500, real_size = 40856480 2024/11/23-09:57:17.206291 139957676762688 [top_zip_table_builder.cc:1773] BuildReorderMap(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-23921/att-00/255057.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.76 raw-val = 0.1316 GB zip-val = 0.0406 GB avg-val =1016.98 avg-zval = 313.65 2024/11/23-09:57:17.206473 139957676762688 [top_zip_table_builder.cc:1299] /storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-23921/att-00/255057.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-09:57:17.369039 139957676762688 [top_zip_table_builder.cc:3100] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-23921/att-00/255057.sst): second pass time = 0.83's, 158.646'MB/sec, value only(98.1% of KV) wait indexing time = 0.00's, remap KeyValue time = 0.16's, 262.210'MB/sec (all stages of remap) Get OrderMap time = 0.00's, 2472.142'MB/sec (index lex order gen) rebuild zvType time = 0.12's, 0.270'MB/sec write SST data time = 0.04's, 997.822'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 = 43769.575'MB/sec zip my value time = 0.83's, unzip length = 0.132'GB zip my value throughput = 158.646'MB/sec zip pipeline throughput = 158.646'MB/sec entries = 129432 avg-key = 20.00 avg-zkey = 1.76 avg-val = 1016.98 avg-zval = 313.65 usrkeys = 129432 avg-key = 12.00 avg-zkey = 1.76 avg-val = 1023.98 avg-zval = 313.65 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.0406 GB dict = 1.38 MB all = 0.0427 GB } UnZip/Zip{ index = 6.8170 value = 3.2424 dict = 1.00 all = 3.1441 } Zip/UnZip{ index = 0.1467 value = 0.3084 dict = 1.00 all = 0.3181 } ---------------------------- total value len = 9.776370 GB avg = 0.184 KB (by entry num) total key len = 1.458941 GB avg = 0.027 KB total ukey len = 1.008750 GB avg = 0.015 KB total ukey num = 0.066456454 Billion total entry num = 0.053264183 Billion write speed all = 3.016065974 MB/sec (with seq num) write speed all = 2.901677837 MB/sec (without seq num) 2024/11/23-09:57:17.421951 139957676762688 [compaction_job.cc:1948] [default] [JOB 23921] Generated table #255057: 129432 keys, 42689141 bytes, temperature: kWarm 2024/11/23-09:57:17.422037 139957676762688 EVENT_LOG_v1 {"time_micros": 1732327037421997, "cf_name": "default", "job": 23921, "event": "table_file_creation", "file_number": 255057, "file_size": 42689141, "file_checksum": "", "file_checksum_func_name": "Unknown", "smallest_seqno": 3237868290, "largest_seqno": 3918068213, "table_properties": {"data_size": 40596112, "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": 2588640, "raw_average_key_size": 20, "raw_value_size": 131630018, "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": 1732327035, "oldest_key_time": 0, "file_creation_time": 1732327035, "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": 255057, "seqno_to_time_mapping": "N/A", "__indexstats__": "[...1 records...]"}} 2024/11/23-09:57:17.457677 139957676762688 [top_zip_table_builder.cc:934] ToplingZipTableBuilder(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-23921/att-00/255058.sst) /dev/shm/ToplingTemp/Topling-18927-SVhpVn 2024/11/23-09:57:18.002249 139957676762688 [top_zip_table_builder.cc:1352] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-23921/att-00/255058.sst): first pass time = 0.54's, 246.481'MB/sec 2024/11/23-09:57:18.821281 139957676762688 [top_zip_table_builder.cc:2772] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-23921/att-00/255058.sst): old prealloc_size = 147639500, real_size = 40589728 2024/11/23-09:57:18.821422 139957676762688 [top_zip_table_builder.cc:1773] BuildReorderMap(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-23921/att-00/255058.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.84 raw-val = 0.1316 GB zip-val = 0.0403 GB avg-val =1017.00 avg-zval = 311.51 2024/11/23-09:57:18.821582 139957676762688 [top_zip_table_builder.cc:1299] /storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-23921/att-00/255058.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-09:57:18.982997 139957676762688 [top_zip_table_builder.cc:3100] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-23921/att-00/255058.sst): second pass time = 0.82's, 160.863'MB/sec, value only(98.1% of KV) wait indexing time = 0.00's, remap KeyValue time = 0.16's, 262.396'MB/sec (all stages of remap) Get OrderMap time = 0.00's, 2020.425'MB/sec (index lex order gen) rebuild zvType time = 0.12's, 0.274'MB/sec write SST data time = 0.04's, 975.081'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 = 47442.253'MB/sec zip my value time = 0.82's, unzip length = 0.132'GB zip my value throughput = 160.863'MB/sec zip pipeline throughput = 160.863'MB/sec entries = 129429 avg-key = 20.00 avg-zkey = 1.84 avg-val = 1017.00 avg-zval = 311.51 usrkeys = 129429 avg-key = 12.00 avg-zkey = 1.84 avg-val = 1024.00 avg-zval = 311.51 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.0403 GB dict = 1.38 MB all = 0.0424 GB } UnZip/Zip{ index = 6.5149 value = 3.2647 dict = 1.00 all = 3.1638 } Zip/UnZip{ index = 0.1535 value = 0.3063 dict = 1.00 all = 0.3161 } ---------------------------- total value len = 9.908905 GB avg = 0.186 KB (by entry num) total key len = 1.461530 GB avg = 0.027 KB total ukey len = 1.010303 GB avg = 0.015 KB total ukey num = 0.066585883 Billion total entry num = 0.053393612 Billion write speed all = 3.051017415 MB/sec (with seq num) write speed all = 2.936400981 MB/sec (without seq num) 2024/11/23-09:57:19.035543 139957676762688 [compaction_job.cc:1948] [default] [JOB 23921] Generated table #255058: 129429 keys, 42422629 bytes, temperature: kWarm 2024/11/23-09:57:19.035609 139957676762688 EVENT_LOG_v1 {"time_micros": 1732327039035574, "cf_name": "default", "job": 23921, "event": "table_file_creation", "file_number": 255058, "file_size": 42422629, "file_checksum": "", "file_checksum_func_name": "Unknown", "smallest_seqno": 3237873605, "largest_seqno": 3918097390, "table_properties": {"data_size": 40318800, "index_size": 238400, "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": 131629193, "raw_average_value_size": 1016, "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": 1732327037, "oldest_key_time": 0, "file_creation_time": 1732327037, "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": 255058, "seqno_to_time_mapping": "N/A", "__indexstats__": "[...1 records...]"}} 2024/11/23-09:57:19.069925 139957676762688 [top_zip_table_builder.cc:934] ToplingZipTableBuilder(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-23921/att-00/255059.sst) /dev/shm/ToplingTemp/Topling-18927-HBw2rm 2024/11/23-09:57:19.975119 139957676762688 [top_zip_table_builder.cc:1352] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-23921/att-00/255059.sst): first pass time = 0.91's, 148.283'MB/sec 2024/11/23-09:57:21.319734 139957676762688 [top_zip_table_builder.cc:2772] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-23921/att-00/255059.sst): old prealloc_size = 147639500, real_size = 40796688 2024/11/23-09:57:21.319871 139957676762688 [top_zip_table_builder.cc:1773] BuildReorderMap(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-23921/att-00/255059.sst): index type = NestLoudsTrieDAWG_Mixed_XL_256_32_FL, store type = DictZipBlobStore usrkeys = 129431 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.99 avg-zval = 313.14 2024/11/23-09:57:21.320044 139957676762688 [top_zip_table_builder.cc:1299] /storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-23921/att-00/255059.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-09:57:21.479741 139957676762688 [top_zip_table_builder.cc:3100] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-23921/att-00/255059.sst): second pass time = 1.34's, 97.942'MB/sec, value only(98.1% of KV) wait indexing time = 0.00's, remap KeyValue time = 0.16's, 266.435'MB/sec (all stages of remap) Get OrderMap time = 0.00's, 1987.202'MB/sec (index lex order gen) rebuild zvType time = 0.12's, 0.276'MB/sec write SST data time = 0.04's, 998.682'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 = 41141.616'MB/sec zip my value time = 1.34's, unzip length = 0.132'GB zip my value throughput = 97.942'MB/sec zip pipeline throughput = 160.031'MB/sec entries = 129431 avg-key = 20.00 avg-zkey = 1.80 avg-val = 1016.99 avg-zval = 313.14 usrkeys = 129431 avg-key = 12.00 avg-zkey = 1.80 avg-val = 1023.99 avg-zval = 313.14 TagRS{ kinds = RS_Key0_Tag1 bytes = 0 } TagArray{ size = 129431 bytes = 485376 } seq expand size = 906017 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.6507 value = 3.2477 dict = 1.00 all = 3.1485 } Zip/UnZip{ index = 0.1504 value = 0.3079 dict = 1.00 all = 0.3176 } ---------------------------- total value len = 10.041441 GB avg = 0.188 KB (by entry num) total key len = 1.464119 GB avg = 0.027 KB total ukey len = 1.011857 GB avg = 0.015 KB total ukey num = 0.066715314 Billion total entry num = 0.053523043 Billion write speed all = 3.085208276 MB/sec (with seq num) write speed all = 2.970390924 MB/sec (without seq num) 2024/11/23-09:57:21.574107 139957676762688 [compaction_job.cc:1948] [default] [JOB 23921] Generated table #255059: 129431 keys, 42629082 bytes, temperature: kWarm 2024/11/23-09:57:21.574181 139957676762688 EVENT_LOG_v1 {"time_micros": 1732327041574139, "cf_name": "default", "job": 23921, "event": "table_file_creation", "file_number": 255059, "file_size": 42629082, "file_checksum": "", "file_checksum_func_name": "Unknown", "smallest_seqno": 3237872082, "largest_seqno": 3918099067, "table_properties": {"data_size": 40530624, "index_size": 233536, "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": 2588620, "raw_average_key_size": 20, "raw_value_size": 131629857, "raw_average_value_size": 1016, "num_data_blocks": 1, "num_entries": 129431, "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": 1732327039, "oldest_key_time": 0, "file_creation_time": 1732327039, "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": 255059, "seqno_to_time_mapping": "N/A", "__indexstats__": "[...1 records...]"}} 2024/11/23-09:57:21.608600 139957676762688 [top_zip_table_builder.cc:934] ToplingZipTableBuilder(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-23921/att-00/255060.sst) /dev/shm/ToplingTemp/Topling-18927-Bd97oo 2024/11/23-09:57:21.616179 139957676762688 [top_zip_table_builder.cc:1352] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-23921/att-00/255060.sst): first pass time = 0.01's, 197.805'MB/sec 2024/11/23-09:57:21.642449 139957676762688 [top_zip_table_builder.cc:2772] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-23921/att-00/255060.sst): old prealloc_size = 147639500, real_size = 474144 2024/11/23-09:57:21.642495 139957676762688 [top_zip_table_builder.cc:1773] BuildReorderMap(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-23921/att-00/255060.sst): index type = UintIndex_IL_256_32 , store type = DictZipBlobStore usrkeys = 1437 prefix = 4 raw-key = 0.0000 GB zip-key = 0.0000 GB avg-key = 8.00 avg-zkey = 0.33 raw-val = 0.0015 GB zip-val = 0.0005 GB avg-val =1016.96 avg-zval = 329.25 2024/11/23-09:57:21.644475 139957676762688 [top_zip_table_builder.cc:3100] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-23921/att-00/255060.sst): second pass time = 0.03's, 56.905'MB/sec, value only(98.1% of KV) wait indexing time = 0.00's, remap KeyValue time = 0.00's, 249.373'MB/sec (all stages of remap) Get OrderMap time = 0.00's, 16.805'MB/sec (index lex order gen) rebuild zvType time = 0.00's, 0.289'MB/sec write SST data time = 0.00's, 674.989'MB/sec dict compress time = 4356233619.02's, 0.000'MB/sec z-dict build time = 0.00's, sample length = 0.022'MB, throughput = 1064.571'MB/sec zip my value time = 0.03's, unzip length = 0.001'GB zip my value throughput = 56.905'MB/sec zip pipeline throughput = 56.905'MB/sec entries = 1437 avg-key = 20.00 avg-zkey = 0.36 avg-val = 1016.96 avg-zval = 329.25 usrkeys = 1437 avg-key = 12.00 avg-zkey = 0.36 avg-val = 1023.96 avg-zval = 329.25 TagRS{ kinds = RS_Key0_Tag1 bytes = 0 } TagArray{ size = 1437 bytes = 5408 } seq expand size = 10059 multi value expand size = 0 cnt WriteAppend = 11 UnZipSize{ index = 0.0000 GB value = 0.0015 GB dict = 0.02 MB all = 0.0015 GB } __ZipSize{ index = 0.0000 GB value = 0.0005 GB dict = 0.02 MB all = 0.0005 GB } UnZip/Zip{ index = 33.6797 value = 3.0887 dict = 1.00 all = 2.9645 } Zip/UnZip{ index = 0.0297 value = 0.3238 dict = 1.00 all = 0.3373 } ---------------------------- total value len = 10.042912 GB avg = 0.188 KB (by entry num) total key len = 1.464147 GB avg = 0.027 KB total ukey len = 1.011874 GB avg = 0.015 KB total ukey num = 0.066716751 Billion total entry num = 0.053524480 Billion write speed all = 3.085474250 MB/sec (with seq num) write speed all = 2.970658888 MB/sec (without seq num) 2024/11/23-09:57:21.651316 139957676762688 [compaction_job.cc:1948] [default] [JOB 23921] Generated table #255060: 1437 keys, 502645 bytes, temperature: kWarm 2024/11/23-09:57:21.651365 139957676762688 EVENT_LOG_v1 {"time_micros": 1732327041651337, "cf_name": "default", "job": 23921, "event": "table_file_creation", "file_number": 255060, "file_size": 502645, "file_checksum": "", "file_checksum_func_name": "Unknown", "smallest_seqno": 3318197105, "largest_seqno": 3916581309, "table_properties": {"data_size": 473136, "index_size": 512, "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": 28740, "raw_average_key_size": 20, "raw_value_size": 1461374, "raw_average_value_size": 1016, "num_data_blocks": 1, "num_entries": 1437, "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-UintIndex_IL_256_32-DictZipBlobStore", "creation_time": 1732327041, "oldest_key_time": 0, "file_creation_time": 1732327041, "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": 255060, "seqno_to_time_mapping": "N/A", "__indexstats__": "[...1 records...]"}} 2024/11/23-09:57:21.658941 139957676762688 [compaction_job.cc:703] job-23921: subcompact[0], size: 0.383952 G, files: /storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-23921/att-00 [255051,255052,255053,255054,255055,255056,255057,255058,255059,255060] 2024/11/23-09:57:21.723944 139957676762688 (Original Log Time 2024/11/23-09:57:03.346179) EVENT_LOG_v1 {"time_micros": 1732327023346153, "job": 23921, "event": "compaction_started", "compaction_reason": "FilesMarkedForCompaction", "files_L2": [255047], "files_L3": [254962, 254963, 254964, 254965, 254966, 254967, 254968, 254969, 254978], "score": 0.994265, "input_data_size": 416148812, "oldest_snapshot_seqno": 3918111319} 2024/11/23-09:57:21.723947 139957676762688 (Original Log Time 2024/11/23-09:57:21.719500) [compaction_job.cc:2022] [default] [JOB 23921] Compacted 1@2 + 9@3 files to L3 => 383951871 bytes 2024/11/23-09:57:21.723949 139957676762688 (Original Log Time 2024/11/23-09:57:21.723873) [compaction_job.cc:1224] [default] compacted to: files[0 0 0 10 0 0 0] max score 0.01, MB/sec: 22.7 rd, 21.0 wr, level 3, files in(1, 9) out(10 +0 blob) MB in(31.2, 365.7 +0.0 blob) out(366.2 +0.0 blob), read-write-amplify(24.5) write-amplify(11.7) OK, records in: 1196781, records dropped: 30465 output_compression: Snappy 2024/11/23-09:57:21.723955 139957676762688 (Original Log Time 2024/11/23-09:57:21.723924) EVENT_LOG_v1 {"time_micros": 1732327041723899, "cf": "default", "job": 23921, "event": "compaction_finished", "compaction_time_micros": 18312766, "compaction_time_cpu_micros": 3705177, "output_level": 3, "num_output_files": 10, "total_output_size": 383951871, "num_input_records": 1196781, "num_output_records": 1166316, "num_subcompactions": 1, "output_compression": "Snappy", "num_single_delete_mismatches": 0, "num_single_delete_fallthrough": 0, "file_write_nanos": 348483728, "file_range_sync_nanos": 0, "file_fsync_nanos": 6655, "file_prepare_write_nanos": 30940, "lsm_state": [0, 0, 0, 10, 0, 0, 0]} 2024/11/23-09:57:21.858955 139957676762688 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":1732327023,"executesMs":18507,"compactionJobId":23921,"attempt":0,"compactionInputRawBytes":1241046632,"compactionInputZipBytes":416148812,"compactionOutputRawBytes":0,"compactionOutputZipBytes":383951871,"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-7c6d4aa793e28f805b89b18f0ff41a21-1b8676ca68ef4316-00"}}, response = 2024/11/23-09:57:21.859056 139957676762688 INFO dcompact_worker.cpp:1459: finish /worker/dcompact-log/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/2024-11-22T14.14.14/.rocksdb/job-23921/att-00: olev 3, work 18.508 s, result 5.910 ms, install 4.472 ms, input{raw 1.156 GiB zip 396.870 MiB}