-
Bug
-
Resolution: Not a Bug
-
Critical
-
None
-
False
-
-
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)