[gbase@gbase_rh7_015 test1_n2]$ ll\ntotal 8\n-rw------- 1 gbase gbase 91 Feb 14 16:06 C00000.seg.1\n-rw------- 1 gbase gbase 88 Feb 14 15:56 C00000.seg.BAK\n[gbase@gbase_rh7_015 test1_n2]$ mv C00000.seg.1 C00000.seg.1.BAK\n<\/code><\/pre>\n\n\n\n\u6d4b\u8bd5\u65f6\uff0c\u4e3a\u4e86\u907f\u514d\u6570\u636e\u7f13\u51b2\u7684\u5f71\u54cd\uff0c\u53ef\u4ee5\u91cd\u542f\u8282\u70b9\u670d\u52a1\uff0c\u6216\u8005\u96c6\u7fa4\u5c42release cache<\/p>\n\n\n\n
\ngbase> release cache;\nQuery OK, 0 rows affected (Elapsed: 00:00:00.00)<\/code><\/pre>\n\n\n\n<\/span>\u67e5\u8be2\u62a5\u9519<\/span><\/h3>\n\n\n\ngbase> select * from test1;\nERROR 1708 (HY000): [10.0.2.115:5050](GBA-02AD-0005)Failed to query in gnode:\nDETAIL: Gbase database\/table corrupted (testdb.test1_n2.1.75.\/opt\/gbase\/gnode\/userdata\/gbase\/testdb\/sys_tablespace\/test1_n2\/C00000.seg.1): I\/O operation on \/opt\/gbase\/gnode\/userdata\/gbase\/testdb\/sys_tablespace\/test1_n2\/C00000.seg.1 failed with error - No such file or directory, File name \/opt\/gbase\/gnode\/userdata\/gbase\/testdb\/sys_tablespace\/test1_n2\/C00000.seg.1\nSQL: SELECT \/*10.0.2.101_31_9_2022-02-14_16:08:13*\/ \/*+ TID('3473447') *\/ `vcname000001.\ngbase>\n<\/code><\/pre>\n\n\n\n<\/span>gcluster\u7684express.log\u91cc\u62a5\u9519\uff0c\u5e76\u8bbe\u7f6e\u4e86event<\/span><\/h3>\n\n\n\n\u8bbe\u7f6e\u4fe1\u606f\u5982\u4e0b<\/p>\n\n\n\n
2022-02-14 16:08:13.466 [DDL][WARN ][S:31][Q:57]<DealCorruptError|2687>:Set testdb.test1 StorageEvent successfully, corrupt info: Gbase database\/table corrupted <\/code><\/pre>\n\n\n\n\u5b8c\u6574\u65e5\u5fd7\u5982\u4e0b<\/p>\n\n\n\n
2022-02-14 16:08:13.433 [SQLDISP][ERROR][S:31][Q:57]<StoreResult|2277>:ForwardResult failed. reason: HOST(10.0.2.115:5050->74), Gbase database\/table corrupted (testdb.test1_n2.1.75.\/opt\/gbase\/gnode\/userdata\/gbase\/testdb\/sys_tablespace\/test1_n2\/C00000.seg.1): I\/O operation on \/opt\/gbase\/gnode\/userdata\/gbase\/testdb\/sys_tablespace\/test1_n2\/C00000.seg.1 failed with error - No such file or directory, File name \/opt\/gbase\/gnode\/userdata\/gbase\/testdb\/sys_tablespace\/test1_n2\/C00000.seg.1\n2022-02-14 16:08:13.436 [SQLDISP][ERROR][S:31][Q:57]<ExecSQL|2567>:StoreResult failed, THD(0x3b54000) HOST(10.0.2.115:5050->74) reason: Gbase database\/table corrupted (testdb.test1_n2.1.75.\/opt\/gbase\/gnode\/userdata\/gbase\/testdb\/sys_tablespace\/test1_n2\/C00000.seg.1): I\/O operation on \/opt\/gbase\/gnode\/userdata\/gbase\/testdb\/sys_tablespace\/test1_n2\/C00000.seg.1 failed with error - No such file or directory, File name \/opt\/gbase\/gnode\/userdata\/gbase\/testdb\/sys_tablespace\/test1_n2\/C00000.seg.1\n2022-02-14 16:08:13.436 [DDL][WARN ][S:31][Q:57]<DealCorruptError|2506>:Catch corrupt info from gnode - Gbase database\/table corrupted (testdb.test1_n2.1.75.\/opt\/gbase\/gnode\/userdata\/gbase\/testdb\/sys_tablespace\/test1_n2\/C00000.seg.1): I\/O operation on \/opt\/gbase\/gnode\/userdata\/gbase\/testdb\/sys_tablespace\/test1_n2\/C00000.seg.1 failed with error - No such file or directory, File name \/opt\/gbase\/gnode\/userdata\/gbase\/testdb\/sys_tablespace\/test1_n2\/C00000.seg.1\n2022-02-14 16:08:13.466 [DDL][WARN ][S:31][Q:57]<DealCorruptError|2687>:Set testdb.test1 StorageEvent successfully, corrupt info: Gbase database\/table corrupted (testdb.test1_n2.1.75.\/opt\/gbase\/gnode\/userdata\/gbase\/testdb\/sys_tablespace\/test1_n2\/C00000.seg.1): I\/O operation on \/opt\/gbase\/gnode\/userdata\/gbase\/testdb\/sys_tablespace\/test1_n2\/C00000.seg.1 failed with error - No such file or directory, File name \/opt\/gbase\/gnode\/userdata\/gbase\/testdb\/sys_tablespace\/test1_n2\/C00000.seg.1\n2022-02-14 16:08:13.466 [EXECTR][ERROR][S:31][Q:57]<DispatchTasks|3248>:Async task error: [10.0.2.115:5050](GBA-02AD-0005)Failed to query in gnode:\nDETAIL: Gbase database\/table corrupted (testdb.test1_n2.1.75.\/opt\/gbase\/gnode\/userdata\/gbase\/testdb\/sys_tablespace\/test1_n2\/C00000.seg.1): I\/O operation on \/opt\/gbase\/gnode\/userdata\/gbase\/testdb\/sys_tablespace\/test1_n2\/C00000.seg.1 failed with error - No such file or directory, File name \/opt\/gbase\/gnode\/userdata\/gbase\/testdb\/sys_tablespace\/test1_n2\/C00000.seg.1\nSQL: SELECT \/*10.0.2.101_31_9_2022-02-14_16:08:13*\/ \/*+ TID('3473447') *\/ `vcname000001.testdb.test1`.`id` AS `id` FROM `testdb`.`test1_n2` `vcname000001.testdb.test1`\n2022-02-14 16:08:13.469 [EXECTR][ERROR][S:31][Q:57]<ExecutePlan|153>:ExecutePlan failed.\n<\/code><\/pre>\n\n\n\n<\/span>\u5206\u7247\u8868\u4e22\u5931<\/span><\/h2>\n\n\n\n\u6b64\u60c5\u51b5\u591a\u7531\u4e8e\u73af\u5883\u95ee\u9898\u5bfc\u81f4\uff0c\u6bd4\u5982\u78c1\u76d8\u7a7a\u95f4\u6ee1\uff0c\u6587\u4ef6\u7cfb\u7edf\u6545\u969c\u7b49\uff0c\u4e5f\u5305\u62ec\u4eba\u5de5\u8bef\u64cd\u4f5c\u5220\u9664\u3002\u6b64\u65f6\u6545\u969c\u8282\u70b9\u4e0d\u5b58\u5728\u5206\u7247\u8868\u4e86\u3002<\/p>\n\n\n\n
<\/span>\u5220\u6389\u8868\u5206\u7247<\/span><\/h3>\n\n\n\n\u901a\u8fc7gncli \u767b\u5f55\uff0c\u5220\u6389test1_n1\u5206\u7247\u3002<\/p>\n\n\n\n
gbase> drop table test1_n1;\nQuery OK, 0 rows affected (Elapsed: 00:00:00.00)\n\ngbase> select * from test1_n1;\nERROR 1146 (42S02): Table 'testdb.test1_n1' doesn't exist<\/code><\/pre>\n\n\n\n<\/span>\u5173\u95ed\u8282\u70b9101\uff0c \u9ed8\u8ba4\u7cfb\u7edf\u6545\u969c<\/span><\/h3>\n\n\n\n\u53ef\u4ee5\u770b\u5230101\u7684gnode\u72b6\u6001\u4e3aCLOSE<\/span><\/p>\n\n\n\n[gbase@gbase_rh7_001 ~]$ gcluster_services gbase stop\nStopping gbase : [ OK ]\n[gbase@gbase_rh7_001 ~]$ gcadmin\nCLUSTER STATE: ACTIVE\nVIRTUAL CLUSTER MODE: NORMAL\n\n=============================================================\n| GBASE COORDINATOR CLUSTER INFORMATION |\n=============================================================\n| NodeName | IpAddress | gcware | gcluster | DataState |\n-------------------------------------------------------------\n| coordinator1 | 10.0.2.101 | OPEN | OPEN | 0 |\n-------------------------------------------------------------\n| coordinator2 | 10.0.2.115 | OPEN | OPEN | 0 |\n-------------------------------------------------------------\n=========================================================================================================\n| GBASE DATA CLUSTER INFORMATION |\n=========================================================================================================\n| NodeName | IpAddress | DistributionId | gnode | syncserver | DataState |\n---------------------------------------------------------------------------------------------------------\n| node1 | 10.0.2.101 | 1 | CLOSE<\/span> | OPEN | 0 |\n---------------------------------------------------------------------------------------------------------\n| node2 | 10.0.2.115 | 1 | OPEN | OPEN | 0 |\n---------------------------------------------------------------------------------------------------------<\/code><\/pre>\n\n\n\n<\/span>\u9ed8\u8ba4\u540e\u7eed\u6570\u636e\u64cd\u4f5c\uff0c\u89e6\u53d1event<\/span><\/h3>\n\n\n\n\u518d\u6b21\u5411test1\u8868\u63d2\u5165\u6570\u636e<\/p>\n\n\n\n
[gbase@gbase_rh7_001 ~]$ gccli testdb\n\nGBase client 9.5.2.44.283f8db1. Copyright (c) 2004-2022, GBase. All Rights Reserved.\n\ngbase> insert into test1 values(5),(6),(7),(8);\nQuery OK, 4 rows affected (Elapsed: 00:00:00.15)\nRecords: 4 Duplicates: 0 Warnings: 0<\/code><\/pre>\n\n\n\n<\/span>\u67e5\u770bevent<\/span><\/h3>\n\n\n\n\u67e5\u770b\u96c6\u7fa4\u72b6\u6001gcadmin, \u770b\u5230\u53ef\u4ee5101\u7684DataState\u4e3a1\uff0c\u6709\u4e0d\u4e00\u81f4\u72b6\u6001\u3002<\/p>\n\n\n\n
\u67e5\u770bevent\u8be6\u60c5\uff0c\u67091\u4e2admlevent, \u5bf9\u5e94101\u8282\u70b9\u7684test1\u8868\u7684\u5206\u7247n1\u548cn2, \u5176\u4e2dn1\u7684\u8868\u5df2\u7ecf\u88ab\u5220\u6389\uff0c n2\u53ea\u662f\u5355\u7eaf\u7684\u6570\u636e\u4e0d\u4e00\u81f4\u3002<\/p>\n\n\n\n
[gbase@gbase_rh7_001 ~]$ gcadmin\nCLUSTER STATE: ACTIVE\nVIRTUAL CLUSTER MODE: NORMAL\n\n=============================================================\n| GBASE COORDINATOR CLUSTER INFORMATION |\n=============================================================\n| NodeName | IpAddress | gcware | gcluster | DataState |\n-------------------------------------------------------------\n| coordinator1 | 10.0.2.101 | OPEN | OPEN | 0 |\n-------------------------------------------------------------\n| coordinator2 | 10.0.2.115 | OPEN | OPEN | 0 |\n-------------------------------------------------------------\n=========================================================================================================\n| GBASE DATA CLUSTER INFORMATION |\n=========================================================================================================\n| NodeName | IpAddress | DistributionId | gnode | syncserver | DataState |\n---------------------------------------------------------------------------------------------------------\n| node1 | 10.0.2.101 | 1 | CLOSE | OPEN | 1<\/span> |\n---------------------------------------------------------------------------------------------------------\n| node2 | 10.0.2.115 | 1 | OPEN | OPEN | 0 |\n---------------------------------------------------------------------------------------------------------\n\n[gbase@gbase_rh7_001 ~]$ gcadmin showdmlevent\nVc event count:1\nEvent ID: 2\nObjectName: testdb.test1\n\nFail Data Copy:\n------------------------------------------------------\nSegName: n1 SCN: 19459 NodeIP: 10.0.2.101 FAILURE\nSegName: n2 SCN: 19459 NodeIP: 10.0.2.101 FAILURE\n\n\n[gbase@gbase_rh7_001 ~]$ gcadmin showddlevent\nVc event count:0\n[gbase@gbase_rh7_001 ~]$ gcadmin showdmlstorageevent\nVc event count:0\n[gbase@gbase_rh7_001 ~]$ <\/code><\/pre>\n\n\n\n<\/span>\u542f\u52a8\u670d\u52a1\uff0c\u6a21\u62df\u6545\u969c\u6062\u590d<\/span><\/h3>\n\n\n\n\u542f\u52a8\u670d\u52a1\u540e\uff0c101\u8282\u70b9\u7684gnode\u670d\u52a1\u72b6\u6001\u4eceCLOSE\u53d8\u6210\u4e86OPEN<\/p>\n\n\n\n
[gbase@gbase_rh7_001 ~]$ gcluster_services gbase start\nStarting gbase : [ OK ]\n[gbase@gbase_rh7_001 ~]$ \n[gbase@gbase_rh7_001 gcluster]$ gcadmin\nCLUSTER STATE: ACTIVE\nVIRTUAL CLUSTER MODE: NORMAL\n\n=============================================================\n| GBASE COORDINATOR CLUSTER INFORMATION |\n=============================================================\n| NodeName | IpAddress | gcware | gcluster | DataState |\n-------------------------------------------------------------\n| coordinator1 | 10.0.2.101 | OPEN | OPEN | 0 |\n-------------------------------------------------------------\n| coordinator2 | 10.0.2.115 | OPEN | OPEN | 0 |\n-------------------------------------------------------------\n=========================================================================================================\n| GBASE DATA CLUSTER INFORMATION |\n=========================================================================================================\n| NodeName | IpAddress | DistributionId | gnode | syncserver | DataState |\n---------------------------------------------------------------------------------------------------------\n| node1 | 10.0.2.101 | 1 | OPEN<\/span> | OPEN | 1 |\n---------------------------------------------------------------------------------------------------------\n| node2 | 10.0.2.115 | 1 | OPEN | OPEN | 0 |\n---------------------------------------------------------------------------------------------------------\n<\/code><\/pre>\n\n\n\n<\/span>\u67e5\u770b\u540c\u6b65\u65e5\u5fd7<\/span><\/h3>\n\n\n\n\u8be5\u65e5\u5fd7gc_recover.log\u53ef\u80fd\u51fa\u73b0\u5728\u4efb\u610f\u4e00\u53f0\u8c03\u5ea6\u8282\u70b9\u7684\u65e5\u5fd7\u76ee\u5f55\u5185\uff0c\u4e00\u822c\u662f\u7b2c\u4e00\u53f0\uff0c\u5982\u53d1\u73b0\u5176\u65e5\u5fd7\u957f\u65f6\u95f4\u6ca1\u6709\u53d8\u52a8\uff0c\u5219\u8868\u793a\u540c\u6b65\u7684master\u8fc1\u79fb\u5230\u5176\u5b83\u8282\u70b9\u4e86\u3002<\/p>\n\n\n\n
\u4ece\u65e5\u5fd7\u770b\uff0c\u671f\u95f4\u81ea\u52a8\u751f\u6210\u4e86dmlstorageevent, \u5e76\u91cd\u5efa\u5efa\u4e86\u5206\u7247\u8868\u3002<\/p>\n\n\n\n
DoDmlRecover 609 table not in local data node,but in source gcluster, convert to dmlstorage.<\/code><\/pre>\n\n\n\n\u540e\u9762\u4ece\u526f\u672c\u5206\u7247\u62ff\u5230\u4e86\u8868\u7ed3\u6784<\/p>\n\n\n\n
QueryExecute ip 10.0.2.115,port 5050, do \"show full create table \"testdb\".\"test1_n1\";\"<\/code><\/pre>\n\n\n\n\u5e76\u91cd\u5efa\u4e86\u4e22\u5931\u7684\u5206\u7247\u8868<\/p>\n\n\n\n
MatchJustOneResult:CREATE TABLE \"test1_n1\" (\n \"id\" int(11) DEFAULT NULL\n) ENGINE=EXPRESS TID(610) UID(1) DEFAULT CHARSET=utf8 TABLESPACE='sys_tablespace' COLUMN_IDS(0)<\/code><\/pre>\n\n\n\n\u4e4b\u540e\u8c03\u7528sync_client\u540c\u6b65\u4e86\u6570\u636e<\/p>\n\n\n\n
QueryExecute ip 10.0.2.101,port 5050, do \"start 'gc_sync_client' '10.0.2.115 vcname000001 testdb test1_n1 10.0.2.101 5258 2 0'\"<\/code><\/pre>\n\n\n\n\u5b8c\u6574\u7684\u540c\u6b65\u4fe1\u606f\u5982\u4e0b<\/p>\n\n\n\n
2022-02-14 10:14:33.240 [INFO ] <session:4, tid:609, db:testdb,tb:test1,nx:n1,et:DML,eid:2,en:node,ip:10.0.2.101>: recoverinfo gn dml: eventid:2 DB(testdb), TABLE(test1), SLICE(1), TID(609), src:1929510922(10.0.2.115) dst:1694629898(10.0.2.101)\n2022-02-14 10:14:33.263 [INFO ] <session:4, tid:609, db:testdb,tb:test1,nx:n1,et:DML,eid:2,en:node,ip:10.0.2.101>: PreWriteDMLSBeforeSync eventid 2\n2022-02-14 10:14:33.263 [INFO ] <session:4, tid:609, db:testdb,tb:test1,nx:n1,et:DML,eid:2,en:node,ip:10.0.2.101>: QueryExecute ip 10.0.2.101,port 5258, do \"select table_id from information_schema.tables where table_schema='testdb' and table_name='test1' and TABLE_VC='vcname000001'\"\n2022-02-14 10:14:33.263 [INFO ] <session:4, tid:609, db:testdb,tb:test1,nx:n1,et:DML,eid:2,en:node,ip:10.0.2.101>: MatchJustOneResult:609\n2022-02-14 10:14:33.263 [INFO ] <session:4, tid:609, db:testdb,tb:test1,nx:n1,et:DML,eid:2,en:node,ip:10.0.2.101>: QueryExecute ip 10.0.2.101,port 5050, do \"select scn from information_schema.tables where table_schema='testdb' and table_name='test1_n1'\"\n2022-02-14 10:14:33.264 [ERROR] <session:4, tid:609, db:testdb,tb:test1,nx:n1,et:DML,eid:2,en:node,ip:10.0.2.101>: MatchJustOneResult, row is null, or row number is not one\n2022-02-14 10:14:33.264 [ERROR] <session:4, tid:609, db:testdb,tb:test1,nx:n1,et:DML,eid:2,en:node,ip:10.0.2.101>: QueryTableExists null\n2022-02-14 10:14:33.264 [INFO ] <session:4, tid:609, db:testdb,tb:test1,nx:n1,et:DML,eid:2,en:node,ip:10.0.2.101>: DoDmlRecover 609 table not in local data node,but in source gcluster, convert to dmlstorage.<\/span>\n2022-02-14 10:14:33.264 [INFO ] <session:4, tid:609, db:testdb,tb:test1,nx:n1,et:DML,eid:2,en:node,ip:10.0.2.101>: delete dml event, table testdb.test1,event id 2 suffix n1\n2022-02-14 10:14:33.276 [INFO ] <session:4, tid:609, db:testdb,tb:test1,nx:n1,et:DML,eid:2,en:node,ip:10.0.2.101>: SET_ONLINE, Succeeded in setting local node table 'testdb.test1_n1' online, eventinfo(eventid:2,segId:1)\n2022-02-14 10:14:33.276 [INFO ] <session:4, tid:609, db:testdb,tb:test1,nx:n1,et:DML,eid:2,en:node,ip:10.0.2.101>: CheckToClearOrKeepPreWriteDMLS keep eventid 2\n2022-02-14 10:14:33.293 [INFO ] <session:4>: DealDMLRecoverUnLock vc00001.testdb.test1\n2022-02-14 10:14:33.521 [INFO ] <session:4>: source node is 0x7302000a, table test1, suffix n2\n2022-02-14 10:14:33.555 [INFO ] <session:4>: DealDMLRecoverLock vc00001.testdb.test1 success\n2022-02-14 10:14:33.564 [INFO ] <session:4, tid:609, db:testdb,tb:test1,nx:n2,et:DML,eid:2,en:node,ip:10.0.2.101>: recoverinfo gn dml: eventid:2 DB(testdb), TABLE(test1), SLICE(2), TID(609), src:1929510922(10.0.2.115) dst:1694629898(10.0.2.101)\n2022-02-14 10:14:33.573 [INFO ] <session:4, tid:609, db:testdb,tb:test1,nx:n2,et:DML,eid:2,en:node,ip:10.0.2.101>: PreWriteDMLSBeforeSync eventid 3\n2022-02-14 10:14:33.573 [INFO ] <session:4, tid:609, db:testdb,tb:test1,nx:n2,et:DML,eid:2,en:node,ip:10.0.2.101>: QueryExecute ip 10.0.2.101,port 5258, do \"select table_id from information_schema.tables where table_schema='testdb' and table_name='test1' and TABLE_VC='vcname000001'\"\n2022-02-14 10:14:33.574 [INFO ] <session:4, tid:609, db:testdb,tb:test1,nx:n2,et:DML,eid:2,en:node,ip:10.0.2.101>: MatchJustOneResult:609\n2022-02-14 10:14:33.574 [INFO ] <session:4, tid:609, db:testdb,tb:test1,nx:n2,et:DML,eid:2,en:node,ip:10.0.2.101>: QueryExecute ip 10.0.2.101,port 5050, do \"select scn from information_schema.tables where table_schema='testdb' and table_name='test1_n2'\"\n2022-02-14 10:14:33.575 [INFO ] <session:4, tid:609, db:testdb,tb:test1,nx:n2,et:DML,eid:2,en:node,ip:10.0.2.101>: MatchJustOneResult:19458\n2022-02-14 10:14:33.575 [INFO ] <session:4, tid:609, db:testdb,tb:test1,nx:n2,et:DML,eid:2,en:node,ip:10.0.2.101>: QueryExecute ip 10.0.2.101,port 5050, do \"FLUSH ROLLBACK \"testdb\".\"test1_n2\"\"\n2022-02-14 10:14:33.576 [INFO ] <session:4, tid:609, db:testdb,tb:test1,nx:n2,et:DML,eid:2,en:node,ip:10.0.2.101>: QueryExecute ip 10.0.2.101,port 5050, do \"revert table `testdb`.`test1_n2` scn_number 19459\"\n2022-02-14 10:14:33.576 [ERROR] <session:4, tid:609, db:testdb,tb:test1,nx:n2,et:DML,eid:2,en:node,ip:10.0.2.101>: QueryExecute, (1753)(Can't revert table: Table testdb.test1_n2 can not do revert with scn(19459\/cur:19458)), do \"revert table `testdb`.`test1_n2` scn_number 19459\" error\n2022-02-14 10:14:33.576 [INFO ] <session:4, tid:609, db:testdb,tb:test1,nx:n2,et:DML,eid:2,en:node,ip:10.0.2.101>: do revert success!\n2022-02-14 10:14:33.576 [INFO ] <session:4, tid:609, db:testdb,tb:test1,nx:n2,et:DML,eid:2,en:node,ip:10.0.2.101>: QueryExecute ip 10.0.2.101,port 5050, do \"flush transaction_log\"\n2022-02-14 10:14:33.576 [INFO ] <session:4, tid:609, db:testdb,tb:test1,nx:n2,et:DML,eid:2,en:node,ip:10.0.2.101>: QueryExecute ip 10.0.2.115,port 5050, do \"flush transaction_log\"\n2022-02-14 10:14:33.577 [INFO ] <session:4, tid:609, db:testdb,tb:test1,nx:n2,et:DML,eid:2,en:node,ip:10.0.2.101>: QueryExecute ip 10.0.2.101,port 5050, do \"start 'gc_sync_client' '10.0.2.115 vcname000001 testdb test1_n2 10.0.2.101 5258 2 0'\"\n2022-02-14 10:14:33.728 [INFO ] <session:4, tid:609, db:testdb,tb:test1,nx:n2,et:DML,eid:2,en:node,ip:10.0.2.101>: ExecSyncQuery, sync table returned with (0)\n2022-02-14 10:14:33.728 [INFO ] <session:4, tid:609, db:testdb,tb:test1,nx:n2,et:DML,eid:2,en:node,ip:10.0.2.101>: check drop sql thread quit.\n2022-02-14 10:14:33.729 [INFO ] <session:4, tid:609, db:testdb,tb:test1,nx:n2,et:DML,eid:2,en:node,ip:10.0.2.101>: QueryExecute ip 10.0.2.101,port 5050, do \"flush transaction_log\"\n2022-02-14 10:14:33.729 [INFO ] <session:4, tid:609, db:testdb,tb:test1,nx:n2,et:DML,eid:2,en:node,ip:10.0.2.101>: QueryExecute ip 10.0.2.115,port 5050, do \"flush transaction_log\"\n2022-02-14 10:14:33.729 [INFO ] <session:4, tid:609, db:testdb,tb:test1,nx:n2,et:DML,eid:2,en:node,ip:10.0.2.101>: CALL_SYNC, Sync client executed successfully\n2022-02-14 10:14:33.730 [INFO ] <session:4, tid:609, db:testdb,tb:test1,nx:n2,et:DML,eid:2,en:node,ip:10.0.2.101>: QueryExecute ip 10.0.2.101,port 5050, do \"refresh table \"testdb\".\"test1_n2\"\"\n2022-02-14 10:14:33.730 [INFO ] <session:4, tid:609, db:testdb,tb:test1,nx:n2,et:DML,eid:2,en:node,ip:10.0.2.101>: Sync client returned errcode:[0],errmsg:[success]\n2022-02-14 10:14:33.730 [INFO ] <session:4, tid:609, db:testdb,tb:test1,nx:n2,et:DML,eid:2,en:node,ip:10.0.2.101>: CALL_SYNC, Sync end, return 0\n2022-02-14 10:14:33.738 [INFO ] <session:4, tid:609, db:testdb,tb:test1,nx:n2,et:DML,eid:2,en:node,ip:10.0.2.101>: CHECK_SYNC_RESULT,Checking whether SYNC is succeeded{DB: 'testdb', Table: 'test1', slice: 'n2'}\n2022-02-14 10:14:33.738 [INFO ] <session:4, tid:609, db:testdb,tb:test1,nx:n2,et:DML,eid:2,en:node,ip:10.0.2.101>: QueryExecute ip 10.0.2.101,port 5050, do \"select scn from information_schema.tables where table_schema='testdb' and table_name='test1_n2'\"\n2022-02-14 10:14:33.738 [INFO ] <session:4, tid:609, db:testdb,tb:test1,nx:n2,et:DML,eid:2,en:node,ip:10.0.2.101>: MatchJustOneResult:19459\n2022-02-14 10:14:33.738 [INFO ] <session:4, tid:609, db:testdb,tb:test1,nx:n2,et:DML,eid:2,en:node,ip:10.0.2.101>: QueryExecute ip 10.0.2.115,port 5050, do \"refresh table \"testdb\".\"test1_n2\"\"\n2022-02-14 10:14:33.739 [INFO ] <session:4, tid:609, db:testdb,tb:test1,nx:n2,et:DML,eid:2,en:node,ip:10.0.2.101>: QueryExecute ip 10.0.2.115,port 5050, do \"select scn from information_schema.tables where table_schema='testdb' and table_name='test1_n2'\"\n2022-02-14 10:14:33.740 [INFO ] <session:4, tid:609, db:testdb,tb:test1,nx:n2,et:DML,eid:2,en:node,ip:10.0.2.101>: MatchJustOneResult:19459\n2022-02-14 10:14:33.740 [INFO ] <session:4, tid:609, db:testdb,tb:test1,nx:n2,et:DML,eid:2,en:node,ip:10.0.2.101>: CHECK_SYNC_RESULT, recover GNode: 19459\n2022-02-14 10:14:33.740 [INFO ] <session:4, tid:609, db:testdb,tb:test1,nx:n2,et:DML,eid:2,en:node,ip:10.0.2.101>: CHECK_SYNC_RESULT, source GNode[1]: 19459\n2022-02-14 10:14:33.740 [INFO ] <session:4, tid:609, db:testdb,tb:test1,nx:n2,et:DML,eid:2,en:node,ip:10.0.2.101>: delete dml event, table testdb.test1,event id 2 suffix n2\n2022-02-14 10:14:33.750 [INFO ] <session:4, tid:609, db:testdb,tb:test1,nx:n2,et:DML,eid:2,en:node,ip:10.0.2.101>: SET_ONLINE, Succeeded in setting local node table 'testdb.test1_n2' online, eventinfo(eventid:2,segId:2)\n2022-02-14 10:14:33.750 [INFO ] <session:4, tid:609, db:testdb,tb:test1,nx:n2,et:DML,eid:2,en:node,ip:10.0.2.101>: CheckToClearOrKeepPreWriteDMLS delete eventid 3\n2022-02-14 10:14:33.759 [INFO ] <session:4, tid:609, db:testdb,tb:test1,nx:n2,et:DML,eid:2,en:node,ip:10.0.2.101>: delete dmlstorage event, event id: 3\n2022-02-14 10:14:33.769 [INFO ] <session:4>: DealDMLRecoverUnLock vc00001.testdb.test1\n2022-02-14 10:14:33.973 [INFO ] <session:4>: End dml recover .,tid 609\n2022-02-14 10:14:33.982 [INFO ] <session:4>: UnLock: GCRECOVER_LOCK_TID_vc00001_609 unlock success, 8130053026070134784\n2022-02-14 10:14:33.993 [INFO ] <session:4>: Lock: GCRECOVER_LOCK_TID_vc00001_609 end success, 1600173590353805312\n2022-02-14 10:14:33.995 [INFO ] <session:4>: Start DMLStorge recover .,tid 609 eventnum 1\n2022-02-14 10:14:34.012 [INFO ] <session:4>: QueryExecute ip 10.0.2.101,port 5258, do \"select table_id from information_schema.tables where table_schema='testdb' and table_name='test1' and TABLE_VC='vcname000001'\"\n2022-02-14 10:14:34.013 [INFO ] <session:4>: MatchJustOneResult:609\n2022-02-14 10:14:34.027 [INFO ] <session:4>: DealDMLRecoverLock vc00001.testdb.test1 success\n2022-02-14 10:14:34.035 [INFO ] <session:4,tid:609, db:testdb,tb:test1,nx:n1,et:DMLS,eid:2,en:node,ip:10.0.2.101>: recoverinfo gn(dmls): eventid=2, tablename=testdb.test1, src:0x1929510922(10.0.2.115) dst:0x1694629898(10.0.2.101)\n2022-02-14 10:14:34.035 [INFO ] <session:4,tid:609, db:testdb,tb:test1,nx:n1,et:DMLS,eid:2,en:node,ip:10.0.2.101>: QueryExecute ip 10.0.2.101,port 5050, do \"DROP SELF TABLE IF EXISTS \"testdb\".\"test1_n1\";\"\n2022-02-14 10:14:34.035 [INFO ] <session:4,tid:609, db:testdb,tb:test1,nx:n1,et:DMLS,eid:2,en:node,ip:10.0.2.101>: QueryExecute ip 10.0.2.115,port 5050, do \"select scn from information_schema.tables where table_schema='testdb' and table_name='test1_n1'\"\n2022-02-14 10:14:34.036 [INFO ] <session:4,tid:609, db:testdb,tb:test1,nx:n1,et:DMLS,eid:2,en:node,ip:10.0.2.101>: MatchJustOneResult:19459\n2022-02-14 10:14:34.036 [INFO ] <session:4,tid:609, db:testdb,tb:test1,nx:n1,et:DMLS,eid:2,en:node,ip:10.0.2.101>: QueryExecute ip 10.0.2.115,port 5050, do \"select scn from information_schema.tables where table_schema='testdb' and table_name='test1_n1'\"\n2022-02-14 10:14:34.037 [INFO ] <session:4,tid:609, db:testdb,tb:test1,nx:n1,et:DMLS,eid:2,en:node,ip:10.0.2.101>: MatchJustOneResult:19459\n2022-02-14 10:14:34.037 [INFO ] <session:4,tid:609, db:testdb,tb:test1,nx:n1,et:DMLS,eid:2,en:node,ip:10.0.2.101>: QueryExecute ip 10.0.2.115,port 5050, do \"show full create table \"testdb\".\"test1_n1\";<\/span>\"\n2022-02-14 10:14:34.037 [INFO ] <session:4,tid:609, db:testdb,tb:test1,nx:n1,et:DMLS,eid:2,en:node,ip:10.0.2.101>: MatchJustOneResult:CREATE TABLE \"test1_n1\" (\n \"id\" int(11) DEFAULT NULL\n) ENGINE=EXPRESS TID(610) UID(1) DEFAULT CHARSET=utf8 TABLESPACE='sys_tablespace' COLUMN_IDS(0)<\/span>\n2022-02-14 10:14:34.037 [INFO ] <session:4,tid:609, db:testdb,tb:test1,nx:n1,et:DMLS,eid:2,en:node,ip:10.0.2.101>: QueryExecute ip 10.0.2.101,port 5050, do \"use self \"testdb\";\"\n2022-02-14 10:14:34.038 [INFO ] <session:4,tid:609, db:testdb,tb:test1,nx:n1,et:DMLS,eid:2,en:node,ip:10.0.2.101>: QueryExecute ip 10.0.2.101,port 5050, do \"SET SELF SCN = 19459\"\n2022-02-14 10:14:34.038 [INFO ] <session:4,tid:609, db:testdb,tb:test1,nx:n1,et:DMLS,eid:2,en:node,ip:10.0.2.101>: QueryExecute ip 10.0.2.101,port 5050, do \"CREATE SELF TABLE \"test1_n1\" (\n \"id\" int(11) DEFAULT NULL\n) ENGINE=EXPRESS TID(610) UID(1) DEFAULT CHARSET=utf8 TABLESPACE='sys_tablespace' COLUMN_IDS(0)\"\n2022-02-14 10:14:34.060 [INFO ] <session:4,tid:609, db:testdb,tb:test1,nx:n1,et:DMLS,eid:2,en:node,ip:10.0.2.101>: QueryExecute ip 10.0.2.101,port 5050, do \"flush transaction_log\"\n2022-02-14 10:14:34.060 [INFO ] <session:4,tid:609, db:testdb,tb:test1,nx:n1,et:DMLS,eid:2,en:node,ip:10.0.2.101>: QueryExecute ip 10.0.2.115,port 5050, do \"flush transaction_log\"\n2022-02-14 10:14:34.061 [INFO ] <session:4,tid:609, db:testdb,tb:test1,nx:n1,et:DMLS,eid:2,en:node,ip:10.0.2.101>: QueryExecute ip 10.0.2.101,port 5050, do \"start 'gc_sync_client' '10.0.2.115 vcname000001 testdb test1_n1 10.0.2.101 5258 2 0'\"<\/span>\n2022-02-14 10:14:34.374 [INFO ] <session:4,tid:609, db:testdb,tb:test1,nx:n1,et:DMLS,eid:2,en:node,ip:10.0.2.101>: ExecSyncQuery, sync table returned with (0)\n2022-02-14 10:14:34.374 [INFO ] <session:4,tid:609, db:testdb,tb:test1,nx:n1,et:DMLS,eid:2,en:node,ip:10.0.2.101>: check drop sql thread quit.\n2022-02-14 10:14:34.374 [INFO ] <session:4,tid:609, db:testdb,tb:test1,nx:n1,et:DMLS,eid:2,en:node,ip:10.0.2.101>: QueryExecute ip 10.0.2.101,port 5050, do \"flush transaction_log\"\n2022-02-14 10:14:34.375 [INFO ] <session:4,tid:609, db:testdb,tb:test1,nx:n1,et:DMLS,eid:2,en:node,ip:10.0.2.101>: QueryExecute ip 10.0.2.115,port 5050, do \"flush transaction_log\"\n2022-02-14 10:14:34.375 [INFO ] <session:4,tid:609, db:testdb,tb:test1,nx:n1,et:DMLS,eid:2,en:node,ip:10.0.2.101>: CALL_SYNC, Sync client executed successfully\n2022-02-14 10:14:34.375 [INFO ] <session:4,tid:609, db:testdb,tb:test1,nx:n1,et:DMLS,eid:2,en:node,ip:10.0.2.101>: QueryExecute ip 10.0.2.101,port 5050, do \"refresh table \"testdb\".\"test1_n1\"\"\n2022-02-14 10:14:34.376 [INFO ] <session:4,tid:609, db:testdb,tb:test1,nx:n1,et:DMLS,eid:2,en:node,ip:10.0.2.101>: Sync client returned errcode:[0],errmsg:[success]\n2022-02-14 10:14:34.376 [INFO ] <session:4,tid:609, db:testdb,tb:test1,nx:n1,et:DMLS,eid:2,en:node,ip:10.0.2.101>: CALL_SYNC, Sync end, return 0\n2022-02-14 10:14:34.380 [INFO ] <session:4,tid:609, db:testdb,tb:test1,nx:n1,et:DMLS,eid:2,en:node,ip:10.0.2.101>: CHECK_SYNC_RESULT,Checking whether SYNC is succeeded{DB: 'testdb', Table: 'test1', slice: 'n1'}\n2022-02-14 10:14:34.380 [INFO ] <session:4,tid:609, db:testdb,tb:test1,nx:n1,et:DMLS,eid:2,en:node,ip:10.0.2.101>: QueryExecute ip 10.0.2.101,port 5050, do \"select scn from information_schema.tables where table_schema='testdb' and table_name='test1_n1'\"\n2022-02-14 10:14:34.381 [INFO ] <session:4,tid:609, db:testdb,tb:test1,nx:n1,et:DMLS,eid:2,en:node,ip:10.0.2.101>: MatchJustOneResult:19459\n2022-02-14 10:14:34.381 [INFO ] <session:4,tid:609, db:testdb,tb:test1,nx:n1,et:DMLS,eid:2,en:node,ip:10.0.2.101>: QueryExecute ip 10.0.2.115,port 5050, do \"refresh table \"testdb\".\"test1_n1\"\"\n2022-02-14 10:14:34.382 [INFO ] <session:4,tid:609, db:testdb,tb:test1,nx:n1,et:DMLS,eid:2,en:node,ip:10.0.2.101>: QueryExecute ip 10.0.2.115,port 5050, do \"select scn from information_schema.tables where table_schema='testdb' and table_name='test1_n1'\"\n2022-02-14 10:14:34.383 [INFO ] <session:4,tid:609, db:testdb,tb:test1,nx:n1,et:DMLS,eid:2,en:node,ip:10.0.2.101>: MatchJustOneResult:19459\n2022-02-14 10:14:34.383 [INFO ] <session:4,tid:609, db:testdb,tb:test1,nx:n1,et:DMLS,eid:2,en:node,ip:10.0.2.101>: CHECK_SYNC_RESULT, recover GNode: 19459\n2022-02-14 10:14:34.383 [INFO ] <session:4,tid:609, db:testdb,tb:test1,nx:n1,et:DMLS,eid:2,en:node,ip:10.0.2.101>: CHECK_SYNC_RESULT, source GNode[1]: 19459\n2022-02-14 10:14:34.383 [INFO ] <session:4,tid:609, db:testdb,tb:test1,nx:n1,et:DMLS,eid:2,en:node,ip:10.0.2.101>: Delete DMLStorge eventId 2\n2022-02-14 10:14:34.396 [INFO ] <session:4,tid:609, db:testdb,tb:test1,nx:n1,et:DMLS,eid:2,en:node,ip:10.0.2.101>: delete dmlstorage event, event id: 2\n2022-02-14 10:14:34.404 [INFO ] <session:4>: DealDMLSRecoverUnLock vc00001.testdb.test1\n2022-02-14 10:14:34.817 [INFO ] <session:4,tid:609, db:testdb,tb:test1,nx:n1,et:DMLS,eid:2,en:node,ip:10.0.2.101>: UnLock: GCRECOVER_LOCK_TID_vc00001_609 unlock success, 1600173590353805312\n\n<\/code><\/pre>\n\n\n\n<\/span>\u8868\u4e22\u5931\u65f6\u81ea\u52a8\u6062\u590d\u7684\u603b\u7ed3<\/span><\/h3>\n\n\n\n\u5f53\u96c6\u7fa4\u540c\u6b65\u8fdb\u7a0b\u53d1\u73b0\u76ee\u6807\u8868\u4e22\u5931\u65f6\uff0c\u4f1a\u81ea\u52a8\u4ece\u6570\u636e\u4e0d\u4e00\u81f4\u7684dmlevent\u5347\u7ea7\u5230\u5b58\u50a8\u4e0d\u4e00\u81f4\u7684dmlstorageevent, \u7136\u540e\u4ece\u526f\u672c\u83b7\u5f97\u8868\u7ed3\u6784\uff0c\u91cd\u5efa\u5efa\u7acb\u5206\u7247\u8868\uff0c\u7136\u540e\u518d\u540c\u6b65\u6570\u636e\u5b9e\u73b0\u6574\u4e2a\u6062\u590d\u8fc7\u7a0b\u3002<\/p>\n\n\n\n
<\/span>\u5206\u7247\u8868\u7684\u8868\u7ed3\u6784\u4e0d\u4e00\u81f4<\/span><\/h2>\n\n\n\n\u6b64\u79cd\u60c5\u51b5\uff0c\u5728\u666e\u901a\u573a\u666f\u662f\u4e0d\u4f1a\u51fa\u73b0\u7684\uff0c\u9664\u4e86\u975e\u5e38\u53e4\u8001\u7248\u672c\u7684\u4ea7\u54c1BUG\uff0c\u5728\u65b0\u7248\u672c\u91cc\uff0c\u6700\u5e38\u89c1\u7684\u662f\u78c1\u76d8\u6ee1\u4e86\uff0c\u6216\u8005\u5f02\u5e38\u65ad\u7535\uff0c\u5bfc\u81f4\u5206\u7247\u6ca1\u6709\u5b8c\u6210\u8868\u7ed3\u6784\u53d8\u52a8\uff0c\u540c\u65f6\uff0c\u56e0\u4e3a\u78c1\u76d8\u6ee1\u4e86\u6216\u5f02\u5e38\u65ad\u7535\uff0c\u8fdeevent\u90fd\u6ca1\u6709\u88ab\u6b63\u786e\u8bb0\u5f55\u5230\u7269\u7406\u78c1\u76d8\u4e0a\u3002<\/p>\n\n\n\n
<\/span>\u6784\u9020\u8868\u7ed3\u6784\u4e0d\u4e00\u81f4<\/span><\/h3>\n\n\n\n\u901a\u8fc7\u5728\u5206\u7247\u8868\uff0c\u589e\u52a0\u4e00\u4e2a\u5b57\u6bb5\u6765\u6a21\u62df\u4e3b\u526f\u5206\u7247\u7684\u8868\u7ed3\u6784\u4e0d\u4e00\u81f4\u3002<\/p>\n\n\n\n
gncli ......\n\ngbase> desc test1_n1;\n+-------+---------+------+-----+---------+-------+\n| Field | Type | Null | Key | Default | Extra |\n+-------+---------+------+-----+---------+-------+\n| id | int(11) | YES | | NULL | |\n+-------+---------+------+-----+---------+-------+\n1 row in set (Elapsed: 00:00:00.01)\n\ngbase> alter table test1_n1 add column name varchar(100);\\\nQuery OK, 5 rows affected (Elapsed: 00:00:00.07)\nRecords: 5 Duplicates: 0 Warnings: 0\n\ngbase> desc test1_n1;\n+-------+--------------+------+-----+---------+-------+\n| Field | Type | Null | Key | Default | Extra |\n+-------+--------------+------+-----+---------+-------+\n| id | int(11) | YES | | NULL | |\n| name | varchar(100) | YES | | NULL | |\n+-------+--------------+------+-----+---------+-------+\n2 rows in set (Elapsed: 00:00:00.00)\n\ngbase> desc test1_n2;\n+-------+---------+------+-----+---------+-------+\n| Field | Type | Null | Key | Default | Extra |\n+-------+---------+------+-----+---------+-------+\n| id | int(11) | YES | | NULL | |\n+-------+---------+------+-----+---------+-------+\n1 row in set (Elapsed: 00:00:00.00)<\/code><\/pre>\n\n\n\n<\/span>\u6a21\u62df\u6545\u969c<\/span><\/h3>\n\n\n\n\u505c\u6389\u670d\u52a1\u6a21\u62df\u6545\u969c\uff0c\u53d8\u52a8\u6570\u636e\u751f\u6210event\uff0c\u542f\u52a8\u670d\u52a1\u6a21\u62df\u6545\u969c\u6062\u590d\u3002<\/p>\n\n\n\n
[gbase@gbase_rh7_001 gcluster]$ gcluster_services gbase stop\nStopping gbase : [ OK ]\n[gbase@gbase_rh7_001 gcluster]$ gcadmin\nCLUSTER STATE: ACTIVE\nVIRTUAL CLUSTER MODE: NORMAL\n\n=============================================================\n| GBASE COORDINATOR CLUSTER INFORMATION |\n=============================================================\n| NodeName | IpAddress | gcware | gcluster | DataState |\n-------------------------------------------------------------\n| coordinator1 | 10.0.2.101 | OPEN | OPEN | 0 |\n-------------------------------------------------------------\n| coordinator2 | 10.0.2.115 | OPEN | OPEN | 0 |\n-------------------------------------------------------------\n=========================================================================================================\n| GBASE DATA CLUSTER INFORMATION |\n=========================================================================================================\n| NodeName | IpAddress | DistributionId | gnode | syncserver | DataState |\n---------------------------------------------------------------------------------------------------------\n| node1 | 10.0.2.101 | 1 | CLOSE | OPEN | 0 |\n---------------------------------------------------------------------------------------------------------\n| node2 | 10.0.2.115 | 1 | OPEN | OPEN | 0 |\n---------------------------------------------------------------------------------------------------------\n\n[gbase@gbase_rh7_001 gcluster]$ gccli testdb\n\nGBase client 9.5.2.44.283f8db1. Copyright (c) 2004-2022, GBase. All Rights Reserved.\n\ngbase> insert into test1 values(9),(10),(11),(12);\nQuery OK, 4 rows affected (Elapsed: 00:00:00.15)\nRecords: 4 Duplicates: 0 Warnings: 0\n\ngbase> ^CAborted\n[gbase@gbase_rh7_001 gcluster]$ gcadmin\nCLUSTER STATE: ACTIVE\nVIRTUAL CLUSTER MODE: NORMAL\n\n=============================================================\n| GBASE COORDINATOR CLUSTER INFORMATION |\n=============================================================\n| NodeName | IpAddress | gcware | gcluster | DataState |\n-------------------------------------------------------------\n| coordinator1 | 10.0.2.101 | OPEN | OPEN | 0 |\n-------------------------------------------------------------\n| coordinator2 | 10.0.2.115 | OPEN | OPEN | 0 |\n-------------------------------------------------------------\n=========================================================================================================\n| GBASE DATA CLUSTER INFORMATION |\n=========================================================================================================\n| NodeName | IpAddress | DistributionId | gnode | syncserver | DataState |\n---------------------------------------------------------------------------------------------------------\n| node1 | 10.0.2.101 | 1 | CLOSE | OPEN | 1 |\n---------------------------------------------------------------------------------------------------------\n| node2 | 10.0.2.115 | 1 | OPEN | OPEN | 0 |\n---------------------------------------------------------------------------------------------------------\n\n[gbase@gbase_rh7_001 gcluster]$\n[gbase@gbase_rh7_001 gcluster]$ gcadmin showdmlevent\nVc event count:1\nEvent ID: 3\nObjectName: testdb.test1\n\nFail Data Copy:\n------------------------------------------------------\nSegName: n1 SCN: 19460 NodeIP: 10.0.2.101 FAILURE\nSegName: n2 SCN: 19460 NodeIP: 10.0.2.101 FAILURE\n\n\n[gbase@gbase_rh7_001 gcluster]$ gcadmin showddlevent\nVc event count:0\n[gbase@gbase_rh7_001 gcluster]$ gcadmin showdmlstorageevent\nVc event count:0\n\n[gbase@gbase_rh7_001 gcluster]$ gcluster_services gbase start\nStarting gbase : [ OK ]\n[gbase@gbase_rh7_001 gcluster]$ <\/code><\/pre>\n\n\n\n<\/span>\u67e5\u770b\u540c\u6b65\u65e5\u5fd7<\/span><\/h3>\n\n\n\n\u96c6\u7fa4\u540c\u6b65\u670d\u52a1\u5148\u8c03\u7528sync_client\u8fdb\u884c\u6570\u636e\u6062\u590d<\/p>\n\n\n\n
2022-02-14 10:24:32.732 [INFO ] <session:6, tid:609, db:testdb,tb:test1,nx:n1,et:DML,eid:3,en:node,ip:10.0.2.101>: QueryExecute ip 10.0.2.101,port 5050, do \"start 'gc_sync_client' '10.0.2.115 vcname000001 testdb test1_n1 10.0.2.101 5258 2 0'\"<\/code><\/pre>\n\n\n\n\u6062\u590d\u6570\u62a5\u9519<\/p>\n\n\n\n
2022-02-14 10:24:32.784 [ERROR] <session:6, tid:609, db:testdb,tb:test1,nx:n1,et:DML,eid:3,en:node,ip:10.0.2.101>: QueryExecute, (1733)((GBA-01EX-700) Gbase general error: gc_sync_client returned error code 30.), do \"start 'gc_sync_client' '10.0.2.115 vcname000001 testdb test1_n1 10.0.2.101 5258 2 0'\" error<\/code><\/pre>\n\n\n\n\u62a5\u9519\u539f\u56e0\u662f\u8868\u7ed3\u6784\u4e0d\u4e00\u81f4<\/p>\n\n\n\n
2022-02-14 10:24:32.787 [INFO ] <session:6, tid:609, db:testdb,tb:test1,nx:n1,et:DML,eid:3,en:node,ip:10.0.2.101>: Sync client returned errcode:[30],errmsg:[show full create table for sync is different.]<\/code><\/pre>\n\n\n\n\u5220\u6389\u4e86\u4e0d\u4e00\u81f4\u7684\u5206\u7247\u8868<\/p>\n\n\n\n
2022-02-14 10:24:33.674 [INFO ] <session:6,tid:609, db:testdb,tb:test1,nx:n1,et:DMLS,eid:4,en:node,ip:10.0.2.101>: QueryExecute ip 10.0.2.101,port 5050, do \"DROP SELF TABLE IF EXISTS \"testdb\".\"test1_n1\";\"<\/code><\/pre>\n\n\n\n\u4ece\u526f\u672c\u62ff\u5230\u4e86\u8868\u7ed3\u6784<\/p>\n\n\n\n
2022-02-14 10:24:33.677 [INFO ] <session:6,tid:609, db:testdb,tb:test1,nx:n1,et:DMLS,eid:4,en:node,ip:10.0.2.101>: QueryExecute ip 10.0.2.115,port 5050, do \"show full create table \"testdb\".\"test1_n1\";\"<\/code><\/pre>\n\n\n\n\u521b\u5efa\u5206\u7247\u8868<\/p>\n\n\n\n
2022-02-14 10:24:33.678 [INFO ] <session:6,tid:609, db:testdb,tb:test1,nx:n1,et:DMLS,eid:4,en:node,ip:10.0.2.101>: QueryExecute ip 10.0.2.101,port 5050, do \"CREATE SELF TABLE \"test1_n1\" (\n \"id\" int(11) DEFAULT NULL\n) ENGINE=EXPRESS TID(610) UID(1) DEFAULT CHARSET=utf8 TABLESPACE='sys_tablespace' COLUMN_IDS(0)\"<\/code><\/pre>\n\n\n\n\u8c03\u7528sync_client\u6062\u590d\u6570\u636e<\/p>\n\n\n\n
2022-02-14 10:24:33.708 [INFO ] <session:6,tid:609, db:testdb,tb:test1,nx:n1,et:DMLS,eid:4,en:node,ip:10.0.2.101>: QueryExecute ip 10.0.2.101,port 5050, do \"start 'gc_sync_client' '10.0.2.115 vcname000001 testdb test1_n1 10.0.2.101 5258 2 0'\"<\/code><\/pre>\n\n\n\n\u5b8c\u6574\u7684\u6062\u590d\u65e5\u5fd7\u5982\u4e0b<\/p>\n\n\n\n
2022-02-14 10:24:32.659 [INFO ] <session:6>: Lock: GCRECOVER_LOCK_TID_vc00001_609 end success, 1091491459250520064\n2022-02-14 10:24:32.660 [INFO ] <session:6>: Start dml recover .,tid 609, eventid 3, Num 2\n2022-02-14 10:24:32.669 [INFO ] <session:6>: GetSourceNodeForDMl for nodeId(1694629898), the node is inValid, uValid=1\n2022-02-14 10:24:32.669 [INFO ] <session:6>: source node is 0x7302000a, table test1, suffix n1\n2022-02-14 10:24:32.705 [INFO ] <session:6>: DealDMLRecoverLock vc00001.testdb.test1 success\n2022-02-14 10:24:32.707 [INFO ] <session:6, tid:609, db:testdb,tb:test1,nx:n1,et:DML,eid:3,en:node,ip:10.0.2.101>: recoverinfo gn dml: eventid:3 DB(testdb), TABLE(test1), SLICE(1), TID(609), src:1929510922(10.0.2.115) dst:1694629898(10.0.2.101)\n2022-02-14 10:24:32.726 [INFO ] <session:6, tid:609, db:testdb,tb:test1,nx:n1,et:DML,eid:3,en:node,ip:10.0.2.101>: PreWriteDMLSBeforeSync eventid 4\n2022-02-14 10:24:32.726 [INFO ] <session:6, tid:609, db:testdb,tb:test1,nx:n1,et:DML,eid:3,en:node,ip:10.0.2.101>: QueryExecute ip 10.0.2.101,port 5258, do \"select table_id from information_schema.tables where table_schema='testdb' and table_name='test1' and TABLE_VC='vcname000001'\"\n2022-02-14 10:24:32.728 [INFO ] <session:6, tid:609, db:testdb,tb:test1,nx:n1,et:DML,eid:3,en:node,ip:10.0.2.101>: MatchJustOneResult:609\n2022-02-14 10:24:32.728 [INFO ] <session:6, tid:609, db:testdb,tb:test1,nx:n1,et:DML,eid:3,en:node,ip:10.0.2.101>: QueryExecute ip 10.0.2.101,port 5050, do \"select scn from information_schema.tables where table_schema='testdb' and table_name='test1_n1'\"\n2022-02-14 10:24:32.730 [INFO ] <session:6, tid:609, db:testdb,tb:test1,nx:n1,et:DML,eid:3,en:node,ip:10.0.2.101>: MatchJustOneResult:0\n2022-02-14 10:24:32.730 [INFO ] <session:6, tid:609, db:testdb,tb:test1,nx:n1,et:DML,eid:3,en:node,ip:10.0.2.101>: QueryExecute ip 10.0.2.101,port 5050, do \"FLUSH ROLLBACK \"testdb\".\"test1_n1\"\"\n2022-02-14 10:24:32.731 [INFO ] <session:6, tid:609, db:testdb,tb:test1,nx:n1,et:DML,eid:3,en:node,ip:10.0.2.101>: QueryExecute ip 10.0.2.101,port 5050, do \"revert table `testdb`.`test1_n1` scn_number 19460\"\n2022-02-14 10:24:32.732 [ERROR] <session:6, tid:609, db:testdb,tb:test1,nx:n1,et:DML,eid:3,en:node,ip:10.0.2.101>: QueryExecute, (1753)(Can't revert table: Table testdb.test1_n1 can not do revert with scn(19460\/cur:0)), do \"revert table `testdb`.`test1_n1` scn_number 19460\" error\n2022-02-14 10:24:32.732 [INFO ] <session:6, tid:609, db:testdb,tb:test1,nx:n1,et:DML,eid:3,en:node,ip:10.0.2.101>: do revert success!\n2022-02-14 10:24:32.732 [INFO ] <session:6, tid:609, db:testdb,tb:test1,nx:n1,et:DML,eid:3,en:node,ip:10.0.2.101>: QueryExecute ip 10.0.2.101,port 5050, do \"flush transaction_log\"\n2022-02-14 10:24:32.732 [INFO ] <session:6, tid:609, db:testdb,tb:test1,nx:n1,et:DML,eid:3,en:node,ip:10.0.2.101>: QueryExecute ip 10.0.2.115,port 5050, do \"flush transaction_log\"\n2022-02-14 10:24:32.732 [INFO ] <session:6, tid:609, db:testdb,tb:test1,nx:n1,et:DML,eid:3,en:node,ip:10.0.2.101>: QueryExecute ip 10.0.2.101,port 5050, do \"start<\/span> 'gc_sync_client' '10.0.2.115 vcname000001 testdb test1_n1 10.0.2.101 5258 2 0'\"\n2022-02-14 10:24:32.784 [ERROR] <session:6, tid:609, db:testdb,tb:test1,nx:n1,et:DML,eid:3,en:node,ip:10.0.2.101>: QueryExecute, (1733)((GBA-01EX-700) Gbase general error: gc_sync_client returned error code 30.), do \"start 'gc_sync_client' '10.0.2.115 vcname000001 testdb test1_n1 10.0.2.101 5258 2 0'\" error<\/span>\n2022-02-14 10:24:32.784 [ERROR] <session:6, tid:609, db:testdb,tb:test1,nx:n1,et:DML,eid:3,en:node,ip:10.0.2.101>: QuerySyncCommand, Sync error\n2022-02-14 10:24:32.784 [INFO ] <session:6, tid:609, db:testdb,tb:test1,nx:n1,et:DML,eid:3,en:node,ip:10.0.2.101>: ExecSyncQuery, sync table returned with (30)\n2022-02-14 10:24:32.785 [INFO ] <session:6, tid:609, db:testdb,tb:test1,nx:n1,et:DML,eid:3,en:node,ip:10.0.2.101>: check drop sql thread quit.\n2022-02-14 10:24:32.785 [INFO ] <session:6, tid:609, db:testdb,tb:test1,nx:n1,et:DML,eid:3,en:node,ip:10.0.2.101>: QueryExecute ip 10.0.2.101,port 5050, do \"flush transaction_log\"\n2022-02-14 10:24:32.786 [INFO ] <session:6, tid:609, db:testdb,tb:test1,nx:n1,et:DML,eid:3,en:node,ip:10.0.2.101>: QueryExecute ip 10.0.2.115,port 5050, do \"flush transaction_log\"\n2022-02-14 10:24:32.786 [ERROR] <session:6, tid:609, db:testdb,tb:test1,nx:n1,et:DML,eid:3,en:node,ip:10.0.2.101>: CALL_SYNC, Sync client return ERR_SHOW_CREATE_TABLE_IS_DIFF\n2022-02-14 10:24:32.787 [INFO ] <session:6, tid:609, db:testdb,tb:test1,nx:n1,et:DML,eid:3,en:node,ip:10.0.2.101>: Sync client returned errcode:[30],errmsg:[show full create table for sync is different.]<\/span>\n2022-02-14 10:24:32.787 [INFO ] <session:6, tid:609, db:testdb,tb:test1,nx:n1,et:DML,eid:3,en:node,ip:10.0.2.101>: CALL_SYNC, Sync end, return 26\n2022-02-14 10:24:32.790 [INFO ] <session:6, tid:609, db:testdb,tb:test1,nx:n1,et:DML,eid:3,en:node,ip:10.0.2.101>: delete dml event, table testdb.test1,event id 3 suffix n1\n2022-02-14 10:24:32.799 [INFO ] <session:6, tid:609, db:testdb,tb:test1,nx:n1,et:DML,eid:3,en:node,ip:10.0.2.101>: SET_ONLINE, Succeeded in setting local node table 'testdb.test1_n1' online, eventinfo(eventid:3,segId:1)\n2022-02-14 10:24:32.799 [INFO ] <session:6, tid:609, db:testdb,tb:test1,nx:n1,et:DML,eid:3,en:node,ip:10.0.2.101>: CheckToClearOrKeepPreWriteDMLS keep eventid 4\n2022-02-14 10:24:32.809 [INFO ] <session:6>: DealDMLRecoverUnLock vc00001.testdb.test1\n2022-02-14 10:24:33.025 [INFO ] <session:6>: source node is 0x7302000a, table test1, suffix n2\n2022-02-14 10:24:33.071 [INFO ] <session:6>: DealDMLRecoverLock vc00001.testdb.test1 success\n2022-02-14 10:24:33.074 [INFO ] <session:6, tid:609, db:testdb,tb:test1,nx:n2,et:DML,eid:3,en:node,ip:10.0.2.101>: recoverinfo gn dml: eventid:3 DB(testdb), TABLE(test1), SLICE(2), TID(609), src:1929510922(10.0.2.115) dst:1694629898(10.0.2.101)\n2022-02-14 10:24:33.087 [INFO ] <session:6, tid:609, db:testdb,tb:test1,nx:n2,et:DML,eid:3,en:node,ip:10.0.2.101>: PreWriteDMLSBeforeSync eventid 5\n2022-02-14 10:24:33.088 [INFO ] <session:6, tid:609, db:testdb,tb:test1,nx:n2,et:DML,eid:3,en:node,ip:10.0.2.101>: QueryExecute ip 10.0.2.101,port 5258, do \"select table_id from information_schema.tables where table_schema='testdb' and table_name='test1' and TABLE_VC='vcname000001'\"\n2022-02-14 10:24:33.089 [INFO ] <session:6, tid:609, db:testdb,tb:test1,nx:n2,et:DML,eid:3,en:node,ip:10.0.2.101>: MatchJustOneResult:609\n2022-02-14 10:24:33.089 [INFO ] <session:6, tid:609, db:testdb,tb:test1,nx:n2,et:DML,eid:3,en:node,ip:10.0.2.101>: QueryExecute ip 10.0.2.101,port 5050, do \"select scn from information_schema.tables where table_schema='testdb' and table_name='test1_n2'\"\n2022-02-14 10:24:33.090 [INFO ] <session:6, tid:609, db:testdb,tb:test1,nx:n2,et:DML,eid:3,en:node,ip:10.0.2.101>: MatchJustOneResult:19459\n2022-02-14 10:24:33.090 [INFO ] <session:6, tid:609, db:testdb,tb:test1,nx:n2,et:DML,eid:3,en:node,ip:10.0.2.101>: QueryExecute ip 10.0.2.101,port 5050, do \"FLUSH ROLLBACK \"testdb\".\"test1_n2\"\"\n2022-02-14 10:24:33.091 [INFO ] <session:6, tid:609, db:testdb,tb:test1,nx:n2,et:DML,eid:3,en:node,ip:10.0.2.101>: QueryExecute ip 10.0.2.101,port 5050, do \"revert table `testdb`.`test1_n2` scn_number 19460\"\n2022-02-14 10:24:33.092 [ERROR] <session:6, tid:609, db:testdb,tb:test1,nx:n2,et:DML,eid:3,en:node,ip:10.0.2.101>: QueryExecute, (1753)(Can't revert table: Table testdb.test1_n2 can not do revert with scn(19460\/cur:19459)), do \"revert table `testdb`.`test1_n2` scn_number 19460\" error\n2022-02-14 10:24:33.092 [INFO ] <session:6, tid:609, db:testdb,tb:test1,nx:n2,et:DML,eid:3,en:node,ip:10.0.2.101>: do revert success!\n2022-02-14 10:24:33.092 [INFO ] <session:6, tid:609, db:testdb,tb:test1,nx:n2,et:DML,eid:3,en:node,ip:10.0.2.101>: QueryExecute ip 10.0.2.101,port 5050, do \"flush transaction_log\"\n2022-02-14 10:24:33.093 [INFO ] <session:6, tid:609, db:testdb,tb:test1,nx:n2,et:DML,eid:3,en:node,ip:10.0.2.101>: QueryExecute ip 10.0.2.115,port 5050, do \"flush transaction_log\"\n2022-02-14 10:24:33.093 [INFO ] <session:6, tid:609, db:testdb,tb:test1,nx:n2,et:DML,eid:3,en:node,ip:10.0.2.101>: QueryExecute ip 10.0.2.101,port 5050, do \"start 'gc_sync_client' '10.0.2.115 vcname000001 testdb test1_n2 10.0.2.101 5258 2 0'\"\n2022-02-14 10:24:33.393 [INFO ] <session:6, tid:609, db:testdb,tb:test1,nx:n2,et:DML,eid:3,en:node,ip:10.0.2.101>: ExecSyncQuery, sync table returned with (0)\n2022-02-14 10:24:33.393 [INFO ] <session:6, tid:609, db:testdb,tb:test1,nx:n2,et:DML,eid:3,en:node,ip:10.0.2.101>: check drop sql thread quit.\n2022-02-14 10:24:33.393 [INFO ] <session:6, tid:609, db:testdb,tb:test1,nx:n2,et:DML,eid:3,en:node,ip:10.0.2.101>: QueryExecute ip 10.0.2.101,port 5050, do \"flush transaction_log\"\n2022-02-14 10:24:33.394 [INFO ] <session:6, tid:609, db:testdb,tb:test1,nx:n2,et:DML,eid:3,en:node,ip:10.0.2.101>: QueryExecute ip 10.0.2.115,port 5050, do \"flush transaction_log\"\n2022-02-14 10:24:33.394 [INFO ] <session:6, tid:609, db:testdb,tb:test1,nx:n2,et:DML,eid:3,en:node,ip:10.0.2.101>: CALL_SYNC, Sync client executed successfully\n2022-02-14 10:24:33.394 [INFO ] <session:6, tid:609, db:testdb,tb:test1,nx:n2,et:DML,eid:3,en:node,ip:10.0.2.101>: QueryExecute ip 10.0.2.101,port 5050, do \"refresh table \"testdb\".\"test1_n2\"\"\n2022-02-14 10:24:33.395 [INFO ] <session:6, tid:609, db:testdb,tb:test1,nx:n2,et:DML,eid:3,en:node,ip:10.0.2.101>: Sync client returned errcode:[0],errmsg:[success]\n2022-02-14 10:24:33.395 [INFO ] <session:6, tid:609, db:testdb,tb:test1,nx:n2,et:DML,eid:3,en:node,ip:10.0.2.101>: CALL_SYNC, Sync end, return 0\n2022-02-14 10:24:33.399 [INFO ] <session:6, tid:609, db:testdb,tb:test1,nx:n2,et:DML,eid:3,en:node,ip:10.0.2.101>: CHECK_SYNC_RESULT,Checking whether SYNC is succeeded{DB: 'testdb', Table: 'test1', slice: 'n2'}\n2022-02-14 10:24:33.399 [INFO ] <session:6, tid:609, db:testdb,tb:test1,nx:n2,et:DML,eid:3,en:node,ip:10.0.2.101>: QueryExecute ip 10.0.2.101,port 5050, do \"select scn from information_schema.tables where table_schema='testdb' and table_name='test1_n2'\"\n2022-02-14 10:24:33.400 [INFO ] <session:6, tid:609, db:testdb,tb:test1,nx:n2,et:DML,eid:3,en:node,ip:10.0.2.101>: MatchJustOneResult:19460\n2022-02-14 10:24:33.400 [INFO ] <session:6, tid:609, db:testdb,tb:test1,nx:n2,et:DML,eid:3,en:node,ip:10.0.2.101>: QueryExecute ip 10.0.2.115,port 5050, do \"refresh table \"testdb\".\"test1_n2\"\"\n2022-02-14 10:24:33.401 [INFO ] <session:6, tid:609, db:testdb,tb:test1,nx:n2,et:DML,eid:3,en:node,ip:10.0.2.101>: QueryExecute ip 10.0.2.115,port 5050, do \"select scn from information_schema.tables where table_schema='testdb' and table_name='test1_n2'\"\n2022-02-14 10:24:33.401 [INFO ] <session:6, tid:609, db:testdb,tb:test1,nx:n2,et:DML,eid:3,en:node,ip:10.0.2.101>: MatchJustOneResult:19460\n2022-02-14 10:24:33.402 [INFO ] <session:6, tid:609, db:testdb,tb:test1,nx:n2,et:DML,eid:3,en:node,ip:10.0.2.101>: CHECK_SYNC_RESULT, recover GNode: 19460\n2022-02-14 10:24:33.402 [INFO ] <session:6, tid:609, db:testdb,tb:test1,nx:n2,et:DML,eid:3,en:node,ip:10.0.2.101>: CHECK_SYNC_RESULT, source GNode[1]: 19460\n2022-02-14 10:24:33.402 [INFO ] <session:6, tid:609, db:testdb,tb:test1,nx:n2,et:DML,eid:3,en:node,ip:10.0.2.101>: delete dml event, table testdb.test1,event id 3 suffix n2\n2022-02-14 10:24:33.411 [INFO ] <session:6, tid:609, db:testdb,tb:test1,nx:n2,et:DML,eid:3,en:node,ip:10.0.2.101>: SET_ONLINE, Succeeded in setting local node table 'testdb.test1_n2' online, eventinfo(eventid:3,segId:2)\n2022-02-14 10:24:33.411 [INFO ] <session:6, tid:609, db:testdb,tb:test1,nx:n2,et:DML,eid:3,en:node,ip:10.0.2.101>: CheckToClearOrKeepPreWriteDMLS delete eventid 5\n2022-02-14 10:24:33.419 [INFO ] <session:6, tid:609, db:testdb,tb:test1,nx:n2,et:DML,eid:3,en:node,ip:10.0.2.101>: delete dmlstorage event, event id: 5\n2022-02-14 10:24:33.430 [INFO ] <session:6>: DealDMLRecoverUnLock vc00001.testdb.test1\n2022-02-14 10:24:33.631 [INFO ] <session:6>: End dml recover .,tid 609\n2022-02-14 10:24:33.633 [INFO ] <session:6>: UnLock: GCRECOVER_LOCK_TID_vc00001_609 unlock success, 1091491459250520064\n2022-02-14 10:24:33.636 [INFO ] <session:6>: Lock: GCRECOVER_LOCK_TID_vc00001_609 end success, 2113927811545694208\n2022-02-14 10:24:33.636 [INFO ] <session:6>: Start DMLStorge recover .,tid 609 eventnum 1\n2022-02-14 10:24:33.650 [INFO ] <session:6>: QueryExecute ip 10.0.2.101,port 5258, do \"select table_id from information_schema.tables where table_schema='testdb' and table_name='test1' and TABLE_VC='vcname000001'\"\n2022-02-14 10:24:33.651 [INFO ] <session:6>: MatchJustOneResult:609\n2022-02-14 10:24:33.671 [INFO ] <session:6>: DealDMLRecoverLock vc00001.testdb.test1 success\n2022-02-14 10:24:33.674 [INFO ] <session:6,tid:609, db:testdb,tb:test1,nx:n1,et:DMLS,eid:4,en:node,ip:10.0.2.101>: recoverinfo gn(dmls): eventid=4, tablename=testdb.test1, src:0x1929510922(10.0.2.115) dst:0x1694629898(10.0.2.101)\n2022-02-14 10:24:33.674 [INFO ] <session:6,tid:609, db:testdb,tb:test1,nx:n1,et:DMLS,eid:4,en:node,ip:10.0.2.101>: QueryExecute ip 10.0.2.101,port 5050, do \"DROP SELF TABLE IF EXISTS \"testdb\".\"test1_n1\";\"<\/span>\n2022-02-14 10:24:33.675 [INFO ] <session:6,tid:609, db:testdb,tb:test1,nx:n1,et:DMLS,eid:4,en:node,ip:10.0.2.101>: QueryExecute ip 10.0.2.115,port 5050, do \"select scn from information_schema.tables where table_schema='testdb' and table_name='test1_n1'\"\n2022-02-14 10:24:33.676 [INFO ] <session:6,tid:609, db:testdb,tb:test1,nx:n1,et:DMLS,eid:4,en:node,ip:10.0.2.101>: MatchJustOneResult:19460\n2022-02-14 10:24:33.676 [INFO ] <session:6,tid:609, db:testdb,tb:test1,nx:n1,et:DMLS,eid:4,en:node,ip:10.0.2.101>: QueryExecute ip 10.0.2.115,port 5050, do \"select scn from information_schema.tables where table_schema='testdb' and table_name='test1_n1'\"\n2022-02-14 10:24:33.677 [INFO ] <session:6,tid:609, db:testdb,tb:test1,nx:n1,et:DMLS,eid:4,en:node,ip:10.0.2.101>: MatchJustOneResult:19460\n2022-02-14 10:24:33.677 [INFO ] <session:6,tid:609, db:testdb,tb:test1,nx:n1,et:DMLS,eid:4,en:node,ip:10.0.2.101>: QueryExecute ip 10.0.2.115,port 5050, do \"show full create table \"testdb\".\"test1_n1\";\"<\/span>\n2022-02-14 10:24:33.677 [INFO ] <session:6,tid:609, db:testdb,tb:test1,nx:n1,et:DMLS,eid:4,en:node,ip:10.0.2.101>: MatchJustOneResult:CREATE TABLE \"test1_n1\" (\n \"id\" int(11) DEFAULT NULL\n) ENGINE=EXPRESS TID(610) UID(1) DEFAULT CHARSET=utf8 TABLESPACE='sys_tablespace' COLUMN_IDS(0)\n2022-02-14 10:24:33.677 [INFO ] <session:6,tid:609, db:testdb,tb:test1,nx:n1,et:DMLS,eid:4,en:node,ip:10.0.2.101>: QueryExecute ip 10.0.2.101,port 5050, do \"use self \"testdb\";\"\n2022-02-14 10:24:33.678 [INFO ] <session:6,tid:609, db:testdb,tb:test1,nx:n1,et:DMLS,eid:4,en:node,ip:10.0.2.101>: QueryExecute ip 10.0.2.101,port 5050, do \"SET SELF SCN = 19460\"\n2022-02-14 10:24:33.678 [INFO ] <session:6,tid:609, db:testdb,tb:test1,nx:n1,et:DMLS,eid:4,en:node,ip:10.0.2.101>: QueryExecute ip 10.0.2.101,port 5050, do \"CREATE SELF TABLE \"test1_n1\" (\n \"id\" int(11) DEFAULT NULL\n) ENGINE=EXPRESS TID(610) UID(1) DEFAULT CHARSET=utf8 TABLESPACE='sys_tablespace' COLUMN_IDS(0)\"<\/span>\n2022-02-14 10:24:33.707 [INFO ] <session:6,tid:609, db:testdb,tb:test1,nx:n1,et:DMLS,eid:4,en:node,ip:10.0.2.101>: QueryExecute ip 10.0.2.101,port 5050, do \"flush transaction_log\"\n2022-02-14 10:24:33.707 [INFO ] <session:6,tid:609, db:testdb,tb:test1,nx:n1,et:DMLS,eid:4,en:node,ip:10.0.2.101>: QueryExecute ip 10.0.2.115,port 5050, do \"flush transaction_log\"\n2022-02-14 10:24:33.708 [INFO ] <session:6,tid:609, db:testdb,tb:test1,nx:n1,et:DMLS,eid:4,en:node,ip:10.0.2.101>: QueryExecute ip 10.0.2.101,port 5050, do \"start 'gc_sync_client' '10.0.2.115 vcname000001 testdb test1_n1 10.0.2.101 5258 2 0'\"<\/span>\n2022-02-14 10:24:33.879 [INFO ] <session:6,tid:609, db:testdb,tb:test1,nx:n1,et:DMLS,eid:4,en:node,ip:10.0.2.101>: ExecSyncQuery, sync table returned with (0)\n2022-02-14 10:24:33.879 [INFO ] <session:6,tid:609, db:testdb,tb:test1,nx:n1,et:DMLS,eid:4,en:node,ip:10.0.2.101>: check drop sql thread quit.\n2022-02-14 10:24:33.879 [INFO ] <session:6,tid:609, db:testdb,tb:test1,nx:n1,et:DMLS,eid:4,en:node,ip:10.0.2.101>: QueryExecute ip 10.0.2.101,port 5050, do \"flush transaction_log\"\n2022-02-14 10:24:33.880 [INFO ] <session:6,tid:609, db:testdb,tb:test1,nx:n1,et:DMLS,eid:4,en:node,ip:10.0.2.101>: QueryExecute ip 10.0.2.115,port 5050, do \"flush transaction_log\"\n2022-02-14 10:24:33.880 [INFO ] <session:6,tid:609, db:testdb,tb:test1,nx:n1,et:DMLS,eid:4,en:node,ip:10.0.2.101>: CALL_SYNC, Sync client executed successfully\n2022-02-14 10:24:33.880 [INFO ] <session:6,tid:609, db:testdb,tb:test1,nx:n1,et:DMLS,eid:4,en:node,ip:10.0.2.101>: QueryExecute ip 10.0.2.101,port 5050, do \"refresh table \"testdb\".\"test1_n1\"\"\n2022-02-14 10:24:33.881 [INFO ] <session:6,tid:609, db:testdb,tb:test1,nx:n1,et:DMLS,eid:4,en:node,ip:10.0.2.101>: Sync client returned errcode:[0],errmsg:[success]\n2022-02-14 10:24:33.881 [INFO ] <session:6,tid:609, db:testdb,tb:test1,nx:n1,et:DMLS,eid:4,en:node,ip:10.0.2.101>: CALL_SYNC, Sync end, return 0\n2022-02-14 10:24:33.884 [INFO ] <session:6,tid:609, db:testdb,tb:test1,nx:n1,et:DMLS,eid:4,en:node,ip:10.0.2.101>: CHECK_SYNC_RESULT,Checking whether SYNC is succeeded{DB: 'testdb', Table: 'test1', slice: 'n1'}\n2022-02-14 10:24:33.884 [INFO ] <session:6,tid:609, db:testdb,tb:test1,nx:n1,et:DMLS,eid:4,en:node,ip:10.0.2.101>: QueryExecute ip 10.0.2.101,port 5050, do \"select scn from information_schema.tables where table_schema='testdb' and table_name='test1_n1'\"\n2022-02-14 10:24:33.885 [INFO ] <session:6,tid:609, db:testdb,tb:test1,nx:n1,et:DMLS,eid:4,en:node,ip:10.0.2.101>: MatchJustOneResult:19460\n2022-02-14 10:24:33.885 [INFO ] <session:6,tid:609, db:testdb,tb:test1,nx:n1,et:DMLS,eid:4,en:node,ip:10.0.2.101>: QueryExecute ip 10.0.2.115,port 5050, do \"refresh table \"testdb\".\"test1_n1\"\"\n2022-02-14 10:24:33.886 [INFO ] <session:6,tid:609, db:testdb,tb:test1,nx:n1,et:DMLS,eid:4,en:node,ip:10.0.2.101>: QueryExecute ip 10.0.2.115,port 5050, do \"select scn from information_schema.tables where table_schema='testdb' and table_name='test1_n1'\"\n2022-02-14 10:24:33.886 [INFO ] <session:6,tid:609, db:testdb,tb:test1,nx:n1,et:DMLS,eid:4,en:node,ip:10.0.2.101>: MatchJustOneResult:19460\n2022-02-14 10:24:33.887 [INFO ] <session:6,tid:609, db:testdb,tb:test1,nx:n1,et:DMLS,eid:4,en:node,ip:10.0.2.101>: CHECK_SYNC_RESULT, recover GNode: 19460\n2022-02-14 10:24:33.887 [INFO ] <session:6,tid:609, db:testdb,tb:test1,nx:n1,et:DMLS,eid:4,en:node,ip:10.0.2.101>: CHECK_SYNC_RESULT, source GNode[1]: 19460\n2022-02-14 10:24:33.887 [INFO ] <session:6,tid:609, db:testdb,tb:test1,nx:n1,et:DMLS,eid:4,en:node,ip:10.0.2.101>: Delete DMLStorge eventId 4\n2022-02-14 10:24:33.895 [INFO ] <session:6,tid:609, db:testdb,tb:test1,nx:n1,et:DMLS,eid:4,en:node,ip:10.0.2.101>: delete dmlstorage event, event id: 4\n2022-02-14 10:24:33.904 [INFO ] <session:6>: DealDMLSRecoverUnLock vc00001.testdb.test1\n2022-02-14 10:24:34.109 [INFO ] <session:6,tid:609, db:testdb,tb:test1,nx:n1,et:DMLS,eid:4,en:node,ip:10.0.2.101>: UnLock: GCRECOVER_LOCK_TID_vc00001_609 unlock success, 2113927811545694208\n<\/code><\/pre>\n\n\n\n<\/span>\u5206\u7247\u8868\u7ed3\u6784\u4e0d\u4e00\u81f4\u7684\u6062\u590d\u603b\u7ed3<\/span><\/h3>\n\n\n\n\u5f53\u540c\u6b65\u8fdb\u7a0b\u53d1\u9001\u540c\u6b65\u547d\u4ee4\u8fd4\u56de\u5931\u8d25\u65f6\uff0c\u6839\u636e\u9519\u8bef\u539f\u56e0\u5224\u65ad\u662f\u4e3b\u526f\u672c\u8868\u7ed3\u6784\u4e0d\u540c\uff0c\u5219\u5220\u9664\u6389\u6545\u969c\u7684\u8868\uff0c\u4ece\u526f\u672c\u83b7\u5f97\u6bd4\u7ed3\u6784\u540e\u91cd\u5efa\u5206\u7247\u3002\u6700\u540e\u540c\u6b65\u6570\u636e\u5b8c\u6210\u6574\u4e2a\u6062\u590d\u8fc7\u7a0b\u3002<\/p>\n\n\n\n
<\/span>\u89e6\u53d1Event\u7684\u573a\u666f<\/span><\/h2>\n\n\n\n\u9664\u4e86\u524d\u9762\u4ecb\u7ecd\u7684\u5728\u670d\u52a1\u79bb\u7ebf\u65f6\uff0c\u6709\u6570\u636e\u64cd\u4f5c\u4f1a\u51fa\u73b0event\uff0c\u5728\u5982\u4e0b\u6761\u4ef6\u4e5f\u80fd\u89e6\u53d1event\u3002<\/p>\n\n\n\n
<\/span>INSERT\u64cd\u4f5c\u6d89\u53ca\u5230\u4e86\u4e22\u5931\u7684\u5206\u7247<\/span><\/h3>\n\n\n\n\u5982\u679cInsert\u64cd\u4f5c\u6d89\u53ca\u5230\u4e86\u6545\u969c\u5206\u7247\uff0c\u5219\u96c6\u7fa4\u4f1a\u81ea\u52a8\u8bbe\u7f6eevent\u3002\u5982\u679c\u4e0d\u6d89\u53ca\u8be5\u5206\u7247\uff0c\u5219\u4e0d\u4f1a\u89e6\u53d1\u3002<\/p>\n\n\n\n
\u6ce8\u610f\uff0c\u5728V95\u91cc\u5982\u679c\u6709\u53ef\u7528\u7684\u5206\u7247\uff0c\u5219insert\u4f1a\u6210\u529f\uff0c\u4f46\u4f1a\u8bbe\u7f6eevent,\u5728V8\u91cc\u4f1a\u62a5\u9519\uff0c\u518d\u8bbe\u7f6eevent\u3002<\/p>\n\n\n\n
gbase> insert into test1 values(99999),(100000),(121212),(1213133131);\nQuery OK, 4 rows affected (Elapsed: 00:00:00.18)\nRecords: 4 Duplicates: 0 Warnings: 0<\/code><\/pre>\n\n\n\n\u6ce8\u610f\uff0c\u8be5SQL\u80fd\u6b63\u5e38\u8fd0\u884c\uff0c\u4e5f\u6ca1\u6709wanrings\uff0c\u53ea\u662f\u5728\u8fde\u63a5\u8282\u70b9gcluster\u7684express.log\u91cc\u80fd\u770b\u5230\u5982\u4e0b\u5185\u5bb9\uff1a<\/p>\n\n\n\n
Failed to flush data, host: 10.0.2.101, suffix: n1, scn:19463, cause: Table 'testdb.test1_n1' doesn't exist\u3002<\/p>\n\n\n\n
\u7136\u540e\u8bbe\u7f6e\u4e86event\uff1aSet Node(10.0.2.101)testdb.test1[n1][scn:19463] data state<\/p>\n\n\n\n
2022-02-14 14:41:20.750 [INSERT][WARN ][S:109][Q:70]:Failed to flush data, host: 10.0.2.101, suffix: n1, scn:19463, cause: Table 'testdb.test1_n1' doesn't exist\n2022-02-14 14:41:20.863 [DEF ] [S:109][Q:70][insert] Set Node(10.0.2.101)testdb.test1[n1][scn:19463] data state\n2022-02-14 14:41:20.891 [DEF ] [S:109][Q:70]Successfully set data(testdb.test1:normal) LOCKED, event id is 4\n<\/code><\/pre>\n\n\n\n<\/span>LOAD\u52a0\u8f7d\u8bed\u53e5\u6d89\u53ca\u4e86\u4e22\u5931\u7684\u5206\u7247<\/span><\/h3>\n\n\n\n\u4e0einsert \u4e0d\u540c\uff0cload\u8bed\u53e5\u90fd\u4f1a\u62a5\u9519(\u8868\u4e0d\u5b58\u5728),\u7136\u540e\u8bbe\u7f6eevent, \u540c\u6b65\u5b8c\u6210\u540e\uff0c\u53ef\u4ee5\u6b63\u5e38\u52a0\u8f7d\u4e86\u3002<\/p>\n\n\n\n
gbase> load data infile 'sftp:\/\/gbase:gbase1234@10.0.2.101\/home\/gbase\/test1.txt' into table test1;\nERROR 1733 (HY000): (GBA-01EX-700) Gbase general error: Task 20481 failed, [10.0.2.115:5050](GBA-02AD-0005)Failed to query in gnode:\nDETAIL: (GBA-01EX-700) Gbase general error: (gns_host: 10.0.2.101) Table 'testdb.test1_n1' doesn't exist\nSQL: LOAD \/*+ TID('3473414') *\/ DATA INFILE 'sftp:\/\/gbase:*********@10.0.2.101\/home\/gbase\/test1.txt#offset=0&length=22&firstblock&ffsize=22' INTO TABLE `testdb`.`test1_n1` DATA_FORMAT 3 FILE_FORMAT UNDEFINED HOST '10.0.2.115' CURRENT_TIMESTAMP 1644825079 SCN_NUMBER 20481 GCLUSTER_PORT 5258 I\ngbase>\ngbase> load data infile 'sftp:\/\/gbase:gbase1234@10.0.2.101\/home\/gbase\/test1.txt' into table test1;\nQuery OK, 11 rows affected (Elapsed: 00:00:01.59)\nTask 20482 finished, Loaded 11 records, Skipped 0 records<\/code><\/pre>\n\n\n\ngcluster\u7684express.log\u65e5\u5fd7<\/p>\n\n\n\n
2022-02-14 15:51:19.806 [SQLDISP][ERROR][S:10][Q:8]<ExecSQL|2545>:Query failed, THD(0x6170000) HOST(10.0.2.115:5050->18) reason: (GBA-01EX-700) Gbase general error: (gns_host: 10.0.2.101) Table 'testdb.test1_n1' doesn't exist SQL(LOAD \/*+ TID('3473414') *\/ DATA INFILE 'sftp:\/\/gbase:*********@10.0.2.101\/home\/gbase\/test1.txt#offset=0&length=22&firstblock&ffsize=22' INTO TABLE `testdb`.`test1_n1` DATA_FORMAT 3 FILE_FORMAT UNDEFINED HOST '10.0.2.115' CURRENT_TIMESTAMP 1644825079 SCN_NUMBER 20481 GCLUSTER_PORT 5258 INTO SERVER (HOST '10.0.2.101, 10.0.2.115', PORT '5050', USER 'root', DATABASE 'testdb', TABLE 'test1_n1, test1_n2', COMMENT 'table_host 0 0 1 # 1 1 0, scn 20481, group 0 ,distribution 1, hash_map 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0\n2022-02-14 15:51:19.854 [LOAD][ERROR][S:10][Q:8]<ExecuteLoad|2055>:Task 20481 failed, [10.0.2.115:5050](GBA-02AD-0005)Failed to query in gnode:\nDETAIL: (GBA-01EX-700) Gbase general error: (gns_host: 10.0.2.101) Table 'testdb.test1_n1' doesn't exist\nSQL: LOAD \/*+ TID('3473414') *\/ DATA INFILE 'sftp:\/\/gbase:*********@10.0.2.101\/home\/gbase\/test1.txt#offset=0&length=22&firstblock&ffsize=22' INTO TABLE `testdb`.`test1_n1` DATA_FORMAT 3 FILE_FORMAT UNDEFINED HOST '10.0.2.115' CURRENT_TIMESTAMP 1644825079 SCN_NUMBER 20481 GCLUSTER_PORT 5258 INTO SERVER (HOST '10.0.2.101, 10.0.2.115', PORT '5050', USER 'root', DATABASE 'testdb', TABLE 'test1_n1, test1_n2', COMMENT 'table_host 0 0 1 # 1 1 0, scn 20481, group 0 ,distribution 1, hash_map 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 ... 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1 0 1\n2022-02-14 15:51:19.861 [SQLDISP][ERROR][S:10][Q:8]<ExecSQL|2545>:Query failed, THD(0x6170000) HOST(10.0.2.101:5050->17) reason: (GBA-01EX-700) Gbase general error: table doesn't exist SQL(\/*10.0.2.101_10_2_2022-02-14_15:51:19*\/ flush \/*+TID('3473414')*\/ rollback \"testdb\".\"test1_n1\")\n2022-02-14 15:51:19.863 [COMMIT][WARN ][S:10][Q:8]<FlushRollbackAsync|4581>:Some 'Flush rollback' failed, cause: [10.0.2.101:5050](GBA-02AD-0005)Failed to query in gnode:\nDETAIL: (GBA-01EX-700) Gbase general error: table doesn't exist\nSQL: \/*10.0.2.101_10_2_2022-02-14_15:51:19*\/ flush \/*+TID('3473414')*\/ rollback \"testdb\".\"test1_n1\"\n2022-02-14 15:51:19.863 [COMMIT][ERROR][S:10][Q:8]<FlushRollbackAsync|4620>:Failed to flush rollback table 'test1[n1]' in 10.0.2.101, cause: (GBA-01EX-700) Gbase general error: table doesn't exist\n2022-02-14 15:51:19.869 [DEF ] [S:10][Q:8][load] Set Node(10.0.2.101)testdb.test1[n1][scn:20481] data state\n2022-02-14 15:51:19.908 [DEF ] [S:10][Q:8]Successfully set data(testdb.test1:normal) LOCKED, event id is 5\n<\/code><\/pre>\n\n\n\n<\/span>\u5f53\u524d\u7248\u672c\u672a\u89e6\u53d1event\u7684\u573a\u666f<\/span><\/h2>\n\n\n\n\u622a\u81f32022-02-14 ,9.5.2.44.2\u7248\u672c\uff0c\u5982\u4e0b\u573a\u666f\u6ca1\u6709\u89e6\u53d1event.<\/p>\n\n\n\n
<\/span>\u96c6\u7fa4\u6b63\u5e38\uff0c\u4f46\u4e3b\u526f\u672c\u8868\u7ed3\u6784\u4e0d\u4e00\u81f4<\/span><\/h3>\n\n\n\n\u4e00\u4e2a\u5206\u7247\u591a\u4e86\u4e00\u5217\uff0c\u4f46\u6b64\u65f6\u7531\u4e8e\u96c6\u7fa4\u6b63\u5e38\uff0c\u6ca1\u6709\u4e0d\u4e00\u81f4\u7684\u6807\u8bb0\uff0c\u6240\u4ee5\u96c6\u7fa4\u6b64\u65f6\u65e0\u6cd5\u5224\u65ad\u54ea\u4e2a\u662f\u6b63\u786e\u7684\u3002SQL\u4f1a\u62a5\u9519\u3002<\/p>\n\n\n\n
gbase> insert into test1 (id) values(99999),(100000),(121212),(1213133131);\nERROR 1705 (HY000): gcluster DML error: Commit failed\nERROR NO: 1733ERROR DETAIL: (GBA-01EX-700) Gbase general error: (gns_host: 10.0.2.101) source table and destination table are not same: src:( localhost:testdb.test1_n1 ) dst:( 10.0.2.115:testdb.test1_n1 ) column count: 2 -- 1\nSQL: insert into test1 (id) values(99999),(100000),(121212),(1213133131)\ngbase>\n<\/code><\/pre>\n\n\n\n\u65e5\u5fd7\u91cc\u62a5\u9519\uff0c\u4f46\u6ca1\u6709\u8bbe\u7f6eevent<\/p>\n\n\n\n
2022-02-14 15:57:25.262 [SQLDISP][ERROR][S:21][Q:32]<ExecSQL|2545>:Query failed, THD(0x3b54000) HOST(10.0.2.101:5050->62) reason: (GBA-01EX-700) Gbase general error: (gns_host: 10.0.2.101) source table and destination table are not same: src:( localhost:testdb.test1_n1 ) dst:( 10.0.2.115:testdb.test1_n1 ) column count: 2 -- 1 SQL(\/*10.0.2.101_21_2_2022-02-14_15:57:25*\/ SELECT \/*+TID('3473427')*\/ * from `testdb`.`test1_n1` limit 0 target into server (HOST '10.0.2.101,10.0.2.115',PORT '5050', USER 'root ', PASSWORD '',DATABASE 'testdb',TABLE 'test1_n1',COMMENT 'table_host 0 0 1, scn 20485, gc_check '))\n2022-02-14 15:57:25.266 [SQLDISP][ERROR][S:21][Q:32]<ExecSQL|2545>:Query failed, THD(0x3b54000) HOST(10.0.2.101:5050->63) reason: (GBA-01EX-700) Gbase general error: Query execution was interrupted. SQL(\/*10.0.2.101_21_2_2022-02-14_15:57:25*\/ SELECT \/*+TID('3473427')*\/ * from `testdb`.`test1_n2` limit 0 target into server (HOST '10.0.2.101,10.0.2.115',PORT '5050', USER 'root ', PASSWORD '',DATABASE 'testdb',TABLE 'test1_n2',COMMENT 'table_host 0 0 1, scn 20485, gc_check '))\n2022-02-14 15:57:25.266 [COMMIT][WARN ][S:21][Q:32]<CommitAsync|4127>:Do rollback ....\n2022-02-14 15:57:25.595 [COMMIT][WARN ][S:21][Q:32]<DoRevert|357>:Revert 'testdb.test1[n1]' success, 10.0.2.101\n2022-02-14 15:57:25.595 [COMMIT][WARN ][S:21][Q:32]<DoRevert|357>:Revert 'testdb.test1[n2]' success, 10.0.2.101\n2022-02-14 15:57:25.595 [COMMIT][WARN ][S:21][Q:32]<DoRevert|357>:Revert 'testdb.test1[n1]' success, 10.0.2.115\n2022-02-14 15:57:25.595 [COMMIT][WARN ][S:21][Q:32]<DoRevert|357>:Revert 'testdb.test1[n2]' success, 10.0.2.115\n2022-02-14 15:57:25.620 [INSERT][ERROR][S:21][Q:32]:Commit failed\nERROR NO: 1733ERROR DETAIL: (GBA-01EX-700) Gbase general error: (gns_host: 10.0.2.101) source table and destination table are not same: src:( localhost:testdb.test1_n1 ) dst:( 10.0.2.115:testdb.test1_n1 ) column count: 2 -- 1\nSQL: insert into test1 (id) values(99999),(100000),(121212),(1213133131)\n<\/code><\/pre>\n\n\n\n