Avoid writing 'read/write on closed pipe' error in logs on expected connection closure
General
Escalation
General
Escalation
Description
Hi,
From time to time the following error appears in PBM logs, it doesn't affect data restore however might confuse users that smth went wrong during the process.
2021-08-09T16:12:13Z I [rs1/localhost:27019] [pitrestore/2021-08-09T14:09:15Z] starting oplog replay
2021-08-09T16:12:13Z I [rs1/localhost:27019] [pitrestore/2021-08-09T14:09:15Z] oplog replay finished on \{1628518006 7}
2021-08-09T16:12:13Z I [rs1/localhost:27019] [pitrestore/2021-08-09T14:09:15Z] replay chunks
2021-08-09T16:12:14Z I [rs1/localhost:27019] [pitrestore/2021-08-09T14:09:15Z] oplog replay finished on \{1628518153 26} <1628518155>
2021-08-09T16:12:14Z W [rs1/localhost:27019] [pitrestore/2021-08-09T14:09:15Z] errReadObj Err: io: read/write on closed pipe
2021-08-09T16:12:14Z W [rs1/localhost:27019] [pitrestore/2021-08-09T14:09:15Z] reader closed pipe, stopping download
2021-08-09T16:12:14Z I [rs1/localhost:27019] [pitrestore/2021-08-09T14:09:15Z] recovery successfully finished
2021-08-10T09:30:48Z I [rs1/localhost:27018] [pitrestore/2021-08-09T14:09:15Z] Node in not suitable for restore
2021-08-10T09:30:48Z I [rs1/localhost:27020] [pitrestore/2021-08-09T14:09:15Z] Node in not suitable for restore
2021-08-10T09:30:48Z I [rs1/localhost:27019] [pitrestore/2021-08-09T14:09:15Z] recovery started
2021-08-10T09:30:49Z I [rs1/localhost:27019] [pitrestore/2021-08-09T14:09:15Z] snapshot 2021-08-09T14:06:25Z
2021-08-10T09:30:53Z I [rs1/localhost:27019] [pitrestore/2021-08-09T14:09:15Z] mongorestore finished
2021-08-10T09:30:56Z I [rs1/localhost:27019] [pitrestore/2021-08-09T14:09:15Z] restoring users and roles
2021-08-10T09:30:56Z I [rs1/localhost:27019] [pitrestore/2021-08-09T14:09:15Z] starting oplog replay
2021-08-10T09:30:56Z I [rs1/localhost:27019] [pitrestore/2021-08-09T14:09:15Z] oplog replay finished on \{1628518006 7}
2021-08-10T09:30:56Z I [rs1/localhost:27019] [pitrestore/2021-08-09T14:09:15Z] replay chunks
2021-08-10T09:30:57Z I [rs1/localhost:27019] [pitrestore/2021-08-09T14:09:15Z] oplog replay finished on \{1628518153 26} <1628518155>
*2021-08-10T09:30:57Z W [rs1/localhost:27019] [pitrestore/2021-08-09T14:09:15Z] errReadObj Err: io: read/write on closed pipe*
2021-08-10T09:30:57Z W [rs1/localhost:27019] [pitrestore/2021-08-09T14:09:15Z] reader closed pipe, stopping download
2021-08-10T09:30:57Z I [rs1/localhost:27019] [pitrestore/2021-08-09T14:09:15Z] recovery successfully finished
Hi,
From time to time the following error appears in PBM logs, it doesn't affect data restore however might confuse users that smth went wrong during the process.
2021-08-09T16:12:13Z I [rs1/localhost:27019] [pitrestore/2021-08-09T14:09:15Z] starting oplog replay 2021-08-09T16:12:13Z I [rs1/localhost:27019] [pitrestore/2021-08-09T14:09:15Z] oplog replay finished on \{1628518006 7} 2021-08-09T16:12:13Z I [rs1/localhost:27019] [pitrestore/2021-08-09T14:09:15Z] replay chunks 2021-08-09T16:12:14Z I [rs1/localhost:27019] [pitrestore/2021-08-09T14:09:15Z] oplog replay finished on \{1628518153 26} <1628518155> 2021-08-09T16:12:14Z W [rs1/localhost:27019] [pitrestore/2021-08-09T14:09:15Z] errReadObj Err: io: read/write on closed pipe 2021-08-09T16:12:14Z W [rs1/localhost:27019] [pitrestore/2021-08-09T14:09:15Z] reader closed pipe, stopping download 2021-08-09T16:12:14Z I [rs1/localhost:27019] [pitrestore/2021-08-09T14:09:15Z] recovery successfully finished 2021-08-10T09:30:48Z I [rs1/localhost:27018] [pitrestore/2021-08-09T14:09:15Z] Node in not suitable for restore 2021-08-10T09:30:48Z I [rs1/localhost:27020] [pitrestore/2021-08-09T14:09:15Z] Node in not suitable for restore 2021-08-10T09:30:48Z I [rs1/localhost:27019] [pitrestore/2021-08-09T14:09:15Z] recovery started 2021-08-10T09:30:49Z I [rs1/localhost:27019] [pitrestore/2021-08-09T14:09:15Z] snapshot 2021-08-09T14:06:25Z 2021-08-10T09:30:53Z I [rs1/localhost:27019] [pitrestore/2021-08-09T14:09:15Z] mongorestore finished 2021-08-10T09:30:56Z I [rs1/localhost:27019] [pitrestore/2021-08-09T14:09:15Z] restoring users and roles 2021-08-10T09:30:56Z I [rs1/localhost:27019] [pitrestore/2021-08-09T14:09:15Z] starting oplog replay 2021-08-10T09:30:56Z I [rs1/localhost:27019] [pitrestore/2021-08-09T14:09:15Z] oplog replay finished on \{1628518006 7} 2021-08-10T09:30:56Z I [rs1/localhost:27019] [pitrestore/2021-08-09T14:09:15Z] replay chunks 2021-08-10T09:30:57Z I [rs1/localhost:27019] [pitrestore/2021-08-09T14:09:15Z] oplog replay finished on \{1628518153 26} <1628518155> *2021-08-10T09:30:57Z W [rs1/localhost:27019] [pitrestore/2021-08-09T14:09:15Z] errReadObj Err: io: read/write on closed pipe* 2021-08-10T09:30:57Z W [rs1/localhost:27019] [pitrestore/2021-08-09T14:09:15Z] reader closed pipe, stopping download 2021-08-10T09:30:57Z I [rs1/localhost:27019] [pitrestore/2021-08-09T14:09:15Z] recovery successfully finished