Bug 2083256
| Summary: | SSP Reconcile logging improvement when CR resources are changed | |||
|---|---|---|---|---|
| Product: | Container Native Virtualization (CNV) | Reporter: | Geetika Kapoor <gkapoor> | |
| Component: | SSP | Assignee: | Andrej Krejcir <akrejcir> | |
| Status: | CLOSED ERRATA | QA Contact: | Geetika Kapoor <gkapoor> | |
| Severity: | unspecified | Docs Contact: | ||
| Priority: | unspecified | |||
| Version: | 4.11.0 | CC: | akrejcir, cnv-qe-bugs, ksimon, mocohen | |
| Target Milestone: | --- | |||
| Target Release: | 4.11.0 | |||
| Hardware: | Unspecified | |||
| OS: | Unspecified | |||
| Whiteboard: | ||||
| Fixed In Version: | kubevirt-ssp-operator-container-v4.11.0-36 | Doc Type: | If docs needed, set a value | |
| Doc Text: | Story Points: | --- | ||
| Clone Of: | ||||
| : | 2089658 (view as bug list) | Environment: | ||
| Last Closed: | 2022-09-14 19:32:24 UTC | Type: | Bug | |
| Regression: | --- | Mount Type: | --- | |
| Documentation: | --- | CRM: | ||
| Verified Versions: | Category: | --- | ||
| oVirt Team: | --- | RHEL 7.3 requirements from Atomic Host: | ||
| Cloudforms Team: | --- | Target Upstream Version: | ||
| Embargoed: | ||||
| Bug Depends On: | ||||
| Bug Blocks: | 2089658 | |||
We set the default log level to "info", because "debug" was too verbose: Bug 2037421 The "debug" level can be enabled using the command line parameter "--zap-log-level debug" in the ssp operator deployment. But probably HCO or OLM will revert this change. $ oc get csv -n openshift-cnv
NAME DISPLAY VERSION REPLACES PHASE
jaeger-operator.v1.30.1-0.1648511826.p Red Hat OpenShift distributed tracing platform 1.30.1+0.1648511826.p Succeeded
kiali-operator.v1.48.0 Kiali Operator 1.48.0 kiali-operator.v1.36.10 Succeeded
kubevirt-hyperconverged-operator.v4.11.0 OpenShift Virtualization 4.11.0 kubevirt-hyperconverged-operator.v4.10.2 Succeeded
servicemeshoperator.v2.2.0 Red Hat OpenShift Service Mesh 2.2.0-0 servicemeshoperator.v2.1.3 Succeeded
Logging shows extra logging messages
{"level":"info","ts":1656603209.7133174,"logger":"controllers.SSP","msg":"Reconciliation event received","ssp":{"namespace":"openshift-cnv","name":"ssp-kubevirt-hyperconverged"},"cause_type":"DataImportCron","cause_object":"openshift-virtualization-os-images/dic-non-existing-source"}
{"level":"info","ts":1656603209.7133625,"logger":"controllers.SSP","msg":"Starting reconciliation","ssp":"openshift-cnv/ssp-kubevirt-hyperconverged"}
{"level":"info","ts":1656603209.723232,"logger":"controllers.SSP","msg":"Reconciling operands...","ssp":"openshift-cnv/ssp-kubevirt-hyperconverged"}
{"level":"info","ts":1656603210.7501254,"logger":"controllers.SSP","msg":"CR status updated","ssp":"openshift-cnv/ssp-kubevirt-hyperconverged"}
{"level":"info","ts":1656603249.705958,"logger":"controllers.SSP","msg":"Reconciliation event received","ssp":{"namespace":"openshift-cnv","name":"ssp-kubevirt-hyperconverged"},"cause_type":"DataImportCron","cause_object":"openshift-virtualization-os-images/dic-non-existing-source"}
{"level":"info","ts":1656603249.7060814,"logger":"controllers.SSP","msg":"Reconciliation event received","ssp":{"namespace":"openshift-cnv","name":"ssp-kubevirt-hyperconverged"},"cause_type":"DataImportCron","cause_object":"openshift-virtualization-os-images/dic-non-existing-source"}
{"level":"info","ts":1656603249.7061138,"logger":"controllers.SSP","msg":"Starting reconciliation","ssp":"openshift-cnv/ssp-kubevirt-hyperconverged"}
{"level":"info","ts":1656603249.717128,"logger":"controllers.SSP","msg":"Reconciling operands...","ssp":"openshift-cnv/ssp-kubevirt-hyperconverged"}
{"level":"info","ts":1656603250.589064,"logger":"controllers.SSP","msg":"CR status updated","ssp":"openshift-cnv/ssp-kubevirt-hyperconverged"}
{"level":"info","ts":1656603257.9466932,"logger":"ssp-resource","msg":"validate update","name":"ssp-kubevirt-hyperconverged"}
{"level":"info","ts":1656603257.9694862,"logger":"ssp-resource","msg":"validate update","name":"ssp-kubevirt-hyperconverged"}
{"level":"info","ts":1656603257.973504,"logger":"controllers.SSP","msg":"Starting reconciliation","ssp":"openshift-cnv/ssp-kubevirt-hyperconverged"}
{"level":"info","ts":1656603257.9816644,"logger":"controllers.SSP","msg":"Reconciling operands...","ssp":"openshift-cnv/ssp-kubevirt-hyperconverged"}
Since the problem described in this bug report should be resolved in a recent advisory, it has been closed with a resolution of ERRATA. For information on the advisory (Important: OpenShift Virtualization 4.11.0 Images security and bug fix update), and where to find the updated files, follow the link below. If the solution does not work for you, open a new bug report. https://access.redhat.com/errata/RHSA-2022:6526 |
Description of problem: Better logging is needed for SSP 'ssps.ssp.kubevirt.io' in case SSP reconcile. Current logger don't show why reconcile happens and what changes in CR. Changes which let reconcile to happen is good to have and it will help to identify product issues and if reconcile should actually happen. Current logs: {"level":"info","ts":1652102869.4633186,"logger":"controller.ssp","msg":"Starting workers","reconciler group":"ssp.kubevirt.io","reconciler kind":"SSP","worker count":1} {"level":"info","ts":1652102869.4998937,"logger":"ssp-resource","msg":"validate update","name":"ssp-kubevirt-hyperconverged"} {"level":"error","ts":1652102870.4478307,"logger":"controller.ssp","msg":"Reconciler error","reconciler group":"ssp.kubevirt.io","reconciler kind":"SSP","name":"ssp-kubevirt-hyperconverged","namespace":"openshift-cnv","error":"Operation cannot be fulfilled on ssps.ssp.kubevirt.io \"ssp-kubevirt-hyperconverged\": the object has been modified; please apply your changes to the latest version and try again","stacktrace":"sigs.k8s.io/controller-runtime/pkg/internal/controller.(*Controller).processNextWorkItem\n\t/remote-source/app/vendor/sigs.k8s.io/controller-runtime/pkg/internal/controller/controller.go:266\nsigs.k8s.io/controller-runtime/pkg/internal/controller.(*Controller).Start.func2.2\n\t/remote-source/app/vendor/sigs.k8s.io/controller-runtime/pkg/internal/controller/controller.go:227"} Version-Release number of selected component (if applicable): 4.10.1 How reproducible: always. Devops team is seeing most of the times Steps to Reproduce: 1. Running automation tests show these errors 2. 3. Actual results: Expected results: Additional info: