Hi,
I’m using cbbackupmgr on couchabse 5.1 for backup/restore of a cluster of couchbase (3 nodes).
The backup is done, I was trying to restore my data to the same cluster (after purging data/indexes) but the restore is failing .Checking the server indexer log, Im noticing a strange behaviour:
the cluster is trying to restore some gsi indexed multiple times which is causing “index already exists” error.
I have attached the indexer log and we can check for example the server is trying to restore the primary index and the index ss_index twice which is causing the fail.
Any help please, this is an urgent production issue.
[Info] RestoreContext: Processing index in backup image (bucket_2, #primary, 0).
[Info] RestoreContext: Processing index in backup image (bucket_2, date_index, 0).
[Info] RestoreContext: Processing index in backup image (bucket_2, ss_index, 0).
[Info] RestoreContext: Processing index in backup image (bucket_2, rule_index, 0).
[Info] RestoreContext: Processing index in backup image (bucket_2, c_date_index, 0).
[Info] RestoreContext: Processing index in backup image (bucket_2, #primary, 0).
[Info] RestoreContext: Processing index in backup image (bucket_2, context_index, 0).
[Info] RestoreContext: Processing index in backup image (bucket_2, audit_index, 0).
[Info] RestoreContext: Processing index in backup image (bucket_2, rule_index, 0).
[Info] RestoreContext: Processing index in backup image (bucket_2, ss_index, 0).
[Info] RestoreContext: Processing index in backup image (bucket_2, date_index, 0).
[Info] RestoreContext: Processing index in backup image (bucket_2, archive_index, 0).
[Info] RestoreContext: Processing index in backup image (bucket_2, search_index, 0).
[Info] RestoreContext: Processing index in backup image (bucket_2, archive_index, 0).
[Info] RestoreContext: Processing index in backup image (bucket_2, c_date_index, 0).
[Info] RestoreContext: Processing index in backup image (bucket_2, context_index, 0).
[Info] RestoreContext: Processing index in backup image (bucket_2, search_index, 0).
[Info] RestoreContext: Processing index in backup image (bucket_2, audit_index, 0).
[Info] ClustMgr:handleStats &{92 <nil> false}
[Info] RestoreContext: Index (bucket_2, #primary, 0) does not exist in current cluster. Make it a restore candidate.
[Info] RestoreContext: Index (bucket_2, date_index, 0) does not exist in current cluster. Make it a restore candidate.
[Info] RestoreContext: Index (bucket_2, ss_index, 0) does not exist in current cluster. Make it a restore candidate.
[Info] RestoreContext: Index (bucket_2, rule_index, 0) does not exist in current cluster. Make it a restore candidate.
[Info] RestoreContext: Index (bucket_2, c_date_index, 0) does not exist in current cluster. Make it a restore candidate.
[Info] RestoreContext: Index (bucket_2, #primary, 0) does not exist in current cluster. Make it a restore candidate.
[Info] RestoreContext: Index (bucket_2, context_index, 0) does not exist in current cluster. Make it a restore candidate.
[Info] RestoreContext: Index (bucket_2, audit_index, 0) does not exist in current cluster. Make it a restore candidate.
[Info] RestoreContext: Index (bucket_2, rule_index, 0) does not exist in current cluster. Make it a restore candidate.
[Info] RestoreContext: Index (bucket_2, ss_index, 0) does not exist in current cluster. Make it a restore candidate.
[Info] RestoreContext: Index (bucket_2, date_index, 0) does not exist in current cluster. Make it a restore candidate.
[Info] RestoreContext: Index (bucket_2, archive_index, 0) does not exist in current cluster. Make it a restore candidate.
[Info] RestoreContext: Index (bucket_2, search_index, 0) does not exist in current cluster. Make it a restore candidate.
[Info] RestoreContext: Index (bucket_2, archive_index, 0) does not exist in current cluster. Make it a restore candidate.
[Info] RestoreContext: Index (bucket_2, c_date_index, 0) does not exist in current cluster. Make it a restore candidate.
[Info] RestoreContext: Index (bucket_2, context_index, 0) does not exist in current cluster. Make it a restore candidate.
[Info] RestoreContext: Index (bucket_2, search_index, 0) does not exist in current cluster. Make it a restore candidate.
[Info] RestoreContext: Index (bucket_2, audit_index, 0) does not exist in current cluster. Make it a restore candidate.
[Info] RestoreContext: Index Layout before planning
[Info] RestoreContext: Indexer NodeId node1:8091 IndexerId 080900281ad4da7Xaa5841c813666f5ea
[Info] RestoreContext: Indexer NodeId node2:8091 IndexerId 7b1e39c9f6a40577Xa58bdb0467e40e54
[Info] RestoreContext: Indexer NodeId node3:8091 IndexerId 70cabc3210ea4828Xc25c73da4a24d8e2
[Info] RestoreContext: Indexer NodeId 4abaa0604e5c671f4c71f15c434c8bd8 IndexerId 4abaa0604e5c671f4c71f15c434c8bd8
[Info] RestoreContext: Index (bucket_2, #primary, 0)
[Info] RestoreContext: Index (bucket_2, date_index, 0)
[Info] RestoreContext: Index (bucket_2, ss_index, 0)
[Info] RestoreContext: Index (bucket_2, rule_index, 0)
[Info] RestoreContext: Indexer NodeId c2c5093159fc59X56293b27f207e9f44e IndexerId c2c5093159fc59X56293b27f207e9f44e
[Info] RestoreContext: Index (bucket_2, c_date_index, 0)
[Info] RestoreContext: Index (bucket_2, #primary, 0)
[Info] RestoreContext: Index (bucket_2, context_index, 0)
[Info] RestoreContext: Index (bucket_2, audit_index, 0)
[Info] RestoreContext: Index (bucket_2, rule_index, 0)
[Info] RestoreContext: Index (bucket_2, ss_index, 0)
[Info] RestoreContext: Index (bucket_2, date_index, 0)
[Info] RestoreContext: Index (bucket_2, archive_index, 0)
[Info] RestoreContext: Index (bucket_2, search_index, 0)
[Info] RestoreContext: Indexer NodeId e8d85c10940cd08c917da66ff480c976 IndexerId e8d85c10940cd08c917da66ff480c976
[Info] RestoreContext: Index (bucket_2, archive_index, 0)
[Info] RestoreContext: Index (bucket_2, c_date_index, 0)
[Info] RestoreContext: Index (bucket_2, context_index, 0)
[Info] RestoreContext: Index (bucket_2, search_index, 0)
[Info] RestoreContext: Index (bucket_2, audit_index, 0)
[Info] ************ Indexer Layout *************
[Info] ----------------------------------------
[Info] Memory Quota: 7516192768 (7G)
[Info] CPU Quota: 32
[Info] ----------------------------------------
[Info] Indexer Memory Mean 0 (0)
[Info] Indexer Memory Deviation 0 (0) (0.00%)
[Info] Indexer Memory Utilization 0.0000
[Info] Indexer CPU Mean 0.0000
[Info] Indexer CPU Deviation 0.00 (0.00%)
[Info] Indexer CPU Utilization 0.0000
[Info] Total Index Data (in original layout) 0
[Info] Index Data Moved (after planning) 0 (0.00%)
[Info] No. Index (in original layout) 0
[Info] No. Index Moved (after planning) 0 (0.00%)
[Info] ----------------------------------------
[Info]
[Info] Indexer serverGroup:Group 1, nodeId:node1:8091, useLiveData:true
[Info] Indexer total memory:0 (0), data:0 (0), overhead:0 (0), cpu:0.0000, number of indexes:5 isDeleted:false isNew:true
[Info] ------------------------------------------------------------------------------------------------------------------
[Info] Index name:archive_index, bucket:bucket_2, defnId:2069453184060899133, instId:1909686809119982976, new/moved:true defer:false ignoreEquivCheck:false
[Info] Index total memory:0 (0), data:0 (0), overhead:0 (0), cpu:0.0000
[Info] ------------------------------------------------------------------------------------------------------------------
[Info] Index name:c_date_index, bucket:bucket_2, defnId:11291573438480902931, instId:8193215093999208328, new/moved:true defer:false ignoreEquivCheck:false
[Info] Index total memory:0 (0), data:0 (0), overhead:0 (0), cpu:0.0000
[Info] ------------------------------------------------------------------------------------------------------------------
[Info] Index name:context_index, bucket:bucket_2, defnId:12866538974849553918, instId:12128833875134126828, new/moved:true defer:false ignoreEquivCheck:false
[Info] Index total memory:0 (0), data:0 (0), overhead:0 (0), cpu:0.0000
[Info] ------------------------------------------------------------------------------------------------------------------
[Info] Index name:search_index, bucket:bucket_2, defnId:13505671716855971152, instId:4419719151511314113, new/moved:true defer:false ignoreEquivCheck:false
[Info] Index total memory:0 (0), data:0 (0), overhead:0 (0), cpu:0.0000
[Info] ------------------------------------------------------------------------------------------------------------------
[Info] Index name:audit_index, bucket:bucket_2, defnId:15830718730262099701, instId:14543496332732822243, new/moved:true defer:false ignoreEquivCheck:false
[Info] Index total memory:0 (0), data:0 (0), overhead:0 (0), cpu:0.0000
[Info]
[Info] Indexer serverGroup:Group 1, nodeId:node2:8091, useLiveData:true
[Info] Indexer total memory:0 (0), data:0 (0), overhead:0 (0), cpu:0.0000, number of indexes:9 isDeleted:false isNew:true
[Info] ------------------------------------------------------------------------------------------------------------------
[Info] Index name:c_date_index, bucket:bucket_2, defnId:11291573438480902931, instId:7538599140524132818, new/moved:true defer:false ignoreEquivCheck:false
[Info] Index total memory:0 (0), data:0 (0), overhead:0 (0), cpu:0.0000
[Info] ------------------------------------------------------------------------------------------------------------------
[Info] Index name:#primary, bucket:bucket_2, defnId:15727696393653815423, instId:7658393146625706509, new/moved:true defer:false ignoreEquivCheck:false
[Info] Index total memory:0 (0), data:0 (0), overhead:0 (0), cpu:0.0000
[Info] ------------------------------------------------------------------------------------------------------------------
[Info] Index name:context_index, bucket:bucket_2, defnId:12866538974849553918, instId:9131996837407901675, new/moved:true defer:false ignoreEquivCheck:false
[Info] Index total memory:0 (0), data:0 (0), overhead:0 (0), cpu:0.0000
[Info] ------------------------------------------------------------------------------------------------------------------
[Info] Index name:audit_index, bucket:bucket_2, defnId:15830718730262099701, instId:16897468386531290402, new/moved:true defer:false ignoreEquivCheck:false
[Info] Index total memory:0 (0), data:0 (0), overhead:0 (0), cpu:0.0000
[Info] ------------------------------------------------------------------------------------------------------------------
[Info] Index name:rule_index, bucket:bucket_2, defnId:7617837682162938941, instId:6041135326713349948, new/moved:true defer:false ignoreEquivCheck:false
[Info] Index total memory:0 (0), data:0 (0), overhead:0 (0), cpu:0.0000
[Info] ------------------------------------------------------------------------------------------------------------------
[Info] Index name:ss_index, bucket:bucket_2, defnId:13286887612614279168, instId:12426977418396683569, new/moved:true defer:false ignoreEquivCheck:false
[Info] Index total memory:0 (0), data:0 (0), overhead:0 (0), cpu:0.0000
[Info] ------------------------------------------------------------------------------------------------------------------
[Info] Index name:date_index, bucket:bucket_2, defnId:16655560177260919351, instId:13678697307690311920, new/moved:true defer:false ignoreEquivCheck:false
[Info] Index total memory:0 (0), data:0 (0), overhead:0 (0), cpu:0.0000
[Info] ------------------------------------------------------------------------------------------------------------------
[Info] Index name:archive_index, bucket:bucket_2, defnId:2069453184060899133, instId:1102663903838181114, new/moved:true defer:false ignoreEquivCheck:false
[Info] Index total memory:0 (0), data:0 (0), overhead:0 (0), cpu:0.0000
[Info] ------------------------------------------------------------------------------------------------------------------
[Info] Index name:search_index, bucket:bucket_2, defnId:13505671716855971152, instId:6113395698417029322, new/moved:true defer:false ignoreEquivCheck:false
[Info] Index total memory:0 (0), data:0 (0), overhead:0 (0), cpu:0.0000
[Info]
[Info] Indexer serverGroup:Group 1, nodeId:node3:8091, useLiveData:true
[Info] Indexer total memory:0 (0), data:0 (0), overhead:0 (0), cpu:0.0000, number of indexes:4 isDeleted:false isNew:true
[Info] ------------------------------------------------------------------------------------------------------------------
[Info] Index name:#primary, bucket:bucket_2, defnId:15727696393653815423, instId:4096245016537395240, new/moved:true defer:false ignoreEquivCheck:false
[Info] Index total memory:0 (0), data:0 (0), overhead:0 (0), cpu:0.0000
[Info] ------------------------------------------------------------------------------------------------------------------
[Info] Index name:date_index, bucket:bucket_2, defnId:16655560177260919351, instId:15679718370850184967, new/moved:true defer:false ignoreEquivCheck:false
[Info] Index total memory:0 (0), data:0 (0), overhead:0 (0), cpu:0.0000
[Info] ------------------------------------------------------------------------------------------------------------------
[Info] Index name:ss_index, bucket:bucket_2, defnId:13286887612614279168, instId:266880605015013666, new/moved:true defer:false ignoreEquivCheck:false
[Info] Index total memory:0 (0), data:0 (0), overhead:0 (0), cpu:0.0000
[Info] ------------------------------------------------------------------------------------------------------------------
[Info] Index name:rule_index, bucket:bucket_2, defnId:7617837682162938941, instId:8872793381245656402, new/moved:true defer:false ignoreEquivCheck:false
[Info] Index total memory:0 (0), data:0 (0), overhead:0 (0), cpu:0.0000
[Info] ****************************************
[Info] RestoreContext: Restoring index (bucket_2, #primary, 0) at indexer node2:8091
[Info] RestoreContext: Restoring index (bucket_2, date_index, 0) at indexer node2:8091
[Info] RestoreContext: Restoring index (bucket_2, ss_index, 0) at indexer node2:8091
[Info] RestoreContext: Restoring index (bucket_2, rule_index, 0) at indexer node2:8091
[Info] RestoreContext: Restoring index (bucket_2, c_date_index, 0) at indexer node1:8091
[Info] RestoreContext: Restoring index (bucket_2, #primary, 0) at indexer node2:8091
[Info] RestoreContext: Restoring index (bucket_2, context_index, 0) at indexer node1:8091
[Info] RestoreContext: Restoring index (bucket_2, audit_index, 0) at indexer node1:8091
[Info] RestoreContext: Restoring index (bucket_2, rule_index, 0) at indexer node2:8091
[Info] RestoreContext: Restoring index (bucket_2, ss_index, 0) at indexer node2:8091
[Info] RestoreContext: Restoring index (bucket_2, date_index, 0) at indexer node2:8091
[Info] RestoreContext: Restoring index (bucket_2, archive_index, 0) at indexer node1:8091
[Info] RestoreContext: Restoring index (bucket_2, search_index, 0) at indexer node1:8091
[Info] RestoreContext: Restoring index (bucket_2, archive_index, 0) at indexer node1:8091
[Info] RestoreContext: Restoring index (bucket_2, c_date_index, 0) at indexer node1:8091
[Info] RestoreContext: Restoring index (bucket_2, context_index, 0) at indexer node1:8091
[Info] RestoreContext: Restoring index (bucket_2, search_index, 0) at indexer node1:8091
[Info] RestoreContext: Restoring index (bucket_2, audit_index, 0) at indexer node1:8091
[Info] Refreshing indexer list due to cluster changes or auto-refresh.
[Info] Refreshed Indexer List: [node1:9100 node2:9100 node3:9100]
[Info] switched currmeta from 3 -> 5 force true
[Info] Refreshing indexer list due to cluster changes or auto-refresh.
[Info] Refreshed Indexer List: [node1:9100 node2:9100 node3:9100]
[Info] switched currmeta from 5 -> 6 force true
[Info] Refreshing indexer list due to cluster changes or auto-refresh.
[Info] Refreshed Indexer List: [node1:9100 node2:9100 node3:9100]
[Info] switched currmeta from 6 -> 8 force true
[Info] Refreshing indexer list due to cluster changes or auto-refresh.
[Info] Refreshed Indexer List: [node1:9100 node2:9100 node3:9100]
[Info] switched currmeta from 8 -> 11 force true
[Info] Refreshing indexer list due to cluster changes or auto-refresh.
[Info] Refreshed Indexer List: [node1:9100 node2:9100 node3:9100]
[Info] switched currmeta from 11 -> 11 force true
[Info] Refreshing indexer list due to cluster changes or auto-refresh.
[Info] Refreshed Indexer List: [node1:9100 node2:9100 node3:9100]
[Info] switched currmeta from 11 -> 12 force true
[Info] Refreshing indexer list due to cluster changes or auto-refresh.
[Info] Refreshed Indexer List: [node1:9100 node2:9100 node3:9100]
[Info] switched currmeta from 12 -> 14 force true
[Error] requestHandler.makeCreateIndexRequest(): create index request fails. Error=Index bucket_2.#primary already exists
iple response.WriteHeader calls
[Error] requestHandler.makeCreateIndexRequest(): create index request fails. Error=Index bucket_2.rule_index already exists
[Error] requestHandler.makeCreateIndexRequest(): create index request fails. Error=Index bucket_2.ss_index already exists