2024/11/22-18:58:07.447039 140024726648384 DEBG dcompact_worker.cpp:2214: /storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-08202/att-00: fork to child time = 0.009688 sec 2024/11/22-18:58:07.448123 140024726648384 DEBG dcompact_worker.cpp:1013: Beg SerDeRead: /storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-08202/att-00 2024/11/22-18:58:07.455407 140024726648384 DEBG dcompact_worker.cpp:1033: End SerDeRead: /storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-08202/att-00 2024/11/22-18:58:07.456545 140024726648384 TRAC dcompact_worker.cpp:1083: INFO_LOG_LEVEL: rpc = INFO_LEVEL, env_var = undefined 2024/11/22-18:58:07.456832 140024726648384 [WARN] [column_family.cc:386] multiple cf_paths/db_paths and level_compaction_dynamic_level_bytes can't be used together 2024/11/22-18:58:07.505447 140024726648384 [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-08202/att-00/MANIFEST-206347 2024/11/22-18:58:07.518484 140024726648384 [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-08202/att-00/MANIFEST-206347 succeeded,manifest_file_number is 206347, next_file_number is 221889, last_sequence is 3484292213, log_number is 0,prev_log_number is 0,max_column_family is 0,min_log_number_to_keep is 221723 2024/11/22-18:58:07.518491 140024726648384 [version_set.cc:6266] Column family [default] (ID 0), log number is 0 2024/11/22-18:58:07.518594 140024726648384 [version_set.cc:5721] Creating manifest 221889 2024/11/22-18:58:07.552207 140024726648384 DEBG dcompact_worker.cpp:1218: /storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-08202/att-00: bottommost_level: fake = 1, rpc = 0 2024/11/22-18:58:07.571464 140024726648384 [compaction_job.cc:2437] [default] [JOB 8202] Compacting 1@2 + 15@3 files to L3, score 0.96, subcompactions 1 : 1 2024/11/22-18:58:07.571484 140024726648384 [compaction_job.cc:2445] [default]: Compaction start summary: Base version 6223 Base level 2, inputs: [221861(27MB)], [221815(35MB) 221816(35MB) 221817(35MB) 221818(34MB) 221819(35MB) 221820(35MB) 221821(36MB) 221822(35MB) 221823(35MB) 221824(35MB) 221825(35MB) 221826(10220KB) 221786(35MB) 221787(36MB) 221882(36MB)] 2024/11/22-18:58:07.577359 140024726648384 [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/22-18:58:07.577390 140024726648384 [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/22-18:58:07.577409 140024726648384 [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/22-18:58:07.577414 140024726648384 [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/22-18:58:07.577426 140024726648384 [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/22-18:58:08.036861 140024726648384 [top_zip_table_builder.cc:934] ToplingZipTableBuilder(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-08202/att-00/221890.sst) /dev/shm/ToplingTemp/Topling-249838-vgdBoM 2024/11/22-18:58:08.643963 140024726648384 [top_zip_table_builder.cc:1352] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-08202/att-00/221890.sst): first pass time = 0.61's, 221.100'MB/sec 2024/11/22-18:58:11.899825 140024726648384 [top_zip_table_builder.cc:2772] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-08202/att-00/221890.sst): old prealloc_size = 147639500, real_size = 33570960 2024/11/22-18:58:11.900525 140024726648384 [top_zip_table_builder.cc:1773] BuildReorderMap(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-08202/att-00/221890.sst): index type = NestLoudsTrieDAWG_Mixed_XL_256_32_FL, store type = DictZipBlobStore usrkeys = 924918 prefix = 4 raw-key = 0.0148 GB zip-key = 0.0018 GB avg-key = 16.00 avg-zkey = 1.90 raw-val = 0.1083 GB zip-val = 0.0316 GB avg-val = 117.11 avg-zval = 34.14 2024/11/22-18:58:11.901345 140024726648384 [top_zip_table_builder.cc:1299] /storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-08202/att-00/221890.sst: sumWaitingMem = 0.002 GB, sumWorkingMem = 0.000 GB, reorder workingMem = 0.0023 GB, waited 0.000 sec, Key+Value = 0.134 GB 2024/11/22-18:58:13.124511 140024726648384 [top_zip_table_builder.cc:3100] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-08202/att-00/221890.sst): second pass time = 3.25's, 33.293'MB/sec, value only(80.7% of KV) wait indexing time = 0.00's, remap KeyValue time = 1.22's, 30.659'MB/sec (all stages of remap) Get OrderMap time = 0.00's, 2586.310'MB/sec (index lex order gen) rebuild zvType time = 1.07's, 0.217'MB/sec write SST data time = 0.16's, 239.355'MB/sec dict compress time = 4356233619.02's, 0.000'MB/sec z-dict build time = 0.00's, sample length = 1.081'MB, throughput = 32393.947'MB/sec zip my value time = 3.25's, unzip length = 0.108'GB zip my value throughput = 33.293'MB/sec zip pipeline throughput = 69.624'MB/sec entries = 924918 avg-key = 28.00 avg-zkey = 1.90 avg-val = 117.11 avg-zval = 34.14 usrkeys = 924918 avg-key = 20.00 avg-zkey = 1.90 avg-val = 124.11 avg-zval = 34.14 TagRS{ kinds = RS_Key0_Tag1 bytes = 0 } TagArray{ size = 924918 bytes = 3121616 } seq expand size = 6474426 multi value expand size = 0 cnt WriteAppend = 13 UnZipSize{ index = 0.0185 GB value = 0.1083 GB dict = 1.08 MB all = 0.1342 GB } __ZipSize{ index = 0.0018 GB value = 0.0316 GB dict = 1.08 MB all = 0.0375 GB } UnZip/Zip{ index = 10.5131 value = 3.4300 dict = 1.00 all = 3.5746 } Zip/UnZip{ index = 0.0951 value = 0.2915 dict = 1.00 all = 0.2798 } ---------------------------- total value len = 0.114795 GB avg = 0.124 KB (by entry num) total key len = 0.025898 GB avg = 0.028 KB total ukey len = 0.018498 GB avg = 0.020 KB total ukey num = 0.000924918 Billion total entry num = 0.000924918 Billion write speed all = 0.003870485 MB/sec (with seq num) write speed all = 0.003666927 MB/sec (without seq num) 2024/11/22-18:58:13.159851 140024726648384 [compaction_job.cc:1948] [default] [JOB 8202] Generated table #221890: 924918 keys, 37548138 bytes, temperature: kWarm 2024/11/22-18:58:13.159933 140024726648384 EVENT_LOG_v1 {"time_micros": 1732273093159882, "cf_name": "default", "job": 8202, "event": "table_file_creation", "file_number": 221890, "file_size": 37548138, "file_checksum": "", "file_checksum_func_name": "Unknown", "smallest_seqno": 3376774746, "largest_seqno": 3484020268, "table_properties": {"data_size": 31579968, "index_size": 1759552, "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": 25897704, "raw_average_key_size": 28, "raw_value_size": 108320124, "raw_average_value_size": 117, "num_data_blocks": 1, "num_entries": 924918, "num_filter_entries": 0, "num_deletions": 0, "num_merge_operands": 0, "num_range_deletions": 0, "format_version": 0, "fixed_key_len": 28, "fixed_value_len": 18446744073709551615, "filter_policy": "", "column_family_name": "default", "column_family_id": 0, "comparator": "leveldb.BytewiseComparator", "merge_operator": "nullptr", "prefix_extractor_name": "nullptr", "property_collectors": "[Rdb_tbl_prop_coll_factory]", "compression": "FlatZip", "compression_options": "RS_Key0_Tag1-NestLoudsTrieDAWG_Mixed_XL_256_32_FL-DictZipBlobStore", "creation_time": 1732273088, "oldest_key_time": 0, "file_creation_time": 1732273088, "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": 221890, "seqno_to_time_mapping": "N/A", "__indexstats__": "[...1 records...]"}} 2024/11/22-18:58:13.201178 140024726648384 [top_zip_table_builder.cc:934] ToplingZipTableBuilder(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-08202/att-00/221891.sst) /dev/shm/ToplingTemp/Topling-249838-rhbWdN 2024/11/22-18:58:14.284459 140024726648384 [top_zip_table_builder.cc:1352] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-08202/att-00/221891.sst): first pass time = 1.08's, 123.903'MB/sec 2024/11/22-18:58:17.102817 140024726648384 [top_zip_table_builder.cc:2772] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-08202/att-00/221891.sst): old prealloc_size = 147639500, real_size = 33664384 2024/11/22-18:58:17.103512 140024726648384 [top_zip_table_builder.cc:1773] BuildReorderMap(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-08202/att-00/221891.sst): index type = NestLoudsTrieDAWG_Mixed_XL_256_32_FL, store type = DictZipBlobStore usrkeys = 922974 prefix = 4 raw-key = 0.0148 GB zip-key = 0.0018 GB avg-key = 16.00 avg-zkey = 1.90 raw-val = 0.1084 GB zip-val = 0.0317 GB avg-val = 117.42 avg-zval = 34.32 2024/11/22-18:58:17.104370 140024726648384 [top_zip_table_builder.cc:1299] /storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-08202/att-00/221891.sst: sumWaitingMem = 0.002 GB, sumWorkingMem = 0.000 GB, reorder workingMem = 0.0023 GB, waited 0.000 sec, Key+Value = 0.134 GB 2024/11/22-18:58:18.176896 140024726648384 [top_zip_table_builder.cc:3100] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-08202/att-00/221891.sst): second pass time = 2.82's, 38.463'MB/sec, value only(80.7% of KV) wait indexing time = 0.00's, remap KeyValue time = 1.07's, 35.042'MB/sec (all stages of remap) Get OrderMap time = 0.00's, 2598.310'MB/sec (index lex order gen) rebuild zvType time = 0.88's, 0.262'MB/sec write SST data time = 0.19's, 193.823'MB/sec dict compress time = 4356233619.02's, 0.000'MB/sec z-dict build time = 0.00's, sample length = 1.083'MB, throughput = 32891.494'MB/sec zip my value time = 2.82's, unzip length = 0.108'GB zip my value throughput = 38.463'MB/sec zip pipeline throughput = 58.996'MB/sec entries = 922974 avg-key = 28.00 avg-zkey = 1.90 avg-val = 117.42 avg-zval = 34.32 usrkeys = 922974 avg-key = 20.00 avg-zkey = 1.90 avg-val = 124.42 avg-zval = 34.32 TagRS{ kinds = RS_Key0_Tag1 bytes = 0 } TagArray{ size = 922974 bytes = 3115056 } seq expand size = 6460818 multi value expand size = 0 cnt WriteAppend = 13 UnZipSize{ index = 0.0185 GB value = 0.1084 GB dict = 1.08 MB all = 0.1342 GB } __ZipSize{ index = 0.0018 GB value = 0.0317 GB dict = 1.08 MB all = 0.0376 GB } UnZip/Zip{ index = 10.5140 value = 3.4212 dict = 1.00 all = 3.5661 } Zip/UnZip{ index = 0.0951 value = 0.2923 dict = 1.00 all = 0.2804 } ---------------------------- total value len = 0.229630 GB avg = 0.124 KB (by entry num) total key len = 0.051741 GB avg = 0.028 KB total ukey len = 0.036958 GB avg = 0.020 KB total ukey num = 0.001847892 Billion total entry num = 0.001847892 Billion write speed all = 0.007739521 MB/sec (with seq num) write speed all = 0.007332889 MB/sec (without seq num) 2024/11/22-18:58:18.219481 140024726648384 [compaction_job.cc:1948] [default] [JOB 8202] Generated table #221891: 922974 keys, 37637596 bytes, temperature: kWarm 2024/11/22-18:58:18.219584 140024726648384 EVENT_LOG_v1 {"time_micros": 1732273098219541, "cf_name": "default", "job": 8202, "event": "table_file_creation", "file_number": 221891, "file_size": 37637596, "file_checksum": "", "file_checksum_func_name": "Unknown", "smallest_seqno": 3376774786, "largest_seqno": 3484020278, "table_properties": {"data_size": 31677744, "index_size": 1755712, "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": 25843272, "raw_average_key_size": 28, "raw_value_size": 108374556, "raw_average_value_size": 117, "num_data_blocks": 1, "num_entries": 922974, "num_filter_entries": 0, "num_deletions": 0, "num_merge_operands": 0, "num_range_deletions": 0, "format_version": 0, "fixed_key_len": 28, "fixed_value_len": 18446744073709551615, "filter_policy": "", "column_family_name": "default", "column_family_id": 0, "comparator": "leveldb.BytewiseComparator", "merge_operator": "nullptr", "prefix_extractor_name": "nullptr", "property_collectors": "[Rdb_tbl_prop_coll_factory]", "compression": "FlatZip", "compression_options": "RS_Key0_Tag1-NestLoudsTrieDAWG_Mixed_XL_256_32_FL-DictZipBlobStore", "creation_time": 1732273093, "oldest_key_time": 0, "file_creation_time": 1732273093, "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": 221891, "seqno_to_time_mapping": "N/A", "__indexstats__": "[...1 records...]"}} 2024/11/22-18:58:18.268651 140024726648384 [top_zip_table_builder.cc:934] ToplingZipTableBuilder(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-08202/att-00/221892.sst) /dev/shm/ToplingTemp/Topling-249838-DtONsN 2024/11/22-18:58:19.272966 140024726648384 [top_zip_table_builder.cc:1352] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-08202/att-00/221892.sst): first pass time = 1.00's, 133.648'MB/sec 2024/11/22-18:58:22.021649 140024726648384 [top_zip_table_builder.cc:2772] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-08202/att-00/221892.sst): old prealloc_size = 147639500, real_size = 33275648 2024/11/22-18:58:22.022242 140024726648384 [top_zip_table_builder.cc:1773] BuildReorderMap(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-08202/att-00/221892.sst): index type = NestLoudsTrieDAWG_Mixed_XL_256_32_FL, store type = DictZipBlobStore usrkeys = 927181 prefix = 4 raw-key = 0.0148 GB zip-key = 0.0018 GB avg-key = 16.00 avg-zkey = 1.90 raw-val = 0.1083 GB zip-val = 0.0313 GB avg-val = 116.76 avg-zval = 33.74 2024/11/22-18:58:22.022978 140024726648384 [top_zip_table_builder.cc:1299] /storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-08202/att-00/221892.sst: sumWaitingMem = 0.002 GB, sumWorkingMem = 0.000 GB, reorder workingMem = 0.0023 GB, waited 0.000 sec, Key+Value = 0.134 GB 2024/11/22-18:58:22.984513 140024726648384 [top_zip_table_builder.cc:3100] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-08202/att-00/221892.sst): second pass time = 2.75's, 39.401'MB/sec, value only(80.7% of KV) wait indexing time = 0.00's, remap KeyValue time = 0.96's, 38.632'MB/sec (all stages of remap) Get OrderMap time = 0.00's, 3085.748'MB/sec (index lex order gen) rebuild zvType time = 0.82's, 0.282'MB/sec write SST data time = 0.14's, 263.612'MB/sec dict compress time = 4356233619.02's, 0.000'MB/sec z-dict build time = 0.00's, sample length = 1.080'MB, throughput = 25781.002'MB/sec zip my value time = 2.75's, unzip length = 0.108'GB zip my value throughput = 39.401'MB/sec zip pipeline throughput = 93.659'MB/sec entries = 927181 avg-key = 28.00 avg-zkey = 1.90 avg-val = 116.76 avg-zval = 33.74 usrkeys = 927181 avg-key = 20.00 avg-zkey = 1.90 avg-val = 123.29 avg-zval = 33.74 TagRS{ kinds = RS_Key0_TagN bytes = 144960 } TagArray{ size = 864657 bytes = 2918240 } seq expand size = 6052599 multi value expand size = 0 cnt WriteAppend = 15 UnZipSize{ index = 0.0185 GB value = 0.1083 GB dict = 1.08 MB all = 0.1342 GB } __ZipSize{ index = 0.0018 GB value = 0.0313 GB dict = 1.08 MB all = 0.0372 GB } UnZip/Zip{ index = 10.5140 value = 3.4609 dict = 1.00 all = 3.6083 } Zip/UnZip{ index = 0.0951 value = 0.2889 dict = 1.00 all = 0.2771 } ---------------------------- total value len = 0.343939 GB avg = 0.124 KB (by entry num) total key len = 0.077702 GB avg = 0.028 KB total ukey len = 0.055501 GB avg = 0.020 KB total ukey num = 0.002775073 Billion total entry num = 0.002775073 Billion write speed all = 0.011596332 MB/sec (with seq num) write speed all = 0.010985753 MB/sec (without seq num) 2024/11/22-18:58:23.020153 140024726648384 [compaction_job.cc:1948] [default] [JOB 8202] Generated table #221892: 927181 keys, 37196784 bytes, temperature: kWarm 2024/11/22-18:58:23.020229 140024726648384 EVENT_LOG_v1 {"time_micros": 1732273103020184, "cf_name": "default", "job": 8202, "event": "table_file_creation", "file_number": 221892, "file_size": 37196784, "file_checksum": "", "file_checksum_func_name": "Unknown", "smallest_seqno": 0, "largest_seqno": 3484020321, "table_properties": {"data_size": 31279984, "index_size": 1763712, "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": 25961068, "raw_average_key_size": 28, "raw_value_size": 108256786, "raw_average_value_size": 116, "num_data_blocks": 1, "num_entries": 927181, "num_filter_entries": 0, "num_deletions": 0, "num_merge_operands": 0, "num_range_deletions": 0, "format_version": 0, "fixed_key_len": 28, "fixed_value_len": 18446744073709551615, "filter_policy": "", "column_family_name": "default", "column_family_id": 0, "comparator": "leveldb.BytewiseComparator", "merge_operator": "nullptr", "prefix_extractor_name": "nullptr", "property_collectors": "[Rdb_tbl_prop_coll_factory]", "compression": "FlatZip", "compression_options": "RS_Key0_TagN-NestLoudsTrieDAWG_Mixed_XL_256_32_FL-DictZipBlobStore", "creation_time": 1732273098, "oldest_key_time": 0, "file_creation_time": 1732273098, "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": 221892, "seqno_to_time_mapping": "N/A", "__indexstats__": "[...1 records...]"}} 2024/11/22-18:58:23.059509 140024726648384 [top_zip_table_builder.cc:934] ToplingZipTableBuilder(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-08202/att-00/221893.sst) /dev/shm/ToplingTemp/Topling-249838-8h74jN 2024/11/22-18:58:24.320777 140024726648384 [top_zip_table_builder.cc:1352] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-08202/att-00/221893.sst): first pass time = 1.26's, 106.418'MB/sec 2024/11/22-18:58:26.249966 140024726648384 [top_zip_table_builder.cc:2772] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-08202/att-00/221893.sst): old prealloc_size = 147639500, real_size = 34983520 2024/11/22-18:58:26.250657 140024726648384 [top_zip_table_builder.cc:1773] BuildReorderMap(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-08202/att-00/221893.sst): index type = NestLoudsTrieDAWG_Mixed_XL_256_32_FL, store type = DictZipBlobStore usrkeys = 1081570 prefix = 4 raw-key = 0.0173 GB zip-key = 0.0021 GB avg-key = 16.00 avg-zkey = 1.91 raw-val = 0.1039 GB zip-val = 0.0326 GB avg-val = 96.10 avg-zval = 30.18 2024/11/22-18:58:26.250704 140024726648384 [top_zip_table_builder.cc:1299] /storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-08202/att-00/221893.sst: sumWaitingMem = 0.003 GB, sumWorkingMem = 0.000 GB, reorder workingMem = 0.0028 GB, waited 0.000 sec, Key+Value = 0.134 GB 2024/11/22-18:58:26.887957 140024726648384 [top_zip_table_builder.cc:3100] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-08202/att-00/221893.sst): second pass time = 1.93's, 53.894'MB/sec, value only(77.4% of KV) wait indexing time = 0.00's, remap KeyValue time = 0.64's, 56.045'MB/sec (all stages of remap) Get OrderMap time = 0.00's, 3104.540'MB/sec (index lex order gen) rebuild zvType time = 0.50's, 0.546'MB/sec write SST data time = 0.14's, 251.385'MB/sec dict compress time = 4356233619.02's, 0.000'MB/sec z-dict build time = 0.00's, sample length = 1.032'MB, throughput = 32441.879'MB/sec zip my value time = 1.93's, unzip length = 0.104'GB zip my value throughput = 53.894'MB/sec zip pipeline throughput = 107.390'MB/sec entries = 1081570 avg-key = 28.00 avg-zkey = 1.91 avg-val = 96.10 avg-zval = 30.18 usrkeys = 1081570 avg-key = 20.00 avg-zkey = 1.91 avg-val = 96.10 avg-zval = 30.18 TagRS{ kinds = LegacyZvType bytes = 0 } TagArray{ size = 0 bytes = 0 } seq expand size = 0 multi value expand size = 0 cnt WriteAppend = 11 UnZipSize{ index = 0.0216 GB value = 0.1039 GB dict = 1.03 MB all = 0.1342 GB } __ZipSize{ index = 0.0021 GB value = 0.0326 GB dict = 1.03 MB all = 0.0358 GB } UnZip/Zip{ index = 10.4476 value = 3.1840 dict = 1.00 all = 3.7538 } Zip/UnZip{ index = 0.0957 value = 0.3141 dict = 1.00 all = 0.2664 } ---------------------------- total value len = 0.447873 GB avg = 0.116 KB (by entry num) total key len = 0.107986 GB avg = 0.028 KB total ukey len = 0.077133 GB avg = 0.020 KB total ukey num = 0.003856643 Billion total entry num = 0.003856643 Billion write speed all = 0.015286062 MB/sec (with seq num) write speed all = 0.014437604 MB/sec (without seq num) 2024/11/22-18:58:26.919118 140024726648384 [compaction_job.cc:1948] [default] [JOB 8202] Generated table #221893: 1081570 keys, 35755492 bytes, temperature: kWarm 2024/11/22-18:58:26.919192 140024726648384 EVENT_LOG_v1 {"time_micros": 1732273106919151, "cf_name": "default", "job": 8202, "event": "table_file_creation", "file_number": 221893, "file_size": 35755492, "file_checksum": "", "file_checksum_func_name": "Unknown", "smallest_seqno": 0, "largest_seqno": 0, "table_properties": {"data_size": 32642448, "index_size": 2070464, "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": 30283960, "raw_average_key_size": 28, "raw_value_size": 103933849, "raw_average_value_size": 96, "num_data_blocks": 1, "num_entries": 1081570, "num_filter_entries": 0, "num_deletions": 0, "num_merge_operands": 0, "num_range_deletions": 0, "format_version": 0, "fixed_key_len": 28, "fixed_value_len": 18446744073709551615, "filter_policy": "", "column_family_name": "default", "column_family_id": 0, "comparator": "leveldb.BytewiseComparator", "merge_operator": "nullptr", "prefix_extractor_name": "nullptr", "property_collectors": "[Rdb_tbl_prop_coll_factory]", "compression": "TooZip", "compression_options": "LegacyZvType-NestLoudsTrieDAWG_Mixed_XL_256_32_FL-DictZipBlobStore", "creation_time": 1732273103, "oldest_key_time": 0, "file_creation_time": 1732273103, "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": 221893, "seqno_to_time_mapping": "N/A", "__indexstats__": "[...1 records...]"}} 2024/11/22-18:58:26.957621 140024726648384 [top_zip_table_builder.cc:934] ToplingZipTableBuilder(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-08202/att-00/221894.sst) /dev/shm/ToplingTemp/Topling-249838-vRCvcK 2024/11/22-18:58:28.412233 140024726648384 [top_zip_table_builder.cc:1352] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-08202/att-00/221894.sst): first pass time = 1.45's, 92.273'MB/sec 2024/11/22-18:58:29.899262 140024726648384 [top_zip_table_builder.cc:2772] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-08202/att-00/221894.sst): old prealloc_size = 147639500, real_size = 33849792 2024/11/22-18:58:29.899823 140024726648384 [top_zip_table_builder.cc:1773] BuildReorderMap(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-08202/att-00/221894.sst): index type = NestLoudsTrieDAWG_Mixed_XL_256_32_FL, store type = DictZipBlobStore usrkeys = 924941 prefix = 4 raw-key = 0.0148 GB zip-key = 0.0018 GB avg-key = 16.00 avg-zkey = 1.91 raw-val = 0.1083 GB zip-val = 0.0318 GB avg-val = 117.11 avg-zval = 34.43 2024/11/22-18:58:29.900448 140024726648384 [top_zip_table_builder.cc:1299] /storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-08202/att-00/221894.sst: sumWaitingMem = 0.002 GB, sumWorkingMem = 0.000 GB, reorder workingMem = 0.0023 GB, waited 0.000 sec, Key+Value = 0.134 GB 2024/11/22-18:58:30.941605 140024726648384 [top_zip_table_builder.cc:3100] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-08202/att-00/221894.sst): second pass time = 1.49's, 72.905'MB/sec, value only(80.7% of KV) wait indexing time = 0.00's, remap KeyValue time = 1.04's, 35.724'MB/sec (all stages of remap) Get OrderMap time = 0.00's, 3318.849'MB/sec (index lex order gen) rebuild zvType time = 0.91's, 0.254'MB/sec write SST data time = 0.13's, 285.219'MB/sec dict compress time = 4356233619.02's, 0.000'MB/sec z-dict build time = 0.00's, sample length = 1.081'MB, throughput = 31130.918'MB/sec zip my value time = 1.49's, unzip length = 0.108'GB zip my value throughput = 72.905'MB/sec zip pipeline throughput = 72.905'MB/sec entries = 924941 avg-key = 28.00 avg-zkey = 1.91 avg-val = 117.11 avg-zval = 34.43 usrkeys = 924941 avg-key = 20.00 avg-zkey = 1.91 avg-val = 122.26 avg-zval = 34.43 TagRS{ kinds = RS_Key0_TagN bytes = 144640 } TagArray{ size = 680632 bytes = 2382224 } seq expand size = 4764424 multi value expand size = 0 cnt WriteAppend = 17 UnZipSize{ index = 0.0185 GB value = 0.1083 GB dict = 1.08 MB all = 0.1342 GB } __ZipSize{ index = 0.0018 GB value = 0.0318 GB dict = 1.08 MB all = 0.0372 GB } UnZip/Zip{ index = 10.4442 value = 3.4012 dict = 1.00 all = 3.6045 } Zip/UnZip{ index = 0.0957 value = 0.2940 dict = 1.00 all = 0.2774 } ---------------------------- total value len = 0.560957 GB avg = 0.117 KB (by entry num) total key len = 0.133884 GB avg = 0.028 KB total ukey len = 0.095632 GB avg = 0.020 KB total ukey num = 0.004781584 Billion total entry num = 0.004781584 Billion write speed all = 0.019105927 MB/sec (with seq num) write speed all = 0.018054100 MB/sec (without seq num) 2024/11/22-18:58:30.973405 140024726648384 [compaction_job.cc:1948] [default] [JOB 8202] Generated table #221894: 924941 keys, 37235950 bytes, temperature: kWarm 2024/11/22-18:58:30.973469 140024726648384 EVENT_LOG_v1 {"time_micros": 1732273110973436, "cf_name": "default", "job": 8202, "event": "table_file_creation", "file_number": 221894, "file_size": 37235950, "file_checksum": "", "file_checksum_func_name": "Unknown", "smallest_seqno": 0, "largest_seqno": 3484020388, "table_properties": {"data_size": 31847152, "index_size": 1771200, "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": 25898348, "raw_average_key_size": 28, "raw_value_size": 108319470, "raw_average_value_size": 117, "num_data_blocks": 1, "num_entries": 924941, "num_filter_entries": 0, "num_deletions": 0, "num_merge_operands": 0, "num_range_deletions": 0, "format_version": 0, "fixed_key_len": 28, "fixed_value_len": 18446744073709551615, "filter_policy": "", "column_family_name": "default", "column_family_id": 0, "comparator": "leveldb.BytewiseComparator", "merge_operator": "nullptr", "prefix_extractor_name": "nullptr", "property_collectors": "[Rdb_tbl_prop_coll_factory]", "compression": "FlatZip", "compression_options": "RS_Key0_TagN-NestLoudsTrieDAWG_Mixed_XL_256_32_FL-DictZipBlobStore", "creation_time": 1732273106, "oldest_key_time": 0, "file_creation_time": 1732273106, "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": 221894, "seqno_to_time_mapping": "N/A", "__indexstats__": "[...1 records...]"}} 2024/11/22-18:58:31.010811 140024726648384 [top_zip_table_builder.cc:934] ToplingZipTableBuilder(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-08202/att-00/221895.sst) /dev/shm/ToplingTemp/Topling-249838-ITgzrK 2024/11/22-18:58:31.906128 140024726648384 [top_zip_table_builder.cc:1352] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-08202/att-00/221895.sst): first pass time = 0.90's, 149.918'MB/sec 2024/11/22-18:58:33.319666 140024726648384 [top_zip_table_builder.cc:2772] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-08202/att-00/221895.sst): old prealloc_size = 147639500, real_size = 33536640 2024/11/22-18:58:33.320225 140024726648384 [top_zip_table_builder.cc:1773] BuildReorderMap(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-08202/att-00/221895.sst): index type = NestLoudsTrieDAWG_Mixed_XL_256_32_FL, store type = DictZipBlobStore usrkeys = 923024 prefix = 4 raw-key = 0.0148 GB zip-key = 0.0018 GB avg-key = 16.00 avg-zkey = 1.90 raw-val = 0.1084 GB zip-val = 0.0315 GB avg-val = 117.41 avg-zval = 34.18 2024/11/22-18:58:33.320941 140024726648384 [top_zip_table_builder.cc:1299] /storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-08202/att-00/221895.sst: sumWaitingMem = 0.002 GB, sumWorkingMem = 0.000 GB, reorder workingMem = 0.0023 GB, waited 0.000 sec, Key+Value = 0.134 GB 2024/11/22-18:58:34.243837 140024726648384 [top_zip_table_builder.cc:3100] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-08202/att-00/221895.sst): second pass time = 1.41's, 76.703'MB/sec, value only(80.7% of KV) wait indexing time = 0.00's, remap KeyValue time = 0.92's, 40.589'MB/sec (all stages of remap) Get OrderMap time = 0.00's, 3273.886'MB/sec (index lex order gen) rebuild zvType time = 0.80's, 0.289'MB/sec write SST data time = 0.13's, 296.925'MB/sec dict compress time = 4356233619.02's, 0.000'MB/sec z-dict build time = 0.00's, sample length = 1.083'MB, throughput = 33907.198'MB/sec zip my value time = 1.41's, unzip length = 0.108'GB zip my value throughput = 76.703'MB/sec zip pipeline throughput = 76.703'MB/sec entries = 923024 avg-key = 28.00 avg-zkey = 1.90 avg-val = 117.41 avg-zval = 34.18 usrkeys = 923024 avg-key = 20.00 avg-zkey = 1.90 avg-val = 124.41 avg-zval = 34.18 TagRS{ kinds = RS_Key0_Tag1 bytes = 0 } TagArray{ size = 923024 bytes = 3115216 } seq expand size = 6461168 multi value expand size = 0 cnt WriteAppend = 13 UnZipSize{ index = 0.0185 GB value = 0.1084 GB dict = 1.08 MB all = 0.1342 GB } __ZipSize{ index = 0.0018 GB value = 0.0315 GB dict = 1.08 MB all = 0.0375 GB } UnZip/Zip{ index = 10.5122 value = 3.4350 dict = 1.00 all = 3.5782 } Zip/UnZip{ index = 0.0951 value = 0.2911 dict = 1.00 all = 0.2795 } ---------------------------- total value len = 0.675791 GB avg = 0.118 KB (by entry num) total key len = 0.159729 GB avg = 0.028 KB total ukey len = 0.114092 GB avg = 0.020 KB total ukey num = 0.005704608 Billion total entry num = 0.005704608 Billion write speed all = 0.022972067 MB/sec (with seq num) write speed all = 0.021717312 MB/sec (without seq num) 2024/11/22-18:58:34.278125 140024726648384 [compaction_job.cc:1948] [default] [JOB 8202] Generated table #221895: 923024 keys, 37509922 bytes, temperature: kWarm 2024/11/22-18:58:34.278193 140024726648384 EVENT_LOG_v1 {"time_micros": 1732273114278154, "cf_name": "default", "job": 8202, "event": "table_file_creation", "file_number": 221895, "file_size": 37509922, "file_checksum": "", "file_checksum_func_name": "Unknown", "smallest_seqno": 3365258144, "largest_seqno": 3484020402, "table_properties": {"data_size": 31549616, "index_size": 1756096, "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": 25844672, "raw_average_key_size": 28, "raw_value_size": 108373184, "raw_average_value_size": 117, "num_data_blocks": 1, "num_entries": 923024, "num_filter_entries": 0, "num_deletions": 0, "num_merge_operands": 0, "num_range_deletions": 0, "format_version": 0, "fixed_key_len": 28, "fixed_value_len": 18446744073709551615, "filter_policy": "", "column_family_name": "default", "column_family_id": 0, "comparator": "leveldb.BytewiseComparator", "merge_operator": "nullptr", "prefix_extractor_name": "nullptr", "property_collectors": "[Rdb_tbl_prop_coll_factory]", "compression": "FlatZip", "compression_options": "RS_Key0_Tag1-NestLoudsTrieDAWG_Mixed_XL_256_32_FL-DictZipBlobStore", "creation_time": 1732273110, "oldest_key_time": 0, "file_creation_time": 1732273110, "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": 221895, "seqno_to_time_mapping": "N/A", "__indexstats__": "[...1 records...]"}} 2024/11/22-18:58:34.313670 140024726648384 [top_zip_table_builder.cc:934] ToplingZipTableBuilder(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-08202/att-00/221896.sst) /dev/shm/ToplingTemp/Topling-249838-hrXENM 2024/11/22-18:58:35.143757 140024726648384 [top_zip_table_builder.cc:1352] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-08202/att-00/221896.sst): first pass time = 0.83's, 161.699'MB/sec 2024/11/22-18:58:36.483624 140024726648384 [top_zip_table_builder.cc:2772] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-08202/att-00/221896.sst): old prealloc_size = 147639500, real_size = 33808128 2024/11/22-18:58:36.484196 140024726648384 [top_zip_table_builder.cc:1773] BuildReorderMap(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-08202/att-00/221896.sst): index type = NestLoudsTrieDAWG_Mixed_XL_256_32_FL, store type = DictZipBlobStore usrkeys = 922918 prefix = 4 raw-key = 0.0148 GB zip-key = 0.0018 GB avg-key = 16.00 avg-zkey = 1.90 raw-val = 0.1084 GB zip-val = 0.0318 GB avg-val = 117.43 avg-zval = 34.48 2024/11/22-18:58:36.484945 140024726648384 [top_zip_table_builder.cc:1299] /storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-08202/att-00/221896.sst: sumWaitingMem = 0.002 GB, sumWorkingMem = 0.000 GB, reorder workingMem = 0.0023 GB, waited 0.000 sec, Key+Value = 0.134 GB 2024/11/22-18:58:37.416258 140024726648384 [top_zip_table_builder.cc:3100] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-08202/att-00/221896.sst): second pass time = 1.34's, 80.927'MB/sec, value only(80.7% of KV) wait indexing time = 0.00's, remap KeyValue time = 0.93's, 40.634'MB/sec (all stages of remap) Get OrderMap time = 0.00's, 3190.470'MB/sec (index lex order gen) rebuild zvType time = 0.81's, 0.286'MB/sec write SST data time = 0.12's, 303.754'MB/sec dict compress time = 4356233619.02's, 0.000'MB/sec z-dict build time = 0.00's, sample length = 1.083'MB, throughput = 33496.211'MB/sec zip my value time = 1.34's, unzip length = 0.108'GB zip my value throughput = 80.927'MB/sec zip pipeline throughput = 80.927'MB/sec entries = 922918 avg-key = 28.00 avg-zkey = 1.90 avg-val = 117.43 avg-zval = 34.48 usrkeys = 922918 avg-key = 20.00 avg-zkey = 1.90 avg-val = 124.43 avg-zval = 34.48 TagRS{ kinds = RS_Key0_Tag1 bytes = 0 } TagArray{ size = 922918 bytes = 3230224 } seq expand size = 6460426 multi value expand size = 0 cnt WriteAppend = 13 UnZipSize{ index = 0.0185 GB value = 0.1084 GB dict = 1.08 MB all = 0.1342 GB } __ZipSize{ index = 0.0018 GB value = 0.0318 GB dict = 1.08 MB all = 0.0379 GB } UnZip/Zip{ index = 10.5122 value = 3.4058 dict = 1.00 all = 3.5417 } Zip/UnZip{ index = 0.0951 value = 0.2936 dict = 1.00 all = 0.2823 } ---------------------------- total value len = 0.790628 GB avg = 0.119 KB (by entry num) total key len = 0.185571 GB avg = 0.028 KB total ukey len = 0.132551 GB avg = 0.020 KB total ukey num = 0.006627526 Billion total entry num = 0.006627526 Billion write speed all = 0.026837577 MB/sec (with seq num) write speed all = 0.025379950 MB/sec (without seq num) 2024/11/22-18:58:37.450243 140024726648384 [compaction_job.cc:1948] [default] [JOB 8202] Generated table #221896: 922918 keys, 37896288 bytes, temperature: kWarm 2024/11/22-18:58:37.450308 140024726648384 EVENT_LOG_v1 {"time_micros": 1732273117450274, "cf_name": "default", "job": 8202, "event": "table_file_creation", "file_number": 221896, "file_size": 37896288, "file_checksum": "", "file_checksum_func_name": "Unknown", "smallest_seqno": 3273701103, "largest_seqno": 3484020417, "table_properties": {"data_size": 31821296, "index_size": 1755904, "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": 25841704, "raw_average_key_size": 28, "raw_value_size": 108376072, "raw_average_value_size": 117, "num_data_blocks": 1, "num_entries": 922918, "num_filter_entries": 0, "num_deletions": 0, "num_merge_operands": 0, "num_range_deletions": 0, "format_version": 0, "fixed_key_len": 28, "fixed_value_len": 18446744073709551615, "filter_policy": "", "column_family_name": "default", "column_family_id": 0, "comparator": "leveldb.BytewiseComparator", "merge_operator": "nullptr", "prefix_extractor_name": "nullptr", "property_collectors": "[Rdb_tbl_prop_coll_factory]", "compression": "FlatZip", "compression_options": "RS_Key0_Tag1-NestLoudsTrieDAWG_Mixed_XL_256_32_FL-DictZipBlobStore", "creation_time": 1732273114, "oldest_key_time": 0, "file_creation_time": 1732273114, "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": 221896, "seqno_to_time_mapping": "N/A", "__indexstats__": "[...1 records...]"}} 2024/11/22-18:58:37.487480 140024726648384 [top_zip_table_builder.cc:934] ToplingZipTableBuilder(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-08202/att-00/221897.sst) /dev/shm/ToplingTemp/Topling-249838-6D6tXN 2024/11/22-18:58:38.412091 140024726648384 [top_zip_table_builder.cc:1352] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-08202/att-00/221897.sst): first pass time = 0.92's, 145.168'MB/sec 2024/11/22-18:58:39.728745 140024726648384 [top_zip_table_builder.cc:2772] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-08202/att-00/221897.sst): old prealloc_size = 147639500, real_size = 32931152 2024/11/22-18:58:39.729345 140024726648384 [top_zip_table_builder.cc:1773] BuildReorderMap(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-08202/att-00/221897.sst): index type = NestLoudsTrieDAWG_Mixed_XL_256_32_FL, store type = DictZipBlobStore usrkeys = 923051 prefix = 4 raw-key = 0.0148 GB zip-key = 0.0018 GB avg-key = 16.00 avg-zkey = 1.93 raw-val = 0.1084 GB zip-val = 0.0309 GB avg-val = 117.41 avg-zval = 33.50 2024/11/22-18:58:39.730094 140024726648384 [top_zip_table_builder.cc:1299] /storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-08202/att-00/221897.sst: sumWaitingMem = 0.002 GB, sumWorkingMem = 0.000 GB, reorder workingMem = 0.0023 GB, waited 0.000 sec, Key+Value = 0.134 GB 2024/11/22-18:58:40.617867 140024726648384 [top_zip_table_builder.cc:3100] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-08202/att-00/221897.sst): second pass time = 1.32's, 82.352'MB/sec, value only(80.7% of KV) wait indexing time = 0.00's, remap KeyValue time = 0.89's, 41.637'MB/sec (all stages of remap) Get OrderMap time = 0.00's, 3128.959'MB/sec (index lex order gen) rebuild zvType time = 0.76's, 0.302'MB/sec write SST data time = 0.12's, 296.670'MB/sec dict compress time = 4356233619.02's, 0.000'MB/sec z-dict build time = 0.00's, sample length = 1.083'MB, throughput = 31415.417'MB/sec zip my value time = 1.32's, unzip length = 0.108'GB zip my value throughput = 82.352'MB/sec zip pipeline throughput = 82.352'MB/sec entries = 923051 avg-key = 28.00 avg-zkey = 1.93 avg-val = 117.41 avg-zval = 33.50 usrkeys = 923051 avg-key = 20.00 avg-zkey = 1.93 avg-val = 124.41 avg-zval = 33.50 TagRS{ kinds = RS_Key0_Tag1 bytes = 0 } TagArray{ size = 923051 bytes = 3230688 } seq expand size = 6461357 multi value expand size = 0 cnt WriteAppend = 16 UnZipSize{ index = 0.0185 GB value = 0.1084 GB dict = 1.08 MB all = 0.1342 GB } __ZipSize{ index = 0.0018 GB value = 0.0309 GB dict = 1.08 MB all = 0.0370 GB } UnZip/Zip{ index = 10.3749 value = 3.5048 dict = 1.00 all = 3.6256 } Zip/UnZip{ index = 0.0964 value = 0.2853 dict = 1.00 all = 0.2758 } ---------------------------- total value len = 0.905462 GB avg = 0.120 KB (by entry num) total key len = 0.211416 GB avg = 0.028 KB total ukey len = 0.151012 GB avg = 0.020 KB total ukey num = 0.007550577 Billion total entry num = 0.007550577 Billion write speed all = 0.030702415 MB/sec (with seq num) write speed all = 0.029041922 MB/sec (without seq num) 2024/11/22-18:58:40.649816 140024726648384 [compaction_job.cc:1948] [default] [JOB 8202] Generated table #221897: 923051 keys, 37019770 bytes, temperature: kWarm 2024/11/22-18:58:40.649883 140024726648384 EVENT_LOG_v1 {"time_micros": 1732273120649849, "cf_name": "default", "job": 8202, "event": "table_file_creation", "file_number": 221897, "file_size": 37019770, "file_checksum": "", "file_checksum_func_name": "Unknown", "smallest_seqno": 3273701122, "largest_seqno": 3484020738, "table_properties": {"data_size": 30920832, "index_size": 1779392, "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": 25845428, "raw_average_key_size": 28, "raw_value_size": 108372358, "raw_average_value_size": 117, "num_data_blocks": 1, "num_entries": 923051, "num_filter_entries": 0, "num_deletions": 0, "num_merge_operands": 0, "num_range_deletions": 0, "format_version": 0, "fixed_key_len": 28, "fixed_value_len": 18446744073709551615, "filter_policy": "", "column_family_name": "default", "column_family_id": 0, "comparator": "leveldb.BytewiseComparator", "merge_operator": "nullptr", "prefix_extractor_name": "nullptr", "property_collectors": "[Rdb_tbl_prop_coll_factory]", "compression": "FlatZip", "compression_options": "RS_Key0_Tag1-NestLoudsTrieDAWG_Mixed_XL_256_32_FL-DictZipBlobStore", "creation_time": 1732273117, "oldest_key_time": 0, "file_creation_time": 1732273117, "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": 221897, "seqno_to_time_mapping": "N/A", "__indexstats__": "[...1 records...]"}} 2024/11/22-18:58:40.689878 140024726648384 [top_zip_table_builder.cc:934] ToplingZipTableBuilder(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-08202/att-00/221898.sst) /dev/shm/ToplingTemp/Topling-249838-9JOQ1N 2024/11/22-18:58:41.496330 140024726648384 [top_zip_table_builder.cc:1352] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-08202/att-00/221898.sst): first pass time = 0.81's, 166.438'MB/sec 2024/11/22-18:58:42.835833 140024726648384 [top_zip_table_builder.cc:2772] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-08202/att-00/221898.sst): old prealloc_size = 147639500, real_size = 33591200 2024/11/22-18:58:42.836397 140024726648384 [top_zip_table_builder.cc:1773] BuildReorderMap(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-08202/att-00/221898.sst): index type = NestLoudsTrieDAWG_Mixed_XL_256_32_FL, store type = DictZipBlobStore usrkeys = 923025 prefix = 4 raw-key = 0.0148 GB zip-key = 0.0018 GB avg-key = 16.00 avg-zkey = 1.90 raw-val = 0.1084 GB zip-val = 0.0316 GB avg-val = 117.41 avg-zval = 34.24 2024/11/22-18:58:42.838248 140024726648384 [top_zip_table_builder.cc:1299] /storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-08202/att-00/221898.sst: sumWaitingMem = 0.002 GB, sumWorkingMem = 0.000 GB, reorder workingMem = 0.0023 GB, waited 0.000 sec, Key+Value = 0.134 GB 2024/11/22-18:58:43.764269 140024726648384 [top_zip_table_builder.cc:3100] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-08202/att-00/221898.sst): second pass time = 1.34's, 80.945'MB/sec, value only(80.7% of KV) wait indexing time = 0.00's, remap KeyValue time = 0.93's, 40.461'MB/sec (all stages of remap) Get OrderMap time = 0.00's, 3252.377'MB/sec (index lex order gen) rebuild zvType time = 0.80's, 0.289'MB/sec write SST data time = 0.13's, 292.757'MB/sec dict compress time = 4356233619.02's, 0.000'MB/sec z-dict build time = 0.00's, sample length = 1.083'MB, throughput = 30350.933'MB/sec zip my value time = 1.34's, unzip length = 0.108'GB zip my value throughput = 80.945'MB/sec zip pipeline throughput = 80.945'MB/sec entries = 923025 avg-key = 28.00 avg-zkey = 1.90 avg-val = 117.41 avg-zval = 34.24 usrkeys = 923025 avg-key = 20.00 avg-zkey = 1.90 avg-val = 124.41 avg-zval = 34.24 TagRS{ kinds = RS_Key0_Tag1 bytes = 0 } TagArray{ size = 923025 bytes = 3115232 } seq expand size = 6461175 multi value expand size = 0 cnt WriteAppend = 12 UnZipSize{ index = 0.0185 GB value = 0.1084 GB dict = 1.08 MB all = 0.1342 GB } __ZipSize{ index = 0.0018 GB value = 0.0316 GB dict = 1.08 MB all = 0.0376 GB } UnZip/Zip{ index = 10.5107 value = 3.4291 dict = 1.00 all = 3.5730 } Zip/UnZip{ index = 0.0951 value = 0.2916 dict = 1.00 all = 0.2799 } ---------------------------- total value len = 1.020296 GB avg = 0.120 KB (by entry num) total key len = 0.237261 GB avg = 0.028 KB total ukey len = 0.169472 GB avg = 0.020 KB total ukey num = 0.008473602 Billion total entry num = 0.008473602 Billion write speed all = 0.034566621 MB/sec (with seq num) write speed all = 0.032703301 MB/sec (without seq num) 2024/11/22-18:58:43.797404 140024726648384 [compaction_job.cc:1948] [default] [JOB 8202] Generated table #221898: 923025 keys, 37564470 bytes, temperature: kWarm 2024/11/22-18:58:43.797471 140024726648384 EVENT_LOG_v1 {"time_micros": 1732273123797437, "cf_name": "default", "job": 8202, "event": "table_file_creation", "file_number": 221898, "file_size": 37564470, "file_checksum": "", "file_checksum_func_name": "Unknown", "smallest_seqno": 3369194706, "largest_seqno": 3484020445, "table_properties": {"data_size": 31603920, "index_size": 1756352, "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": 25844700, "raw_average_key_size": 28, "raw_value_size": 108373122, "raw_average_value_size": 117, "num_data_blocks": 1, "num_entries": 923025, "num_filter_entries": 0, "num_deletions": 0, "num_merge_operands": 0, "num_range_deletions": 0, "format_version": 0, "fixed_key_len": 28, "fixed_value_len": 18446744073709551615, "filter_policy": "", "column_family_name": "default", "column_family_id": 0, "comparator": "leveldb.BytewiseComparator", "merge_operator": "nullptr", "prefix_extractor_name": "nullptr", "property_collectors": "[Rdb_tbl_prop_coll_factory]", "compression": "FlatZip", "compression_options": "RS_Key0_Tag1-NestLoudsTrieDAWG_Mixed_XL_256_32_FL-DictZipBlobStore", "creation_time": 1732273120, "oldest_key_time": 0, "file_creation_time": 1732273120, "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": 221898, "seqno_to_time_mapping": "N/A", "__indexstats__": "[...1 records...]"}} 2024/11/22-18:58:43.835337 140024726648384 [top_zip_table_builder.cc:934] ToplingZipTableBuilder(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-08202/att-00/221899.sst) /dev/shm/ToplingTemp/Topling-249838-CCzNsO 2024/11/22-18:58:44.697059 140024726648384 [top_zip_table_builder.cc:1352] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-08202/att-00/221899.sst): first pass time = 0.86's, 156.148'MB/sec 2024/11/22-18:58:46.047570 140024726648384 [top_zip_table_builder.cc:2772] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-08202/att-00/221899.sst): old prealloc_size = 147639500, real_size = 33725568 2024/11/22-18:58:46.048133 140024726648384 [top_zip_table_builder.cc:1773] BuildReorderMap(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-08202/att-00/221899.sst): index type = NestLoudsTrieDAWG_Mixed_XL_256_32_FL, store type = DictZipBlobStore usrkeys = 922861 prefix = 4 raw-key = 0.0148 GB zip-key = 0.0018 GB avg-key = 16.00 avg-zkey = 1.90 raw-val = 0.1084 GB zip-val = 0.0317 GB avg-val = 117.44 avg-zval = 34.39 2024/11/22-18:58:46.050208 140024726648384 [top_zip_table_builder.cc:1299] /storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-08202/att-00/221899.sst: sumWaitingMem = 0.002 GB, sumWorkingMem = 0.000 GB, reorder workingMem = 0.0023 GB, waited 0.000 sec, Key+Value = 0.134 GB 2024/11/22-18:58:46.988744 140024726648384 [top_zip_table_builder.cc:3100] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-08202/att-00/221899.sst): second pass time = 1.35's, 80.317'MB/sec, value only(80.7% of KV) wait indexing time = 0.00's, remap KeyValue time = 0.94's, 40.275'MB/sec (all stages of remap) Get OrderMap time = 0.00's, 3258.455'MB/sec (index lex order gen) rebuild zvType time = 0.81's, 0.285'MB/sec write SST data time = 0.13's, 286.870'MB/sec dict compress time = 4356233619.02's, 0.000'MB/sec z-dict build time = 0.00's, sample length = 1.083'MB, throughput = 28767.040'MB/sec zip my value time = 1.35's, unzip length = 0.108'GB zip my value throughput = 80.317'MB/sec zip pipeline throughput = 80.317'MB/sec entries = 922861 avg-key = 28.00 avg-zkey = 1.90 avg-val = 117.44 avg-zval = 34.39 usrkeys = 922861 avg-key = 20.00 avg-zkey = 1.90 avg-val = 124.32 avg-zval = 34.39 TagRS{ kinds = RS_Key0_TagN bytes = 144280 } TagArray{ size = 907847 bytes = 3177472 } seq expand size = 6354929 multi value expand size = 0 cnt WriteAppend = 14 UnZipSize{ index = 0.0185 GB value = 0.1084 GB dict = 1.08 MB all = 0.1342 GB } __ZipSize{ index = 0.0018 GB value = 0.0317 GB dict = 1.08 MB all = 0.0379 GB } UnZip/Zip{ index = 10.5134 value = 3.4146 dict = 1.00 all = 3.5409 } Zip/UnZip{ index = 0.0951 value = 0.2929 dict = 1.00 all = 0.2824 } ---------------------------- total value len = 1.135029 GB avg = 0.121 KB (by entry num) total key len = 0.263101 GB avg = 0.028 KB total ukey len = 0.187929 GB avg = 0.020 KB total ukey num = 0.009396463 Billion total entry num = 0.009396463 Billion write speed all = 0.038427156 MB/sec (with seq num) write speed all = 0.036361085 MB/sec (without seq num) 2024/11/22-18:58:47.025524 140024726648384 [compaction_job.cc:1948] [default] [JOB 8202] Generated table #221899: 922861 keys, 37905380 bytes, temperature: kWarm 2024/11/22-18:58:47.025587 140024726648384 EVENT_LOG_v1 {"time_micros": 1732273127025554, "cf_name": "default", "job": 8202, "event": "table_file_creation", "file_number": 221899, "file_size": 37905380, "file_checksum": "", "file_checksum_func_name": "Unknown", "smallest_seqno": 0, "largest_seqno": 3484020456, "table_properties": {"data_size": 31739056, "index_size": 1755584, "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": 25840108, "raw_average_key_size": 28, "raw_value_size": 108377702, "raw_average_value_size": 117, "num_data_blocks": 1, "num_entries": 922861, "num_filter_entries": 0, "num_deletions": 0, "num_merge_operands": 0, "num_range_deletions": 0, "format_version": 0, "fixed_key_len": 28, "fixed_value_len": 18446744073709551615, "filter_policy": "", "column_family_name": "default", "column_family_id": 0, "comparator": "leveldb.BytewiseComparator", "merge_operator": "nullptr", "prefix_extractor_name": "nullptr", "property_collectors": "[Rdb_tbl_prop_coll_factory]", "compression": "FlatZip", "compression_options": "RS_Key0_TagN-NestLoudsTrieDAWG_Mixed_XL_256_32_FL-DictZipBlobStore", "creation_time": 1732273123, "oldest_key_time": 0, "file_creation_time": 1732273123, "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": 221899, "seqno_to_time_mapping": "N/A", "__indexstats__": "[...1 records...]"}} 2024/11/22-18:58:47.062683 140024726648384 [top_zip_table_builder.cc:934] ToplingZipTableBuilder(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-08202/att-00/221900.sst) /dev/shm/ToplingTemp/Topling-249838-9oBR4J 2024/11/22-18:58:47.871841 140024726648384 [top_zip_table_builder.cc:1352] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-08202/att-00/221900.sst): first pass time = 0.81's, 165.882'MB/sec 2024/11/22-18:58:49.191715 140024726648384 [top_zip_table_builder.cc:2772] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-08202/att-00/221900.sst): old prealloc_size = 147639500, real_size = 33787392 2024/11/22-18:58:49.192263 140024726648384 [top_zip_table_builder.cc:1773] BuildReorderMap(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-08202/att-00/221900.sst): index type = NestLoudsTrieDAWG_Mixed_XL_256_32_FL, store type = DictZipBlobStore usrkeys = 922958 prefix = 4 raw-key = 0.0148 GB zip-key = 0.0018 GB avg-key = 16.00 avg-zkey = 1.90 raw-val = 0.1084 GB zip-val = 0.0318 GB avg-val = 117.42 avg-zval = 34.46 2024/11/22-18:58:49.192864 140024726648384 [top_zip_table_builder.cc:1299] /storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-08202/att-00/221900.sst: sumWaitingMem = 0.002 GB, sumWorkingMem = 0.000 GB, reorder workingMem = 0.0023 GB, waited 0.000 sec, Key+Value = 0.134 GB 2024/11/22-18:58:50.126277 140024726648384 [top_zip_table_builder.cc:3100] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-08202/att-00/221900.sst): second pass time = 1.32's, 82.153'MB/sec, value only(80.7% of KV) wait indexing time = 0.00's, remap KeyValue time = 0.93's, 39.743'MB/sec (all stages of remap) Get OrderMap time = 0.00's, 3343.088'MB/sec (index lex order gen) rebuild zvType time = 0.81's, 0.286'MB/sec write SST data time = 0.13's, 289.421'MB/sec dict compress time = 4356233619.02's, 0.000'MB/sec z-dict build time = 0.00's, sample length = 1.083'MB, throughput = 25877.883'MB/sec zip my value time = 1.32's, unzip length = 0.108'GB zip my value throughput = 82.153'MB/sec zip pipeline throughput = 82.153'MB/sec entries = 922958 avg-key = 28.00 avg-zkey = 1.90 avg-val = 117.42 avg-zval = 34.46 usrkeys = 922958 avg-key = 20.00 avg-zkey = 1.90 avg-val = 122.52 avg-zval = 34.46 TagRS{ kinds = RS_Key0_TagN bytes = 144320 } TagArray{ size = 671906 bytes = 2351680 } seq expand size = 4703342 multi value expand size = 0 cnt WriteAppend = 13 UnZipSize{ index = 0.0185 GB value = 0.1084 GB dict = 1.08 MB all = 0.1342 GB } __ZipSize{ index = 0.0018 GB value = 0.0318 GB dict = 1.08 MB all = 0.0371 GB } UnZip/Zip{ index = 10.5130 value = 3.4080 dict = 1.00 all = 3.6137 } Zip/UnZip{ index = 0.0951 value = 0.2934 dict = 1.00 all = 0.2767 } ---------------------------- total value len = 1.248107 GB avg = 0.121 KB (by entry num) total key len = 0.288944 GB avg = 0.028 KB total ukey len = 0.206388 GB avg = 0.020 KB total ukey num = 0.010319421 Billion total entry num = 0.010319421 Billion write speed all = 0.042241719 MB/sec (with seq num) write speed all = 0.039972906 MB/sec (without seq num) 2024/11/22-18:58:50.157093 140024726648384 [compaction_job.cc:1948] [default] [JOB 8202] Generated table #221900: 922958 keys, 37141372 bytes, temperature: kWarm 2024/11/22-18:58:50.157159 140024726648384 EVENT_LOG_v1 {"time_micros": 1732273130157124, "cf_name": "default", "job": 8202, "event": "table_file_creation", "file_number": 221900, "file_size": 37141372, "file_checksum": "", "file_checksum_func_name": "Unknown", "smallest_seqno": 0, "largest_seqno": 3484020473, "table_properties": {"data_size": 31800624, "index_size": 1755840, "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": 25842824, "raw_average_key_size": 28, "raw_value_size": 108375028, "raw_average_value_size": 117, "num_data_blocks": 1, "num_entries": 922958, "num_filter_entries": 0, "num_deletions": 0, "num_merge_operands": 0, "num_range_deletions": 0, "format_version": 0, "fixed_key_len": 28, "fixed_value_len": 18446744073709551615, "filter_policy": "", "column_family_name": "default", "column_family_id": 0, "comparator": "leveldb.BytewiseComparator", "merge_operator": "nullptr", "prefix_extractor_name": "nullptr", "property_collectors": "[Rdb_tbl_prop_coll_factory]", "compression": "FlatZip", "compression_options": "RS_Key0_TagN-NestLoudsTrieDAWG_Mixed_XL_256_32_FL-DictZipBlobStore", "creation_time": 1732273127, "oldest_key_time": 0, "file_creation_time": 1732273127, "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": 221900, "seqno_to_time_mapping": "N/A", "__indexstats__": "[...1 records...]"}} 2024/11/22-18:58:50.192044 140024726648384 [top_zip_table_builder.cc:934] ToplingZipTableBuilder(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-08202/att-00/221901.sst) /dev/shm/ToplingTemp/Topling-249838-osDO9J 2024/11/22-18:58:51.220845 140024726648384 [top_zip_table_builder.cc:1352] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-08202/att-00/221901.sst): first pass time = 1.03's, 130.466'MB/sec 2024/11/22-18:58:52.547708 140024726648384 [top_zip_table_builder.cc:2772] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-08202/att-00/221901.sst): old prealloc_size = 147639500, real_size = 33707728 2024/11/22-18:58:52.548279 140024726648384 [top_zip_table_builder.cc:1773] BuildReorderMap(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-08202/att-00/221901.sst): index type = NestLoudsTrieDAWG_Mixed_XL_256_32_FL, store type = DictZipBlobStore usrkeys = 926692 prefix = 4 raw-key = 0.0148 GB zip-key = 0.0018 GB avg-key = 16.00 avg-zkey = 1.90 raw-val = 0.1083 GB zip-val = 0.0317 GB avg-val = 116.84 avg-zval = 34.22 2024/11/22-18:58:52.549037 140024726648384 [top_zip_table_builder.cc:1299] /storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-08202/att-00/221901.sst: sumWaitingMem = 0.002 GB, sumWorkingMem = 0.000 GB, reorder workingMem = 0.0023 GB, waited 0.000 sec, Key+Value = 0.134 GB 2024/11/22-18:58:53.460163 140024726648384 [top_zip_table_builder.cc:3100] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-08202/att-00/221901.sst): second pass time = 1.33's, 81.642'MB/sec, value only(80.7% of KV) wait indexing time = 0.00's, remap KeyValue time = 0.91's, 41.306'MB/sec (all stages of remap) Get OrderMap time = 0.00's, 3212.601'MB/sec (index lex order gen) rebuild zvType time = 0.78's, 0.295'MB/sec write SST data time = 0.13's, 295.537'MB/sec dict compress time = 4356233619.02's, 0.000'MB/sec z-dict build time = 0.00's, sample length = 1.080'MB, throughput = 23562.899'MB/sec zip my value time = 1.33's, unzip length = 0.108'GB zip my value throughput = 81.642'MB/sec zip pipeline throughput = 81.642'MB/sec entries = 926692 avg-key = 28.00 avg-zkey = 1.90 avg-val = 116.84 avg-zval = 34.22 usrkeys = 926692 avg-key = 20.00 avg-zkey = 1.90 avg-val = 123.84 avg-zval = 34.22 TagRS{ kinds = RS_Key0_Tag1 bytes = 0 } TagArray{ size = 926692 bytes = 3127600 } seq expand size = 6486844 multi value expand size = 0 cnt WriteAppend = 13 UnZipSize{ index = 0.0185 GB value = 0.1083 GB dict = 1.08 MB all = 0.1342 GB } __ZipSize{ index = 0.0018 GB value = 0.0317 GB dict = 1.08 MB all = 0.0377 GB } UnZip/Zip{ index = 10.5122 value = 3.4141 dict = 1.00 all = 3.5611 } Zip/UnZip{ index = 0.0951 value = 0.2929 dict = 1.00 all = 0.2808 } ---------------------------- total value len = 1.362864 GB avg = 0.121 KB (by entry num) total key len = 0.314891 GB avg = 0.028 KB total ukey len = 0.224922 GB avg = 0.020 KB total ukey num = 0.011246113 Billion total entry num = 0.011246113 Billion write speed all = 0.046104383 MB/sec (with seq num) write speed all = 0.043632055 MB/sec (without seq num) 2024/11/22-18:58:53.492154 140024726648384 [compaction_job.cc:1948] [default] [JOB 8202] Generated table #221901: 926692 keys, 37689618 bytes, temperature: kWarm 2024/11/22-18:58:53.492220 140024726648384 EVENT_LOG_v1 {"time_micros": 1732273133492187, "cf_name": "default", "job": 8202, "event": "table_file_creation", "file_number": 221901, "file_size": 37689618, "file_checksum": "", "file_checksum_func_name": "Unknown", "smallest_seqno": 3369353416, "largest_seqno": 3484020569, "table_properties": {"data_size": 31712768, "index_size": 1763072, "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": 25947376, "raw_average_key_size": 28, "raw_value_size": 108270356, "raw_average_value_size": 116, "num_data_blocks": 1, "num_entries": 926692, "num_filter_entries": 0, "num_deletions": 0, "num_merge_operands": 0, "num_range_deletions": 0, "format_version": 0, "fixed_key_len": 28, "fixed_value_len": 18446744073709551615, "filter_policy": "", "column_family_name": "default", "column_family_id": 0, "comparator": "leveldb.BytewiseComparator", "merge_operator": "nullptr", "prefix_extractor_name": "nullptr", "property_collectors": "[Rdb_tbl_prop_coll_factory]", "compression": "FlatZip", "compression_options": "RS_Key0_Tag1-NestLoudsTrieDAWG_Mixed_XL_256_32_FL-DictZipBlobStore", "creation_time": 1732273130, "oldest_key_time": 0, "file_creation_time": 1732273130, "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": 221901, "seqno_to_time_mapping": "N/A", "__indexstats__": "[...1 records...]"}} 2024/11/22-18:58:53.532529 140024726648384 [top_zip_table_builder.cc:934] ToplingZipTableBuilder(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-08202/att-00/221902.sst) /dev/shm/ToplingTemp/Topling-249838-JDMf5L 2024/11/22-18:58:54.419454 140024726648384 [top_zip_table_builder.cc:1352] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-08202/att-00/221902.sst): first pass time = 0.89's, 151.337'MB/sec 2024/11/22-18:58:55.738455 140024726648384 [top_zip_table_builder.cc:2772] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-08202/att-00/221902.sst): old prealloc_size = 147639500, real_size = 34095072 2024/11/22-18:58:55.739006 140024726648384 [top_zip_table_builder.cc:1773] BuildReorderMap(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-08202/att-00/221902.sst): index type = NestLoudsTrieDAWG_Mixed_XL_256_32_FL, store type = DictZipBlobStore usrkeys = 925876 prefix = 4 raw-key = 0.0148 GB zip-key = 0.0018 GB avg-key = 16.00 avg-zkey = 1.90 raw-val = 0.1083 GB zip-val = 0.0321 GB avg-val = 116.96 avg-zval = 34.67 2024/11/22-18:58:55.739540 140024726648384 [top_zip_table_builder.cc:1299] /storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-08202/att-00/221902.sst: sumWaitingMem = 0.002 GB, sumWorkingMem = 0.000 GB, reorder workingMem = 0.0023 GB, waited 0.000 sec, Key+Value = 0.134 GB 2024/11/22-18:58:56.592113 140024726648384 [top_zip_table_builder.cc:3100] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-08202/att-00/221902.sst): second pass time = 1.32's, 82.146'MB/sec, value only(80.7% of KV) wait indexing time = 0.00's, remap KeyValue time = 0.85's, 43.510'MB/sec (all stages of remap) Get OrderMap time = 0.00's, 3329.609'MB/sec (index lex order gen) rebuild zvType time = 0.73's, 0.319'MB/sec write SST data time = 0.13's, 291.024'MB/sec dict compress time = 4356233619.02's, 0.000'MB/sec z-dict build time = 0.00's, sample length = 1.081'MB, throughput = 30271.541'MB/sec zip my value time = 1.32's, unzip length = 0.108'GB zip my value throughput = 82.146'MB/sec zip pipeline throughput = 82.146'MB/sec entries = 925876 avg-key = 28.00 avg-zkey = 1.90 avg-val = 116.96 avg-zval = 34.67 usrkeys = 925876 avg-key = 20.00 avg-zkey = 1.90 avg-val = 121.54 avg-zval = 34.67 TagRS{ kinds = RS_Key0_TagN bytes = 144760 } TagArray{ size = 605445 bytes = 2043392 } seq expand size = 4238115 multi value expand size = 0 cnt WriteAppend = 13 UnZipSize{ index = 0.0185 GB value = 0.1083 GB dict = 1.08 MB all = 0.1342 GB } __ZipSize{ index = 0.0018 GB value = 0.0321 GB dict = 1.08 MB all = 0.0371 GB } UnZip/Zip{ index = 10.5102 value = 3.3735 dict = 1.00 all = 3.6136 } Zip/UnZip{ index = 0.0951 value = 0.2964 dict = 1.00 all = 0.2767 } ---------------------------- total value len = 1.475395 GB avg = 0.121 KB (by entry num) total key len = 0.340816 GB avg = 0.028 KB total ukey len = 0.243440 GB avg = 0.020 KB total ukey num = 0.012171989 Billion total entry num = 0.012171989 Billion write speed all = 0.049904827 MB/sec (with seq num) write speed all = 0.047229187 MB/sec (without seq num) 2024/11/22-18:58:56.626082 140024726648384 [compaction_job.cc:1948] [default] [JOB 8202] Generated table #221902: 925876 keys, 37142022 bytes, temperature: kWarm 2024/11/22-18:58:56.626149 140024726648384 EVENT_LOG_v1 {"time_micros": 1732273136626112, "cf_name": "default", "job": 8202, "event": "table_file_creation", "file_number": 221902, "file_size": 37142022, "file_checksum": "", "file_checksum_func_name": "Unknown", "smallest_seqno": 0, "largest_seqno": 3484020511, "table_properties": {"data_size": 32101584, "index_size": 1761856, "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": 25924528, "raw_average_key_size": 28, "raw_value_size": 108293216, "raw_average_value_size": 116, "num_data_blocks": 1, "num_entries": 925876, "num_filter_entries": 0, "num_deletions": 0, "num_merge_operands": 0, "num_range_deletions": 0, "format_version": 0, "fixed_key_len": 28, "fixed_value_len": 18446744073709551615, "filter_policy": "", "column_family_name": "default", "column_family_id": 0, "comparator": "leveldb.BytewiseComparator", "merge_operator": "nullptr", "prefix_extractor_name": "nullptr", "property_collectors": "[Rdb_tbl_prop_coll_factory]", "compression": "FlatZip", "compression_options": "RS_Key0_TagN-NestLoudsTrieDAWG_Mixed_XL_256_32_FL-DictZipBlobStore", "creation_time": 1732273133, "oldest_key_time": 0, "file_creation_time": 1732273133, "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": 221902, "seqno_to_time_mapping": "N/A", "__indexstats__": "[...1 records...]"}} 2024/11/22-18:58:56.659503 140024726648384 [top_zip_table_builder.cc:934] ToplingZipTableBuilder(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-08202/att-00/221903.sst) /dev/shm/ToplingTemp/Topling-249838-N4ObyL 2024/11/22-18:58:57.493120 140024726648384 [top_zip_table_builder.cc:1352] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-08202/att-00/221903.sst): first pass time = 0.83's, 161.015'MB/sec 2024/11/22-18:58:58.852811 140024726648384 [top_zip_table_builder.cc:2772] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-08202/att-00/221903.sst): old prealloc_size = 147639500, real_size = 35628880 2024/11/22-18:58:58.853334 140024726648384 [top_zip_table_builder.cc:1773] BuildReorderMap(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-08202/att-00/221903.sst): index type = NestLoudsTrieDAWG_Mixed_XL_256_32_FL, store type = DictZipBlobStore usrkeys = 923371 prefix = 4 raw-key = 0.0148 GB zip-key = 0.0018 GB avg-key = 16.00 avg-zkey = 1.90 raw-val = 0.1084 GB zip-val = 0.0336 GB avg-val = 117.36 avg-zval = 36.43 2024/11/22-18:58:58.853975 140024726648384 [top_zip_table_builder.cc:1299] /storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-08202/att-00/221903.sst: sumWaitingMem = 0.002 GB, sumWorkingMem = 0.000 GB, reorder workingMem = 0.0023 GB, waited 0.000 sec, Key+Value = 0.134 GB 2024/11/22-18:58:59.668250 140024726648384 [top_zip_table_builder.cc:3100] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-08202/att-00/221903.sst): second pass time = 1.36's, 79.737'MB/sec, value only(80.7% of KV) wait indexing time = 0.00's, remap KeyValue time = 0.82's, 47.285'MB/sec (all stages of remap) Get OrderMap time = 0.00's, 3486.855'MB/sec (index lex order gen) rebuild zvType time = 0.69's, 0.336'MB/sec write SST data time = 0.13's, 299.158'MB/sec dict compress time = 4356233619.02's, 0.000'MB/sec z-dict build time = 0.00's, sample length = 1.083'MB, throughput = 32807.841'MB/sec zip my value time = 1.36's, unzip length = 0.108'GB zip my value throughput = 79.737'MB/sec zip pipeline throughput = 79.737'MB/sec entries = 923371 avg-key = 28.00 avg-zkey = 1.90 avg-val = 117.36 avg-zval = 36.43 usrkeys = 923371 avg-key = 20.00 avg-zkey = 1.90 avg-val = 121.52 avg-zval = 36.43 TagRS{ kinds = RS_Key0_TagN bytes = 144360 } TagArray{ size = 549503 bytes = 1923280 } seq expand size = 3846521 multi value expand size = 0 cnt WriteAppend = 15 UnZipSize{ index = 0.0185 GB value = 0.1084 GB dict = 1.08 MB all = 0.1342 GB } __ZipSize{ index = 0.0018 GB value = 0.0336 GB dict = 1.08 MB all = 0.0386 GB } UnZip/Zip{ index = 10.5143 value = 3.2211 dict = 1.00 all = 3.4809 } Zip/UnZip{ index = 0.0951 value = 0.3104 dict = 1.00 all = 0.2873 } ---------------------------- total value len = 1.587605 GB avg = 0.121 KB (by entry num) total key len = 0.366670 GB avg = 0.028 KB total ukey len = 0.261907 GB avg = 0.020 KB total ukey num = 0.013095360 Billion total entry num = 0.013095360 Billion write speed all = 0.053693941 MB/sec (with seq num) write speed all = 0.050815569 MB/sec (without seq num) 2024/11/22-18:58:59.702526 140024726648384 [compaction_job.cc:1948] [default] [JOB 8202] Generated table #221903: 923371 keys, 38558005 bytes, temperature: kWarm 2024/11/22-18:58:59.702592 140024726648384 EVENT_LOG_v1 {"time_micros": 1732273139702558, "cf_name": "default", "job": 8202, "event": "table_file_creation", "file_number": 221903, "file_size": 38558005, "file_checksum": "", "file_checksum_func_name": "Unknown", "smallest_seqno": 0, "largest_seqno": 3484020701, "table_properties": {"data_size": 33641408, "index_size": 1756416, "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": 25854388, "raw_average_key_size": 28, "raw_value_size": 108363370, "raw_average_value_size": 117, "num_data_blocks": 1, "num_entries": 923371, "num_filter_entries": 0, "num_deletions": 0, "num_merge_operands": 0, "num_range_deletions": 0, "format_version": 0, "fixed_key_len": 28, "fixed_value_len": 18446744073709551615, "filter_policy": "", "column_family_name": "default", "column_family_id": 0, "comparator": "leveldb.BytewiseComparator", "merge_operator": "nullptr", "prefix_extractor_name": "nullptr", "property_collectors": "[Rdb_tbl_prop_coll_factory]", "compression": "FlatZip", "compression_options": "RS_Key0_TagN-NestLoudsTrieDAWG_Mixed_XL_256_32_FL-DictZipBlobStore", "creation_time": 1732273136, "oldest_key_time": 0, "file_creation_time": 1732273136, "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": 221903, "seqno_to_time_mapping": "N/A", "__indexstats__": "[...1 records...]"}} 2024/11/22-18:58:59.887752 140024726648384 [top_zip_table_builder.cc:934] ToplingZipTableBuilder(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-08202/att-00/221904.sst) /dev/shm/ToplingTemp/Topling-249838-yEwlZM 2024/11/22-18:59:00.104975 140024726648384 [top_zip_table_builder.cc:1352] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-08202/att-00/221904.sst): first pass time = 0.22's, 235.789'MB/sec 2024/11/22-18:59:00.601758 140024726648384 [top_zip_table_builder.cc:2772] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-08202/att-00/221904.sst): old prealloc_size = 147639500, real_size = 13720928 2024/11/22-18:59:00.602005 140024726648384 [top_zip_table_builder.cc:1773] BuildReorderMap(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-08202/att-00/221904.sst): index type = NestLoudsTrieDAWG_Mixed_XL_256_32_FL, store type = DictZipBlobStore usrkeys = 350727 prefix = 4 raw-key = 0.0056 GB zip-key = 0.0007 GB avg-key = 16.00 avg-zkey = 1.92 raw-val = 0.0414 GB zip-val = 0.0130 GB avg-val = 118.00 avg-zval = 36.95 2024/11/22-18:59:00.602378 140024726648384 [top_zip_table_builder.cc:1299] /storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-08202/att-00/221904.sst: sumWaitingMem = 0.001 GB, sumWorkingMem = 0.000 GB, reorder workingMem = 0.0008 GB, waited 0.000 sec, Key+Value = 0.051 GB 2024/11/22-18:59:00.960520 140024726648384 [top_zip_table_builder.cc:3100] ToplingZipTableBuilder::Finish(/storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-08202/att-00/221904.sst): second pass time = 0.50's, 83.413'MB/sec, value only(80.8% of KV) wait indexing time = 0.00's, remap KeyValue time = 0.36's, 42.477'MB/sec (all stages of remap) Get OrderMap time = 0.00's, 3143.025'MB/sec (index lex order gen) rebuild zvType time = 0.31's, 0.283'MB/sec write SST data time = 0.05's, 315.350'MB/sec dict compress time = 4356233619.02's, 0.000'MB/sec z-dict build time = 0.00's, sample length = 0.420'MB, throughput = 13500.997'MB/sec zip my value time = 0.50's, unzip length = 0.041'GB zip my value throughput = 83.413'MB/sec zip pipeline throughput = 83.413'MB/sec entries = 350727 avg-key = 28.00 avg-zkey = 1.92 avg-val = 118.00 avg-zval = 36.95 usrkeys = 350727 avg-key = 20.00 avg-zkey = 1.92 avg-val = 125.00 avg-zval = 36.95 TagRS{ kinds = RS_Key0_Tag1 bytes = 0 } TagArray{ size = 350727 bytes = 1183712 } seq expand size = 2455089 multi value expand size = 0 cnt WriteAppend = 11 UnZipSize{ index = 0.0070 GB value = 0.0414 GB dict = 0.42 MB all = 0.0512 GB } __ZipSize{ index = 0.0007 GB value = 0.0130 GB dict = 0.42 MB all = 0.0152 GB } UnZip/Zip{ index = 10.4294 value = 3.1932 dict = 1.00 all = 3.3603 } Zip/UnZip{ index = 0.0959 value = 0.3132 dict = 1.00 all = 0.2976 } ---------------------------- total value len = 1.631446 GB avg = 0.121 KB (by entry num) total key len = 0.376490 GB avg = 0.028 KB total ukey len = 0.268922 GB avg = 0.020 KB total ukey num = 0.013446087 Billion total entry num = 0.013446087 Billion write speed all = 0.055166331 MB/sec (with seq num) write speed all = 0.052210974 MB/sec (without seq num) 2024/11/22-18:59:00.976911 140024726648384 [compaction_job.cc:1948] [default] [JOB 8202] Generated table #221904: 350727 keys, 15238745 bytes, temperature: kWarm 2024/11/22-18:59:00.976974 140024726648384 EVENT_LOG_v1 {"time_micros": 1732273140976941, "cf_name": "default", "job": 8202, "event": "table_file_creation", "file_number": 221904, "file_size": 15238745, "file_checksum": "", "file_checksum_func_name": "Unknown", "smallest_seqno": 3411904442, "largest_seqno": 3479084182, "table_properties": {"data_size": 12960464, "index_size": 672576, "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": 9820356, "raw_average_key_size": 28, "raw_value_size": 41385786, "raw_average_value_size": 118, "num_data_blocks": 1, "num_entries": 350727, "num_filter_entries": 0, "num_deletions": 0, "num_merge_operands": 0, "num_range_deletions": 0, "format_version": 0, "fixed_key_len": 28, "fixed_value_len": 118, "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": 1732273139, "oldest_key_time": 0, "file_creation_time": 1732273139, "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": 221904, "seqno_to_time_mapping": "N/A", "__indexstats__": "[...1 records...]"}} 2024/11/22-18:59:00.991634 140024726648384 [compaction_job.cc:703] job-08202: subcompact[0], size: 0.539040 G, files: /storage/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/mytopling/.rocksdb/job-08202/att-00 [221890,221891,221892,221893,221894,221895,221896,221897,221898,221899,221900,221901,221902,221903,221904] 2024/11/22-18:59:01.093800 140024726648384 (Original Log Time 2024/11/22-18:58:07.571532) EVENT_LOG_v1 {"time_micros": 1732273087571495, "job": 8202, "event": "compaction_started", "compaction_reason": "FilesMarkedForCompaction", "files_L2": [221861], "files_L3": [221815, 221816, 221817, 221818, 221819, 221820, 221821, 221822, 221823, 221824, 221825, 221826, 221786, 221787, 221882], "score": 0.964133, "input_data_size": 564244659, "oldest_snapshot_seqno": 3484292163} 2024/11/22-18:59:01.093803 140024726648384 (Original Log Time 2024/11/22-18:59:01.093021) [compaction_job.cc:2022] [default] [JOB 8202] Compacted 1@2 + 15@3 files to L3 => 539039552 bytes 2024/11/22-18:59:01.093805 140024726648384 (Original Log Time 2024/11/22-18:59:01.093729) [compaction_job.cc:1224] [default] compacted to: files[0 0 0 15 0 0 0] max score 0.02, MB/sec: 10.6 rd, 10.1 wr, level 3, files in(1, 15) out(15 +0 blob) MB in(27.1, 511.0 +0.0 blob) out(514.1 +0.0 blob), read-write-amplify(38.8) write-amplify(18.9) OK, records in: 13553630, records dropped: 107543 output_compression: Snappy 2024/11/22-18:59:01.093813 140024726648384 (Original Log Time 2024/11/22-18:59:01.093774) EVENT_LOG_v1 {"time_micros": 1732273141093754, "cf": "default", "job": 8202, "event": "compaction_finished", "compaction_time_micros": 53420111, "compaction_time_cpu_micros": 22575688, "output_level": 3, "num_output_files": 15, "total_output_size": 539039552, "num_input_records": 13553630, "num_output_records": 13446087, "num_subcompactions": 1, "output_compression": "Snappy", "num_single_delete_mismatches": 0, "num_single_delete_fallthrough": 0, "file_write_nanos": 342130512, "file_range_sync_nanos": 0, "file_fsync_nanos": 15502, "file_prepare_write_nanos": 32331, "lsm_state": [0, 0, 0, 15, 0, 0, 0]} 2024/11/22-18:59:01.213756 140024726648384 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":1732273087,"executesMs":53640,"compactionJobId":8202,"attempt":0,"compactionInputRawBytes":1945526521,"compactionInputZipBytes":564244659,"compactionOutputRawBytes":0,"compactionOutputZipBytes":539039552,"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-01c4cc245d6cf0028ec0e6b888c6c9f1-cf1ab5e7c5030d6b-00"}}, response = 2024/11/22-18:59:01.213854 140024726648384 INFO dcompact_worker.cpp:1459: finish /worker/dcompact-log/nfs/i-wz9deglxdazgiryp6ltb_10.57.5.137/2024-11-22T14.14.14/.rocksdb/job-08202/att-00: olev 3, work 53.641 s, result 4.861 ms, install 0.814 ms, input{raw 1.812 GiB zip 538.106 MiB}