BR 全备份和恢复，系统库无法恢复

快乐的非鱼 · 2023 年3 月 20 日 04:04

【 TiDB 使用环境】测试
【 TiDB 版本】6.5.1
【复现路径】使用BR全备份，在单机环境上，实现了集群效果
【遇到的问题：问题现象及影响】
备份和恢复提示都成功，但是mysql 库的单张表例如analyze_options，在备份后手工删除了，在恢复，不成功，除了系统表外，自己的数据库是ok的
【资源配置】
【附件：截图/日志/监控】
[2023/03/20 11:51:32.811 +08:00] [INFO] [info.go:49] [“Welcome to Backup & Restore (BR)”] [release-version=v6.5.1] [git-hash=4084b077d615f9dc0a41cf2e30bc6e1a02332df2] [git-branch=heads/refs/tags/v6.5.1] [go-version=go1.19.5] [utc-build-time=“2023-03-07 16:04:15”] [race-enabled=false]
[2023/03/20 11:51:32.811 +08:00] [INFO] [common.go:718] [arguments] [__command=“br restore table”] [db=mysql] [log-file=backup.log] [pd=“[:2379]”] [ratelimit=128] [storage=local:///data/tidb/backup/br] [table=analyze_options]
[2023/03/20 11:51:32.811 +08:00] [INFO] [common.go:159] [“trying to connect to etcd”] [addr=“[:2379]”]
[2023/03/20 11:51:32.812 +08:00] [INFO] [conn.go:147] [“new mgr”] [pdAddrs=:2379]
[2023/03/20 11:51:32.812 +08:00] [INFO] [client.go:405] [“[pd] create pd client with endpoints”] [pd-address=“[:2379]”]
[2023/03/20 11:51:32.814 +08:00] [INFO] [base_client.go:360] [“[pd] update member urls”] [old-urls=“[http://:2379]”] [new-urls=“[http://192.168.68.10:2379,http://192.168.68.10:2381]”]
[2023/03/20 11:51:32.814 +08:00] [INFO] [base_client.go:378] [“[pd] switch leader”] [new-leader=http://192.168.68.10:2379] [old-leader=]
[2023/03/20 11:51:32.814 +08:00] [INFO] [base_client.go:105] [“[pd] init cluster id”] [cluster-id=7212221220004930598]
[2023/03/20 11:51:32.814 +08:00] [INFO] [client.go:698] [“[pd] tso dispatcher created”] [dc-location=global]
[2023/03/20 11:51:32.814 +08:00] [INFO] [conn.go:121] [“checked alive KV stores”] [aliveStores=2] [totalStores=2]
[2023/03/20 11:51:32.814 +08:00] [INFO] [client.go:405] [“[pd] create pd client with endpoints”] [pd-address=“[:2379]”]
[2023/03/20 11:51:32.815 +08:00] [INFO] [base_client.go:360] [“[pd] update member urls”] [old-urls=“[http://:2379]”] [new-urls=“[http://192.168.68.10:2379,http://192.168.68.10:2381]”]
[2023/03/20 11:51:32.815 +08:00] [INFO] [base_client.go:378] [“[pd] switch leader”] [new-leader=http://192.168.68.10:2379] [old-leader=]
[2023/03/20 11:51:32.815 +08:00] [INFO] [base_client.go:105] [“[pd] init cluster id”] [cluster-id=7212221220004930598]
[2023/03/20 11:51:32.815 +08:00] [INFO] [client.go:698] [“[pd] tso dispatcher created”] [dc-location=global]
[2023/03/20 11:51:32.816 +08:00] [INFO] [tidb.go:77] [“new domain”] [store=tikv-7212221220004930598] [“ddl lease”=1s] [“stats lease”=-1ns] [“index usage sync lease”=0s]
[2023/03/20 11:51:32.947 +08:00] [INFO] [domain.go:2286] [acquireServerID] [serverID=2166121] [“lease id”=38d386f9acefc7a2]
[2023/03/20 11:51:33.053 +08:00] [WARN] [info.go:245] [“init TiFlashReplicaManager”] [“pd addrs”=“[192.168.68.10:2381,192.168.68.10:2379]”]
[2023/03/20 11:51:33.454 +08:00] [INFO] [domain.go:220] [“full load InfoSchema success”] [currentSchemaVersion=0] [neededSchemaVersion=121] [“start time”=5.758189ms]
[2023/03/20 11:51:33.529 +08:00] [INFO] [domain.go:487] [“full load and reset schema validator”]
[2023/03/20 11:51:33.529 +08:00] [INFO] [ddl.go:701] [“[ddl] start DDL”] [ID=56ce697b-0584-40f1-a761-9e0a4862ea9b] [runWorker=true]
[2023/03/20 11:51:33.529 +08:00] [INFO] [ddl.go:647] [“[ddl] start delRangeManager OK”] [“is a emulator”=false]
[2023/03/20 11:51:33.529 +08:00] [INFO] [manager.go:151] [“start campaign owner”] [ownerInfo=“[ddl] /tidb/ddl/fg/owner”]
[2023/03/20 11:51:33.529 +08:00] [INFO] [ddl_worker.go:171] [“[ddl] start DDL worker”] [worker=“worker 1, tp general”]
[2023/03/20 11:51:33.529 +08:00] [INFO] [ddl_worker.go:171] [“[ddl] start DDL worker”] [worker=“worker 2, tp add index”]
[2023/03/20 11:51:33.595 +08:00] [WARN] [env.go:52] [“[ddl-ingest] initialize environment failed”] [“storage limitation”=“only support TiKV storage”] [“current storage”=unistore] [“lightning is initialized”=false]
[2023/03/20 11:51:33.595 +08:00] [INFO] [env.go:108] [“[ddl-ingest] the lightning sorted dir”] [“data path:”=/tmp/tidb/tmp_ddl-4000]
[2023/03/20 11:51:33.595 +08:00] [INFO] [env.go:75] [“[ddl-ingest] init global lightning backend environment finished”] [“memory limitation”=2147483648] [“sort path disk quota”=48294789120] [“max open file number”=4096] [“lightning is initialized”=true]
[2023/03/20 11:51:33.595 +08:00] [INFO] [owner_daemon.go:70] [“begin advancer daemon”] [daemon-id=LogBackup::Advancer]
[2023/03/20 11:51:33.595 +08:00] [INFO] [manager.go:151] [“start campaign owner”] [ownerInfo=“[log-backup] /tidb/br-stream/owner”]
[2023/03/20 11:51:33.795 +08:00] [INFO] [owner_daemon.go:81] [“begin running daemon”] [id=6ced7bb9-f459-4115-b926-67f378be3b86] [daemon-id=LogBackup::Advancer]
[2023/03/20 11:51:33.795 +08:00] [WARN] [sysvar_cache.go:50] [“sysvar cache is empty, triggering rebuild”]
[2023/03/20 11:51:33.799 +08:00] [INFO] [ddl.go:1287] [“[ddl] switch metadata lock feature”] [enable=true]
[2023/03/20 11:51:33.799 +08:00] [INFO] [manager.go:151] [“start campaign owner”] [ownerInfo=“[stats] /tidb/stats/owner”]
[2023/03/20 11:51:33.847 +08:00] [INFO] [client.go:451] [“new worker pool”] [currency-count=128]
[2023/03/20 11:51:33.847 +08:00] [INFO] [client.go:264] [“set placement policy mode”] [mode=STRICT]
[2023/03/20 11:51:33.847 +08:00] [INFO] [db.go:75] [“set tidb_placement_mode success”] [mode=STRICT]
[2023/03/20 11:51:33.847 +08:00] [INFO] [manager.go:151] [“start campaign owner”] [ownerInfo=“[stats] /tidb/stats/owner”]
[2023/03/20 11:51:33.954 +08:00] [INFO] [db.go:75] [“set tidb_placement_mode success”] [mode=STRICT]
[2023/03/20 11:51:33.954 +08:00] [INFO] [db.go:75] [“set tidb_placement_mode success”] [mode=STRICT]
[2023/03/20 11:51:33.954 +08:00] [INFO] [db.go:75] [“set tidb_placement_mode success”] [mode=STRICT]
[2023/03/20 11:51:33.954 +08:00] [INFO] [db.go:75] [“set tidb_placement_mode success”] [mode=STRICT]
[2023/03/20 11:51:33.954 +08:00] [INFO] [db.go:75] [“set tidb_placement_mode success”] [mode=STRICT]
[2023/03/20 11:51:33.955 +08:00] [INFO] [db.go:75] [“set tidb_placement_mode success”] [mode=STRICT]
[2023/03/20 11:51:33.955 +08:00] [INFO] [db.go:75] [“set tidb_placement_mode success”] [mode=STRICT]
[2023/03/20 11:51:33.955 +08:00] [INFO] [db.go:75] [“set tidb_placement_mode success”] [mode=STRICT]
[2023/03/20 11:51:33.955 +08:00] [INFO] [db.go:75] [“set tidb_placement_mode success”] [mode=STRICT]
[2023/03/20 11:51:33.955 +08:00] [INFO] [db.go:75] [“set tidb_placement_mode success”] [mode=STRICT]
[2023/03/20 11:51:33.955 +08:00] [INFO] [db.go:75] [“set tidb_placement_mode success”] [mode=STRICT]
[2023/03/20 11:51:33.955 +08:00] [INFO] [db.go:75] [“set tidb_placement_mode success”] [mode=STRICT]
[2023/03/20 11:51:33.956 +08:00] [INFO] [db.go:75] [“set tidb_placement_mode success”] [mode=STRICT]
[2023/03/20 11:51:33.956 +08:00] [INFO] [db.go:75] [“set tidb_placement_mode success”] [mode=STRICT]
[2023/03/20 11:51:33.956 +08:00] [INFO] [db.go:75] [“set tidb_placement_mode success”] [mode=STRICT]
[2023/03/20 11:51:33.956 +08:00] [INFO] [db.go:75] [“set tidb_placement_mode success”] [mode=STRICT]
[2023/03/20 11:51:33.958 +08:00] [INFO] [client.go:2792] [“set new_collation_enabled”] [new_collation_enabled=true]
[2023/03/20 11:51:33.961 +08:00] [INFO] [client.go:384] [“load backupmeta”] [databases=2] [jobs=0]
[2023/03/20 11:51:33.962 +08:00] [INFO] [import.go:291] [“multi ingest support”] [support=true]
[2023/03/20 11:51:34.004 +08:00] [WARN] [restore.go:880] [“set max-index-length to max(30724) to skip check index length in DDL"]
[2023/03/20 11:51:34.004 +08:00] [INFO] [client.go:609] [“create database”] [name=__TiDB_BR_Temporary_mysql]
[2023/03/20 11:51:34.287 +08:00] [INFO] [ddl_worker.go:314] [“[ddl] add DDL jobs”] [“batch count”=1] [jobs=“ID:307, Type:create schema, State:queueing, SchemaState:none, SchemaID:306, TableID:0, RowCount:0, ArgLen:1, start time: 2023-03-20 11:51:34.056 +0800 CST, Err:, ErrCount:0, SnapshotVersion:0; “] [table=true]
[2023/03/20 11:51:34.320 +08:00] [INFO] [ddl.go:1011] [”[ddl] start DDL job”] [job=“ID:307, Type:create schema, State:queueing, SchemaState:none, SchemaID:306, TableID:0, RowCount:0, ArgLen:1, start time: 2023-03-20 11:51:34.056 +0800 CST, Err:, ErrCount:0, SnapshotVersion:0”] [query="/from(br)/CREATE DATABASE IF NOT EXISTS __TiDB_BR_Temporary_mysql /!40100 DEFAULT CHARACTER SET utf8mb4 */”]
[2023/03/20 11:51:34.922 +08:00] [INFO] [domain.go:194] [“diff load InfoSchema success”] [currentSchemaVersion=121] [neededSchemaVersion=122] [“start time”=590.788µs] [phyTblIDs=“[]”] [actionTypes=“[]”]
[2023/03/20 11:51:35.146 +08:00] [INFO] [domain.go:707] [“session manager is nil”]
[2023/03/20 11:51:35.146 +08:00] [INFO] [domain.go:726] [“mdl gets lock, update to owner”] [jobID=307] [version=122]
[2023/03/20 11:51:35.596 +08:00] [INFO] [info.go:1068] [SetTiFlashGroupConfig]
[2023/03/20 11:51:35.822 +08:00] [INFO] [ddl.go:1109] [“[ddl] DDL job is finished”] [jobID=307]
[2023/03/20 11:51:35.822 +08:00] [INFO] [callback.go:121] [“performing DDL change, must reload”]
[2023/03/20 11:51:35.823 +08:00] [INFO] [client.go:748] [“start create tables”]
[2023/03/20 11:51:35.823 +08:00] [INFO] [client.go:244] [“registering the table IDs”] [ids=“ID:empty(end=61)”]
[2023/03/20 11:51:35.823 +08:00] [INFO] [client.go:859] [“create tables”] [“table start”=0] [“table end”=1]
[2023/03/20 11:51:35.823 +08:00] [INFO] [client.go:668] [“client to create tables”]
[2023/03/20 11:51:36.212 +08:00] [INFO] [ddl_worker.go:314] [“[ddl] add DDL jobs”] [“batch count”=1] [jobs=“ID:309, Type:create tables, State:queueing, SchemaState:none, SchemaID:306, TableID:0, RowCount:0, ArgLen:2, start time: 2023-03-20 11:51:36.006 +0800 CST, Err:, ErrCount:0, SnapshotVersion:0; “] [table=true]
[2023/03/20 11:51:36.253 +08:00] [INFO] [ddl.go:1011] [”[ddl] start DDL job”] [job=“ID:309, Type:create tables, State:queueing, SchemaState:none, SchemaID:306, TableID:0, RowCount:0, ArgLen:2, start time: 2023-03-20 11:51:36.006 +0800 CST, Err:, ErrCount:0, SnapshotVersion:0”] [query=“/from(br)/CREATE TABLE analyze_options (\n table_id bigint(64) NOT NULL,\n sample_num bigint(64) NOT NULL DEFAULT ‘0’,\n sample_rate double NOT NULL DEFAULT ‘-1’,\n buckets bigint(64) NOT NULL DEFAULT ‘0’,\n topn bigint(64) NOT NULL DEFAULT ‘-1’,\n column_choice enum(‘DEFAULT’,‘ALL’,‘PREDICATE’,‘LIST’) NOT NULL DEFAULT ‘DEFAULT’,\n column_ids mediumtext DEFAULT NULL,\n PRIMARY KEY (table_id) /*T![clustered_index] CLUSTERED */\n) ENGINE=InnoDB DEFAULT CHARSET=utf8mb4 COLLATE=utf8mb4_bin;”]
[2023/03/20 11:51:37.170 +08:00] [INFO] [domain.go:194] [“diff load InfoSchema success”] [currentSchemaVersion=122] [neededSchemaVersion=123] [“start time”=862.483µs] [phyTblIDs=“[308]”] [actionTypes=“[1152921504606846976]”]
[2023/03/20 11:51:37.296 +08:00] [INFO] [domain.go:707] [“session manager is nil”]
[2023/03/20 11:51:37.296 +08:00] [INFO] [domain.go:726] [“mdl gets lock, update to owner”] [jobID=309] [version=123]
[2023/03/20 11:51:38.255 +08:00] [INFO] [ddl.go:1109] [“[ddl] DDL job is finished”] [jobID=309]
[2023/03/20 11:51:38.255 +08:00] [INFO] [worker.go:85] [“table created”] [speed=“0.41 ops/s”] [table=analyze_options] [database=__TiDB_BR_Temporary_mysql]
[2023/03/20 11:51:38.255 +08:00] [INFO] [restore.go:688] [“range and file prepared”] [“file count”=1] [“range count”=1]
[2023/03/20 11:51:38.255 +08:00] [INFO] [client.go:1241] [“switch to import mode at beginning”]
[2023/03/20 11:51:38.255 +08:00] [INFO] [util.go:348] [“merge and validate file”] [database=__TiDB_BR_Temporary_mysql] [table=analyze_options] [Files(total)=1] [File(write)=1] [File(default)=0] [Region(total)=1] [“Regoin(keys avg)”=1] [“Region(bytes avg)”=50] [Merged(regions)=1] [“Merged(keys avg)”=1] [“Merged(bytes avg)”=50]
[2023/03/20 11:51:38.255 +08:00] [INFO] [util.go:321] [“all range generated”]
[2023/03/20 11:51:38.257 +08:00] [INFO] [pd.go:445] [“pause scheduler successful at beginning”] [name=“[balance-hot-region-scheduler,balance-leader-scheduler,balance-region-scheduler]”]
[2023/03/20 11:51:39.588 +08:00] [INFO] [pd.go:453] [“pause configs successful at beginning”] [cfg=“{"enable-location-replacement":"false","leader-schedule-limit":8,"max-merge-region-keys":0,"max-merge-region-size":0,"max-pending-peer-count":2147483647,"max-snapshot-count":40,"region-schedule-limit":40}”]
[2023/03/20 11:51:39.588 +08:00] [INFO] [client.go:507] [“reset pd timestamp”] [ts=440212612043505668]
[2023/03/20 11:51:39.589 +08:00] [INFO] [client.go:1338] [“Start to validate checksum”]
[2023/03/20 11:51:39.589 +08:00] [INFO] [batcher.go:362] [“sending batch lastly on close”] [size=1]
[2023/03/20 11:51:39.589 +08:00] [INFO] [batcher.go:325] [“restore batch start”] [ranges=“{total=1,ranges="[\"[74800000000000003C5F720000000000000000, 74800000000000003C5F72FFFFFFFFFFFFFFFF00)\"]",totalFiles=1,totalKVs=1,totalBytes=50,totalSize=50}”] [tables=“[__TiDB_BR_Temporary_mysql.analyze_options]”]
[2023/03/20 11:51:39.589 +08:00] [INFO] [pipeline_items.go:226] [“restore batch: waiting ranges”] [range=0]
[2023/03/20 11:51:39.589 +08:00] [INFO] [split.go:94] [“get split keys for region”] [len=1] [region=17]
[2023/03/20 11:51:39.589 +08:00] [INFO] [split.go:97] [“split regions”] [region=“{ID=17,startKey=7480000000000001FF2B5F72FFFFFFFFFFFFFFFFFF0000000000FB,endKey=748000FFFFFFFFFFFFFB00000000000000F8,epoch="conf_ver:3 version:151 ",peers="id:18 store_id:1 ,id:30 store_id:4 "}”] [keys=“{total=1,keys="[7480000000000001345F72FFFFFFFFFFFFFFFF00]"}”] [ranges=“{total=1,ranges="[\"[74800000000000003C5F720000000000000000, 74800000000000003C5F72FFFFFFFFFFFFFFFF00)\"]",totalFiles=1,totalKVs=1,totalBytes=50,totalSize=50}”]
[2023/03/20 11:51:40.003 +08:00] [INFO] [split.go:125] [“scattered regions”] [count=1]
[2023/03/20 11:51:40.003 +08:00] [INFO] [split.go:139] [“start to wait for scattering regions”] [regions=1] [take=414.088087ms]
[2023/03/20 11:51:40.003 +08:00] [INFO] [split.go:151] [“waiting for scattering regions done”] [regions=1] [take=246.195µs]
[2023/03/20 11:51:40.003 +08:00] [INFO] [pipeline_items.go:278] [“TiKV Sender: split worker exits.”]
[2023/03/20 11:51:40.003 +08:00] [INFO] [pipeline_items.go:362] [“TiKV Sender: restore worker prepare to close.”]
[2023/03/20 11:51:40.063 +08:00] [WARN] [domain.go:499] [“loading schema takes a long time”] [“take time”=1.467315607s]
[2023/03/20 11:51:40.643 +08:00] [INFO] [client.go:1167] [“import files done”] [files=“{total=1,files="[4/121_35_71b22c6fc0ddb6fa82024b05ca6ee474cd54c133dadcd57e9fcaf2208f6d8c95_1679277851588_write.sst]",totalKVs=1,totalBytes=50,totalSize=1617}”] [take=533.196482ms]
[2023/03/20 11:51:40.643 +08:00] [INFO] [client.go:1140] [“Restore files”] [take=639.858719ms] [files=“{total=1,files="[4/121_35_71b22c6fc0ddb6fa82024b05ca6ee474cd54c133dadcd57e9fcaf2208f6d8c95_1679277851588_write.sst]",totalKVs=1,totalBytes=50,totalSize=1617}”]
[2023/03/20 11:51:40.643 +08:00] [INFO] [pipeline_items.go:388] [“restore batch done”] [ranges=“{total=1,ranges="[\"[74800000000000003C5F720000000000000000, 74800000000000003C5F72FFFFFFFFFFFFFFFF00)\"]",totalFiles=1,totalKVs=1,totalBytes=50,totalSize=50}”]
[2023/03/20 11:51:40.643 +08:00] [INFO] [pipeline_items.go:368] [“TiKV Sender: restore worker exits.”]
[2023/03/20 11:51:40.643 +08:00] [INFO] [pipeline_items.go:118] [“restore table done”] [tables=“[__TiDB_BR_Temporary_mysql.analyze_options]”]
[2023/03/20 11:51:40.643 +08:00] [INFO] [restore.go:898] [“doing postwork”] [“table count”=1]
[2023/03/20 11:51:40.693 +08:00] [INFO] [client.go:1387] [“all checksum ended”]
[2023/03/20 11:51:40.693 +08:00] [INFO] [session.go:3739] [“CRUCIAL OPERATION”] [conn=0] [schemaVersion=123] [cur_db=] [sql=“DROP DATABASE IF EXISTS __tidb_br_temporary_mysql”] [user=]
[2023/03/20 11:51:40.877 +08:00] [INFO] [ddl_worker.go:314] [“[ddl] add DDL jobs”] [“batch count”=1] [jobs=“ID:310, Type:drop schema, State:queueing, SchemaState:public, SchemaID:306, TableID:0, RowCount:0, ArgLen:1, start time: 2023-03-20 11:51:40.656 +0800 CST, Err:, ErrCount:0, SnapshotVersion:0; “] [table=true]
[2023/03/20 11:51:40.943 +08:00] [INFO] [ddl.go:1011] [”[ddl] start DDL job”] [job=“ID:310, Type:drop schema, State:queueing, SchemaState:public, SchemaID:306, TableID:0, RowCount:0, ArgLen:1, start time: 2023-03-20 11:51:40.656 +0800 CST, Err:, ErrCount:0, SnapshotVersion:0”] [query=“DROP DATABASE IF EXISTS __tidb_br_temporary_mysql”]
[2023/03/20 11:51:41.586 +08:00] [INFO] [domain.go:194] [“diff load InfoSchema success”] [currentSchemaVersion=123] [neededSchemaVersion=124] [“start time”=279.695µs] [phyTblIDs=“[308]”] [actionTypes=“[4]”]
[2023/03/20 11:51:41.746 +08:00] [INFO] [domain.go:707] [“session manager is nil”]
[2023/03/20 11:51:41.746 +08:00] [INFO] [domain.go:726] [“mdl gets lock, update to owner”] [jobID=310] [version=124]
[2023/03/20 11:51:42.688 +08:00] [INFO] [domain.go:194] [“diff load InfoSchema success”] [currentSchemaVersion=124] [neededSchemaVersion=125] [“start time”=237.496µs] [phyTblIDs=“[]”] [actionTypes=“[]”]
[2023/03/20 11:51:42.846 +08:00] [INFO] [domain.go:707] [“session manager is nil”]
[2023/03/20 11:51:42.846 +08:00] [INFO] [domain.go:726] [“mdl gets lock, update to owner”] [jobID=310] [version=125]
[2023/03/20 11:51:43.785 +08:00] [INFO] [domain.go:194] [“diff load InfoSchema success”] [currentSchemaVersion=125] [neededSchemaVersion=126] [“start time”=249.995µs] [phyTblIDs=“[]”] [actionTypes=“[]”]
[2023/03/20 11:51:43.995 +08:00] [INFO] [domain.go:707] [“session manager is nil”]
[2023/03/20 11:51:43.996 +08:00] [INFO] [domain.go:726] [“mdl gets lock, update to owner”] [jobID=310] [version=126]
[2023/03/20 11:51:44.946 +08:00] [INFO] [ddl.go:1109] [“[ddl] DDL job is finished”] [jobID=310]
[2023/03/20 11:51:44.946 +08:00] [INFO] [callback.go:121] [“performing DDL change, must reload”]
[2023/03/20 11:51:44.947 +08:00] [INFO] [client.go:1258] [“stop automatic switch to import mode”]
[2023/03/20 11:51:46.000 +08:00] [INFO] [coprocessor.go:1109] [“[TIME_COP_WAIT] resp_time:902.374739ms txnStartTS:440214304864600066 region_id:13 store_addr:192.168.68.10:20161 kv_process_ms:0 kv_wait_ms:0 kv_read_ms:1 processed_versions:0 total_versions:253 rocksdb_delete_skipped_count:90 rocksdb_key_skipped_count:630 rocksdb_cache_hit_count:0 rocksdb_read_count:0 rocksdb_read_byte:0”]
[2023/03/20 11:51:46.021 +08:00] [INFO] [pd.go:492] [“resume scheduler”] [schedulers=“[balance-hot-region-scheduler,balance-leader-scheduler,balance-region-scheduler]”]
[2023/03/20 11:51:46.021 +08:00] [INFO] [pd.go:478] [“exit pause scheduler and configs successful”]
[2023/03/20 11:51:46.021 +08:00] [INFO] [pd.go:512] [“resume scheduler successful”] [scheduler=balance-hot-region-scheduler]
[2023/03/20 11:51:46.022 +08:00] [INFO] [pd.go:512] [“resume scheduler successful”] [scheduler=balance-leader-scheduler]
[2023/03/20 11:51:46.022 +08:00] [INFO] [pd.go:512] [“resume scheduler successful”] [scheduler=balance-region-scheduler]
[2023/03/20 11:51:46.022 +08:00] [INFO] [pd.go:611] [“restoring config”] [config=“{"enable-location-replacement":"true","leader-schedule-limit":4,"max-merge-region-keys":200000,"max-merge-region-size":20,"max-pending-peer-count":64,"max-snapshot-count":64,"region-schedule-limit":2048}”]
[2023/03/20 11:51:47.141 +08:00] [INFO] [client.go:328] [“Restore client closed”]
[2023/03/20 11:51:47.600 +08:00] [INFO] [manager.go:247] [“failed to campaign”] [“owner info”=“[ddl] /tidb/ddl/fg/owner ownerManager 56ce697b-0584-40f1-a761-9e0a4862ea9b”] [error=“context canceled”]
[2023/03/20 11:51:47.600 +08:00] [INFO] [manager.go:228] [“break campaign loop, context is done”] [“owner info”=“[ddl] /tidb/ddl/fg/owner ownerManager 56ce697b-0584-40f1-a761-9e0a4862ea9b”]
[2023/03/20 11:51:47.683 +08:00] [INFO] [manager.go:272] [“revoke session”] [“owner info”=“[ddl] /tidb/ddl/fg/owner ownerManager 56ce697b-0584-40f1-a761-9e0a4862ea9b”] []
[2023/03/20 11:51:47.724 +08:00] [INFO] [ddl_workerpool.go:82] [“[ddl] closing workerPool”]
[2023/03/20 11:51:47.724 +08:00] [INFO] [ddl_workerpool.go:82] [“[ddl] closing workerPool”]
[2023/03/20 11:51:47.724 +08:00] [INFO] [ddl_worker.go:165] [“[ddl] DDL worker closed”] [worker=“worker 1, tp general”] [“take time”=400ns]
[2023/03/20 11:51:47.724 +08:00] [INFO] [ddl_worker.go:165] [“[ddl] DDL worker closed”] [worker=“worker 2, tp add index”] [“take time”=100ns]
[2023/03/20 11:51:47.724 +08:00] [INFO] [delete_range.go:148] [“[ddl] closing delRange”]
[2023/03/20 11:51:47.724 +08:00] [INFO] [session_pool.go:94] [“[ddl] closing sessionPool”]
[2023/03/20 11:51:47.724 +08:00] [INFO] [ddl.go:815] [“[ddl] DDL closed”] [ID=56ce697b-0584-40f1-a761-9e0a4862ea9b] [“take time”=582.970719ms]
[2023/03/20 11:51:47.724 +08:00] [INFO] [ddl.go:639] [“[ddl] stop DDL”] [ID=56ce697b-0584-40f1-a761-9e0a4862ea9b]
[2023/03/20 11:51:47.782 +08:00] [INFO] [domain.go:748] [“loadSchemaInLoop exited.”]
[2023/03/20 11:51:47.782 +08:00] [INFO] [domain.go:565] [“infoSyncerKeeper exited.”]
[2023/03/20 11:51:47.783 +08:00] [INFO] [domain.go:590] [“globalConfigSyncerKeeper exited.”]
[2023/03/20 11:51:47.783 +08:00] [INFO] [manager.go:247] [“failed to campaign”] [“owner info”=“[stats] /tidb/stats/owner ownerManager 56ce697b-0584-40f1-a761-9e0a4862ea9b”] [error=“lost watcher waiting for delete”]
[2023/03/20 11:51:47.782 +08:00] [INFO] [domain.go:2313] [“serverIDKeeper exited.”]
[2023/03/20 11:51:47.783 +08:00] [INFO] [manager.go:247] [“failed to campaign”] [“owner info”=“[stats] /tidb/stats/owner ownerManager 56ce697b-0584-40f1-a761-9e0a4862ea9b”] [error=“lost watcher waiting for delete”]
[2023/03/20 11:51:47.783 +08:00] [INFO] [manager.go:247] [“failed to campaign”] [“owner info”=“[log-backup] /tidb/br-stream/owner ownerManager 6ced7bb9-f459-4115-b926-67f378be3b86”] [error=“lost watcher waiting for delete”]
[2023/03/20 11:51:47.783 +08:00] [INFO] [advancer.go:268] [“[log backup advancer] Meet task event”] [event=“Err(, err = EOF)”]
[2023/03/20 11:51:47.783 +08:00] [ERROR] [advancer.go:271] [“listen task meet error, would reopen.”] [error=EOF] [stack=“github.com/pingcap/tidb/br/pkg/streamhelper.(*CheckpointAdvancer).StartTaskListener.func1\n\t/home/jenkins/agent/workspace/build-common/go/src/github.com/pingcap/br/br/pkg/streamhelper/advancer.go:271”]
[2023/03/20 11:51:47.783 +08:00] [INFO] [advancer.go:274] [“[log backup advancer] Task watcher exits due to some error.”] [error=EOF]
[2023/03/20 11:51:47.783 +08:00] [INFO] [domain.go:536] [“topNSlowQueryLoop exited.”]
[2023/03/20 11:51:47.783 +08:00] [INFO] [manager.go:247] [“failed to campaign”] [“owner info”=“[log-backup] /tidb/br-stream/owner ownerManager 6ced7bb9-f459-4115-b926-67f378be3b86”] [error=“context canceled”]
[2023/03/20 11:51:47.783 +08:00] [INFO] [manager.go:219] [“etcd session is done, creates a new one”] [“owner info”=“[log-backup] /tidb/br-stream/owner ownerManager 6ced7bb9-f459-4115-b926-67f378be3b86”]
[2023/03/20 11:51:47.783 +08:00] [INFO] [domain.go:1108] [“closestReplicaReadCheckLoop exited.”]
[2023/03/20 11:51:47.783 +08:00] [WARN] [domain.go:2399] [“fail to wait until the ttl job manager stop”] [error=“context canceled”]
[2023/03/20 11:51:47.783 +08:00] [INFO] [owner_daemon.go:85] [“daemon loop exits”] [id=6ced7bb9-f459-4115-b926-67f378be3b86] [daemon-id=LogBackup::Advancer]
[2023/03/20 11:51:47.783 +08:00] [INFO] [manager.go:247] [“failed to campaign”] [“owner info”=“[stats] /tidb/stats/owner ownerManager 56ce697b-0584-40f1-a761-9e0a4862ea9b”] [error=“rpc error: code = Unavailable desc = error reading from server: read tcp 192.168.68.10:45874->192.168.68.10:2381: use of closed network connection”]
[2023/03/20 11:51:47.783 +08:00] [INFO] [domain.go:876] [“domain closed”] [“take time”=641.407089ms]
[2023/03/20 11:51:47.783 +08:00] [INFO] [manager.go:223] [“break campaign loop, NewSession failed”] [“owner info”=“[log-backup] /tidb/br-stream/owner ownerManager 6ced7bb9-f459-4115-b926-67f378be3b86”] [error=“context canceled”] [errorVerbose=“context canceled\ngithub.com/pingcap/errors.AddStack\n\t/go/pkg/mod/github.com/pingcap/errors@v0.11.5-0.20220729040631-518f63d66278/errors.go:174\ngithub.com/pingcap/errors.Trace\n\t/go/pkg/mod/github.com/pingcap/errors@v0.11.5-0.20220729040631-518f63d66278/juju_adaptor.go:15\ngithub.com/pingcap/tidb/util.contextDone\n\t/home/jenkins/agent/workspace/build-common/go/src/github.com/pingcap/br/util/etcd.go:90\ngithub.com/pingcap/tidb/util.NewSession\n\t/home/jenkins/agent/workspace/build-common/go/src/github.com/pingcap/br/util/etcd.go:50\ngithub.com/pingcap/tidb/owner.(*ownerManager).campaignLoop\n\t/home/jenkins/agent/workspace/build-common/go/src/github.com/pingcap/br/owner/manager.go:221\nruntime.goexit\n\t/usr/local/go/src/runtime/asm_amd64.s:1594”]
[2023/03/20 11:51:47.783 +08:00] [INFO] [manager.go:219] [“etcd session is done, creates a new one”] [“owner info”=“[stats] /tidb/stats/owner ownerManager 56ce697b-0584-40f1-a761-9e0a4862ea9b”]
[2023/03/20 11:51:47.783 +08:00] [INFO] [manager.go:247] [“failed to campaign”] [“owner info”=“[stats] /tidb/stats/owner ownerManager 56ce697b-0584-40f1-a761-9e0a4862ea9b”] [error=“rpc error: code = Unavailable desc = error reading from server: read tcp 192.168.68.10:50090->192.168.68.10:2379: use of closed network connection”]
[2023/03/20 11:51:47.783 +08:00] [INFO] [manager.go:219] [“etcd session is done, creates a new one”] [“owner info”=“[stats] /tidb/stats/owner ownerManager 56ce697b-0584-40f1-a761-9e0a4862ea9b”]
[2023/03/20 11:51:47.783 +08:00] [INFO] [client.go:716] [“[pd] exit tso dispatcher”] [dc-location=global]
[2023/03/20 11:51:47.783 +08:00] [INFO] [client.go:779] [“[pd] stop fetching the pending tso requests due to context canceled”] [dc-location=global]
[2023/03/20 11:51:47.783 +08:00] [INFO] [client.go:716] [“[pd] exit tso dispatcher”] [dc-location=global]
[2023/03/20 11:51:47.783 +08:00] [INFO] [collector.go:74] [“Table Restore success summary”] [total-ranges=2] [ranges-succeed=2] [ranges-failed=0] [split-region=89.398µs] [restore-ranges=1] [total-take=14.972495481s] [BackupTS=440212612043505668] [RestoreTS=440214301954801674] [total-kv=1] [total-kv-size=50B] [average-speed=3.339B/s] [restore-data-size(after-compressed)=1.617kB] [Size=1617]

h5n1 · 2023 年3 月 20 日 04:10

https://docs.pingcap.com/zh/tidb/stable/br-snapshot-guide#恢复-mysql-数据库下的表

system · 2023 年5 月 19 日 04:10

此话题已在最后回复的 60 天后被自动关闭。不再允许新回复。