Uploaded image for project: 'Percona Backup for MongoDB'
  1. Percona Backup for MongoDB
  2. PBM-640

Remove cancelBackup and fix pitrestore filters for pbm logs command

    XMLWordPrintable

Details

    • Bug
    • Status: Done
    • Medium
    • Resolution: Fixed
    • None
    • 1.6.0
    • None
    • None

    Description

      Hi,

      Pbm logs command has filter by events, however not all events are filtered correctly.

      pbm logs
-e, --event=EVENT Event in format backup[/2020-10-06T11:45:14Z]. Events: backup, restore, cancelBackup, resyncBcpList, pitr, pitrestore, delete

      1. cancelBackup doesn't show any info, since logs rather belong to backup event rather than cancelBackup event, I believe this filter should be either removed, or info should be duplicated for both events as backup, so cancelBackup.

      $ pbm logs -t 0 -e cancelBackup
$ pbm logs -t 0 | grep cancel
2021-02-11T11:17:34Z I [rs1/localhost:27019] got command cancelBackup <ts: 1613042254>
2021-02-11T11:17:34Z I [rs1/localhost:27020] got command cancelBackup <ts: 1613042254>
2021-02-11T11:17:34Z I [rs2/localhost:28018] got command cancelBackup <ts: 1613042254>
2021-02-11T11:17:34Z I [rs1/localhost:27018] got command cancelBackup <ts: 1613042254>
2021-02-11T11:17:34Z I [rs2/localhost:28019] got command cancelBackup <ts: 1613042254>
2021-02-11T11:17:34Z I [config/localhost:27028] got command cancelBackup <ts: 1613042254>
2021-02-11T11:17:34Z I [config/localhost:27029] got command cancelBackup <ts: 1613042254>
2021-02-11T11:17:34Z I [config/localhost:27027] got command cancelBackup <ts: 1613042254>
2021-02-11T11:17:34Z I [rs2/localhost:28020] got command cancelBackup <ts: 1613042254>
2021-02-11T11:17:35Z I [config/localhost:27027] [backup/2021-02-11T11:17:14Z] mark RS as canceled `mongodump: backup canceled`: <nil>
2021-02-11T11:17:35Z I [config/localhost:27027] [backup/2021-02-11T11:17:14Z] mark backup as canceled `mongodump: backup canceled`: <nil>
2021-02-11T11:17:35Z I [config/localhost:27027] [backup/2021-02-11T11:17:14Z] backup was canceled
2021-02-11T11:17:36Z I [rs1/localhost:27020] [backup/2021-02-11T11:17:14Z] mark RS as canceled `waiting for dump done: backup canceled`: <nil>
2021-02-11T11:17:36Z I [rs1/localhost:27020] [backup/2021-02-11T11:17:14Z] backup was canceled
2021-02-11T11:17:36Z I [rs2/localhost:28020] [backup/2021-02-11T11:17:14Z] mark RS as canceled `waiting for dump done: backup canceled`: <nil>
2021-02-11T11:17:36Z I [rs2/localhost:28020] [backup/2021-02-11T11:17:14Z] backup was canceled

      2. pitrestore doesn't show any info since restore runs under 'pitr' label

      pbm logs -t0 -e pitrestore
pbm logs -t0 | egrep "pitrestore|pitr"
...
2021-02-11T11:22:27Z I [config/localhost:27028] got command pitrestore [name: 2021-02-11T11:22:27.22197114Z, point-in-time ts: 1613042478] <ts: 1613042547>
2021-02-11T11:22:27Z I [rs1/localhost:27020] got command pitrestore [name: 2021-02-11T11:22:27.22197114Z, point-in-time ts: 1613042478] <ts: 1613042547>
2021-02-11T11:22:27Z I [rs2/localhost:28020] got command pitrestore [name: 2021-02-11T11:22:27.22197114Z, point-in-time ts: 1613042478] <ts: 1613042547>
2021-02-11T11:22:27Z I [rs1/localhost:27018] got command pitrestore [name: 2021-02-11T11:22:27.22197114Z, point-in-time ts: 1613042478] <ts: 1613042547>
2021-02-11T11:22:27Z I [rs2/localhost:28018] got command pitrestore [name: 2021-02-11T11:22:27.22197114Z, point-in-time ts: 1613042478] <ts: 1613042547>
2021-02-11T11:22:27Z I [config/localhost:27027] got command pitrestore [name: 2021-02-11T11:22:27.22197114Z, point-in-time ts: 1613042478] <ts: 1613042547>
2021-02-11T11:22:27Z I [rs2/localhost:28019] got command pitrestore [name: 2021-02-11T11:22:27.22197114Z, point-in-time ts: 1613042478] <ts: 1613042547>
2021-02-11T11:22:27Z I [rs1/localhost:27019] got command pitrestore [name: 2021-02-11T11:22:27.22197114Z, point-in-time ts: 1613042478] <ts: 1613042547>
2021-02-11T11:22:27Z I [config/localhost:27029] got command pitrestore [name: 2021-02-11T11:22:27.22197114Z, point-in-time ts: 1613042478] <ts: 1613042547>
2021-02-11T11:22:27Z I [rs2/localhost:28020] [pitr/2021-02-11T11:21:18Z] Node in not suitable for restore
2021-02-11T11:22:27Z I [config/localhost:27027] [pitr/2021-02-11T11:21:18Z] Node in not suitable for restore
2021-02-11T11:22:27Z I [rs1/localhost:27020] [pitr/2021-02-11T11:21:18Z] Node in not suitable for restore
2021-02-11T11:22:27Z I [rs1/localhost:27018] [pitr/2021-02-11T11:21:18Z] Node in not suitable for restore
2021-02-11T11:22:27Z I [rs2/localhost:28018] [pitr/2021-02-11T11:21:18Z] Node in not suitable for restore
2021-02-11T11:22:27Z I [config/localhost:27029] [pitr/2021-02-11T11:21:18Z] Node in not suitable for restore
2021-02-11T11:22:27Z I [rs1/localhost:27019] [pitr/2021-02-11T11:21:18Z] recovery started
2021-02-11T11:22:27Z I [config/localhost:27028] [pitr/2021-02-11T11:21:18Z] recovery started
2021-02-11T11:22:27Z I [rs2/localhost:28019] [pitr/2021-02-11T11:21:18Z] recovery started
2021-02-11T11:22:33Z I [rs2/localhost:28019] [pitr/2021-02-11T11:21:18Z] mongorestore finished
2021-02-11T11:22:33Z I [rs1/localhost:27019] [pitr/2021-02-11T11:21:18Z] mongorestore finished
2021-02-11T11:22:38Z I [config/localhost:27028] [pitr/2021-02-11T11:21:18Z] mongorestore finished
2021-02-11T11:22:39Z I [rs1/localhost:27019] [pitr/2021-02-11T11:21:18Z] starting oplog replay
2021-02-11T11:22:39Z I [rs1/localhost:27019] [pitr/2021-02-11T11:21:18Z] oplog replay finished on {1613042374 15}
2021-02-11T11:22:39Z I [rs1/localhost:27019] [pitr/2021-02-11T11:21:18Z] restoring users and roles
2021-02-11T11:22:40Z I [rs2/localhost:28019] [pitr/2021-02-11T11:21:18Z] starting oplog replay
2021-02-11T11:22:40Z I [config/localhost:27028] [pitr/2021-02-11T11:21:18Z] starting oplog replay
2021-02-11T11:22:40Z I [rs1/localhost:27019] [pitr/2021-02-11T11:21:18Z] replay chunks
2021-02-11T11:22:40Z I [rs2/localhost:28019] [pitr/2021-02-11T11:21:18Z] oplog replay finished on {1613042373 3}
2021-02-11T11:22:40Z I [rs2/localhost:28019] [pitr/2021-02-11T11:21:18Z] restoring users and roles
2021-02-11T11:22:40Z I [config/localhost:27028] [pitr/2021-02-11T11:21:18Z] oplog replay finished on {1613042373 7}
2021-02-11T11:22:40Z I [rs2/localhost:28019] [pitr/2021-02-11T11:21:18Z] replay chunks
2021-02-11T11:22:40Z I [config/localhost:27028] [pitr/2021-02-11T11:21:18Z] restoring users and roles
2021-02-11T11:22:40Z I [config/localhost:27028] [pitr/2021-02-11T11:21:18Z] replay chunks
2021-02-11T11:22:41Z I [rs1/localhost:27019] [pitr/2021-02-11T11:21:18Z] oplog replay finished on {1613042476 23} <1613042478>
2021-02-11T11:22:41Z I [rs2/localhost:28019] [pitr/2021-02-11T11:21:18Z] oplog replay finished on {1613042477 10} <1613042478>
2021-02-11T11:22:41Z I [rs1/localhost:27019] [pitr/2021-02-11T11:21:18Z] recovery successfully finished
2021-02-11T11:22:41Z I [rs2/localhost:28019] [pitr/2021-02-11T11:21:18Z] recovery successfully finished
2021-02-11T11:22:41Z I [config/localhost:27028] [pitr/2021-02-11T11:21:18Z] oplog replay finished on {1613042477 7} <1613042478>
2021-02-11T11:22:42Z I [config/localhost:27028] [pitr/2021-02-11T11:21:18Z] recovery successfully finished

      Please check and fix the issue

      Attachments

        Issue Links

          mentioned in

          Page Loading...

          Activity

            People

              andrew.pogrebnoi Andrew Pogrebnoi
              sandra.romanchenko Sandra Romanchenko
              Votes:
              0 Vote for this issue
              Watchers:
              2 Start watching this issue

              Dates

                Created:
                Updated:
                Resolved:

                Time Tracking

                  Estimated:
                  Original Estimate - Not Specified
                  Not Specified
                  Remaining:
                  Remaining Estimate - Not Specified
                  Not Specified
                  Logged:
                  Time Spent - 30 minutes
                  30m

                  Smart Checklist