Uploaded image for project: 'OpenShift Bugs'
  1. OpenShift Bugs
  2. OCPBUGS-62964

[Boxcutter] There are too many reconcile logs

XMLWordPrintable

    • Quality / Stability / Reliability
    • False
    • Hide

      None

      Show
      None
    • None
    • None
    • None
    • None
    • None
    • Rejected
    • None
    • None
    • None
    • None
    • None
    • None
    • None
    • None

      Description of problem:

      There are two many reconcile logs 

      Version-Release number of selected component (if applicable):

         4.21.0-0.nightly-2025-10-07-171402

      How reproducible:

          always

      Steps to Reproduce:

      
1. open boxcutter feature  and install clusterextension

xzha@xzha1-mac ~ % oc get clusterextension
NAME              INSTALLED BUNDLE    VERSION   INSTALLED   PROGRESSING   AGE
extension-85187   nginx85187.v1.0.7   1.0.7     True        True          162m

     2. check operator-controller-controller-manager log 

1011 08:43:47.421762       1 clusterextensionrevision_controller.go:101] "reconcile ending" logger="cluster-extension-revision" controller="clusterextensionrevision" controllerGroup="olm.operatorframework.io" controllerKind="ClusterExtensionRevision" ClusterExtensionRevision="extension-85187-1" namespace="" name="extension-85187-1" reconcileID="634b8a14-31a1-41b2-b6ac-f48fd3a728b4"
I1011 08:43:47.769846       1 cache.go:104] "image already unpacked" logger="cluster-extension" controller="controller-operator-cluster-extension-controller" controllerGroup="olm.operatorframework.io" controllerKind="ClusterExtension" ClusterExtension="extension-85187" namespace="" name="extension-85187" reconcileID="37191ac4-d406-4ac0-8707-bc10ed522d37" ref="quay.io/openshifttest/nginxolm-operator-bundle:v1.0.0-nginxolm85187" digest="sha256:393ad16ee6ba429e001c48bf8c9fa27738ddeb9e47f0633deaa6487bb25603e1"
I1011 08:43:47.769886       1 clusterextension_controller.go:290] "applying bundle contents" logger="cluster-extension" controller="controller-operator-cluster-extension-controller" controllerGroup="olm.operatorframework.io" controllerKind="ClusterExtension" ClusterExtension="extension-85187" namespace="" name="extension-85187" reconcileID="37191ac4-d406-4ac0-8707-bc10ed522d37"
I1011 08:43:47.786640       1 clusterextension_controller.go:139] "reconcile ending" logger="cluster-extension" controller="controller-operator-cluster-extension-controller" controllerGroup="olm.operatorframework.io" controllerKind="ClusterExtension" ClusterExtension="extension-85187" namespace="" name="extension-85187" reconcileID="37191ac4-d406-4ac0-8707-bc10ed522d37"
I1011 08:43:47.786718       1 clusterextension_controller.go:105] "reconcile starting" logger="cluster-extension" controller="controller-operator-cluster-extension-controller" controllerGroup="olm.operatorframework.io" controllerKind="ClusterExtension" ClusterExtension="extension-85187" namespace="" name="extension-85187" reconcileID="bece7135-095e-4bfb-9a60-bbf0cfc2e938"
I1011 08:43:47.786753       1 clusterextension_controller.go:186] "handling finalizers" logger="cluster-extension" controller="controller-operator-cluster-extension-controller" controllerGroup="olm.operatorframework.io" controllerKind="ClusterExtension" ClusterExtension="extension-85187" namespace="" name="extension-85187" reconcileID="bece7135-095e-4bfb-9a60-bbf0cfc2e938"
I1011 08:43:47.786880       1 clusterextension_controller.go:217] "getting installed bundle" logger="cluster-extension" controller="controller-operator-cluster-extension-controller" controllerGroup="olm.operatorframework.io" controllerKind="ClusterExtension" ClusterExtension="extension-85187" namespace="" name="extension-85187" reconcileID="bece7135-095e-4bfb-9a60-bbf0cfc2e938"
I1011 08:43:47.786968       1 clusterextension_controller.go:234] "resolving bundle" logger="cluster-extension" controller="controller-operator-cluster-extension-controller" controllerGroup="olm.operatorframework.io" controllerKind="ClusterExtension" ClusterExtension="extension-85187" namespace="" name="extension-85187" reconcileID="bece7135-095e-4bfb-9a60-bbf0cfc2e938"
I1011 08:43:47.787067       1 catalog.go:286] "using ClusterCatalogs for resolution" logger="cluster-extension" controller="controller-operator-cluster-extension-controller" controllerGroup="olm.operatorframework.io" controllerKind="ClusterExtension" ClusterExtension="extension-85187" namespace="" name="extension-85187" reconcileID="bece7135-095e-4bfb-9a60-bbf0cfc2e938" catalogs=["catalog-75515","openshift-certified-operators","openshift-community-operators","openshift-redhat-marketplace","openshift-redhat-operators","catalog-85187"]
I1011 08:43:47.792167       1 catalog.go:207] "resolution succeeded" logger="cluster-extension" controller="controller-operator-cluster-extension-controller" controllerGroup="olm.operatorframework.io" controllerKind="ClusterExtension" ClusterExtension="extension-85187" namespace="" name="extension-85187" reconcileID="bece7135-095e-4bfb-9a60-bbf0cfc2e938" stats=[{"catalogName":"catalog-75515","packageFound":false,"totalBundles":0,"matchedBundles":0},{"catalogName":"openshift-certified-operators","packageFound":false,"totalBundles":0,"matchedBundles":0},{"catalogName":"openshift-community-operators","packageFound":false,"totalBundles":0,"matchedBundles":0},{"catalogName":"openshift-redhat-marketplace","packageFound":false,"totalBundles":0,"matchedBundles":0},{"catalogName":"openshift-redhat-operators","packageFound":false,"totalBundles":0,"matchedBundles":0},{"catalogName":"catalog-85187","packageFound":true,"totalBundles":8,"matchedBundles":1}]
I1011 08:43:47.792192       1 clusterextension_controller.go:272] "unpacking resolved bundle" logger="cluster-extension" controller="controller-operator-cluster-extension-controller" controllerGroup="olm.operatorframework.io" controllerKind="ClusterExtension" ClusterExtension="extension-85187" namespace="" name="extension-85187" reconcileID="bece7135-095e-4bfb-9a60-bbf0cfc2e938"
I1011 08:43:47.792211       1 main.go:366] "using available authentication information for pulling image" logger="cluster-extension" controller="controller-operator-cluster-extension-controller" controllerGroup="olm.operatorframework.io" controllerKind="ClusterExtension" ClusterExtension="extension-85187" namespace="" name="extension-85187" reconcileID="bece7135-095e-4bfb-9a60-bbf0cfc2e938"
I1011 08:43:48.444618       1 cache.go:104] "image already unpacked" logger="cluster-extension" controller="controller-operator-cluster-extension-controller" controllerGroup="olm.operatorframework.io" controllerKind="ClusterExtension" ClusterExtension="extension-85187" namespace="" name="extension-85187" reconcileID="bece7135-095e-4bfb-9a60-bbf0cfc2e938" ref="quay.io/openshifttest/nginxolm-operator-bundle:v1.0.0-nginxolm85187" digest="sha256:393ad16ee6ba429e001c48bf8c9fa27738ddeb9e47f0633deaa6487bb25603e1"
I1011 08:43:48.444657       1 clusterextension_controller.go:290] "applying bundle contents" logger="cluster-extension" controller="controller-operator-cluster-extension-controller" controllerGroup="olm.operatorframework.io" controllerKind="ClusterExtension" ClusterExtension="extension-85187" namespace="" name="extension-85187" reconcileID="bece7135-095e-4bfb-9a60-bbf0cfc2e938"
I1011 08:43:48.452040       1 clusterextension_controller.go:139] "reconcile ending" logger="cluster-extension" controller="controller-operator-cluster-extension-controller" controllerGroup="olm.operatorframework.io" controllerKind="ClusterExtension" ClusterExtension="extension-85187" namespace="" name="extension-85187" reconcileID="bece7135-095e-4bfb-9a60-bbf0cfc2e938"
I1011 08:44:07.263345       1 clusterextension_controller.go:105] "reconcile starting" logger="cluster-extension" controller="controller-operator-cluster-extension-controller" controllerGroup="olm.operatorframework.io" controllerKind="ClusterExtension" ClusterExtension="extension-85187" namespace="" name="extension-85187" reconcileID="68952119-819a-4d53-b187-4bd09b57c7f5"
I1011 08:44:07.263380       1 clusterextension_controller.go:186] "handling finalizers" logger="cluster-extension" controller="controller-operator-cluster-extension-controller" controllerGroup="olm.operatorframework.io" controllerKind="ClusterExtension" ClusterExtension="extension-85187" namespace="" name="extension-85187" reconcileID="68952119-819a-4d53-b187-4bd09b57c7f5"
I1011 08:44:07.263468       1 clusterextension_controller.go:217] "getting installed bundle" logger="cluster-extension" controller="controller-operator-cluster-extension-controller" controllerGroup="olm.operatorframework.io" controllerKind="ClusterExtension" ClusterExtension="extension-85187" namespace="" name="extension-85187" reconcileID="68952119-819a-4d53-b187-4bd09b57c7f5"
I1011 08:44:07.263539       1 clusterextension_controller.go:234] "resolving bundle" logger="cluster-extension" controller="controller-operator-cluster-extension-controller" controllerGroup="olm.operatorframework.io" controllerKind="ClusterExtension" ClusterExtension="extension-85187" namespace="" name="extension-85187" reconcileID="68952119-819a-4d53-b187-4bd09b57c7f5"
I1011 08:44:07.263613       1 catalog.go:286] "using ClusterCatalogs for resolution" logger="cluster-extension" controller="controller-operator-cluster-extension-controller" controllerGroup="olm.operatorframework.io" controllerKind="ClusterExtension" ClusterExtension="extension-85187" namespace="" name="extension-85187" reconcileID="68952119-819a-4d53-b187-4bd09b57c7f5" catalogs=["catalog-75515","openshift-certified-operators","openshift-community-operators","openshift-redhat-marketplace","openshift-redhat-operators","catalog-85187"]
I1011 08:44:07.268368       1 catalog.go:207] "resolution succeeded" logger="cluster-extension" controller="controller-operator-cluster-extension-controller" controllerGroup="olm.operatorframework.io" controllerKind="ClusterExtension" ClusterExtension="extension-85187" namespace="" name="extension-85187" reconcileID="68952119-819a-4d53-b187-4bd09b57c7f5" stats=[{"catalogName":"catalog-75515","packageFound":false,"totalBundles":0,"matchedBundles":0},{"catalogName":"openshift-certified-operators","packageFound":false,"totalBundles":0,"matchedBundles":0},{"catalogName":"openshift-community-operators","packageFound":false,"totalBundles":0,"matchedBundles":0},{"catalogName":"openshift-redhat-marketplace","packageFound":false,"totalBundles":0,"matchedBundles":0},{"catalogName":"openshift-redhat-operators","packageFound":false,"totalBundles":0,"matchedBundles":0},{"catalogName":"catalog-85187","packageFound":true,"totalBundles":8,"matchedBundles":1}]
I1011 08:44:07.268392       1 clusterextension_controller.go:272] "unpacking resolved bundle" logger="cluster-extension" controller="controller-operator-cluster-extension-controller" controllerGroup="olm.operatorframework.io" controllerKind="ClusterExtension" ClusterExtension="extension-85187" namespace="" name="extension-85187" reconcileID="68952119-819a-4d53-b187-4bd09b57c7f5"
I1011 08:44:07.268413       1 main.go:366] "using available authentication information for pulling image" logger="cluster-extension" controller="controller-operator-cluster-extension-controller" controllerGroup="olm.operatorframework.io" controllerKind="ClusterExtension" ClusterExtension="extension-85187" namespace="" name="extension-85187" reconcileID="68952119-819a-4d53-b187-4bd09b57c7f5"
I1011 08:44:08.003680       1 cache.go:104] "image already unpacked" logger="cluster-extension" controller="controller-operator-cluster-extension-controller" controllerGroup="olm.operatorframework.io" controllerKind="ClusterExtension" ClusterExtension="extension-85187" namespace="" name="extension-85187" reconcileID="68952119-819a-4d53-b187-4bd09b57c7f5" ref="quay.io/openshifttest/nginxolm-operator-bundle:v1.0.0-nginxolm85187" digest="sha256:393ad16ee6ba429e001c48bf8c9fa27738ddeb9e47f0633deaa6487bb25603e1"
I1011 08:44:08.003731       1 clusterextension_controller.go:290] "applying bundle contents" logger="cluster-extension" controller="controller-operator-cluster-extension-controller" controllerGroup="olm.operatorframework.io" controllerKind="ClusterExtension" ClusterExtension="extension-85187" namespace="" name="extension-85187" reconcileID="68952119-819a-4d53-b187-4bd09b57c7f5"
I1011 08:44:08.011583       1 clusterextension_controller.go:139] "reconcile ending" logger="cluster-extension" controller="controller-operator-cluster-extension-controller" controllerGroup="olm.operatorframework.io" controllerKind="ClusterExtension" ClusterExtension="extension-85187" namespace="" name="extension-85187" reconcileID="68952119-819a-4d53-b187-4bd09b57c7f5"
I1011 08:44:44.209309       1 reflector.go:979] "Watch close" logger="controller-runtime.cache" reflector="pkg/cache/internal/informers.go:114" type="*v1.Secret" totalItems=6
I1011 08:46:33.210295       1 reflector.go:979] "Watch close" logger="controller-runtime.cache" reflector="pkg/cache/internal/informers.go:114" type="*v1.PartialObjectMetadata" totalItems=13
I1011 08:46:42.930361       1 clusterextension_controller.go:105] "reconcile starting" logger="cluster-extension" controller="controller-operator-cluster-extension-controller" controllerGroup="olm.operatorframework.io" controllerKind="ClusterExtension" ClusterExtension="extension-85187" namespace="" name="extension-85187" reconcileID="c9ab4340-0186-4896-9dc2-1bda5c9301eb"
I1011 08:46:42.930406       1 clusterextension_controller.go:186] "handling finalizers" logger="cluster-extension" controller="controller-operator-cluster-extension-controller" controllerGroup="olm.operatorframework.io" controllerKind="ClusterExtension" ClusterExtension="extension-85187" namespace="" name="extension-85187" reconcileID="c9ab4340-0186-4896-9dc2-1bda5c9301eb"
I1011 08:46:42.930533       1 clusterextension_controller.go:217] "getting installed bundle" logger="cluster-extension" controller="controller-operator-cluster-extension-controller" controllerGroup="olm.operatorframework.io" controllerKind="ClusterExtension" ClusterExtension="extension-85187" namespace="" name="extension-85187" reconcileID="c9ab4340-0186-4896-9dc2-1bda5c9301eb"
I1011 08:46:42.930605       1 clusterextension_controller.go:234] "resolving bundle" logger="cluster-extension" controller="controller-operator-cluster-extension-controller" controllerGroup="olm.operatorframework.io" controllerKind="ClusterExtension" ClusterExtension="extension-85187" namespace="" name="extension-85187" reconcileID="c9ab4340-0186-4896-9dc2-1bda5c9301eb"
I1011 08:46:42.930682       1 catalog.go:286] "using ClusterCatalogs for resolution" logger="cluster-extension" controller="controller-operator-cluster-extension-controller" controllerGroup="olm.operatorframework.io" controllerKind="ClusterExtension" ClusterExtension="extension-85187" namespace="" name="extension-85187" reconcileID="c9ab4340-0186-4896-9dc2-1bda5c9301eb" catalogs=["openshift-community-operators","openshift-redhat-marketplace","openshift-redhat-operators","catalog-85187","catalog-75515","openshift-certified-operators"]
I1011 08:46:42.935475       1 catalog.go:207] "resolution succeeded" logger="cluster-extension" controller="controller-operator-cluster-extension-controller" controllerGroup="olm.operatorframework.io" controllerKind="ClusterExtension" ClusterExtension="extension-85187" namespace="" name="extension-85187" reconcileID="c9ab4340-0186-4896-9dc2-1bda5c9301eb" stats=[{"catalogName":"openshift-community-operators","packageFound":false,"totalBundles":0,"matchedBundles":0},{"catalogName":"openshift-redhat-marketplace","packageFound":false,"totalBundles":0,"matchedBundles":0},{"catalogName":"openshift-redhat-operators","packageFound":false,"totalBundles":0,"matchedBundles":0},{"catalogName":"catalog-85187","packageFound":true,"totalBundles":8,"matchedBundles":1},{"catalogName":"catalog-75515","packageFound":false,"totalBundles":0,"matchedBundles":0},{"catalogName":"openshift-certified-operators","packageFound":false,"totalBundles":0,"matchedBundles":0}]
I1011 08:46:42.935504       1 clusterextension_controller.go:272] "unpacking resolved bundle" logger="cluster-extension" controller="controller-operator-cluster-extension-controller" controllerGroup="olm.operatorframework.io" controllerKind="ClusterExtension" ClusterExtension="extension-85187" namespace="" name="extension-85187" reconcileID="c9ab4340-0186-4896-9dc2-1bda5c9301eb"
I1011 08:46:42.935535       1 main.go:366] "using available authentication information for pulling image" logger="cluster-extension" controller="controller-operator-cluster-extension-controller" controllerGroup="olm.operatorframework.io" controllerKind="ClusterExtension" ClusterExtension="extension-85187" namespace="" name="extension-85187" reconcileID="c9ab4340-0186-4896-9dc2-1bda5c9301eb"
I1011 08:46:44.749702       1 pull.go:161] "pulled image" logger="cluster-extension" controller="controller-operator-cluster-extension-controller" controllerGroup="olm.operatorframework.io" controllerKind="ClusterExtension" ClusterExtension="extension-85187" namespace="" name="extension-85187" reconcileID="c9ab4340-0186-4896-9dc2-1bda5c9301eb" ref="quay.io/openshifttest/nginxolm-operator-bundle:v1.0.1-nginxolm85187" digest="sha256:2f2c1911b2c9e0f60805363bc69b538556f8112c7c6968ce498f89b347a808b2"
I1011 08:46:44.750102       1 cache.go:167] "unpacking image" logger="cluster-extension" controller="controller-operator-cluster-extension-controller" controllerGroup="olm.operatorframework.io" controllerKind="ClusterExtension" ClusterExtension="extension-85187" namespace="" name="extension-85187" reconcileID="c9ab4340-0186-4896-9dc2-1bda5c9301eb" ref="quay.io/openshifttest/nginxolm-operator-bundle:v1.0.1-nginxolm85187" digest="sha256:2f2c1911b2c9e0f60805363bc69b538556f8112c7c6968ce498f89b347a808b2" path="/var/cache/unpack/extension-85187/sha256:2f2c1911b2c9e0f60805363bc69b538556f8112c7c6968ce498f89b347a808b2"
I1011 08:46:44.754392       1 cache.go:176] "applied layer" logger="cluster-extension" controller="controller-operator-cluster-extension-controller" controllerGroup="olm.operatorframework.io" controllerKind="ClusterExtension" ClusterExtension="extension-85187" namespace="" name="extension-85187" reconcileID="c9ab4340-0186-4896-9dc2-1bda5c9301eb" ref="quay.io/openshifttest/nginxolm-operator-bundle:v1.0.1-nginxolm85187" digest="sha256:2f2c1911b2c9e0f60805363bc69b538556f8112c7c6968ce498f89b347a808b2" layer=0
I1011 08:46:44.759315       1 cache.go:176] "applied layer" logger="cluster-extension" controller="controller-operator-cluster-extension-controller" controllerGroup="olm.operatorframework.io" controllerKind="ClusterExtension" ClusterExtension="extension-85187" namespace="" name="extension-85187" reconcileID="c9ab4340-0186-4896-9dc2-1bda5c9301eb" ref="quay.io/openshifttest/nginxolm-operator-bundle:v1.0.1-nginxolm85187" digest="sha256:2f2c1911b2c9e0f60805363bc69b538556f8112c7c6968ce498f89b347a808b2" layer=1
I1011 08:46:44.763525       1 cache.go:176] "applied layer" logger="cluster-extension" controller="controller-operator-cluster-extension-controller" controllerGroup="olm.operatorframework.io" controllerKind="ClusterExtension" ClusterExtension="extension-85187" namespace="" name="extension-85187" reconcileID="c9ab4340-0186-4896-9dc2-1bda5c9301eb" ref="quay.io/openshifttest/nginxolm-operator-bundle:v1.0.1-nginxolm85187" digest="sha256:2f2c1911b2c9e0f60805363bc69b538556f8112c7c6968ce498f89b347a808b2" layer=2
I1011 08:46:44.764538       1 clusterextension_controller.go:290] "applying bundle contents" logger="cluster-extension" controller="controller-operator-cluster-extension-controller" controllerGroup="olm.operatorframework.io" controllerKind="ClusterExtension" ClusterExtension="extension-85187" namespace="" name="extension-85187" reconcileID="c9ab4340-0186-4896-9dc2-1bda5c9301eb"
I1011 08:46:44.787722       1 clusterextensionrevision_controller.go:77] "reconcile starting" logger="cluster-extension-revision" controller="clusterextensionrevision" controllerGroup="olm.operatorframework.io" controllerKind="ClusterExtensionRevision" ClusterExtensionRevision="extension-85187-2" namespace="" name="extension-85187-2" reconcileID="c86f47a8-b331-459d-a7f5-cc8e2c5966fd"
I1011 08:46:44.798103       1 clusterextension_controller.go:139] "reconcile ending" logger="cluster-extension" controller="controller-operator-cluster-extension-controller" controllerGroup="olm.operatorframework.io" controllerKind="ClusterExtension" ClusterExtension="extension-85187" namespace="" name="extension-85187" reconcileID="c9ab4340-0186-4896-9dc2-1bda5c9301eb"
I1011 08:46:44.798180       1 clusterextension_controller.go:105] "reconcile starting" logger="cluster-extension" controller="controller-operator-cluster-extension-controller" controllerGroup="olm.operatorframework.io" controllerKind="ClusterExtension" ClusterExtension="extension-85187" namespace="" name="extension-85187" reconcileID="fd626c06-90e4-4438-a989-5f5731787991"
I1011 08:46:44.798245       1 clusterextension_controller.go:186] "handling finalizers" logger="cluster-extension" controller="controller-operator-cluster-extension-controller" controllerGroup="olm.operatorframework.io" controllerKind="ClusterExtension" ClusterExtension="extension-85187" namespace="" name="extension-85187" reconcileID="fd626c06-90e4-4438-a989-5f5731787991"
I1011 08:46:44.798483       1 clusterextension_controller.go:217] "getting installed bundle" logger="cluster-extension" controller="controller-operator-cluster-extension-controller" controllerGroup="olm.operatorframework.io" controllerKind="ClusterExtension" ClusterExtension="extension-85187" namespace="" name="extension-85187" reconcileID="fd626c06-90e4-4438-a989-5f5731787991"
I1011 08:46:44.798662       1 clusterextension_controller.go:272] "unpacking resolved bundle" logger="cluster-extension" controller="controller-operator-cluster-extension-controller" controllerGroup="olm.operatorframework.io" controllerKind="ClusterExtension" ClusterExtension="extension-85187" namespace="" name="extension-85187" reconcileID="fd626c06-90e4-4438-a989-5f5731787991"
I1011 08:46:44.798689       1 main.go:366] "using available authentication information for pulling image" logger="cluster-extension" controller="controller-operator-cluster-extension-controller" controllerGroup="olm.operatorframework.io" controllerKind="ClusterExtension" ClusterExtension="extension-85187" namespace="" name="extension-85187" reconcileID="fd626c06-90e4-4438-a989-5f5731787991"
I1011 08:46:45.239480       1 clusterextensionrevision_controller.go:205] "reconcile report" logger="cluster-extension-revision" controller="clusterextensionrevision" controllerGroup="olm.operatorframework.io" controllerKind="ClusterExtensionRevision" ClusterExtensionRevision="extension-85187-2" namespace="" name="extension-85187-2" reconcileID="c86f47a8-b331-459d-a7f5-cc8e2c5966fd" report=<
	Revision
	Complete: false
	In Transition: false
	Phases:
	- Phase "rbac"
	  Complete: true
	  In Transition: false
	  Objects:
	  - Object ServiceAccount.v1 ns-85187/nginx85187-controller-manager
	    Action: "Updated"
	    Probes:
	    - Progress: Succeeded
	    Other:
	    - "openshift.io/image-registry-pull-secrets_service-account-controller"
	      .imagePullSecrets     3.

      Actual results:

      Expected results:

      Additional info:

              rh-ee-cchantse Catherine Chan-Tse
              rhn-support-xzha Xia Zhao
              None
              None
              Xia Zhao Xia Zhao
              None
              Votes:
              0 Vote for this issue
              Watchers:
              3 Start watching this issue

                Created:
                Updated: