Uploaded image for project: 'OpenShift API for Data Protection'
  1. OpenShift API for Data Protection
  2. OADP-4169

Restore is partially failing for tagged imagestreams

XMLWordPrintable

    • Icon: Bug Bug
    • Resolution: Not a Bug
    • Icon: Critical Critical
    • OADP 1.4.1
    • None
    • restore
    • False
    • Hide

      None

      Show
      None
    • False
    • ToDo
    • 0
    • 0
    • Very Likely
    • 0
    • None
    • Unset
    • Unknown
    • Yes

      Description of problem: 

      Restore partially fails for tagged imagestreams with these error:

      time="2024-05-29T11:20:57Z" level=error msg="error restoring internal-image: ImageStream.image.openshift.io \"internal-image\" is invalid: []: Internal error: imagestreams \"internal-image\" is invalid: spec.tags[tag1].from.name: Invalid value: \"internal-image@sha256:3639d99a75ac279144a3565029a4f23e84482200e36a00849191afd78a1bd9b2\": error generating tag event: imagestreamimage.image.openshift.io \"sha256:3639d99a75ac279144a3565029a4f23e84482200e36a00849191afd78a1bd9b2\" not found" logSource="pkg/restore/restore.go:1641" restore=velero/test-restore3 

      Probably the same as https://issues.redhat.com/browse/OADP-144.

      Also seeing the same results with OCP 4.15.

       

      Version-Release number of selected component (if applicable):
      OCP 4.16 
      Velero 1.14

       

      How reproducible:
      Always

       

      Steps to Reproduce:
      1. Create a namespace

      $ oc create ns test1

      2. Create a imagestream resource

      $ oc import-image internal-image:alp --from quay.io/migqe/alpine:3.16 -n test1 --confirm 

      3. Tag imported image.

      $ oc -n test1 tag internal-image:alp internal-image:tag1 --alias=false 

      Actual results: 

      Restore is partially failing. 

       

      $ oc get restore  test-restore3 -o yaml
      apiVersion: velero.io/v1
      kind: Restore
      metadata:
        creationTimestamp: "2024-05-29T11:20:54Z"
        finalizers:
        - restores.velero.io/external-resources-finalizer
        generation: 7
        name: test-restore3
        namespace: velero
        resourceVersion: "131725"
        uid: a3082b14-276f-4a4f-9f8e-9ba3a6599c49
      spec:
        backupName: test-backup3
        excludedResources:
        - nodes
        - events
        - events.events.k8s.io
        - backups.velero.io
        - restores.velero.io
        - resticrepositories.velero.io
        - csinodes.storage.k8s.io
        - volumeattachments.storage.k8s.io
        - backuprepositories.velero.io
        itemOperationTimeout: 4h0m0s
      status:
        completionTimestamp: "2024-05-29T11:20:59Z"
        errors: 1
        hookStatus: {}
        phase: PartiallyFailed
        progress:
          itemsRestored: 28
          totalItems: 28
        startTimestamp: "2024-05-29T11:20:54Z"
        warnings: 9
      

      Expected results:

      Restore should be completed successfully. 

      Additional info:
      Velero logs

      $ velero restore logs test-restore3 -n velero 
      time="2024-05-29T11:20:54Z" level=info msg="starting restore" logSource="pkg/controller/restore_controller.go:545" restore=velero/test-restore3
      time="2024-05-29T11:20:54Z" level=info msg="Starting restore of backup velero/test-backup3" logSource="pkg/restore/restore.go:420" restore=velero/test-restore3
      time="2024-05-29T11:20:54Z" level=info msg="Resource 'customresourcedefinitions.apiextensions.k8s.io' will be restored at cluster scope" logSource="pkg/restore/restore.go:2271" restore=velero/test-restore3
      time="2024-05-29T11:20:54Z" level=info msg="restore status includes excludes: <nil>" logSource="pkg/restore/restore.go:1304" restore=velero/test-restore3
      time="2024-05-29T11:20:54Z" level=info msg="Executing item action for customresourcedefinitions.apiextensions.k8s.io" logSource="pkg/restore/restore.go:1318" restore=velero/test-restore3
      time="2024-05-29T11:20:54Z" level=info msg="Executing CRDV1PreserveUnknownFieldsAction" cmd=/velero logSource="pkg/restore/actions/crd_v1_preserve_unknown_fields_action.go:49" pluginName=velero restore=velero/test-restore3
      time="2024-05-29T11:20:54Z" level=info msg="Getting client for apiextensions.k8s.io/v1, Kind=CustomResourceDefinition" logSource="pkg/restore/restore.go:1021" restore=velero/test-restore3
      time="2024-05-29T11:20:54Z" level=info msg="Attempting to restore CustomResourceDefinition: clusterserviceversions.operators.coreos.com" logSource="pkg/restore/restore.go:1487" restore=velero/test-restore3
      time="2024-05-29T11:20:54Z" level=info msg="waiting informer cache sync for customresourcedefinitions.apiextensions.k8s.io, /clusterserviceversions.operators.coreos.com ..." logSource="pkg/restore/restore.go:1046" restore=velero/test-restore3
      time="2024-05-29T11:20:55Z" level=info msg="Restored 1 items out of an estimated total of 1 (estimate will change throughout the restore)" logSource="pkg/restore/restore.go:778" name=clusterserviceversions.operators.coreos.com namespace= progress= resource=customresourcedefinitions.apiextensions.k8s.io restore=velero/test-restore3
      time="2024-05-29T11:20:55Z" level=info msg="Resource 'namespaces' will be restored at cluster scope" logSource="pkg/restore/restore.go:2271" restore=velero/test-restore3
      time="2024-05-29T11:20:55Z" level=info msg="Resource 'serviceaccounts' will be restored into namespace 'test1'" logSource="pkg/restore/restore.go:2269" restore=velero/test-restore3
      time="2024-05-29T11:20:55Z" level=info msg="Resource 'secrets' will be restored into namespace 'test1'" logSource="pkg/restore/restore.go:2269" restore=velero/test-restore3
      time="2024-05-29T11:20:55Z" level=info msg="Resource 'configmaps' will be restored into namespace 'test1'" logSource="pkg/restore/restore.go:2269" restore=velero/test-restore3
      time="2024-05-29T11:20:55Z" level=info msg="resource cannot be resolved via discovery" logSource="pkg/restore/restore.go:2190" resource=clusterclasses.cluster.x-k8s.io restore=velero/test-restore3
      time="2024-05-29T11:20:55Z" level=info msg="Resource 'clusterserviceversions.operators.coreos.com' will be restored into namespace 'test1'" logSource="pkg/restore/restore.go:2269" restore=velero/test-restore3
      time="2024-05-29T11:20:55Z" level=info msg="Resource 'imagestreams.image.openshift.io' will be restored into namespace 'test1'" logSource="pkg/restore/restore.go:2269" restore=velero/test-restore3
      time="2024-05-29T11:20:55Z" level=info msg="Resource 'imagestreamtags.image.openshift.io' will be restored into namespace 'test1'" logSource="pkg/restore/restore.go:2269" restore=velero/test-restore3
      time="2024-05-29T11:20:55Z" level=info msg="Resource 'imagetags.image.openshift.io' will be restored into namespace 'test1'" logSource="pkg/restore/restore.go:2269" restore=velero/test-restore3
      time="2024-05-29T11:20:55Z" level=info msg="Resource 'rolebindings.authorization.openshift.io' will be restored into namespace 'test1'" logSource="pkg/restore/restore.go:2269" restore=velero/test-restore3
      time="2024-05-29T11:20:55Z" level=info msg="Resource 'rolebindings.rbac.authorization.k8s.io' will be restored into namespace 'test1'" logSource="pkg/restore/restore.go:2269" restore=velero/test-restore3
      time="2024-05-29T11:20:55Z" level=info msg="resource cannot be resolved via discovery" logSource="pkg/restore/restore.go:2190" resource=clusterbootstraps.run.tanzu.vmware.com restore=velero/test-restore3
      time="2024-05-29T11:20:55Z" level=info msg="resource cannot be resolved via discovery" logSource="pkg/restore/restore.go:2190" resource=clusters.cluster.x-k8s.io restore=velero/test-restore3
      time="2024-05-29T11:20:55Z" level=info msg="resource cannot be resolved via discovery" logSource="pkg/restore/restore.go:2190" resource=clusterresourcesets.addons.cluster.x-k8s.io restore=velero/test-restore3
      time="2024-05-29T11:20:55Z" level=info msg="waiting informer cache sync ..." logSource="pkg/restore/restore.go:594" restore=velero/test-restore3
      time="2024-05-29T11:20:56Z" level=info msg="Skipping customresourcedefinitions.apiextensions.k8s.io/clusterserviceversions.operators.coreos.com because it's already been restored." logSource="pkg/restore/restore.go:1163" restore=velero/test-restore3
      time="2024-05-29T11:20:56Z" level=info msg="Restored 1 items out of an estimated total of 28 (estimate will change throughout the restore)" logSource="pkg/restore/restore.go:778" name=clusterserviceversions.operators.coreos.com namespace= progress= resource=customresourcedefinitions.apiextensions.k8s.io restore=velero/test-restore3
      time="2024-05-29T11:20:56Z" level=info msg="restore status includes excludes: <nil>" logSource="pkg/restore/restore.go:1304" restore=velero/test-restore3
      time="2024-05-29T11:20:56Z" level=info msg="Executing item action for serviceaccounts" logSource="pkg/restore/restore.go:1318" restore=velero/test-restore3
      time="2024-05-29T11:20:56Z" level=info msg="Executing ServiceAccountAction" cmd=/velero logSource="pkg/restore/actions/service_account_action.go:47" pluginName=velero restore=velero/test-restore3
      time="2024-05-29T11:20:56Z" level=info msg="Done executing ServiceAccountAction" cmd=/velero logSource="pkg/restore/actions/service_account_action.go:78" pluginName=velero restore=velero/test-restore3
      time="2024-05-29T11:20:56Z" level=info msg="Executing item action for serviceaccounts" logSource="pkg/restore/restore.go:1318" restore=velero/test-restore3
      time="2024-05-29T11:20:56Z" level=info msg="[serviceaccount-restore] Entering ServiceAccount restore plugin" cmd=/plugins/velero-plugins logSource="/opt/app-root/src/github.com/konveyor/openshift-velero-plugin/velero-plugins/serviceaccount/restore.go:27" pluginName=velero-plugins restore=velero/test-restore3
      time="2024-05-29T11:20:56Z" level=info msg="[serviceaccount-restore] Checking for pull secrets to remove" cmd=/plugins/velero-plugins logSource="/opt/app-root/src/github.com/konveyor/openshift-velero-plugin/velero-plugins/serviceaccount/restore.go:33" pluginName=velero-plugins restore=velero/test-restore3
      time="2024-05-29T11:20:56Z" level=info msg="[serviceaccount-restore] Checking if secret builder-dockercfg-7nm7k matches builder-dockercfg-" cmd=/plugins/velero-plugins logSource="/opt/app-root/src/github.com/konveyor/openshift-velero-plugin/velero-plugins/serviceaccount/restore.go:37" pluginName=velero-plugins restore=velero/test-restore3
      time="2024-05-29T11:20:56Z" level=info msg="[serviceaccount-restore] Match found - excluding this secret" cmd=/plugins/velero-plugins logSource="/opt/app-root/src/github.com/konveyor/openshift-velero-plugin/velero-plugins/serviceaccount/restore.go:41" pluginName=velero-plugins restore=velero/test-restore3
      time="2024-05-29T11:20:56Z" level=info msg="[serviceaccount-restore] Checking if image pull secret builder-dockercfg-7nm7k matches builder-dockercfg-" cmd=/plugins/velero-plugins logSource="/opt/app-root/src/github.com/konveyor/openshift-velero-plugin/velero-plugins/serviceaccount/restore.go:50" pluginName=velero-plugins restore=velero/test-restore3
      time="2024-05-29T11:20:56Z" level=info msg="[serviceaccount-restore] Match found - excluding this secret" cmd=/plugins/velero-plugins logSource="/opt/app-root/src/github.com/konveyor/openshift-velero-plugin/velero-plugins/serviceaccount/restore.go:54" pluginName=velero-plugins restore=velero/test-restore3
      time="2024-05-29T11:20:56Z" level=info msg="Getting client for /v1, Kind=ServiceAccount" logSource="pkg/restore/restore.go:1021" restore=velero/test-restore3
      time="2024-05-29T11:20:56Z" level=info msg="Attempting to restore ServiceAccount: builder" logSource="pkg/restore/restore.go:1487" restore=velero/test-restore3
      time="2024-05-29T11:20:56Z" level=info msg="Restored 3 items out of an estimated total of 29 (estimate will change throughout the restore)" logSource="pkg/restore/restore.go:778" name=builder namespace=test1 progress= resource=serviceaccounts restore=velero/test-restore3
      time="2024-05-29T11:20:56Z" level=info msg="restore status includes excludes: <nil>" logSource="pkg/restore/restore.go:1304" restore=velero/test-restore3
      time="2024-05-29T11:20:56Z" level=info msg="Executing item action for serviceaccounts" logSource="pkg/restore/restore.go:1318" restore=velero/test-restore3
      time="2024-05-29T11:20:56Z" level=info msg="Executing ServiceAccountAction" cmd=/velero logSource="pkg/restore/actions/service_account_action.go:47" pluginName=velero restore=velero/test-restore3
      time="2024-05-29T11:20:56Z" level=info msg="Done executing ServiceAccountAction" cmd=/velero logSource="pkg/restore/actions/service_account_action.go:78" pluginName=velero restore=velero/test-restore3
      time="2024-05-29T11:20:56Z" level=info msg="Executing item action for serviceaccounts" logSource="pkg/restore/restore.go:1318" restore=velero/test-restore3
      time="2024-05-29T11:20:56Z" level=info msg="[serviceaccount-restore] Entering ServiceAccount restore plugin" cmd=/plugins/velero-plugins logSource="/opt/app-root/src/github.com/konveyor/openshift-velero-plugin/velero-plugins/serviceaccount/restore.go:27" pluginName=velero-plugins restore=velero/test-restore3
      time="2024-05-29T11:20:56Z" level=info msg="[serviceaccount-restore] Checking for pull secrets to remove" cmd=/plugins/velero-plugins logSource="/opt/app-root/src/github.com/konveyor/openshift-velero-plugin/velero-plugins/serviceaccount/restore.go:33" pluginName=velero-plugins restore=velero/test-restore3
      time="2024-05-29T11:20:56Z" level=info msg="[serviceaccount-restore] Checking if secret default-dockercfg-vfkz7 matches default-dockercfg-" cmd=/plugins/velero-plugins logSource="/opt/app-root/src/github.com/konveyor/openshift-velero-plugin/velero-plugins/serviceaccount/restore.go:37" pluginName=velero-plugins restore=velero/test-restore3
      time="2024-05-29T11:20:56Z" level=info msg="[serviceaccount-restore] Match found - excluding this secret" cmd=/plugins/velero-plugins logSource="/opt/app-root/src/github.com/konveyor/openshift-velero-plugin/velero-plugins/serviceaccount/restore.go:41" pluginName=velero-plugins restore=velero/test-restore3
      time="2024-05-29T11:20:56Z" level=info msg="[serviceaccount-restore] Checking if image pull secret default-dockercfg-vfkz7 matches default-dockercfg-" cmd=/plugins/velero-plugins logSource="/opt/app-root/src/github.com/konveyor/openshift-velero-plugin/velero-plugins/serviceaccount/restore.go:50" pluginName=velero-plugins restore=velero/test-restore3
      time="2024-05-29T11:20:56Z" level=info msg="[serviceaccount-restore] Match found - excluding this secret" cmd=/plugins/velero-plugins logSource="/opt/app-root/src/github.com/konveyor/openshift-velero-plugin/velero-plugins/serviceaccount/restore.go:54" pluginName=velero-plugins restore=velero/test-restore3
      time="2024-05-29T11:20:56Z" level=info msg="Attempting to restore ServiceAccount: default" logSource="pkg/restore/restore.go:1487" restore=velero/test-restore3
      time="2024-05-29T11:20:56Z" level=info msg="Restored 4 items out of an estimated total of 29 (estimate will change throughout the restore)" logSource="pkg/restore/restore.go:778" name=default namespace=test1 progress= resource=serviceaccounts restore=velero/test-restore3
      time="2024-05-29T11:20:56Z" level=info msg="restore status includes excludes: <nil>" logSource="pkg/restore/restore.go:1304" restore=velero/test-restore3
      time="2024-05-29T11:20:56Z" level=info msg="Executing item action for serviceaccounts" logSource="pkg/restore/restore.go:1318" restore=velero/test-restore3
      time="2024-05-29T11:20:56Z" level=info msg="Executing ServiceAccountAction" cmd=/velero logSource="pkg/restore/actions/service_account_action.go:47" pluginName=velero restore=velero/test-restore3
      time="2024-05-29T11:20:56Z" level=info msg="Done executing ServiceAccountAction" cmd=/velero logSource="pkg/restore/actions/service_account_action.go:78" pluginName=velero restore=velero/test-restore3
      time="2024-05-29T11:20:56Z" level=info msg="Executing item action for serviceaccounts" logSource="pkg/restore/restore.go:1318" restore=velero/test-restore3
      time="2024-05-29T11:20:57Z" level=info msg="[secret-restore] Entering Secret restore plugin" cmd=/plugins/velero-plugins logSource="/opt/app-root/src/github.com/konveyor/openshift-velero-plugin/velero-plugins/secret/restore.go:29" pluginName=velero-plugins restore=velero/test-restore3
      time="2024-05-29T11:20:57Z" level=info msg="[secret-restore] Secret: default-dockercfg-s9vth" cmd=/plugins/velero-plugins logSource="/opt/app-root/src/github.com/konveyor/openshift-velero-plugin/velero-plugins/secret/restore.go:34" pluginName=velero-plugins restore=velero/test-restore3
      time="2024-05-29T11:20:57Z" level=info msg="Attempting to restore Secret: default-dockercfg-s9vth" logSource="pkg/restore/restore.go:1487" restore=velero/test-restore3
      time="2024-05-29T11:20:57Z" level=info msg="the managed fields for test1/default-dockercfg-s9vth is patched" logSource="pkg/restore/restore.go:1688" restore=velero/test-restore3
      time="2024-05-29T11:20:57Z" level=info msg="Restored 9 items out of an estimated total of 29 (estimate will change throughout the restore)" logSource="pkg/restore/restore.go:778" name=default-dockercfg-s9vth namespace=test1 progress= resource=secrets restore=velero/test-restore3
      time="2024-05-29T11:20:57Z" level=info msg="restore status includes excludes: <nil>" logSource="pkg/restore/restore.go:1304" restore=velero/test-restore3
      time="2024-05-29T11:20:57Z" level=info msg="Executing item action for secrets" logSource="pkg/restore/restore.go:1318" restore=velero/test-restore3
      time="2024-05-29T11:20:57Z" level=info msg="Executing SecretAction" cmd=/velero logSource="pkg/restore/actions/secret_action.go:58" pluginName=velero restore=velero/test-restore3
      time="2024-05-29T11:20:57Z" level=info msg="Done executing SecretAction" cmd=/velero logSource="pkg/restore/actions/secret_action.go:69" pluginName=velero restore=velero/test-restore3
      time="2024-05-29T11:20:57Z" level=info msg="Executing item action for secrets" logSource="pkg/restore/restore.go:1318" restore=velero/test-restore3
      time="2024-05-29T11:20:57Z" level=info msg="[secret-restore] Entering Secret restore plugin" cmd=/plugins/velero-plugins logSource="/opt/app-root/src/github.com/konveyor/openshift-velero-plugin/velero-plugins/secret/restore.go:29" pluginName=velero-plugins restore=velero/test-restore3
      time="2024-05-29T11:20:57Z" level=info msg="[secret-restore] Secret: default-dockercfg-vfkz7" cmd=/plugins/velero-plugins logSource="/opt/app-root/src/github.com/konveyor/openshift-velero-plugin/velero-plugins/secret/restore.go:34" pluginName=velero-plugins restore=velero/test-restore3
      time="2024-05-29T11:20:57Z" level=info msg="Attempting to restore Secret: default-dockercfg-vfkz7" logSource="pkg/restore/restore.go:1487" restore=velero/test-restore3
      time="2024-05-29T11:20:57Z" level=info msg="the managed fields for test1/default-dockercfg-vfkz7 is patched" logSource="pkg/restore/restore.go:1688" restore=velero/test-restore3
      time="2024-05-29T11:20:57Z" level=info msg="Restored 10 items out of an estimated total of 29 (estimate will change throughout the restore)" logSource="pkg/restore/restore.go:778" name=default-dockercfg-vfkz7 namespace=test1 progress= resource=secrets restore=velero/test-restore3
      time="2024-05-29T11:20:57Z" level=info msg="restore status includes excludes: <nil>" logSource="pkg/restore/restore.go:1304" restore=velero/test-restore3
      time="2024-05-29T11:20:57Z" level=info msg="Executing item action for secrets" logSource="pkg/restore/restore.go:1318" restore=velero/test-restore3
      time="2024-05-29T11:20:57Z" level=info msg="Executing SecretAction" cmd=/velero logSource="pkg/restore/actions/secret_action.go:58" pluginName=velero restore=velero/test-restore3
      time="2024-05-29T11:20:57Z" level=info msg="Done executing SecretAction" cmd=/velero logSource="pkg/restore/actions/secret_action.go:84" pluginName=velero restore=velero/test-restore3
      time="2024-05-29T11:20:57Z" level=info msg="Skipping restore of Secret: default-token-8k4mq because a registered plugin discarded it" logSource="pkg/restore/restore.go:1354" restore=velero/test-restore3
      time="2024-05-29T11:20:57Z" level=info msg="Restored 11 items out of an estimated total of 29 (estimate will change throughout the restore)" logSource="pkg/restore/restore.go:778" name=default-token-8k4mq namespace=test1 progress= resource=secrets restore=velero/test-restore3
      time="2024-05-29T11:20:57Z" level=info msg="restore status includes excludes: <nil>" logSource="pkg/restore/restore.go:1304" restore=velero/test-restore3
      time="2024-05-29T11:20:57Z" level=info msg="Executing item action for secrets" logSource="pkg/restore/restore.go:1318" restore=velero/test-restore3
      time="2024-05-29T11:20:57Z" level=info msg="Executing SecretAction" cmd=/velero logSource="pkg/restore/actions/secret_action.go:58" pluginName=velero restore=velero/test-restore3
      time="2024-05-29T11:20:57Z" level=info msg="Done executing SecretAction" cmd=/velero logSource="pkg/restore/actions/secret_action.go:69" pluginName=velero restore=velero/test-restore3
      time="2024-05-29T11:20:57Z" level=info msg="Executing item action for secrets" logSource="pkg/restore/restore.go:1318" restore=velero/test-restore3
      time="2024-05-29T11:20:57Z" level=info msg="[secret-restore] Entering Secret restore plugin" cmd=/plugins/velero-plugins logSource="/opt/app-root/src/github.com/konveyor/openshift-velero-plugin/velero-plugins/secret/restore.go:29" pluginName=velero-plugins restore=velero/test-restore3
      time="2024-05-29T11:20:57Z" level=info msg="[secret-restore] Secret: deployer-dockercfg-8dkt8" cmd=/plugins/velero-plugins logSource="/opt/app-root/src/github.com/konveyor/openshift-velero-plugin/velero-plugins/secret/restore.go:34" pluginName=velero-plugins restore=velero/test-restore3
      time="2024-05-29T11:20:57Z" level=info msg="Attempting to restore Secret: deployer-dockercfg-8dkt8" logSource="pkg/restore/restore.go:1487" restore=velero/test-restore3
      time="2024-05-29T11:20:57Z" level=info msg="the managed fields for test1/deployer-dockercfg-8dkt8 is patched" logSource="pkg/restore/restore.go:1688" restore=velero/test-restore3
      time="2024-05-29T11:20:57Z" level=info msg="Restored 12 items out of an estimated total of 29 (estimate will change throughout the restore)" logSource="pkg/restore/restore.go:778" name=deployer-dockercfg-8dkt8 namespace=test1 progress= resource=secrets restore=velero/test-restore3
      time="2024-05-29T11:20:57Z" level=info msg="restore status includes excludes: <nil>" logSource="pkg/restore/restore.go:1304" restore=velero/test-restore3
      time="2024-05-29T11:20:57Z" level=info msg="Executing item action for secrets" logSource="pkg/restore/restore.go:1318" restore=velero/test-restore3
      time="2024-05-29T11:20:57Z" level=info msg="Executing SecretAction" cmd=/velero logSource="pkg/restore/actions/secret_action.go:58" pluginName=velero restore=velero/test-restore3
      time="2024-05-29T11:20:57Z" level=info msg="Done executing SecretAction" cmd=/velero logSource="pkg/restore/actions/secret_action.go:69" pluginName=velero restore=velero/test-restore3
      time="2024-05-29T11:20:57Z" level=info msg="Executing item action for secrets" logSource="pkg/restore/restore.go:1318" restore=velero/test-restore3
      time="2024-05-29T11:20:57Z" level=info msg="[secret-restore] Entering Secret restore plugin" cmd=/plugins/velero-plugins logSource="/opt/app-root/src/github.com/konveyor/openshift-velero-plugin/velero-plugins/secret/restore.go:29" pluginName=velero-plugins restore=velero/test-restore3
      time="2024-05-29T11:20:57Z" level=info msg="[secret-restore] Secret: deployer-dockercfg-njnd6" cmd=/plugins/velero-plugins logSource="/opt/app-root/src/github.com/konveyor/openshift-velero-plugin/velero-plugins/secret/restore.go:34" pluginName=velero-plugins restore=velero/test-restore3
      time="2024-05-29T11:20:57Z" level=info msg="Attempting to restore Secret: deployer-dockercfg-njnd6" logSource="pkg/restore/restore.go:1487" restore=velero/test-restore3
      time="2024-05-29T11:20:57Z" level=info msg="the managed fields for test1/deployer-dockercfg-njnd6 is patched" logSource="pkg/restore/restore.go:1688" restore=velero/test-restore3
      time="2024-05-29T11:20:57Z" level=info msg="Restored 13 items out of an estimated total of 29 (estimate will change throughout the restore)" logSource="pkg/restore/restore.go:778" name=deployer-dockercfg-njnd6 namespace=test1 progress= resource=secrets restore=velero/test-restore3
      time="2024-05-29T11:20:57Z" level=info msg="restore status includes excludes: <nil>" logSource="pkg/restore/restore.go:1304" restore=velero/test-restore3
      time="2024-05-29T11:20:57Z" level=info msg="Executing item action for secrets" logSource="pkg/restore/restore.go:1318" restore=velero/test-restore3
      time="2024-05-29T11:20:57Z" level=info msg="Executing SecretAction" cmd=/velero logSource="pkg/restore/actions/secret_action.go:58" pluginName=velero restore=velero/test-restore3
      time="2024-05-29T11:20:57Z" level=info msg="Done executing SecretAction" cmd=/velero logSource="pkg/restore/actions/secret_action.go:84" pluginName=velero restore=velero/test-restore3
      time="2024-05-29T11:20:57Z" level=info msg="Skipping restore of Secret: deployer-token-ggkm7 because a registered plugin discarded it" logSource="pkg/restore/restore.go:1354" restore=velero/test-restore3
      time="2024-05-29T11:20:57Z" level=info msg="Restored 14 items out of an estimated total of 29 (estimate will change throughout the restore)" logSource="pkg/restore/restore.go:778" name=deployer-token-ggkm7 namespace=test1 progress= resource=secrets restore=velero/test-restore3
      time="2024-05-29T11:20:57Z" level=info msg="restore status includes excludes: <nil>" logSource="pkg/restore/restore.go:1304" restore=velero/test-restore3
      time="2024-05-29T11:20:57Z" level=info msg="Getting client for /v1, Kind=ConfigMap" logSource="pkg/restore/restore.go:1021" restore=velero/test-restore3
      time="2024-05-29T11:20:57Z" level=info msg="Attempting to restore ConfigMap: kube-root-ca.crt" logSource="pkg/restore/restore.go:1487" restore=velero/test-restore3
      time="2024-05-29T11:20:57Z" level=info msg="Restored 15 items out of an estimated total of 29 (estimate will change throughout the restore)" logSource="pkg/restore/restore.go:778" name=kube-root-ca.crt namespace=test1 progress= resource=configmaps restore=velero/test-restore3
      time="2024-05-29T11:20:57Z" level=info msg="restore status includes excludes: <nil>" logSource="pkg/restore/restore.go:1304" restore=velero/test-restore3
      time="2024-05-29T11:20:57Z" level=info msg="Attempting to restore ConfigMap: openshift-service-ca.crt" logSource="pkg/restore/restore.go:1487" restore=velero/test-restore3
      time="2024-05-29T11:20:57Z" level=info msg="Restored 16 items out of an estimated total of 29 (estimate will change throughout the restore)" logSource="pkg/restore/restore.go:778" name=openshift-service-ca.crt namespace=test1 progress= resource=configmaps restore=velero/test-restore3
      time="2024-05-29T11:20:57Z" level=info msg="restore status includes excludes: <nil>" logSource="pkg/restore/restore.go:1304" restore=velero/test-restore3
      time="2024-05-29T11:20:57Z" level=info msg="Getting client for operators.coreos.com/v1alpha1, Kind=ClusterServiceVersion" logSource="pkg/restore/restore.go:1021" restore=velero/test-restore3
      time="2024-05-29T11:20:57Z" level=info msg="Attempting to restore ClusterServiceVersion: volsync-product.v0.9.1" logSource="pkg/restore/restore.go:1487" restore=velero/test-restore3
      time="2024-05-29T11:20:57Z" level=info msg="the managed fields for test1/volsync-product.v0.9.1 is patched" logSource="pkg/restore/restore.go:1688" restore=velero/test-restore3
      time="2024-05-29T11:20:57Z" level=info msg="Restored 17 items out of an estimated total of 29 (estimate will change throughout the restore)" logSource="pkg/restore/restore.go:778" name=volsync-product.v0.9.1 namespace=test1 progress= resource=clusterserviceversions.operators.coreos.com restore=velero/test-restore3
      time="2024-05-29T11:20:57Z" level=info msg="restore status includes excludes: <nil>" logSource="pkg/restore/restore.go:1304" restore=velero/test-restore3
      time="2024-05-29T11:20:57Z" level=info msg="Executing item action for imagestreams.image.openshift.io" logSource="pkg/restore/restore.go:1318" restore=velero/test-restore3
      time="2024-05-29T11:20:57Z" level=info msg="[common-restore] Entering common restore plugin" cmd=/plugins/velero-plugins logSource="/opt/app-root/src/github.com/konveyor/openshift-velero-plugin/velero-plugins/common/restore.go:49" pluginName=velero-plugins restore=velero/test-restore3
      time="2024-05-29T11:20:57Z" level=info msg="[common-restore] common restore plugin for internal-image" cmd=/plugins/velero-plugins logSource="/opt/app-root/src/github.com/konveyor/openshift-velero-plugin/velero-plugins/common/restore.go:57" pluginName=velero-plugins restore=velero/test-restore3
      time="2024-05-29T11:20:57Z" level=info msg="[GetRegistryInfo] value from imagestream" cmd=/plugins/velero-plugins logSource="/opt/app-root/src/github.com/konveyor/openshift-velero-plugin/velero-plugins/common/shared.go:84" pluginName=velero-plugins restore=velero/test-restore3
      time="2024-05-29T11:20:57Z" level=info msg="Executing item action for imagestreams.image.openshift.io" logSource="pkg/restore/restore.go:1318" restore=velero/test-restore3
      time="2024-05-29T11:20:57Z" level=info msg="[is-restore] Entering ImageStream restore plugin" cmd=/plugins/velero-plugins logSource="/opt/app-root/src/github.com/konveyor/openshift-velero-plugin/velero-plugins/imagestream/restore.go:33" pluginName=velero-plugins restore=velero/test-restore3
      time="2024-05-29T11:20:57Z" level=info msg="[is-restore] image: \"internal-image\"" cmd=/plugins/velero-plugins logSource="/opt/app-root/src/github.com/konveyor/openshift-velero-plugin/velero-plugins/imagestream/restore.go:37" pluginName=velero-plugins restore=velero/test-restore3
      time="2024-05-29T11:20:57Z" level=info msg="Getting client for image.openshift.io/v1, Kind=ImageStream" logSource="pkg/restore/restore.go:1021" restore=velero/test-restore3
      time="2024-05-29T11:20:57Z" level=info msg="Attempting to restore ImageStream: internal-image" logSource="pkg/restore/restore.go:1487" restore=velero/test-restore3
      time="2024-05-29T11:20:57Z" level=error msg="error restoring internal-image: ImageStream.image.openshift.io \"internal-image\" is invalid: []: Internal error: imagestreams \"internal-image\" is invalid: spec.tags[tag1].from.name: Invalid value: \"internal-image@sha256:3639d99a75ac279144a3565029a4f23e84482200e36a00849191afd78a1bd9b2\": error generating tag event: imagestreamimage.image.openshift.io \"sha256:3639d99a75ac279144a3565029a4f23e84482200e36a00849191afd78a1bd9b2\" not found" logSource="pkg/restore/restore.go:1641" restore=velero/test-restore3
      time="2024-05-29T11:20:57Z" level=info msg="Restored 18 items out of an estimated total of 29 (estimate will change throughout the restore)" logSource="pkg/restore/restore.go:778" name=internal-image namespace=test1 progress= resource=imagestreams.image.openshift.io restore=velero/test-restore3
      time="2024-05-29T11:20:57Z" level=info msg="restore status includes excludes: <nil>" logSource="pkg/restore/restore.go:1304" restore=velero/test-restore3
      time="2024-05-29T11:20:57Z" level=info msg="Executing item action for imagestreamtags.image.openshift.io" logSource="pkg/restore/restore.go:1318" restore=velero/test-restore3
      time="2024-05-29T11:20:57Z" level=info msg="[common-restore] Entering common restore plugin" cmd=/plugins/velero-plugins logSource="/opt/app-root/src/github.com/konveyor/openshift-velero-plugin/velero-plugins/common/restore.go:49" pluginName=velero-plugins restore=velero/test-restore3
      time="2024-05-29T11:20:57Z" level=info msg="[common-restore] common restore plugin for internal-image:alp" cmd=/plugins/velero-plugins logSource="/opt/app-root/src/github.com/konveyor/openshift-velero-plugin/velero-plugins/common/restore.go:57" pluginName=velero-plugins restore=velero/test-restore3
      time="2024-05-29T11:20:57Z" level=info msg="Executing item action for imagestreamtags.image.openshift.io" logSource="pkg/restore/restore.go:1318" restore=velero/test-restore3
      time="2024-05-29T11:20:57Z" level=info msg="[istag-restore] Entering ImageStreamTag restore plugin" cmd=/plugins/velero-plugins logSource="/opt/app-root/src/github.com/konveyor/openshift-velero-plugin/velero-plugins/imagestreamtag/restore.go:40" pluginName=velero-plugins restore=velero/test-restore3
      time="2024-05-29T11:20:57Z" level=info msg="[istag-restore] Restoring imagestreamtag internal-image:alp" cmd=/plugins/velero-plugins logSource="/opt/app-root/src/github.com/konveyor/openshift-velero-plugin/velero-plugins/imagestreamtag/restore.go:49" pluginName=velero-plugins restore=velero/test-restore3
      time="2024-05-29T11:20:57Z" level=info msg="[istag-restore] backup internal registry: \"image-registry.openshift-image-registry.svc:5000\"" cmd=/plugins/velero-plugins logSource="/opt/app-root/src/github.com/konveyor/openshift-velero-plugin/velero-plugins/imagestreamtag/restore.go:52" pluginName=velero-plugins restore=velero/test-restore3
      time="2024-05-29T11:20:57Z" level=info msg="[istag-restore] Reference tag: DockerImage, tag: quay.io/migqe/alpine:3.16" cmd=/plugins/velero-plugins logSource="/opt/app-root/src/github.com/konveyor/openshift-velero-plugin/velero-plugins/imagestreamtag/restore.go:74" pluginName=velero-plugins restore=velero/test-restore3
      time="2024-05-29T11:20:57Z" level=info msg="[istag-restore] Restoring reference or remote imagestreamtag" cmd=/plugins/velero-plugins logSource="/opt/app-root/src/github.com/konveyor/openshift-velero-plugin/velero-plugins/imagestreamtag/restore.go:102" pluginName=velero-plugins restore=velero/test-restore3
      time="2024-05-29T11:20:57Z" level=info msg="[istag-restore] AreAdditionalItemsReady called" cmd=/plugins/velero-plugins logSource="/opt/app-root/src/github.com/konveyor/openshift-velero-plugin/velero-plugins/imagestreamtag/restore.go:122" pluginName=velero-plugins restore=velero/test-restore3
      time="2024-05-29T11:20:57Z" level=info msg="Getting client for image.openshift.io/v1, Kind=ImageStreamTag" logSource="pkg/restore/restore.go:1021" restore=velero/test-restore3
      time="2024-05-29T11:20:57Z" level=info msg="Attempting to restore ImageStreamTag: internal-image:alp" logSource="pkg/restore/restore.go:1487" restore=velero/test-restore3
      time="2024-05-29T11:20:57Z" level=info msg="Restored 19 items out of an estimated total of 29 (estimate will change throughout the restore)" logSource="pkg/restore/restore.go:778" name="internal-image:alp" namespace=test1 progress= resource=imagestreamtags.image.openshift.io restore=velero/test-restore3
      time="2024-05-29T11:20:57Z" level=info msg="restore status includes excludes: <nil>" logSource="pkg/restore/restore.go:1304" restore=velero/test-restore3
      time="2024-05-29T11:20:57Z" level=info msg="Executing item action for imagestreamtags.image.openshift.io" logSource="pkg/restore/restore.go:1318" restore=velero/test-restore3
      time="2024-05-29T11:20:57Z" level=info msg="[common-restore] Entering common restore plugin" cmd=/plugins/velero-plugins logSource="/opt/app-root/src/github.com/konveyor/openshift-velero-plugin/velero-plugins/common/restore.go:49" pluginName=velero-plugins restore=velero/test-restore3
      time="2024-05-29T11:20:57Z" level=info msg="[common-restore] common restore plugin for internal-image:tag1" cmd=/plugins/velero-plugins logSource="/opt/app-root/src/github.com/konveyor/openshift-velero-plugin/velero-plugins/common/restore.go:57" pluginName=velero-plugins restore=velero/test-restore3
      time="2024-05-29T11:20:57Z" level=info msg="Executing item action for imagestreamtags.image.openshift.io" logSource="pkg/restore/restore.go:1318" restore=velero/test-restore3
      time="2024-05-29T11:20:57Z" level=info msg="[istag-restore] Entering ImageStreamTag restore plugin" cmd=/plugins/velero-plugins logSource="/opt/app-root/src/github.com/konveyor/openshift-velero-plugin/velero-plugins/imagestreamtag/restore.go:40" pluginName=velero-plugins restore=velero/test-restore3
      time="2024-05-29T11:20:57Z" level=info msg="[istag-restore] Restoring imagestreamtag internal-image:tag1" cmd=/plugins/velero-plugins logSource="/opt/app-root/src/github.com/konveyor/openshift-velero-plugin/velero-plugins/imagestreamtag/restore.go:49" pluginName=velero-plugins restore=velero/test-restore3
      time="2024-05-29T11:20:57Z" level=info msg="[istag-restore] backup internal registry: \"image-registry.openshift-image-registry.svc:5000\"" cmd=/plugins/velero-plugins logSource="/opt/app-root/src/github.com/konveyor/openshift-velero-plugin/velero-plugins/imagestreamtag/restore.go:52" pluginName=velero-plugins restore=velero/test-restore3
      time="2024-05-29T11:20:57Z" level=info msg="[istag-restore] Setting additionalItems: test1/internal-image:alp" cmd=/plugins/velero-plugins logSource="/opt/app-root/src/github.com/konveyor/openshift-velero-plugin/velero-plugins/imagestreamtag/restore.go:60" pluginName=velero-plugins restore=velero/test-restore3
      time="2024-05-29T11:20:57Z" level=info msg="[istag-restore] Reference tag: ImageStreamImage, tag: internal-image@sha256:3639d99a75ac279144a3565029a4f23e84482200e36a00849191afd78a1bd9b2" cmd=/plugins/velero-plugins logSource="/opt/app-root/src/github.com/konveyor/openshift-velero-plugin/velero-plugins/imagestreamtag/restore.go:74" pluginName=velero-plugins restore=velero/test-restore3
      time="2024-05-29T11:20:57Z" level=info msg="[istag-restore] Restoring reference or remote imagestreamtag" cmd=/plugins/velero-plugins logSource="/opt/app-root/src/github.com/konveyor/openshift-velero-plugin/velero-plugins/imagestreamtag/restore.go:102" pluginName=velero-plugins restore=velero/test-restore3
      time="2024-05-29T11:20:57Z" level=info msg="Skipping imagestreamtags.image.openshift.io/test1/internal-image:alp because it's already been restored." logSource="pkg/restore/restore.go:1163" restore=velero/test-restore3
      time="2024-05-29T11:20:57Z" level=info msg="[istag-restore] AreAdditionalItemsReady called" cmd=/plugins/velero-plugins logSource="/opt/app-root/src/github.com/konveyor/openshift-velero-plugin/velero-plugins/imagestreamtag/restore.go:122" pluginName=velero-plugins restore=velero/test-restore3
      time="2024-05-29T11:20:57Z" level=info msg="[istag-restore] AreAdditionalItemsReady: not ready: test1/internal-image:alp" cmd=/plugins/velero-plugins logSource="/opt/app-root/src/github.com/konveyor/openshift-velero-plugin/velero-plugins/imagestreamtag/restore.go:145" pluginName=velero-plugins restore=velero/test-restore3
      time="2024-05-29T11:20:58Z" level=info msg="[istag-restore] AreAdditionalItemsReady called" cmd=/plugins/velero-plugins logSource="/opt/app-root/src/github.com/konveyor/openshift-velero-plugin/velero-plugins/imagestreamtag/restore.go:122" pluginName=velero-plugins restore=velero/test-restore3
      time="2024-05-29T11:20:58Z" level=info msg="[istag-restore] AreAdditionalItemsReady: ready: test1/internal-image:alp" cmd=/plugins/velero-plugins logSource="/opt/app-root/src/github.com/konveyor/openshift-velero-plugin/velero-plugins/imagestreamtag/restore.go:148" pluginName=velero-plugins restore=velero/test-restore3
      time="2024-05-29T11:20:58Z" level=info msg="Attempting to restore ImageStreamTag: internal-image:tag1" logSource="pkg/restore/restore.go:1487" restore=velero/test-restore3
      time="2024-05-29T11:20:58Z" level=info msg="Restored 20 items out of an estimated total of 29 (estimate will change throughout the restore)" logSource="pkg/restore/restore.go:778" name="internal-image:tag1" namespace=test1 progress= resource=imagestreamtags.image.openshift.io restore=velero/test-restore3
      time="2024-05-29T11:20:58Z" level=info msg="restore status includes excludes: <nil>" logSource="pkg/restore/restore.go:1304" restore=velero/test-restore3
      time="2024-05-29T11:20:58Z" level=info msg="Executing item action for imagetags.image.openshift.io" logSource="pkg/restore/restore.go:1318" restore=velero/test-restore3
      time="2024-05-29T11:20:58Z" level=info msg="[imagetag-restore] skipping restore of imagetag" cmd=/plugins/velero-plugins logSource="/opt/app-root/src/github.com/konveyor/openshift-velero-plugin/velero-plugins/imagetag/restore.go:23" pluginName=velero-plugins restore=velero/test-restore3
      time="2024-05-29T11:20:58Z" level=info msg="Skipping restore of ImageTag: internal-image:alp because a registered plugin discarded it" logSource="pkg/restore/restore.go:1354" restore=velero/test-restore3
      time="2024-05-29T11:20:58Z" level=info msg="Restored 21 items out of an estimated total of 29 (estimate will change throughout the restore)" logSource="pkg/restore/restore.go:778" name="internal-image:alp" namespace=test1 progress= resource=imagetags.image.openshift.io restore=velero/test-restore3
      time="2024-05-29T11:20:58Z" level=info msg="restore status includes excludes: <nil>" logSource="pkg/restore/restore.go:1304" restore=velero/test-restore3
      time="2024-05-29T11:20:58Z" level=info msg="Executing item action for imagetags.image.openshift.io" logSource="pkg/restore/restore.go:1318" restore=velero/test-restore3
      time="2024-05-29T11:20:58Z" level=info msg="[imagetag-restore] skipping restore of imagetag" cmd=/plugins/velero-plugins logSource="/opt/app-root/src/github.com/konveyor/openshift-velero-plugin/velero-plugins/imagetag/restore.go:23" pluginName=velero-plugins restore=velero/test-restore3
      time="2024-05-29T11:20:58Z" level=info msg="Skipping restore of ImageTag: internal-image:tag1 because a registered plugin discarded it" logSource="pkg/restore/restore.go:1354" restore=velero/test-restore3
      time="2024-05-29T11:20:58Z" level=info msg="Restored 22 items out of an estimated total of 29 (estimate will change throughout the restore)" logSource="pkg/restore/restore.go:778" name="internal-image:tag1" namespace=test1 progress= resource=imagetags.image.openshift.io restore=velero/test-restore3
      time="2024-05-29T11:20:58Z" level=info msg="restore status includes excludes: <nil>" logSource="pkg/restore/restore.go:1304" restore=velero/test-restore3
      time="2024-05-29T11:20:58Z" level=info msg="Executing item action for rolebindings.authorization.openshift.io" logSource="pkg/restore/restore.go:1318" restore=velero/test-restore3
      time="2024-05-29T11:20:58Z" level=info msg="[rolebinding-restore] Entering Role Bindings restore plugin" cmd=/plugins/velero-plugins logSource="/opt/app-root/src/github.com/konveyor/openshift-velero-plugin/velero-plugins/rolebindings/restore.go:28" pluginName=velero-plugins restore=velero/test-restore3
      time="2024-05-29T11:20:58Z" level=info msg="[rolebinding-restore] role binding - system:deployers, API version%!(EXTRA string=authorization.openshift.io/v1)" cmd=/plugins/velero-plugins logSource="/opt/app-root/src/github.com/konveyor/openshift-velero-plugin/velero-plugins/rolebindings/restore.go:34" pluginName=velero-plugins restore=velero/test-restore3
      time="2024-05-29T11:20:58Z" level=info msg="Getting client for authorization.openshift.io/v1, Kind=RoleBinding" logSource="pkg/restore/restore.go:1021" restore=velero/test-restore3
      time="2024-05-29T11:20:58Z" level=info msg="Attempting to restore RoleBinding: system:deployers" logSource="pkg/restore/restore.go:1487" restore=velero/test-restore3
      time="2024-05-29T11:20:58Z" level=warning msg="Unable to retrieve in-cluster version of test1/system:deployers: error getting resource from lister for rolebindings.authorization.openshift.io, test1/system:deployers: rolebindings.authorization.openshift.io \"system:deployers\" not found, object won't be restored by velero or have restore labels, and existing resource policy is not applied" logSource="pkg/restore/restore.go:1526" restore=velero/test-restore3
      time="2024-05-29T11:20:58Z" level=info msg="Restored 23 items out of an estimated total of 29 (estimate will change throughout the restore)" logSource="pkg/restore/restore.go:778" name="system:deployers" namespace=test1 progress= resource=rolebindings.authorization.openshift.io restore=velero/test-restore3
      time="2024-05-29T11:20:58Z" level=info msg="restore status includes excludes: <nil>" logSource="pkg/restore/restore.go:1304" restore=velero/test-restore3
      time="2024-05-29T11:20:58Z" level=info msg="Executing item action for rolebindings.authorization.openshift.io" logSource="pkg/restore/restore.go:1318" restore=velero/test-restore3
      time="2024-05-29T11:20:58Z" level=info msg="[rolebinding-restore] Entering Role Bindings restore plugin" cmd=/plugins/velero-plugins logSource="/opt/app-root/src/github.com/konveyor/openshift-velero-plugin/velero-plugins/rolebindings/restore.go:28" pluginName=velero-plugins restore=velero/test-restore3
      time="2024-05-29T11:20:58Z" level=info msg="[rolebinding-restore] role binding - system:image-builders, API version%!(EXTRA string=authorization.openshift.io/v1)" cmd=/plugins/velero-plugins logSource="/opt/app-root/src/github.com/konveyor/openshift-velero-plugin/velero-plugins/rolebindings/restore.go:34" pluginName=velero-plugins restore=velero/test-restore3
      time="2024-05-29T11:20:58Z" level=info msg="Attempting to restore RoleBinding: system:image-builders" logSource="pkg/restore/restore.go:1487" restore=velero/test-restore3
      time="2024-05-29T11:20:58Z" level=warning msg="Unable to retrieve in-cluster version of test1/system:image-builders: error getting resource from lister for rolebindings.authorization.openshift.io, test1/system:image-builders: rolebindings.authorization.openshift.io \"system:image-builders\" not found, object won't be restored by velero or have restore labels, and existing resource policy is not applied" logSource="pkg/restore/restore.go:1526" restore=velero/test-restore3
      time="2024-05-29T11:20:58Z" level=info msg="Restored 24 items out of an estimated total of 29 (estimate will change throughout the restore)" logSource="pkg/restore/restore.go:778" name="system:image-builders" namespace=test1 progress= resource=rolebindings.authorization.openshift.io restore=velero/test-restore3
      time="2024-05-29T11:20:58Z" level=info msg="restore status includes excludes: <nil>" logSource="pkg/restore/restore.go:1304" restore=velero/test-restore3
      time="2024-05-29T11:20:58Z" level=info msg="Executing item action for rolebindings.authorization.openshift.io" logSource="pkg/restore/restore.go:1318" restore=velero/test-restore3
      time="2024-05-29T11:20:58Z" level=info msg="[rolebinding-restore] Entering Role Bindings restore plugin" cmd=/plugins/velero-plugins logSource="/opt/app-root/src/github.com/konveyor/openshift-velero-plugin/velero-plugins/rolebindings/restore.go:28" pluginName=velero-plugins restore=velero/test-restore3
      time="2024-05-29T11:20:58Z" level=info msg="[rolebinding-restore] role binding - system:image-pullers, API version%!(EXTRA string=authorization.openshift.io/v1)" cmd=/plugins/velero-plugins logSource="/opt/app-root/src/github.com/konveyor/openshift-velero-plugin/velero-plugins/rolebindings/restore.go:34" pluginName=velero-plugins restore=velero/test-restore3
      time="2024-05-29T11:20:58Z" level=info msg="Attempting to restore RoleBinding: system:image-pullers" logSource="pkg/restore/restore.go:1487" restore=velero/test-restore3
      time="2024-05-29T11:20:58Z" level=warning msg="Unable to retrieve in-cluster version of test1/system:image-pullers: error getting resource from lister for rolebindings.authorization.openshift.io, test1/system:image-pullers: rolebindings.authorization.openshift.io \"system:image-pullers\" not found, object won't be restored by velero or have restore labels, and existing resource policy is not applied" logSource="pkg/restore/restore.go:1526" restore=velero/test-restore3
      time="2024-05-29T11:20:58Z" level=info msg="Restored 25 items out of an estimated total of 29 (estimate will change throughout the restore)" logSource="pkg/restore/restore.go:778" name="system:image-pullers" namespace=test1 progress= resource=rolebindings.authorization.openshift.io restore=velero/test-restore3
      time="2024-05-29T11:20:58Z" level=info msg="restore status includes excludes: <nil>" logSource="pkg/restore/restore.go:1304" restore=velero/test-restore3
      time="2024-05-29T11:20:58Z" level=info msg="Executing item action for rolebindings.rbac.authorization.k8s.io" logSource="pkg/restore/restore.go:1318" restore=velero/test-restore3
      time="2024-05-29T11:20:58Z" level=info msg="Getting client for rbac.authorization.k8s.io/v1, Kind=RoleBinding" logSource="pkg/restore/restore.go:1021" restore=velero/test-restore3
      time="2024-05-29T11:20:58Z" level=info msg="Attempting to restore RoleBinding: system:deployers" logSource="pkg/restore/restore.go:1487" restore=velero/test-restore3
      time="2024-05-29T11:20:58Z" level=info msg="Restored 26 items out of an estimated total of 29 (estimate will change throughout the restore)" logSource="pkg/restore/restore.go:778" name="system:deployers" namespace=test1 progress= resource=rolebindings.rbac.authorization.k8s.io restore=velero/test-restore3
      time="2024-05-29T11:20:58Z" level=info msg="restore status includes excludes: <nil>" logSource="pkg/restore/restore.go:1304" restore=velero/test-restore3
      time="2024-05-29T11:20:58Z" level=info msg="Executing item action for rolebindings.rbac.authorization.k8s.io" logSource="pkg/restore/restore.go:1318" restore=velero/test-restore3
      time="2024-05-29T11:20:58Z" level=info msg="Attempting to restore RoleBinding: system:image-builders" logSource="pkg/restore/restore.go:1487" restore=velero/test-restore3
      time="2024-05-29T11:20:58Z" level=info msg="Restored 27 items out of an estimated total of 29 (estimate will change throughout the restore)" logSource="pkg/restore/restore.go:778" name="system:image-builders" namespace=test1 progress= resource=rolebindings.rbac.authorization.k8s.io restore=velero/test-restore3
      time="2024-05-29T11:20:58Z" level=info msg="restore status includes excludes: <nil>" logSource="pkg/restore/restore.go:1304" restore=velero/test-restore3
      time="2024-05-29T11:20:58Z" level=info msg="Executing item action for rolebindings.rbac.authorization.k8s.io" logSource="pkg/restore/restore.go:1318" restore=velero/test-restore3
      time="2024-05-29T11:20:58Z" level=info msg="Attempting to restore RoleBinding: system:image-pullers" logSource="pkg/restore/restore.go:1487" restore=velero/test-restore3
      time="2024-05-29T11:20:58Z" level=info msg="Restored 28 items out of an estimated total of 29 (estimate will change throughout the restore)" logSource="pkg/restore/restore.go:778" name="system:image-pullers" namespace=test1 progress= resource=rolebindings.rbac.authorization.k8s.io restore=velero/test-restore3
      time="2024-05-29T11:20:58Z" level=info msg="Waiting for all pod volume restores to complete" logSource="pkg/restore/restore.go:656" restore=velero/test-restore3
      time="2024-05-29T11:20:58Z" level=info msg="Done waiting for all pod volume restores to complete" logSource="pkg/restore/restore.go:672" restore=velero/test-restore3
      time="2024-05-29T11:20:58Z" level=error msg="Namespace test1, resource restore error: error restoring imagestreams.image.openshift.io/test1/internal-image: ImageStream.image.openshift.io \"internal-image\" is invalid: []: Internal error: imagestreams \"internal-image\" is invalid: spec.tags[tag1].from.name: Invalid value: \"internal-image@sha256:3639d99a75ac279144a3565029a4f23e84482200e36a00849191afd78a1bd9b2\": error generating tag event: imagestreamimage.image.openshift.io \"sha256:3639d99a75ac279144a3565029a4f23e84482200e36a00849191afd78a1bd9b2\" not found" logSource="pkg/controller/restore_controller.go:591" restore=velero/test-restore3
      time="2024-05-29T11:20:58Z" level=warning msg="Cluster resource restore warning: could not restore, CustomResourceDefinition \"clusterserviceversions.operators.coreos.com\" already exists. Warning: the in-cluster version is different than the backed-up version" logSource="pkg/controller/restore_controller.go:598" restore=velero/test-restore3
      time="2024-05-29T11:20:58Z" level=warning msg="Namespace test1, resource restore warning: could not restore, ConfigMap \"kube-root-ca.crt\" already exists. Warning: the in-cluster version is different than the backed-up version" logSource="pkg/controller/restore_controller.go:602" restore=velero/test-restore3
      time="2024-05-29T11:20:58Z" level=warning msg="Namespace test1, resource restore warning: could not restore, ConfigMap \"openshift-service-ca.crt\" already exists. Warning: the in-cluster version is different than the backed-up version" logSource="pkg/controller/restore_controller.go:602" restore=velero/test-restore3
      time="2024-05-29T11:20:58Z" level=warning msg="Namespace test1, resource restore warning: error getting resource from lister for rolebindings.authorization.openshift.io, test1/system:deployers: rolebindings.authorization.openshift.io \"system:deployers\" not found" logSource="pkg/controller/restore_controller.go:602" restore=velero/test-restore3
      time="2024-05-29T11:20:58Z" level=warning msg="Namespace test1, resource restore warning: error getting resource from lister for rolebindings.authorization.openshift.io, test1/system:image-builders: rolebindings.authorization.openshift.io \"system:image-builders\" not found" logSource="pkg/controller/restore_controller.go:602" restore=velero/test-restore3
      time="2024-05-29T11:20:58Z" level=warning msg="Namespace test1, resource restore warning: error getting resource from lister for rolebindings.authorization.openshift.io, test1/system:image-pullers: rolebindings.authorization.openshift.io \"system:image-pullers\" not found" logSource="pkg/controller/restore_controller.go:602" restore=velero/test-restore3
      time="2024-05-29T11:20:58Z" level=warning msg="Namespace test1, resource restore warning: could not restore, RoleBinding \"system:deployers\" already exists. Warning: the in-cluster version is different than the backed-up version" logSource="pkg/controller/restore_controller.go:602" restore=velero/test-restore3
      time="2024-05-29T11:20:58Z" level=warning msg="Namespace test1, resource restore warning: could not restore, RoleBinding \"system:image-builders\" already exists. Warning: the in-cluster version is different than the backed-up version" logSource="pkg/controller/restore_controller.go:602" restore=velero/test-restore3
      time="2024-05-29T11:20:58Z" level=warning msg="Namespace test1, resource restore warning: could not restore, RoleBinding \"system:image-pullers\" already exists. Warning: the in-cluster version is different than the backed-up version" logSource="pkg/controller/restore_controller.go:602" restore=velero/test-restore3
      time="2024-05-29T11:20:58Z" level=info msg="restore completed" logSource="pkg/controller/restore_controller.go:605" restore=velero/test-restore3 

      Describe output. 

       

      $ velero describe restore  test-restore3 -n velero --details
      Name:         test-restore3
      Namespace:    velero
      Labels:       <none>
      Annotations:  <none>
      Phase:                       PartiallyFailed (run 'velero restore logs test-restore3' for more information)
      Total items to be restored:  28
      Items restored:              28
      Started:    2024-05-29 16:50:54 +0530 IST
      Completed:  2024-05-29 16:50:59 +0530 IST
      Warnings:
        Velero:     <none>
        Cluster:  could not restore, CustomResourceDefinition "clusterserviceversions.operators.coreos.com" already exists. Warning: the in-cluster version is different than the backed-up version
        Namespaces:
          test1:  could not restore, ConfigMap "kube-root-ca.crt" already exists. Warning: the in-cluster version is different than the backed-up version
                  could not restore, ConfigMap "openshift-service-ca.crt" already exists. Warning: the in-cluster version is different than the backed-up version
                  error getting resource from lister for rolebindings.authorization.openshift.io, test1/system:deployers: rolebindings.authorization.openshift.io "system:deployers" not found
                  error getting resource from lister for rolebindings.authorization.openshift.io, test1/system:image-builders: rolebindings.authorization.openshift.io "system:image-builders" not found
                  error getting resource from lister for rolebindings.authorization.openshift.io, test1/system:image-pullers: rolebindings.authorization.openshift.io "system:image-pullers" not found
                  could not restore, RoleBinding "system:deployers" already exists. Warning: the in-cluster version is different than the backed-up version
                  could not restore, RoleBinding "system:image-builders" already exists. Warning: the in-cluster version is different than the backed-up version
                  could not restore, RoleBinding "system:image-pullers" already exists. Warning: the in-cluster version is different than the backed-up version
      Errors:
        Velero:     <none>
        Cluster:    <none>
        Namespaces:
          test1:  error restoring imagestreams.image.openshift.io/test1/internal-image: ImageStream.image.openshift.io "internal-image" is invalid: []: Internal error: imagestreams "internal-image" is invalid: spec.tags[tag1].from.name: Invalid value: "internal-image@sha256:3639d99a75ac279144a3565029a4f23e84482200e36a00849191afd78a1bd9b2": error generating tag event: imagestreamimage.image.openshift.io "sha256:3639d99a75ac279144a3565029a4f23e84482200e36a00849191afd78a1bd9b2" not found
      Backup:  test-backup3
      Namespaces:
        Included:  all namespaces found in the backup
        Excluded:  <none>
      Resources:
        Included:        *
        Excluded:        nodes, events, events.events.k8s.io, backups.velero.io, restores.velero.io, resticrepositories.velero.io, csinodes.storage.k8s.io, volumeattachments.storage.k8s.io, backuprepositories.velero.io
        Cluster-scoped:  auto
      Namespace mappings:  <none>
      Label selector:  <none>
      Or label selector:  <none>
      Restore PVs:  auto
      Existing Resource Policy:   <none>
      ItemOperationTimeout:       4h0m0s
      Preserve Service NodePorts:  auto
      Resource List:
        apiextensions.k8s.io/v1/CustomResourceDefinition:
          - clusterserviceversions.operators.coreos.com(failed)
        authorization.openshift.io/v1/RoleBinding:
          - test1/system:deployers(failed)
          - test1/system:image-builders(failed)
          - test1/system:image-pullers(failed)
        image.openshift.io/v1/ImageStream:
          - test1/internal-image(failed)
        image.openshift.io/v1/ImageStreamTag:
          - test1/internal-image:alp(created)
          - test1/internal-image:tag1(created)
        image.openshift.io/v1/ImageTag:
          - test1/internal-image:alp(skipped)
          - test1/internal-image:tag1(skipped)
        operators.coreos.com/v1alpha1/ClusterServiceVersion:
          - test1/volsync-product.v0.9.1(created)
        rbac.authorization.k8s.io/v1/RoleBinding:
          - test1/system:deployers(failed)
          - test1/system:image-builders(failed)
          - test1/system:image-pullers(failed)
        v1/ConfigMap:
          - test1/kube-root-ca.crt(failed)
          - test1/openshift-service-ca.crt(failed)
        v1/Namespace:
          - test1(created)
        v1/Secret:
          - test1/builder-dockercfg-7nm7k(created)
          - test1/builder-dockercfg-zmlww(created)
          - test1/builder-token-jvkh8(skipped)
          - test1/default-dockercfg-s9vth(created)
          - test1/default-dockercfg-vfkz7(created)
          - test1/default-token-8k4mq(skipped)
          - test1/deployer-dockercfg-8dkt8(created)
          - test1/deployer-dockercfg-njnd6(created)
          - test1/deployer-token-ggkm7(skipped)
        v1/ServiceAccount:
          - test1/builder(skipped)
          - test1/default(skipped)
          - test1/deployer(skipped)
      

       

       

              wnstb Wes Hayutin
              rhn-support-prajoshi Prasad Joshi
              Prasad Joshi Prasad Joshi
              Votes:
              0 Vote for this issue
              Watchers:
              3 Start watching this issue

                Created:
                Updated:
                Resolved: