[admin@10-10-10-59 log]$ grep YB420A92023B-000620411DE480F1-0-0 rootservice.log [2024-08-22 16:34:13.236660] INFO [STORAGE.TABLELOCK] send_rpc_task_ (ob_table_lock_service.cpp:2125) [21868][DDLPQueueTh1][T1004][YB420A92023B-000620411DE480F1-0-0] [lt=8] send table lock rpc(ret=0, ret="OB_SUCCESS", retry_ctx.send_rpc_count_=1, addr="10.10.10.59:2882", request={task_type_:9, lsid_:{id:1}, params_:[{lock_id:{obj_type:5, obj_id:1004}, lock_mode:2, owner_id:{id:0}, op_type:4, is_deadlock_avoid_enabled:true, is_try_lock:false, expired_time:1724316653233751, schema_version:-1}], tx_desc_:{this:0x2baa0c47ab20, tx_id:{txid:47007612}, state:2, addr:"10.10.10.59:2882", tenant_id:1004, session_id:1, assoc_session_id:1, xid:NULL, xa_mode:"", xa_start_addr:"0.0.0.0:0", access_mode:0, tx_consistency_type:0, isolation:1, snapshot_version:{val:18446744073709551615, v:3}, snapshot_scn:0, active_scn:1724315653234651, op_sn:4, alloc_ts:1724315653235706, active_ts:1724315653235706, commit_ts:-1, finish_ts:-1, timeout_us:999997703, lock_timeout_us:-1, expire_ts:1724316653233409, coord_id:{id:-1}, parts:[], exec_info_reap_ts:0, commit_version:{val:18446744073709551615, v:3}, commit_times:0, commit_cb:null, cluster_id:1723522017, cluster_version:17180000519, flags_.SHADOW:false, flags_.INTERRUPTED:false, flags_.BLOCK:false, flags_.REPLICA:false, can_elr:false, cflict_txs:[], abort_cause:0, commit_expire_ts:0, commit_task_.is_registered():false, ref:2}}) [2024-08-22 16:34:13.236926] INFO [STORAGE.TABLELOCK] process_lock_task_ (ob_table_lock_service.cpp:1248) [21868][DDLPQueueTh1][T1004][YB420A92023B-000620411DE480F1-0-0] [lt=40] [table lock] lock_table(ret=0, ctx={is_in_trans_:true, table_id_:18446744073709551615, partition_id_:94149721893248, tablet_list_:[], obj_list_:[{obj_type:5, obj_id:1004}], lock_op_type_:4, origin_timeout_us_:999997643, timeout_us_:999997643, abs_timeout_ts_:1724316653233751, tx_desc_:{this:0x2baa0c47ab20, tx_id:{txid:47007612}, state:2, addr:"10.10.10.59:2882", tenant_id:1004, session_id:1, assoc_session_id:1, xid:NULL, xa_mode:"", xa_start_addr:"0.0.0.0:0", access_mode:0, tx_consistency_type:0, isolation:1, snapshot_version:{val:18446744073709551615, v:3}, snapshot_scn:0, active_scn:1724315653234651, op_sn:4, alloc_ts:1724315653235706, active_ts:1724315653235706, commit_ts:-1, finish_ts:-1, timeout_us:999997703, lock_timeout_us:-1, expire_ts:1724316653233409, coord_id:{id:-1}, parts:[{id:{id:1}, addr:"10.10.10.59:2882", epoch:21673519114103296, first_scn:1724315653236762, last_scn:1724315653236762, last_touch_ts:1}], exec_info_reap_ts:0, commit_version:{val:18446744073709551615, v:3}, commit_times:0, commit_cb:null, cluster_id:1723522017, cluster_version:17180000519, flags_.SHADOW:false, flags_.INTERRUPTED:false, flags_.BLOCK:false, flags_.REPLICA:false, can_elr:false, cflict_txs:[], abort_cause:0, commit_expire_ts:0, commit_task_.is_registered():false, ref:2}, tx_param_:{cluster_id:1723522017, timeout_us:86400000000, lock_timeout_us:-1, access_mode:0, isolation:1}, current_savepoint_:0, need_rollback_ls_:[], schema_version_:-1, tx_is_killed_:false, is_from_sql_:false, ret_code_before_end_stmt_or_tx_:0, stmt_savepoint_:0}, lock_mode=2, lock_owner={id:0}) [2024-08-22 16:34:13.236984] INFO [RS] add_lock_object_by_database_name_ (ob_ddl_helper.cpp:473) [21868][DDLPQueueTh1][T0][YB420A92023B-000620411DE480F1-0-0] [lt=47] add lock object by database name(ret=0, ret="OB_SUCCESS", database_name=test, lock_mode=2, lock_obj_id=5763478079148079143) [2024-08-22 16:34:13.237000] INFO [RS] lock_objects_in_map_ (ob_ddl_helper.cpp:440) [21868][DDLPQueueTh1][T0][YB420A92023B-000620411DE480F1-0-0] [lt=9] try lock object(ret=0, ret="OB_SUCCESS", lock_arg={ObLockRequest:{owner_id:{id:0}, lock_mode:2, op_type:4, timeout_us:999996747}, obj_type:9, obj_id:5763478079148079143}) [2024-08-22 16:34:13.237501] INFO [STORAGE.TABLELOCK] send_rpc_task_ (ob_table_lock_service.cpp:2125) [21868][DDLPQueueTh1][T1004][YB420A92023B-000620411DE480F1-0-0] [lt=12] send table lock rpc(ret=0, ret="OB_SUCCESS", retry_ctx.send_rpc_count_=1, addr="10.10.10.59:2882", request={task_type_:9, lsid_:{id:1}, params_:[{lock_id:{obj_type:9, obj_id:5763478079148079143}, lock_mode:2, owner_id:{id:0}, op_type:4, is_deadlock_avoid_enabled:true, is_try_lock:false, expired_time:1724316653233765, schema_version:-1}], tx_desc_:{this:0x2baa0c47ab20, tx_id:{txid:47007612}, state:2, addr:"10.10.10.59:2882", tenant_id:1004, session_id:1, assoc_session_id:1, xid:NULL, xa_mode:"", xa_start_addr:"0.0.0.0:0", access_mode:0, tx_consistency_type:0, isolation:1, snapshot_version:{val:18446744073709551615, v:3}, snapshot_scn:0, active_scn:1724315653234651, op_sn:6, alloc_ts:1724315653235706, active_ts:1724315653235706, commit_ts:-1, finish_ts:-1, timeout_us:999997703, lock_timeout_us:-1, expire_ts:1724316653233409, coord_id:{id:-1}, parts:[{id:{id:1}, addr:"10.10.10.59:2882", epoch:21673519114103296, first_scn:1724315653236762, last_scn:1724315653236762, last_touch_ts:1}], exec_info_reap_ts:0, commit_version:{val:18446744073709551615, v:3}, commit_times:0, commit_cb:null, cluster_id:1723522017, cluster_version:17180000519, flags_.SHADOW:false, flags_.INTERRUPTED:false, flags_.BLOCK:false, flags_.REPLICA:false, can_elr:false, cflict_txs:[], abort_cause:0, commit_expire_ts:0, commit_task_.is_registered():false, ref:2}}) [2024-08-22 16:34:13.237666] INFO [STORAGE.TABLELOCK] process_lock_task_ (ob_table_lock_service.cpp:1248) [21868][DDLPQueueTh1][T1004][YB420A92023B-000620411DE480F1-0-0] [lt=32] [table lock] lock_table(ret=0, ctx={is_in_trans_:true, table_id_:18446744073709551615, partition_id_:0, tablet_list_:[], obj_list_:[{obj_type:9, obj_id:5763478079148079143}], lock_op_type_:4, origin_timeout_us_:999996747, timeout_us_:999996747, abs_timeout_ts_:1724316653233765, tx_desc_:{this:0x2baa0c47ab20, tx_id:{txid:47007612}, state:2, addr:"10.10.10.59:2882", tenant_id:1004, session_id:1, assoc_session_id:1, xid:NULL, xa_mode:"", xa_start_addr:"0.0.0.0:0", access_mode:0, tx_consistency_type:0, isolation:1, snapshot_version:{val:18446744073709551615, v:3}, snapshot_scn:0, active_scn:1724315653234651, op_sn:6, alloc_ts:1724315653235706, active_ts:1724315653235706, commit_ts:-1, finish_ts:-1, timeout_us:999997703, lock_timeout_us:-1, expire_ts:1724316653233409, coord_id:{id:-1}, parts:[{id:{id:1}, addr:"10.10.10.59:2882", epoch:21673519114103296, first_scn:1724315653236762, last_scn:1724315653236767, last_touch_ts:1}], exec_info_reap_ts:0, commit_version:{val:18446744073709551615, v:3}, commit_times:0, commit_cb:null, cluster_id:1723522017, cluster_version:17180000519, flags_.SHADOW:false, flags_.INTERRUPTED:false, flags_.BLOCK:false, flags_.REPLICA:false, can_elr:false, cflict_txs:[], abort_cause:0, commit_expire_ts:0, commit_task_.is_registered():false, ref:2}, tx_param_:{cluster_id:1723522017, timeout_us:86400000000, lock_timeout_us:-1, access_mode:0, isolation:1}, current_savepoint_:0, need_rollback_ls_:[], schema_version_:-1, tx_is_killed_:false, is_from_sql_:false, ret_code_before_end_stmt_or_tx_:0, stmt_savepoint_:0}, lock_mode=2, lock_owner={id:0}) [2024-08-22 16:34:13.237738] INFO [RS] lock_database_by_obj_name_ (ob_create_table_helper.cpp:254) [21868][DDLPQueueTh1][T0][YB420A92023B-000620411DE480F1-0-0] [lt=33] lock databases(ret=0, ret="OB_SUCCESS", tenant_id=1004, cost_ts=756) [2024-08-22 16:34:13.237750] INFO [RS] add_lock_object_by_name_ (ob_ddl_helper.cpp:511) [21868][DDLPQueueTh1][T0][YB420A92023B-000620411DE480F1-0-0] [lt=9] add lock object by name(ret=0, ret="OB_SUCCESS", database_name=test, object_name=mytest, schema_type=5, lock_mode=1, lock_obj_id=12386232741040086195) [2024-08-22 16:34:13.237760] INFO [RS] lock_objects_in_map_ (ob_ddl_helper.cpp:440) [21868][DDLPQueueTh1][T0][YB420A92023B-000620411DE480F1-0-0] [lt=7] try lock object(ret=0, ret="OB_SUCCESS", lock_arg={ObLockRequest:{owner_id:{id:0}, lock_mode:1, op_type:4, timeout_us:999995986}, obj_type:10, obj_id:12386232741040086195}) [2024-08-22 16:34:13.238278] INFO [STORAGE.TABLELOCK] send_rpc_task_ (ob_table_lock_service.cpp:2125) [21868][DDLPQueueTh1][T1004][YB420A92023B-000620411DE480F1-0-0] [lt=10] send table lock rpc(ret=0, ret="OB_SUCCESS", retry_ctx.send_rpc_count_=1, addr="10.10.10.59:2882", request={task_type_:9, lsid_:{id:1}, params_:[{lock_id:{obj_type:10, obj_id:12386232741040086195}, lock_mode:1, owner_id:{id:0}, op_type:4, is_deadlock_avoid_enabled:true, is_try_lock:false, expired_time:1724316653233761, schema_version:-1}], tx_desc_:{this:0x2baa0c47ab20, tx_id:{txid:47007612}, state:2, addr:"10.10.10.59:2882", tenant_id:1004, session_id:1, assoc_session_id:1, xid:NULL, xa_mode:"", xa_start_addr:"0.0.0.0:0", access_mode:0, tx_consistency_type:0, isolation:1, snapshot_version:{val:18446744073709551615, v:3}, snapshot_scn:0, active_scn:1724315653234651, op_sn:8, alloc_ts:1724315653235706, active_ts:1724315653235706, commit_ts:-1, finish_ts:-1, timeout_us:999997703, lock_timeout_us:-1, expire_ts:1724316653233409, coord_id:{id:-1}, parts:[{id:{id:1}, addr:"10.10.10.59:2882", epoch:21673519114103296, first_scn:1724315653236762, last_scn:1724315653236767, last_touch_ts:1}], exec_info_reap_ts:0, commit_version:{val:18446744073709551615, v:3}, commit_times:0, commit_cb:null, cluster_id:1723522017, cluster_version:17180000519, flags_.SHADOW:false, flags_.INTERRUPTED:false, flags_.BLOCK:false, flags_.REPLICA:false, can_elr:false, cflict_txs:[], abort_cause:0, commit_expire_ts:0, commit_task_.is_registered():false, ref:2}}) [2024-08-22 16:34:13.238401] INFO [STORAGE.TABLELOCK] process_lock_task_ (ob_table_lock_service.cpp:1248) [21868][DDLPQueueTh1][T1004][YB420A92023B-000620411DE480F1-0-0] [lt=44] [table lock] lock_table(ret=0, ctx={is_in_trans_:true, table_id_:18446744073709551615, partition_id_:48011197546688, tablet_list_:[], obj_list_:[{obj_type:10, obj_id:12386232741040086195}], lock_op_type_:4, origin_timeout_us_:999995986, timeout_us_:999995986, abs_timeout_ts_:1724316653233761, tx_desc_:{this:0x2baa0c47ab20, tx_id:{txid:47007612}, state:2, addr:"10.10.10.59:2882", tenant_id:1004, session_id:1, assoc_session_id:1, xid:NULL, xa_mode:"", xa_start_addr:"0.0.0.0:0", access_mode:0, tx_consistency_type:0, isolation:1, snapshot_version:{val:18446744073709551615, v:3}, snapshot_scn:0, active_scn:1724315653234651, op_sn:8, alloc_ts:1724315653235706, active_ts:1724315653235706, commit_ts:-1, finish_ts:-1, timeout_us:999997703, lock_timeout_us:-1, expire_ts:1724316653233409, coord_id:{id:-1}, parts:[{id:{id:1}, addr:"10.10.10.59:2882", epoch:21673519114103296, first_scn:1724315653236762, last_scn:1724315653237816, last_touch_ts:1}], exec_info_reap_ts:0, commit_version:{val:18446744073709551615, v:3}, commit_times:0, commit_cb:null, cluster_id:1723522017, cluster_version:17180000519, flags_.SHADOW:false, flags_.INTERRUPTED:false, flags_.BLOCK:false, flags_.REPLICA:false, can_elr:false, cflict_txs:[], abort_cause:0, commit_expire_ts:0, commit_task_.is_registered():false, ref:2}, tx_param_:{cluster_id:1723522017, timeout_us:86400000000, lock_timeout_us:-1, access_mode:0, isolation:1}, current_savepoint_:0, need_rollback_ls_:[], schema_version_:-1, tx_is_killed_:false, is_from_sql_:false, ret_code_before_end_stmt_or_tx_:0, stmt_savepoint_:0}, lock_mode=1, lock_owner={id:0}) [2024-08-22 16:34:13.238467] INFO [RS] lock_objects_by_name_ (ob_create_table_helper.cpp:352) [21868][DDLPQueueTh1][T0][YB420A92023B-000620411DE480F1-0-0] [lt=29] lock objects by name(ret=0, ret="OB_SUCCESS", tenant_id=1004, cost_ts=720) [2024-08-22 16:34:13.239282] INFO [SHARE.SCHEMA] retrieve_database_schema (ob_schema_retrieve_utils.ipp:2838) [21868][DDLPQueueTh1][T0][YB420A92023B-000620411DE480F1-0-0] [lt=9] retrieve database schema succeed(schema={tenant_id:1004, database_id:500001, schema_version:1723549589593112, database_name:"test", charset_type:2, collation_type:"utf8mb4_general_ci", name_case_mode:-1, comment:"test schema", read_only:false, default_tablegroup_id:18446744073709551615, default_tablegroup_name:"", in_recyclebin:false}) [2024-08-22 16:34:13.239302] INFO [SHARE.SCHEMA] retrieve_database_schema (ob_schema_retrieve_utils.ipp:2838) [21868][DDLPQueueTh1][T0][YB420A92023B-000620411DE480F1-0-0] [lt=18] retrieve all database schemas succeed(schema_array=[{tenant_id:1004, database_id:500001, schema_version:1723549589593112, database_name:"test", charset_type:2, collation_type:"utf8mb4_general_ci", name_case_mode:-1, comment:"test schema", read_only:false, default_tablegroup_id:18446744073709551615, default_tablegroup_name:"", in_recyclebin:false}]) [2024-08-22 16:34:13.239334] INFO [SHARE.SCHEMA] get_batch_databases (ob_schema_service_sql_impl.cpp:3365) [21868][DDLPQueueTh1][T0][YB420A92023B-000620411DE480F1-0-0] [lt=7] get batch database schema finish(schema_version=9223372036854775807, ret=0) [2024-08-22 16:34:13.239707] INFO [SHARE.SCHEMA] get_synonym_id (ob_latest_schema_guard.cpp:291) [21868][DDLPQueueTh1][T0][YB420A92023B-000620411DE480F1-0-0] [lt=10] synonym not exist(ret=0, ret="OB_SUCCESS", tenant_id=1004, database_id=500001, synonym_name=mytest) [2024-08-22 16:34:13.240135] INFO [SHARE.SCHEMA] get_table_id (ob_latest_schema_guard.cpp:242) [21868][DDLPQueueTh1][T0][YB420A92023B-000620411DE480F1-0-0] [lt=14] table not exist(ret=0, ret="OB_SUCCESS", tenant_id=1004, database_id=500001, session_id=0, table_name=mytest) [2024-08-22 16:34:13.240563] INFO [SHARE.SCHEMA] retrieve_tenant_schema (ob_schema_retrieve_utils.ipp:2661) [21868][DDLPQueueTh1][T0][YB420A92023B-000620411DE480F1-0-0] [lt=16] retrieve tenant schema(tenant_schema={tenant_id:1004, schema_version:1723549590517704, tenant_name:"obuser", zone_list:["zone1", "zone2", "zone3"], primary_zone:"RANDOM", charset_type:0, locked:false, comment:"", name_case_mode:-1, read_only:false, locality_str:"FULL{1}@zone1, FULL{1}@zone2, FULL{1}@zone3", zone_replica_attr_array:[{replica_attr_set:{full_replica_attr_array:[{num:1, memstore_percent:100}], logonly_replica_attr_array:[], readonly_replica_attr_array:[], encryption_logonly_replica_attr_array:[]}, zone_set:["zone1"], zone:"zone1"}, {replica_attr_set:{full_replica_attr_array:[{num:1, memstore_percent:100}], logonly_replica_attr_array:[], readonly_replica_attr_array:[], encryption_logonly_replica_attr_array:[]}, zone_set:["zone2"], zone:"zone2"}, {replica_attr_set:{full_replica_attr_array:[{num:1, memstore_percent:100}], logonly_replica_attr_array:[], readonly_replica_attr_array:[], encryption_logonly_replica_attr_array:[]}, zone_set:["zone3"], zone:"zone3"}], primary_zone_array:[], previous_locality_str:"", default_tablegroup_id:18446744073709551615, default_tablegroup_name:"", compatibility_mode:0, drop_tenant_time:-1, status:0, in_recyclebin:false, arbitration_service_status:{status:3}}, is_deleted=false) [2024-08-22 16:34:13.240639] INFO [SHARE.SCHEMA] get_batch_tenants (ob_schema_service_sql_impl.cpp:3289) [21868][DDLPQueueTh1][T0][YB420A92023B-000620411DE480F1-0-0] [lt=27] get batch tenants info finish(schema_version=9223372036854775807, ret=0) [2024-08-22 16:34:13.240653] INFO [RS] prefetch_schemas_ (ob_create_table_helper.cpp:714) [21868][DDLPQueueTh1][T0][YB420A92023B-000620411DE480F1-0-0] [lt=10] prefetch schemas(ret=0, ret="OB_SUCCESS", tenant_id=1004, cost_ts=2175) [2024-08-22 16:34:13.240659] INFO [RS] add_lock_object_by_id_ (ob_ddl_helper.cpp:535) [21868][DDLPQueueTh1][T0][YB420A92023B-000620411DE480F1-0-0] [lt=5] add lock object by id(ret=0, ret="OB_SUCCESS", lock_obj_id=200010, schema_type=2, lock_mode=2) [2024-08-22 16:34:13.241036] INFO [SHARE.SCHEMA] get_mock_fk_parent_table_id (ob_latest_schema_guard.cpp:266) [21868][DDLPQueueTh1][T0][YB420A92023B-000620411DE480F1-0-0] [lt=9] mock parent table not exist(ret=0, ret="OB_SUCCESS", tenant_id=1004, database_id=500001, table_name=mytest) [2024-08-22 16:34:13.241056] INFO [RS] lock_objects_in_map_ (ob_ddl_helper.cpp:440) [21868][DDLPQueueTh1][T0][YB420A92023B-000620411DE480F1-0-0] [lt=14] try lock object(ret=0, ret="OB_SUCCESS", lock_arg={ObLockRequest:{owner_id:{id:0}, lock_mode:2, op_type:4, timeout_us:999992690}, obj_type:3, obj_id:200010}) [2024-08-22 16:34:13.241520] INFO [STORAGE.TABLELOCK] send_rpc_task_ (ob_table_lock_service.cpp:2125) [21868][DDLPQueueTh1][T1004][YB420A92023B-000620411DE480F1-0-0] [lt=12] send table lock rpc(ret=0, ret="OB_SUCCESS", retry_ctx.send_rpc_count_=1, addr="10.10.10.59:2882", request={task_type_:9, lsid_:{id:1}, params_:[{lock_id:{obj_type:3, obj_id:200010}, lock_mode:2, owner_id:{id:0}, op_type:4, is_deadlock_avoid_enabled:true, is_try_lock:false, expired_time:1724316653233761, schema_version:-1}], tx_desc_:{this:0x2baa0c47ab20, tx_id:{txid:47007612}, state:2, addr:"10.10.10.59:2882", tenant_id:1004, session_id:1, assoc_session_id:1, xid:NULL, xa_mode:"", xa_start_addr:"0.0.0.0:0", access_mode:0, tx_consistency_type:0, isolation:1, snapshot_version:{val:18446744073709551615, v:3}, snapshot_scn:0, active_scn:1724315653234651, op_sn:10, alloc_ts:1724315653235706, active_ts:1724315653235706, commit_ts:-1, finish_ts:-1, timeout_us:999997703, lock_timeout_us:-1, expire_ts:1724316653233409, coord_id:{id:-1}, parts:[{id:{id:1}, addr:"10.10.10.59:2882", epoch:21673519114103296, first_scn:1724315653236762, last_scn:1724315653237816, last_touch_ts:1}], exec_info_reap_ts:0, commit_version:{val:18446744073709551615, v:3}, commit_times:0, commit_cb:null, cluster_id:1723522017, cluster_version:17180000519, flags_.SHADOW:false, flags_.INTERRUPTED:false, flags_.BLOCK:false, flags_.REPLICA:false, can_elr:false, cflict_txs:[], abort_cause:0, commit_expire_ts:0, commit_task_.is_registered():false, ref:2}}) [2024-08-22 16:34:13.241662] INFO [STORAGE.TABLELOCK] process_lock_task_ (ob_table_lock_service.cpp:1248) [21868][DDLPQueueTh1][T1004][YB420A92023B-000620411DE480F1-0-0] [lt=35] [table lock] lock_table(ret=0, ctx={is_in_trans_:true, table_id_:18446744073709551615, partition_id_:18446744073709551615, tablet_list_:[], obj_list_:[{obj_type:3, obj_id:200010}], lock_op_type_:4, origin_timeout_us_:999992690, timeout_us_:999992690, abs_timeout_ts_:1724316653233761, tx_desc_:{this:0x2baa0c47ab20, tx_id:{txid:47007612}, state:2, addr:"10.10.10.59:2882", tenant_id:1004, session_id:1, assoc_session_id:1, xid:NULL, xa_mode:"", xa_start_addr:"0.0.0.0:0", access_mode:0, tx_consistency_type:0, isolation:1, snapshot_version:{val:18446744073709551615, v:3}, snapshot_scn:0, active_scn:1724315653234651, op_sn:10, alloc_ts:1724315653235706, active_ts:1724315653235706, commit_ts:-1, finish_ts:-1, timeout_us:999997703, lock_timeout_us:-1, expire_ts:1724316653233409, coord_id:{id:-1}, parts:[{id:{id:1}, addr:"10.10.10.59:2882", epoch:21673519114103296, first_scn:1724315653236762, last_scn:1724315653240986, last_touch_ts:1}], exec_info_reap_ts:0, commit_version:{val:18446744073709551615, v:3}, commit_times:0, commit_cb:null, cluster_id:1723522017, cluster_version:17180000519, flags_.SHADOW:false, flags_.INTERRUPTED:false, flags_.BLOCK:false, flags_.REPLICA:false, can_elr:false, cflict_txs:[], abort_cause:0, commit_expire_ts:0, commit_task_.is_registered():false, ref:2}, tx_param_:{cluster_id:1723522017, timeout_us:86400000000, lock_timeout_us:-1, access_mode:0, isolation:1}, current_savepoint_:0, need_rollback_ls_:[], schema_version_:-1, tx_is_killed_:false, is_from_sql_:false, ret_code_before_end_stmt_or_tx_:0, stmt_savepoint_:0}, lock_mode=2, lock_owner={id:0}) [2024-08-22 16:34:13.241728] INFO [RS] lock_objects_by_id_ (ob_create_table_helper.cpp:467) [21868][DDLPQueueTh1][T0][YB420A92023B-000620411DE480F1-0-0] [lt=28] lock objects by id(ret=0, ret="OB_SUCCESS", tenant_id=1004, cost_ts=1070) [2024-08-22 16:34:13.241770] INFO [RS] post_lock_objects_by_id_ (ob_create_table_helper.cpp:526) [21868][DDLPQueueTh1][T0][YB420A92023B-000620411DE480F1-0-0] [lt=9] lock objects by id in post(ret=0, ret="OB_SUCCESS", tenant_id=1004, cost_ts=33) [2024-08-22 16:34:13.241776] INFO [RS] check_ddl_conflict_ (ob_create_table_helper.cpp:694) [21868][DDLPQueueTh1][T0][YB420A92023B-000620411DE480F1-0-0] [lt=5] check ddl confict(ret=0, ret="OB_SUCCESS", tenant_id=1004, cost_ts=1) [2024-08-22 16:34:13.242227] INFO [STORAGE] ~ObStorageTableGuard (ob_storage_table_guard.cpp:153) [21868][DDLPQueueTh1][T1004][YB420A92023B-000620411DE480F1-0-0] [lt=3] throttle statics(need_speed_limit=false, last_throttle_status=false, last_print_log_time=1724315618128892, stat={total_throttle_time_us:0, total_skip_throttle_time_us:0, last_log_timestamp:1724315653242037, last_throttle_status:false, 0=0, 1=0, 2=0, 3=0}) [2024-08-22 16:34:13.242266] INFO [SHARE] fetch_new_max_id (ob_max_id_fetcher.cpp:274) [21868][DDLPQueueTh1][T0][YB420A92023B-000620411DE480F1-0-0] [lt=16] fetch_new_max_id(ret=0, ret="OB_SUCCESS", size=1, tenant_id=1004, fetch_id=500024, max_id_type=12, fetch_max_id_type=12, id=18446744073709551615, initial=18446744073709551615) [2024-08-22 16:34:13.245984] INFO [SHARE.SCHEMA] retrieve_audit_schema (ob_schema_retrieve_utils.ipp:2846) [21868][DDLPQueueTh1][T0][YB420A92023B-000620411DE480F1-0-0] [lt=11] retrieve all audit schemas succeed(schema_array=[]) [2024-08-22 16:34:13.246024] INFO [SHARE.SCHEMA] gen_batch_new_schema_versions (ob_schema_service_sql_impl.cpp:508) [21868][DDLPQueueTh1][T0][YB420A92023B-000620411DE480F1-0-0] [lt=13] batch gen schema version(tenant_id=1004, version_cnt=2, start_schema_version=1724315653246016, end_schema_version=1724315653246024) [2024-08-22 16:34:13.246037] INFO [RS] create_task_and_assign_schema_version (ob_ddl_trans_controller.cpp:238) [21868][DDLPQueueTh1][T0][YB420A92023B-000620411DE480F1-0-0] [lt=11] create_task_and_assign_schema_version(ret=0, ret="OB_SUCCESS", tenant_id=1004, task_id=1724315653246024) [2024-08-22 16:34:13.246043] INFO [SHARE.SCHEMA] gen_new_schema_version (ob_schema_service_sql_impl.cpp:469) [21868][DDLPQueueTh1][T0][YB420A92023B-000620411DE480F1-0-0] [lt=4] new schema version(tenant_id=1004, schema_version=1724315653246016) [2024-08-22 16:34:13.246452] INFO [SHARE.SCHEMA] create_table (ob_table_sql_service.cpp:2408) [21868][DDLPQueueTh1][T0][YB420A92023B-000620411DE480F1-0-0] [lt=3] add_table cost: (cost_usec=404) [2024-08-22 16:34:13.249163] WDIAG [SQL.PC] common_free (ob_lib_cache_object_manager.cpp:141) [21868][DDLPQueueTh1][T1004][YB420A92023B-000620411DE480F1-0-0] [lt=12][errcode=0] set logical del time(cache_obj->get_logical_del_time()=93734386668, cache_obj->added_lc()=false, cache_obj->get_object_id()=321, cache_obj->get_tenant_id()=1004, lbt()="0x1203501c 0xa579180 0x4c450b1 0x4c41a98 0x4fc5dcc 0x504b057 0x506c1ac 0x9fd38bb 0x4fd1ed4 0x4febff4 0x111dc063 0x111fe11f 0x85acdf3 0x897376d 0xa12aac9 0x4fed16b 0x12d8e05f 0x12d8f2ca 0x12d8f849 0x1230b621 0x123077f7 0x2ba9611faea5 0x2ba96150db0d") [2024-08-22 16:34:13.251117] WDIAG [SQL.PC] common_free (ob_lib_cache_object_manager.cpp:141) [21868][DDLPQueueTh1][T1004][YB420A92023B-000620411DE480F1-0-0] [lt=20][errcode=0] set logical del time(cache_obj->get_logical_del_time()=93734388616, cache_obj->added_lc()=false, cache_obj->get_object_id()=322, cache_obj->get_tenant_id()=1004, lbt()="0x1203501c 0xa579180 0x4c450b1 0x4c41a98 0x4fc5dcc 0x504b057 0x506c1ac 0x9fd38bb 0x4fd1ed4 0x4febff4 0x111dc0af 0x111fe11f 0x85acdf3 0x897376d 0xa12aac9 0x4fed16b 0x12d8e05f 0x12d8f2ca 0x12d8f849 0x1230b621 0x123077f7 0x2ba9611faea5 0x2ba96150db0d") [2024-08-22 16:34:13.251158] INFO [SHARE.SCHEMA] create_table (ob_table_sql_service.cpp:2417) [21868][DDLPQueueTh1][T0][YB420A92023B-000620411DE480F1-0-0] [lt=17] add_column cost: (cost_usec=4706) [2024-08-22 16:34:13.251168] INFO [SHARE.SCHEMA] create_table (ob_table_sql_service.cpp:2425) [21868][DDLPQueueTh1][T0][YB420A92023B-000620411DE480F1-0-0] [lt=8] add part info cost: (cost_usec=11) [2024-08-22 16:34:13.251319] INFO [SHARE.SCHEMA] create_table (ob_table_sql_service.cpp:2464) [21868][DDLPQueueTh1][T0][YB420A92023B-000620411DE480F1-0-0] [lt=4] log_operation cost: (cost_usec=150) [2024-08-22 16:34:13.251842] INFO [SHARE] alloc_ls_for_normal_table_tablet (ob_balance_group_ls_stat_operator.cpp:1594) [21868][DDLPQueueTh1][T0][YB420A92023B-000620411DE480F1-0-0] [lt=14] alloc ls for normal table tablet(tenant_id=1004, table_id=500025) [2024-08-22 16:34:13.251857] INFO [SHARE] alloc_tablet_for_non_partitioned_balance_group_by_cache_ (ob_balance_group_ls_stat_operator.cpp:1084) [21868][DDLPQueueTh1][T0][YB420A92023B-000620411DE480F1-0-0] [lt=13] alloc tablet for non partitioned balance group by cache(tenant_id=1004, table_id=500025) [2024-08-22 16:34:13.252245] INFO [SHARE.SCHEMA] should_reload_cache_ (ob_tablet_balance_allocator.cpp:71) [21868][DDLPQueueTh1][T0][YB420A92023B-000620411DE480F1-0-0] [lt=4] [NON PARTITIONED TABLET CACHE] failure/non parallel ddl occur or cache is empty, should be reloaded(tenant_id=1004) [2024-08-22 16:34:13.252259] INFO [SHARE.SCHEMA] inner_reset_cache_ (ob_tablet_balance_allocator.cpp:51) [21868][DDLPQueueTh1][T0][YB420A92023B-000620411DE480F1-0-0] [lt=13] [NON PARTITIONED TABLET CACHE] reset cache(tenant_id=1004) [2024-08-22 16:34:13.252683] INFO [SHARE.SCHEMA] reload_cache_ (ob_tablet_balance_allocator.cpp:180) [21868][DDLPQueueTh1][T0][YB420A92023B-000620411DE480F1-0-0] [lt=5] [NON PARTITIONED TABLET CACHE] reload cache(ret=0, ret="OB_SUCCESS", tenant_id=1004, cost=419) [2024-08-22 16:34:13.252703] INFO [SHARE] generate_inc_sql_ (ob_balance_group_ls_stat_operator.cpp:437) [21868][DDLPQueueTh1][T0][YB420A92023B-000620411DE480F1-0-0] [lt=15] balance group ls inc sql(sql_string=INSERT INTO __all_balance_group_ls_stat (tenant_id, balance_group_id_high, balance_group_id_low, ls_id, tablet_group_count, balance_group_name) VALUES (1004, 0, 0, 1002, 1, 'NON_PART_TABLE') ON DUPLICATE KEY UPDATE tablet_group_count = tablet_group_count + 1, balance_group_name = 'NON_PART_TABLE') [2024-08-22 16:34:13.253118] INFO [STORAGE.TRANS] rollback (ob_memtable_context.cpp:847) [21868][DDLPQueueTh1][T1003][YB420A92023B-000620411DE480F1-0-0] [lt=6] memtable handle rollback to successfuly(from_seq_no=1724315653251343, to_seq_no=1724315653251341, *this={ObIMvccCtx={alloc_type=0 ctx_descriptor=0 min_table_version=1723549578897328 max_table_version=1723549578897328 trans_version={val:4611686018427387903, v:0} commit_version={val:0, v:0} lock_wait_start_ts=0 replay_compact_version={val:0, v:0}} end_code=0 tx_status=0 is_readonly=false ref=0 trans_id={txid:47071400} ls_id=1 callback_alloc_count=0 callback_free_count=0 checksum=0 tmp_checksum=0 checksum_scn={val:0, v:0} redo_filled_count=0 redo_sync_succ_count=0 redo_sync_fail_count=0 main_list_length=0 unsynced_cnt=0 unsubmitted_cnt_=0 cb_statistics:[main=1, slave=0, merge=0, tx_end=0, rollback_to=1, fast_commit=0, remove_memtable=0]}) [2024-08-22 16:34:13.255088] INFO [STORAGE.TABLELOCK] send_rpc_task_ (ob_table_lock_service.cpp:2125) [21868][DDLPQueueTh1][T1004][YB420A92023B-000620411DE480F1-0-0] [lt=20] send table lock rpc(ret=0, ret="OB_SUCCESS", retry_ctx.send_rpc_count_=1, addr="10.10.10.59:2882", request={task_type_:9, lsid_:{id:1}, params_:[{lock_id:{obj_type:4, obj_id:1002}, lock_mode:2, owner_id:{id:0}, op_type:4, is_deadlock_avoid_enabled:true, is_try_lock:false, expired_time:1724316653233752, schema_version:-1}], tx_desc_:{this:0x2baa0c47ab20, tx_id:{txid:47007612}, state:2, addr:"10.10.10.59:2882", tenant_id:1004, session_id:1, assoc_session_id:1, xid:NULL, xa_mode:"", xa_start_addr:"0.0.0.0:0", access_mode:0, tx_consistency_type:0, isolation:1, snapshot_version:{val:18446744073709551615, v:3}, snapshot_scn:0, active_scn:1724315653234651, op_sn:18, alloc_ts:1724315653235706, active_ts:1724315653235706, commit_ts:-1, finish_ts:-1, timeout_us:999997703, lock_timeout_us:-1, expire_ts:1724316653233409, coord_id:{id:-1}, parts:[{id:{id:1}, addr:"10.10.10.59:2882", epoch:21673519114103296, first_scn:1724315653236762, last_scn:1724315653251336, last_touch_ts:1724315653245520}], exec_info_reap_ts:1724315653245519, commit_version:{val:18446744073709551615, v:3}, commit_times:0, commit_cb:null, cluster_id:1723522017, cluster_version:17180000519, flags_.SHADOW:false, flags_.INTERRUPTED:false, flags_.BLOCK:false, flags_.REPLICA:false, can_elr:true, cflict_txs:[], abort_cause:0, commit_expire_ts:0, commit_task_.is_registered():false, ref:2}}) [2024-08-22 16:34:13.255217] INFO [STORAGE.TABLELOCK] process_lock_task_ (ob_table_lock_service.cpp:1248) [21868][DDLPQueueTh1][T1004][YB420A92023B-000620411DE480F1-0-0] [lt=39] [table lock] lock_table(ret=0, ctx={is_in_trans_:true, table_id_:18446744073709551615, partition_id_:0, tablet_list_:[], obj_list_:[{obj_type:4, obj_id:1002}], lock_op_type_:4, origin_timeout_us_:999979174, timeout_us_:999979174, abs_timeout_ts_:1724316653233752, tx_desc_:{this:0x2baa0c47ab20, tx_id:{txid:47007612}, state:2, addr:"10.10.10.59:2882", tenant_id:1004, session_id:1, assoc_session_id:1, xid:NULL, xa_mode:"", xa_start_addr:"0.0.0.0:0", access_mode:0, tx_consistency_type:0, isolation:1, snapshot_version:{val:18446744073709551615, v:3}, snapshot_scn:0, active_scn:1724315653234651, op_sn:18, alloc_ts:1724315653235706, active_ts:1724315653235706, commit_ts:-1, finish_ts:-1, timeout_us:999997703, lock_timeout_us:-1, expire_ts:1724316653233409, coord_id:{id:-1}, parts:[{id:{id:1}, addr:"10.10.10.59:2882", epoch:21673519114103296, first_scn:1724315653236762, last_scn:1724315653254429, last_touch_ts:1724315653245520}], exec_info_reap_ts:1724315653245519, commit_version:{val:18446744073709551615, v:3}, commit_times:0, commit_cb:null, cluster_id:1723522017, cluster_version:17180000519, flags_.SHADOW:false, flags_.INTERRUPTED:false, flags_.BLOCK:false, flags_.REPLICA:false, can_elr:true, cflict_txs:[], abort_cause:0, commit_expire_ts:0, commit_task_.is_registered():false, ref:2}, tx_param_:{cluster_id:1723522017, timeout_us:86400000000, lock_timeout_us:-1, access_mode:0, isolation:1}, current_savepoint_:0, need_rollback_ls_:[], schema_version_:-1, tx_is_killed_:false, is_from_sql_:false, ret_code_before_end_stmt_or_tx_:0, stmt_savepoint_:0}, lock_mode=2, lock_owner={id:0}) [2024-08-22 16:34:13.255340] INFO [RS] add_create_tablet_arg (ob_tablet_creator.cpp:270) [21868][DDLPQueueTh1][T0][YB420A92023B-000620411DE480F1-0-0] [lt=31] new log stream(ls_key={id:1002}) [2024-08-22 16:34:13.255577] INFO [RS] add_create_tablets_of_tables_arg_ (ob_table_creator.cpp:329) [21868][DDLPQueueTh1][T0][YB420A92023B-000620411DE480F1-0-0] [lt=10] finish create_tablet_to_table_history(ret=0, ret="OB_SUCCESS", table_schema.get_tenant_id()=1004, table_schema.get_table_id()=500025, cost_ts=141) [2024-08-22 16:34:13.255612] INFO [STORAGE.TRANS] statistics (ob_location_adapter.cpp:72) [21868][DDLPQueueTh1][T1004][YB420A92023B-000620411DE480F1-0-0] [lt=14] location adapter statistics(renew_access=0, total_access=45, error_count=0, renew_rate=0.000000000e+00) [2024-08-22 16:34:13.255709] INFO [STORAGE] init (ob_sstable.cpp:118) [21868][DDLPQueueTh1][T1004][YB420A92023B-000620411DE480F1-0-0] [lt=12] succeeded to init sstable(ret=0, this={ObITable:{this:0x2ba97b44d4f0, key:{tablet_id:{id:200025}, column_group_idx:0, table_type:"MAJOR", scn_range:{start_scn:{val:0, v:0}, end_scn:{val:1724263204457998983, v:0}}}, ref_cnt:0, upper_trans_version:1724263204457998983, timestamp:0}, this:0x2ba97b44d4f0, addr:[0-0-0-3458764685619232768-0](offset=0,size=160,type=3,seq=0), upper_trans_version:1724263204457998983, max_merged_trans_version:1724263204457998983, data_macro_block_count:0, nested_size:2097152, nested_offset:0, contain_uncommitted_row:false, filled_tx_scn:{val:0, v:0}, meta:{basic_meta:{version:1, length:89, row_count_:0, occupy_size_:0, original_size_:0, data_checksum_:0, index_type_:0, rowkey_column_count_:3, column_cnt_:5, data_macro_block_count_:0, data_micro_block_count_:0, use_old_macro_block_count_:0, index_macro_block_count_:0, sstable_format_version_:1, schema_version_:1724315653246016, create_snapshot_version_:0, progressive_merge_round_:1, progressive_merge_step_:0, data_index_tree_height_:0, table_mode_:{table_mode_flag:0, pk_mode:2, table_state_flag:0, view_created_method_flag:0, table_organization_mode:1, auto_increment_mode:0, rowid_mode:0, view_column_filled_flag:0}, upper_trans_version_:1724263204457998983, max_merged_trans_version_:1724263204457998983, recycle_version:0, ddl_scn_:{val:0, v:0}, filled_tx_scn_:{val:0, v:0}, contain_uncommitted_row_:false, status_:4, root_row_store_type:2, compressor_type:1, encrypt_id:0, master_key_id:-1, sstable_logic_seq:0, encrypt_key:"data_size:16, data:00000000000000000000000000000000", latest_row_store_type:1}, column_checksums:0x2baa53798280, column_checksum_count:5, data_root_info:{addr:[0-0-0-0-0](offset=0,size=0,type=0,seq=0), block_data:{buf:null, size:0, extra_buf:null, extra_size:0, type:1}}, macro_info:{macro_meta_info:{addr:[0-0-0-0-0](offset=0,size=0,type=0,seq=0), block_data:{buf:null, size:0, extra_buf:null, extra_size:0, type:1}}, data_block_count:0, other_block_count:0, linked_block_count:0, data_block_ids:null, other_block_ids:null, linked_block_ids:null, entry_id:[-1](ver=0,mode=0,seq=0), is_meta_root:false, nested_offset:0, nested_size:2097152}}, valid_for_reading:true}) [2024-08-22 16:34:13.255771] INFO [STORAGE] init (ob_medium_compaction_mgr.cpp:155) [21868][DDLPQueueTh1][T1004][YB420A92023B-000620411DE480F1-0-0] [lt=59] success to init medium clog recorder(ret=0, ls_id={id:1002}, tablet_id={id:200025}, max_saved_version=0) [2024-08-22 16:34:13.255777] INFO [STORAGE.TRANS] init_storage_recorder (ob_tablet_memtable_mgr.cpp:149) [21868][DDLPQueueTh1][T1004][YB420A92023B-000620411DE480F1-0-0] [lt=5] success to init storage recorder(ret=0, ls_id={id:1002}, tablet_id={id:200025}, max_saved_schema_version=1724315653246016, max_saved_medium_scn=0, compat_mode=0) [2024-08-22 16:34:13.255809] INFO [STORAGE] init_for_first_time_creation (ob_tablet.cpp:258) [21868][DDLPQueueTh1][T1004][YB420A92023B-000620411DE480F1-0-0] [lt=9] succeeded to init tablet for first time creation(ret=0, sstable={ObITable:{this:0x2ba97b44d4f0, key:{tablet_id:{id:200025}, column_group_idx:0, table_type:"MAJOR", scn_range:{start_scn:{val:0, v:0}, end_scn:{val:1724263204457998983, v:0}}}, ref_cnt:0, upper_trans_version:1724263204457998983, timestamp:0}, this:0x2ba97b44d4f0, addr:[0-0-0-3458764685619232768-0](offset=0,size=160,type=3,seq=0), upper_trans_version:1724263204457998983, max_merged_trans_version:1724263204457998983, data_macro_block_count:0, nested_size:2097152, nested_offset:0, contain_uncommitted_row:false, filled_tx_scn:{val:0, v:0}, meta:{basic_meta:{version:1, length:89, row_count_:0, occupy_size_:0, original_size_:0, data_checksum_:0, index_type_:0, rowkey_column_count_:3, column_cnt_:5, data_macro_block_count_:0, data_micro_block_count_:0, use_old_macro_block_count_:0, index_macro_block_count_:0, sstable_format_version_:1, schema_version_:1724315653246016, create_snapshot_version_:0, progressive_merge_round_:1, progressive_merge_step_:0, data_index_tree_height_:0, table_mode_:{table_mode_flag:0, pk_mode:2, table_state_flag:0, view_created_method_flag:0, table_organization_mode:1, auto_increment_mode:0, rowid_mode:0, view_column_filled_flag:0}, upper_trans_version_:1724263204457998983, max_merged_trans_version_:1724263204457998983, recycle_version:0, ddl_scn_:{val:0, v:0}, filled_tx_scn_:{val:0, v:0}, contain_uncommitted_row_:false, status_:4, root_row_store_type:2, compressor_type:1, encrypt_id:0, master_key_id:-1, sstable_logic_seq:0, encrypt_key:"data_size:16, data:00000000000000000000000000000000", latest_row_store_type:1}, column_checksums:0x2baa53798280, column_checksum_count:5, data_root_info:{addr:[0-0-0-0-0](offset=0,size=0,type=0,seq=0), block_data:{buf:null, size:0, extra_buf:null, extra_size:0, type:1}}, macro_info:{macro_meta_info:{addr:[0-0-0-0-0](offset=0,size=0,type=0,seq=0), block_data:{buf:null, size:0, extra_buf:null, extra_size:0, type:1}}, data_block_count:0, other_block_count:0, linked_block_count:0, data_block_ids:null, other_block_ids:null, linked_block_ids:null, entry_id:[-1](ver=0,mode=0,seq=0), is_meta_root:false, nested_offset:0, nested_size:2097152}}, valid_for_reading:true}, *this={this:0x2baa2d16e060, is_inited:true, wash_score:-9223372036854775808, hold_ref_cnt:true, ref_cnt:1, version:2, length:0, tablet_addr:[0-0-0-3458765810900664320-0](offset=0,size=1208,type=3,seq=0), allocator:0x2baa61fb8240, tablet_meta:{version:1, ls_id:{id:1002}, tablet_id:{id:200025}, data_tablet_id:{id:200025}, ref_tablet_id:{id:0}, has_next_tablet:false, create_scn:{val:18446744073709551615, v:3}, start_scn:{val:1, v:0}, clog_checkpoint_scn:{val:1, v:0}, ddl_checkpoint_scn:{val:1, v:0}, snapshot_version:1724263204457998983, multi_version_start:1724263204457998983, compat_mode:0, ha_status:{restore_status:0, data_status:0, expected_status:0, reserved:0}, report_status:{merge_snapshot_version:1724263204457998983, cur_report_version:1724263204457998983, data_checksum:0, row_count:0}, table_store_flag:{with_major_sstable:1}, ddl_start_scn:{val:0, v:0}, ddl_snapshot_version:0, max_sync_storage_schema_version:1724315653246016, max_serialized_medium_scn:0, ddl_execution_id:-1, ddl_data_format_version:0, ddl_commit_scn:{val:0, v:0}, mds_checkpoint_scn:{val:1, v:0}, transfer_info:{ls_id:{id:0}, transfer_start_scn:{val:0, v:0}, transfer_seq:0, has_transfer_table:false}, create_schema_version:1724315653246016}, table_store_addr:{ptr:{this:0x2baa2d16e520, version:257, major_tables:{cnt:1, is_inited:true}, minor_tables:{cnt:0, is_inited:false}, ddl_sstables:{cnt:0, is_inited:false}, meta_major_tables:{cnt:0, is_inited:false}, memtables:{count:0}, is_ready_for_read:true, sstable_arrays:{major_tables:{{i:0, addr:[0-0-0-3458764685619232768-0](offset=0,size=160,type=3,seq=0), type:"MAJOR", tablet_id:{id:200025}, scn_range:{start_scn:{val:0, v:0}, end_scn:{val:1724263204457998983, v:0}}, snapshot_version:1724263204457998983, max_merge_version:1724263204457998983}}, minor_tables:{null}, ddl_sstables:{null}, ddl_mem_sstables:{null}, meta_major_tables:{null}, memtables_:{null}}}, addr:[0-0-0-3458764857417924608-0](offset=0,size=320,type=3,seq=0)}, storage_schema_addr:{ptr:{this:0x2baa2d16e960, storage_schema_version:2, version:0, is_use_bloomfilter:0, column_info_simplified:1, compat_mode:0, table_type:3, index_type:0, index_status:1, row_store_type:1, schema_version:1724315653246016, column_cnt:3, store_column_cnt:3, tablet_size:134217728, pctfree:0, block_size:16384, progressive_merge_round:1, master_key_id:18446744073709551615, compressor_type:6, encryption:"", encrypt_key:"", rowkey_cnt:1, rowkey_array:[{column_idx:18, meta_type:{type:"BIGINT UNSIGNED", collation:"binary", coercibility:"NUMERIC"}, order:0}], column_array:[]}, addr:[0-0-0-3458764814468251648-0](offset=0,size=280,type=3,seq=0)}, mds_data:{is_inited:true, tablet_status:{uncommitted_kv:{ptr:(nil), addr:[0-0-0-0-0](offset=0,size=0,type=0,seq=0)}, committed_kv:{ptr:(nil), addr:[0-0-0-0-0](offset=0,size=0,type=0,seq=0)}}, aux_tablet_info:{uncommitted_kv:{ptr:(nil), addr:[0-0-0-0-0](offset=0,size=0,type=0,seq=0)}, committed_kv:{ptr:(nil), addr:[0-0-0-0-0](offset=0,size=0,type=0,seq=0)}}, extra_medium_info:{info:1, compat:1, last_compaction_type:0, wait_check_flag:0, last_medium_scn:0}, medium_info_list:{ptr:(nil), addr:[0-0-0-0-0](offset=0,size=0,type=0,seq=0)}, auto_inc_seq:{ptr:(nil), addr:[0-0-0-0-0](offset=0,size=0,type=0,seq=0)}, tablet_status_cache:{tablet_status:{val:7, str:"MAX"}, transfer_scn:{val:18446744073709551615, v:3}, transfer_ls_id:{id:-1}, data_type:0, create_commit_scn:{val:18446744073709551615, v:3}, create_commit_version:-1, delete_commit_scn:{val:18446744073709551615, v:3}, delete_commit_version:-1, transfer_out_commit_version:-1}}, ddl_kvs:null, ddl_kv_count:0, memtables:[null, null, null, null, null, null, null, null, null, null, null, null, null, null, null, null], memtable_count:0}) [2024-08-22 16:34:13.255917] INFO [MDS] register_to_mds_table_mgr (mds_table_mgr.cpp:100) [21868][DDLPQueueTh1][T1004][YB420A92023B-000620411DE480F1-0-0] [lt=83] register success(ret=0, ret="OB_SUCCESS", p_mds_table={this:0x2baa78a09070, ls_id:{id:1002}, tablet_id:{id:200025}, flushing_scn:{val:18446744073709551615, v:3}, rec_scn:{val:4611686018427387903, v:0}, last_inner_recycled_scn:{val:0, v:0}, total_node_cnt:0, construct_sequence:23, debug_info:{do_init_tablet_pointer:0x2ba9fdc15518, do_remove_tablet_pointer:null, init_ts:"2024-08-22 16:34:13.255915", last_reset_ts:"1970-01-01 08:00:00.0", remove_ts:"1970-01-01 08:00:00.0", last_flush_ts:"1970-01-01 08:00:00.0", switch_to_empty_shell_ts:"1970-01-01 08:00:00.0", init_trace_id:YB420A92023B-000620411DE480F1-0-0, remove_trace_id:Y0-0000000000000000-0-0}}) [2024-08-22 16:34:13.255941] INFO [MDS] init (mds_table_base.cpp:76) [21868][DDLPQueueTh1][T1004][YB420A92023B-000620411DE480F1-0-0] [lt=24] mds table inited(ret=0, ret="OB_SUCCESS", *this={this:0x2baa78a09070, ls_id:{id:1002}, tablet_id:{id:200025}, flushing_scn:{val:18446744073709551615, v:3}, rec_scn:{val:4611686018427387903, v:0}, last_inner_recycled_scn:{val:0, v:0}, total_node_cnt:0, construct_sequence:23, debug_info:{do_init_tablet_pointer:0x2ba9fdc15518, do_remove_tablet_pointer:null, init_ts:"2024-08-22 16:34:13.255915", last_reset_ts:"1970-01-01 08:00:00.0", remove_ts:"1970-01-01 08:00:00.0", last_flush_ts:"1970-01-01 08:00:00.0", switch_to_empty_shell_ts:"1970-01-01 08:00:00.0", init_trace_id:YB420A92023B-000620411DE480F1-0-0, remove_trace_id:Y0-0000000000000000-0-0}}) [2024-08-22 16:34:13.255976] INFO [MDS.EVENT]construct_insert_record_user_mds_node_ (mds_row.ipp:216) [21868][DDLPQueueTh1][T1004][YB420A92023B-000620411DE480F1-0-0] [lt=15] WRITE_NODE(key={tenant_id:1004, ls_id:{id:1002}, tablet_id:{id:200025}}, event={alloc:null, timestamp:"2024-08-22 16:34:13.255976", event:"WRITE_NODE", info_str:"{tablet_status:{val:1, str:"NORMAL"}, transfer_scn:{val:18446744073709551615, v:3}, transfer_ls_id:{id:-1}, data_type:1, create_commit_scn:{val:18446744073709551615, v:3}, create_commit_version:-1, delete_commit_scn:{val:18446744073709551615, v:3}, delete_commit_version:-1, transfer_out_commit_version:-1}", unit_id:0, key_str:"Dummy", writer_type:1, writer_id:47007612, 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}) [2024-08-22 16:34:13.255994] INFO [STORAGE] set_tablet_status (ob_ls_tablet_service.cpp:2373) [21868][DDLPQueueTh1][T1004][YB420A92023B-000620411DE480F1-0-0] [lt=15] succeeded to set tablet status(ret=0, tablet_id={id:200025}, tablet_status={tablet_status:{val:1, str:"NORMAL"}, transfer_scn:{val:18446744073709551615, v:3}, transfer_ls_id:{id:-1}, data_type:1, create_commit_scn:{val:18446744073709551615, v:3}, create_commit_version:-1, delete_commit_scn:{val:18446744073709551615, v:3}, delete_commit_version:-1, transfer_out_commit_version:-1}) [2024-08-22 16:34:13.256007] INFO [STORAGE] set_tablet_gc_trigger (ob_tablet_gc_service.cpp:289) [21868][DDLPQueueTh1][T1004][YB420A92023B-000620411DE480F1-0-0] [lt=10] set_tablet_gc_trigger(this={tablet_persist_trigger:2, is_inited:true}) [2024-08-22 16:34:13.256012] INFO [MDS] register_process (ob_tablet_create_mds_helper.cpp:84) [21868][DDLPQueueTh1][T1004][YB420A92023B-000620411DE480F1-0-0] [lt=5] create tablet register(ret=0, ret="OB_SUCCESS", arg={ls_id:{id:1002}, major_frozen_scn:{val:1724263204457998983, v:0}, total_tablet_cnt:1, tablets:{ [21868] [YB420A92023B-000620411DE480F1-0-0] data_tablet_id:{id:200025}, tablet_ids:[{id:200025}], compat_mode:0, is_create_bind_hidden_tablets:false [2024-08-22 16:34:13.256027] INFO [STORAGE.TRANS] notify_data_source_ (ob_trans_part_ctx.cpp:6606) [21868][DDLPQueueTh1][T1004][YB420A92023B-000620411DE480F1-0-0] [lt=12] notify MDS(ret=0, trans_id_={txid:47007612}, ls_id_={id:1002}, notify_type=0, log_ts={val:18446744073709551615, v:3}, notify_array.count()=1, notify_array=[{register_no_:0, has_submitted_:false, has_synced_:false, type:3, data_.length():569}], total_time=384) [2024-08-22 16:34:13.256045] INFO [STORAGE.TRANS] register_mds_into_tx (ob_trans_service.cpp:909) [21868][DDLPQueueTh1][T1004][YB420A92023B-000620411DE480F1-0-0] [lt=13] register multi data source result(ret=0, ret="OB_SUCCESS", arg={tenant_id:1004, tx_desc:{this:0x2baa0c47ab20, tx_id:{txid:47007612}, state:2, addr:"10.10.10.59:2882", tenant_id:1004, session_id:1, assoc_session_id:1, xid:NULL, xa_mode:"", xa_start_addr:"0.0.0.0:0", access_mode:0, tx_consistency_type:0, isolation:1, snapshot_version:{val:18446744073709551615, v:3}, snapshot_scn:0, active_scn:1724315653234651, op_sn:20, alloc_ts:1724315653235706, active_ts:1724315653235706, commit_ts:-1, finish_ts:-1, timeout_us:999997703, lock_timeout_us:-1, expire_ts:1724316653233409, coord_id:{id:-1}, parts:[{id:{id:1}, addr:"10.10.10.59:2882", epoch:21673519114103296, first_scn:1724315653236762, last_scn:1724315653254432, last_touch_ts:1724315653245521}, {id:{id:1002}, addr:"10.10.10.59:2882", epoch:16325494575367338, first_scn:1724315653254434, last_scn:1724315653254434, last_touch_ts:1724315653245521}], exec_info_reap_ts:1724315653245520, commit_version:{val:18446744073709551615, v:3}, commit_times:0, commit_cb:null, cluster_id:1723522017, cluster_version:17180000519, flags_.SHADOW:false, flags_.INTERRUPTED:false, flags_.BLOCK:false, flags_.REPLICA:false, can_elr:true, cflict_txs:[], abort_cause:0, commit_expire_ts:0, commit_task_.is_registered():false, ref:2}, ls_id:{id:1002}, type:3, buf_.length():0x239, request_id:0, register_flag:{need_flush_redo_instantly_:false, mds_base_scn_:{val:18446744073709551615, v:3}}}, result={result:0, tx_result:{incomplete:false, parts:[{id:{id:1}, addr:"10.10.10.59:2882", epoch:21673519114103296, first_scn:1724315653236762, last_scn:1724315653254432, last_touch_ts:1724315653245520}, {id:{id:1002}, addr:"10.10.10.59:2882", epoch:16325494575367338, first_scn:1724315653254434, last_scn:1724315653254434, last_touch_ts:1724315653245520}], touched_ls_list:[], cflict_txs:[]}}, tx_desc={this:0x2baa0c47ab20, tx_id:{txid:47007612}, state:2, addr:"10.10.10.59:2882", tenant_id:1004, session_id:1, assoc_session_id:1, xid:NULL, xa_mode:"", xa_start_addr:"0.0.0.0:0", access_mode:0, tx_consistency_type:0, isolation:1, snapshot_version:{val:18446744073709551615, v:3}, snapshot_scn:0, active_scn:1724315653234651, op_sn:20, alloc_ts:1724315653235706, active_ts:1724315653235706, commit_ts:-1, finish_ts:-1, timeout_us:999997703, lock_timeout_us:-1, expire_ts:1724316653233409, coord_id:{id:-1}, parts:[{id:{id:1}, addr:"10.10.10.59:2882", epoch:21673519114103296, first_scn:1724315653236762, last_scn:1724315653254432, last_touch_ts:1724315653245521}, {id:{id:1002}, addr:"10.10.10.59:2882", epoch:16325494575367338, first_scn:1724315653254434, last_scn:1724315653254434, last_touch_ts:1724315653245521}], exec_info_reap_ts:1724315653245520, commit_version:{val:18446744073709551615, v:3}, commit_times:0, commit_cb:null, cluster_id:1723522017, cluster_version:17180000519, flags_.SHADOW:false, flags_.INTERRUPTED:false, flags_.BLOCK:false, flags_.REPLICA:false, can_elr:true, cflict_txs:[], abort_cause:0, commit_expire_ts:0, commit_task_.is_registered():false, ref:2}, local_retry_cnt=0, retry_cnt=0, request_id=0, time_guard=time guard 'register mds' cost too much time, used=473, time_dist: start register=1, register in ctx begin=13, register in ctx end=418) [2024-08-22 16:34:13.256092] INFO [SERVER] register_multi_data_source (ob_inner_sql_connection.cpp:1177) [21868][DDLPQueueTh1][T0][YB420A92023B-000620411DE480F1-0-0] [lt=45] register mds in inner_sql_connection(ret=0, ret="OB_SUCCESS", this=0x2ba98a65c050, local_execute=true, get_resource_conn_id()=18446744073709551615, get_session().get_sessid()=1, get_session().get_tx_desc()={this:0x2baa0c47ab20, tx_id:{txid:47007612}, state:2, addr:"10.10.10.59:2882", tenant_id:1004, session_id:1, assoc_session_id:1, xid:NULL, xa_mode:"", xa_start_addr:"0.0.0.0:0", access_mode:0, tx_consistency_type:0, isolation:1, snapshot_version:{val:18446744073709551615, v:3}, snapshot_scn:0, active_scn:1724315653234651, op_sn:20, alloc_ts:1724315653235706, active_ts:1724315653235706, commit_ts:-1, finish_ts:-1, timeout_us:999997703, lock_timeout_us:-1, expire_ts:1724316653233409, coord_id:{id:-1}, parts:[{id:{id:1}, addr:"10.10.10.59:2882", epoch:21673519114103296, first_scn:1724315653236762, last_scn:1724315653254432, last_touch_ts:1724315653245521}, {id:{id:1002}, addr:"10.10.10.59:2882", epoch:16325494575367338, first_scn:1724315653254434, last_scn:1724315653254434, last_touch_ts:1724315653245521}], exec_info_reap_ts:1724315653245520, commit_version:{val:18446744073709551615, v:3}, commit_times:0, commit_cb:null, cluster_id:1723522017, cluster_version:17180000519, flags_.SHADOW:false, flags_.INTERRUPTED:false, flags_.BLOCK:false, flags_.REPLICA:false, can_elr:true, cflict_txs:[], abort_cause:0, commit_expire_ts:0, commit_task_.is_registered():false, ref:2}) [2024-08-22 16:34:13.256115] INFO [RS] execute (ob_tablet_creator.cpp:354) [21868][DDLPQueueTh1][T0][YB420A92023B-000620411DE480F1-0-0] [lt=20] generate create arg(ret=0, ret="OB_SUCCESS", buf_len=569, batch_arg->arg_.tablets_.count()=1, batch_arg->arg_=id:{id:1002}, major_frozen_scn:{val:1724263204457998983, v:0}, need_check_tablet_cnt:true, is_old_mds:false, tablets:[tablet_ids:[{id:200025}], data_tablet_id:{id:200025}, table_schema_index:[0], compat_mode:0, is_create_bind_hidden_tablets:false], cost_ts=509) [2024-08-22 16:34:13.256466] INFO [SHARE.SCHEMA] fill_schema_operation (ob_schema_retrieve_utils.ipp:2622) [21868][DDLPQueueTh1][T0][YB420A92023B-000620411DE480F1-0-0] [lt=13] fill schema operation(schema_operation=schema_version:1724315653246016, tenant_id:1004, database_id:500001, user_id:0, database_name:"", tablegroup_id:18446744073709551615, table_id:500025, operation_type:"OB_DDL_CREATE_TABLE", label_se_policy_id:500025, label_se_component_id:500025, label_se_label_id:500025, label_se_user_level_id:500025, outline_id:500025, udf_name:"", sequence_id:500025, keystore_id:500025, outline_id:500025, udf_name:"", sequence_id:500025, tablespace_id:500025, profile_id:500025, audit_id:500025, grantee_id:0, grantor_id:500001, ddl_stmt_str:"", dblink_id:500025, directory_id:500025) [2024-08-22 16:34:13.256772] INFO [SHARE.SCHEMA] get_batch_tenants (ob_schema_service_sql_impl.cpp:3289) [21868][DDLPQueueTh1][T0][YB420A92023B-000620411DE480F1-0-0] [lt=31] get batch tenants info finish(schema_version=9223372036854775806, ret=0) [2024-08-22 16:34:13.256784] INFO [SHARE.SCHEMA] get_batch_databases (ob_schema_service_sql_impl.cpp:3365) [21868][DDLPQueueTh1][T0][YB420A92023B-000620411DE480F1-0-0] [lt=10] get batch database schema finish(schema_version=9223372036854775806, ret=0) [2024-08-22 16:34:13.256910] INFO [SHARE.SCHEMA] retrieve_table_schema (ob_schema_retrieve_utils.ipp:107) [21868][DDLPQueueTh1][T0][YB420A92023B-000620411DE480F1-0-0] [lt=3] retrieve table schema(ret=0, ret="OB_SUCCESS", table_schema={simple_table_schema:{tenant_id:1004, database_id:500001, tablegroup_id:18446744073709551615, table_id:500025, association_table_id:18446744073709551615, in_offline_ddl_white_list:false, table_name:"mytest", session_id:0, index_type:0, table_type:3, table_mode:{table_mode_flag:0, pk_mode:2, table_state_flag:0, view_created_method_flag:0, table_organization_mode:1, auto_increment_mode:0, rowid_mode:0, view_column_filled_flag:0}, tablespace_id:18446744073709551615, data_table_id:0, name_casemode:-1, schema_version:1724315653246016, part_level:0, part_option:{part_func_type:0, part_func_expr:"", part_num:1, auto_part:false, auto_part_size:0}, sub_part_option:{part_func_type:0, part_func_expr:"", part_num:0, auto_part:false, auto_part_size:0}, partition_num:0, def_subpartition_num:0, partition_array:null, def_subpartition_array:null, hidden_partition_array:null, index_status:1, duplicate_scope:0, encryption:"", encrypt_key:"", master_key_id:18446744073709551615, sub_part_template_flags:0, get_tablet_id():{id:200025}, max_dependency_version:-1, object_status:1, is_force_view:false, truncate_version:-1}, max_used_column_id:17, sess_active_time:0, rowkey_column_num:1, index_column_num:0, rowkey_split_pos:0, block_size:16384, is_use_bloomfilter:false, progressive_merge_num:0, tablet_size:134217728, pctfree:0, load_type:0, index_using_type:0, def_type:1, charset_type:2, collation_type:45, index_status:1, partition_status:0, code_version:1, comment:"", pk_comment:"", create_host:"", tablegroup_name:"", compressor_type:6, row_store_type:1, store_format:3, expire_info:"", view_schema:{view_definition:"", check_option:"none", is_updatable:"false", is_materialized:"false", character_set_client:0, collation_connection:"invalid_type"}, autoinc_column_id:0, auto_increment:1, read_only:false, simple_index_infos:[], mv_tid_array:null, base_table_ids:[], rowkey_info:{columns:null, capacity:0}, partition_key_info:{columns:null, capacity:0}, column_cnt:0, table_dop:1, constraints:null, column_array:null, aux_vp_tid_array:[], define_user_id:200001, aux_lob_meta_tid:18446744073709551615, aux_lob_piece_tid:18446744073709551615, name_generated_type:0, lob_inrow_threshold:4096}, is_deleted=false) [2024-08-22 16:34:13.256952] INFO [SHARE.SCHEMA] retrieve_table_schema (ob_schema_retrieve_utils.ipp:120) [21868][DDLPQueueTh1][T0][YB420A92023B-000620411DE480F1-0-0] [lt=39] retrieve table schema array(tenant_id=1004) [2024-08-22 16:34:13.257135] INFO [SHARE.SCHEMA] get_batch_table_schema (ob_schema_service_sql_impl.cpp:418) [21868][DDLPQueueTh1][T0][YB420A92023B-000620411DE480F1-0-0] [lt=4] get batch table schema finish(schema_version=9223372036854775806, ret=0) [2024-08-22 16:34:13.257235] INFO [SHARE.SCHEMA] fetch_increment_table_schemas_for_data_dict_ (ob_server_schema_service.cpp:5110) [21868][DDLPQueueTh1][T0][YB420A92023B-000620411DE480F1-0-0] [lt=11] fetch increment table schema for data dict(tenant_id=1004, table_id=500025, schema_version=1724315653246016) [2024-08-22 16:34:13.258472] INFO [STORAGE.TRANS] notify_data_source_ (ob_trans_part_ctx.cpp:6606) [21868][DDLPQueueTh1][T1004][YB420A92023B-000620411DE480F1-0-0] [lt=11] notify MDS(ret=0, trans_id_={txid:47007612}, ls_id_={id:1}, notify_type=0, log_ts={val:18446744073709551615, v:3}, notify_array.count()=1, notify_array=[{register_no_:0, has_submitted_:false, has_synced_:false, type:6, data_.length():239}], total_time=0) [2024-08-22 16:34:13.258502] INFO [STORAGE.TRANS] register_mds_into_tx (ob_trans_service.cpp:909) [21868][DDLPQueueTh1][T1004][YB420A92023B-000620411DE480F1-0-0] [lt=26] register multi data source result(ret=0, ret="OB_SUCCESS", arg={tenant_id:1004, tx_desc:{this:0x2baa0c47ab20, tx_id:{txid:47007612}, state:2, addr:"10.10.10.59:2882", tenant_id:1004, session_id:1, assoc_session_id:1, xid:NULL, xa_mode:"", xa_start_addr:"0.0.0.0:0", access_mode:0, tx_consistency_type:0, isolation:1, snapshot_version:{val:18446744073709551615, v:3}, snapshot_scn:0, active_scn:1724315653234651, op_sn:23, alloc_ts:1724315653235706, active_ts:1724315653235706, commit_ts:-1, finish_ts:-1, timeout_us:999997703, lock_timeout_us:-1, expire_ts:1724316653233409, coord_id:{id:-1}, parts:[{id:{id:1}, addr:"10.10.10.59:2882", epoch:21673519114103296, first_scn:1724315653236762, last_scn:1724315653257640, last_touch_ts:1724315653245522}, {id:{id:1002}, addr:"10.10.10.59:2882", epoch:16325494575367338, first_scn:1724315653254434, last_scn:1724315653254434, last_touch_ts:1724315653245522}], exec_info_reap_ts:1724315653245521, commit_version:{val:18446744073709551615, v:3}, commit_times:0, commit_cb:null, cluster_id:1723522017, cluster_version:17180000519, flags_.SHADOW:false, flags_.INTERRUPTED:false, flags_.BLOCK:false, flags_.REPLICA:false, can_elr:true, cflict_txs:[], abort_cause:0, commit_expire_ts:0, commit_task_.is_registered():false, ref:2}, ls_id:{id:1}, type:6, buf_.length():0xef, request_id:0, register_flag:{need_flush_redo_instantly_:false, mds_base_scn_:{val:18446744073709551615, v:3}}}, result={result:0, tx_result:{incomplete:false, parts:[{id:{id:1}, addr:"10.10.10.59:2882", epoch:21673519114103296, first_scn:1724315653236762, last_scn:1724315653257640, last_touch_ts:1724315653245521}, {id:{id:1002}, addr:"10.10.10.59:2882", epoch:16325494575367338, first_scn:1724315653254434, last_scn:1724315653254434, last_touch_ts:1724315653245521}], touched_ls_list:[], cflict_txs:[]}}, tx_desc={this:0x2baa0c47ab20, tx_id:{txid:47007612}, state:2, addr:"10.10.10.59:2882", tenant_id:1004, session_id:1, assoc_session_id:1, xid:NULL, xa_mode:"", xa_start_addr:"0.0.0.0:0", access_mode:0, tx_consistency_type:0, isolation:1, snapshot_version:{val:18446744073709551615, v:3}, snapshot_scn:0, active_scn:1724315653234651, op_sn:23, alloc_ts:1724315653235706, active_ts:1724315653235706, commit_ts:-1, finish_ts:-1, timeout_us:999997703, lock_timeout_us:-1, expire_ts:1724316653233409, coord_id:{id:-1}, parts:[{id:{id:1}, addr:"10.10.10.59:2882", epoch:21673519114103296, first_scn:1724315653236762, last_scn:1724315653257640, last_touch_ts:1724315653245522}, {id:{id:1002}, addr:"10.10.10.59:2882", epoch:16325494575367338, first_scn:1724315653254434, last_scn:1724315653254434, last_touch_ts:1724315653245522}], exec_info_reap_ts:1724315653245521, commit_version:{val:18446744073709551615, v:3}, commit_times:0, commit_cb:null, cluster_id:1723522017, cluster_version:17180000519, flags_.SHADOW:false, flags_.INTERRUPTED:false, flags_.BLOCK:false, flags_.REPLICA:false, can_elr:true, cflict_txs:[], abort_cause:0, commit_expire_ts:0, commit_task_.is_registered():false, ref:2}, local_retry_cnt=0, retry_cnt=0, request_id=0, time_guard=time guard 'register mds' cost too much time, used=97, time_dist: start register=1, register in ctx begin=2, register in ctx end=38) [2024-08-22 16:34:13.258564] INFO [SERVER] register_multi_data_source (ob_inner_sql_connection.cpp:1177) [21868][DDLPQueueTh1][T0][YB420A92023B-000620411DE480F1-0-0] [lt=60] register mds in inner_sql_connection(ret=0, ret="OB_SUCCESS", this=0x2ba98a65c050, local_execute=true, get_resource_conn_id()=18446744073709551615, get_session().get_sessid()=1, get_session().get_tx_desc()={this:0x2baa0c47ab20, tx_id:{txid:47007612}, state:2, addr:"10.10.10.59:2882", tenant_id:1004, session_id:1, assoc_session_id:1, xid:NULL, xa_mode:"", xa_start_addr:"0.0.0.0:0", access_mode:0, tx_consistency_type:0, isolation:1, snapshot_version:{val:18446744073709551615, v:3}, snapshot_scn:0, active_scn:1724315653234651, op_sn:23, alloc_ts:1724315653235706, active_ts:1724315653235706, commit_ts:-1, finish_ts:-1, timeout_us:999997703, lock_timeout_us:-1, expire_ts:1724316653233409, coord_id:{id:-1}, parts:[{id:{id:1}, addr:"10.10.10.59:2882", epoch:21673519114103296, first_scn:1724315653236762, last_scn:1724315653257640, last_touch_ts:1724315653245522}, {id:{id:1002}, addr:"10.10.10.59:2882", epoch:16325494575367338, first_scn:1724315653254434, last_scn:1724315653254434, last_touch_ts:1724315653245522}], exec_info_reap_ts:1724315653245521, commit_version:{val:18446744073709551615, v:3}, commit_times:0, commit_cb:null, cluster_id:1723522017, cluster_version:17180000519, flags_.SHADOW:false, flags_.INTERRUPTED:false, flags_.BLOCK:false, flags_.REPLICA:false, can_elr:true, cflict_txs:[], abort_cause:0, commit_expire_ts:0, commit_task_.is_registered():false, ref:2}) [2024-08-22 16:34:13.258589] INFO [SHARE.SCHEMA] gen_new_schema_version (ob_schema_service_sql_impl.cpp:469) [21868][DDLPQueueTh1][T0][YB420A92023B-000620411DE480F1-0-0] [lt=16] new schema version(tenant_id=1004, schema_version=1724315653246024) [2024-08-22 16:34:13.258915] INFO [STORAGE.TRANS] notify_data_source_ (ob_trans_part_ctx.cpp:6606) [21868][DDLPQueueTh1][T1004][YB420A92023B-000620411DE480F1-0-0] [lt=4] notify MDS(ret=0, trans_id_={txid:47007612}, ls_id_={id:1}, notify_type=2, log_ts={val:18446744073709551615, v:3}, notify_array.count()=1, notify_array=[{register_no_:1, has_submitted_:true, has_synced_:false, type:6, data_.length():239}], total_time=0) [2024-08-22 16:34:13.258983] INFO [STORAGE.TRANS] statistics_ (ob_trans_rpc.cpp:670) [21868][DDLPQueueTh1][T1004][YB420A92023B-000620411DE480F1-0-0] [lt=16] rpc statistics(total_trans_msg_count=7, total_batch_msg_count=7) [2024-08-22 16:34:13.261436] INFO [RS] remove_task (ob_ddl_trans_controller.cpp:331) [21868][DDLPQueueTh1][T0][YB420A92023B-000620411DE480F1-0-0] [lt=10] remove parallel ddl task(tasks_.at(i)={tenant_id:1004, task_id:1724315653246024, task_end:true}) [2024-08-22 16:34:13.261454] TRACE [TRACE] execute (ob_create_table_helper.cpp:185) [21868][DDLPQueueTh1][T0][YB420A92023B-000620411DE480F1-0-0] [lt=14] [parallel create table](TRACE=begin_ts=1724315653235208 2024-08-22 08:34:13.235208|[create_table_begin] u=0 |[start_ddl_trans] u=1772 |[lock_objects] u=4800 |[generate_schemas] u=4240 |[gen_task_id_and_versions] u=22 |[create_schemas] u=5292 |[create_tablets] u=5058 |[inc_schema_dict] u=2193 |[wait_ddl_trans] u=2 |[end_ddl_trans] u=2866 |[create_table_end] u=1 |total_timeu=26246) [2024-08-22 16:34:13.261515] INFO [SHARE] add_event (ob_event_history_table_operator.h:290) [21868][DDLPQueueTh1][T0][YB420A92023B-000620411DE480F1-0-0] [lt=14] event table add task(ret=0, event_table_name="__all_rootservice_event_history", sql=INSERT INTO __all_rootservice_event_history (gmt_create, module, event, name1, value1, name2, value2, name3, value3, name4, value4, rs_svr_ip, rs_svr_port) VALUES (usec_to_time(1724315653261481), 'ddl', 'parallel_create_table', 'ret', 0, 'tenant_id', 1004, 'table_id', 500025, 'cost', 26292, '10.10.10.59', 2882)) [2024-08-22 16:34:13.261526] INFO [RS] process_ (ob_rs_rpc_processor.h:230) [21868][DDLPQueueTh1][T0][YB420A92023B-000620411DE480F1-0-0] [lt=11] [DDL] execute ddl like stmt(ret=0, cost=26339, ddl_arg={ddl_stmt_str:"CREATE TABLE mytest (