Skip to content
PBM-FULL

PBM-1389 disallow performing selective backup #175

Sign in to view logs

PBM-1389 disallow performing selective backup

PBM-1389 disallow performing selective backup #175

Triggered via pull request September 19, 2024 13:09
@olexandr-havryliakolexandr-havryliak
synchronize #226
PBM_unsupported_features
Status Failure
Total duration 1h 50m 27s
Artifacts –

PBM-FULL.yml

on: pull_request
Matrix: test
Fit to window
Zoom out
Zoom in

Annotations

13 errors
test_PBM-979.test_external_PBM_T240: pbm-functional/pytest/test_PBM-979.py#L181
AssertionError: Cannot start restore, another operation running: {'type': 'backup', 'opID': '66ec2372687dbda9936a5031', 'name': '2024-09-19T13:13:22Z', 'startTS': 1726751602, 'status': 'copyDone'} 2024-09-19T13:13:00Z I [rs1/rs103:27017] pbm-agent: Version: 2.6.0 Platform: linux/amd64 GitCommit: d556a6dc87585a4af72174e2a57970ea9e6ab5cb GitBranch: dev BuildTime: 2024-09-19_13:12_UTC GoVersion: go1.23.1 2024-09-19T13:13:00Z I [rs1/rs103:27017] starting PITR routine 2024-09-19T13:13:00Z I [rs1/rs102:27017] pbm-agent: Version: 2.6.0 Platform: linux/amd64 GitCommit: d556a6dc87585a4af72174e2a57970ea9e6ab5cb GitBranch: dev BuildTime: 2024-09-19_13:12_UTC GoVersion: go1.23.1 2024-09-19T13:13:00Z I [rs1/rs102:27017] starting PITR routine 2024-09-19T13:13:00Z I [rs1/rs103:27017] node: rs1/rs103:27017 2024-09-19T13:13:00Z I [rs1/rs101:27017] pbm-agent: Version: 2.6.0 Platform: linux/amd64 GitCommit: d556a6dc87585a4af72174e2a57970ea9e6ab5cb GitBranch: dev BuildTime: 2024-09-19_13:12_UTC GoVersion: go1.23.1 2024-09-19T13:13:00Z I [rs1/rs101:27017] starting PITR routine 2024-09-19T13:13:00Z I [rs1/rs102:27017] node: rs1/rs102:27017 2024-09-19T13:13:00Z I [rs1/rs103:27017] conn level ReadConcern: majority; WriteConcern: majority 2024-09-19T13:13:00Z I [rs1/rs101:27017] node: rs1/rs101:27017 2024-09-19T13:13:00Z I [rs1/rs103:27017] listening for the commands 2024-09-19T13:13:00Z I [rs1/rs102:27017] conn level ReadConcern: majority; WriteConcern: majority 2024-09-19T13:13:00Z I [rs1/rs101:27017] conn level ReadConcern: majority; WriteConcern: majority 2024-09-19T13:13:00Z I [rs1/rs102:27017] listening for the commands 2024-09-19T13:13:00Z I [rs1/rs101:27017] listening for the commands 2024-09-19T13:13:02Z I [rs1/rs104:27017] pbm-agent: Version: 2.6.0 Platform: linux/amd64 GitCommit: d556a6dc87585a4af72174e2a57970ea9e6ab5cb GitBranch: dev BuildTime: 2024-09-19_13:12_UTC GoVersion: go1.23.1 2024-09-19T13:13:02Z I [rs1/rs104:27017] node: rs1/rs104:27017 2024-09-19T13:13:02Z I [rs1/rs104:27017] conn level ReadConcern: majority; WriteConcern: majority 2024-09-19T13:13:02Z I [rs1/rs104:27017] listening for the commands 2024-09-19T13:13:05Z W [rs1/rs103:27017] [agentCheckup] get current storage status: query mongo: mongo: no documents in result 2024-09-19T13:13:05Z W [rs1/rs102:27017] [agentCheckup] get current storage status: query mongo: mongo: no documents in result 2024-09-19T13:13:05Z E [rs1/rs103:27017] [agentCheckup] check storage connection: unable to get storage: get config: get: mongo: no documents in result 2024-09-19T13:13:05Z E [rs1/rs102:27017] [agentCheckup] check storage connection: unable to get storage: get config: get: mongo: no documents in result 2024-09-19T13:13:05Z W [rs1/rs101:27017] [agentCheckup] get current storage status: query mongo: mongo: no documents in result 2024-09-19T13:13:05Z E [rs1/rs101:27017] [agentCheckup] check storage connection: unable to get storage: get config: get: mongo: no documents in result 2024-09-19T13:13:07Z W [rs1/rs104:27017] [agentCheckup] get current storage status: query mongo: mongo: no documents in result 2024-09-19T13:13:07Z E [rs1/rs104:27017] [agentCheckup] check storage connection: unable to get storage: get config: get: mongo: no documents in result 2024-09-19T13:13:10Z E [rs1/rs103:27017] [agentCheckup] check storage connection: unable to get storage: get config: get: mongo: no documents in result 2024-09-19T13:13:10Z E [rs1/rs102:27017] [agentCheckup] check storage connection: unable to get storage: get config: get: mongo: no documents in result 2024-09-19T13:13:10Z E [rs1/rs101:27017] [agentCheckup] check storage connection: unable to get storage: get config: get: mongo: no documents in result 2024-09-19T13:13:12Z W [rs1/rs104:27017] [agentCheckup] storage is not initialized 2024-09-19T13:13:12Z I [rs1/rs104:27017] got command resync <ts: 1726751591> 2024-09-19T13:13:12Z I [rs1/rs104:27017] got epoch {1726751590 6} 2024-09-19T13:13:12Z I [rs1/rs104:27017] [resync] started 2024-09-19T13:13:12Z D [rs1/rs104:27017] [resync] uploading ".pbm.init" [size hint: 5 (5.00B); part size: 10485760 (10.00MB)] 2024-09-19T13:13:12Z D [rs1/rs104:27017] [resync] got backups list: 0 2024-09-19T13:13:12Z D [rs1/rs104:27017] [resync] got physical restores list: 0 2024-09-19T13:13:12Z D [rs1/rs104:27017] [resync] epoch set to {1726751592 10} 2024-09-19T13:13:12Z I [rs1/rs104:27017] [resync] succeed 2024-09-19T13:13:12Z I [rs1/rs103:27017] got command resync <ts: 1726751591> 2024-09-19T13:13:12Z I [rs1/rs102:27017] got command resync <ts: 1726751591> 2024-09-19T13:13:12Z I [rs1/rs101:27017] got command resync <ts: 1726751591> 2024-09-19T13:13:12Z I [rs1/rs103:27017] got epoch {1726751592 10} 2024-09-19T13:13:12Z I [rs1/rs102:27017] got epoch {1726751592 10} 2024-09-19T13:13:12Z I [rs1/rs101:27017] got epoch {1726751592 10} 2024-09-19T13:13:12Z D [rs1/rs101:27017] [resync] lock not acquired 2024-09-19T13:13:12Z D [rs1/rs102:27017] [resync] lock not acquired 2024-09-19T13:13:12Z D [rs1/rs103:27017] [resync] get lock: duplicate operation: 66ec236782f7d809ca024cc6 [Resync storage] 2024-09-19T13:13:12Z D [rs1/rs103:27017] [resync] lock not acquired 2024-09-19T13:13:22Z I [rs1/rs103:27017] got command backup [name: 2024-09-19T13:13:22Z, compression: s2 (level: default)] <ts: 1726751602> 2024-09-19T13:13:22Z I [rs1/rs102:27017] got command backup [name: 2024-09-19T13:13:22Z, compression: s2 (level: default)] <ts: 1726751602> 2024-09-19T13:13:22Z I [rs1/rs103:27017] got epoch {1726751592 10} 2024-09-19T13:13:22Z I [rs1/rs101:27017] got command backup [name: 2024-09-19T13:13:22Z, compression: s2 (level: default)] <ts: 1726751602> 2024-09-19T13:13:22Z I [rs1/rs102:27017] got epoch {1726751592 10} 2024-09-19T13:13:22Z I [rs1/rs101:27017] got epoch {1726751592 10} 2024-09-19T13:13:22Z D [rs1/rs101:27017] [backup/2024-09-19T13:13:22Z] init backup meta 2024-09-19T13:13:22Z D [rs1/rs101:27017] [backup/2024-09-19T13:13:22Z] nomination list for rs1: [[rs103:27017] [rs102:27017] [rs101:27017]] 2024-09-19T13:13:22Z D [rs1/rs101:27017] [backup/2024-09-19T13:13:22Z] nomination rs1, set candidates [rs103:27017] 2024-09-19T13:13:22Z I [rs1/rs103:27017] [backup/2024-09-19T13:13:22Z] backup started 2024-09-19T13:13:22Z D [rs1/rs101:27017] [backup/2024-09-19T13:13:22Z] skip after nomination, probably started by another node 2024-09-19T13:13:23Z I [rs1/rs104:27017] got command backup [name: 2024-09-19T13:13:22Z, compression: s2 (level: default)] <ts: 1726751602> 2024-09-19T13:13:23Z I [rs1/rs104:27017] got epoch {1726751592 10} 2024-09-19T13:13:23Z D [rs1/rs102:27017] [backup/2024-09-19T13:13:22Z] skip after nomination, probably started by another node 2024-09-19T13:13:23Z D [rs1/rs104:27017] [backup/2024-09-19T13:13:22Z] skip after nomination, probably started by another node 2024-09-19T13:13:23Z D [rs1/rs103:27017] [backup/2024-09-19T13:13:22Z] backup cursor id: de4f11d4-840b-44fb-9cce-13cce6ad75c3 2024-09-19T13:13:26Z D [rs1/rs103:27017] [backup/2024-09-19T13:13:22Z] set journal up to {1726751603 5} 2024-09-19T13:13:27Z D [rs1/rs101:27017] [backup/2024-09-19T13:13:22Z] bcp nomination: rs1 won by rs103:27017
test_vault.test_external_PBM_T239: pbm-functional/pytest/test_vault.py#L74
AssertionError: Cannot start restore, another operation running: {'type': 'backup', 'opID': '66ec27e444069529e3dbeaae', 'name': '2024-09-19T13:32:20Z', 'startTS': 1726752740, 'status': 'copyDone'} 2024-09-19T13:32:08Z I [rs1/rs102:27017] pbm-agent: Version: 2.6.0 Platform: linux/amd64 GitCommit: d556a6dc87585a4af72174e2a57970ea9e6ab5cb GitBranch: dev BuildTime: 2024-09-19_13:12_UTC GoVersion: go1.23.1 2024-09-19T13:32:08Z I [rs1/rs102:27017] starting PITR routine 2024-09-19T13:32:08Z I [rs1/rs101:27017] pbm-agent: Version: 2.6.0 Platform: linux/amd64 GitCommit: d556a6dc87585a4af72174e2a57970ea9e6ab5cb GitBranch: dev BuildTime: 2024-09-19_13:12_UTC GoVersion: go1.23.1 2024-09-19T13:32:08Z I [rs1/rs101:27017] starting PITR routine 2024-09-19T13:32:08Z I [rs1/rs102:27017] node: rs1/rs102:27017 2024-09-19T13:32:08Z I [rs1/rs103:27017] pbm-agent: Version: 2.6.0 Platform: linux/amd64 GitCommit: d556a6dc87585a4af72174e2a57970ea9e6ab5cb GitBranch: dev BuildTime: 2024-09-19_13:12_UTC GoVersion: go1.23.1 2024-09-19T13:32:08Z I [rs1/rs103:27017] starting PITR routine 2024-09-19T13:32:08Z I [rs1/rs101:27017] node: rs1/rs101:27017 2024-09-19T13:32:08Z I [rs1/rs102:27017] conn level ReadConcern: majority; WriteConcern: majority 2024-09-19T13:32:08Z I [rs1/rs102:27017] listening for the commands 2024-09-19T13:32:08Z I [rs1/rs101:27017] conn level ReadConcern: majority; WriteConcern: majority 2024-09-19T13:32:08Z I [rs1/rs103:27017] node: rs1/rs103:27017 2024-09-19T13:32:08Z I [rs1/rs103:27017] conn level ReadConcern: majority; WriteConcern: majority 2024-09-19T13:32:08Z I [rs1/rs101:27017] listening for the commands 2024-09-19T13:32:08Z I [rs1/rs103:27017] listening for the commands 2024-09-19T13:32:13Z W [rs1/rs101:27017] [agentCheckup] get current storage status: query mongo: mongo: no documents in result 2024-09-19T13:32:13Z E [rs1/rs101:27017] [agentCheckup] check storage connection: unable to get storage: get config: get: mongo: no documents in result 2024-09-19T13:32:13Z W [rs1/rs102:27017] [agentCheckup] get current storage status: query mongo: mongo: no documents in result 2024-09-19T13:32:13Z E [rs1/rs102:27017] [agentCheckup] check storage connection: unable to get storage: get config: get: mongo: no documents in result 2024-09-19T13:32:13Z W [rs1/rs103:27017] [agentCheckup] get current storage status: query mongo: mongo: no documents in result 2024-09-19T13:32:13Z E [rs1/rs103:27017] [agentCheckup] check storage connection: unable to get storage: get config: get: mongo: no documents in result 2024-09-19T13:32:15Z I [rs1/rs102:27017] got command resync <ts: 1726752734> 2024-09-19T13:32:15Z I [rs1/rs101:27017] got command resync <ts: 1726752734> 2024-09-19T13:32:15Z I [rs1/rs102:27017] got epoch {1726752733 11} 2024-09-19T13:32:15Z I [rs1/rs101:27017] got epoch {1726752733 11} 2024-09-19T13:32:15Z I [rs1/rs103:27017] got command resync <ts: 1726752734> 2024-09-19T13:32:15Z I [rs1/rs103:27017] got epoch {1726752733 11} 2024-09-19T13:32:15Z I [rs1/rs102:27017] [resync] started 2024-09-19T13:32:15Z D [rs1/rs101:27017] [resync] lock not acquired 2024-09-19T13:32:15Z D [rs1/rs103:27017] [resync] lock not acquired 2024-09-19T13:32:15Z D [rs1/rs102:27017] [resync] uploading ".pbm.init" [size hint: 5 (5.00B); part size: 10485760 (10.00MB)] 2024-09-19T13:32:15Z D [rs1/rs102:27017] [resync] got backups list: 0 2024-09-19T13:32:15Z D [rs1/rs102:27017] [resync] got physical restores list: 2 2024-09-19T13:32:15Z D [rs1/rs102:27017] [resync] epoch set to {1726752735 16} 2024-09-19T13:32:15Z I [rs1/rs102:27017] [resync] succeed 2024-09-19T13:32:20Z I [rs1/rs101:27017] got command backup [name: 2024-09-19T13:32:20Z, compression: s2 (level: default)] <ts: 1726752740> 2024-09-19T13:32:20Z I [rs1/rs102:27017] got command backup [name: 2024-09-19T13:32:20Z, compression: s2 (level: default)] <ts: 1726752740> 2024-09-19T13:32:20Z I [rs1/rs101:27017] got epoch {1726752735 16} 2024-09-19T13:32:20Z I [rs1/rs102:27017] got epoch {1726752735 16} 2024-09-19T13:32:20Z I [rs1/rs103:27017] got command backup [name: 2024-09-19T13:32:20Z, compression: s2 (level: default)] <ts: 1726752740> 2024-09-19T13:32:20Z I [rs1/rs103:27017] got epoch {1726752735 16} 2024-09-19T13:32:20Z D [rs1/rs101:27017] [backup/2024-09-19T13:32:20Z] init backup meta 2024-09-19T13:32:20Z D [rs1/rs101:27017] [backup/2024-09-19T13:32:20Z] nomination list for rs1: [[rs102:27017 rs103:27017] [rs101:27017]] 2024-09-19T13:32:20Z D [rs1/rs101:27017] [backup/2024-09-19T13:32:20Z] nomination rs1, set candidates [rs102:27017 rs103:27017] 2024-09-19T13:32:21Z I [rs1/rs102:27017] [backup/2024-09-19T13:32:20Z] backup started 2024-09-19T13:32:21Z D [rs1/rs103:27017] [backup/2024-09-19T13:32:20Z] skip: lock not acquired 2024-09-19T13:32:21Z D [rs1/rs101:27017] [backup/2024-09-19T13:32:20Z] skip after nomination, probably started by another node 2024-09-19T13:32:22Z D [rs1/rs102:27017] [backup/2024-09-19T13:32:20Z] backup cursor id: 35edf6ca-27ff-406d-a5e0-c1eb502a819e 2024-09-19T13:32:25Z D [rs1/rs102:27017] [backup/2024-09-19T13:32:20Z] set journal up to {1726752741 6} 2024-09-19T13:32:25Z D [rs1/rs101:27017] [backup/2024-09-19T13:32:20Z] bcp nomination: rs1 won by rs102:27017
test (8.0, external)
Process completed with exit code 1.
test_PBM-979.test_external_PBM_T240: pbm-functional/pytest/test_PBM-979.py#L181
AssertionError: Cannot start restore, another operation running: {'type': 'backup', 'opID': '66ec234de6f6c2c6d4c4fe4f', 'name': '2024-09-19T13:12:45Z', 'startTS': 1726751565, 'status': 'copyDone'} 2024-09-19T13:12:23Z I [rs1/rs102:27017] pbm-agent: Version: 2.6.0 Platform: linux/amd64 GitCommit: d556a6dc87585a4af72174e2a57970ea9e6ab5cb GitBranch: dev BuildTime: 2024-09-19_13:11_UTC GoVersion: go1.23.1 2024-09-19T13:12:23Z I [rs1/rs101:27017] pbm-agent: Version: 2.6.0 Platform: linux/amd64 GitCommit: d556a6dc87585a4af72174e2a57970ea9e6ab5cb GitBranch: dev BuildTime: 2024-09-19_13:11_UTC GoVersion: go1.23.1 2024-09-19T13:12:23Z I [rs1/rs103:27017] starting PITR routine 2024-09-19T13:12:23Z I [rs1/rs101:27017] starting PITR routine 2024-09-19T13:12:23Z I [rs1/rs102:27017] starting PITR routine 2024-09-19T13:12:23Z I [rs1/rs103:27017] pbm-agent: Version: 2.6.0 Platform: linux/amd64 GitCommit: d556a6dc87585a4af72174e2a57970ea9e6ab5cb GitBranch: dev BuildTime: 2024-09-19_13:11_UTC GoVersion: go1.23.1 2024-09-19T13:12:23Z I [rs1/rs101:27017] node: rs1/rs101:27017 2024-09-19T13:12:23Z I [rs1/rs102:27017] node: rs1/rs102:27017 2024-09-19T13:12:23Z I [rs1/rs103:27017] node: rs1/rs103:27017 2024-09-19T13:12:23Z I [rs1/rs101:27017] conn level ReadConcern: majority; WriteConcern: majority 2024-09-19T13:12:23Z I [rs1/rs102:27017] conn level ReadConcern: majority; WriteConcern: majority 2024-09-19T13:12:23Z I [rs1/rs103:27017] conn level ReadConcern: majority; WriteConcern: majority 2024-09-19T13:12:23Z I [rs1/rs101:27017] listening for the commands 2024-09-19T13:12:23Z I [rs1/rs102:27017] listening for the commands 2024-09-19T13:12:23Z I [rs1/rs103:27017] listening for the commands 2024-09-19T13:12:25Z I [rs1/rs104:27017] pbm-agent: Version: 2.6.0 Platform: linux/amd64 GitCommit: d556a6dc87585a4af72174e2a57970ea9e6ab5cb GitBranch: dev BuildTime: 2024-09-19_13:11_UTC GoVersion: go1.23.1 2024-09-19T13:12:25Z I [rs1/rs104:27017] node: rs1/rs104:27017 2024-09-19T13:12:25Z I [rs1/rs104:27017] conn level ReadConcern: majority; WriteConcern: majority 2024-09-19T13:12:25Z I [rs1/rs104:27017] listening for the commands 2024-09-19T13:12:28Z W [rs1/rs101:27017] [agentCheckup] get current storage status: query mongo: mongo: no documents in result 2024-09-19T13:12:28Z W [rs1/rs102:27017] [agentCheckup] get current storage status: query mongo: mongo: no documents in result 2024-09-19T13:12:28Z E [rs1/rs101:27017] [agentCheckup] check storage connection: unable to get storage: get config: get: mongo: no documents in result 2024-09-19T13:12:28Z W [rs1/rs103:27017] [agentCheckup] get current storage status: query mongo: mongo: no documents in result 2024-09-19T13:12:28Z E [rs1/rs102:27017] [agentCheckup] check storage connection: unable to get storage: get config: get: mongo: no documents in result 2024-09-19T13:12:28Z E [rs1/rs103:27017] [agentCheckup] check storage connection: unable to get storage: get config: get: mongo: no documents in result 2024-09-19T13:12:30Z W [rs1/rs104:27017] [agentCheckup] get current storage status: query mongo: mongo: no documents in result 2024-09-19T13:12:30Z E [rs1/rs104:27017] [agentCheckup] check storage connection: unable to get storage: get config: get: mongo: no documents in result 2024-09-19T13:12:33Z E [rs1/rs101:27017] [agentCheckup] check storage connection: unable to get storage: get config: get: mongo: no documents in result 2024-09-19T13:12:33Z E [rs1/rs102:27017] [agentCheckup] check storage connection: unable to get storage: get config: get: mongo: no documents in result 2024-09-19T13:12:33Z E [rs1/rs103:27017] [agentCheckup] check storage connection: unable to get storage: get config: get: mongo: no documents in result 2024-09-19T13:12:35Z W [rs1/rs104:27017] [agentCheckup] storage is not initialized 2024-09-19T13:12:35Z I [rs1/rs104:27017] got command resync <ts: 1726751555> 2024-09-19T13:12:35Z I [rs1/rs104:27017] got epoch {1726751553 6} 2024-09-19T13:12:35Z I [rs1/rs104:27017] [resync] started 2024-09-19T13:12:35Z D [rs1/rs104:27017] [resync] uploading ".pbm.init" [size hint: 5 (5.00B); part size: 10485760 (10.00MB)] 2024-09-19T13:12:35Z D [rs1/rs104:27017] [resync] got backups list: 0 2024-09-19T13:12:35Z D [rs1/rs104:27017] [resync] got physical restores list: 0 2024-09-19T13:12:35Z D [rs1/rs104:27017] [resync] epoch set to {1726751555 13} 2024-09-19T13:12:35Z I [rs1/rs104:27017] [resync] succeed 2024-09-19T13:12:35Z I [rs1/rs102:27017] got command resync <ts: 1726751555> 2024-09-19T13:12:35Z I [rs1/rs103:27017] got command resync <ts: 1726751555> 2024-09-19T13:12:35Z I [rs1/rs101:27017] got command resync <ts: 1726751555> 2024-09-19T13:12:35Z I [rs1/rs102:27017] got epoch {1726751555 13} 2024-09-19T13:12:35Z I [rs1/rs103:27017] got epoch {1726751555 13} 2024-09-19T13:12:35Z I [rs1/rs101:27017] got epoch {1726751555 13} 2024-09-19T13:12:35Z D [rs1/rs103:27017] [resync] lock not acquired 2024-09-19T13:12:35Z D [rs1/rs101:27017] [resync] lock not acquired 2024-09-19T13:12:35Z D [rs1/rs102:27017] [resync] get lock: duplicate operation: 66ec23430b8f5d604199c770 [Resync storage] 2024-09-19T13:12:35Z D [rs1/rs102:27017] [resync] lock not acquired 2024-09-19T13:12:45Z I [rs1/rs104:27017] got command backup [name: 2024-09-19T13:12:45Z, compression: s2 (level: default)] <ts: 1726751565> 2024-09-19T13:12:45Z I [rs1/rs104:27017] got epoch {1726751555 13} 2024-09-19T13:12:45Z I [rs1/rs102:27017] got command backup [name: 2024-09-19T13:12:45Z, compression: s2 (level: default)] <ts: 1726751565> 2024-09-19T13:12:45Z I [rs1/rs101:27017] got command backup [name: 2024-09-19T13:12:45Z, compression: s2 (level: default)] <ts: 1726751565> 2024-09-19T13:12:45Z I [rs1/rs103:27017] got command backup [name: 2024-09-19T13:12:45Z, compression: s2 (level: default)] <ts: 1726751565> 2024-09-19T13:12:45Z I [rs1/rs102:27017] got epoch {1726751555 13} 2024-09-19T13:12:45Z I [rs1/rs101:27017] got epoch {1726751555 13} 2024-09-19T13:12:45Z I [rs1/rs103:27017] got epoch {1726751555 13} 2024-09-19T13:12:45Z D [rs1/rs101:27017] [backup/2024-09-19T13:12:45Z] init backup meta 2024-09-19T13:12:45Z D [rs1/rs101:27017] [backup/2024-09-19T13:12:45Z] nomination list for rs1: [[rs103:27017] [rs102:27017] [rs101:27017]] 2024-09-19T13:12:45Z D [rs1/rs101:27017] [backup/2024-09-19T13:12:45Z] nomination rs1, set candidates [rs103:27017] 2024-09-19T13:12:46Z I [rs1/rs103:27017] [backup/2024-09-19T13:12:45Z] backup started 2024-09-19T13:12:46Z D [rs1/rs101:27017] [backup/2024-09-19T13:12:45Z] skip after nomination, probably started by another node 2024-09-19T13:12:46Z D [rs1/rs104:27017] [backup/2024-09-19T13:12:45Z] skip after nomination, probably started by another node 2024-09-19T13:12:46Z D [rs1/rs102:27017] [backup/2024-09-19T13:12:45Z] skip after nomination, probably started by another node 2024-09-19T13:12:47Z D [rs1/rs103:27017] [backup/2024-09-19T13:12:45Z] backup cursor id: a0873c52-9904-417b-ab86-e8bc67e4d319 2024-09-19T13:12:50Z D [rs1/rs103:27017] [backup/2024-09-19T13:12:45Z] set journal up to {1726751567 1} 2024-09-19T13:12:50Z D [rs1/rs101:27017] [backup/2024-09-19T13:12:45Z] bcp nomination: rs1 won by rs103:27017
test (6.0, external)
Process completed with exit code 1.
test_PBM-1114.test_logical_PBM_T266: pbm-functional/pytest/test_PBM-1114.py#L56
AssertionError: Starting backup '2024-09-19T13:20:33Z'.... Waiting for '2024-09-19T13:20:33Z' backup.......................... done Backup '2024-09-19T13:20:33Z' to remote store 'http://nginx-minio:21114/bcp/pbme2etest' assert 0 != 0 + where 0 = CommandResult(backend=<testinfra.backend.docker.DockerBackend object at 0x7fe8163f0d90>, exit_status=0, command=b'pbm ........... done\nBackup '2024-09-19T13:20:33Z' to remote store 'http://nginx-minio:21114/bcp/pbme2etest'\n", _stderr=b'').rc
test (7.0, logical)
Process completed with exit code 1.
test_PBM-1114.test_logical_PBM_T266: pbm-functional/pytest/test_PBM-1114.py#L56
AssertionError: Starting backup '2024-09-19T13:20:31Z'.... Waiting for '2024-09-19T13:20:31Z' backup................ done Backup '2024-09-19T13:20:31Z' to remote store 'http://nginx-minio:21114/bcp/pbme2etest' assert 0 != 0 + where 0 = CommandResult(backend=<testinfra.backend.docker.DockerBackend object at 0x7fcddeb17b90>, exit_status=0, command=b'pbm ........... done\nBackup '2024-09-19T13:20:31Z' to remote store 'http://nginx-minio:21114/bcp/pbme2etest'\n", _stderr=b'').rc
test (5.0, logical)
Process completed with exit code 1.
test_PBM-1114.test_logical_PBM_T266: pbm-functional/pytest/test_PBM-1114.py#L56
AssertionError: Starting backup '2024-09-19T13:20:18Z'.... Waiting for '2024-09-19T13:20:18Z' backup.......................... done Backup '2024-09-19T13:20:18Z' to remote store 'http://nginx-minio:21114/bcp/pbme2etest' assert 0 != 0 + where 0 = CommandResult(backend=<testinfra.backend.docker.DockerBackend object at 0x7f8beec49d90>, exit_status=0, command=b'pbm ........... done\nBackup '2024-09-19T13:20:18Z' to remote store 'http://nginx-minio:21114/bcp/pbme2etest'\n", _stderr=b'').rc
test (6.0, logical)
Process completed with exit code 1.
test_PBM-1114.test_logical_PBM_T266: pbm-functional/pytest/test_PBM-1114.py#L56
AssertionError: Starting backup '2024-09-19T13:21:17Z'.... Waiting for '2024-09-19T13:21:17Z' backup.......................... done Backup '2024-09-19T13:21:17Z' to remote store 'http://nginx-minio:21114/bcp/pbme2etest' assert 0 != 0 + where 0 = CommandResult(backend=<testinfra.backend.docker.DockerBackend object at 0x7ff585117050>, exit_status=0, command=b'pbm ........... done\nBackup '2024-09-19T13:21:17Z' to remote store 'http://nginx-minio:21114/bcp/pbme2etest'\n", _stderr=b'').rc
test (8.0, logical)
Process completed with exit code 1.