improve log messages #170
Annotations
1 error
test_azurite.test_logical_pitr:
psmdb-testing/pbm-functional/pytest/test_azurite.py#L81
AssertionError: Backup failedError: get backup metadata: get: context deadline exceeded
2025-03-27T12:09:48Z I [rs2/rs201:27017]
% _____
%%% | __ \
###%%%%%%%%%%%%* | |__) |__ _ __ ___ ___ _ __ __ _
### ##%% %%%% | ___/ _ \ '__/ __/ _ \| '_ \ / _` |
#### ##% %%%% | | | __/ | | (_| (_) | | | | (_| |
### #### %%% |_| \___|_| \___\___/|_| |_|\__,_|
,((### ### %%% _____ _
(((( (### #### %%%% / ____| | |
((( ((# ###### | (___ __ _ _ _ __ _ __| |
(((( (((# #### \___ \ / _` | | | |/ _` |/ _` |
/(( ,((( *### ____) | (_| | |_| | (_| | (_| |
//// ((( #### |_____/ \__, |\__,_|\__,_|\__,_|
/// (((( #### | |
/////////////(((((((((((((((((######## |_| Join @ squad.percona.com/mongodb
** Join Percona Squad! **
Participate in monthly SWAG raffles, get early access to new product features,
invite-only ”ask me anything” sessions with database performance experts.
Interested? Fill in the form at squad.percona.com/mongodb
2025-03-27T12:09:48Z I [rs2/rs201:27017] log options: log-path=/dev/stderr, log-level:D, log-json:false
2025-03-27T12:09:48Z I [rs1/rs103:27017]
% _____
%%% | __ \
###%%%%%%%%%%%%* | |__) |__ _ __ ___ ___ _ __ __ _
### ##%% %%%% | ___/ _ \ '__/ __/ _ \| '_ \ / _` |
#### ##% %%%% | | | __/ | | (_| (_) | | | | (_| |
### #### %%% |_| \___|_| \___\___/|_| |_|\__,_|
,((### ### %%% _____ _
(((( (### #### %%%% / ____| | |
((( ((# ###### | (___ __ _ _ _ __ _ __| |
(((( (((# #### \___ \ / _` | | | |/ _` |/ _` |
/(( ,((( *### ____) | (_| | |_| | (_| | (_| |
//// ((( #### |_____/ \__, |\__,_|\__,_|\__,_|
/// (((( #### | |
/////////////(((((((((((((((((######## |_| Join @ squad.percona.com/mongodb
** Join Percona Squad! **
Participate in monthly SWAG raffles, get early access to new product features,
invite-only ”ask me anything” sessions with database performance experts.
Interested? Fill in the form at squad.percona.com/mongodb
2025-03-27T12:09:48Z I [rs2/rs201:27017] pbm-agent:
Version: 2.9.0
Platform: linux/amd64
GitCommit: 4d57900c32b75514bdc6e85f0fd42f4814f79364
GitBranch: CURRENT_PR
BuildTime: 2025-03-27_11:07_UTC
GoVersion: go1.22.12
2025-03-27T12:09:48Z I [rs2/rs201:27017] starting PITR routine
2025-03-27T12:09:48Z I [rs1/rs101:27017]
% _____
%%% | __ \
###%%%%%%%%%%%%* | |__) |__ _ __ ___ ___ _ __ __ _
### ##%% %%%% | ___/ _ \ '__/ __/ _ \| '_ \ / _` |
#### ##% %%%% | | | __/ | | (_| (_) | | | | (_| |
### #### %%% |_| \___|_| \___\___/|_| |_|\__,_|
,((### ### %%% _____ _
(((( (### #### %%%% / ____| | |
((( ((# ###### | (___ __ _ _ _ __ _ __| |
(((( (((# #### \___ \ / _` | | | |/ _` |/ _` |
/(( ,((( *### ____) | (_| | |_| | (_| | (_| |
//// ((( #### |_____/ \__, |\__,_|\__,_|\__,_|
/// (((( #### | |
/////////////(((((((((((((((((######## |_| Join @ squad.percona.com/mongodb
** Join Percona Squad! **
Participate in monthly SWAG raffles, get early access to new product features,
invite-only ”ask me anything” sessions with database performance experts.
Interested? Fill in the form at squad.percona.com/mongodb
2025-03-27T12:09:48Z I [rs1/rs102:27017]
% _____
%%% | __ \
###%%%%%%%%%%%%* | |__) |__ _ __ ___ ___ _ __ __ _
### ##%% %%%% | ___/ _ \ '__/ __/ _ \| '_ \ / _` |
#### ##% %%%% | | | __/ | | (_| (_) | | | | (_| |
### #### %%% |_| \___|_| \___\___/|_| |_|\__,_|
,((### ### %%% _____ _
(((( (### #### %%%% / ____| | |
((( ((# ###### | (___ __ _ _ _ __ _ __| |
(((( (((# #### \___ \ / _` | | | |/ _` |/ _` |
/(( ,((( *### ____) | (_| | |_| | (_| | (_| |
//// ((( #### |_____/ \__, |\__,_|\__,_|\__,_|
/// (((( #### | |
/////////////(((((((((((((((((######## |_| Join @ squad.percona.com/mongodb
** Join Percona Squad! **
Participate in monthly SWAG raffles, get early access to new product features,
invite-only ”ask me anything” sessions with database performance experts.
Interested? Fill in the form at squad.percona.com/mongodb
2025-03-27T12:09:48Z I [rs1/rs103:27017] log options: log-path=/dev/stderr, log-level:D, log-json:false
2025-03-27T12:09:48Z I [rs1/rs102:27017] log options: log-path=/dev/stderr, log-level:D, log-json:false
2025-03-27T12:09:48Z I [rs2/rs201:27017] node: rs2/rs201:27017
2025-03-27T12:09:48Z E [rs2/rs201:27017] [agentCheckup] check storage connection: unable to get storage: get config: get: mongo: no documents in result
2025-03-27T12:09:48Z I [rs1/rs101:27017] log options: log-path=/dev/stderr, log-level:D, log-json:false
2025-03-27T12:09:48Z I [rs2/rs201:27017] conn level ReadConcern: majority; WriteConcern: majority
2025-03-27T12:09:48Z I [rs1/rs102:27017] pbm-agent:
Version: 2.9.0
Platform: linux/amd64
GitCommit: 4d57900c32b75514bdc6e85f0fd42f4814f79364
GitBranch: CURRENT_PR
BuildTime: 2025-03-27_11:07_UTC
GoVersion: go1.22.12
2025-03-27T12:09:48Z I [rs1/rs102:27017] starting PITR routine
2025-03-27T12:09:48Z I [rs1/rs103:27017] pbm-agent:
Version: 2.9.0
Platform: linux/amd64
GitCommit: 4d57900c32b75514bdc6e85f0fd42f4814f79364
GitBranch: CURRENT_PR
BuildTime: 2025-03-27_11:07_UTC
GoVersion: go1.22.12
2025-03-27T12:09:48Z I [rs1/rs103:27017] starting PITR routine
2025-03-27T12:09:48Z I [rs1/rs101:27017] pbm-agent:
Version: 2.9.0
Platform: linux/amd64
GitCommit: 4d57900c32b75514bdc6e85f0fd42f4814f79364
GitBranch: CURRENT_PR
BuildTime: 2025-03-27_11:07_UTC
GoVersion: go1.22.12
2025-03-27T12:09:48Z I [rs1/rs101:27017] starting PITR routine
2025-03-27T12:09:48Z I [rs1/rs102:27017] node: rs1/rs102:27017
2025-03-27T12:09:48Z I [rs2/rs201:27017] listening for the commands
2025-03-27T12:09:48Z I [rs1/rs103:27017] node: rs1/rs103:27017
2025-03-27T12:09:48Z I [rs1/rs101:27017] node: rs1/rs101:27017
2025-03-27T12:09:48Z E [rs1/rs102:27017] [agentCheckup] check storage connection: unable to get storage: get config: get: mongo: no documents in result
2025-03-27T12:09:48Z I [rs1/rs102:27017] conn level ReadConcern: majority; WriteConcern: majority
2025-03-27T12:09:48Z E [rs1/rs101:27017] [agentCheckup] check storage connection: unable to get storage: get config: get: mongo: no documents in result
2025-03-27T12:09:48Z E [rs1/rs103:27017] [agentCheckup] check storage connection: unable to get storage: get config: get: mongo: no documents in result
2025-03-27T12:09:48Z I [rs1/rs103:27017] conn level ReadConcern: majority; WriteConcern: majority
2025-03-27T12:09:48Z I [rs1/rs101:27017] conn level ReadConcern: majority; WriteConcern: majority
2025-03-27T12:09:48Z I [rs1/rs102:27017] listening for the commands
2025-03-27T12:09:48Z I [rs1/rs103:27017] listening for the commands
2025-03-27T12:09:48Z I [rs1/rs101:27017] listening for the commands
2025-03-27T12:09:49Z I [rs2/rs202:27017]
% _____
%%% | __ \
###%%%%%%%%%%%%* | |__) |__ _ __ ___ ___ _ __ __ _
### ##%% %%%% | ___/ _ \ '__/ __/ _ \| '_ \ / _` |
#### ##% %%%% | | | __/ | | (_| (_) | | | | (_| |
### #### %%% |_| \___|_| \___\___/|_| |_|\__,_|
,((### ### %%% _____ _
(((( (### #### %%%% / ____| | |
((( ((# ###### | (___ __ _ _ _ __ _ __| |
(((( (((# #### \___ \ / _` | | | |/ _` |/ _` |
/(( ,((( *### ____) | (_| | |_| | (_| | (_| |
//// ((( #### |_____/ \__, |\__,_|\__,_|\__,_|
/// (((( #### | |
/////////////(((((((((((((((((######## |_| Join @ squad.percona.com/mongodb
** Join Percona Squad! **
Participate in monthly SWAG raffles, get early access to new product features,
invite-only ”ask me anything” sessions with database performance experts.
Interested? Fill in the form at squad.percona.com/mongodb
2025-03-27T12:09:49Z I [rs2/rs202:27017] log options: log-path=/dev/stderr, log-level:D, log-json:false
2025-03-27T12:09:49Z I [rs2/rs202:27017] pbm-agent:
Version: 2.9.0
Platform: linux/amd64
GitCommit: 4d57900c32b75514bdc6e85f0fd42f4814f79364
GitBranch: CURRENT_PR
BuildTime: 2025-03-27_11:07_UTC
GoVersion: go1.22.12
2025-03-27T12:09:49Z I [rs2/rs202:27017] starting PITR routine
2025-03-27T12:09:49Z I [rs2/rs202:27017] node: rs2/rs202:27017
2025-03-27T12:09:49Z E [rs2/rs202:27017] [agentCheckup] check storage connection: unable to get storage: get config: get: mongo: no documents in result
2025-03-27T12:09:49Z I [rs2/rs202:27017] conn level ReadConcern: majority; WriteConcern: majority
2025-03-27T12:09:49Z I [rs2/rs202:27017] listening for the commands
2025-03-27T12:09:49Z I [rscfg/rscfg01:27017]
% _____
%%% | __ \
###%%%%%%%%%%%%* | |__) |__ _ __ ___ ___ _ __ __ _
### ##%% %%%% | ___/ _ \ '__/ __/ _ \| '_ \ / _` |
#### ##% %%%% | | | __/ | | (_| (_) | | | | (_| |
### #### %%% |_| \___|_| \___\___/|_| |_|\__,_|
,((### ### %%% _____ _
(((( (### #### %%%% / ____| | |
((( ((# ###### | (___ __ _ _ _ __ _ __| |
(((( (((# #### \___ \ / _` | | | |/ _` |/ _` |
/(( ,((( *### ____) | (_| | |_| | (_| | (_| |
//// ((( #### |_____/ \__, |\__,_|\__,_|\__,_|
/// (((( #### | |
/////////////(((((((((((((((((######## |_| Join @ squad.percona.com/mongodb
** Join Percona Squad! **
Participate in monthly SWAG raffles, get early access to new product features,
invite-only ”ask me anything” sessions with database performance experts.
Interested? Fill in the form at squad.percona.com/mongodb
2025-03-27T12:09:49Z I [rscfg/rscfg01:27017] log options: log-path=/dev/stderr, log-level:D, log-json:false
2025-03-27T12:09:49Z I [rscfg/rscfg02:27017]
% _____
%%% | __ \
###%%%%%%%%%%%%* | |__) |__ _ __ ___ ___ _ __ __ _
### ##%% %%%% | ___/ _ \ '__/ __/ _ \| '_ \ / _` |
#### ##% %%%% | | | __/ | | (_| (_) | | | | (_| |
### #### %%% |_| \___|_| \___\___/|_| |_|\__,_|
,((### ### %%% _____ _
(((( (### #### %%%% / ____| | |
((( ((# ###### | (___ __ _ _ _ __ _ __| |
(((( (((# #### \___ \ / _` | | | |/ _` |/ _` |
/(( ,((( *### ____) | (_| | |_| | (_| | (_| |
//// ((( #### |_____/ \__, |\__,_|\__,_|\__,_|
/// (((( #### | |
/////////////(((((((((((((((((######## |_| Join @ squad.percona.com/mongodb
** Join Percona Squad! **
Participate in monthly SWAG raffles, get early access to new product features,
invite-only ”ask me anything” sessions with database performance experts.
Interested? Fill in the form at squad.percona.com/mongodb
2025-03-27T12:09:49Z I [rscfg/rscfg01:27017] pbm-agent:
Version: 2.9.0
Platform: linux/amd64
GitCommit: 4d57900c32b75514bdc6e85f0fd42f4814f79364
GitBranch: CURRENT_PR
BuildTime: 2025-03-27_11:07_UTC
GoVersion: go1.22.12
2025-03-27T12:09:49Z I [rscfg/rscfg01:27017] starting PITR routine
2025-03-27T12:09:49Z I [rs2/rs203:27017]
% _____
%%% | __ \
###%%%%%%%%%%%%* | |__) |__ _ __ ___ ___ _ __ __ _
### ##%% %%%% | ___/ _ \ '__/ __/ _ \| '_ \ / _` |
#### ##% %%%% | | | __/ | | (_| (_) | | | | (_| |
### #### %%% |_| \___|_| \___\___/|_| |_|\__,_|
,((### ### %%% _____ _
(((( (### #### %%%% / ____| | |
((( ((# ###### | (___ __ _ _ _ __ _ __| |
(((( (((# #### \___ \ / _` | | | |/ _` |/ _` |
/(( ,((( *### ____) | (_| | |_| | (_| | (_| |
//// ((( #### |_____/ \__, |\__,_|\__,_|\__,_|
/// (((( #### | |
/////////////(((((((((((((((((######## |_| Join @ squad.percona.com/mongodb
** Join Percona Squad! **
Participate in monthly SWAG raffles, get early access to new product features,
invite-only ”ask me anything” sessions with database performance experts.
Interested? Fill in the form at squad.percona.com/mongodb
2025-03-27T12:09:49Z I [rscfg/rscfg02:27017] log options: log-path=/dev/stderr, log-level:D, log-json:false
2025-03-27T12:09:49Z E [rscfg/rscfg01:27017] [agentCheckup] check storage connection: unable to get storage: get config: get: mongo: no documents in result
2025-03-27T12:09:49Z I [rs2/rs203:27017] log options: log-path=/dev/stderr, log-level:D, log-json:false
2025-03-27T12:09:49Z I [rscfg/rscfg01:27017] node: rscfg/rscfg01:27017
2025-03-27T12:09:49Z I [rscfg/rscfg02:27017] pbm-agent:
Version: 2.9.0
Platform: linux/amd64
GitCommit: 4d57900c32b75514bdc6e85f0fd42f4814f79364
GitBranch: CURRENT_PR
BuildTime: 2025-03-27_11:07_UTC
GoVersion: go1.22.12
2025-03-27T12:09:49Z I [rscfg/rscfg02:27017] starting PITR routine
2025-03-27T12:09:49Z I [rscfg/rscfg02:27017] node: rscfg/rscfg02:27017
2025-03-27T12:09:49Z I [rs2/rs203:27017] pbm-agent:
Version: 2.9.0
Platform: linux/amd64
GitCommit: 4d57900c32b75514bdc6e85f0fd42f4814f79364
GitBranch: CURRENT_PR
BuildTime: 2025-03-27_11:07_UTC
GoVersion: go1.22.12
2025-03-27T12:09:49Z I [rs2/rs203:27017] starting PITR routine
2025-03-27T12:09:49Z I [rscfg/rscfg01:27017] conn level ReadConcern: majority; WriteConcern: majority
2025-03-27T12:09:49Z I [rs2/rs203:27017] node: rs2/rs203:27017
2025-03-27T12:09:49Z E [rscfg/rscfg02:27017] [agentCheckup] check storage connection: unable to get storage: get config: get: mongo: no documents in result
2025-03-27T12:09:49Z I [rscfg/rscfg01:27017] listening for the commands
2025-03-27T12:09:49Z E [rs2/rs203:27017] [agentCheckup] check storage connection: unable to get storage: get config: get: mongo: no documents in result
2025-03-27T12:09:49Z I [rscfg/rscfg02:27017] conn level ReadConcern: majority; WriteConcern: majority
2025-03-27T12:09:49Z I [rs2/rs203:27017] conn level ReadConcern: majority; WriteConcern: majority
2025-03-27T12:09:49Z I [rscfg/rscfg02:27017] listening for the commands
2025-03-27T12:09:49Z I [rs2/rs203:27017] listening for the commands
2025-03-27T12:09:51Z I [rscfg/rscfg03:27017]
% _____
%%% | __ \
###%%%%%%%%%%%%* | |__) |__ _ __ ___ ___ _ __ __ _
### ##%% %%%% | ___/ _ \ '__/ __/ _ \| '_ \ / _` |
#### ##% %%%% | | | __/ | | (_| (_) | | | | (_| |
### #### %%% |_| \___|_| \___\___/|_| |_|\__,_|
,((### ### %%% _____ _
(((( (### #### %%%% / ____| | |
((( ((# ###### | (___ __ _ _ _ __ _ __| |
(((( (((# #### \___ \ / _` | | | |/ _` |/ _` |
/(( ,((( *### ____) | (_| | |_| | (_| | (_| |
//// ((( #### |_____/ \__, |\__,_|\__,_|\__,_|
/// (((( #### | |
/////////////(((((((((((((((((######## |_| Join @ squad.percona.com/mongodb
** Join Percona Squad! **
Participate in monthly SWAG raffles, get early access to new product features,
invite-only ”ask me anything” sessions with database performance experts.
Interested? Fill in the form at squad.percona.com/mongodb
2025-03-27T12:09:51Z I [rscfg/rscfg03:27017] log options: log-path=/dev/stderr, log-level:D, log-json:false
2025-03-27T12:09:51Z I [rscfg/rscfg03:27017] pbm-agent:
Version: 2.9.0
Platform: linux/amd64
GitCommit: 4d57900c32b75514bdc6e85f0fd42f4814f79364
GitBranch: CURRENT_PR
BuildTime: 2025-03-27_11:07_UTC
GoVersion: go1.22.12
2025-03-27T12:09:51Z I [rscfg/rscfg03:27017] starting PITR routine
2025-03-27T12:09:51Z I [rscfg/rscfg03:27017] node: rscfg/rscfg03:27017
2025-03-27T12:09:51Z E [rscfg/rscfg03:27017] [agentCheckup] check storage connection: unable to get storage: get config: get: mongo: no documents in result
2025-03-27T12:09:51Z I [rscfg/rscfg03:27017] conn level ReadConcern: majority; WriteConcern: majority
2025-03-27T12:09:51Z I [rscfg/rscfg03:27017] listening for the commands
2025-03-27T12:09:53Z E [rs2/rs201:27017] [agentCheckup] check storage connection: unable to get storage: get config: get: mongo: no documents in result
2025-03-27T12:09:53Z E [rs1/rs102:27017] [agentCheckup] check storage connection: unable to get storage: get config: get: mongo: no documents in result
2025-03-27T12:09:53Z E [rs1/rs103:27017] [agentCheckup] check storage connection: unable to get storage: get config: get: mongo: no documents in result
2025-03-27T12:09:53Z E [rs1/rs101:27017] [agentCheckup] check storage connection: unable to get storage: get config: get: mongo: no documents in result
2025-03-27T12:09:54Z E [rs2/rs202:27017] [agentCheckup] check storage connection: unable to get storage: get config: get: mongo: no documents in result
2025-03-27T12:09:54Z E [rscfg/rscfg01:27017] [agentCheckup] check storage connection: unable to get storage: get config: get: mongo: no documents in result
2025-03-27T12:09:54Z E [rscfg/rscfg02:27017] [agentCheckup] check storage connection: unable to get storage: get config: get: mongo: no documents in result
2025-03-27T12:09:54Z E [rs2/rs203:27017] [agentCheckup] check storage connection: unable to get storage: get config: get: mongo: no documents in result
2025-03-27T12:09:56Z E [rscfg/rscfg03:27017] [agentCheckup] check storage connection: unable to get storage: get config: get: mongo: no documents in result
2025-03-27T12:09:57Z I [rs2/rs202:27017] got command resync <ts: 1743077397>, opid: 67e5401580b6b6227a3de03c
2025-03-27T12:09:57Z I [rs2/rs202:27017] got epoch {1743077396 3}
2025-03-27T12:09:57Z I [rs2/rs202:27017] [resync] not a member of the leader rs
2025-03-27T12:09:57Z I [rscfg/rscfg01:27017] got command resync <ts: 1743077397>, opid: 67e5401580b6b6227a3de03c
2025-03-27T12:09:57Z I [rscfg/rscfg01:27017] got epoch {1743077396 3}
2025-03-27T12:09:57Z I [rscfg/rscfg01:27017] [resync] started
2025-03-27T12:09:57Z I [rscfg/rscfg02:27017] got command resync <ts: 1743077397>, opid: 67e5401580b6b6227a3de03c
2025-03-27T12:09:57Z I [rscfg/rscfg02:27017] got epoch {1743077396 3}
2025-03-27T12:09:57Z I [rs2/rs203:27017] got command resync <ts: 1743077397>, opid: 67e5401580b6b6227a3de03c
2025-03-27T12:09:57Z I [rs2/rs203:27017] got epoch {1743077396 3}
2025-03-27T12:09:57Z I [rs2/rs203:27017] [resync] not a member of the leader rs
2025-03-27T12:09:57Z D [rscfg/rscfg02:27017] [resync] lock not acquired
2025-03-27T12:09:57Z D [rscfg/rscfg01:27017] [resync] uploading ".pbm.init" [size hint: 5 (5.00B); part size: 10485760 (10.00MB)]
2025-03-27T12:09:57Z D [rscfg/rscfg01:27017] [resync] got backups list: 0
2025-03-27T12:09:57Z D [rscfg/rscfg01:27017] [resync] got physical restores list: 0
2025-03-27T12:09:57Z D [rscfg/rscfg01:27017] [resync] epoch set to {1743077397 20}
2025-03-27T12:09:57Z I [rscfg/rscfg01:27017] [resync] succeed
2025-03-27T12:09:58Z I [rs2/rs201:27017] got command resync <ts: 1743077397>, opid: 67e5401580b6b6227a3de03c
2025-03-27T12:09:58Z I [rs2/rs201:27017] got epoch {1743077397 20}
2025-03-27T12:09:58Z I [rs2/rs201:27017] [resync] not a member of the leader rs
2025-03-27T12:09:58Z I [rs1/rs102:27017] got command resync <ts: 1743077397>, opid: 67e5401580b6b6227a3de03c
2025-03-27T12:09:58Z I [rs1/rs102:27017] got epoch {1743077397 20}
2025-03-27T12:09:58Z I [rs1/rs103:27017] got command resync <ts: 1743077397>, opid: 67e5401580b6b6227a3de03c
2025-03-27T12:09:58Z I [rs1/rs101:27017] got command resync <ts: 1743077397>, opid: 67e5401580b6b6227a3de03c
2025-03-27T12:09:58Z I [rs1/rs103:27017] got epoch {1743077397 20}
2025-03-27T12:09:58Z I [rs1/rs101:27017] got epoch {1743077397 20}
2025-03-27T12:09:58Z I [rs1/rs102:27017] [resync] not a member of the leader rs
2025-03-27T12:09:58Z I [rs1/rs103:27017] [resync] not a member of the leader rs
2025-03-27T12:09:58Z I [rs1/rs101:27017] [resync] not a member of the leader rs
2025-03-27T12:09:58Z I [rscfg/rscfg03:27017] got command resync <ts: 1743077397>, opid: 67e5401580b6b6227a3de03c
2025-03-27T12:09:58Z I [rscfg/rscfg03:27017] got epoch {1743077397 20}
2025-03-27T12:09:58Z D [rscfg/rscfg03:27017] [resync] get lock: duplicate operation: 67e5401580b6b6227a3de03c [Resync storage]
2025-03-27T12:09:58Z D [rscfg/rscfg03:27017] [resync] lock not acquired
2025-03-27T12:10:03Z I [rs2/rs201:27017] got command resync <ts: 1743077402>, opid: 67e5401ab4df8ce29e7c2921
2025-03-27T12:10:03Z I [rs2/rs201:27017] got epoch {1743077397 20}
2025-03-27T12:10:03Z I [rs2/rs201:27017] [resync] not a member of the leader rs
2025-03-27T12:10:03Z I [rs1/rs102:27017] got command resync <ts: 1743077402>, opid: 67e5401ab4df8ce29e7c2921
2025-03-27T12:10:03Z I [rs1/rs102:27017] got epoch {1743077397 20}
2025-03-27T12:10:03Z I [rs1/rs101:27017] got command resync <ts: 1743077402>, opid: 67e5401ab4df8ce29e7c2921
2025-03-27T12:10:03Z I [rs1/rs103:27017] got command resync <ts: 1743077402>, opid: 67e5401ab4df8ce29e7c2921
2025-03-27T12:10:03Z I [rs1/rs103:27017] got epoch {1743077397 20}
2025-03-27T12:10:03Z I [rs1/rs101:27017] got epoch {1743077397 20}
2025-03-27T12:10:03Z I [rs1/rs102:27017] [resync] not a member of the leader rs
2025-03-27T12:10:03Z I [rs1/rs103:27017] [resync] not a member of the leader rs
2025-03-27T12:10:03Z I [rs1/rs101:27017] [resync] not a member of the leader rs
2025-03-27T12:10:03Z I [rscfg/rscfg03:27017] got command resync <ts: 1743077402>, opid: 67e5401ab4df8ce29e7c2921
2025-03-27T12:10:03Z I [rscfg/rscfg03:27017] got epoch {1743077397 20}
2025-03-27T12:10:03Z I [rscfg/rscfg03:27017] [resync] started
2025-03-27T12:10:03Z D [rscfg/rscfg03:27017] [resync] BufferSize is set to 10485760 (~10Mb) | 5
2025-03-27T12:10:03Z D [rscfg/rscfg03:27017] [resync] got backups list: 0
2025-03-27T12:10:03Z D [rscfg/rscfg03:27017] [resync] got physical restores list: 0
2025-03-27T12:10:03Z D [rscfg/rscfg03:27017] [resync] epoch set to {1743077403 148}
2025-03-27T12:10:03Z I [rscfg/rscfg03:27017] [resync] succeed
2025-03-27T12:10:03Z I [rs2/rs202:27017] got command resync <ts: 1743077402>, opid: 67e5401ab4df8ce29e7c2921
2025-03-27T12:10:03Z I [rs2/rs202:27017] got epoch {1743077403 148}
2025-03-27T12:10:03Z I [rs2/rs202:27017] [resync] not a member of the leader rs
2025-03-27T12:10:03Z I [rscfg/rscfg01:27017] got command resync <ts: 1743077402>, opid: 67e5401ab4df8ce29e7c2921
2025-03-27T12:10:03Z I [rscfg/rscfg01:27017] got epoch {1743077403 148}
2025-03-27T12:10:03Z I [rscfg/rscfg02:27017] got command resync <ts: 1743077402>, opid: 67e5401ab4df8ce29e7c2921
2025-03-27T12:10:03Z D [rscfg/rscfg01:27017] [resync] get lock: duplicate operation: 67e5401ab4df8ce29e7c2921 [Resync storage]
2025-03-27T12:10:03Z I [rscfg/rscfg02:27017] got epoch {1743077403 148}
2025-03-27T12:10:03Z I [rs2/rs203:27017] got command resync <ts: 1743077402>, opid: 67e5401ab4df8ce29e7c2921
2025-03-27T12:10:03Z D [rscfg/rscfg01:27017] [resync] lock not acquired
2025-03-27T12:10:03Z I [rs2/rs203:27017] got epoch {1743077403 148}
2025-03-27T12:10:03Z I [rscfg/rscfg01:27017] got command backup [name: 2025-03-27T12:10:03Z, compression: s2 (level: default)] <ts: 1743077403>, opid: 67e5401b528bb8338046b06e
2025-03-27T12:10:03Z I [rs2/rs203:27017] [resync] not a member of the leader rs
2025-03-27T12:10:03Z I [rscfg/rscfg01:27017] got epoch {1743077403 148}
2025-03-27T12:10:03Z I [rs2/rs203:27017] got command backup [name: 2025-03-27T12:10:03Z, compression: s2 (level: default)] <ts: 1743077403>, opid: 67e5401b528bb8338046b06e
2025-03-27T12:10:03Z I [rs2/rs203:27017] got epoch {1743077403 148}
2025-03-27T12:10:03Z D [rscfg/rscfg02:27017] [resync] get lock: duplicate operation: 67e5401ab4df8ce29e7c2921 [Resync storage]
2025-03-27T12:10:03Z E [rscfg/rscfg01:27017] [backup/2025-03-27T12:10:03Z] unable to proceed with the backup, active lock is present
2025-03-27T12:10:03Z D [rscfg/rscfg02:27017] [resync] lock not acquired
2025-03-27T12:10:03Z I [rscfg/rscfg02:27017] got command backup [name: 2025-03-27T12:10:03Z, compression: s2 (level: default)] <ts: 1743077403>, opid: 67e5401b528bb8338046b06e
2025-03-27T12:10:03Z I [rscfg/rscfg02:27017] got epoch {1743077403 148}
2025-03-27T12:10:04Z I [rs2/rs201:27017] got command backup [name: 2025-03-27T12:10:03Z, compression: s2 (level: default)] <ts: 1743077403>, opid: 67e5401b528bb8338046b06e
2025-03-27T12:10:04Z I [rs2/rs201:27017] got epoch {1743077403 148}
2025-03-27T12:10:04Z I [rs1/rs102:27017] got command backup [name: 2025-03-27T12:10:03Z, compression: s2 (level: default)] <ts: 1743077403>, opid: 67e5401b528bb8338046b06e
2025-03-27T12:10:04Z I [rs1/rs102:27017] got epoch {1743077403 148}
2025-03-27T12:10:04Z I [rs1/rs101:27017] got command backup [name: 2025-03-27T12:10:03Z, compression: s2 (level: default)] <ts: 1743077403>, opid: 67e5401b528bb8338046b06e
2025-03-27T12:10:04Z I [rs1/rs101:27017] got epoch {1743077403 148}
2025-03-27T12:10:04Z I [rs1/rs103:27017] got command backup [name: 2025-03-27T12:10:03Z, compression: s2 (level: default)] <ts: 1743077403>, opid: 67e5401b528bb8338046b06e
2025-03-27T12:10:04Z I [rs1/rs103:27017] got epoch {1743077403 148}
2025-03-27T12:10:04Z I [rscfg/rscfg03:27017] got command backup [name: 2025-03-27T12:10:03Z, compression: s2 (level: default)] <ts: 1743077403>, opid: 67e5401b528bb8338046b06e
2025-03-27T12:10:04Z I [rscfg/rscfg03:27017] got epoch {1743077403 148}
2025-03-27T12:10:04Z I [rs2/rs202:27017] got command backup [name: 2025-03-27T12:10:03Z, compression: s2 (level: default)] <ts: 1743077403>, opid: 67e5401b528bb8338046b06e
2025-03-27T12:10:04Z I [rs2/rs202:27017] got epoch {1743077403 148}
2025-03-27T12:10:18Z D [rs2/rs203:27017] [backup/2025-03-27T12:10:03Z] nomination timeout
2025-03-27T12:10:18Z D [rs2/rs203:27017] [backup/2025-03-27T12:10:03Z] skip after nomination, probably started by another node
2025-03-27T12:10:18Z D [rscfg/rscfg02:27017] [backup/2025-03-27T12:10:03Z] nomination timeout
2025-03-27T12:10:18Z D [rscfg/rscfg02:27017] [backup/2025-03-27T12:10:03Z] skip after nomination, probably started by another node
2025-03-27T12:10:19Z D [rs2/rs201:27017] [backup/2025-03-27T12:10:03Z] nomination timeout
2025-03-27T12:10:19Z D [rs2/rs201:27017] [backup/2025-03-27T12:10:03Z] skip after nomination, probably started by another node
2025-03-27T12:10:19Z D [rs1/rs102:27017] [backup/2025-03-27T12:10:03Z] nomination timeout
2025-03-27T12:10:19Z D [rs1/rs102:27017] [backup/2025-03-27T12:10:03Z] skip after nomination, probably started by another node
2025-03-27T12:10:19Z D [rs1/rs101:27017] [backup/2025-03-27T12:10:03Z] nomination timeout
2025-03-27T12:10:19Z D [rs1/rs101:27017] [backup/2025-03-27T12:10:03Z] skip after nomination, probably started by another node
2025-03-27T12:10:19Z D [rs1/rs103:27017] [backup/2025-03-27T12:10:03Z] nomination timeout
2025-03-27T12:10:19Z D [rs1/rs103:27017] [backup/2025-03-27T12:10:03Z] skip after nomination, probably started by another node
2025-03-27T12:10:19Z D [rscfg/rscfg03:27017] [backup/2025-03-27T12:10:03Z] nomination timeout
2025-03-27T12:10:19Z D [rscfg/rscfg03:27017] [backup/2025-03-27T12:10:03Z] skip after nomination, probably started by another node
2025-03-27T12:10:19Z D [rs2/rs202:27017] [backup/2025-03-27T12:10:03Z] nomination timeout
2025-03-27T12:10:19Z D [rs2/rs202:27017] [backup/2025-03-27T12:10:03Z] skip after nomination, probably started by another node
|