[2025-03-27 09:02:03.732937] WDIAG [STORAGE] ~ObOccamTimeGuard (ob_occam_time_guard.h:269) [2597][T1_Occam][T1][YCEE7F000001-00063092CD8FFE74-0-0] [lt=1][errcode=0] cost too much time:ob_tablet_create_delete_helper.cpp:static int oceanbase::storage::ObTabletCreateDeleteHelper::get_tablet(const oceanbase::storage::ObTabletMapKey &, oceanbase::storage::ObTabletHandle &, const int64_t), (*this=|threshold=10.00ms|start at 09:02:03.694|total=38.62ms) [2025-03-27 09:04:04.785381] WDIAG [STORAGE] ~ObOccamTimeGuard (ob_occam_time_guard.h:269) [2597][T1_Occam][T1][YCEE7F000001-00063092CD8FFED4-0-0] [lt=149][errcode=0] cost too much time:ob_tablet_create_delete_helper.cpp:static int oceanbase::storage::ObTabletCreateDeleteHelper::get_tablet(const oceanbase::storage::ObTabletMapKey &, oceanbase::storage::ObTabletHandle &, const int64_t), (*this=|threshold=10.00ms|start at 09:04:04.726|total=58.80ms) [2025-03-27 09:05:51.684096] WDIAG [MDS] ~ObOccamTimeGuard (ob_occam_time_guard.h:269) [2763][T1_MediumLoop][T1][YCEE7F000001-00063090CB0FFFF4-0-0] [lt=0][errcode=0] cost too much time:ob_i_tablet_mds_interface.ipp:int oceanbase::storage::ObITabletMdsInterface::get_mds_data_from_tablet(const common::ObFunction &) const, (*this=|threshold=10.00ms|start at 09:05:51.668|225=0us|229=15.12ms|total=15.16ms) [2025-03-27 09:05:51.684151] WDIAG [MDS] ~ObOccamTimeGuard (ob_occam_time_guard.h:269) [2763][T1_MediumLoop][T1][YCEE7F000001-00063090CB0FFFF4-0-0] [lt=51][errcode=0] cost too much time:ob_i_tablet_mds_interface.ipp:int oceanbase::storage::ObITabletMdsInterface::get_latest(OP &&, bool &, const int64_t) const [T = oceanbase::storage::ObTabletBindingMdsUserData, OP = (lambda at /home/jenkins/agent/workspace/rpm-ocea..., (*this=|threshold=10.00ms|start at 09:05:51.668|479=0us|491=1us|493=0us|500=11us|506=0us|total=15.21ms) [2025-03-27 09:05:51.684162] WDIAG [MDS] ~ObOccamTimeGuard (ob_occam_time_guard.h:269) [2763][T1_MediumLoop][T1][YCEE7F000001-00063090CB0FFFF4-0-0] [lt=10][errcode=0] cost too much time:ob_i_tablet_mds_interface.ipp:int oceanbase::storage::ObITabletMdsInterface::get_latest_ddl_data(oceanbase::storage::ObTabletBindingMdsUserData &, bool &) const, (*this=|threshold=10.00ms|start at 09:05:51.668|75=0us|total=15.22ms) [2025-03-27 09:05:51.684793] INFO [MDS.EVENT]construct_insert_record_user_mds_node_ (mds_row.ipp:216) [2763][T1_MediumLoop][T1][YCEE7F000001-00063090CB0FFFF4-0-0] [lt=5] WRITE_NODE(key={tenant_id:1, ls_id:{id:1}, tablet_id:{id:1152921504606852838}}, event={alloc:null, timestamp:"2025-03-27 08:38:00.820979", event:"WRITE_NODE", info_str:"{compaction_type:"MEDIUM_COMPACTION", medium_merge_reason:"INEFFICIENT_QUERY", medium_snapshot:1743037541146263893, last_medium_snapshot:1743012003173736479, tenant_id:1, cluster_id:1, medium_compat_version:4, data_version:17180000519, is_schema_changed:0, storage_schema:{this:0x14d7c706db00, storage_schema_version:2, version:0, is_use_bloomfilter:0, column_info_simplified:0, compat_mode:0, table_type:5, index_type:1, index_status:2, row_store_type:1, schema_version:1743012000485000, column_cnt:2, store_column_cnt:2, tablet_size:134217728, pctfree:0, block_size:16384, progressive_merge_round:8, master_key_id:18446744073709551615, compressor_type:6, encryption:"", encrypt_key:"", rowkey_cnt:2, rowkey_array:[{column_idx:17, meta_type:{type:"VARCHAR", collation:"utf8mb4_general_ci", coercibility:"INVALID"}, order:0}, {column_idx:16, meta_type:{type:"BIGINT", collation:"binary", coercibility:"NUMERIC"}, order:0}], column_array:[{meta_type:{type:"BIGINT", collation:"binary", coercibility:"NUMERIC"}, is_column_sto", unit_id:3, key_str:"", writer_type:3, writer_id:10000, seq_no:0, redo_scn:{val:4611686018427387903, v:0}, end_scn:{val:4611686018427387903, v:0}, trans_version:{val:4611686018427387903, v:0}, node_type:1, state:0}) [2025-03-27 09:05:51.685191] INFO [MDS.EVENT]on_redo_ (mds_node.ipp:150) [2776][T1_ApplySrv1][T1][YCEE7F000001-00063092D23FFD2E-0-0] [lt=0] ON_REDO(key={tenant_id:1, ls_id:{id:1}, tablet_id:{id:1152921504606852838}}, event={alloc:null, timestamp:"2025-03-27 08:37:45.796415", event:"ON_REDO", info_str:"{compaction_type:"MEDIUM_COMPACTION", medium_merge_reason:"INEFFICIENT_QUERY", medium_snapshot:1743037541146263893, last_medium_snapshot:1743012003173736479, tenant_id:1, cluster_id:1, medium_compat_version:4, data_version:17180000519, is_schema_changed:0, storage_schema:{this:0x14d7c706db00, storage_schema_version:2, version:0, is_use_bloomfilter:0, column_info_simplified:0, compat_mode:0, table_type:5, index_type:1, index_status:2, row_store_type:1, schema_version:1743012000485000, column_cnt:2, store_column_cnt:2, tablet_size:134217728, pctfree:0, block_size:16384, progressive_merge_round:8, master_key_id:18446744073709551615, compressor_type:6, encryption:"", encrypt_key:"", rowkey_cnt:2, rowkey_array:[{column_idx:17, meta_type:{type:"VARCHAR", collation:"utf8mb4_general_ci", coercibility:"INVALID"}, order:0}, {column_idx:16, meta_type:{type:"BIGINT", collation:"binary", coercibility:"NUMERIC"}, order:0}], column_array:[{meta_type:{type:"BIGINT", collation:"binary", coercibility:"NUMERIC"}, is_column_sto", unit_id:3, key_str:"", writer_type:3, writer_id:10000, seq_no:0, redo_scn:{val:1743037551613543029, v:0}, end_scn:{val:4611686018427387903, v:0}, trans_version:{val:4611686018427387903, v:0}, node_type:1, state:0}) [2025-03-27 09:05:51.685234] INFO [MDS.EVENT]before_prepare_ (mds_node.ipp:174) [2776][T1_ApplySrv1][T1][YCEE7F000001-00063092D23FFD2E-0-0] [lt=26] BEFORE_PREPARE(key={tenant_id:1, ls_id:{id:1}, tablet_id:{id:1152921504606852838}}, event={alloc:null, timestamp:"2025-03-27 08:37:45.796459", event:"BEFORE_PREPARE", info_str:"{compaction_type:"MEDIUM_COMPACTION", medium_merge_reason:"INEFFICIENT_QUERY", medium_snapshot:1743037541146263893, last_medium_snapshot:1743012003173736479, tenant_id:1, cluster_id:1, medium_compat_version:4, data_version:17180000519, is_schema_changed:0, storage_schema:{this:0x14d7c706db00, storage_schema_version:2, version:0, is_use_bloomfilter:0, column_info_simplified:0, compat_mode:0, table_type:5, index_type:1, index_status:2, row_store_type:1, schema_version:1743012000485000, column_cnt:2, store_column_cnt:2, tablet_size:134217728, pctfree:0, block_size:16384, progressive_merge_round:8, master_key_id:18446744073709551615, compressor_type:6, encryption:"", encrypt_key:"", rowkey_cnt:2, rowkey_array:[{column_idx:17, meta_type:{type:"VARCHAR", collation:"utf8mb4_general_ci", coercibility:"INVALID"}, order:0}, {column_idx:16, meta_type:{type:"BIGINT", collation:"binary", coercibility:"NUMERIC"}, order:0}], column_array:[{meta_type:{type:"BIGINT", collation:"binary", coercibility:"NUMERIC"}, is_column_sto", unit_id:3, key_str:"", writer_type:3, writer_id:10000, seq_no:0, redo_scn:{val:1743037551613543029, v:0}, end_scn:{val:4611686018427387903, v:0}, trans_version:{val:0, v:0}, node_type:1, state:1}) [2025-03-27 09:05:51.685264] INFO [MDS.EVENT]on_prepare_ (mds_node.ipp:202) [2776][T1_ApplySrv1][T1][YCEE7F000001-00063092D23FFD2E-0-0] [lt=15] ON_PREPARE(key={tenant_id:1, ls_id:{id:1}, tablet_id:{id:1152921504606852838}}, event={alloc:null, timestamp:"2025-03-27 08:37:45.796490", event:"ON_PREPARE", info_str:"{compaction_type:"MEDIUM_COMPACTION", medium_merge_reason:"INEFFICIENT_QUERY", medium_snapshot:1743037541146263893, last_medium_snapshot:1743012003173736479, tenant_id:1, cluster_id:1, medium_compat_version:4, data_version:17180000519, is_schema_changed:0, storage_schema:{this:0x14d7c706db00, storage_schema_version:2, version:0, is_use_bloomfilter:0, column_info_simplified:0, compat_mode:0, table_type:5, index_type:1, index_status:2, row_store_type:1, schema_version:1743012000485000, column_cnt:2, store_column_cnt:2, tablet_size:134217728, pctfree:0, block_size:16384, progressive_merge_round:8, master_key_id:18446744073709551615, compressor_type:6, encryption:"", encrypt_key:"", rowkey_cnt:2, rowkey_array:[{column_idx:17, meta_type:{type:"VARCHAR", collation:"utf8mb4_general_ci", coercibility:"INVALID"}, order:0}, {column_idx:16, meta_type:{type:"BIGINT", collation:"binary", coercibility:"NUMERIC"}, order:0}], column_array:[{meta_type:{type:"BIGINT", collation:"binary", coercibility:"NUMERIC"}, is_column_sto", unit_id:3, key_str:"", writer_type:3, writer_id:10000, seq_no:0, redo_scn:{val:1743037551613543029, v:0}, end_scn:{val:4611686018427387903, v:0}, trans_version:{val:1743037551613543029, v:0}, node_type:1, state:2}) [2025-03-27 09:05:51.685292] INFO [MDS.EVENT]on_commit_ (mds_node.ipp:239) [2776][T1_ApplySrv1][T1][YCEE7F000001-00063092D23FFD2E-0-0] [lt=13] ON_COMMIT(key={tenant_id:1, ls_id:{id:1}, tablet_id:{id:1152921504606852838}}, event={alloc:null, timestamp:"2025-03-27 08:37:45.796517", event:"ON_COMMIT", info_str:"{compaction_type:"MEDIUM_COMPACTION", medium_merge_reason:"INEFFICIENT_QUERY", medium_snapshot:1743037541146263893, last_medium_snapshot:1743012003173736479, tenant_id:1, cluster_id:1, medium_compat_version:4, data_version:17180000519, is_schema_changed:0, storage_schema:{this:0x14d7c706db00, storage_schema_version:2, version:0, is_use_bloomfilter:0, column_info_simplified:0, compat_mode:0, table_type:5, index_type:1, index_status:2, row_store_type:1, schema_version:1743012000485000, column_cnt:2, store_column_cnt:2, tablet_size:134217728, pctfree:0, block_size:16384, progressive_merge_round:8, master_key_id:18446744073709551615, compressor_type:6, encryption:"", encrypt_key:"", rowkey_cnt:2, rowkey_array:[{column_idx:17, meta_type:{type:"VARCHAR", collation:"utf8mb4_general_ci", coercibility:"INVALID"}, order:0}, {column_idx:16, meta_type:{type:"BIGINT", collation:"binary", coercibility:"NUMERIC"}, order:0}], column_array:[{meta_type:{type:"BIGINT", collation:"binary", coercibility:"NUMERIC"}, is_column_sto", unit_id:3, key_str:"", writer_type:3, writer_id:10000, seq_no:0, redo_scn:{val:1743037551613543029, v:0}, end_scn:{val:1743037551613543029, v:0}, trans_version:{val:1743037551613543029, v:0}, node_type:1, state:3}) [2025-03-27 09:05:57.072438] WDIAG [STORAGE] ~ObOccamTimeGuard (ob_occam_time_guard.h:269) [2685][T1_MINI_MERGE][T1][YCEE7F000001-000630927F9FFCF0-0-0] [lt=16][errcode=0] cost too much time:ob_tablet_persister.cpp:static int oceanbase::storage::ObTabletPersister::convert_tablet_to_disk_arg(common::ObArenaAllocator &, const oceanbase::storage::ObTablet &, common::ObIArray &, common::ObIArray &, common::ObIArray &, common::ObIArray::scan_KV_row(DUMP_OP &&, share::SCN &, const uint8_t, const uint8_t, const bool) con..., (*this=|threshold=10.00ms|start at 09:07:51.865|476=0us|total=27.65ms) [2025-03-27 09:07:51.892946] WDIAG [STORAGE] ~ObOccamTimeGuard (ob_occam_time_guard.h:269) [2763][T1_MediumLoop][T1][YCEE7F000001-00063090CB0FFFF5-0-0] [lt=320][errcode=0] cost too much time:ob_tablet.cpp:int oceanbase::storage::ObTablet::read_mds_table_medium_info_list(common::ObIAllocator &, oceanbase::storage::ObTabletDumpedMediumInfo &) const, (*this=|threshold=10.00ms|start at 09:07:51.864|5188=0us|5190=0us|5199=0us|total=27.97ms) [2025-03-27 09:07:51.982330] WDIAG [STORAGE] ~ObOccamTimeGuard (ob_occam_time_guard.h:269) [2763][T1_MediumLoop][T1][YCEE7F000001-00063090CB0FFFF5-0-0] [lt=158][errcode=0] cost too much time:ob_tablet.cpp:int oceanbase::storage::ObTablet::read_mds_table_medium_info_list(common::ObIAllocator &, oceanbase::storage::ObTabletDumpedMediumInfo &) const, (*this=|threshold=10.00ms|start at 09:07:51.966|5188=0us|5190=0us|5199=0us|total=15.48ms) [2025-03-27 09:07:51.992258] WDIAG [MDS] ~ObOccamTimeGuard (ob_occam_time_guard.h:269) [2763][T1_MediumLoop][T1][YCEE7F000001-00063090CB0FFFF5-0-0] [lt=196][errcode=0] cost too much time:mds_dump_node.h:int oceanbase::storage::mds::MdsDumpNode::init(const uint8_t, const uint8_t, const UserMdsNode &, oceanbase::common::ObIAllocator &) [K = oceanbase::compaction::ObMediumCompactionInfoKey, V = oceanbase::compac..., (*this=|threshold=1.00ms|start at 09:07:51.989|196=0us|215=2.43ms|total=2.48ms) [2025-03-27 09:07:55.031037] WDIAG [STORAGE] ~ObOccamTimeGuard (ob_occam_time_guard.h:269) [2700][T1_MAJOR_MERGE][T1][YCEE7F000001-000630927F9FFCF1-0-0] [lt=14][errcode=0] cost too much time:ob_tablet_slog_helper.cpp:static int oceanbase::storage::ObTabletSlogHelper::write_update_tablet_slog(const share::ObLSID &, const common::ObTabletID &, const oceanbase::storage::ObMetaDiskAddr &), (*this=|threshold=10.00ms|start at 09:07:55.140|44=0us|52=116us|56=16.84ms|total=16.99ms) [2025-03-27 09:08:04.402389] WDIAG [MDS] ~ObOccamTimeGuard (ob_occam_time_guard.h:269) [2597][T1_Occam][T1][YCEE7F000001-00063092CD8FFF92-0-0] [lt=9][errcode=0] cost too much time:mds_tenant_service.cpp:int oceanbase::storage::mds::ObTenantMdsTimer::get_tablet_oldest_scn_(oceanbase::storage::ObTablet &, share::SCN &), (*this=|threshold=5.00ms|start at 09:08:04.396|441=0us|total=6.15ms) [2025-03-27 09:09:21.580140] WDIAG [MDS] ~ObOccamTimeGuard (ob_occam_time_guard.h:269) [2597][T1_Occam][T1][YCEE7F000001-00063092CD8FFFD0-0-0] [lt=40][errcode=0] cost too much time:mds_table_handler.cpp:int oceanbase::storage::mds::ObMdsTableHandler::try_release_nodes_below(const share::SCN &), (*this=|threshold=5.00ms|start at 09:09:21.570|145=0us|149=0us|total=9.82ms) [2025-03-27 09:09:21.580175] WDIAG [MDS] ~ObOccamTimeGuard (ob_occam_time_guard.h:269) [2597][T1_Occam][T1][YCEE7F000001-00063092CD8FFFD0-0-0] [lt=33][errcode=0] cost too much time:mds_tenant_service.cpp:int oceanbase::storage::mds::ObTenantMdsTimer::try_recycle_mds_table_(oceanbase::storage::ObTablet &, const share::SCN &), (*this=|threshold=5.00ms|start at 09:09:21.570|468=0us|total=9.84ms) [2025-03-27 09:09:36.607940] WDIAG [MDS] ~ObOccamTimeGuard (ob_occam_time_guard.h:269) [2597][T1_Occam][T1][YCEE7F000001-00063092CD8FFFDC-0-0] [lt=12][errcode=0] cost too much time:mds_table_handler.cpp:int oceanbase::storage::mds::ObMdsTableHandler::try_release_nodes_below(const share::SCN &), (*this=|threshold=5.00ms|start at 09:09:36.591|145=1us|149=16.64ms|total=16.70ms) [2025-03-27 09:09:36.608188] WDIAG [MDS] ~ObOccamTimeGuard (ob_occam_time_guard.h:269) [2597][T1_Occam][T1][YCEE7F000001-00063092CD8FFFDC-0-0] [lt=244][errcode=0] cost too much time:mds_tenant_service.cpp:int oceanbase::storage::mds::ObTenantMdsTimer::try_recycle_mds_table_(oceanbase::storage::ObTablet &, const share::SCN &), (*this=|threshold=5.00ms|start at 09:09:36.591|468=0us|total=16.91ms) [2025-03-27 09:09:36.608229] WDIAG [MDS] ~ObOccamTimeGuard (ob_occam_time_guard.h:269) [2597][T1_Occam][T1][YCEE7F000001-00063092CD8FFFDC-0-0] [lt=36][errcode=0] cost too much time:mds_tenant_service.cpp:int oceanbase::storage::mds::ObTenantMdsTimer::process_with_tablet_(oceanbase::storage::ObTablet &), (*this=|threshold=10.00ms|start at 09:09:36.591|414=0us|416=0us|418=16.93ms|total=16.95ms) [2025-03-27 09:09:52.123839] WDIAG [STORAGE] ~ObOccamTimeGuard (ob_occam_time_guard.h:269) [2763][T1_MediumLoop][T1][YCEE7F000001-00063090CB0FFFF6-0-0] [lt=425][errcode=0] cost too much time:ob_tablet_slog_helper.cpp:static int oceanbase::storage::ObTabletSlogHelper::write_update_tablet_slog(const share::ObLSID &, const common::ObTabletID &, const oceanbase::storage::ObMetaDiskAddr &), (*this=|threshold=10.00ms|start at 09:09:52.107|44=1us|52=142us|56=16.37ms|total=16.55ms) [2025-03-27 09:10:09.618721] WDIAG [STORAGE] ~ObOccamTimeGuard (ob_occam_time_guard.h:269) [2751][T1_TxCkpt][T1][Y0-0000000000000000-0-0] [lt=0][errcode=0] cost too much time:ob_tablet_create_delete_helper.cpp:static int oceanbase::storage::ObTabletCreateDeleteHelper::get_tablet(const oceanbase::storage::ObTabletMapKey &, oceanbase::storage::ObTabletHandle &, const int64_t), (*this=|threshold=10.00ms|start at 09:10:09.582|total=36.56ms) [2025-03-27 09:10:09.668185] WDIAG [STORAGE] ~ObOccamTimeGuard (ob_occam_time_guard.h:269) [2751][T1_TxCkpt][T1][Y0-0000000000000000-0-0] [lt=43][errcode=0] cost too much time:ob_tablet_create_delete_helper.cpp:static int oceanbase::storage::ObTabletCreateDeleteHelper::get_tablet(const oceanbase::storage::ObTabletMapKey &, oceanbase::storage::ObTabletHandle &, const int64_t), (*this=|threshold=10.00ms|start at 09:10:09.618|total=49.30ms) [2025-03-27 09:11:09.786242] WDIAG [MDS] ~ObOccamTimeGuard (ob_occam_time_guard.h:269) [2764][T1_SSTableGC][T1][YCEE7F000001-00063092D2AFFAAB-0-0] [lt=0][errcode=0] cost too much time:mds_unit.ipp:int oceanbase::storage::mds::MdsUnit::GetSnapShotOp<(lambda at /home/jenkins/agent/workspace/rpm-oceanbase-ce-4.2.1.0-bp-7-hotfix-..., (*this=|threshold=10.00ms|start at 09:11:09.748|722=0us|total=38.13ms) [2025-03-27 09:11:09.787212] WDIAG [MDS] ~ObOccamTimeGuard (ob_occam_time_guard.h:269) [2764][T1_SSTableGC][T1][YCEE7F000001-00063092D2AFFAAB-0-0] [lt=965][errcode=0] cost too much time:mds_retry_control.h:int oceanbase::storage::mds::retry_release_lock_with_op_until_timeout(const oceanbase::storage::mds::MdsLock &, oceanbase::storage::mds::RetryParam &, OP &&) [MODE = oceanbase::storage::mds::LockMode::READ, OP =..., (*this=|threshold=10.00ms|start at 09:11:09.748|68=0us|total=39.09ms) [2025-03-27 09:11:09.787303] WDIAG [MDS] ~ObOccamTimeGuard (ob_occam_time_guard.h:269) [2764][T1_SSTableGC][T1][YCEE7F000001-00063092D2AFFAAB-0-0] [lt=87][errcode=0] cost too much time:mds_unit.ipp:int oceanbase::storage::mds::MdsUnit::get_snapshot(OP &&, const share::SCN, const int64_t, const int64_t) const [K = oceanbase::st..., (*this=|threshold=10.00ms|start at 09:11:09.748|743=0us|total=39.18ms) [2025-03-27 09:11:09.787395] WDIAG [MDS] ~ObOccamTimeGuard (ob_occam_time_guard.h:269) [2764][T1_SSTableGC][T1][YCEE7F000001-00063092D2AFFAAB-0-0] [lt=84][errcode=0] cost too much time:mds_table_impl.ipp:virtual int oceanbase::storage::mds::MdsTableImpl, oceanbase::st..., (*this=|threshold=5.00ms|start at 09:11:09.748|632=0us|total=39.27ms) [2025-03-27 09:11:09.787464] WDIAG [MDS] ~ObOccamTimeGuard (ob_occam_time_guard.h:269) [2764][T1_SSTableGC][T1][YCEE7F000001-00063092D2AFFAAB-0-0] [lt=63][errcode=0] cost too much time:ob_i_tablet_mds_interface.ipp:int oceanbase::storage::ObITabletMdsInterface::get_snapshot(OP &&, const share::SCN, const int64_t, const int64_t) const [T = oceanbase::storage::ObTabletCreateDeleteMdsUserData, OP = (lambda at /home/..., (*this=|threshold=10.00ms|start at 09:11:09.748|534=0us|546=1us|548=0us|555=39.33ms|560=1us|total=39.34ms) [2025-03-27 09:11:09.787532] WDIAG [MDS] ~ObOccamTimeGuard (ob_occam_time_guard.h:269) [2764][T1_SSTableGC][T1][YCEE7F000001-00063092D2AFFAAB-0-0] [lt=65][errcode=0] cost too much time:ob_i_tablet_mds_interface.ipp:int oceanbase::storage::ObITabletMdsInterface::get_tablet_status(const share::SCN &, oceanbase::storage::ObTabletCreateDeleteMdsUserData &, const int64_t) const, (*this=|threshold=10.00ms|start at 09:11:09.748|33=0us|total=39.41ms) [2025-03-27 09:11:51.598623] WDIAG [MDS] ~ObOccamTimeGuard (ob_occam_time_guard.h:269) [2597][T1_Occam][T1][YCEE7F000001-00063092D32FFAEC-0-0] [lt=407][errcode=0] cost too much time:mds_tenant_service.cpp:int oceanbase::storage::mds::ObTenantMdsTimer::try_recycle_mds_table_(oceanbase::storage::ObTablet &, const share::SCN &), (*this=|threshold=5.00ms|start at 09:11:51.592|468=0us|total=5.65ms) [2025-03-27 09:12:11.674478] WDIAG [MDS] ~ObOccamTimeGuard (ob_occam_time_guard.h:269) [2764][T1_SSTableGC][T1][YCEE7F000001-00063092D2AFFAAD-0-0] [lt=57][errcode=0] cost too much time:mds_table_impl.ipp:virtual int oceanbase::storage::mds::MdsTableImpl, oceanbase::st..., (*this=|threshold=5.00ms|start at 09:12:11.666|632=0us|total=8.14ms) [2025-03-27 09:13:03.845605] WDIAG [MDS] ~ObOccamTimeGuard (ob_occam_time_guard.h:269) [2597][T1_Occam][T1][YCEE7F000001-00063092D32FFB26-0-0] [lt=35][errcode=0] cost too much time:mds_tenant_service.cpp:int oceanbase::storage::mds::ObTenantMdsTimer::get_tablet_oldest_scn_(oceanbase::storage::ObTablet &, share::SCN &), (*this=|threshold=5.00ms|start at 09:13:03.814|441=0us|total=31.34ms) [2025-03-27 09:13:03.845644] WDIAG [MDS] ~ObOccamTimeGuard (ob_occam_time_guard.h:269) [2597][T1_Occam][T1][YCEE7F000001-00063092D32FFB26-0-0] [lt=32][errcode=0] cost too much time:mds_tenant_service.cpp:int oceanbase::storage::mds::ObTenantMdsTimer::process_with_tablet_(oceanbase::storage::ObTablet &), (*this=|threshold=10.00ms|start at 09:13:03.814|414=0us|416=31.35ms|418=4us|total=31.36ms) [2025-03-27 09:13:07.377530] WDIAG [MDS] ~ObOccamTimeGuard (ob_occam_time_guard.h:269) [2597][T1_Occam][T1][YCEE7F000001-00063092D32FFB28-0-0] [lt=9][errcode=0] cost too much time:mds_tenant_service.cpp:int oceanbase::storage::mds::ObTenantMdsTimer::try_gc_mds_table_(oceanbase::storage::ObTablet &), (*this=|threshold=5.00ms|start at 09:13:07.309|488=0us|total=67.72ms) [2025-03-27 09:13:07.377656] WDIAG [MDS] ~ObOccamTimeGuard (ob_occam_time_guard.h:269) [2597][T1_Occam][T1][YCEE7F000001-00063092D32FFB28-0-0] [lt=122][errcode=0] cost too much time:mds_tenant_service.cpp:int oceanbase::storage::mds::ObTenantMdsTimer::process_with_tablet_(oceanbase::storage::ObTablet &), (*this=|threshold=10.00ms|start at 09:13:07.309|414=0us|416=1us|418=1us|total=67.83ms) [2025-03-27 09:13:10.126838] WDIAG [STORAGE] ~ObOccamTimeGuard (ob_occam_time_guard.h:269) [2597][T1_Occam][T1][YCEE7F000001-00063092D32FFB2C-0-0] [lt=164][errcode=0] cost too much time:ob_tablet_create_delete_helper.cpp:static int oceanbase::storage::ObTabletCreateDeleteHelper::get_tablet(const oceanbase::storage::ObTabletMapKey &, oceanbase::storage::ObTabletHandle &, const int64_t), (*this=|threshold=10.00ms|start at 09:13:10.108|total=18.07ms) [2025-03-27 09:15:42.022381] WDIAG [STORAGE] ~ObOccamTimeGuard (ob_occam_time_guard.h:269) [2764][T1_SSTableGC][T1][YCEE7F000001-00063092D2AFFAB4-0-0] [lt=182][errcode=0] cost too much time:ob_tablet_create_delete_helper.cpp:static int oceanbase::storage::ObTabletCreateDeleteHelper::get_tablet(const oceanbase::storage::ObTabletMapKey &, oceanbase::storage::ObTabletHandle &, const int64_t), (*this=|threshold=10.00ms|start at 09:15:42.722|total=15.19ms) [2025-03-27 09:15:52.534394] WDIAG [MDS] ~ObOccamTimeGuard (ob_occam_time_guard.h:269) [2763][T1_MediumLoop][T1][YCEE7F000001-00063090CB0FFFF9-0-0] [lt=38][errcode=0] cost too much time:mds_dump_node.h:int oceanbase::storage::mds::MdsDumpNode::init(const uint8_t, const uint8_t, const UserMdsNode &, oceanbase::common::ObIAllocator &) [K = oceanbase::compaction::ObMediumCompactionInfoKey, V = oceanbase::compac..., (*this=|threshold=1.00ms|start at 09:15:52.530|196=4.02ms|215=5us|total=4.05ms) [2025-03-27 09:15:52.570380] WDIAG [MDS] ~ObOccamTimeGuard (ob_occam_time_guard.h:269) [2763][T1_MediumLoop][T1][YCEE7F000001-00063090CB0FFFF9-0-0] [lt=33][errcode=0] cost too much time:mds_dump_node.cpp:uint32_t oceanbase::storage::mds::MdsDumpKey::generate_hash() const, (*this=|threshold=1.00ms|start at 09:15:52.566|total=4.04ms) [2025-03-27 09:15:52.602386] WDIAG [MDS] ~ObOccamTimeGuard (ob_occam_time_guard.h:269) [2763][T1_MediumLoop][T1][YCEE7F000001-00063090CB0FFFF9-0-0] [lt=34][errcode=0] cost too much time:mds_dump_node.h:int oceanbase::storage::mds::MdsDumpNode::init(const uint8_t, const uint8_t, const UserMdsNode &, oceanbase::common::ObIAllocator &) [K = oceanbase::compaction::ObMediumCompactionInfoKey, V = oceanbase::compac..., (*this=|threshold=1.00ms|start at 09:15:52.598|196=1us|215=4.02ms|total=4.05ms) [2025-03-27 09:15:52.686389] WDIAG [MDS] ~ObOccamTimeGuard (ob_occam_time_guard.h:269) [2763][T1_MediumLoop][T1][YCEE7F000001-00063090CB0FFFF9-0-0] [lt=36][errcode=0] cost too much time:mds_dump_node.h:int oceanbase::storage::mds::MdsDumpNode::init(const uint8_t, const uint8_t, const UserMdsNode &, oceanbase::common::ObIAllocator &) [K = oceanbase::compaction::ObMediumCompactionInfoKey, V = oceanbase::compac..., (*this=|threshold=1.00ms|start at 09:15:52.682|196=1us|215=4.02ms|total=4.05ms) [2025-03-27 09:15:52.732584] WDIAG [MDS] ~ObOccamTimeGuard (ob_occam_time_guard.h:269) [2763][T1_MediumLoop][T1][YCEE7F000001-00063090CB0FFFF9-0-0] [lt=30][errcode=0] cost too much time:mds_dump_node.h:int oceanbase::storage::mds::MdsDumpNode::init(const uint8_t, const uint8_t, const UserMdsNode &, oceanbase::common::ObIAllocator &) [K = oceanbase::compaction::ObMediumCompactionInfoKey, V = oceanbase::compac..., (*this=|threshold=1.00ms|start at 09:15:52.730|196=0us|215=2.22ms|total=2.25ms) [2025-03-27 09:16:08.178077] WDIAG [STORAGE] ~ObOccamTimeGuard (ob_occam_time_guard.h:269) [2597][T1_Occam][T1][YCEE7F000001-00063092D32FFBB8-0-0] [lt=44][errcode=0] cost too much time:ob_tablet_create_delete_helper.cpp:static int oceanbase::storage::ObTabletCreateDeleteHelper::get_tablet(const oceanbase::storage::ObTabletMapKey &, oceanbase::storage::ObTabletHandle &, const int64_t), (*this=|threshold=10.00ms|start at 09:16:07.362|total=815.25ms) [2025-03-27 09:16:09.588089] WDIAG [MDS] ~ObOccamTimeGuard (ob_occam_time_guard.h:269) [2597][T1_Occam][T1][YCEE7F000001-00063092D32FFBBC-0-0] [lt=215][errcode=0] cost too much time:mds_tenant_service.cpp:int oceanbase::storage::mds::ObTenantMdsTimer::get_tablet_oldest_scn_(oceanbase::storage::ObTablet &, share::SCN &), (*this=|threshold=5.00ms|start at 09:16:09.578|441=1us|total=9.76ms) [2025-03-27 09:16:54.554382] WDIAG [MDS] ~ObOccamTimeGuard (ob_occam_time_guard.h:269) [2597][T1_Occam][T1][YCEE7F000001-00063092D32FFBE0-0-0] [lt=248][errcode=0] cost too much time:mds_tenant_service.cpp:int oceanbase::storage::mds::ObTenantMdsTimer::try_gc_mds_table_(oceanbase::storage::ObTablet &), (*this=|threshold=5.00ms|start at 09:16:54.546|488=0us|total=8.05ms) [2025-03-27 09:17:01.615389] WDIAG [MDS] ~ObOccamTimeGuard (ob_occam_time_guard.h:269) [2762][T1_MergeLoop][T1][YCEE7F000001-00063092D09FFAAF-0-0] [lt=1][errcode=0] cost too much time:mds_dump_node.h:int oceanbase::storage::mds::MdsDumpNode::init(const uint8_t, const uint8_t, const UserMdsNode &, oceanbase::common::ObIAllocator &) [K = oceanbase::compaction::ObMediumCompactionInfoKey, V = oceanbase::compac..., (*this=|threshold=1.00ms|start at 09:17:01.611|196=0us|215=4.12ms|total=4.15ms) [2025-03-27 09:17:01.630390] WDIAG [MDS] ~ObOccamTimeGuard (ob_occam_time_guard.h:269) [2762][T1_MergeLoop][T1][YCEE7F000001-00063092D09FFAAF-0-0] [lt=35][errcode=0] cost too much time:mds_dump_node.cpp:void oceanbase::storage::mds::MdsDumpNode::reset(), (*this=|threshold=1.00ms|start at 09:17:01.625|total=5.37ms) [2025-03-27 09:17:01.675873] WDIAG [MDS] ~ObOccamTimeGuard (ob_occam_time_guard.h:269) [2762][T1_MergeLoop][T1][YCEE7F000001-00063092D09FFAAF-0-0] [lt=38][errcode=0] cost too much time:mds_row.ipp:auto oceanbase::storage::mds::MdsRow::scan_dump_node_from_tail_to_head((lambda at /home/jenkins/agent/workspace/rpm-oceanb..., (*this=|threshold=5.00ms|start at 09:17:01.670|505=0us|507=2us|total=5.55ms) [2025-03-27 09:17:01.675927] WDIAG [MDS] ~ObOccamTimeGuard (ob_occam_time_guard.h:269) [2762][T1_MergeLoop][T1][YCEE7F000001-00063092D09FFAAF-0-0] [lt=48][errcode=0] cost too much time:mds_row.ipp:int oceanbase::storage::mds::MdsRow::scan_dump_node_from_tail_to_head(DUMP_OP &&, const uint8_t, const uint8_t, oceanbase:..., (*this=|threshold=5.00ms|start at 09:17:01.670|481=0us|total=5.58ms) [2025-03-27 09:17:04.442391] WDIAG [MDS] ~ObOccamTimeGuard (ob_occam_time_guard.h:269) [2597][T1_Occam][T1][YCEE7F000001-00063092D32FFBE6-0-0] [lt=44][errcode=0] cost too much time:mds_table_handler.cpp:int oceanbase::storage::mds::ObMdsTableHandler::try_release_nodes_below(const share::SCN &), (*this=|threshold=5.00ms|start at 09:17:04.434|145=0us|149=0us|total=8.05ms) [2025-03-27 09:17:04.442428] WDIAG [MDS] ~ObOccamTimeGuard (ob_occam_time_guard.h:269) [2597][T1_Occam][T1][YCEE7F000001-00063092D32FFBE6-0-0] [lt=34][errcode=0] cost too much time:mds_tenant_service.cpp:int oceanbase::storage::mds::ObTenantMdsTimer::try_recycle_mds_table_(oceanbase::storage::ObTablet &, const share::SCN &), (*this=|threshold=5.00ms|start at 09:17:04.434|468=0us|total=8.07ms) [2025-03-27 09:17:13.825195] WDIAG [MDS] ~ObOccamTimeGuard (ob_occam_time_guard.h:269) [1816751][T1_L0_G0][T1][YCEE7F000001-00063092D47FFD8C-0-0] [lt=0][errcode=0] cost too much time:ob_i_tablet_mds_interface.ipp:int oceanbase::storage::ObITabletMdsInterface::get_mds_data_from_tablet(const common::ObFunction &) const, (*this=|threshold=10.00ms|start at 09:17:13.788|225=0us|229=36.92ms|total=36.96ms) [2025-03-27 09:17:13.825240] WDIAG [MDS] ~ObOccamTimeGuard (ob_occam_time_guard.h:269) [1816751][T1_L0_G0][T1][YCEE7F000001-00063092D47FFD8C-0-0] [lt=41][errcode=0] cost too much time:ob_i_tablet_mds_interface.ipp:int oceanbase::storage::ObITabletMdsInterface::get_latest(OP &&, bool &, const int64_t) const [T = oceanbase::storage::ObTabletBindingMdsUserData, OP = (lambda at /home/jenkins/agent/workspace/rpm-ocea..., (*this=|threshold=10.00ms|start at 09:17:13.788|479=0us|491=1us|493=0us|500=5us|506=0us|total=36.98ms) [2025-03-27 09:17:13.825249] WDIAG [MDS] ~ObOccamTimeGuard (ob_occam_time_guard.h:269) [1816751][T1_L0_G0][T1][YCEE7F000001-00063092D47FFD8C-0-0] [lt=8][errcode=0] cost too much time:ob_i_tablet_mds_interface.ipp:int oceanbase::storage::ObITabletMdsInterface::get_latest_ddl_data(oceanbase::storage::ObTabletBindingMdsUserData &, bool &) const, (*this=|threshold=10.00ms|start at 09:17:13.788|75=0us|total=36.99ms) [2025-03-27 09:17:13.875013] WDIAG [MDS] ~ObOccamTimeGuard (ob_occam_time_guard.h:269) [1816751][T1_L0_G0][T1][YCEE7F000001-00063092D47FFD8C-0-0] [lt=6][errcode=0] cost too much time:ob_i_tablet_mds_interface.ipp:int oceanbase::storage::ObITabletMdsInterface::get_mds_data_from_tablet(const common::ObFunction &) const, (*this=|threshold=10.00ms|start at 09:17:13.825|225=0us|229=49.06ms|total=49.10ms) [2025-03-27 09:17:13.875048] WDIAG [MDS] ~ObOccamTimeGuard (ob_occam_time_guard.h:269) [1816751][T1_L0_G0][T1][YCEE7F000001-00063092D47FFD8C-0-0] [lt=32][errcode=0] cost too much time:ob_i_tablet_mds_interface.ipp:int oceanbase::storage::ObITabletMdsInterface::get_latest(OP &&, bool &, const int64_t) const [T = oceanbase::storage::ObTabletBindingMdsUserData, OP = (lambda at /home/jenkins/agent/workspace/rpm-ocea..., (*this=|threshold=10.00ms|start at 09:17:13.825|479=0us|491=1us|493=1us|500=5us|506=0us|total=49.12ms) [2025-03-27 09:17:13.875058] WDIAG [MDS] ~ObOccamTimeGuard (ob_occam_time_guard.h:269) [1816751][T1_L0_G0][T1][YCEE7F000001-00063092D47FFD8C-0-0] [lt=9][errcode=0] cost too much time:ob_i_tablet_mds_interface.ipp:int oceanbase::storage::ObITabletMdsInterface::get_latest_ddl_data(oceanbase::storage::ObTabletBindingMdsUserData &, bool &) const, (*this=|threshold=10.00ms|start at 09:17:13.825|75=0us|total=49.13ms) [2025-03-27 09:17:42.565182] WDIAG [MDS] ~ObOccamTimeGuard (ob_occam_time_guard.h:269) [2597][T1_Occam][T1][YCEE7F000001-00063092D32FFC06-0-0] [lt=7][errcode=0] cost too much time:mds_tenant_service.cpp:int oceanbase::storage::mds::ObTenantMdsTimer::try_recycle_mds_table_(oceanbase::storage::ObTablet &, const share::SCN &), (*this=|threshold=5.00ms|start at 09:17:42.554|468=0us|total=10.85ms) [2025-03-27 09:17:42.565236] WDIAG [MDS] ~ObOccamTimeGuard (ob_occam_time_guard.h:269) [2597][T1_Occam][T1][YCEE7F000001-00063092D32FFC06-0-0] [lt=47][errcode=0] cost too much time:mds_tenant_service.cpp:int oceanbase::storage::mds::ObTenantMdsTimer::process_with_tablet_(oceanbase::storage::ObTablet &), (*this=|threshold=10.00ms|start at 09:17:42.554|414=0us|416=1us|418=10.87ms|total=10.89ms) [2025-03-27 09:17:45.568650] WDIAG [MDS] ~ObOccamTimeGuard (ob_occam_time_guard.h:269) [2597][T1_Occam][T1][YCEE7F000001-00063092D32FFC08-0-0] [lt=15][errcode=0] cost too much time:mds_tenant_service.cpp:int oceanbase::storage::mds::ObTenantMdsTimer::get_tablet_oldest_scn_(oceanbase::storage::ObTablet &, share::SCN &), (*this=|threshold=5.00ms|start at 09:17:45.563|441=0us|total=5.57ms) [2025-03-27 09:17:59.761711] WDIAG [DETECT] ~ObOccamFastTimeGuard (ob_occam_time_guard.h:393) [2743][T1_LCLSender][T1][Y0-0000000000000000-0-0] [lt=22][errcode=0] cost too much time:ob_lcl_batch_sender_thread.cpp:virtual void oceanbase::share::detector::ObLCLBatchSenderThread::run1(), (*this=|threshold=30.00ms|start at 09:17:59.721|242=40.19ms|total=40.23ms) [2025-03-27 09:18:04.349119] WDIAG [MDS] ~ObOccamTimeGuard (ob_occam_time_guard.h:269) [2597][T1_Occam][T1][YCEE7F000001-00063092D32FFC16-0-0] [lt=43][errcode=0] cost too much time:mds_tenant_service.cpp:int oceanbase::storage::mds::ObTenantMdsTimer::try_gc_mds_table_(oceanbase::storage::ObTablet &), (*this=|threshold=5.00ms|start at 09:18:04.301|488=0us|total=47.86ms) [2025-03-27 09:18:04.349160] WDIAG [MDS] ~ObOccamTimeGuard (ob_occam_time_guard.h:269) [2597][T1_Occam][T1][YCEE7F000001-00063092D32FFC16-0-0] [lt=38][errcode=0] cost too much time:mds_tenant_service.cpp:int oceanbase::storage::mds::ObTenantMdsTimer::process_with_tablet_(oceanbase::storage::ObTablet &), (*this=|threshold=10.00ms|start at 09:18:04.301|414=0us|416=1us|418=1us|total=47.88ms) [2025-03-27 09:18:21.600089] WDIAG [MDS] ~ObOccamTimeGuard (ob_occam_time_guard.h:269) [2597][T1_Occam][T1][YCEE7F000001-00063092D32FFC24-0-0] [lt=8][errcode=0] cost too much time:mds_tenant_service.cpp:int oceanbase::storage::mds::ObTenantMdsTimer::process_with_tablet_(oceanbase::storage::ObTablet &), (*this=|threshold=10.00ms|start at 09:18:21.582|414=0us|416=1us|418=1us|total=17.88ms) [2025-03-27 09:18:49.832695] WDIAG [MDS] ~ObOccamTimeGuard (ob_occam_time_guard.h:269) [1816824][T1_L0_G0][T1][YCEE7F000001-00063092D4DFFDD8-0-0] [lt=0][errcode=0] cost too much time:ob_i_tablet_mds_interface.ipp:int oceanbase::storage::ObITabletMdsInterface::get_mds_data_from_tablet(const common::ObFunction &) const, (*this=|threshold=10.00ms|start at 09:18:49.801|225=0us|229=31.37ms|total=31.40ms) [2025-03-27 09:18:49.832734] WDIAG [MDS] ~ObOccamTimeGuard (ob_occam_time_guard.h:269) [1816824][T1_L0_G0][T1][YCEE7F000001-00063092D4DFFDD8-0-0] [lt=36][errcode=0] cost too much time:ob_i_tablet_mds_interface.ipp:int oceanbase::storage::ObITabletMdsInterface::get_latest(OP &&, bool &, const int64_t) const [T = oceanbase::storage::ObTabletBindingMdsUserData, OP = (lambda at /home/jenkins/agent/workspace/rpm-ocea..., (*this=|threshold=10.00ms|start at 09:18:49.801|479=0us|491=1us|493=0us|500=5us|506=0us|total=31.43ms) [2025-03-27 09:18:49.832744] WDIAG [MDS] ~ObOccamTimeGuard (ob_occam_time_guard.h:269) [1816824][T1_L0_G0][T1][YCEE7F000001-00063092D4DFFDD8-0-0] [lt=9][errcode=0] cost too much time:ob_i_tablet_mds_interface.ipp:int oceanbase::storage::ObITabletMdsInterface::get_latest_ddl_data(oceanbase::storage::ObTabletBindingMdsUserData &, bool &) const, (*this=|threshold=10.00ms|start at 09:18:49.801|75=0us|total=31.44ms) [2025-03-27 09:19:22.952697] WDIAG [STORAGE] ~ObOccamTimeGuard (ob_occam_time_guard.h:269) [2597][T1_Occam][T1][YCEE7F000001-00063092D32FFC54-0-0] [lt=235][errcode=0] cost too much time:ob_tablet_create_delete_helper.cpp:static int oceanbase::storage::ObTabletCreateDeleteHelper::get_tablet(const oceanbase::storage::ObTabletMapKey &, oceanbase::storage::ObTabletHandle &, const int64_t), (*this=|threshold=10.00ms|start at 09:19:22.749|total=203.24ms) [2025-03-27 09:21:01.763623] WDIAG [STORAGE] ~ObOccamTimeGuard (ob_occam_time_guard.h:269) [2762][T1_MergeLoop][T1][YCEE7F000001-00063092D09FFAB1-0-0] [lt=11][errcode=0] cost too much time:ob_tablet_create_delete_helper.cpp:static int oceanbase::storage::ObTabletCreateDeleteHelper::get_tablet(const oceanbase::storage::ObTabletMapKey &, oceanbase::storage::ObTabletHandle &, const int64_t), (*this=|threshold=10.00ms|start at 09:21:01.741|total=22.65ms) [2025-03-27 09:21:01.763623] EDIAG [STORAGE] ~ObOccamTimeGuard (ob_occam_time_guard.h:269) [2762][T1_MergeLoop][T1][YCEE7F000001-00063092D09FFAB1-0-0] [lt=11][errcode=0] LOGGER COST TOO MUCH TIME, cost: 1501242, time dist: FORMAT_END=136, ALLOC_END=2, APPEND_END=1501103, BACKTRACE: 0x1203501c 0x711d967 0x5032664 0x711d374 0x71151d1 0x4cf3a8c 0x4cf1067 0x4cf0c30 0x4e71433 0x4e712e9 0x4e6c0e4 0x4e68141 0x1230b621 0x123077c3 0x14d92d7f1f2b 0x14d92d7276bf [2025-03-27 09:21:03.326379] WDIAG [MDS] ~ObOccamTimeGuard (ob_occam_time_guard.h:269) [2762][T1_MergeLoop][T1][YCEE7F000001-00063092D09FFAB1-0-0] [lt=1501242][errcode=0] cost too much time:mds_dump_node.h:int oceanbase::storage::mds::MdsDumpKey::init(const uint8_t, const uint8_t, const UnitKey &, oceanbase::common::ObIAllocator &) [UnitKey = oceanbase::compaction::ObMediumCompactionInfoKey], (*this=|threshold=1.00ms|start at 09:21:03.322|145=1us|157=4.01ms|total=4.04ms) [2025-03-27 09:21:07.226120] WDIAG [OCCAM] ~ObOccamFastTimeGuard (ob_occam_time_guard.h:393) [2601][T1_Occam][T1][Y0-0000000000000000-0-0] [lt=0][errcode=0] cost too much time:ob_table_access_helper.h:static int oceanbase::common::ObTableAccessHelper::read_and_convert_to_values_(const uint64_t, const char **, const int64_t, const oceanbase::common::ObString &, const oceanbase::common::ObString &, T &...)..., (*this=|threshold=1.00s|start at 09:21:05.127|340=2.10s|total=2.10s) [2025-03-27 09:21:07.226270] WDIAG [COORDINATOR] ~ObOccamFastTimeGuard (ob_occam_time_guard.h:393) [2601][T1_Occam][T1][Y0-0000000000000000-0-0] [lt=148][errcode=0] cost too much time:table_accessor.cpp:static int oceanbase::logservice::coordinator::TableAccessor::get_server_stop_status(bool &), (*this=|threshold=1.00s|start at 09:21:07.226|820=2us|total=2.10s) [2025-03-27 09:21:07.229154] WDIAG [COORDINATOR] ~ObOccamFastTimeGuard (ob_occam_time_guard.h:393) [2601][T1_Occam][T1][Y0-0000000000000000-0-0] [lt=13][errcode=0] cost too much time:table_accessor.cpp:static int oceanbase::logservice::coordinator::TableAccessor::get_all_ls_election_reference_info(common::ObIArray &), (*this=|threshold=1.00s|start at 09:21:05.118|567=1us|578=5.81ms|580=1us|582=2.71ms|584=708us|586=2.10s|588=1.42ms|593=1.43ms|599=4us|601=6us|605=2us|total=2.11s) [2025-03-27 09:21:07.229203] WDIAG [COORDINATOR] ~ObOccamFastTimeGuard (ob_occam_time_guard.h:393) [2601][T1_Occam][T1][Y0-0000000000000000-0-0] [lt=44][errcode=0] cost too much time:ob_leader_coordinator.cpp:void oceanbase::logservice::coordinator::ObLeaderCoordinator::refresh(), (*this=|threshold=1.00s|start at 09:21:07.229|143=3us|total=2.11s) [2025-03-27 09:21:07.229409] WDIAG [OCCAM] ~ObOccamFastTimeGuard (ob_occam_time_guard.h:393) [2601][T1_Occam][T1][Y0-0000000000000000-0-0] [lt=203][errcode=0] cost too much time:ob_occam_timer.h:bool oceanbase::common::occam::ObOccamTimerTask::TaskWrapper::operator()(), (*this=|threshold=1.00s|start at 09:21:07.229|total=2.11s) [2025-03-27 09:23:04.419047] WDIAG [MDS] ~ObOccamTimeGuard (ob_occam_time_guard.h:269) [2762][T1_MergeLoop][T1][YCEE7F000001-00063092D09FFAB2-0-0] [lt=32][errcode=0] cost too much time:mds_dump_node.h:int oceanbase::storage::mds::MdsDumpNode::init(const uint8_t, const uint8_t, const UserMdsNode &, oceanbase::common::ObIAllocator &) [K = oceanbase::compaction::ObMediumCompactionInfoKey, V = oceanbase::compac..., (*this=|threshold=1.00ms|start at 09:23:04.416|196=2.10ms|215=4us|total=2.13ms) [2025-03-27 09:23:04.510058] WDIAG [MDS] ~ObOccamTimeGuard (ob_occam_time_guard.h:269) [2762][T1_MergeLoop][T1][YCEE7F000001-00063092D09FFAB2-0-0] [lt=90996][errcode=0] cost too much time:mds_row.ipp:auto oceanbase::storage::mds::MdsRow::scan_dump_node_from_tail_to_head((lambda at /home/jenkins/agent/workspace/rpm-oceanb..., (*this=|threshold=5.00ms|start at 09:23:04.416|505=0us|507=93.10ms|total=93.14ms) [2025-03-27 09:23:04.510158] WDIAG [MDS] ~ObOccamTimeGuard (ob_occam_time_guard.h:269) [2762][T1_MergeLoop][T1][YCEE7F000001-00063092D09FFAB2-0-0] [lt=95][errcode=0] cost too much time:mds_row.ipp:int oceanbase::storage::mds::MdsRow::scan_dump_node_from_tail_to_head(DUMP_OP &&, const uint8_t, const uint8_t, oceanbase:..., (*this=|threshold=5.00ms|start at 09:23:04.416|481=0us|total=93.22ms) [2025-03-27 09:23:04.510195] WDIAG [MDS] ~ObOccamTimeGuard (ob_occam_time_guard.h:269) [2762][T1_MergeLoop][T1][YCEE7F000001-00063092D09FFAB2-0-0] [lt=34][errcode=0] cost too much time:mds_unit.ipp:auto oceanbase::storage::mds::MdsUnit::scan_KV_row((lambda at /home/jenkins/agent/workspace/rpm-oceanbase-ce-4.2.1.0-bp-7..., (*this=|threshold=10.00ms|start at 09:23:04.416|484=0us|491=1us|total=93.26ms) [2025-03-27 09:23:04.510230] WDIAG [MDS] ~ObOccamTimeGuard (ob_occam_time_guard.h:269) [2762][T1_MergeLoop][T1][YCEE7F000001-00063092D09FFAB2-0-0] [lt=29][errcode=0] cost too much time:mds_unit.ipp:int oceanbase::storage::mds::MdsUnit::scan_KV_row(DUMP_OP &&, share::SCN &, const uint8_t, const uint8_t, const bool) con..., (*this=|threshold=10.00ms|start at 09:23:04.416|476=1us|total=93.30ms) [2025-03-27 09:23:04.510262] WDIAG [STORAGE] ~ObOccamTimeGuard (ob_occam_time_guard.h:269) [2762][T1_MergeLoop][T1][YCEE7F000001-00063092D09FFAB2-0-0] [lt=28][errcode=0] cost too much time:ob_tablet.cpp:int oceanbase::storage::ObTablet::read_mds_table_medium_info_list(common::ObIAllocator &, oceanbase::storage::ObTabletDumpedMediumInfo &) const, (*this=|threshold=10.00ms|start at 09:23:04.416|5188=0us|5190=0us|5199=0us|total=93.33ms) [2025-03-27 09:23:04.614132] WDIAG [MDS] ~ObOccamTimeGuard (ob_occam_time_guard.h:269) [2762][T1_MergeLoop][T1][YCEE7F000001-00063092D09FFAB2-0-0] [lt=27][errcode=0] cost too much time:mds_dump_node.h:int oceanbase::storage::mds::MdsDumpNode::init(const uint8_t, const uint8_t, const UserMdsNode &, oceanbase::common::ObIAllocator &) [K = oceanbase::compaction::ObMediumCompactionInfoKey, V = oceanbase::compac..., (*this=|threshold=1.00ms|start at 09:23:04.512|196=1us|215=102.08ms|total=102.12ms) [2025-03-27 09:23:04.614240] WDIAG [MDS] ~ObOccamTimeGuard (ob_occam_time_guard.h:269) [2762][T1_MergeLoop][T1][YCEE7F000001-00063092D09FFAB2-0-0] [lt=95][errcode=0] cost too much time:mds_row.ipp:auto oceanbase::storage::mds::MdsRow::scan_dump_node_from_tail_to_head((lambda at /home/jenkins/agent/workspace/rpm-oceanb..., (*this=|threshold=5.00ms|start at 09:23:04.512|505=0us|507=102.18ms|total=102.21ms) [2025-03-27 09:23:04.614282] WDIAG [MDS] ~ObOccamTimeGuard (ob_occam_time_guard.h:269) [2762][T1_MergeLoop][T1][YCEE7F000001-00063092D09FFAB2-0-0] [lt=37][errcode=0] cost too much time:mds_row.ipp:int oceanbase::storage::mds::MdsRow::scan_dump_node_from_tail_to_head(DUMP_OP &&, const uint8_t, const uint8_t, oceanbase:..., (*this=|threshold=5.00ms|start at 09:23:04.512|481=0us|total=102.24ms) [2025-03-27 09:23:04.614315] WDIAG [MDS] ~ObOccamTimeGuard (ob_occam_time_guard.h:269) [2762][T1_MergeLoop][T1][YCEE7F000001-00063092D09FFAB2-0-0] [lt=28][errcode=0] cost too much time:mds_unit.ipp:auto oceanbase::storage::mds::MdsUnit::scan_KV_row((lambda at /home/jenkins/agent/workspace/rpm-oceanbase-ce-4.2.1.0-bp-7..., (*this=|threshold=10.00ms|start at 09:23:04.512|484=0us|491=1us|total=102.28ms) [2025-03-27 09:23:04.614389] WDIAG [MDS] ~ObOccamTimeGuard (ob_occam_time_guard.h:269) [2762][T1_MergeLoop][T1][YCEE7F000001-00063092D09FFAB2-0-0] [lt=64][errcode=0] cost too much time:mds_unit.ipp:int oceanbase::storage::mds::MdsUnit::scan_KV_row(DUMP_OP &&, share::SCN &, const uint8_t, const uint8_t, const bool) con..., (*this=|threshold=10.00ms|start at 09:23:04.512|476=0us|total=102.36ms) [2025-03-27 09:23:04.614443] WDIAG [MDS] ~ObOccamTimeGuard (ob_occam_time_guard.h:269) [2762][T1_MergeLoop][T1][YCEE7F000001-00063092D09FFAB2-0-0] [lt=46][errcode=0] cost too much time:mds_table_impl.ipp:int oceanbase::storage::mds::MdsTableImpl, oceanbase::storage::m..., (*this=|threshold=100.00ms|start at 09:23:04.512|1105=0us|1124=0us|total=102.41ms) [2025-03-27 09:23:04.614480] WDIAG [MDS] ~ObOccamTimeGuard (ob_occam_time_guard.h:269) [2762][T1_MergeLoop][T1][YCEE7F000001-00063092D09FFAB2-0-0] [lt=29][errcode=0] cost too much time:mds_table_impl.ipp:virtual int oceanbase::storage::mds::MdsTableImpl, oceanbase::st..., (*this=|threshold=100.00ms|start at 09:23:04.512|854=0us|861=0us|total=102.45ms) [2025-03-27 09:23:04.614510] WDIAG [STORAGE] ~ObOccamTimeGuard (ob_occam_time_guard.h:269) [2762][T1_MergeLoop][T1][YCEE7F000001-00063092D09FFAB2-0-0] [lt=28][errcode=0] cost too much time:ob_tablet.cpp:int oceanbase::storage::ObTablet::read_mds_table_medium_info_list(common::ObIAllocator &, oceanbase::storage::ObTabletDumpedMediumInfo &) const, (*this=|threshold=10.00ms|start at 09:23:04.512|5188=0us|5190=0us|5199=0us|total=102.48ms) [2025-03-27 09:23:04.621878] WDIAG [MDS] ~ObOccamTimeGuard (ob_occam_time_guard.h:269) [2762][T1_MergeLoop][T1][YCEE7F000001-00063092D09FFAB2-0-0] [lt=26][errcode=0] cost too much time:mds_dump_node.cpp:void oceanbase::storage::mds::MdsDumpNode::reset(), (*this=|threshold=1.00ms|start at 09:23:04.620|total=1.08ms) [2025-03-27 09:23:33.733904] WDIAG [MDS] ~ObOccamTimeGuard (ob_occam_time_guard.h:269) [2597][T1_Occam][T1][YCEE7F000001-00063092D32FFD1E-0-0] [lt=14617][errcode=0] cost too much time:mds_tenant_service.cpp:int oceanbase::storage::mds::ObTenantMdsTimer::process_with_tablet_(oceanbase::storage::ObTablet &), (*this=|threshold=10.00ms|start at 09:23:33.614|414=1us|416=0us|418=1us|total=119.57ms) [2025-03-27 09:23:48.522954] WDIAG [MDS] ~ObOccamTimeGuard (ob_occam_time_guard.h:269) [2597][T1_Occam][T1][YCEE7F000001-00063092D32FFD2A-0-0] [lt=74][errcode=0] cost too much time:mds_table_handler.cpp:int oceanbase::storage::mds::ObMdsTableHandler::try_release_nodes_below(const share::SCN &), (*this=|threshold=5.00ms|start at 09:23:48.505|145=0us|149=1us|total=17.00ms) [2025-03-27 09:23:48.522989] WDIAG [MDS] ~ObOccamTimeGuard (ob_occam_time_guard.h:269) [2597][T1_Occam][T1][YCEE7F000001-00063092D32FFD2A-0-0] [lt=33][errcode=0] cost too much time:mds_tenant_service.cpp:int oceanbase::storage::mds::ObTenantMdsTimer::try_recycle_mds_table_(oceanbase::storage::ObTablet &, const share::SCN &), (*this=|threshold=5.00ms|start at 09:23:48.505|468=0us|total=17.01ms) [2025-03-27 09:23:48.522999] WDIAG [MDS] ~ObOccamTimeGuard (ob_occam_time_guard.h:269) [2597][T1_Occam][T1][YCEE7F000001-00063092D32FFD2A-0-0] [lt=7][errcode=0] cost too much time:mds_tenant_service.cpp:int oceanbase::storage::mds::ObTenantMdsTimer::process_with_tablet_(oceanbase::storage::ObTablet &), (*this=|threshold=10.00ms|start at 09:23:48.505|414=0us|416=0us|418=17.02ms|total=17.02ms) [2025-03-27 09:24:09.258386] WDIAG [MDS] ~ObOccamTimeGuard (ob_occam_time_guard.h:269) [2768][T1_TransferServ][T1][YCEE7F000001-0006309191DFFE79-0-0] [lt=1][errcode=0] cost too much time:mds_table_base.cpp:int oceanbase::storage::mds::MdsTableBase::advance_state_to(oceanbase::storage::mds::MdsTableBase::State) const, (*this=|threshold=1.00ms|start at 09:24:09.254|total=4.07ms) [2025-03-27 09:24:09.292181] WDIAG [STORAGE] ~ObOccamTimeGuard (ob_occam_time_guard.h:269) [2768][T1_TransferServ][T1][YCEE7F000001-0006309191DFFE79-0-0] [lt=62][errcode=0] cost too much time:ob_tablet_create_delete_helper.cpp:static int oceanbase::storage::ObTabletCreateDeleteHelper::get_tablet(const oceanbase::storage::ObTabletMapKey &, oceanbase::storage::ObTabletHandle &, const int64_t), (*this=|threshold=10.00ms|start at 09:24:09.270|total=21.99ms)