Skip to content

improve log messages #170

improve log messages

improve log messages #170

Triggered via pull request March 27, 2025 11:05
@igroeneigroene
opened #1116
ivan
Status Success
Total duration 46s
Artifacts

codecov.yml

on: pull_request
runner / go-test
38s
runner / go-test
Fit to window
Zoom out
Zoom in

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