Skipping 18,168 KB.. Full Log

[2024/05/31 19:25:21.696 +08:00] [INFO] [split_region.go:236] ["split regions complete"] ["region count"=1] ["region IDs"="[10222]"]
[2024/05/31 19:25:21.696 +08:00] [INFO] [split_region.go:85] ["split batch regions request"] ["split key count"=1] ["batch count"=1] ["first batch, region ID"=14] ["first split key"=748000000000003174]
[2024/05/31 19:25:21.697 +08:00] [INFO] [domain.go:288] ["diff load InfoSchema success"] [isV2=false] [currentSchemaVersion=16519] [neededSchemaVersion=16520] ["start time"=816.824µs] [gotSchemaVersion=16520] [phyTblIDs="[12658,12659,12660]"] [actionTypes="[3,3,3]"] [diffTypes="[\"create table\"]"]
[2024/05/31 19:25:21.700 +08:00] [INFO] [split_region.go:187] ["batch split regions complete"] ["batch region ID"=14] ["first at"=748000000000003174] ["first new region left"="{Id:10224 StartKey:7480000000000031ff7300000000000000f8 EndKey:7480000000000031ff7400000000000000f8 RegionEpoch:{ConfVer:1 Version:4872} Peers:[id:10225 store_id:1 ] EncryptionMeta:<nil> IsInFlashback:false FlashbackStartTs:0}"] ["new region count"=1]
[2024/05/31 19:25:21.700 +08:00] [INFO] [split_region.go:236] ["split regions complete"] ["region count"=1] ["region IDs"="[10224]"]
[2024/05/31 19:25:21.706 +08:00] [WARN] [prewrite.go:382] ["1pc failed and fallbacks to normal commit procedure"] [conn=3227518040] [session_alias=] [startTS=450141807364014088]
[2024/05/31 19:25:21.707 +08:00] [WARN] [prewrite.go:382] ["1pc failed and fallbacks to normal commit procedure"] [conn=3227518040] [session_alias=] [startTS=450141807364014090]
[2024/05/31 19:25:21.709 +08:00] [WARN] [prewrite.go:382] ["1pc failed and fallbacks to normal commit procedure"] [conn=3227518040] [session_alias=] [startTS=450141807364014092]
[2024/05/31 19:25:21.710 +08:00] [WARN] [prewrite.go:382] ["1pc failed and fallbacks to normal commit procedure"] [conn=3227518040] [session_alias=] [startTS=450141807364014094]
[2024/05/31 19:25:21.712 +08:00] [WARN] [prewrite.go:382] ["1pc failed and fallbacks to normal commit procedure"] [conn=3227518040] [session_alias=] [startTS=450141807364014096]
[2024/05/31 19:25:21.713 +08:00] [WARN] [prewrite.go:382] ["1pc failed and fallbacks to normal commit procedure"] [conn=3227518040] [session_alias=] [startTS=450141807364014098]
[2024/05/31 19:25:21.714 +08:00] [WARN] [prewrite.go:382] ["1pc failed and fallbacks to normal commit procedure"] [conn=3227518040] [session_alias=] [startTS=450141807364014100]
[2024/05/31 19:25:21.716 +08:00] [WARN] [prewrite.go:382] ["1pc failed and fallbacks to normal commit procedure"] [conn=3227518040] [session_alias=] [startTS=450141807364014102]
[2024/05/31 19:25:21.719 +08:00] [WARN] [prewrite.go:382] ["1pc failed and fallbacks to normal commit procedure"] [conn=3227518040] [session_alias=] [startTS=450141807364014106]
[2024/05/31 19:25:21.721 +08:00] [WARN] [prewrite.go:382] ["1pc failed and fallbacks to normal commit procedure"] [conn=3227518040] [session_alias=] [startTS=450141807364014108]
[2024/05/31 19:25:21.724 +08:00] [WARN] [prewrite.go:382] ["1pc failed and fallbacks to normal commit procedure"] [conn=3227518040] [session_alias=] [startTS=450141807364014111]
[2024/05/31 19:25:21.726 +08:00] [INFO] [session.go:3959] ["CRUCIAL OPERATION"] [conn=3227518040] [schemaVersion=16520] [cur_db=executor__write] [sql="drop table if exists replace_test;"] [user=root@%]
[2024/05/31 19:25:21.726 +08:00] [INFO] [session.go:3959] ["CRUCIAL OPERATION"] [conn=3227518040] [schemaVersion=16520] [cur_db=executor__write] [sql="create table replace_test (id int PRIMARY KEY AUTO_INCREMENT, c1 int, c2 int, c3 int default 1);"] [user=root@%]
[2024/05/31 19:25:21.745 +08:00] [INFO] [ddl_worker.go:261] ["add DDL jobs"] [category=ddl] ["batch count"=1] [jobs="ID:12663, Type:create table, State:queueing, SchemaState:none, SchemaID:12334, TableID:12662, RowCount:0, ArgLen:2, start time: 2024-05-31 19:25:21.695 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false; "] [table=true]
[2024/05/31 19:25:21.746 +08:00] [INFO] [ddl.go:1192] ["start DDL job"] [category=ddl] [job="ID:12663, Type:create table, State:queueing, SchemaState:none, SchemaID:12334, TableID:12662, RowCount:0, ArgLen:2, start time: 2024-05-31 19:25:21.695 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false"] [query="create table replace_test (id int PRIMARY KEY AUTO_INCREMENT, c1 int, c2 int, c3 int default 1);"]
[2024/05/31 19:25:21.776 +08:00] [INFO] [ddl_worker.go:1210] ["run DDL job"] [worker="worker 4, tp general"] [category=ddl] [jobID=12663] [conn=3227518040] [category=ddl] [job="ID:12663, Type:create table, State:queueing, SchemaState:none, SchemaID:12334, TableID:12662, RowCount:0, ArgLen:0, start time: 2024-05-31 19:25:21.695 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false"]
[2024/05/31 19:25:21.802 +08:00] [INFO] [ddl_worker.go:763] ["finish DDL job"] [worker="worker 4, tp general"] [category=ddl] [jobID=12663] [conn=3227518040] [job="ID:12663, Type:create table, State:synced, SchemaState:public, SchemaID:12334, TableID:12662, RowCount:0, ArgLen:0, start time: 2024-05-31 19:25:21.695 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false"]
[2024/05/31 19:25:21.806 +08:00] [INFO] [ddl.go:1298] ["DDL job is finished"] [category=ddl] [jobID=12663]
[2024/05/31 19:25:21.806 +08:00] [INFO] [callback.go:140] ["performing DDL change, must reload"] [category=ddl]
[2024/05/31 19:25:21.806 +08:00] [INFO] [split_region.go:85] ["split batch regions request"] ["split key count"=1] ["batch count"=1] ["first batch, region ID"=14] ["first split key"=748000000000003176]
[2024/05/31 19:25:21.807 +08:00] [INFO] [split_region.go:187] ["batch split regions complete"] ["batch region ID"=14] ["first at"=748000000000003176] ["first new region left"="{Id:10226 StartKey:7480000000000031ff7400000000000000f8 EndKey:7480000000000031ff7600000000000000f8 RegionEpoch:{ConfVer:1 Version:4873} Peers:[id:10227 store_id:1 ] EncryptionMeta:<nil> IsInFlashback:false FlashbackStartTs:0}"] ["new region count"=1]
[2024/05/31 19:25:21.807 +08:00] [INFO] [split_region.go:236] ["split regions complete"] ["region count"=1] ["region IDs"="[10226]"]
[2024/05/31 19:25:21.807 +08:00] [INFO] [domain.go:288] ["diff load InfoSchema success"] [isV2=false] [currentSchemaVersion=16520] [neededSchemaVersion=16521] ["start time"=560.929µs] [gotSchemaVersion=16521] [phyTblIDs="[12662]"] [actionTypes="[3]"] [diffTypes="[\"create table\"]"]
[2024/05/31 19:25:21.812 +08:00] [WARN] [session.go:2116] ["compile SQL failed"] [conn=3227518040] [session_alias=] [error="[planner:1136]Column count doesn't match value count at row 1"] [SQL="replace replace_test (c1) values ()"]
[2024/05/31 19:25:21.812 +08:00] [INFO] [conn.go:1151] ["command dispatched failed"] [conn=3227518040] [session_alias=] [connInfo="id:3227518040, addr:127.0.0.1:53632 status:11, collation:utf8mb4_general_ci, user:root"] [command=Query] [status="inTxn:1, autocommit:1"] [sql="replace replace_test (c1) values ();"] [txn_mode=PESSIMISTIC] [timestamp=450141807390490636] [err="[planner:1136]Column count doesn't match value count at row 1\ngithub.com/pingcap/errors.AddStack\n\t/go/pkg/mod/github.com/pingcap/errors@v0.11.5-0.20240318064555-6bd07397691f/errors.go:178\ngithub.com/pingcap/errors.(*Error).GenWithStackByArgs\n\t/go/pkg/mod/github.com/pingcap/errors@v0.11.5-0.20240318064555-6bd07397691f/normalize.go:175\ngithub.com/pingcap/tidb/pkg/planner/core.(*PlanBuilder).buildValuesListOfInsert\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/planner/core/planbuilder.go:3878\ngithub.com/pingcap/tidb/pkg/planner/core.(*PlanBuilder).buildInsert\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/planner/core/planbuilder.go:3688\ngithub.com/pingcap/tidb/pkg/planner/core.(*PlanBuilder).Build\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/planner/core/planbuilder.go:494\ngithub.com/pingcap/tidb/pkg/planner.buildLogicalPlan\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/planner/optimize.go:549\ngithub.com/pingcap/tidb/pkg/planner.optimize\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/planner/optimize.go:466\ngithub.com/pingcap/tidb/pkg/planner.Optimize\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/planner/optimize.go:334\ngithub.com/pingcap/tidb/pkg/executor.(*Compiler).Compile\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/executor/compiler.go:99\ngithub.com/pingcap/tidb/pkg/session.(*session).ExecuteStmt\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/session/session.go:2094\ngithub.com/pingcap/tidb/pkg/server.(*TiDBContext).ExecuteStmt\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/server/driver_tidb.go:294\ngithub.com/pingcap/tidb/pkg/server.(*clientConn).handleStmt\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/server/conn.go:2021\ngithub.com/pingcap/tidb/pkg/server.(*clientConn).handleQuery\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/server/conn.go:1774\ngithub.com/pingcap/tidb/pkg/server.(*clientConn).dispatch\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/server/conn.go:1348\ngithub.com/pingcap/tidb/pkg/server.(*clientConn).Run\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/server/conn.go:1114\ngithub.com/pingcap/tidb/pkg/server.(*Server).onConn\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/server/server.go:739\nruntime.goexit\n\t/usr/local/go/src/runtime/asm_amd64.s:1650"]
[2024/05/31 19:25:21.812 +08:00] [WARN] [session.go:2116] ["compile SQL failed"] [conn=3227518040] [session_alias=] [error="[planner:1136]Column count doesn't match value count at row 2"] [SQL="replace replace_test (c1, c2) values (1,2),(1)"]
[2024/05/31 19:25:21.812 +08:00] [INFO] [conn.go:1151] ["command dispatched failed"] [conn=3227518040] [session_alias=] [connInfo="id:3227518040, addr:127.0.0.1:53632 status:11, collation:utf8mb4_general_ci, user:root"] [command=Query] [status="inTxn:1, autocommit:1"] [sql="replace replace_test (c1, c2) values (1,2),(1);"] [txn_mode=PESSIMISTIC] [timestamp=450141807390490637] [err="[planner:1136]Column count doesn't match value count at row 2\ngithub.com/pingcap/errors.AddStack\n\t/go/pkg/mod/github.com/pingcap/errors@v0.11.5-0.20240318064555-6bd07397691f/errors.go:178\ngithub.com/pingcap/errors.(*Error).GenWithStackByArgs\n\t/go/pkg/mod/github.com/pingcap/errors@v0.11.5-0.20240318064555-6bd07397691f/normalize.go:175\ngithub.com/pingcap/tidb/pkg/planner/core.(*PlanBuilder).buildValuesListOfInsert\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/planner/core/planbuilder.go:3890\ngithub.com/pingcap/tidb/pkg/planner/core.(*PlanBuilder).buildInsert\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/planner/core/planbuilder.go:3688\ngithub.com/pingcap/tidb/pkg/planner/core.(*PlanBuilder).Build\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/planner/core/planbuilder.go:494\ngithub.com/pingcap/tidb/pkg/planner.buildLogicalPlan\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/planner/optimize.go:549\ngithub.com/pingcap/tidb/pkg/planner.optimize\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/planner/optimize.go:466\ngithub.com/pingcap/tidb/pkg/planner.Optimize\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/planner/optimize.go:334\ngithub.com/pingcap/tidb/pkg/executor.(*Compiler).Compile\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/executor/compiler.go:99\ngithub.com/pingcap/tidb/pkg/session.(*session).ExecuteStmt\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/session/session.go:2094\ngithub.com/pingcap/tidb/pkg/server.(*TiDBContext).ExecuteStmt\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/server/driver_tidb.go:294\ngithub.com/pingcap/tidb/pkg/server.(*clientConn).handleStmt\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/server/conn.go:2021\ngithub.com/pingcap/tidb/pkg/server.(*clientConn).handleQuery\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/server/conn.go:1774\ngithub.com/pingcap/tidb/pkg/server.(*clientConn).dispatch\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/server/conn.go:1348\ngithub.com/pingcap/tidb/pkg/server.(*clientConn).Run\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/server/conn.go:1114\ngithub.com/pingcap/tidb/pkg/server.(*Server).onConn\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/server/server.go:739\nruntime.goexit\n\t/usr/local/go/src/runtime/asm_amd64.s:1650"]
[2024/05/31 19:25:21.813 +08:00] [WARN] [session.go:2116] ["compile SQL failed"] [conn=3227518040] [session_alias=] [error="[planner:1054]Unknown column 'xxx' in 'field list'"] [SQL="replace replace_test (xxx) values (3)"]
[2024/05/31 19:25:21.813 +08:00] [INFO] [conn.go:1151] ["command dispatched failed"] [conn=3227518040] [session_alias=] [connInfo="id:3227518040, addr:127.0.0.1:53632 status:11, collation:utf8mb4_general_ci, user:root"] [command=Query] [status="inTxn:1, autocommit:1"] [sql="replace replace_test (xxx) values (3);"] [txn_mode=PESSIMISTIC] [timestamp=450141807390490638] [err="[planner:1054]Unknown column 'xxx' in 'field list'\ngithub.com/pingcap/errors.AddStack\n\t/go/pkg/mod/github.com/pingcap/errors@v0.11.5-0.20240318064555-6bd07397691f/errors.go:178\ngithub.com/pingcap/errors.(*Error).GenWithStackByArgs\n\t/go/pkg/mod/github.com/pingcap/errors@v0.11.5-0.20240318064555-6bd07397691f/normalize.go:175\ngithub.com/pingcap/tidb/pkg/planner/core.(*PlanBuilder).getAffectCols\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/planner/core/planbuilder.go:3793\ngithub.com/pingcap/tidb/pkg/planner/core.(*PlanBuilder).buildValuesListOfInsert\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/planner/core/planbuilder.go:3868\ngithub.com/pingcap/tidb/pkg/planner/core.(*PlanBuilder).buildInsert\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/planner/core/planbuilder.go:3688\ngithub.com/pingcap/tidb/pkg/planner/core.(*PlanBuilder).Build\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/planner/core/planbuilder.go:494\ngithub.com/pingcap/tidb/pkg/planner.buildLogicalPlan\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/planner/optimize.go:549\ngithub.com/pingcap/tidb/pkg/planner.optimize\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/planner/optimize.go:466\ngithub.com/pingcap/tidb/pkg/planner.Optimize\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/planner/optimize.go:334\ngithub.com/pingcap/tidb/pkg/executor.(*Compiler).Compile\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/executor/compiler.go:99\ngithub.com/pingcap/tidb/pkg/session.(*session).ExecuteStmt\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/session/session.go:2094\ngithub.com/pingcap/tidb/pkg/server.(*TiDBContext).ExecuteStmt\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/server/driver_tidb.go:294\ngithub.com/pingcap/tidb/pkg/server.(*clientConn).handleStmt\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/server/conn.go:2021\ngithub.com/pingcap/tidb/pkg/server.(*clientConn).handleQuery\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/server/conn.go:1774\ngithub.com/pingcap/tidb/pkg/server.(*clientConn).dispatch\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/server/conn.go:1348\ngithub.com/pingcap/tidb/pkg/server.(*clientConn).Run\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/server/conn.go:1114\ngithub.com/pingcap/tidb/pkg/server.(*Server).onConn\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/server/server.go:739\nruntime.goexit\n\t/usr/local/go/src/runtime/asm_amd64.s:1650"]
[2024/05/31 19:25:21.813 +08:00] [WARN] [session.go:2116] ["compile SQL failed"] [conn=3227518040] [session_alias=] [error="[schema:1146]Table 'executor__write.replace_test_xxx' doesn't exist"] [SQL="replace replace_test_xxx (c1) values ();"]
[2024/05/31 19:25:21.813 +08:00] [INFO] [conn.go:1151] ["command dispatched failed"] [conn=3227518040] [session_alias=] [connInfo="id:3227518040, addr:127.0.0.1:53632 status:11, collation:utf8mb4_general_ci, user:root"] [command=Query] [status="inTxn:1, autocommit:1"] [sql="replace replace_test_xxx (c1) values ();"] [txn_mode=PESSIMISTIC] [timestamp=450141807390490639] [err="[schema:1146]Table 'executor__write.replace_test_xxx' doesn't exist"]
[2024/05/31 19:25:21.815 +08:00] [WARN] [prewrite.go:382] ["1pc failed and fallbacks to normal commit procedure"] [conn=3227518040] [session_alias=] [startTS=450141807390490640]
[2024/05/31 19:25:21.816 +08:00] [WARN] [session.go:2158] ["run statement failed"] [conn=3227518040] [session_alias=] [schemaVersion=16521] [error="[table:1110]Column 'c1' specified twice"] [session="{\n  \"currDBName\": \"executor__write\",\n  \"id\": 3227518040,\n  \"status\": 3,\n  \"strictMode\": true,\n  \"txn\": \"450141807390490643\",\n  \"user\": {\n    \"Username\": \"root\",\n    \"Hostname\": \"127.0.0.1\",\n    \"CurrentUser\": false,\n    \"AuthUsername\": \"root\",\n    \"AuthHostname\": \"%\",\n    \"AuthPlugin\": \"mysql_native_password\"\n  }\n}"]
[2024/05/31 19:25:21.816 +08:00] [INFO] [conn.go:1151] ["command dispatched failed"] [conn=3227518040] [session_alias=] [connInfo="id:3227518040, addr:127.0.0.1:53632 status:11, collation:utf8mb4_general_ci, user:root"] [command=Query] [status="inTxn:1, autocommit:1"] [sql="replace replace_test set c1 = 4, c1 = 5;"] [txn_mode=PESSIMISTIC] [timestamp=450141807390490643] [err="[table:1110]Column 'c1' specified twice\ngithub.com/pingcap/errors.AddStack\n\t/go/pkg/mod/github.com/pingcap/errors@v0.11.5-0.20240318064555-6bd07397691f/errors.go:178\ngithub.com/pingcap/errors.(*Error).GenWithStackByArgs\n\t/go/pkg/mod/github.com/pingcap/errors@v0.11.5-0.20240318064555-6bd07397691f/normalize.go:175\ngithub.com/pingcap/tidb/pkg/table.CheckOnce\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/table/column.go:481\ngithub.com/pingcap/tidb/pkg/executor.(*InsertValues).initInsertColumns\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/executor/insert_common.go:163\ngithub.com/pingcap/tidb/pkg/executor.(*executorBuilder).buildInsert\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/executor/builder.go:978\ngithub.com/pingcap/tidb/pkg/executor.(*executorBuilder).build\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/executor/builder.go:200\ngithub.com/pingcap/tidb/pkg/executor.(*ExecStmt).buildExecutor\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/executor/adapter.go:1172\ngithub.com/pingcap/tidb/pkg/executor.(*ExecStmt).Exec\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/executor/adapter.go:522\ngithub.com/pingcap/tidb/pkg/session.runStmt\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/session/session.go:2285\ngithub.com/pingcap/tidb/pkg/session.(*session).ExecuteStmt\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/session/session.go:2146\ngithub.com/pingcap/tidb/pkg/server.(*TiDBContext).ExecuteStmt\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/server/driver_tidb.go:294\ngithub.com/pingcap/tidb/pkg/server.(*clientConn).handleStmt\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/server/conn.go:2021\ngithub.com/pingcap/tidb/pkg/server.(*clientConn).handleQuery\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/server/conn.go:1774\ngithub.com/pingcap/tidb/pkg/server.(*clientConn).dispatch\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/server/conn.go:1348\ngithub.com/pingcap/tidb/pkg/server.(*clientConn).Run\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/server/conn.go:1114\ngithub.com/pingcap/tidb/pkg/server.(*Server).onConn\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/server/server.go:739\nruntime.goexit\n\t/usr/local/go/src/runtime/asm_amd64.s:1650"]
[2024/05/31 19:25:21.816 +08:00] [WARN] [session.go:2116] ["compile SQL failed"] [conn=3227518040] [session_alias=] [error="[planner:1054]Unknown column 'xxx' in 'field list'"] [SQL="replace replace_test set xxx = 6"]
[2024/05/31 19:25:21.816 +08:00] [INFO] [conn.go:1151] ["command dispatched failed"] [conn=3227518040] [session_alias=] [connInfo="id:3227518040, addr:127.0.0.1:53632 status:11, collation:utf8mb4_general_ci, user:root"] [command=Query] [status="inTxn:1, autocommit:1"] [sql="replace replace_test set xxx = 6;"] [txn_mode=PESSIMISTIC] [timestamp=450141807390490644] [err="[planner:1054]Unknown column 'xxx' in 'field list'\ngithub.com/pingcap/errors.AddStack\n\t/go/pkg/mod/github.com/pingcap/errors@v0.11.5-0.20240318064555-6bd07397691f/errors.go:178\ngithub.com/pingcap/errors.(*Error).GenWithStackByArgs\n\t/go/pkg/mod/github.com/pingcap/errors@v0.11.5-0.20240318064555-6bd07397691f/normalize.go:175\ngithub.com/pingcap/tidb/pkg/planner/core.(*PlanBuilder).getAffectCols\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/planner/core/planbuilder.go:3793\ngithub.com/pingcap/tidb/pkg/planner/core.(*PlanBuilder).buildValuesListOfInsert\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/planner/core/planbuilder.go:3868\ngithub.com/pingcap/tidb/pkg/planner/core.(*PlanBuilder).buildInsert\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/planner/core/planbuilder.go:3688\ngithub.com/pingcap/tidb/pkg/planner/core.(*PlanBuilder).Build\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/planner/core/planbuilder.go:494\ngithub.com/pingcap/tidb/pkg/planner.buildLogicalPlan\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/planner/optimize.go:549\ngithub.com/pingcap/tidb/pkg/planner.optimize\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/planner/optimize.go:466\ngithub.com/pingcap/tidb/pkg/planner.Optimize\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/planner/optimize.go:334\ngithub.com/pingcap/tidb/pkg/executor.(*Compiler).Compile\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/executor/compiler.go:99\ngithub.com/pingcap/tidb/pkg/session.(*session).ExecuteStmt\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/session/session.go:2094\ngithub.com/pingcap/tidb/pkg/server.(*TiDBContext).ExecuteStmt\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/server/driver_tidb.go:294\ngithub.com/pingcap/tidb/pkg/server.(*clientConn).handleStmt\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/server/conn.go:2021\ngithub.com/pingcap/tidb/pkg/server.(*clientConn).handleQuery\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/server/conn.go:1774\ngithub.com/pingcap/tidb/pkg/server.(*clientConn).dispatch\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/server/conn.go:1348\ngithub.com/pingcap/tidb/pkg/server.(*clientConn).Run\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/server/conn.go:1114\ngithub.com/pingcap/tidb/pkg/server.(*Server).onConn\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/server/server.go:739\nruntime.goexit\n\t/usr/local/go/src/runtime/asm_amd64.s:1650"]
[2024/05/31 19:25:21.817 +08:00] [INFO] [session.go:3959] ["CRUCIAL OPERATION"] [conn=3227518040] [schemaVersion=16521] [cur_db=executor__write] [sql="drop table if exists replace_test_1;"] [user=root@%]
[2024/05/31 19:25:21.817 +08:00] [INFO] [session.go:3959] ["CRUCIAL OPERATION"] [conn=3227518040] [schemaVersion=16521] [cur_db=executor__write] [sql="create table replace_test_1 (id int, c1 int);"] [user=root@%]
[2024/05/31 19:25:21.834 +08:00] [INFO] [ddl_worker.go:261] ["add DDL jobs"] [category=ddl] ["batch count"=1] [jobs="ID:12665, Type:create table, State:queueing, SchemaState:none, SchemaID:12334, TableID:12664, RowCount:0, ArgLen:2, start time: 2024-05-31 19:25:21.796 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false; "] [table=true]
[2024/05/31 19:25:21.834 +08:00] [INFO] [ddl.go:1192] ["start DDL job"] [category=ddl] [job="ID:12665, Type:create table, State:queueing, SchemaState:none, SchemaID:12334, TableID:12664, RowCount:0, ArgLen:2, start time: 2024-05-31 19:25:21.796 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false"] [query="create table replace_test_1 (id int, c1 int);"]
[2024/05/31 19:25:21.868 +08:00] [INFO] [ddl_worker.go:1210] ["run DDL job"] [worker="worker 4, tp general"] [category=ddl] [jobID=12665] [conn=3227518040] [category=ddl] [job="ID:12665, Type:create table, State:queueing, SchemaState:none, SchemaID:12334, TableID:12664, RowCount:0, ArgLen:0, start time: 2024-05-31 19:25:21.796 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false"]
[2024/05/31 19:25:21.895 +08:00] [INFO] [ddl_worker.go:763] ["finish DDL job"] [worker="worker 4, tp general"] [category=ddl] [jobID=12665] [conn=3227518040] [job="ID:12665, Type:create table, State:synced, SchemaState:public, SchemaID:12334, TableID:12664, RowCount:0, ArgLen:0, start time: 2024-05-31 19:25:21.796 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false"]
[2024/05/31 19:25:21.899 +08:00] [INFO] [ddl.go:1298] ["DDL job is finished"] [category=ddl] [jobID=12665]
[2024/05/31 19:25:21.899 +08:00] [INFO] [callback.go:140] ["performing DDL change, must reload"] [category=ddl]
[2024/05/31 19:25:21.899 +08:00] [INFO] [split_region.go:85] ["split batch regions request"] ["split key count"=1] ["batch count"=1] ["first batch, region ID"=14] ["first split key"=748000000000003178]
[2024/05/31 19:25:21.900 +08:00] [INFO] [split_region.go:187] ["batch split regions complete"] ["batch region ID"=14] ["first at"=748000000000003178] ["first new region left"="{Id:10228 StartKey:7480000000000031ff7600000000000000f8 EndKey:7480000000000031ff7800000000000000f8 RegionEpoch:{ConfVer:1 Version:4874} Peers:[id:10229 store_id:1 ] EncryptionMeta:<nil> IsInFlashback:false FlashbackStartTs:0}"] ["new region count"=1]
[2024/05/31 19:25:21.900 +08:00] [INFO] [split_region.go:236] ["split regions complete"] ["region count"=1] ["region IDs"="[10228]"]
[2024/05/31 19:25:21.900 +08:00] [INFO] [domain.go:288] ["diff load InfoSchema success"] [isV2=false] [currentSchemaVersion=16521] [neededSchemaVersion=16522] ["start time"=606.854µs] [gotSchemaVersion=16522] [phyTblIDs="[12664]"] [actionTypes="[3]"] [diffTypes="[\"create table\"]"]
[2024/05/31 19:25:21.908 +08:00] [WARN] [session.go:2116] ["compile SQL failed"] [conn=3227518040] [session_alias=] [error="[planner:1136]Column count doesn't match value count at row 1"] [SQL="replace replace_test_1 select c1 from replace_test"]
[2024/05/31 19:25:21.908 +08:00] [INFO] [conn.go:1151] ["command dispatched failed"] [conn=3227518040] [session_alias=] [connInfo="id:3227518040, addr:127.0.0.1:53632 status:11, collation:utf8mb4_general_ci, user:root"] [command=Query] [status="inTxn:1, autocommit:1"] [sql="replace replace_test_1 select c1 from replace_test;"] [txn_mode=PESSIMISTIC] [timestamp=450141807416705035] [err="[planner:1136]Column count doesn't match value count at row 1\ngithub.com/pingcap/errors.AddStack\n\t/go/pkg/mod/github.com/pingcap/errors@v0.11.5-0.20240318064555-6bd07397691f/errors.go:178\ngithub.com/pingcap/errors.(*Error).GenWithStackByArgs\n\t/go/pkg/mod/github.com/pingcap/errors@v0.11.5-0.20240318064555-6bd07397691f/normalize.go:175\ngithub.com/pingcap/tidb/pkg/planner/core.(*PlanBuilder).buildSelectPlanOfInsert\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/planner/core/planbuilder.go:4001\ngithub.com/pingcap/tidb/pkg/planner/core.(*PlanBuilder).buildInsert\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/planner/core/planbuilder.go:3694\ngithub.com/pingcap/tidb/pkg/planner/core.(*PlanBuilder).Build\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/planner/core/planbuilder.go:494\ngithub.com/pingcap/tidb/pkg/planner.buildLogicalPlan\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/planner/optimize.go:549\ngithub.com/pingcap/tidb/pkg/planner.optimize\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/planner/optimize.go:466\ngithub.com/pingcap/tidb/pkg/planner.Optimize\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/planner/optimize.go:334\ngithub.com/pingcap/tidb/pkg/executor.(*Compiler).Compile\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/executor/compiler.go:99\ngithub.com/pingcap/tidb/pkg/session.(*session).ExecuteStmt\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/session/session.go:2094\ngithub.com/pingcap/tidb/pkg/server.(*TiDBContext).ExecuteStmt\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/server/driver_tidb.go:294\ngithub.com/pingcap/tidb/pkg/server.(*clientConn).handleStmt\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/server/conn.go:2021\ngithub.com/pingcap/tidb/pkg/server.(*clientConn).handleQuery\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/server/conn.go:1774\ngithub.com/pingcap/tidb/pkg/server.(*clientConn).dispatch\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/server/conn.go:1348\ngithub.com/pingcap/tidb/pkg/server.(*clientConn).Run\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/server/conn.go:1114\ngithub.com/pingcap/tidb/pkg/server.(*Server).onConn\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/server/server.go:739\nruntime.goexit\n\t/usr/local/go/src/runtime/asm_amd64.s:1650"]
[2024/05/31 19:25:21.908 +08:00] [INFO] [session.go:3959] ["CRUCIAL OPERATION"] [conn=3227518040] [schemaVersion=16522] [cur_db=executor__write] [sql="create table replace_test_2 (id int, c1 int);"] [user=root@%]
[2024/05/31 19:25:21.924 +08:00] [INFO] [ddl_worker.go:261] ["add DDL jobs"] [category=ddl] ["batch count"=1] [jobs="ID:12667, Type:create table, State:queueing, SchemaState:none, SchemaID:12334, TableID:12666, RowCount:0, ArgLen:2, start time: 2024-05-31 19:25:21.896 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false; "] [table=true]
[2024/05/31 19:25:21.924 +08:00] [INFO] [ddl.go:1192] ["start DDL job"] [category=ddl] [job="ID:12667, Type:create table, State:queueing, SchemaState:none, SchemaID:12334, TableID:12666, RowCount:0, ArgLen:2, start time: 2024-05-31 19:25:21.896 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false"] [query="create table replace_test_2 (id int, c1 int);"]
[2024/05/31 19:25:21.961 +08:00] [INFO] [ddl_worker.go:1210] ["run DDL job"] [worker="worker 4, tp general"] [category=ddl] [jobID=12667] [conn=3227518040] [category=ddl] [job="ID:12667, Type:create table, State:queueing, SchemaState:none, SchemaID:12334, TableID:12666, RowCount:0, ArgLen:0, start time: 2024-05-31 19:25:21.896 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false"]
[2024/05/31 19:25:21.989 +08:00] [INFO] [ddl_worker.go:763] ["finish DDL job"] [worker="worker 4, tp general"] [category=ddl] [jobID=12667] [conn=3227518040] [job="ID:12667, Type:create table, State:synced, SchemaState:public, SchemaID:12334, TableID:12666, RowCount:0, ArgLen:0, start time: 2024-05-31 19:25:21.896 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false"]
[2024/05/31 19:25:21.993 +08:00] [INFO] [ddl.go:1298] ["DDL job is finished"] [category=ddl] [jobID=12667]
[2024/05/31 19:25:21.993 +08:00] [INFO] [callback.go:140] ["performing DDL change, must reload"] [category=ddl]
[2024/05/31 19:25:21.993 +08:00] [INFO] [split_region.go:85] ["split batch regions request"] ["split key count"=1] ["batch count"=1] ["first batch, region ID"=14] ["first split key"=74800000000000317a]
[2024/05/31 19:25:21.994 +08:00] [INFO] [split_region.go:187] ["batch split regions complete"] ["batch region ID"=14] ["first at"=74800000000000317a] ["first new region left"="{Id:10230 StartKey:7480000000000031ff7800000000000000f8 EndKey:7480000000000031ff7a00000000000000f8 RegionEpoch:{ConfVer:1 Version:4875} Peers:[id:10231 store_id:1 ] EncryptionMeta:<nil> IsInFlashback:false FlashbackStartTs:0}"] ["new region count"=1]
[2024/05/31 19:25:21.994 +08:00] [INFO] [split_region.go:236] ["split regions complete"] ["region count"=1] ["region IDs"="[10230]"]
[2024/05/31 19:25:21.994 +08:00] [INFO] [domain.go:288] ["diff load InfoSchema success"] [isV2=false] [currentSchemaVersion=16522] [neededSchemaVersion=16523] ["start time"=635.654µs] [gotSchemaVersion=16523] [phyTblIDs="[12666]"] [actionTypes="[3]"] [diffTypes="[\"create table\"]"]
[2024/05/31 19:25:21.997 +08:00] [WARN] [prewrite.go:382] ["1pc failed and fallbacks to normal commit procedure"] [conn=3227518040] [session_alias=] [startTS=450141807429812245]
[2024/05/31 19:25:21.998 +08:00] [INFO] [session.go:3959] ["CRUCIAL OPERATION"] [conn=3227518040] [schemaVersion=16523] [cur_db=executor__write] [sql="drop table if exists replace_test_3;"] [user=root@%]
[2024/05/31 19:25:21.999 +08:00] [INFO] [session.go:3959] ["CRUCIAL OPERATION"] [conn=3227518040] [schemaVersion=16523] [cur_db=executor__write] [sql="create table replace_test_3 (c1 int, c2 int, UNIQUE INDEX (c2));"] [user=root@%]
[2024/05/31 19:25:22.015 +08:00] [INFO] [ddl_worker.go:261] ["add DDL jobs"] [category=ddl] ["batch count"=1] [jobs="ID:12669, Type:create table, State:queueing, SchemaState:none, SchemaID:12334, TableID:12668, RowCount:0, ArgLen:2, start time: 2024-05-31 19:25:21.996 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false; "] [table=true]
[2024/05/31 19:25:22.015 +08:00] [INFO] [ddl.go:1192] ["start DDL job"] [category=ddl] [job="ID:12669, Type:create table, State:queueing, SchemaState:none, SchemaID:12334, TableID:12668, RowCount:0, ArgLen:2, start time: 2024-05-31 19:25:21.996 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false"] [query="create table replace_test_3 (c1 int, c2 int, UNIQUE INDEX (c2));"]
[2024/05/31 19:25:22.052 +08:00] [INFO] [ddl_worker.go:1210] ["run DDL job"] [worker="worker 4, tp general"] [category=ddl] [jobID=12669] [conn=3227518040] [category=ddl] [job="ID:12669, Type:create table, State:queueing, SchemaState:none, SchemaID:12334, TableID:12668, RowCount:0, ArgLen:0, start time: 2024-05-31 19:25:21.996 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false"]
[2024/05/31 19:25:22.078 +08:00] [INFO] [ddl_worker.go:763] ["finish DDL job"] [worker="worker 4, tp general"] [category=ddl] [jobID=12669] [conn=3227518040] [job="ID:12669, Type:create table, State:synced, SchemaState:public, SchemaID:12334, TableID:12668, RowCount:0, ArgLen:0, start time: 2024-05-31 19:25:21.996 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false"]
[2024/05/31 19:25:22.082 +08:00] [INFO] [ddl.go:1298] ["DDL job is finished"] [category=ddl] [jobID=12669]
[2024/05/31 19:25:22.082 +08:00] [INFO] [callback.go:140] ["performing DDL change, must reload"] [category=ddl]
[2024/05/31 19:25:22.082 +08:00] [INFO] [split_region.go:85] ["split batch regions request"] ["split key count"=1] ["batch count"=1] ["first batch, region ID"=14] ["first split key"=74800000000000317c]
[2024/05/31 19:25:22.083 +08:00] [INFO] [split_region.go:187] ["batch split regions complete"] ["batch region ID"=14] ["first at"=74800000000000317c] ["first new region left"="{Id:10232 StartKey:7480000000000031ff7a00000000000000f8 EndKey:7480000000000031ff7c00000000000000f8 RegionEpoch:{ConfVer:1 Version:4876} Peers:[id:10233 store_id:1 ] EncryptionMeta:<nil> IsInFlashback:false FlashbackStartTs:0}"] ["new region count"=1]
[2024/05/31 19:25:22.083 +08:00] [INFO] [split_region.go:236] ["split regions complete"] ["region count"=1] ["region IDs"="[10232]"]
[2024/05/31 19:25:22.083 +08:00] [INFO] [domain.go:288] ["diff load InfoSchema success"] [isV2=false] [currentSchemaVersion=16523] [neededSchemaVersion=16524] ["start time"=605.428µs] [gotSchemaVersion=16524] [phyTblIDs="[12668]"] [actionTypes="[3]"] [diffTypes="[\"create table\"]"]
[2024/05/31 19:25:22.092 +08:00] [WARN] [prewrite.go:382] ["1pc failed and fallbacks to normal commit procedure"] [conn=3227518040] [session_alias=] [startTS=450141807455764505]
[2024/05/31 19:25:22.094 +08:00] [WARN] [prewrite.go:382] ["1pc failed and fallbacks to normal commit procedure"] [conn=3227518040] [session_alias=] [startTS=450141807455764507]
[2024/05/31 19:25:22.095 +08:00] [WARN] [prewrite.go:382] ["1pc failed and fallbacks to normal commit procedure"] [conn=3227518040] [session_alias=] [startTS=450141807455764509]
[2024/05/31 19:25:22.095 +08:00] [INFO] [session.go:3959] ["CRUCIAL OPERATION"] [conn=3227518040] [schemaVersion=16524] [cur_db=executor__write] [sql="drop table if exists replace_test_4;"] [user=root@%]
[2024/05/31 19:25:22.096 +08:00] [INFO] [session.go:3959] ["CRUCIAL OPERATION"] [conn=3227518040] [schemaVersion=16524] [cur_db=executor__write] [sql="create table replace_test_4 (c1 int, c2 int, c3 int, UNIQUE INDEX (c1, c2));"] [user=root@%]
[2024/05/31 19:25:22.113 +08:00] [INFO] [ddl_worker.go:261] ["add DDL jobs"] [category=ddl] ["batch count"=1] [jobs="ID:12671, Type:create table, State:queueing, SchemaState:none, SchemaID:12334, TableID:12670, RowCount:0, ArgLen:2, start time: 2024-05-31 19:25:22.096 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false; "] [table=true]
[2024/05/31 19:25:22.113 +08:00] [INFO] [ddl.go:1192] ["start DDL job"] [category=ddl] [job="ID:12671, Type:create table, State:queueing, SchemaState:none, SchemaID:12334, TableID:12670, RowCount:0, ArgLen:2, start time: 2024-05-31 19:25:22.096 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false"] [query="create table replace_test_4 (c1 int, c2 int, c3 int, UNIQUE INDEX (c1, c2));"]
[2024/05/31 19:25:22.144 +08:00] [INFO] [ddl_worker.go:1210] ["run DDL job"] [worker="worker 4, tp general"] [category=ddl] [jobID=12671] [conn=3227518040] [category=ddl] [job="ID:12671, Type:create table, State:queueing, SchemaState:none, SchemaID:12334, TableID:12670, RowCount:0, ArgLen:0, start time: 2024-05-31 19:25:22.096 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false"]
[2024/05/31 19:25:22.175 +08:00] [INFO] [ddl_worker.go:763] ["finish DDL job"] [worker="worker 4, tp general"] [category=ddl] [jobID=12671] [conn=3227518040] [job="ID:12671, Type:create table, State:synced, SchemaState:public, SchemaID:12334, TableID:12670, RowCount:0, ArgLen:0, start time: 2024-05-31 19:25:22.096 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false"]
[2024/05/31 19:25:22.179 +08:00] [INFO] [ddl.go:1298] ["DDL job is finished"] [category=ddl] [jobID=12671]
[2024/05/31 19:25:22.179 +08:00] [INFO] [callback.go:140] ["performing DDL change, must reload"] [category=ddl]
[2024/05/31 19:25:22.179 +08:00] [INFO] [split_region.go:85] ["split batch regions request"] ["split key count"=1] ["batch count"=1] ["first batch, region ID"=14] ["first split key"=74800000000000317e]
[2024/05/31 19:25:22.180 +08:00] [INFO] [split_region.go:187] ["batch split regions complete"] ["batch region ID"=14] ["first at"=74800000000000317e] ["first new region left"="{Id:10234 StartKey:7480000000000031ff7c00000000000000f8 EndKey:7480000000000031ff7e00000000000000f8 RegionEpoch:{ConfVer:1 Version:4877} Peers:[id:10235 store_id:1 ] EncryptionMeta:<nil> IsInFlashback:false FlashbackStartTs:0}"] ["new region count"=1]
[2024/05/31 19:25:22.180 +08:00] [INFO] [split_region.go:236] ["split regions complete"] ["region count"=1] ["region IDs"="[10234]"]
[2024/05/31 19:25:22.180 +08:00] [INFO] [domain.go:288] ["diff load InfoSchema success"] [isV2=false] [currentSchemaVersion=16524] [neededSchemaVersion=16525] ["start time"=644.523µs] [gotSchemaVersion=16525] [phyTblIDs="[12670]"] [actionTypes="[3]"] [diffTypes="[\"create table\"]"]
[2024/05/31 19:25:22.185 +08:00] [WARN] [prewrite.go:382] ["1pc failed and fallbacks to normal commit procedure"] [conn=3227518040] [session_alias=] [startTS=450141807482241040]
[2024/05/31 19:25:22.186 +08:00] [INFO] [session.go:3959] ["CRUCIAL OPERATION"] [conn=3227518040] [schemaVersion=16525] [cur_db=executor__write] [sql="drop table if exists replace_test_5;"] [user=root@%]
[2024/05/31 19:25:22.186 +08:00] [INFO] [session.go:3959] ["CRUCIAL OPERATION"] [conn=3227518040] [schemaVersion=16525] [cur_db=executor__write] [sql="create table replace_test_5 (c1 int, c2 int, c3 int, PRIMARY KEY (c1, c2));"] [user=root@%]
[2024/05/31 19:25:22.203 +08:00] [INFO] [ddl_worker.go:261] ["add DDL jobs"] [category=ddl] ["batch count"=1] [jobs="ID:12673, Type:create table, State:queueing, SchemaState:none, SchemaID:12334, TableID:12672, RowCount:0, ArgLen:2, start time: 2024-05-31 19:25:22.196 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false; "] [table=true]
[2024/05/31 19:25:22.203 +08:00] [INFO] [ddl.go:1192] ["start DDL job"] [category=ddl] [job="ID:12673, Type:create table, State:queueing, SchemaState:none, SchemaID:12334, TableID:12672, RowCount:0, ArgLen:2, start time: 2024-05-31 19:25:22.196 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false"] [query="create table replace_test_5 (c1 int, c2 int, c3 int, PRIMARY KEY (c1, c2));"]
[2024/05/31 19:25:22.243 +08:00] [INFO] [ddl_worker.go:1210] ["run DDL job"] [worker="worker 4, tp general"] [category=ddl] [jobID=12673] [conn=3227518040] [category=ddl] [job="ID:12673, Type:create table, State:queueing, SchemaState:none, SchemaID:12334, TableID:12672, RowCount:0, ArgLen:0, start time: 2024-05-31 19:25:22.196 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false"]
[2024/05/31 19:25:22.273 +08:00] [INFO] [ddl_worker.go:763] ["finish DDL job"] [worker="worker 4, tp general"] [category=ddl] [jobID=12673] [conn=3227518040] [job="ID:12673, Type:create table, State:synced, SchemaState:public, SchemaID:12334, TableID:12672, RowCount:0, ArgLen:0, start time: 2024-05-31 19:25:22.196 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false"]
[2024/05/31 19:25:22.277 +08:00] [INFO] [ddl.go:1298] ["DDL job is finished"] [category=ddl] [jobID=12673]
[2024/05/31 19:25:22.277 +08:00] [INFO] [callback.go:140] ["performing DDL change, must reload"] [category=ddl]
[2024/05/31 19:25:22.277 +08:00] [INFO] [split_region.go:85] ["split batch regions request"] ["split key count"=1] ["batch count"=1] ["first batch, region ID"=14] ["first split key"=748000000000003180]
[2024/05/31 19:25:22.278 +08:00] [INFO] [split_region.go:187] ["batch split regions complete"] ["batch region ID"=14] ["first at"=748000000000003180] ["first new region left"="{Id:10236 StartKey:7480000000000031ff7e00000000000000f8 EndKey:7480000000000031ff8000000000000000f8 RegionEpoch:{ConfVer:1 Version:4878} Peers:[id:10237 store_id:1 ] EncryptionMeta:<nil> IsInFlashback:false FlashbackStartTs:0}"] ["new region count"=1]
[2024/05/31 19:25:22.278 +08:00] [INFO] [split_region.go:236] ["split regions complete"] ["region count"=1] ["region IDs"="[10236]"]
[2024/05/31 19:25:22.278 +08:00] [INFO] [domain.go:288] ["diff load InfoSchema success"] [isV2=false] [currentSchemaVersion=16525] [neededSchemaVersion=16526] ["start time"=525.939µs] [gotSchemaVersion=16526] [phyTblIDs="[12672]"] [actionTypes="[3]"] [diffTypes="[\"create table\"]"]
[2024/05/31 19:25:22.283 +08:00] [INFO] [session.go:3959] ["CRUCIAL OPERATION"] [conn=3227518040] [schemaVersion=16526] [cur_db=executor__write] [sql="drop table if exists tIssue989;"] [user=root@%]
[2024/05/31 19:25:22.283 +08:00] [INFO] [session.go:3959] ["CRUCIAL OPERATION"] [conn=3227518040] [schemaVersion=16526] [cur_db=executor__write] [sql="CREATE TABLE tIssue989 (a int, b int, PRIMARY KEY(a), UNIQUE KEY(b));"] [user=root@%]
[2024/05/31 19:25:22.301 +08:00] [INFO] [ddl_worker.go:261] ["add DDL jobs"] [category=ddl] ["batch count"=1] [jobs="ID:12675, Type:create table, State:queueing, SchemaState:none, SchemaID:12334, TableID:12674, RowCount:0, ArgLen:2, start time: 2024-05-31 19:25:22.296 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false; "] [table=true]
[2024/05/31 19:25:22.301 +08:00] [INFO] [ddl.go:1192] ["start DDL job"] [category=ddl] [job="ID:12675, Type:create table, State:queueing, SchemaState:none, SchemaID:12334, TableID:12674, RowCount:0, ArgLen:2, start time: 2024-05-31 19:25:22.296 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false"] [query="CREATE TABLE tIssue989 (a int, b int, PRIMARY KEY(a), UNIQUE KEY(b));"]
[2024/05/31 19:25:22.340 +08:00] [INFO] [ddl_worker.go:1210] ["run DDL job"] [worker="worker 4, tp general"] [category=ddl] [jobID=12675] [conn=3227518040] [category=ddl] [job="ID:12675, Type:create table, State:queueing, SchemaState:none, SchemaID:12334, TableID:12674, RowCount:0, ArgLen:0, start time: 2024-05-31 19:25:22.296 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false"]
[2024/05/31 19:25:22.377 +08:00] [INFO] [ddl_worker.go:763] ["finish DDL job"] [worker="worker 4, tp general"] [category=ddl] [jobID=12675] [conn=3227518040] [job="ID:12675, Type:create table, State:synced, SchemaState:public, SchemaID:12334, TableID:12674, RowCount:0, ArgLen:0, start time: 2024-05-31 19:25:22.296 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false"]
[2024/05/31 19:25:22.381 +08:00] [INFO] [ddl.go:1298] ["DDL job is finished"] [category=ddl] [jobID=12675]
[2024/05/31 19:25:22.381 +08:00] [INFO] [callback.go:140] ["performing DDL change, must reload"] [category=ddl]
[2024/05/31 19:25:22.381 +08:00] [INFO] [split_region.go:85] ["split batch regions request"] ["split key count"=1] ["batch count"=1] ["first batch, region ID"=14] ["first split key"=748000000000003182]
[2024/05/31 19:25:22.382 +08:00] [INFO] [split_region.go:187] ["batch split regions complete"] ["batch region ID"=14] ["first at"=748000000000003182] ["first new region left"="{Id:10238 StartKey:7480000000000031ff8000000000000000f8 EndKey:7480000000000031ff8200000000000000f8 RegionEpoch:{ConfVer:1 Version:4879} Peers:[id:10239 store_id:1 ] EncryptionMeta:<nil> IsInFlashback:false FlashbackStartTs:0}"] ["new region count"=1]
[2024/05/31 19:25:22.382 +08:00] [INFO] [split_region.go:236] ["split regions complete"] ["region count"=1] ["region IDs"="[10238]"]
[2024/05/31 19:25:22.382 +08:00] [INFO] [domain.go:288] ["diff load InfoSchema success"] [isV2=false] [currentSchemaVersion=16526] [neededSchemaVersion=16527] ["start time"=549.517µs] [gotSchemaVersion=16527] [phyTblIDs="[12674]"] [actionTypes="[3]"] [diffTypes="[\"create table\"]"]
[2024/05/31 19:25:22.384 +08:00] [WARN] [prewrite.go:382] ["1pc failed and fallbacks to normal commit procedure"] [conn=3227518040] [session_alias=] [startTS=450141807534407692]
[2024/05/31 19:25:22.386 +08:00] [WARN] [prewrite.go:382] ["1pc failed and fallbacks to normal commit procedure"] [conn=3227518040] [session_alias=] [startTS=450141807534407694]
[2024/05/31 19:25:22.388 +08:00] [INFO] [session.go:3959] ["CRUCIAL OPERATION"] [conn=3227518040] [schemaVersion=16527] [cur_db=executor__write] [sql="drop table if exists tIssue1012;"] [user=root@%]
[2024/05/31 19:25:22.388 +08:00] [INFO] [session.go:3959] ["CRUCIAL OPERATION"] [conn=3227518040] [schemaVersion=16527] [cur_db=executor__write] [sql="CREATE TABLE tIssue1012 (a int, b int, PRIMARY KEY(a), UNIQUE KEY(b));"] [user=root@%]
[2024/05/31 19:25:22.406 +08:00] [INFO] [ddl_worker.go:261] ["add DDL jobs"] [category=ddl] ["batch count"=1] [jobs="ID:12677, Type:create table, State:queueing, SchemaState:none, SchemaID:12334, TableID:12676, RowCount:0, ArgLen:2, start time: 2024-05-31 19:25:22.395 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false; "] [table=true]
[2024/05/31 19:25:22.406 +08:00] [INFO] [ddl.go:1192] ["start DDL job"] [category=ddl] [job="ID:12677, Type:create table, State:queueing, SchemaState:none, SchemaID:12334, TableID:12676, RowCount:0, ArgLen:2, start time: 2024-05-31 19:25:22.395 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false"] [query="CREATE TABLE tIssue1012 (a int, b int, PRIMARY KEY(a), UNIQUE KEY(b));"]
[2024/05/31 19:25:22.447 +08:00] [INFO] [ddl_worker.go:1210] ["run DDL job"] [worker="worker 4, tp general"] [category=ddl] [jobID=12677] [conn=3227518040] [category=ddl] [job="ID:12677, Type:create table, State:queueing, SchemaState:none, SchemaID:12334, TableID:12676, RowCount:0, ArgLen:0, start time: 2024-05-31 19:25:22.395 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false"]
[2024/05/31 19:25:22.475 +08:00] [INFO] [ddl_worker.go:763] ["finish DDL job"] [worker="worker 4, tp general"] [category=ddl] [jobID=12677] [conn=3227518040] [job="ID:12677, Type:create table, State:synced, SchemaState:public, SchemaID:12334, TableID:12676, RowCount:0, ArgLen:0, start time: 2024-05-31 19:25:22.395 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false"]
[2024/05/31 19:25:22.479 +08:00] [INFO] [ddl.go:1298] ["DDL job is finished"] [category=ddl] [jobID=12677]
[2024/05/31 19:25:22.479 +08:00] [INFO] [callback.go:140] ["performing DDL change, must reload"] [category=ddl]
[2024/05/31 19:25:22.479 +08:00] [INFO] [split_region.go:85] ["split batch regions request"] ["split key count"=1] ["batch count"=1] ["first batch, region ID"=14] ["first split key"=748000000000003184]
[2024/05/31 19:25:22.480 +08:00] [INFO] [split_region.go:187] ["batch split regions complete"] ["batch region ID"=14] ["first at"=748000000000003184] ["first new region left"="{Id:10240 StartKey:7480000000000031ff8200000000000000f8 EndKey:7480000000000031ff8400000000000000f8 RegionEpoch:{ConfVer:1 Version:4880} Peers:[id:10241 store_id:1 ] EncryptionMeta:<nil> IsInFlashback:false FlashbackStartTs:0}"] ["new region count"=1]
[2024/05/31 19:25:22.480 +08:00] [INFO] [split_region.go:236] ["split regions complete"] ["region count"=1] ["region IDs"="[10240]"]
[2024/05/31 19:25:22.480 +08:00] [INFO] [domain.go:288] ["diff load InfoSchema success"] [isV2=false] [currentSchemaVersion=16527] [neededSchemaVersion=16528] ["start time"=573.96µs] [gotSchemaVersion=16528] [phyTblIDs="[12676]"] [actionTypes="[3]"] [diffTypes="[\"create table\"]"]
[2024/05/31 19:25:22.482 +08:00] [WARN] [prewrite.go:382] ["1pc failed and fallbacks to normal commit procedure"] [conn=3227518040] [session_alias=] [startTS=450141807560884244]
[2024/05/31 19:25:22.484 +08:00] [WARN] [prewrite.go:382] ["1pc failed and fallbacks to normal commit procedure"] [conn=3227518040] [session_alias=] [startTS=450141807560884246]
[2024/05/31 19:25:22.485 +08:00] [WARN] [prewrite.go:382] ["1pc failed and fallbacks to normal commit procedure"] [conn=3227518040] [session_alias=] [startTS=450141807560884248]
[2024/05/31 19:25:22.487 +08:00] [INFO] [session.go:3959] ["CRUCIAL OPERATION"] [conn=3227518040] [schemaVersion=16528] [cur_db=executor__write] [sql="drop table if exists t1;"] [user=root@%]
[2024/05/31 19:25:22.487 +08:00] [INFO] [session.go:3959] ["CRUCIAL OPERATION"] [conn=3227518040] [schemaVersion=16528] [cur_db=executor__write] [sql="create table t1(a int primary key, b int);"] [user=root@%]
[2024/05/31 19:25:22.509 +08:00] [INFO] [ddl_worker.go:261] ["add DDL jobs"] [category=ddl] ["batch count"=1] [jobs="ID:12679, Type:create table, State:queueing, SchemaState:none, SchemaID:12334, TableID:12678, RowCount:0, ArgLen:2, start time: 2024-05-31 19:25:22.496 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false; "] [table=true]
[2024/05/31 19:25:22.509 +08:00] [INFO] [ddl.go:1192] ["start DDL job"] [category=ddl] [job="ID:12679, Type:create table, State:queueing, SchemaState:none, SchemaID:12334, TableID:12678, RowCount:0, ArgLen:2, start time: 2024-05-31 19:25:22.496 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false"] [query="create table t1(a int primary key, b int);"]
[2024/05/31 19:25:22.553 +08:00] [INFO] [ddl_worker.go:1210] ["run DDL job"] [worker="worker 4, tp general"] [category=ddl] [jobID=12679] [conn=3227518040] [category=ddl] [job="ID:12679, Type:create table, State:queueing, SchemaState:none, SchemaID:12334, TableID:12678, RowCount:0, ArgLen:0, start time: 2024-05-31 19:25:22.496 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false"]
[2024/05/31 19:25:22.585 +08:00] [INFO] [ddl_worker.go:763] ["finish DDL job"] [worker="worker 4, tp general"] [category=ddl] [jobID=12679] [conn=3227518040] [job="ID:12679, Type:create table, State:synced, SchemaState:public, SchemaID:12334, TableID:12678, RowCount:0, ArgLen:0, start time: 2024-05-31 19:25:22.496 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false"]
[2024/05/31 19:25:22.589 +08:00] [INFO] [ddl.go:1298] ["DDL job is finished"] [category=ddl] [jobID=12679]
[2024/05/31 19:25:22.589 +08:00] [INFO] [callback.go:140] ["performing DDL change, must reload"] [category=ddl]
[2024/05/31 19:25:22.589 +08:00] [INFO] [split_region.go:85] ["split batch regions request"] ["split key count"=1] ["batch count"=1] ["first batch, region ID"=14] ["first split key"=748000000000003186]
[2024/05/31 19:25:22.590 +08:00] [INFO] [split_region.go:187] ["batch split regions complete"] ["batch region ID"=14] ["first at"=748000000000003186] ["first new region left"="{Id:10242 StartKey:7480000000000031ff8400000000000000f8 EndKey:7480000000000031ff8600000000000000f8 RegionEpoch:{ConfVer:1 Version:4881} Peers:[id:10243 store_id:1 ] EncryptionMeta:<nil> IsInFlashback:false FlashbackStartTs:0}"] ["new region count"=1]
[2024/05/31 19:25:22.590 +08:00] [INFO] [split_region.go:236] ["split regions complete"] ["region count"=1] ["region IDs"="[10242]"]
[2024/05/31 19:25:22.591 +08:00] [INFO] [domain.go:288] ["diff load InfoSchema success"] [isV2=false] [currentSchemaVersion=16528] [neededSchemaVersion=16529] ["start time"=578.284µs] [gotSchemaVersion=16529] [phyTblIDs="[12678]"] [actionTypes="[3]"] [diffTypes="[\"create table\"]"]
[2024/05/31 19:25:22.593 +08:00] [WARN] [prewrite.go:382] ["1pc failed and fallbacks to normal commit procedure"] [conn=3227518040] [session_alias=] [startTS=450141807587098643]
[2024/05/31 19:25:22.596 +08:00] [WARN] [prewrite.go:382] ["1pc failed and fallbacks to normal commit procedure"] [conn=3227518040] [session_alias=] [startTS=450141807587098647]
[2024/05/31 19:25:22.598 +08:00] [WARN] [prewrite.go:382] ["1pc failed and fallbacks to normal commit procedure"] [conn=3227518040] [session_alias=] [startTS=450141807600205825]
[2024/05/31 19:25:22.599 +08:00] [INFO] [session.go:3959] ["CRUCIAL OPERATION"] [conn=3227518040] [schemaVersion=16529] [cur_db=executor__write] [sql="drop table if exists t1, t2;"] [user=root@%]
[2024/05/31 19:25:22.616 +08:00] [INFO] [ddl_worker.go:261] ["add DDL jobs"] [category=ddl] ["batch count"=1] [jobs="ID:12680, Type:drop table, State:queueing, SchemaState:public, SchemaID:12334, TableID:12678, RowCount:0, ArgLen:2, start time: 2024-05-31 19:25:22.596 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false; "] [table=true]
[2024/05/31 19:25:22.616 +08:00] [INFO] [ddl.go:1192] ["start DDL job"] [category=ddl] [job="ID:12680, Type:drop table, State:queueing, SchemaState:public, SchemaID:12334, TableID:12678, RowCount:0, ArgLen:2, start time: 2024-05-31 19:25:22.596 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false"] [query="drop table if exists t1, t2;"]
[2024/05/31 19:25:22.650 +08:00] [INFO] [ddl_worker.go:1210] ["run DDL job"] [worker="worker 4, tp general"] [category=ddl] [jobID=12680] [conn=3227518040] [category=ddl] [job="ID:12680, Type:drop table, State:queueing, SchemaState:public, SchemaID:12334, TableID:12678, RowCount:0, ArgLen:0, start time: 2024-05-31 19:25:22.596 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false"]
[2024/05/31 19:25:22.676 +08:00] [INFO] [ddl_worker.go:1210] ["run DDL job"] [worker="worker 4, tp general"] [category=ddl] [jobID=12680] [conn=3227518040] [category=ddl] [job="ID:12680, Type:drop table, State:running, SchemaState:write only, SchemaID:12334, TableID:12678, RowCount:0, ArgLen:0, start time: 2024-05-31 19:25:22.596 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false"]
[2024/05/31 19:25:22.701 +08:00] [INFO] [ddl_worker.go:1210] ["run DDL job"] [worker="worker 4, tp general"] [category=ddl] [jobID=12680] [conn=3227518040] [category=ddl] [job="ID:12680, Type:drop table, State:running, SchemaState:delete only, SchemaID:12334, TableID:12678, RowCount:0, ArgLen:0, start time: 2024-05-31 19:25:22.596 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false"]
[2024/05/31 19:25:22.727 +08:00] [INFO] [delete_range.go:447] ["insert into delete-range table"] [category=ddl] [jobID=12680] [tableIDs="[12678]"] [comment="drop table: table ID"]
[2024/05/31 19:25:22.729 +08:00] [INFO] [delete_range.go:112] ["add job into delete-range table"] [category=ddl] [jobID=12680] [jobType="drop table"]
[2024/05/31 19:25:22.729 +08:00] [INFO] [ddl_worker.go:763] ["finish DDL job"] [worker="worker 4, tp general"] [category=ddl] [jobID=12680] [conn=3227518040] [job="ID:12680, Type:drop table, State:synced, SchemaState:none, SchemaID:12334, TableID:12678, RowCount:0, ArgLen:3, start time: 2024-05-31 19:25:22.596 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false"]
[2024/05/31 19:25:22.733 +08:00] [INFO] [ddl.go:1298] ["DDL job is finished"] [category=ddl] [jobID=12680]
[2024/05/31 19:25:22.733 +08:00] [INFO] [callback.go:140] ["performing DDL change, must reload"] [category=ddl]
[2024/05/31 19:25:22.734 +08:00] [INFO] [domain.go:288] ["diff load InfoSchema success"] [isV2=false] [currentSchemaVersion=16529] [neededSchemaVersion=16532] ["start time"=561.605µs] [gotSchemaVersion=16532] [phyTblIDs="[12678]"] [actionTypes="[4]"] [diffTypes="[\"drop table\",\"drop table\",\"drop table\"]"]
[2024/05/31 19:25:22.736 +08:00] [INFO] [session.go:3959] ["CRUCIAL OPERATION"] [conn=3227518040] [schemaVersion=16532] [cur_db=executor__write] [sql="create table t1 (a int primary key, b int default 20, c int default 30);"] [user=root@%]
[2024/05/31 19:25:22.753 +08:00] [INFO] [ddl_worker.go:261] ["add DDL jobs"] [category=ddl] ["batch count"=1] [jobs="ID:12682, Type:create table, State:queueing, SchemaState:none, SchemaID:12334, TableID:12681, RowCount:0, ArgLen:2, start time: 2024-05-31 19:25:22.746 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false; "] [table=true]
[2024/05/31 19:25:22.753 +08:00] [INFO] [ddl.go:1192] ["start DDL job"] [category=ddl] [job="ID:12682, Type:create table, State:queueing, SchemaState:none, SchemaID:12334, TableID:12681, RowCount:0, ArgLen:2, start time: 2024-05-31 19:25:22.746 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false"] [query="create table t1 (a int primary key, b int default 20, c int default 30);"]
[2024/05/31 19:25:22.792 +08:00] [INFO] [ddl_worker.go:1210] ["run DDL job"] [worker="worker 4, tp general"] [category=ddl] [jobID=12682] [conn=3227518040] [category=ddl] [job="ID:12682, Type:create table, State:queueing, SchemaState:none, SchemaID:12334, TableID:12681, RowCount:0, ArgLen:0, start time: 2024-05-31 19:25:22.746 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false"]
[2024/05/31 19:25:22.819 +08:00] [INFO] [ddl_worker.go:763] ["finish DDL job"] [worker="worker 4, tp general"] [category=ddl] [jobID=12682] [conn=3227518040] [job="ID:12682, Type:create table, State:synced, SchemaState:public, SchemaID:12334, TableID:12681, RowCount:0, ArgLen:0, start time: 2024-05-31 19:25:22.746 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false"]
[2024/05/31 19:25:22.822 +08:00] [INFO] [ddl.go:1298] ["DDL job is finished"] [category=ddl] [jobID=12682]
[2024/05/31 19:25:22.822 +08:00] [INFO] [callback.go:140] ["performing DDL change, must reload"] [category=ddl]
[2024/05/31 19:25:22.822 +08:00] [INFO] [split_region.go:85] ["split batch regions request"] ["split key count"=1] ["batch count"=1] ["first batch, region ID"=14] ["first split key"=748000000000003189]
[2024/05/31 19:25:22.823 +08:00] [INFO] [split_region.go:187] ["batch split regions complete"] ["batch region ID"=14] ["first at"=748000000000003189] ["first new region left"="{Id:10244 StartKey:7480000000000031ff8600000000000000f8 EndKey:7480000000000031ff8900000000000000f8 RegionEpoch:{ConfVer:1 Version:4882} Peers:[id:10245 store_id:1 ] EncryptionMeta:<nil> IsInFlashback:false FlashbackStartTs:0}"] ["new region count"=1]
[2024/05/31 19:25:22.823 +08:00] [INFO] [split_region.go:236] ["split regions complete"] ["region count"=1] ["region IDs"="[10244]"]
[2024/05/31 19:25:22.824 +08:00] [INFO] [domain.go:288] ["diff load InfoSchema success"] [isV2=false] [currentSchemaVersion=16532] [neededSchemaVersion=16533] ["start time"=615.199µs] [gotSchemaVersion=16533] [phyTblIDs="[12681]"] [actionTypes="[3]"] [diffTypes="[\"create table\"]"]
[2024/05/31 19:25:22.826 +08:00] [WARN] [prewrite.go:382] ["1pc failed and fallbacks to normal commit procedure"] [conn=3227518040] [session_alias=] [startTS=450141807652634637]
[2024/05/31 19:25:22.828 +08:00] [WARN] [prewrite.go:382] ["1pc failed and fallbacks to normal commit procedure"] [conn=3227518040] [session_alias=] [startTS=450141807652634639]
[2024/05/31 19:25:22.831 +08:00] [WARN] [prewrite.go:382] ["1pc failed and fallbacks to normal commit procedure"] [conn=3227518040] [session_alias=] [startTS=450141807652634642]
[2024/05/31 19:25:22.834 +08:00] [WARN] [prewrite.go:382] ["1pc failed and fallbacks to normal commit procedure"] [conn=3227518040] [session_alias=] [startTS=450141807652634646]
[2024/05/31 19:25:22.836 +08:00] [WARN] [prewrite.go:382] ["1pc failed and fallbacks to normal commit procedure"] [conn=3227518040] [session_alias=] [startTS=450141807652634649]
[2024/05/31 19:25:22.838 +08:00] [INFO] [session.go:3959] ["CRUCIAL OPERATION"] [conn=3227518040] [schemaVersion=16533] [cur_db=executor__write] [sql="create table t2 (pk int primary key, a int default 1, b int generated always as (-a) virtual, c int generated always as (-a) stored);"] [user=root@%]
[2024/05/31 19:25:22.854 +08:00] [INFO] [ddl_worker.go:261] ["add DDL jobs"] [category=ddl] ["batch count"=1] [jobs="ID:12684, Type:create table, State:queueing, SchemaState:none, SchemaID:12334, TableID:12683, RowCount:0, ArgLen:2, start time: 2024-05-31 19:25:22.846 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false; "] [table=true]
[2024/05/31 19:25:22.854 +08:00] [INFO] [ddl.go:1192] ["start DDL job"] [category=ddl] [job="ID:12684, Type:create table, State:queueing, SchemaState:none, SchemaID:12334, TableID:12683, RowCount:0, ArgLen:2, start time: 2024-05-31 19:25:22.846 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false"] [query="create table t2 (pk int primary key, a int default 1, b int generated always as (-a) virtual, c int generated always as (-a) stored);"]
[2024/05/31 19:25:22.882 +08:00] [INFO] [ddl_worker.go:1210] ["run DDL job"] [worker="worker 4, tp general"] [category=ddl] [jobID=12684] [conn=3227518040] [category=ddl] [job="ID:12684, Type:create table, State:queueing, SchemaState:none, SchemaID:12334, TableID:12683, RowCount:0, ArgLen:0, start time: 2024-05-31 19:25:22.846 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false"]
[2024/05/31 19:25:22.908 +08:00] [INFO] [ddl_worker.go:763] ["finish DDL job"] [worker="worker 4, tp general"] [category=ddl] [jobID=12684] [conn=3227518040] [job="ID:12684, Type:create table, State:synced, SchemaState:public, SchemaID:12334, TableID:12683, RowCount:0, ArgLen:0, start time: 2024-05-31 19:25:22.846 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false"]
[2024/05/31 19:25:22.912 +08:00] [INFO] [ddl.go:1298] ["DDL job is finished"] [category=ddl] [jobID=12684]
[2024/05/31 19:25:22.912 +08:00] [INFO] [callback.go:140] ["performing DDL change, must reload"] [category=ddl]
[2024/05/31 19:25:22.912 +08:00] [INFO] [split_region.go:85] ["split batch regions request"] ["split key count"=1] ["batch count"=1] ["first batch, region ID"=14] ["first split key"=74800000000000318b]
[2024/05/31 19:25:22.915 +08:00] [INFO] [split_region.go:187] ["batch split regions complete"] ["batch region ID"=14] ["first at"=74800000000000318b] ["first new region left"="{Id:10246 StartKey:7480000000000031ff8900000000000000f8 EndKey:7480000000000031ff8b00000000000000f8 RegionEpoch:{ConfVer:1 Version:4883} Peers:[id:10247 store_id:1 ] EncryptionMeta:<nil> IsInFlashback:false FlashbackStartTs:0}"] ["new region count"=1]
[2024/05/31 19:25:22.915 +08:00] [INFO] [split_region.go:236] ["split regions complete"] ["region count"=1] ["region IDs"="[10246]"]
[2024/05/31 19:25:22.915 +08:00] [INFO] [domain.go:288] ["diff load InfoSchema success"] [isV2=false] [currentSchemaVersion=16533] [neededSchemaVersion=16534] ["start time"=1.232261ms] [gotSchemaVersion=16534] [phyTblIDs="[12683]"] [actionTypes="[3]"] [diffTypes="[\"create table\"]"]
[2024/05/31 19:25:22.918 +08:00] [WARN] [prewrite.go:382] ["1pc failed and fallbacks to normal commit procedure"] [conn=3227518040] [session_alias=] [startTS=450141807678586889]
[2024/05/31 19:25:22.921 +08:00] [WARN] [prewrite.go:382] ["1pc failed and fallbacks to normal commit procedure"] [conn=3227518040] [session_alias=] [startTS=450141807678586892]
[2024/05/31 19:25:22.923 +08:00] [WARN] [prewrite.go:382] ["1pc failed and fallbacks to normal commit procedure"] [conn=3227518040] [session_alias=] [startTS=450141807678586896]
[2024/05/31 19:25:22.926 +08:00] [WARN] [prewrite.go:382] ["1pc failed and fallbacks to normal commit procedure"] [conn=3227518040] [session_alias=] [startTS=450141807678586899]
[2024/05/31 19:25:22.928 +08:00] [WARN] [prewrite.go:382] ["1pc failed and fallbacks to normal commit procedure"] [conn=3227518040] [session_alias=] [startTS=450141807678586902]
[2024/05/31 19:25:22.930 +08:00] [WARN] [session.go:2116] ["compile SQL failed"] [conn=3227518040] [session_alias=] [error="[planner:3105]The value specified for generated column 'b' in table 't2' is not allowed."] [SQL="replace t2 set b=default(a)"]
[2024/05/31 19:25:22.930 +08:00] [INFO] [conn.go:1151] ["command dispatched failed"] [conn=3227518040] [session_alias=] [connInfo="id:3227518040, addr:127.0.0.1:53632 status:10, collation:utf8mb4_general_ci, user:root"] [command=Query] [status="inTxn:0, autocommit:1"] [sql="replace t2 set b=default(a);"] [txn_mode=PESSIMISTIC] [timestamp=0] [err="[planner:3105]The value specified for generated column 'b' in table 't2' is not allowed.\ngithub.com/pingcap/errors.AddStack\n\t/go/pkg/mod/github.com/pingcap/errors@v0.11.5-0.20240318064555-6bd07397691f/errors.go:178\ngithub.com/pingcap/errors.(*Error).GenWithStackByArgs\n\t/go/pkg/mod/github.com/pingcap/errors@v0.11.5-0.20240318064555-6bd07397691f/normalize.go:175\ngithub.com/pingcap/tidb/pkg/planner/core.PlanBuilder.getInsertColExpr\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/planner/core/planbuilder.go:3819\ngithub.com/pingcap/tidb/pkg/planner/core.(*PlanBuilder).buildValuesListOfInsert\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/planner/core/planbuilder.go:3894\ngithub.com/pingcap/tidb/pkg/planner/core.(*PlanBuilder).buildInsert\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/planner/core/planbuilder.go:3688\ngithub.com/pingcap/tidb/pkg/planner/core.(*PlanBuilder).Build\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/planner/core/planbuilder.go:494\ngithub.com/pingcap/tidb/pkg/planner.buildLogicalPlan\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/planner/optimize.go:549\ngithub.com/pingcap/tidb/pkg/planner.optimize\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/planner/optimize.go:466\ngithub.com/pingcap/tidb/pkg/planner.Optimize\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/planner/optimize.go:334\ngithub.com/pingcap/tidb/pkg/executor.(*Compiler).Compile\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/executor/compiler.go:99\ngithub.com/pingcap/tidb/pkg/session.(*session).ExecuteStmt\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/session/session.go:2094\ngithub.com/pingcap/tidb/pkg/server.(*TiDBContext).ExecuteStmt\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/server/driver_tidb.go:294\ngithub.com/pingcap/tidb/pkg/server.(*clientConn).handleStmt\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/server/conn.go:2021\ngithub.com/pingcap/tidb/pkg/server.(*clientConn).handleQuery\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/server/conn.go:1774\ngithub.com/pingcap/tidb/pkg/server.(*clientConn).dispatch\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/server/conn.go:1348\ngithub.com/pingcap/tidb/pkg/server.(*clientConn).Run\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/server/conn.go:1114\ngithub.com/pingcap/tidb/pkg/server.(*Server).onConn\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/server/server.go:739\nruntime.goexit\n\t/usr/local/go/src/runtime/asm_amd64.s:1650"]
[2024/05/31 19:25:22.930 +08:00] [WARN] [session.go:2116] ["compile SQL failed"] [conn=3227518040] [session_alias=] [error="[planner:3105]The value specified for generated column 'a' in table 't2' is not allowed."] [SQL="replace t2 set a=default(b), b=default(b)"]
[2024/05/31 19:25:22.930 +08:00] [INFO] [conn.go:1151] ["command dispatched failed"] [conn=3227518040] [session_alias=] [connInfo="id:3227518040, addr:127.0.0.1:53632 status:10, collation:utf8mb4_general_ci, user:root"] [command=Query] [status="inTxn:0, autocommit:1"] [sql="replace t2 set a=default(b), b=default(b);"] [txn_mode=PESSIMISTIC] [timestamp=0] [err="[planner:3105]The value specified for generated column 'a' in table 't2' is not allowed.\ngithub.com/pingcap/errors.AddStack\n\t/go/pkg/mod/github.com/pingcap/errors@v0.11.5-0.20240318064555-6bd07397691f/errors.go:178\ngithub.com/pingcap/errors.(*Error).GenWithStackByArgs\n\t/go/pkg/mod/github.com/pingcap/errors@v0.11.5-0.20240318064555-6bd07397691f/normalize.go:175\ngithub.com/pingcap/tidb/pkg/planner/core.PlanBuilder.getInsertColExpr\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/planner/core/planbuilder.go:3819\ngithub.com/pingcap/tidb/pkg/planner/core.(*PlanBuilder).buildValuesListOfInsert\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/planner/core/planbuilder.go:3894\ngithub.com/pingcap/tidb/pkg/planner/core.(*PlanBuilder).buildInsert\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/planner/core/planbuilder.go:3688\ngithub.com/pingcap/tidb/pkg/planner/core.(*PlanBuilder).Build\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/planner/core/planbuilder.go:494\ngithub.com/pingcap/tidb/pkg/planner.buildLogicalPlan\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/planner/optimize.go:549\ngithub.com/pingcap/tidb/pkg/planner.optimize\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/planner/optimize.go:466\ngithub.com/pingcap/tidb/pkg/planner.Optimize\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/planner/optimize.go:334\ngithub.com/pingcap/tidb/pkg/executor.(*Compiler).Compile\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/executor/compiler.go:99\ngithub.com/pingcap/tidb/pkg/session.(*session).ExecuteStmt\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/session/session.go:2094\ngithub.com/pingcap/tidb/pkg/server.(*TiDBContext).ExecuteStmt\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/server/driver_tidb.go:294\ngithub.com/pingcap/tidb/pkg/server.(*clientConn).handleStmt\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/server/conn.go:2021\ngithub.com/pingcap/tidb/pkg/server.(*clientConn).handleQuery\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/server/conn.go:1774\ngithub.com/pingcap/tidb/pkg/server.(*clientConn).dispatch\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/server/conn.go:1348\ngithub.com/pingcap/tidb/pkg/server.(*clientConn).Run\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/server/conn.go:1114\ngithub.com/pingcap/tidb/pkg/server.(*Server).onConn\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/server/server.go:739\nruntime.goexit\n\t/usr/local/go/src/runtime/asm_amd64.s:1650"]
[2024/05/31 19:25:22.931 +08:00] [INFO] [tidb.go:269] ["rollbackTxn called due to ddl/autocommit failure"]
[2024/05/31 19:25:22.931 +08:00] [WARN] [session.go:2158] ["run statement failed"] [conn=3227518040] [session_alias=] [schemaVersion=16534] [error="[table:1364]Field 'pk' doesn't have a default value"] [session="{\n  \"currDBName\": \"executor__write\",\n  \"id\": 3227518040,\n  \"status\": 2,\n  \"strictMode\": true,\n  \"user\": {\n    \"Username\": \"root\",\n    \"Hostname\": \"127.0.0.1\",\n    \"CurrentUser\": false,\n    \"AuthUsername\": \"root\",\n    \"AuthHostname\": \"%\",\n    \"AuthPlugin\": \"mysql_native_password\"\n  }\n}"]
[2024/05/31 19:25:22.931 +08:00] [INFO] [conn.go:1151] ["command dispatched failed"] [conn=3227518040] [session_alias=] [connInfo="id:3227518040, addr:127.0.0.1:53632 status:10, collation:utf8mb4_general_ci, user:root"] [command=Query] [status="inTxn:0, autocommit:1"] [sql="replace t2 set a=default(a), c=default(c);"] [txn_mode=PESSIMISTIC] [timestamp=450141807678586907] [err="[table:1364]Field 'pk' doesn't have a default value\ngithub.com/pingcap/errors.AddStack\n\t/go/pkg/mod/github.com/pingcap/errors@v0.11.5-0.20240318064555-6bd07397691f/errors.go:178\ngithub.com/pingcap/errors.(*Error).GenWithStackByArgs\n\t/go/pkg/mod/github.com/pingcap/errors@v0.11.5-0.20240318064555-6bd07397691f/normalize.go:175\ngithub.com/pingcap/tidb/pkg/table.CheckNoDefaultValueForInsert\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/table/column.go:554\ngithub.com/pingcap/tidb/pkg/executor.(*InsertValues).getColDefaultValue\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/executor/insert_common.go:587\ngithub.com/pingcap/tidb/pkg/executor.(*InsertValues).fillColValue\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/executor/insert_common.go:651\ngithub.com/pingcap/tidb/pkg/executor.(*InsertValues).fillRow\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/executor/insert_common.go:689\ngithub.com/pingcap/tidb/pkg/executor.(*InsertValues).fastEvalRow\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/executor/insert_common.go:415\ngithub.com/pingcap/tidb/pkg/executor.insertRows\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/executor/insert_common.go:219\ngithub.com/pingcap/tidb/pkg/executor.(*ReplaceExec).Next\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/executor/replace.go:204\ngithub.com/pingcap/tidb/pkg/executor/internal/exec.Next\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/executor/internal/exec/executor.go:410\ngithub.com/pingcap/tidb/pkg/executor.(*ExecStmt).next\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/executor/adapter.go:1211\ngithub.com/pingcap/tidb/pkg/executor.(*ExecStmt).handleNoDelayExecutor\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/executor/adapter.go:960\ngithub.com/pingcap/tidb/pkg/executor.(*ExecStmt).handleNoDelay\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/executor/adapter.go:794\ngithub.com/pingcap/tidb/pkg/executor.(*ExecStmt).Exec\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/executor/adapter.go:559\ngithub.com/pingcap/tidb/pkg/session.runStmt\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/session/session.go:2285\ngithub.com/pingcap/tidb/pkg/session.(*session).ExecuteStmt\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/session/session.go:2146\ngithub.com/pingcap/tidb/pkg/server.(*TiDBContext).ExecuteStmt\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/server/driver_tidb.go:294\ngithub.com/pingcap/tidb/pkg/server.(*clientConn).handleStmt\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/server/conn.go:2021\ngithub.com/pingcap/tidb/pkg/server.(*clientConn).handleQuery\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/server/conn.go:1774\ngithub.com/pingcap/tidb/pkg/server.(*clientConn).dispatch\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/server/conn.go:1348\ngithub.com/pingcap/tidb/pkg/server.(*clientConn).Run\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/server/conn.go:1114\ngithub.com/pingcap/tidb/pkg/server.(*Server).onConn\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/server/server.go:739\nruntime.goexit\n\t/usr/local/go/src/runtime/asm_amd64.s:1650"]
[2024/05/31 19:25:22.931 +08:00] [WARN] [session.go:2116] ["compile SQL failed"] [conn=3227518040] [session_alias=] [error="[planner:3105]The value specified for generated column 'c' in table 't2' is not allowed."] [SQL="replace t2 set c=default(a)"]
[2024/05/31 19:25:22.931 +08:00] [INFO] [conn.go:1151] ["command dispatched failed"] [conn=3227518040] [session_alias=] [connInfo="id:3227518040, addr:127.0.0.1:53632 status:10, collation:utf8mb4_general_ci, user:root"] [command=Query] [status="inTxn:0, autocommit:1"] [sql="replace t2 set c=default(a);"] [txn_mode=PESSIMISTIC] [timestamp=0] [err="[planner:3105]The value specified for generated column 'c' in table 't2' is not allowed.\ngithub.com/pingcap/errors.AddStack\n\t/go/pkg/mod/github.com/pingcap/errors@v0.11.5-0.20240318064555-6bd07397691f/errors.go:178\ngithub.com/pingcap/errors.(*Error).GenWithStackByArgs\n\t/go/pkg/mod/github.com/pingcap/errors@v0.11.5-0.20240318064555-6bd07397691f/normalize.go:175\ngithub.com/pingcap/tidb/pkg/planner/core.PlanBuilder.getInsertColExpr\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/planner/core/planbuilder.go:3819\ngithub.com/pingcap/tidb/pkg/planner/core.(*PlanBuilder).buildValuesListOfInsert\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/planner/core/planbuilder.go:3894\ngithub.com/pingcap/tidb/pkg/planner/core.(*PlanBuilder).buildInsert\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/planner/core/planbuilder.go:3688\ngithub.com/pingcap/tidb/pkg/planner/core.(*PlanBuilder).Build\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/planner/core/planbuilder.go:494\ngithub.com/pingcap/tidb/pkg/planner.buildLogicalPlan\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/planner/optimize.go:549\ngithub.com/pingcap/tidb/pkg/planner.optimize\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/planner/optimize.go:466\ngithub.com/pingcap/tidb/pkg/planner.Optimize\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/planner/optimize.go:334\ngithub.com/pingcap/tidb/pkg/executor.(*Compiler).Compile\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/executor/compiler.go:99\ngithub.com/pingcap/tidb/pkg/session.(*session).ExecuteStmt\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/session/session.go:2094\ngithub.com/pingcap/tidb/pkg/server.(*TiDBContext).ExecuteStmt\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/server/driver_tidb.go:294\ngithub.com/pingcap/tidb/pkg/server.(*clientConn).handleStmt\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/server/conn.go:2021\ngithub.com/pingcap/tidb/pkg/server.(*clientConn).handleQuery\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/server/conn.go:1774\ngithub.com/pingcap/tidb/pkg/server.(*clientConn).dispatch\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/server/conn.go:1348\ngithub.com/pingcap/tidb/pkg/server.(*clientConn).Run\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/server/conn.go:1114\ngithub.com/pingcap/tidb/pkg/server.(*Server).onConn\n\t/home/jenkins/agent/workspace/pingcap/tidb/periodics_integration_test/tidb/pkg/server/server.go:739\nruntime.goexit\n\t/usr/local/go/src/runtime/asm_amd64.s:1650"]
[2024/05/31 19:25:22.932 +08:00] [INFO] [session.go:3959] ["CRUCIAL OPERATION"] [conn=3227518040] [schemaVersion=16534] [cur_db=executor__write] [sql="drop table t1, t2;"] [user=root@%]
[2024/05/31 19:25:22.948 +08:00] [INFO] [ddl_worker.go:261] ["add DDL jobs"] [category=ddl] ["batch count"=1] [jobs="ID:12685, Type:drop table, State:queueing, SchemaState:public, SchemaID:12334, TableID:12681, RowCount:0, ArgLen:2, start time: 2024-05-31 19:25:22.895 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false; "] [table=true]
[2024/05/31 19:25:22.948 +08:00] [INFO] [ddl.go:1192] ["start DDL job"] [category=ddl] [job="ID:12685, Type:drop table, State:queueing, SchemaState:public, SchemaID:12334, TableID:12681, RowCount:0, ArgLen:2, start time: 2024-05-31 19:25:22.895 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false"] [query="drop table t1, t2;"]
[2024/05/31 19:25:22.975 +08:00] [INFO] [ddl_worker.go:1210] ["run DDL job"] [worker="worker 4, tp general"] [category=ddl] [jobID=12685] [conn=3227518040] [category=ddl] [job="ID:12685, Type:drop table, State:queueing, SchemaState:public, SchemaID:12334, TableID:12681, RowCount:0, ArgLen:0, start time: 2024-05-31 19:25:22.895 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false"]
[2024/05/31 19:25:23.002 +08:00] [INFO] [ddl_worker.go:1210] ["run DDL job"] [worker="worker 4, tp general"] [category=ddl] [jobID=12685] [conn=3227518040] [category=ddl] [job="ID:12685, Type:drop table, State:running, SchemaState:write only, SchemaID:12334, TableID:12681, RowCount:0, ArgLen:0, start time: 2024-05-31 19:25:22.895 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false"]
[2024/05/31 19:25:23.028 +08:00] [INFO] [ddl_worker.go:1210] ["run DDL job"] [worker="worker 4, tp general"] [category=ddl] [jobID=12685] [conn=3227518040] [category=ddl] [job="ID:12685, Type:drop table, State:running, SchemaState:delete only, SchemaID:12334, TableID:12681, RowCount:0, ArgLen:0, start time: 2024-05-31 19:25:22.895 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false"]
[2024/05/31 19:25:23.054 +08:00] [INFO] [delete_range.go:447] ["insert into delete-range table"] [category=ddl] [jobID=12685] [tableIDs="[12681]"] [comment="drop table: table ID"]
[2024/05/31 19:25:23.056 +08:00] [INFO] [delete_range.go:112] ["add job into delete-range table"] [category=ddl] [jobID=12685] [jobType="drop table"]
[2024/05/31 19:25:23.056 +08:00] [INFO] [ddl_worker.go:763] ["finish DDL job"] [worker="worker 4, tp general"] [category=ddl] [jobID=12685] [conn=3227518040] [job="ID:12685, Type:drop table, State:synced, SchemaState:none, SchemaID:12334, TableID:12681, RowCount:0, ArgLen:3, start time: 2024-05-31 19:25:22.895 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false"]
[2024/05/31 19:25:23.059 +08:00] [INFO] [ddl.go:1298] ["DDL job is finished"] [category=ddl] [jobID=12685]
[2024/05/31 19:25:23.059 +08:00] [INFO] [callback.go:140] ["performing DDL change, must reload"] [category=ddl]
[2024/05/31 19:25:23.061 +08:00] [INFO] [domain.go:288] ["diff load InfoSchema success"] [isV2=false] [currentSchemaVersion=16534] [neededSchemaVersion=16537] ["start time"=427.013µs] [gotSchemaVersion=16537] [phyTblIDs="[12681]"] [actionTypes="[4]"] [diffTypes="[\"drop table\",\"drop table\",\"drop table\"]"]
[2024/05/31 19:25:23.077 +08:00] [INFO] [ddl_worker.go:261] ["add DDL jobs"] [category=ddl] ["batch count"=1] [jobs="ID:12686, Type:drop table, State:queueing, SchemaState:public, SchemaID:12334, TableID:12683, RowCount:0, ArgLen:2, start time: 2024-05-31 19:25:23.046 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false; "] [table=true]
[2024/05/31 19:25:23.077 +08:00] [INFO] [ddl.go:1192] ["start DDL job"] [category=ddl] [job="ID:12686, Type:drop table, State:queueing, SchemaState:public, SchemaID:12334, TableID:12683, RowCount:0, ArgLen:2, start time: 2024-05-31 19:25:23.046 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false"] [query="drop table t1, t2;"]
[2024/05/31 19:25:23.127 +08:00] [INFO] [ddl_worker.go:1210] ["run DDL job"] [worker="worker 4, tp general"] [category=ddl] [jobID=12686] [conn=3227518040] [category=ddl] [job="ID:12686, Type:drop table, State:queueing, SchemaState:public, SchemaID:12334, TableID:12683, RowCount:0, ArgLen:0, start time: 2024-05-31 19:25:23.046 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false"]
[2024/05/31 19:25:23.153 +08:00] [INFO] [ddl_worker.go:1210] ["run DDL job"] [worker="worker 4, tp general"] [category=ddl] [jobID=12686] [conn=3227518040] [category=ddl] [job="ID:12686, Type:drop table, State:running, SchemaState:write only, SchemaID:12334, TableID:12683, RowCount:0, ArgLen:0, start time: 2024-05-31 19:25:23.046 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false"]
[2024/05/31 19:25:23.180 +08:00] [INFO] [ddl_worker.go:1210] ["run DDL job"] [worker="worker 4, tp general"] [category=ddl] [jobID=12686] [conn=3227518040] [category=ddl] [job="ID:12686, Type:drop table, State:running, SchemaState:delete only, SchemaID:12334, TableID:12683, RowCount:0, ArgLen:0, start time: 2024-05-31 19:25:23.046 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false"]
[2024/05/31 19:25:23.206 +08:00] [INFO] [delete_range.go:447] ["insert into delete-range table"] [category=ddl] [jobID=12686] [tableIDs="[12683]"] [comment="drop table: table ID"]
[2024/05/31 19:25:23.208 +08:00] [INFO] [delete_range.go:112] ["add job into delete-range table"] [category=ddl] [jobID=12686] [jobType="drop table"]
[2024/05/31 19:25:23.208 +08:00] [INFO] [ddl_worker.go:763] ["finish DDL job"] [worker="worker 4, tp general"] [category=ddl] [jobID=12686] [conn=3227518040] [job="ID:12686, Type:drop table, State:synced, SchemaState:none, SchemaID:12334, TableID:12683, RowCount:0, ArgLen:3, start time: 2024-05-31 19:25:23.046 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false"]
[2024/05/31 19:25:23.211 +08:00] [INFO] [ddl.go:1298] ["DDL job is finished"] [category=ddl] [jobID=12686]
[2024/05/31 19:25:23.211 +08:00] [INFO] [callback.go:140] ["performing DDL change, must reload"] [category=ddl]
[2024/05/31 19:25:23.212 +08:00] [INFO] [domain.go:288] ["diff load InfoSchema success"] [isV2=false] [currentSchemaVersion=16537] [neededSchemaVersion=16540] ["start time"=370.971µs] [gotSchemaVersion=16540] [phyTblIDs="[12683]"] [actionTypes="[4]"] [diffTypes="[\"drop table\",\"drop table\",\"drop table\"]"]
[2024/05/31 19:25:23.214 +08:00] [INFO] [session.go:3959] ["CRUCIAL OPERATION"] [conn=3227518050] [schemaVersion=16540] [cur_db=test] [sql="drop database `executor__write`"] [user=root@%]
[2024/05/31 19:25:23.230 +08:00] [INFO] [ddl_worker.go:261] ["add DDL jobs"] [category=ddl] ["batch count"=1] [jobs="ID:12687, Type:drop schema, State:queueing, SchemaState:public, SchemaID:12334, TableID:0, RowCount:0, ArgLen:1, start time: 2024-05-31 19:25:23.196 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false; "] [table=true]
[2024/05/31 19:25:23.230 +08:00] [INFO] [ddl.go:1192] ["start DDL job"] [category=ddl] [job="ID:12687, Type:drop schema, State:queueing, SchemaState:public, SchemaID:12334, TableID:0, RowCount:0, ArgLen:1, start time: 2024-05-31 19:25:23.196 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false"] [query="drop database `executor__write`"]
[2024/05/31 19:25:23.267 +08:00] [INFO] [ddl_worker.go:1210] ["run DDL job"] [worker="worker 4, tp general"] [category=ddl] [jobID=12687] [conn=3227518050] [category=ddl] [job="ID:12687, Type:drop schema, State:queueing, SchemaState:public, SchemaID:12334, TableID:0, RowCount:0, ArgLen:0, start time: 2024-05-31 19:25:23.196 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false"]
[2024/05/31 19:25:23.295 +08:00] [INFO] [ddl_worker.go:1210] ["run DDL job"] [worker="worker 4, tp general"] [category=ddl] [jobID=12687] [conn=3227518050] [category=ddl] [job="ID:12687, Type:drop schema, State:running, SchemaState:write only, SchemaID:12334, TableID:0, RowCount:0, ArgLen:0, start time: 2024-05-31 19:25:23.196 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false"]
[2024/05/31 19:25:23.320 +08:00] [INFO] [ddl_worker.go:1210] ["run DDL job"] [worker="worker 4, tp general"] [category=ddl] [jobID=12687] [conn=3227518050] [category=ddl] [job="ID:12687, Type:drop schema, State:running, SchemaState:delete only, SchemaID:12334, TableID:0, RowCount:0, ArgLen:0, start time: 2024-05-31 19:25:23.196 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false"]
[2024/05/31 19:25:23.352 +08:00] [INFO] [delete_range.go:447] ["insert into delete-range table"] [category=ddl] [jobID=12687] [tableIDs="[12467,12469,12484,12651,12658,12659,12660,12662,12664,12666,12668,12670,12672,12674,12676]"] [comment="drop schema: table IDs"]
[2024/05/31 19:25:23.355 +08:00] [INFO] [delete_range.go:112] ["add job into delete-range table"] [category=ddl] [jobID=12687] [jobType="drop schema"]
[2024/05/31 19:25:23.355 +08:00] [INFO] [ddl_worker.go:763] ["finish DDL job"] [worker="worker 4, tp general"] [category=ddl] [jobID=12687] [conn=3227518050] [job="ID:12687, Type:drop schema, State:synced, SchemaState:none, SchemaID:12334, TableID:0, RowCount:0, ArgLen:1, start time: 2024-05-31 19:25:23.196 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false"]
[2024/05/31 19:25:23.359 +08:00] [INFO] [ddl.go:1298] ["DDL job is finished"] [category=ddl] [jobID=12687]
[2024/05/31 19:25:23.359 +08:00] [INFO] [callback.go:140] ["performing DDL change, must reload"] [category=ddl]
[2024/05/31 19:25:23.360 +08:00] [INFO] [domain.go:288] ["diff load InfoSchema success"] [isV2=false] [currentSchemaVersion=16540] [neededSchemaVersion=16543] ["start time"=477.308µs] [gotSchemaVersion=16543] [phyTblIDs="[12467,12469,12484,12651,12658,12659,12660,12662,12664,12666,12668,12670,12672,12674,12676]"] [actionTypes="[2,2,2,2,2,2,2,2,2,2,2,2,2,2,2]"] [diffTypes="[\"drop schema\",\"drop schema\",\"drop schema\"]"]
[2024/05/31 19:25:23.362 +08:00] [INFO] [session.go:3959] ["CRUCIAL OPERATION"] [conn=3227518052] [schemaVersion=16543] [cur_db=test] [sql="create database `explain-non-select-stmt`"] [user=root@%]
[2024/05/31 19:25:23.379 +08:00] [INFO] [ddl_worker.go:261] ["add DDL jobs"] [category=ddl] ["batch count"=1] [jobs="ID:12689, Type:create schema, State:queueing, SchemaState:none, SchemaID:12688, TableID:0, RowCount:0, ArgLen:1, start time: 2024-05-31 19:25:23.346 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false; "] [table=true]
[2024/05/31 19:25:23.379 +08:00] [INFO] [ddl.go:1192] ["start DDL job"] [category=ddl] [job="ID:12689, Type:create schema, State:queueing, SchemaState:none, SchemaID:12688, TableID:0, RowCount:0, ArgLen:1, start time: 2024-05-31 19:25:23.346 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false"] [query="create database `explain-non-select-stmt`"]
[2024/05/31 19:25:23.415 +08:00] [INFO] [ddl_worker.go:1210] ["run DDL job"] [worker="worker 4, tp general"] [category=ddl] [jobID=12689] [conn=3227518052] [category=ddl] [job="ID:12689, Type:create schema, State:queueing, SchemaState:none, SchemaID:12688, TableID:0, RowCount:0, ArgLen:0, start time: 2024-05-31 19:25:23.346 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false"]
[2024/05/31 19:25:23.442 +08:00] [INFO] [ddl_worker.go:763] ["finish DDL job"] [worker="worker 4, tp general"] [category=ddl] [jobID=12689] [conn=3227518052] [job="ID:12689, Type:create schema, State:synced, SchemaState:public, SchemaID:12688, TableID:0, RowCount:0, ArgLen:0, start time: 2024-05-31 19:25:23.346 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false"]
[2024/05/31 19:25:23.445 +08:00] [INFO] [ddl.go:1298] ["DDL job is finished"] [category=ddl] [jobID=12689]
[2024/05/31 19:25:23.445 +08:00] [INFO] [callback.go:140] ["performing DDL change, must reload"] [category=ddl]
[2024/05/31 19:25:23.446 +08:00] [INFO] [domain.go:288] ["diff load InfoSchema success"] [isV2=false] [currentSchemaVersion=16543] [neededSchemaVersion=16544] ["start time"=238.062µs] [gotSchemaVersion=16544] [phyTblIDs="[]"] [actionTypes="[]"] [diffTypes="[\"create schema\"]"]
[2024/05/31 19:25:23.450 +08:00] [INFO] [session.go:3959] ["CRUCIAL OPERATION"] [conn=3227518054] [schemaVersion=16544] [cur_db=explain-non-select-stmt] [sql="drop table if exists t;"] [user=root@%]
[2024/05/31 19:25:23.451 +08:00] [INFO] [session.go:3959] ["CRUCIAL OPERATION"] [conn=3227518054] [schemaVersion=16544] [cur_db=explain-non-select-stmt] [sql="create table t(a bigint, b bigint);"] [user=root@%]
[2024/05/31 19:25:23.470 +08:00] [INFO] [ddl_worker.go:261] ["add DDL jobs"] [category=ddl] ["batch count"=1] [jobs="ID:12691, Type:create table, State:queueing, SchemaState:none, SchemaID:12688, TableID:12690, RowCount:0, ArgLen:2, start time: 2024-05-31 19:25:23.445 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false; "] [table=true]
[2024/05/31 19:25:23.470 +08:00] [INFO] [ddl.go:1192] ["start DDL job"] [category=ddl] [job="ID:12691, Type:create table, State:queueing, SchemaState:none, SchemaID:12688, TableID:12690, RowCount:0, ArgLen:2, start time: 2024-05-31 19:25:23.445 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false"] [query="create table t(a bigint, b bigint);"]
[2024/05/31 19:25:23.510 +08:00] [INFO] [ddl_worker.go:1210] ["run DDL job"] [worker="worker 4, tp general"] [category=ddl] [jobID=12691] [conn=3227518054] [category=ddl] [job="ID:12691, Type:create table, State:queueing, SchemaState:none, SchemaID:12688, TableID:12690, RowCount:0, ArgLen:0, start time: 2024-05-31 19:25:23.445 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false"]
[2024/05/31 19:25:23.536 +08:00] [INFO] [ddl_worker.go:763] ["finish DDL job"] [worker="worker 4, tp general"] [category=ddl] [jobID=12691] [conn=3227518054] [job="ID:12691, Type:create table, State:synced, SchemaState:public, SchemaID:12688, TableID:12690, RowCount:0, ArgLen:0, start time: 2024-05-31 19:25:23.445 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false"]
[2024/05/31 19:25:23.540 +08:00] [INFO] [ddl.go:1298] ["DDL job is finished"] [category=ddl] [jobID=12691]
[2024/05/31 19:25:23.540 +08:00] [INFO] [callback.go:140] ["performing DDL change, must reload"] [category=ddl]
[2024/05/31 19:25:23.540 +08:00] [INFO] [split_region.go:85] ["split batch regions request"] ["split key count"=1] ["batch count"=1] ["first batch, region ID"=14] ["first split key"=748000000000003192]
[2024/05/31 19:25:23.542 +08:00] [INFO] [split_region.go:187] ["batch split regions complete"] ["batch region ID"=14] ["first at"=748000000000003192] ["first new region left"="{Id:10248 StartKey:7480000000000031ff8b00000000000000f8 EndKey:7480000000000031ff9200000000000000f8 RegionEpoch:{ConfVer:1 Version:4884} Peers:[id:10249 store_id:1 ] EncryptionMeta:<nil> IsInFlashback:false FlashbackStartTs:0}"] ["new region count"=1]
[2024/05/31 19:25:23.542 +08:00] [INFO] [split_region.go:236] ["split regions complete"] ["region count"=1] ["region IDs"="[10248]"]
[2024/05/31 19:25:23.542 +08:00] [INFO] [domain.go:288] ["diff load InfoSchema success"] [isV2=false] [currentSchemaVersion=16544] [neededSchemaVersion=16545] ["start time"=513.622µs] [gotSchemaVersion=16545] [phyTblIDs="[12690]"] [actionTypes="[3]"] [diffTypes="[\"create table\"]"]
[2024/05/31 19:25:23.546 +08:00] [INFO] [session.go:3959] ["CRUCIAL OPERATION"] [conn=3227518058] [schemaVersion=16545] [cur_db=test] [sql="drop database `explain-non-select-stmt`"] [user=root@%]
[2024/05/31 19:25:23.561 +08:00] [INFO] [ddl_worker.go:261] ["add DDL jobs"] [category=ddl] ["batch count"=1] [jobs="ID:12692, Type:drop schema, State:queueing, SchemaState:public, SchemaID:12688, TableID:0, RowCount:0, ArgLen:1, start time: 2024-05-31 19:25:23.545 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false; "] [table=true]
[2024/05/31 19:25:23.561 +08:00] [INFO] [ddl.go:1192] ["start DDL job"] [category=ddl] [job="ID:12692, Type:drop schema, State:queueing, SchemaState:public, SchemaID:12688, TableID:0, RowCount:0, ArgLen:1, start time: 2024-05-31 19:25:23.545 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false"] [query="drop database `explain-non-select-stmt`"]
[2024/05/31 19:25:23.596 +08:00] [INFO] [ddl_worker.go:1210] ["run DDL job"] [worker="worker 4, tp general"] [category=ddl] [jobID=12692] [conn=3227518058] [category=ddl] [job="ID:12692, Type:drop schema, State:queueing, SchemaState:public, SchemaID:12688, TableID:0, RowCount:0, ArgLen:0, start time: 2024-05-31 19:25:23.545 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false"]
[2024/05/31 19:25:23.622 +08:00] [INFO] [ddl_worker.go:1210] ["run DDL job"] [worker="worker 4, tp general"] [category=ddl] [jobID=12692] [conn=3227518058] [category=ddl] [job="ID:12692, Type:drop schema, State:running, SchemaState:write only, SchemaID:12688, TableID:0, RowCount:0, ArgLen:0, start time: 2024-05-31 19:25:23.545 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false"]
[2024/05/31 19:25:23.649 +08:00] [INFO] [ddl_worker.go:1210] ["run DDL job"] [worker="worker 4, tp general"] [category=ddl] [jobID=12692] [conn=3227518058] [category=ddl] [job="ID:12692, Type:drop schema, State:running, SchemaState:delete only, SchemaID:12688, TableID:0, RowCount:0, ArgLen:0, start time: 2024-05-31 19:25:23.545 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false"]
[2024/05/31 19:25:23.675 +08:00] [INFO] [delete_range.go:447] ["insert into delete-range table"] [category=ddl] [jobID=12692] [tableIDs="[12690]"] [comment="drop schema: table IDs"]
[2024/05/31 19:25:23.677 +08:00] [INFO] [delete_range.go:112] ["add job into delete-range table"] [category=ddl] [jobID=12692] [jobType="drop schema"]
[2024/05/31 19:25:23.678 +08:00] [INFO] [ddl_worker.go:763] ["finish DDL job"] [worker="worker 4, tp general"] [category=ddl] [jobID=12692] [conn=3227518058] [job="ID:12692, Type:drop schema, State:synced, SchemaState:none, SchemaID:12688, TableID:0, RowCount:0, ArgLen:1, start time: 2024-05-31 19:25:23.545 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false"]
[2024/05/31 19:25:23.681 +08:00] [INFO] [ddl.go:1298] ["DDL job is finished"] [category=ddl] [jobID=12692]
[2024/05/31 19:25:23.681 +08:00] [INFO] [callback.go:140] ["performing DDL change, must reload"] [category=ddl]
[2024/05/31 19:25:23.682 +08:00] [INFO] [domain.go:288] ["diff load InfoSchema success"] [isV2=false] [currentSchemaVersion=16545] [neededSchemaVersion=16548] ["start time"=427.592µs] [gotSchemaVersion=16548] [phyTblIDs="[12690]"] [actionTypes="[2]"] [diffTypes="[\"drop schema\",\"drop schema\",\"drop schema\"]"]
[2024/05/31 19:25:23.685 +08:00] [INFO] [session.go:3959] ["CRUCIAL OPERATION"] [conn=3227518060] [schemaVersion=16548] [cur_db=test] [sql="create database `explain`"] [user=root@%]
[2024/05/31 19:25:23.707 +08:00] [INFO] [ddl_worker.go:261] ["add DDL jobs"] [category=ddl] ["batch count"=1] [jobs="ID:12694, Type:create schema, State:queueing, SchemaState:none, SchemaID:12693, TableID:0, RowCount:0, ArgLen:1, start time: 2024-05-31 19:25:23.696 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false; "] [table=true]
[2024/05/31 19:25:23.707 +08:00] [INFO] [ddl.go:1192] ["start DDL job"] [category=ddl] [job="ID:12694, Type:create schema, State:queueing, SchemaState:none, SchemaID:12693, TableID:0, RowCount:0, ArgLen:1, start time: 2024-05-31 19:25:23.696 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false"] [query="create database `explain`"]
[2024/05/31 19:25:23.740 +08:00] [INFO] [ddl_worker.go:1210] ["run DDL job"] [worker="worker 4, tp general"] [category=ddl] [jobID=12694] [conn=3227518060] [category=ddl] [job="ID:12694, Type:create schema, State:queueing, SchemaState:none, SchemaID:12693, TableID:0, RowCount:0, ArgLen:0, start time: 2024-05-31 19:25:23.696 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false"]
[2024/05/31 19:25:23.767 +08:00] [INFO] [ddl_worker.go:763] ["finish DDL job"] [worker="worker 4, tp general"] [category=ddl] [jobID=12694] [conn=3227518060] [job="ID:12694, Type:create schema, State:synced, SchemaState:public, SchemaID:12693, TableID:0, RowCount:0, ArgLen:0, start time: 2024-05-31 19:25:23.696 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false"]
[2024/05/31 19:25:23.771 +08:00] [INFO] [ddl.go:1298] ["DDL job is finished"] [category=ddl] [jobID=12694]
[2024/05/31 19:25:23.771 +08:00] [INFO] [callback.go:140] ["performing DDL change, must reload"] [category=ddl]
[2024/05/31 19:25:23.772 +08:00] [INFO] [domain.go:288] ["diff load InfoSchema success"] [isV2=false] [currentSchemaVersion=16548] [neededSchemaVersion=16549] ["start time"=240.226µs] [gotSchemaVersion=16549] [phyTblIDs="[]"] [actionTypes="[]"] [diffTypes="[\"create schema\"]"]
[2024/05/31 19:25:23.776 +08:00] [INFO] [session.go:3959] ["CRUCIAL OPERATION"] [conn=3227518062] [schemaVersion=16549] [cur_db=explain] [sql="drop table if exists t;"] [user=root@%]
[2024/05/31 19:25:23.777 +08:00] [INFO] [session.go:3959] ["CRUCIAL OPERATION"] [conn=3227518062] [schemaVersion=16549] [cur_db=explain] [sql="create table t (id int, c1 timestamp);"] [user=root@%]
[2024/05/31 19:25:23.796 +08:00] [INFO] [ddl_worker.go:261] ["add DDL jobs"] [category=ddl] ["batch count"=1] [jobs="ID:12696, Type:create table, State:queueing, SchemaState:none, SchemaID:12693, TableID:12695, RowCount:0, ArgLen:2, start time: 2024-05-31 19:25:23.746 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false; "] [table=true]
[2024/05/31 19:25:23.797 +08:00] [INFO] [ddl.go:1192] ["start DDL job"] [category=ddl] [job="ID:12696, Type:create table, State:queueing, SchemaState:none, SchemaID:12693, TableID:12695, RowCount:0, ArgLen:2, start time: 2024-05-31 19:25:23.746 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false"] [query="create table t (id int, c1 timestamp);"]
[2024/05/31 19:25:23.838 +08:00] [INFO] [ddl_worker.go:1210] ["run DDL job"] [worker="worker 4, tp general"] [category=ddl] [jobID=12696] [conn=3227518062] [category=ddl] [job="ID:12696, Type:create table, State:queueing, SchemaState:none, SchemaID:12693, TableID:12695, RowCount:0, ArgLen:0, start time: 2024-05-31 19:25:23.746 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false"]
[2024/05/31 19:25:23.864 +08:00] [INFO] [ddl_worker.go:763] ["finish DDL job"] [worker="worker 4, tp general"] [category=ddl] [jobID=12696] [conn=3227518062] [job="ID:12696, Type:create table, State:synced, SchemaState:public, SchemaID:12693, TableID:12695, RowCount:0, ArgLen:0, start time: 2024-05-31 19:25:23.746 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false"]
[2024/05/31 19:25:23.868 +08:00] [INFO] [ddl.go:1298] ["DDL job is finished"] [category=ddl] [jobID=12696]
[2024/05/31 19:25:23.868 +08:00] [INFO] [callback.go:140] ["performing DDL change, must reload"] [category=ddl]
[2024/05/31 19:25:23.868 +08:00] [INFO] [split_region.go:85] ["split batch regions request"] ["split key count"=1] ["batch count"=1] ["first batch, region ID"=14] ["first split key"=748000000000003197]
[2024/05/31 19:25:23.869 +08:00] [INFO] [domain.go:288] ["diff load InfoSchema success"] [isV2=false] [currentSchemaVersion=16549] [neededSchemaVersion=16550] ["start time"=501.395µs] [gotSchemaVersion=16550] [phyTblIDs="[12695]"] [actionTypes="[3]"] [diffTypes="[\"create table\"]"]
[2024/05/31 19:25:23.871 +08:00] [INFO] [session.go:3959] ["CRUCIAL OPERATION"] [conn=3227518062] [schemaVersion=16550] [cur_db=explain] [sql="drop table if exists t;"] [user=root@%]
[2024/05/31 19:25:23.871 +08:00] [INFO] [split_region.go:187] ["batch split regions complete"] ["batch region ID"=14] ["first at"=748000000000003197] ["first new region left"="{Id:10250 StartKey:7480000000000031ff9200000000000000f8 EndKey:7480000000000031ff9700000000000000f8 RegionEpoch:{ConfVer:1 Version:4885} Peers:[id:10251 store_id:1 ] EncryptionMeta:<nil> IsInFlashback:false FlashbackStartTs:0}"] ["new region count"=1]
[2024/05/31 19:25:23.871 +08:00] [INFO] [split_region.go:236] ["split regions complete"] ["region count"=1] ["region IDs"="[10250]"]
[2024/05/31 19:25:23.887 +08:00] [INFO] [ddl_worker.go:261] ["add DDL jobs"] [category=ddl] ["batch count"=1] [jobs="ID:12697, Type:drop table, State:queueing, SchemaState:public, SchemaID:12693, TableID:12695, RowCount:0, ArgLen:2, start time: 2024-05-31 19:25:23.846 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false; "] [table=true]
[2024/05/31 19:25:23.887 +08:00] [INFO] [ddl.go:1192] ["start DDL job"] [category=ddl] [job="ID:12697, Type:drop table, State:queueing, SchemaState:public, SchemaID:12693, TableID:12695, RowCount:0, ArgLen:2, start time: 2024-05-31 19:25:23.846 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false"] [query="drop table if exists t;"]
[2024/05/31 19:25:23.932 +08:00] [INFO] [ddl_worker.go:1210] ["run DDL job"] [worker="worker 4, tp general"] [category=ddl] [jobID=12697] [conn=3227518062] [category=ddl] [job="ID:12697, Type:drop table, State:queueing, SchemaState:public, SchemaID:12693, TableID:12695, RowCount:0, ArgLen:0, start time: 2024-05-31 19:25:23.846 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false"]
[2024/05/31 19:25:23.960 +08:00] [INFO] [ddl_worker.go:1210] ["run DDL job"] [worker="worker 4, tp general"] [category=ddl] [jobID=12697] [conn=3227518062] [category=ddl] [job="ID:12697, Type:drop table, State:running, SchemaState:write only, SchemaID:12693, TableID:12695, RowCount:0, ArgLen:0, start time: 2024-05-31 19:25:23.846 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false"]
[2024/05/31 19:25:23.988 +08:00] [INFO] [ddl_worker.go:1210] ["run DDL job"] [worker="worker 4, tp general"] [category=ddl] [jobID=12697] [conn=3227518062] [category=ddl] [job="ID:12697, Type:drop table, State:running, SchemaState:delete only, SchemaID:12693, TableID:12695, RowCount:0, ArgLen:0, start time: 2024-05-31 19:25:23.846 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false"]
[2024/05/31 19:25:24.013 +08:00] [INFO] [delete_range.go:447] ["insert into delete-range table"] [category=ddl] [jobID=12697] [tableIDs="[12695]"] [comment="drop table: table ID"]
[2024/05/31 19:25:24.015 +08:00] [INFO] [delete_range.go:112] ["add job into delete-range table"] [category=ddl] [jobID=12697] [jobType="drop table"]
[2024/05/31 19:25:24.015 +08:00] [INFO] [ddl_worker.go:763] ["finish DDL job"] [worker="worker 4, tp general"] [category=ddl] [jobID=12697] [conn=3227518062] [job="ID:12697, Type:drop table, State:synced, SchemaState:none, SchemaID:12693, TableID:12695, RowCount:0, ArgLen:3, start time: 2024-05-31 19:25:23.846 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false"]
[2024/05/31 19:25:24.019 +08:00] [INFO] [ddl.go:1298] ["DDL job is finished"] [category=ddl] [jobID=12697]
[2024/05/31 19:25:24.019 +08:00] [INFO] [callback.go:140] ["performing DDL change, must reload"] [category=ddl]
[2024/05/31 19:25:24.020 +08:00] [INFO] [domain.go:288] ["diff load InfoSchema success"] [isV2=false] [currentSchemaVersion=16550] [neededSchemaVersion=16553] ["start time"=589.049µs] [gotSchemaVersion=16553] [phyTblIDs="[12695]"] [actionTypes="[4]"] [diffTypes="[\"drop table\",\"drop table\",\"drop table\"]"]
[2024/05/31 19:25:24.022 +08:00] [INFO] [session.go:3959] ["CRUCIAL OPERATION"] [conn=3227518062] [schemaVersion=16553] [cur_db=explain] [sql="create table t(id int primary key, a int, b int);"] [user=root@%]
[2024/05/31 19:25:24.039 +08:00] [INFO] [ddl_worker.go:261] ["add DDL jobs"] [category=ddl] ["batch count"=1] [jobs="ID:12699, Type:create table, State:queueing, SchemaState:none, SchemaID:12693, TableID:12698, RowCount:0, ArgLen:2, start time: 2024-05-31 19:25:23.996 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false; "] [table=true]
[2024/05/31 19:25:24.039 +08:00] [INFO] [ddl.go:1192] ["start DDL job"] [category=ddl] [job="ID:12699, Type:create table, State:queueing, SchemaState:none, SchemaID:12693, TableID:12698, RowCount:0, ArgLen:2, start time: 2024-05-31 19:25:23.996 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false"] [query="create table t(id int primary key, a int, b int);"]
[2024/05/31 19:25:24.082 +08:00] [INFO] [ddl_worker.go:1210] ["run DDL job"] [worker="worker 4, tp general"] [category=ddl] [jobID=12699] [conn=3227518062] [category=ddl] [job="ID:12699, Type:create table, State:queueing, SchemaState:none, SchemaID:12693, TableID:12698, RowCount:0, ArgLen:0, start time: 2024-05-31 19:25:23.996 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false"]
[2024/05/31 19:25:24.111 +08:00] [INFO] [ddl_worker.go:763] ["finish DDL job"] [worker="worker 4, tp general"] [category=ddl] [jobID=12699] [conn=3227518062] [job="ID:12699, Type:create table, State:synced, SchemaState:public, SchemaID:12693, TableID:12698, RowCount:0, ArgLen:0, start time: 2024-05-31 19:25:23.996 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false"]
[2024/05/31 19:25:24.114 +08:00] [INFO] [ddl.go:1298] ["DDL job is finished"] [category=ddl] [jobID=12699]
[2024/05/31 19:25:24.114 +08:00] [INFO] [callback.go:140] ["performing DDL change, must reload"] [category=ddl]
[2024/05/31 19:25:24.114 +08:00] [INFO] [split_region.go:85] ["split batch regions request"] ["split key count"=1] ["batch count"=1] ["first batch, region ID"=14] ["first split key"=74800000000000319a]
[2024/05/31 19:25:24.116 +08:00] [INFO] [domain.go:288] ["diff load InfoSchema success"] [isV2=false] [currentSchemaVersion=16553] [neededSchemaVersion=16554] ["start time"=560.479µs] [gotSchemaVersion=16554] [phyTblIDs="[12698]"] [actionTypes="[3]"] [diffTypes="[\"create table\"]"]
[2024/05/31 19:25:24.118 +08:00] [INFO] [split_region.go:187] ["batch split regions complete"] ["batch region ID"=14] ["first at"=74800000000000319a] ["first new region left"="{Id:10252 StartKey:7480000000000031ff9700000000000000f8 EndKey:7480000000000031ff9a00000000000000f8 RegionEpoch:{ConfVer:1 Version:4886} Peers:[id:10253 store_id:1 ] EncryptionMeta:<nil> IsInFlashback:false FlashbackStartTs:0}"] ["new region count"=1]
[2024/05/31 19:25:24.118 +08:00] [INFO] [split_region.go:236] ["split regions complete"] ["region count"=1] ["region IDs"="[10252]"]
[2024/05/31 19:25:24.119 +08:00] [INFO] [session.go:3959] ["CRUCIAL OPERATION"] [conn=3227518062] [schemaVersion=16554] [cur_db=explain] [sql="drop table t;"] [user=root@%]
[2024/05/31 19:25:24.134 +08:00] [INFO] [ddl_worker.go:261] ["add DDL jobs"] [category=ddl] ["batch count"=1] [jobs="ID:12700, Type:drop table, State:queueing, SchemaState:public, SchemaID:12693, TableID:12698, RowCount:0, ArgLen:2, start time: 2024-05-31 19:25:24.096 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false; "] [table=true]
[2024/05/31 19:25:24.134 +08:00] [INFO] [ddl.go:1192] ["start DDL job"] [category=ddl] [job="ID:12700, Type:drop table, State:queueing, SchemaState:public, SchemaID:12693, TableID:12698, RowCount:0, ArgLen:2, start time: 2024-05-31 19:25:24.096 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false"] [query="drop table t;"]
[2024/05/31 19:25:24.176 +08:00] [INFO] [ddl_worker.go:1210] ["run DDL job"] [worker="worker 4, tp general"] [category=ddl] [jobID=12700] [conn=3227518062] [category=ddl] [job="ID:12700, Type:drop table, State:queueing, SchemaState:public, SchemaID:12693, TableID:12698, RowCount:0, ArgLen:0, start time: 2024-05-31 19:25:24.096 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false"]
[2024/05/31 19:25:24.205 +08:00] [INFO] [ddl_worker.go:1210] ["run DDL job"] [worker="worker 4, tp general"] [category=ddl] [jobID=12700] [conn=3227518062] [category=ddl] [job="ID:12700, Type:drop table, State:running, SchemaState:write only, SchemaID:12693, TableID:12698, RowCount:0, ArgLen:0, start time: 2024-05-31 19:25:24.096 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false"]
[2024/05/31 19:25:24.230 +08:00] [INFO] [ddl_worker.go:1210] ["run DDL job"] [worker="worker 4, tp general"] [category=ddl] [jobID=12700] [conn=3227518062] [category=ddl] [job="ID:12700, Type:drop table, State:running, SchemaState:delete only, SchemaID:12693, TableID:12698, RowCount:0, ArgLen:0, start time: 2024-05-31 19:25:24.096 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false"]
[2024/05/31 19:25:24.259 +08:00] [INFO] [delete_range.go:447] ["insert into delete-range table"] [category=ddl] [jobID=12700] [tableIDs="[12698]"] [comment="drop table: table ID"]
[2024/05/31 19:25:24.261 +08:00] [INFO] [delete_range.go:112] ["add job into delete-range table"] [category=ddl] [jobID=12700] [jobType="drop table"]
[2024/05/31 19:25:24.262 +08:00] [INFO] [ddl_worker.go:763] ["finish DDL job"] [worker="worker 4, tp general"] [category=ddl] [jobID=12700] [conn=3227518062] [job="ID:12700, Type:drop table, State:synced, SchemaState:none, SchemaID:12693, TableID:12698, RowCount:0, ArgLen:3, start time: 2024-05-31 19:25:24.096 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false"]
[2024/05/31 19:25:24.265 +08:00] [INFO] [ddl.go:1298] ["DDL job is finished"] [category=ddl] [jobID=12700]
[2024/05/31 19:25:24.265 +08:00] [INFO] [callback.go:140] ["performing DDL change, must reload"] [category=ddl]
[2024/05/31 19:25:24.267 +08:00] [INFO] [domain.go:288] ["diff load InfoSchema success"] [isV2=false] [currentSchemaVersion=16554] [neededSchemaVersion=16557] ["start time"=445.384µs] [gotSchemaVersion=16557] [phyTblIDs="[12698]"] [actionTypes="[4]"] [diffTypes="[\"drop table\",\"drop table\",\"drop table\"]"]
[2024/05/31 19:25:24.268 +08:00] [INFO] [session.go:3959] ["CRUCIAL OPERATION"] [conn=3227518062] [schemaVersion=16557] [cur_db=explain] [sql="drop view if exists v;"] [user=root@%]
[2024/05/31 19:25:24.285 +08:00] [INFO] [ddl_worker.go:261] ["add DDL jobs"] [category=ddl] ["batch count"=1] [jobs="ID:12702, Type:create view, State:queueing, SchemaState:none, SchemaID:12693, TableID:12701, RowCount:0, ArgLen:3, start time: 2024-05-31 19:25:24.246 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false; "] [table=true]
[2024/05/31 19:25:24.285 +08:00] [INFO] [ddl.go:1192] ["start DDL job"] [category=ddl] [job="ID:12702, Type:create view, State:queueing, SchemaState:none, SchemaID:12693, TableID:12701, RowCount:0, ArgLen:3, start time: 2024-05-31 19:25:24.246 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false"] [query="create view v as select cast(replace(substring_index(substring_index(\"\",',',1),':',-1),'\"','') as CHAR(32)) as event_id;"]
[2024/05/31 19:25:24.323 +08:00] [INFO] [ddl_worker.go:1210] ["run DDL job"] [worker="worker 4, tp general"] [category=ddl] [jobID=12702] [conn=3227518062] [category=ddl] [job="ID:12702, Type:create view, State:queueing, SchemaState:none, SchemaID:12693, TableID:12701, RowCount:0, ArgLen:0, start time: 2024-05-31 19:25:24.246 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false"]
[2024/05/31 19:25:24.349 +08:00] [INFO] [ddl_worker.go:763] ["finish DDL job"] [worker="worker 4, tp general"] [category=ddl] [jobID=12702] [conn=3227518062] [job="ID:12702, Type:create view, State:synced, SchemaState:public, SchemaID:12693, TableID:12701, RowCount:0, ArgLen:0, start time: 2024-05-31 19:25:24.246 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false"]
[2024/05/31 19:25:24.353 +08:00] [INFO] [ddl.go:1298] ["DDL job is finished"] [category=ddl] [jobID=12702]
[2024/05/31 19:25:24.353 +08:00] [INFO] [callback.go:140] ["performing DDL change, must reload"] [category=ddl]
[2024/05/31 19:25:24.353 +08:00] [INFO] [split_region.go:85] ["split batch regions request"] ["split key count"=1] ["batch count"=1] ["first batch, region ID"=14] ["first split key"=74800000000000319d]
[2024/05/31 19:25:24.354 +08:00] [INFO] [domain.go:288] ["diff load InfoSchema success"] [isV2=false] [currentSchemaVersion=16557] [neededSchemaVersion=16558] ["start time"=499.848µs] [gotSchemaVersion=16558] [phyTblIDs="[12701]"] [actionTypes="[21]"] [diffTypes="[\"create view\"]"]
[2024/05/31 19:25:24.357 +08:00] [INFO] [split_region.go:187] ["batch split regions complete"] ["batch region ID"=14] ["first at"=74800000000000319d] ["first new region left"="{Id:10254 StartKey:7480000000000031ff9a00000000000000f8 EndKey:7480000000000031ff9d00000000000000f8 RegionEpoch:{ConfVer:1 Version:4887} Peers:[id:10255 store_id:1 ] EncryptionMeta:<nil> IsInFlashback:false FlashbackStartTs:0}"] ["new region count"=1]
[2024/05/31 19:25:24.357 +08:00] [INFO] [split_region.go:236] ["split regions complete"] ["region count"=1] ["region IDs"="[10254]"]
[2024/05/31 19:25:24.357 +08:00] [INFO] [session.go:3959] ["CRUCIAL OPERATION"] [conn=3227518066] [schemaVersion=16558] [cur_db=test] [sql="drop database `explain`"] [user=root@%]
[2024/05/31 19:25:24.373 +08:00] [INFO] [ddl_worker.go:261] ["add DDL jobs"] [category=ddl] ["batch count"=1] [jobs="ID:12703, Type:drop schema, State:queueing, SchemaState:public, SchemaID:12693, TableID:0, RowCount:0, ArgLen:1, start time: 2024-05-31 19:25:24.346 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false; "] [table=true]
[2024/05/31 19:25:24.373 +08:00] [INFO] [ddl.go:1192] ["start DDL job"] [category=ddl] [job="ID:12703, Type:drop schema, State:queueing, SchemaState:public, SchemaID:12693, TableID:0, RowCount:0, ArgLen:1, start time: 2024-05-31 19:25:24.346 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false"] [query="drop database `explain`"]
[2024/05/31 19:25:24.413 +08:00] [INFO] [ddl_worker.go:1210] ["run DDL job"] [worker="worker 4, tp general"] [category=ddl] [jobID=12703] [conn=3227518066] [category=ddl] [job="ID:12703, Type:drop schema, State:queueing, SchemaState:public, SchemaID:12693, TableID:0, RowCount:0, ArgLen:0, start time: 2024-05-31 19:25:24.346 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false"]
[2024/05/31 19:25:24.438 +08:00] [INFO] [ddl_worker.go:1210] ["run DDL job"] [worker="worker 4, tp general"] [category=ddl] [jobID=12703] [conn=3227518066] [category=ddl] [job="ID:12703, Type:drop schema, State:running, SchemaState:write only, SchemaID:12693, TableID:0, RowCount:0, ArgLen:0, start time: 2024-05-31 19:25:24.346 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false"]
[2024/05/31 19:25:24.466 +08:00] [INFO] [ddl_worker.go:1210] ["run DDL job"] [worker="worker 4, tp general"] [category=ddl] [jobID=12703] [conn=3227518066] [category=ddl] [job="ID:12703, Type:drop schema, State:running, SchemaState:delete only, SchemaID:12693, TableID:0, RowCount:0, ArgLen:0, start time: 2024-05-31 19:25:24.346 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false"]
[2024/05/31 19:25:24.493 +08:00] [INFO] [delete_range.go:447] ["insert into delete-range table"] [category=ddl] [jobID=12703] [tableIDs="[12701]"] [comment="drop schema: table IDs"]
[2024/05/31 19:25:24.495 +08:00] [INFO] [delete_range.go:112] ["add job into delete-range table"] [category=ddl] [jobID=12703] [jobType="drop schema"]
[2024/05/31 19:25:24.495 +08:00] [INFO] [ddl_worker.go:763] ["finish DDL job"] [worker="worker 4, tp general"] [category=ddl] [jobID=12703] [conn=3227518066] [job="ID:12703, Type:drop schema, State:synced, SchemaState:none, SchemaID:12693, TableID:0, RowCount:0, ArgLen:1, start time: 2024-05-31 19:25:24.346 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false"]
[2024/05/31 19:25:24.498 +08:00] [INFO] [ddl.go:1298] ["DDL job is finished"] [category=ddl] [jobID=12703]
[2024/05/31 19:25:24.499 +08:00] [INFO] [callback.go:140] ["performing DDL change, must reload"] [category=ddl]
[2024/05/31 19:25:24.503 +08:00] [INFO] [domain.go:288] ["diff load InfoSchema success"] [isV2=false] [currentSchemaVersion=16558] [neededSchemaVersion=16561] ["start time"=507.581µs] [gotSchemaVersion=16561] [phyTblIDs="[12701]"] [actionTypes="[2]"] [diffTypes="[\"drop schema\",\"drop schema\",\"drop schema\"]"]
[2024/05/31 19:25:24.505 +08:00] [INFO] [session.go:3959] ["CRUCIAL OPERATION"] [conn=3227518068] [schemaVersion=16561] [cur_db=test] [sql="create database `explain_complex`"] [user=root@%]
[2024/05/31 19:25:24.521 +08:00] [INFO] [ddl_worker.go:261] ["add DDL jobs"] [category=ddl] ["batch count"=1] [jobs="ID:12705, Type:create schema, State:queueing, SchemaState:none, SchemaID:12704, TableID:0, RowCount:0, ArgLen:1, start time: 2024-05-31 19:25:24.496 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false; "] [table=true]
[2024/05/31 19:25:24.521 +08:00] [INFO] [ddl.go:1192] ["start DDL job"] [category=ddl] [job="ID:12705, Type:create schema, State:queueing, SchemaState:none, SchemaID:12704, TableID:0, RowCount:0, ArgLen:1, start time: 2024-05-31 19:25:24.496 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false"] [query="create database `explain_complex`"]
[2024/05/31 19:25:24.567 +08:00] [INFO] [ddl_worker.go:1210] ["run DDL job"] [worker="worker 4, tp general"] [category=ddl] [jobID=12705] [conn=3227518068] [category=ddl] [job="ID:12705, Type:create schema, State:queueing, SchemaState:none, SchemaID:12704, TableID:0, RowCount:0, ArgLen:0, start time: 2024-05-31 19:25:24.496 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false"]
[2024/05/31 19:25:24.597 +08:00] [INFO] [ddl_worker.go:763] ["finish DDL job"] [worker="worker 4, tp general"] [category=ddl] [jobID=12705] [conn=3227518068] [job="ID:12705, Type:create schema, State:synced, SchemaState:public, SchemaID:12704, TableID:0, RowCount:0, ArgLen:0, start time: 2024-05-31 19:25:24.496 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false"]
[2024/05/31 19:25:24.600 +08:00] [INFO] [ddl.go:1298] ["DDL job is finished"] [category=ddl] [jobID=12705]
[2024/05/31 19:25:24.600 +08:00] [INFO] [callback.go:140] ["performing DDL change, must reload"] [category=ddl]
[2024/05/31 19:25:24.601 +08:00] [INFO] [domain.go:288] ["diff load InfoSchema success"] [isV2=false] [currentSchemaVersion=16561] [neededSchemaVersion=16562] ["start time"=259.529µs] [gotSchemaVersion=16562] [phyTblIDs="[]"] [actionTypes="[]"] [diffTypes="[\"create schema\"]"]
[2024/05/31 19:25:24.606 +08:00] [INFO] [session.go:3959] ["CRUCIAL OPERATION"] [conn=3227518070] [schemaVersion=16562] [cur_db=explain_complex] [sql="CREATE TABLE `dt` (\n`id` int(11) unsigned NOT NULL AUTO_INCREMENT ,\n`aid` varchar(32) NOT NULL,\n`cm` int(10) unsigned NOT NULL,\n`pt` varchar(10) NOT NULL,\n`dic` varchar(64) DEFAULT NULL,\n`ip` varchar(15) DEFAULT NULL,\n`ds` date DEFAULT NULL,\n`ds2` varchar(13) DEFAULT NULL ,\n`t` int(13) DEFAULT NULL ,\n`ext` varchar(550) DEFAULT NULL,\n`p1` varchar(64) DEFAULT NULL ,\n`p2` varchar(64) DEFAULT NULL,\n`p3` varchar(64) DEFAULT NULL,\n`p4` varchar(64) DEFAULT NULL,\n`p5` varchar(64) DEFAULT NULL,\n`p6_md5` varchar(32) DEFAULT NULL,\n`p7_md5` varchar(32) DEFAULT NULL,\n`bm` tinyint(1) DEFAULT '0',\n`bgm` tinyint(1) DEFAULT '0',\n`insert_date` timestamp NOT NULL DEFAULT CURRENT_TIMESTAMP,\nPRIMARY KEY (`id`),\nUNIQUE KEY `aid` (`aid`,`dic`),\nKEY `ip` (`ip`),\nKEY `cmi` (`cm`)\n);"] [user=root@%]
[2024/05/31 19:25:24.623 +08:00] [INFO] [ddl_worker.go:261] ["add DDL jobs"] [category=ddl] ["batch count"=1] [jobs="ID:12707, Type:create table, State:queueing, SchemaState:none, SchemaID:12704, TableID:12706, RowCount:0, ArgLen:2, start time: 2024-05-31 19:25:24.596 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false; "] [table=true]
[2024/05/31 19:25:24.623 +08:00] [INFO] [ddl.go:1192] ["start DDL job"] [category=ddl] [job="ID:12707, Type:create table, State:queueing, SchemaState:none, SchemaID:12704, TableID:12706, RowCount:0, ArgLen:2, start time: 2024-05-31 19:25:24.596 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false"] [query="CREATE TABLE `dt` (\n`id` int(11) unsigned NOT NULL AUTO_INCREMENT ,\n`aid` varchar(32) NOT NULL,\n`cm` int(10) unsigned NOT NULL,\n`pt` varchar(10) NOT NULL,\n`dic` varchar(64) DEFAULT NULL,\n`ip` varchar(15) DEFAULT NULL,\n`ds` date DEFAULT NULL,\n`ds2` varchar(13) DEFAULT NULL ,\n`t` int(13) DEFAULT NULL ,\n`ext` varchar(550) DEFAULT NULL,\n`p1` varchar(64) DEFAULT NULL ,\n`p2` varchar(64) DEFAULT NULL,\n`p3` varchar(64) DEFAULT NULL,\n`p4` varchar(64) DEFAULT NULL,\n`p5` varchar(64) DEFAULT NULL,\n`p6_md5` varchar(32) DEFAULT NULL,\n`p7_md5` varchar(32) DEFAULT NULL,\n`bm` tinyint(1) DEFAULT '0',\n`bgm` tinyint(1) DEFAULT '0',\n`insert_date` timestamp NOT NULL DEFAULT CURRENT_TIMESTAMP,\nPRIMARY KEY (`id`),\nUNIQUE KEY `aid` (`aid`,`dic`),\nKEY `ip` (`ip`),\nKEY `cmi` (`cm`)\n);"]
[2024/05/31 19:25:24.658 +08:00] [INFO] [ddl_worker.go:1210] ["run DDL job"] [worker="worker 4, tp general"] [category=ddl] [jobID=12707] [conn=3227518070] [category=ddl] [job="ID:12707, Type:create table, State:queueing, SchemaState:none, SchemaID:12704, TableID:12706, RowCount:0, ArgLen:0, start time: 2024-05-31 19:25:24.596 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false"]
[2024/05/31 19:25:24.688 +08:00] [INFO] [ddl_worker.go:763] ["finish DDL job"] [worker="worker 4, tp general"] [category=ddl] [jobID=12707] [conn=3227518070] [job="ID:12707, Type:create table, State:synced, SchemaState:public, SchemaID:12704, TableID:12706, RowCount:0, ArgLen:0, start time: 2024-05-31 19:25:24.596 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false"]
[2024/05/31 19:25:24.693 +08:00] [INFO] [ddl.go:1298] ["DDL job is finished"] [category=ddl] [jobID=12707]
[2024/05/31 19:25:24.693 +08:00] [INFO] [callback.go:140] ["performing DDL change, must reload"] [category=ddl]
[2024/05/31 19:25:24.693 +08:00] [INFO] [split_region.go:85] ["split batch regions request"] ["split key count"=1] ["batch count"=1] ["first batch, region ID"=14] ["first split key"=7480000000000031a2]
[2024/05/31 19:25:24.694 +08:00] [INFO] [domain.go:288] ["diff load InfoSchema success"] [isV2=false] [currentSchemaVersion=16562] [neededSchemaVersion=16563] ["start time"=747.875µs] [gotSchemaVersion=16563] [phyTblIDs="[12706]"] [actionTypes="[3]"] [diffTypes="[\"create table\"]"]
[2024/05/31 19:25:24.696 +08:00] [INFO] [session.go:3959] ["CRUCIAL OPERATION"] [conn=3227518070] [schemaVersion=16563] [cur_db=explain_complex] [sql="CREATE TABLE `st` (\n`id` int(11) UNSIGNED NOT NULL AUTO_INCREMENT ,\n`pt` varchar(10) NOT NULL ,\n`aid` varchar(35) NOT NULL ,\n`cm` int(10) NOT NULL ,\n`ip` varchar(15) DEFAULT NULL ,\n`dic` varchar(64) DEFAULT NULL ,\n`dit` varchar(5) DEFAULT NULL,\n`p1` varchar(64) DEFAULT NULL ,\n`p2` varchar(64) DEFAULT NULL,\n`p3` varchar(64) DEFAULT NULL,\n`p4` varchar(64) DEFAULT NULL,\n`p5` varchar(64) DEFAULT NULL,\n`p6_md5` varchar(32) DEFAULT NULL,\n`p7_md5` varchar(32) DEFAULT NULL,\n`ext` varchar(550) DEFAULT NULL,\n`bm` tinyint(1) DEFAULT '0',\n`ds` date NOT NULL ,\n`ds2` varchar(13) DEFAULT NULL ,\n`t` int(13) NOT NULL ,\nPRIMARY KEY (`id`),\nKEY `t` (`t`),\nKEY `icd` (`cm`,`ds`)\n);"] [user=root@%]
[2024/05/31 19:25:24.697 +08:00] [INFO] [split_region.go:187] ["batch split regions complete"] ["batch region ID"=14] ["first at"=7480000000000031a2] ["first new region left"="{Id:10256 StartKey:7480000000000031ff9d00000000000000f8 EndKey:7480000000000031ffa200000000000000f8 RegionEpoch:{ConfVer:1 Version:4888} Peers:[id:10257 store_id:1 ] EncryptionMeta:<nil> IsInFlashback:false FlashbackStartTs:0}"] ["new region count"=1]
[2024/05/31 19:25:24.697 +08:00] [INFO] [split_region.go:236] ["split regions complete"] ["region count"=1] ["region IDs"="[10256]"]
[2024/05/31 19:25:24.714 +08:00] [INFO] [ddl_worker.go:261] ["add DDL jobs"] [category=ddl] ["batch count"=1] [jobs="ID:12709, Type:create table, State:queueing, SchemaState:none, SchemaID:12704, TableID:12708, RowCount:0, ArgLen:2, start time: 2024-05-31 19:25:24.696 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false; "] [table=true]
[2024/05/31 19:25:24.714 +08:00] [INFO] [ddl.go:1192] ["start DDL job"] [category=ddl] [job="ID:12709, Type:create table, State:queueing, SchemaState:none, SchemaID:12704, TableID:12708, RowCount:0, ArgLen:2, start time: 2024-05-31 19:25:24.696 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false"] [query="CREATE TABLE `st` (\n`id` int(11) UNSIGNED NOT NULL AUTO_INCREMENT ,\n`pt` varchar(10) NOT NULL ,\n`aid` varchar(35) NOT NULL ,\n`cm` int(10) NOT NULL ,\n`ip` varchar(15) DEFAULT NULL ,\n`dic` varchar(64) DEFAULT NULL ,\n`dit` varchar(5) DEFAULT NULL,\n`p1` varchar(64) DEFAULT NULL ,\n`p2` varchar(64) DEFAULT NULL,\n`p3` varchar(64) DEFAULT NULL,\n`p4` varchar(64) DEFAULT NULL,\n`p5` varchar(64) DEFAULT NULL,\n`p6_md5` varchar(32) DEFAULT NULL,\n`p7_md5` varchar(32) DEFAULT NULL,\n`ext` varchar(550) DEFAULT NULL,\n`bm` tinyint(1) DEFAULT '0',\n`ds` date NOT NULL ,\n`ds2` varchar(13) DEFAULT NULL ,\n`t` int(13) NOT NULL ,\nPRIMARY KEY (`id`),\nKEY `t` (`t`),\nKEY `icd` (`cm`,`ds`)\n);"]
[2024/05/31 19:25:24.750 +08:00] [INFO] [ddl_worker.go:1210] ["run DDL job"] [worker="worker 4, tp general"] [category=ddl] [jobID=12709] [conn=3227518070] [category=ddl] [job="ID:12709, Type:create table, State:queueing, SchemaState:none, SchemaID:12704, TableID:12708, RowCount:0, ArgLen:0, start time: 2024-05-31 19:25:24.696 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false"]
[2024/05/31 19:25:24.777 +08:00] [INFO] [ddl_worker.go:763] ["finish DDL job"] [worker="worker 4, tp general"] [category=ddl] [jobID=12709] [conn=3227518070] [job="ID:12709, Type:create table, State:synced, SchemaState:public, SchemaID:12704, TableID:12708, RowCount:0, ArgLen:0, start time: 2024-05-31 19:25:24.696 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false"]
[2024/05/31 19:25:24.782 +08:00] [INFO] [ddl.go:1298] ["DDL job is finished"] [category=ddl] [jobID=12709]
[2024/05/31 19:25:24.782 +08:00] [INFO] [callback.go:140] ["performing DDL change, must reload"] [category=ddl]
[2024/05/31 19:25:24.782 +08:00] [INFO] [split_region.go:85] ["split batch regions request"] ["split key count"=1] ["batch count"=1] ["first batch, region ID"=14] ["first split key"=7480000000000031a4]
[2024/05/31 19:25:24.784 +08:00] [INFO] [domain.go:288] ["diff load InfoSchema success"] [isV2=false] [currentSchemaVersion=16563] [neededSchemaVersion=16564] ["start time"=765.44µs] [gotSchemaVersion=16564] [phyTblIDs="[12708]"] [actionTypes="[3]"] [diffTypes="[\"create table\"]"]
[2024/05/31 19:25:24.785 +08:00] [INFO] [session.go:3959] ["CRUCIAL OPERATION"] [conn=3227518070] [schemaVersion=16564] [cur_db=explain_complex] [sql="CREATE TABLE `dd` (\n`id` int(11) UNSIGNED NOT NULL AUTO_INCREMENT ,\n`aid` varchar(35) NOT NULL ,\n`pt` varchar(10) NOT NULL ,\n`dic` varchar(64) NOT NULL,\n`dim` varchar(32) NOT NULL ,\n`mac` varchar(32) DEFAULT NULL ,\n`ip` varchar(15) DEFAULT NULL ,\n`t` int(13) DEFAULT NULL ,\n`bm` tinyint(1) DEFAULT '0',\nPRIMARY KEY (`id`),\nUNIQUE KEY `aid` (`aid`,`dic`),\nKEY `ip` (`ip`),\nKEY `pi` (`aid`,`dim`),\nKEY `t` (`t`)\n);"] [user=root@%]
[2024/05/31 19:25:24.786 +08:00] [INFO] [split_region.go:187] ["batch split regions complete"] ["batch region ID"=14] ["first at"=7480000000000031a4] ["first new region left"="{Id:10258 StartKey:7480000000000031ffa200000000000000f8 EndKey:7480000000000031ffa400000000000000f8 RegionEpoch:{ConfVer:1 Version:4889} Peers:[id:10259 store_id:1 ] EncryptionMeta:<nil> IsInFlashback:false FlashbackStartTs:0}"] ["new region count"=1]
[2024/05/31 19:25:24.786 +08:00] [INFO] [split_region.go:236] ["split regions complete"] ["region count"=1] ["region IDs"="[10258]"]
[2024/05/31 19:25:24.804 +08:00] [INFO] [ddl_worker.go:261] ["add DDL jobs"] [category=ddl] ["batch count"=1] [jobs="ID:12711, Type:create table, State:queueing, SchemaState:none, SchemaID:12704, TableID:12710, RowCount:0, ArgLen:2, start time: 2024-05-31 19:25:24.796 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false; "] [table=true]
[2024/05/31 19:25:24.804 +08:00] [INFO] [ddl.go:1192] ["start DDL job"] [category=ddl] [job="ID:12711, Type:create table, State:queueing, SchemaState:none, SchemaID:12704, TableID:12710, RowCount:0, ArgLen:2, start time: 2024-05-31 19:25:24.796 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false"] [query="CREATE TABLE `dd` (\n`id` int(11) UNSIGNED NOT NULL AUTO_INCREMENT ,\n`aid` varchar(35) NOT NULL ,\n`pt` varchar(10) NOT NULL ,\n`dic` varchar(64) NOT NULL,\n`dim` varchar(32) NOT NULL ,\n`mac` varchar(32) DEFAULT NULL ,\n`ip` varchar(15) DEFAULT NULL ,\n`t` int(13) DEFAULT NULL ,\n`bm` tinyint(1) DEFAULT '0',\nPRIMARY KEY (`id`),\nUNIQUE KEY `aid` (`aid`,`dic`),\nKEY `ip` (`ip`),\nKEY `pi` (`aid`,`dim`),\nKEY `t` (`t`)\n);"]
[2024/05/31 19:25:24.848 +08:00] [INFO] [ddl_worker.go:1210] ["run DDL job"] [worker="worker 4, tp general"] [category=ddl] [jobID=12711] [conn=3227518070] [category=ddl] [job="ID:12711, Type:create table, State:queueing, SchemaState:none, SchemaID:12704, TableID:12710, RowCount:0, ArgLen:0, start time: 2024-05-31 19:25:24.796 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false"]
[2024/05/31 19:25:24.875 +08:00] [INFO] [ddl_worker.go:763] ["finish DDL job"] [worker="worker 4, tp general"] [category=ddl] [jobID=12711] [conn=3227518070] [job="ID:12711, Type:create table, State:synced, SchemaState:public, SchemaID:12704, TableID:12710, RowCount:0, ArgLen:0, start time: 2024-05-31 19:25:24.796 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false"]
[2024/05/31 19:25:24.879 +08:00] [INFO] [ddl.go:1298] ["DDL job is finished"] [category=ddl] [jobID=12711]
[2024/05/31 19:25:24.879 +08:00] [INFO] [callback.go:140] ["performing DDL change, must reload"] [category=ddl]
[2024/05/31 19:25:24.879 +08:00] [INFO] [split_region.go:85] ["split batch regions request"] ["split key count"=1] ["batch count"=1] ["first batch, region ID"=14] ["first split key"=7480000000000031a6]
[2024/05/31 19:25:24.881 +08:00] [INFO] [domain.go:288] ["diff load InfoSchema success"] [isV2=false] [currentSchemaVersion=16564] [neededSchemaVersion=16565] ["start time"=636.877µs] [gotSchemaVersion=16565] [phyTblIDs="[12710]"] [actionTypes="[3]"] [diffTypes="[\"create table\"]"]
[2024/05/31 19:25:24.882 +08:00] [INFO] [session.go:3959] ["CRUCIAL OPERATION"] [conn=3227518070] [schemaVersion=16565] [cur_db=explain_complex] [sql="CREATE TABLE `pp` (\n`oid` varchar(20) NOT NULL,\n`uid` bigint(20) unsigned NOT NULL,\n`cid` int(11) unsigned NOT NULL,\n`ppt` int(11) NOT NULL DEFAULT '0',\n`pdt` int(11) DEFAULT '0',\n`am` decimal(10,2) unsigned NOT NULL DEFAULT '0.00',\n`cc` decimal(10,2) NOT NULL DEFAULT '0.00',\n`ps` tinyint(1) NOT NULL,\n`tid` varchar(200) DEFAULT NULL,\n`ppf` varchar(50) NOT NULL,\n`bs` tinyint(1) NOT NULL DEFAULT '0',\n`bex` tinyint(1) NOT NULL DEFAULT '0',\n`bu` int(11) NOT NULL DEFAULT '0',\n`pc` char(10) NOT NULL DEFAULT 'CNY',\n`ui` int(16) NOT NULL DEFAULT '1',\n`cr` decimal(10,4) unsigned NOT NULL DEFAULT '1.0000',\n`pi` int(11) unsigned NOT NULL,\n`si` int(11) unsigned NOT NULL,\n`bcc` int(11) NOT NULL DEFAULT '0',\n`acc` int(11) NOT NULL DEFAULT '0',\nKEY `oid` (`oid`),\nKEY `uid` (`uid`),\nKEY `ppt` (`ppt`),\nKEY `pdt` (`pdt`),\nKEY `cid` (`cid`),\nKEY `ps` (`ps`),\nKEY `sp` (`uid`,`pi`)\n);"] [user=root@%]
[2024/05/31 19:25:24.883 +08:00] [INFO] [split_region.go:187] ["batch split regions complete"] ["batch region ID"=14] ["first at"=7480000000000031a6] ["first new region left"="{Id:10260 StartKey:7480000000000031ffa400000000000000f8 EndKey:7480000000000031ffa600000000000000f8 RegionEpoch:{ConfVer:1 Version:4890} Peers:[id:10261 store_id:1 ] EncryptionMeta:<nil> IsInFlashback:false FlashbackStartTs:0}"] ["new region count"=1]
[2024/05/31 19:25:24.883 +08:00] [INFO] [split_region.go:236] ["split regions complete"] ["region count"=1] ["region IDs"="[10260]"]
[2024/05/31 19:25:24.902 +08:00] [INFO] [ddl_worker.go:261] ["add DDL jobs"] [category=ddl] ["batch count"=1] [jobs="ID:12713, Type:create table, State:queueing, SchemaState:none, SchemaID:12704, TableID:12712, RowCount:0, ArgLen:2, start time: 2024-05-31 19:25:24.895 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false; "] [table=true]
[2024/05/31 19:25:24.902 +08:00] [INFO] [ddl.go:1192] ["start DDL job"] [category=ddl] [job="ID:12713, Type:create table, State:queueing, SchemaState:none, SchemaID:12704, TableID:12712, RowCount:0, ArgLen:2, start time: 2024-05-31 19:25:24.895 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false"] [query="CREATE TABLE `pp` (\n`oid` varchar(20) NOT NULL,\n`uid` bigint(20) unsigned NOT NULL,\n`cid` int(11) unsigned NOT NULL,\n`ppt` int(11) NOT NULL DEFAULT '0',\n`pdt` int(11) DEFAULT '0',\n`am` decimal(10,2) unsigned NOT NULL DEFAULT '0.00',\n`cc` decimal(10,2) NOT NULL DEFAULT '0.00',\n`ps` tinyint(1) NOT NULL,\n`tid` varchar(200) DEFAULT NULL,\n`ppf` varchar(50) NOT NULL,\n`bs` tinyint(1) NOT NULL DEFAULT '0',\n`bex` tinyint(1) NOT NULL DEFAULT '0',\n`bu` int(11) NOT NULL DEFAULT '0',\n`pc` char(10) NOT NULL DEFAULT 'CNY',\n`ui` int(16) NOT NULL DEFAULT '1',\n`cr` decimal(10,4) unsigned NOT NULL DEFAULT '1.0000',\n`pi` int(11) unsigned NOT NULL,\n`si` int(11) unsigned NOT NULL,\n`bcc` int(11) NOT NULL DEFAULT '0',\n`acc` int(11) NOT NULL DEFAULT '0',\nKEY `oid` (`oid`),\nKEY `uid` (`uid`),\nKEY `ppt` (`ppt`),\nKEY `pdt` (`pdt`),\nKEY `cid` (`cid`),\nKEY `ps` (`ps`),\nKEY `sp` (`uid`,`pi`)\n);"]
[2024/05/31 19:25:24.939 +08:00] [INFO] [ddl_worker.go:1210] ["run DDL job"] [worker="worker 4, tp general"] [category=ddl] [jobID=12713] [conn=3227518070] [category=ddl] [job="ID:12713, Type:create table, State:queueing, SchemaState:none, SchemaID:12704, TableID:12712, RowCount:0, ArgLen:0, start time: 2024-05-31 19:25:24.895 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false"]
[2024/05/31 19:25:24.966 +08:00] [INFO] [ddl_worker.go:763] ["finish DDL job"] [worker="worker 4, tp general"] [category=ddl] [jobID=12713] [conn=3227518070] [job="ID:12713, Type:create table, State:synced, SchemaState:public, SchemaID:12704, TableID:12712, RowCount:0, ArgLen:0, start time: 2024-05-31 19:25:24.895 +0800 CST, Err:<nil>, ErrCount:0, SnapshotVersion:0, LocalMode: false"]
[2024/05/31 19:25:24.971 +08:00] [INFO] [ddl.go:1298] ["DDL job is finished"] [category=ddl] [jobID=12713]
[2024/05/31 19:25:24.971 +08:00] [INFO] [callback.go:140] ["performing DDL change, must reload"] [category=ddl]
[2024/05/31 19:25:24.971 +08:00] [INFO] [split_region.go:85] ["split batch regions request"] ["split key count"=1] ["batch count"=1] ["first batch, region ID"=14] ["first split key"=7480000000000031a8]
[2024/05/31 19:25:24.971 +08:00] [INFO] [signal_posix.go:54] ["got signal to exit"] [signal=terminated]
[2024/05/31 19:25:24.971 +08:00] [INFO] [server.go:584] ["setting tidb-server to report unhealthy (shutting-down)"]
[2024/05/31 19:25:24.972 +08:00] [ERROR] [http_status.go:531] ["start status/rpc server error"] [error="accept tcp 127.0.0.1:43139: use of closed network connection"]
[2024/05/31 19:25:24.972 +08:00] [ERROR] [http_status.go:526] ["http server error"] [error="http: Server closed"]
[2024/05/31 19:25:24.972 +08:00] [ERROR] [http_status.go:521] ["grpc server error"] [error="mux: server closed"]
[2024/05/31 19:25:24.972 +08:00] [INFO] [manager.go:232] ["retire owner"] ["owner info"="[autoid] tidb/autoid/leader ownerManager 127.0.0.1:43139"]
[2024/05/31 19:25:24.972 +08:00] [WARN] [manager.go:315] ["is not the owner"] ["owner info"="[autoid] tidb/autoid/leader ownerManager 127.0.0.1:43139"]
[2024/05/31 19:25:24.972 +08:00] [INFO] [manager.go:282] ["break campaign loop, context is done"] ["owner info"="[autoid] tidb/autoid/leader ownerManager 127.0.0.1:43139"]
[Pipeline] archiveArtifacts
Archiving artifacts
No test report files were found. Configuration error?
[Pipeline] }
[Pipeline] // dir
[Pipeline] }
[Pipeline] // stage
[Pipeline] }
[Pipeline] // container
[Pipeline] }
[Pipeline] // withEnv
[Pipeline] }
[Pipeline] // node
[Pipeline] }
[Pipeline] // podTemplate
[Pipeline] }
[Pipeline] // withEnv
[Pipeline] }
[Pipeline] // stage
[Pipeline] }
Failed in branch Matrix - SCRIPT_AND_ARGS = 'integrationtest_with_tikv.sh y'
[Pipeline] // parallel
[Pipeline] }
[Pipeline] // stage
[Pipeline] }
[Pipeline] // timeout
[Pipeline] }
[Pipeline] // withEnv
[Pipeline] }
[Pipeline] // container
[Pipeline] }
[Pipeline] // withEnv
[Pipeline] }
[Pipeline] // node
[Pipeline] }
[Pipeline] // podTemplate
[Pipeline] End of Pipeline
ERROR: script returned exit code 2
Finished: FAILURE