Questions on Ansible operator proxy's working

76 views
Skip to first unread message

Harihara Narayanan B

unread,
Feb 11, 2021, 5:10:22 PM2/11/21
to Operator Framework
Hello, 

I'm using the SDK to write an Ansible operator and have few questions related to the Proxy. 

When I read the operator pod's logs, there are multiple lines for each task that hits the Kubernetes API server, proxy logs "Skipping cache lookup". It also seems to be time consuming to do several cache lookup for different objects for every call to the API server, making the reconciliation loop run longer. 

I looked but couldn't find documentation on the Proxy. Could someone please explain more details on the Proxy - 
    - Can we stop it from doing the cache lookup, or is that counter productive and how? 
    - Can we reduce its logging? It does make the operator pod's logs quite long/busy to read. 
    - Please let me know if these are documented elsewhere that I couldn't find
 
Thank you,

regards,
Hari

Fabian von Feilitzsch

unread,
Feb 12, 2021, 11:45:44 AM2/12/21
to Harihara Narayanan B, Operator Framework
On Thu, Feb 11, 2021 at 5:10 PM Harihara Narayanan B <nhar...@gmail.com> wrote:
Hello, 

I'm using the SDK to write an Ansible operator and have few questions related to the Proxy. 

When I read the operator pod's logs, there are multiple lines for each task that hits the Kubernetes API server, proxy logs "Skipping cache lookup". It also seems to be time consuming to do several cache lookup for different objects for every call to the API server, making the reconciliation loop run longer.

I looked but couldn't find documentation on the Proxy. Could someone please explain more details on the Proxy - 
    - Can we stop it from doing the cache lookup, or is that counter productive and how? 
 
The cache should be much faster than actually reaching out to the API server, and it prevents operators from spamming the API server with requests unnecessarily. If anything the cache should increase the speed of the reconciliation loop. It's possible the modules themselves are issuing unnecessary requests, but that wouldn't be the responsibility of the proxy to fix.

    - Can we reduce its logging? It does make the operator pod's logs quite long/busy to read. 

I am surprised that the cache is putting out so many logs, that could indicate that something is going wrong (or that we do need to decrease the verbosity if not). Would you mind posting some of your operator logs so  I can better see what's going on?

    - Please let me know if these are documented elsewhere that I couldn't find
 

We could definitely use some better documentation of what exactly the proxy is doing. I think this is the only block we have on it specifically (https://sdk.operatorframework.io/docs/building-operators/ansible/reference/information-flow-ansible-operator/#the-proxy), with tidbits spread around the rest of the docs. Some consolidation and additional clarity is definitely needed there.

Thank you,

regards,
Hari

--
You received this message because you are subscribed to the Google Groups "Operator Framework" group.
To unsubscribe from this group and stop receiving emails from it, send an email to operator-framew...@googlegroups.com.
To view this discussion on the web visit https://groups.google.com/d/msgid/operator-framework/5ef622fb-2311-46c0-bb50-4bc515f817d5n%40googlegroups.com.


--

Fabian von Feilitzsch

Senior Software Engineer, OpenShift Engineering

Red Hat

fab...@redhat.com    IM: fabianvf

Harihara Narayanan B

unread,
Feb 12, 2021, 2:39:34 PM2/12/21
to Operator Framework
Hi, 

Thank you for looking at this and sharing the insights. 

Here are the logs from two tasks that reach the API server. 

Task #1: This task gets the list of all Services in a given namespace. There is more than 50 lines in the log for each task. 

Code: 
- name: get service names
  community.kubernetes.k8s_info:
    api_version: v1
    kind: Service
    namespace: "{{ ansible_operator_meta.namespace }}"
  register: services_res

Logs: 

TASK [get-kubernetes-resources : get service names] ****************************
task path: /roles/get-kubernetes-resources/tasks/main.yml:27

-------------------------------------------------------------------------------
2021-02-12T14:16:35.554-0500 INFO proxy Skipping cache lookup {"resource": {"IsResourceRequest":false,"Path":"/version","Verb":"get","APIPrefix":"","APIGroup":"","APIVersion":"","Namespace":"","Resource":"","Subresource":"","Name":"","Parts":null}}
2021-02-12T14:16:35.600-0500 INFO proxy Skipping cache lookup {"resource": {"IsResourceRequest":false,"Path":"/version/openshift","Verb":"get","APIPrefix":"","APIGroup":"","APIVersion":"","Namespace":"","Resource":"","Subresource":"","Name":"","Parts":null}}
2021-02-12T14:16:35.645-0500 INFO proxy Skipping cache lookup {"resource": {"IsResourceRequest":false,"Path":"/apis","Verb":"get","APIPrefix":"","APIGroup":"","APIVersion":"","Namespace":"","Resource":"","Subresource":"","Name":"","Parts":null}}
2021-02-12T14:16:35.739-0500 INFO proxy Skipping cache lookup {"resource": {"IsResourceRequest":false,"Path":"/api/v1","Verb":"get","APIPrefix":"","APIGroup":"","APIVersion":"","Namespace":"","Resource":"","Subresource":"","Name":"","Parts":null}}
2021-02-12T14:16:35.788-0500 INFO proxy Skipping cache lookup {"resource": {"IsResourceRequest":false,"Path":"/apis/apiregistration.k8s.io/v1","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","Resource":"","Subresource":"","Name":"","Parts":null}}
2021-02-12T14:16:35.834-0500 INFO proxy Skipping cache lookup {"resource": {"IsResourceRequest":false,"Path":"/apis/apps/v1","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","Resource":"","Subresource":"","Name":"","Parts":null}}
2021-02-12T14:16:35.879-0500 INFO proxy Skipping cache lookup {"resource": {"IsResourceRequest":false,"Path":"/apis/events.k8s.io/v1","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","Resource":"","Subresource":"","Name":"","Parts":null}}
2021-02-12T14:16:35.924-0500 INFO proxy Skipping cache lookup {"resource": {"IsResourceRequest":false,"Path":"/apis/authentication.k8s.io/v1","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","Resource":"","Subresource":"","Name":"","Parts":null}}
2021-02-12T14:16:35.973-0500 INFO proxy Skipping cache lookup {"resource": {"IsResourceRequest":false,"Path":"/apis/authorization.k8s.io/v1","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","Resource":"","Subresource":"","Name":"","Parts":null}}
2021-02-12T14:16:36.019-0500 INFO proxy Skipping cache lookup {"resource": {"IsResourceRequest":false,"Path":"/apis/autoscaling/v1","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","Resource":"","Subresource":"","Name":"","Parts":null}}
2021-02-12T14:16:36.067-0500 INFO proxy Skipping cache lookup {"resource": {"IsResourceRequest":false,"Path":"/apis/batch/v1","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","Resource":"","Subresource":"","Name":"","Parts":null}}
2021-02-12T14:16:36.112-0500 INFO proxy Skipping cache lookup {"resource": {"IsResourceRequest":false,"Path":"/apis/certificates.k8s.io/v1","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","Resource":"","Subresource":"","Name":"","Parts":null}}
2021-02-12T14:16:36.158-0500 INFO proxy Skipping cache lookup {"resource": {"IsResourceRequest":false,"Path":"/apis/networking.k8s.io/v1","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","Resource":"","Subresource":"","Name":"","Parts":null}}
2021-02-12T14:16:36.214-0500 INFO proxy Skipping cache lookup {"resource": {"IsResourceRequest":false,"Path":"/apis/rbac.authorization.k8s.io/v1","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","Resource":"","Subresource":"","Name":"","Parts":null}}
2021-02-12T14:16:36.262-0500 INFO proxy Skipping cache lookup {"resource": {"IsResourceRequest":false,"Path":"/apis/storage.k8s.io/v1","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","Resource":"","Subresource":"","Name":"","Parts":null}}
2021-02-12T14:16:36.307-0500 INFO proxy Skipping cache lookup {"resource": {"IsResourceRequest":false,"Path":"/apis/admissionregistration.k8s.io/v1","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","Resource":"","Subresource":"","Name":"","Parts":null}}
2021-02-12T14:16:36.351-0500 INFO proxy Skipping cache lookup {"resource": {"IsResourceRequest":false,"Path":"/apis/apiextensions.k8s.io/v1","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","Resource":"","Subresource":"","Name":"","Parts":null}}
2021-02-12T14:16:36.400-0500 INFO proxy Skipping cache lookup {"resource": {"IsResourceRequest":false,"Path":"/apis/scheduling.k8s.io/v1","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","Resource":"","Subresource":"","Name":"","Parts":null}}
2021-02-12T14:16:36.448-0500 INFO proxy Skipping cache lookup {"resource": {"IsResourceRequest":false,"Path":"/apis/coordination.k8s.io/v1","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","Resource":"","Subresource":"","Name":"","Parts":null}}
2021-02-12T14:16:36.492-0500 INFO proxy Skipping cache lookup {"resource": {"IsResourceRequest":false,"Path":"/apis/apps.openshift.io/v1","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","Resource":"","Subresource":"","Name":"","Parts":null}}
2021-02-12T14:16:36.542-0500 INFO proxy Skipping cache lookup {"resource": {"IsResourceRequest":false,"Path":"/apis/authorization.openshift.io/v1","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","Resource":"","Subresource":"","Name":"","Parts":null}}
2021-02-12T14:16:36.592-0500 INFO proxy Skipping cache lookup {"resource": {"IsResourceRequest":false,"Path":"/apis/build.openshift.io/v1","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","Resource":"","Subresource":"","Name":"","Parts":null}}
2021-02-12T14:16:36.638-0500 INFO proxy Skipping cache lookup {"resource": {"IsResourceRequest":false,"Path":"/apis/image.openshift.io/v1","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","Resource":"","Subresource":"","Name":"","Parts":null}}
2021-02-12T14:16:36.686-0500 INFO proxy Skipping cache lookup {"resource": {"IsResourceRequest":false,"Path":"/apis/oauth.openshift.io/v1","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","Resource":"","Subresource":"","Name":"","Parts":null}}
2021-02-12T14:16:36.741-0500 INFO proxy Skipping cache lookup {"resource": {"IsResourceRequest":false,"Path":"/apis/project.openshift.io/v1","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","Resource":"","Subresource":"","Name":"","Parts":null}}
2021-02-12T14:16:36.791-0500 INFO proxy Skipping cache lookup {"resource": {"IsResourceRequest":false,"Path":"/apis/quota.openshift.io/v1","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","Resource":"","Subresource":"","Name":"","Parts":null}}
2021-02-12T14:16:36.841-0500 INFO proxy Skipping cache lookup {"resource": {"IsResourceRequest":false,"Path":"/apis/route.openshift.io/v1","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","Resource":"","Subresource":"","Name":"","Parts":null}}
2021-02-12T14:16:36.891-0500 INFO proxy Skipping cache lookup {"resource": {"IsResourceRequest":false,"Path":"/apis/security.openshift.io/v1","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","Resource":"","Subresource":"","Name":"","Parts":null}}
2021-02-12T14:16:36.957-0500 INFO proxy Skipping cache lookup {"resource": {"IsResourceRequest":false,"Path":"/apis/template.openshift.io/v1","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","Resource":"","Subresource":"","Name":"","Parts":null}}
2021-02-12T14:16:37.013-0500 INFO proxy Skipping cache lookup {"resource": {"IsResourceRequest":false,"Path":"/apis/user.openshift.io/v1","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","Resource":"","Subresource":"","Name":"","Parts":null}}
2021-02-12T14:16:37.085-0500 INFO proxy Skipping cache lookup {"resource": {"IsResourceRequest":false,"Path":"/apis/packages.operators.coreos.com/v1","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","Resource":"","Subresource":"","Name":"","Parts":null}}
2021-02-12T14:16:37.135-0500 INFO proxy Skipping cache lookup {"resource": {"IsResourceRequest":false,"Path":"/apis/config.openshift.io/v1","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","Resource":"","Subresource":"","Name":"","Parts":null}}
2021-02-12T14:16:37.179-0500 INFO proxy Skipping cache lookup {"resource": {"IsResourceRequest":false,"Path":"/apis/operator.openshift.io/v1","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","Resource":"","Subresource":"","Name":"","Parts":null}}
2021-02-12T14:16:37.227-0500 INFO proxy Skipping cache lookup {"resource": {"IsResourceRequest":false,"Path":"/apis/autoscaling.openshift.io/v1","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","Resource":"","Subresource":"","Name":"","Parts":null}}
2021-02-12T14:16:37.273-0500 INFO proxy Skipping cache lookup {"resource": {"IsResourceRequest":false,"Path":"/apis/ceph.rook.io/v1","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","Resource":"","Subresource":"","Name":"","Parts":null}}
2021-02-12T14:16:37.321-0500 INFO proxy Skipping cache lookup {"resource": {"IsResourceRequest":false,"Path":"/apis/cloudcredential.openshift.io/v1","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","Resource":"","Subresource":"","Name":"","Parts":null}}
2021-02-12T14:16:37.366-0500 INFO proxy Skipping cache lookup {"resource": {"IsResourceRequest":false,"Path":"/apis/clusterhealth.ibm.com/v1","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","Resource":"","Subresource":"","Name":"","Parts":null}}
2021-02-12T14:16:37.408-0500 INFO proxy Skipping cache lookup {"resource": {"IsResourceRequest":false,"Path":"/apis/console.openshift.io/v1","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","Resource":"","Subresource":"","Name":"","Parts":null}}
2021-02-12T14:16:37.457-0500 INFO proxy Skipping cache lookup {"resource": {"IsResourceRequest":false,"Path":"/apis/foundation.ibm.com/v1","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","Resource":"","Subresource":"","Name":"","Parts":null}}
2021-02-12T14:16:37.503-0500 INFO proxy Skipping cache lookup {"resource": {"IsResourceRequest":false,"Path":"/apis/hbstrider.ibm.com/v1","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","Resource":"","Subresource":"","Name":"","Parts":null}}
2021-02-12T14:16:37.549-0500 INFO proxy Skipping cache lookup {"resource": {"IsResourceRequest":false,"Path":"/apis/ibmcpcs.ibm.com/v1","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","Resource":"","Subresource":"","Name":"","Parts":null}}
2021-02-12T14:16:37.600-0500 INFO proxy Skipping cache lookup {"resource": {"IsResourceRequest":false,"Path":"/apis/imageregistry.operator.openshift.io/v1","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","Resource":"","Subresource":"","Name":"","Parts":null}}
2021-02-12T14:16:37.655-0500 INFO proxy Skipping cache lookup {"resource": {"IsResourceRequest":false,"Path":"/apis/ingress.operator.openshift.io/v1","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","Resource":"","Subresource":"","Name":"","Parts":null}}
2021-02-12T14:16:37.699-0500 INFO proxy Skipping cache lookup {"resource": {"IsResourceRequest":false,"Path":"/apis/k8s.cni.cncf.io/v1","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","Resource":"","Subresource":"","Name":"","Parts":null}}
2021-02-12T14:16:37.745-0500 INFO proxy Skipping cache lookup {"resource": {"IsResourceRequest":false,"Path":"/apis/machineconfiguration.openshift.io/v1","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","Resource":"","Subresource":"","Name":"","Parts":null}}
2021-02-12T14:16:37.788-0500 INFO proxy Skipping cache lookup {"resource": {"IsResourceRequest":false,"Path":"/apis/metering.ibm.com/v1","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","Resource":"","Subresource":"","Name":"","Parts":null}}
2021-02-12T14:16:37.836-0500 INFO proxy Skipping cache lookup {"resource": {"IsResourceRequest":false,"Path":"/apis/monitoring.coreos.com/v1","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","Resource":"","Subresource":"","Name":"","Parts":null}}
2021-02-12T14:16:37.881-0500 INFO proxy Skipping cache lookup {"resource": {"IsResourceRequest":false,"Path":"/apis/monitoringcontroller.cloud.ibm.com/v1","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","Resource":"","Subresource":"","Name":"","Parts":null}}
2021-02-12T14:16:37.925-0500 INFO proxy Skipping cache lookup {"resource": {"IsResourceRequest":false,"Path":"/apis/network.openshift.io/v1","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","Resource":"","Subresource":"","Name":"","Parts":null}}
2021-02-12T14:16:38.002-0500 INFO proxy Skipping cache lookup {"resource": {"IsResourceRequest":false,"Path":"/apis/network.operator.openshift.io/v1","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","Resource":"","Subresource":"","Name":"","Parts":null}}
2021-02-12T14:16:38.048-0500 INFO proxy Skipping cache lookup {"resource": {"IsResourceRequest":false,"Path":"/apis/oidc.security.ibm.com/v1","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","Resource":"","Subresource":"","Name":"","Parts":null}}
2021-02-12T14:16:38.096-0500 INFO proxy Skipping cache lookup {"resource": {"IsResourceRequest":false,"Path":"/apis/operator.ibm.com/v1","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","Resource":"","Subresource":"","Name":"","Parts":null}}
2021-02-12T14:16:38.141-0500 INFO proxy Skipping cache lookup {"resource": {"IsResourceRequest":false,"Path":"/apis/operators.coreos.com/v1","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","Resource":"","Subresource":"","Name":"","Parts":null}}
2021-02-12T14:16:38.197-0500 INFO proxy Skipping cache lookup {"resource": {"IsResourceRequest":false,"Path":"/apis/redis.databases.cloud.ibm.com/v1","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","Resource":"","Subresource":"","Name":"","Parts":null}}
2021-02-12T14:16:38.239-0500 INFO proxy Skipping cache lookup {"resource": {"IsResourceRequest":false,"Path":"/apis/samples.operator.openshift.io/v1","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","Resource":"","Subresource":"","Name":"","Parts":null}}
2021-02-12T14:16:38.281-0500 INFO proxy Skipping cache lookup {"resource": {"IsResourceRequest":false,"Path":"/apis/security.internal.openshift.io/v1","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","Resource":"","Subresource":"","Name":"","Parts":null}}
2021-02-12T14:16:38.328-0500 INFO proxy Skipping cache lookup {"resource": {"IsResourceRequest":false,"Path":"/apis/tuned.openshift.io/v1","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","Resource":"","Subresource":"","Name":"","Parts":null}}
2021-02-12T14:16:38.501-0500 INFO proxy Read object from cache {"resource": {"IsResourceRequest":true,"Path":"/api/v1/namespaces/hb-test/services","Verb":"list","APIPrefix":"api","APIGroup":"","APIVersion":"v1","Namespace":"hb-test","Resource":"services","Subresource":"","Name":"","Parts":["services"]}}


Task #2: This task involves creating a ConfigMap from a Jinja2 template. 

TASK [Create events configMap_serviceEndpoints.yaml.j2] **************
task path: /roles/events/tasks/3.0.0/main.yml:30

-------------------------------------------------------------------------------
2021-02-12T14:20:18.484-0500 INFO proxy Skipping cache lookup {"resource": {"IsResourceRequest":false,"Path":"/version","Verb":"get","APIPrefix":"","APIGroup":"","APIVersion":"","Namespace":"","Resource":"","Subresource":"","Name":"","Parts":null}}
2021-02-12T14:20:18.530-0500 INFO proxy Skipping cache lookup {"resource": {"IsResourceRequest":false,"Path":"/version/openshift","Verb":"get","APIPrefix":"","APIGroup":"","APIVersion":"","Namespace":"","Resource":"","Subresource":"","Name":"","Parts":null}}
2021-02-12T14:20:18.574-0500 INFO proxy Skipping cache lookup {"resource": {"IsResourceRequest":false,"Path":"/apis","Verb":"get","APIPrefix":"","APIGroup":"","APIVersion":"","Namespace":"","Resource":"","Subresource":"","Name":"","Parts":null}}
2021-02-12T14:20:18.669-0500 INFO proxy Skipping cache lookup {"resource": {"IsResourceRequest":false,"Path":"/api/v1","Verb":"get","APIPrefix":"","APIGroup":"","APIVersion":"","Namespace":"","Resource":"","Subresource":"","Name":"","Parts":null}}
2021-02-12T14:20:18.715-0500 INFO proxy Skipping cache lookup {"resource": {"IsResourceRequest":false,"Path":"/apis/apiregistration.k8s.io/v1","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","Resource":"","Subresource":"","Name":"","Parts":null}}
2021-02-12T14:20:18.760-0500 INFO proxy Skipping cache lookup {"resource": {"IsResourceRequest":false,"Path":"/apis/apps/v1","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","Resource":"","Subresource":"","Name":"","Parts":null}}
2021-02-12T14:20:18.807-0500 INFO proxy Skipping cache lookup {"resource": {"IsResourceRequest":false,"Path":"/apis/events.k8s.io/v1","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","Resource":"","Subresource":"","Name":"","Parts":null}}
2021-02-12T14:20:18.852-0500 INFO proxy Skipping cache lookup {"resource": {"IsResourceRequest":false,"Path":"/apis/authentication.k8s.io/v1","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","Resource":"","Subresource":"","Name":"","Parts":null}}
2021-02-12T14:20:18.901-0500 INFO proxy Skipping cache lookup {"resource": {"IsResourceRequest":false,"Path":"/apis/authorization.k8s.io/v1","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","Resource":"","Subresource":"","Name":"","Parts":null}}
2021-02-12T14:20:18.954-0500 INFO proxy Skipping cache lookup {"resource": {"IsResourceRequest":false,"Path":"/apis/autoscaling/v1","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","Resource":"","Subresource":"","Name":"","Parts":null}}
2021-02-12T14:20:19.001-0500 INFO proxy Skipping cache lookup {"resource": {"IsResourceRequest":false,"Path":"/apis/batch/v1","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","Resource":"","Subresource":"","Name":"","Parts":null}}
2021-02-12T14:20:19.050-0500 INFO proxy Skipping cache lookup {"resource": {"IsResourceRequest":false,"Path":"/apis/certificates.k8s.io/v1","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","Resource":"","Subresource":"","Name":"","Parts":null}}
2021-02-12T14:20:19.097-0500 INFO proxy Skipping cache lookup {"resource": {"IsResourceRequest":false,"Path":"/apis/networking.k8s.io/v1","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","Resource":"","Subresource":"","Name":"","Parts":null}}
2021-02-12T14:20:19.143-0500 INFO proxy Skipping cache lookup {"resource": {"IsResourceRequest":false,"Path":"/apis/rbac.authorization.k8s.io/v1","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","Resource":"","Subresource":"","Name":"","Parts":null}}
2021-02-12T14:20:19.192-0500 INFO proxy Skipping cache lookup {"resource": {"IsResourceRequest":false,"Path":"/apis/storage.k8s.io/v1","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","Resource":"","Subresource":"","Name":"","Parts":null}}
2021-02-12T14:20:19.234-0500 INFO proxy Skipping cache lookup {"resource": {"IsResourceRequest":false,"Path":"/apis/admissionregistration.k8s.io/v1","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","Resource":"","Subresource":"","Name":"","Parts":null}}
2021-02-12T14:20:19.280-0500 INFO proxy Skipping cache lookup {"resource": {"IsResourceRequest":false,"Path":"/apis/apiextensions.k8s.io/v1","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","Resource":"","Subresource":"","Name":"","Parts":null}}
2021-02-12T14:20:19.334-0500 INFO proxy Skipping cache lookup {"resource": {"IsResourceRequest":false,"Path":"/apis/scheduling.k8s.io/v1","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","Resource":"","Subresource":"","Name":"","Parts":null}}
2021-02-12T14:20:19.379-0500 INFO proxy Skipping cache lookup {"resource": {"IsResourceRequest":false,"Path":"/apis/coordination.k8s.io/v1","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","Resource":"","Subresource":"","Name":"","Parts":null}}
2021-02-12T14:20:19.434-0500 INFO proxy Skipping cache lookup {"resource": {"IsResourceRequest":false,"Path":"/apis/apps.openshift.io/v1","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","Resource":"","Subresource":"","Name":"","Parts":null}}
2021-02-12T14:20:19.493-0500 INFO proxy Skipping cache lookup {"resource": {"IsResourceRequest":false,"Path":"/apis/authorization.openshift.io/v1","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","Resource":"","Subresource":"","Name":"","Parts":null}}
2021-02-12T14:20:19.545-0500 INFO proxy Skipping cache lookup {"resource": {"IsResourceRequest":false,"Path":"/apis/build.openshift.io/v1","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","Resource":"","Subresource":"","Name":"","Parts":null}}
2021-02-12T14:20:19.600-0500 INFO proxy Skipping cache lookup {"resource": {"IsResourceRequest":false,"Path":"/apis/image.openshift.io/v1","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","Resource":"","Subresource":"","Name":"","Parts":null}}
2021-02-12T14:20:19.661-0500 INFO proxy Skipping cache lookup {"resource": {"IsResourceRequest":false,"Path":"/apis/oauth.openshift.io/v1","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","Resource":"","Subresource":"","Name":"","Parts":null}}
2021-02-12T14:20:19.723-0500 INFO proxy Skipping cache lookup {"resource": {"IsResourceRequest":false,"Path":"/apis/project.openshift.io/v1","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","Resource":"","Subresource":"","Name":"","Parts":null}}
2021-02-12T14:20:19.778-0500 INFO proxy Skipping cache lookup {"resource": {"IsResourceRequest":false,"Path":"/apis/quota.openshift.io/v1","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","Resource":"","Subresource":"","Name":"","Parts":null}}
2021-02-12T14:20:19.824-0500 INFO proxy Skipping cache lookup {"resource": {"IsResourceRequest":false,"Path":"/apis/route.openshift.io/v1","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","Resource":"","Subresource":"","Name":"","Parts":null}}
2021-02-12T14:20:19.874-0500 INFO proxy Skipping cache lookup {"resource": {"IsResourceRequest":false,"Path":"/apis/security.openshift.io/v1","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","Resource":"","Subresource":"","Name":"","Parts":null}}
2021-02-12T14:20:19.922-0500 INFO proxy Skipping cache lookup {"resource": {"IsResourceRequest":false,"Path":"/apis/template.openshift.io/v1","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","Resource":"","Subresource":"","Name":"","Parts":null}}
2021-02-12T14:20:19.980-0500 INFO proxy Skipping cache lookup {"resource": {"IsResourceRequest":false,"Path":"/apis/user.openshift.io/v1","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","Resource":"","Subresource":"","Name":"","Parts":null}}
2021-02-12T14:20:20.028-0500 INFO proxy Skipping cache lookup {"resource": {"IsResourceRequest":false,"Path":"/apis/packages.operators.coreos.com/v1","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","Resource":"","Subresource":"","Name":"","Parts":null}}
2021-02-12T14:20:20.079-0500 INFO proxy Skipping cache lookup {"resource": {"IsResourceRequest":false,"Path":"/apis/config.openshift.io/v1","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","Resource":"","Subresource":"","Name":"","Parts":null}}
2021-02-12T14:20:20.130-0500 INFO proxy Skipping cache lookup {"resource": {"IsResourceRequest":false,"Path":"/apis/operator.openshift.io/v1","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","Resource":"","Subresource":"","Name":"","Parts":null}}
2021-02-12T14:20:20.176-0500 INFO proxy Skipping cache lookup {"resource": {"IsResourceRequest":false,"Path":"/apis/autoscaling.openshift.io/v1","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","Resource":"","Subresource":"","Name":"","Parts":null}}
2021-02-12T14:20:20.223-0500 INFO proxy Skipping cache lookup {"resource": {"IsResourceRequest":false,"Path":"/apis/ceph.rook.io/v1","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","Resource":"","Subresource":"","Name":"","Parts":null}}
2021-02-12T14:20:20.269-0500 INFO proxy Skipping cache lookup {"resource": {"IsResourceRequest":false,"Path":"/apis/cloudcredential.openshift.io/v1","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","Resource":"","Subresource":"","Name":"","Parts":null}}
2021-02-12T14:20:20.314-0500 INFO proxy Skipping cache lookup {"resource": {"IsResourceRequest":false,"Path":"/apis/clusterhealth.ibm.com/v1","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","Resource":"","Subresource":"","Name":"","Parts":null}}
2021-02-12T14:20:20.359-0500 INFO proxy Skipping cache lookup {"resource": {"IsResourceRequest":false,"Path":"/apis/console.openshift.io/v1","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","Resource":"","Subresource":"","Name":"","Parts":null}}
2021-02-12T14:20:20.402-0500 INFO proxy Skipping cache lookup {"resource": {"IsResourceRequest":false,"Path":"/apis/foundation.ibm.com/v1","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","Resource":"","Subresource":"","Name":"","Parts":null}}
2021-02-12T14:20:20.448-0500 INFO proxy Skipping cache lookup {"resource": {"IsResourceRequest":false,"Path":"/apis/hbstrider.ibm.com/v1","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","Resource":"","Subresource":"","Name":"","Parts":null}}
2021-02-12T14:20:20.497-0500 INFO proxy Skipping cache lookup {"resource": {"IsResourceRequest":false,"Path":"/apis/ibmcpcs.ibm.com/v1","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","Resource":"","Subresource":"","Name":"","Parts":null}}
2021-02-12T14:20:20.541-0500 INFO proxy Skipping cache lookup {"resource": {"IsResourceRequest":false,"Path":"/apis/imageregistry.operator.openshift.io/v1","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","Resource":"","Subresource":"","Name":"","Parts":null}}
2021-02-12T14:20:20.589-0500 INFO proxy Skipping cache lookup {"resource": {"IsResourceRequest":false,"Path":"/apis/ingress.operator.openshift.io/v1","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","Resource":"","Subresource":"","Name":"","Parts":null}}
2021-02-12T14:20:20.635-0500 INFO proxy Skipping cache lookup {"resource": {"IsResourceRequest":false,"Path":"/apis/k8s.cni.cncf.io/v1","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","Resource":"","Subresource":"","Name":"","Parts":null}}
2021-02-12T14:20:20.682-0500 INFO proxy Skipping cache lookup {"resource": {"IsResourceRequest":false,"Path":"/apis/machineconfiguration.openshift.io/v1","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","Resource":"","Subresource":"","Name":"","Parts":null}}
2021-02-12T14:20:20.729-0500 INFO proxy Skipping cache lookup {"resource": {"IsResourceRequest":false,"Path":"/apis/metering.ibm.com/v1","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","Resource":"","Subresource":"","Name":"","Parts":null}}
2021-02-12T14:20:20.783-0500 INFO proxy Skipping cache lookup {"resource": {"IsResourceRequest":false,"Path":"/apis/monitoring.coreos.com/v1","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","Resource":"","Subresource":"","Name":"","Parts":null}}
2021-02-12T14:20:20.827-0500 INFO proxy Skipping cache lookup {"resource": {"IsResourceRequest":false,"Path":"/apis/monitoringcontroller.cloud.ibm.com/v1","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","Resource":"","Subresource":"","Name":"","Parts":null}}
2021-02-12T14:20:20.868-0500 INFO proxy Skipping cache lookup {"resource": {"IsResourceRequest":false,"Path":"/apis/network.openshift.io/v1","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","Resource":"","Subresource":"","Name":"","Parts":null}}
2021-02-12T14:20:20.914-0500 INFO proxy Skipping cache lookup {"resource": {"IsResourceRequest":false,"Path":"/apis/network.operator.openshift.io/v1","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","Resource":"","Subresource":"","Name":"","Parts":null}}
2021-02-12T14:20:20.958-0500 INFO proxy Skipping cache lookup {"resource": {"IsResourceRequest":false,"Path":"/apis/oidc.security.ibm.com/v1","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","Resource":"","Subresource":"","Name":"","Parts":null}}
2021-02-12T14:20:21.005-0500 INFO proxy Skipping cache lookup {"resource": {"IsResourceRequest":false,"Path":"/apis/operator.ibm.com/v1","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","Resource":"","Subresource":"","Name":"","Parts":null}}
2021-02-12T14:20:21.052-0500 INFO proxy Skipping cache lookup {"resource": {"IsResourceRequest":false,"Path":"/apis/operators.coreos.com/v1","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","Resource":"","Subresource":"","Name":"","Parts":null}}
2021-02-12T14:20:21.105-0500 INFO proxy Skipping cache lookup {"resource": {"IsResourceRequest":false,"Path":"/apis/redis.databases.cloud.ibm.com/v1","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","Resource":"","Subresource":"","Name":"","Parts":null}}
2021-02-12T14:20:21.155-0500 INFO proxy Skipping cache lookup {"resource": {"IsResourceRequest":false,"Path":"/apis/samples.operator.openshift.io/v1","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","Resource":"","Subresource":"","Name":"","Parts":null}}
2021-02-12T14:20:21.198-0500 INFO proxy Skipping cache lookup {"resource": {"IsResourceRequest":false,"Path":"/apis/security.internal.openshift.io/v1","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","Resource":"","Subresource":"","Name":"","Parts":null}}
2021-02-12T14:20:21.241-0500 INFO proxy Skipping cache lookup {"resource": {"IsResourceRequest":false,"Path":"/apis/tuned.openshift.io/v1","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","Resource":"","Subresource":"","Name":"","Parts":null}}
2021-02-12T14:20:21.313-0500 INFO proxy Read object from cache {"resource": {"IsResourceRequest":true,"Path":"/api/v1/namespaces/hb-test/configmaps/gi-sample-events-svc-env","Verb":"get","APIPrefix":"api","APIGroup":"","APIVersion":"v1","Namespace":"hb-test","Resource":"configmaps","Subresource":"","Name":"gi-sample-events-svc-env","Parts":["configmaps","gi-sample-events-svc-env"]}}
2021-02-12T14:20:21.476-0500 INFO logging_event_handler [playbook task] {"name": "gi-sample", "namespace": "hb-test", "gvk": "hbstrider.ibm.com/v1, Kind=GuardiumInsights", "event_type": "playbook_on_task_start", "job": "6426100070888298971", "EventData.Name": "Create events configMap.yaml.j2"}

regards,
Hari

Camila Macedo

unread,
Feb 12, 2021, 2:52:54 PM2/12/21
to Harihara Narayanan B, Operator Framework
Hi @Harihara, 

I am not sure if you are the author. However, has an open PR to change the log level to debug in this case which shows to solve your problem. See: https://github.com/operator-framework/operator-sdk/pull/4511

Cheers, 

CAMILA MACEDO

SR. SOFTWARE ENGINEER 

RED HAT Operator framework

Red Hat UK

She / Her / Hers

IM: cmacedo

Phone: +44 7853500035





To view this discussion on the web visit https://groups.google.com/d/msgid/operator-framework/12a9a578-99a1-4efc-a2ae-7ada40f2c73an%40googlegroups.com.

Fabian von Feilitzsch

unread,
Feb 12, 2021, 3:10:48 PM2/12/21
to Harihara Narayanan B, Operator Framework
Oh yeah that's definitely a bug, those are API discovery requests which are issued by the dynamic client to determine what APIs the cluster supports. They should only be issued a single time (on the first use of the module) and then cached for quite a while, if you're seeing that more than one time then something is up. Would you mind filing an issue with the above log as well as the ansible-operator base image you are using? This is behavior I would only expect on very old versions of the Python client (before we supported caching).
 

Harihara Narayanan B

unread,
Feb 12, 2021, 5:55:53 PM2/12/21
to Operator Framework
Hi,


Thank you! 

regards,
Hari

Harihara Narayanan B

unread,
Feb 12, 2021, 5:58:10 PM2/12/21
to Operator Framework
Hi, 

No, am not the author on that PR. Thank you for sharing it, I will follow the progress. 

regards,
Hari

jesus m. rodriguez

unread,
Feb 16, 2021, 11:49:14 PM2/16/21
to Harihara Narayanan B, Operator Framework
Hari,

I verified that PR #4511 fixes the issue. This change will be released with the
Operator SDK v1.5.0.

Before this change:

$ grep "Skipping cache" good.log | wc -l
48

With the above PR

$ grep "Skipping cache" 20210216-bad.log | wc -l
0

Sincerely,
jesus

--
jesus m. rodriguez          | jes...@redhat.com
principal software engineer | irc: zeus
red hat operator sdk   | 919.754.4413 (w)
rhce # 805008586930012      | 919.623.0080 (c)
+---------------------------------------------+
|   "Those who cannot remember the past       |
|    are condemned to repeat it."             |
|                        -- George Santayana  |
+---------------------------------------------+



On Fri, 2021-02-12 at 14:58 -0800, Harihara Narayanan B wrote:
> Hi,
>
> No, am not the author on that PR. Thank you for sharing it, I will follow
> the progress.
>
> regards,
> Hari
>
> On Friday, February 12, 2021 at 2:52:54 PM UTC-5 cma...@redhat.com wrote:
>
> > Hi @Harihara,
> >
> > I am not sure if you are the author. However, has an open PR to change the
> > log level to debug in this case which shows to solve your problem. See:
> > https://github.com/operator-framework/operator-sdk/pull/4511
> >
> > Cheers,
> >
> > CAMILA MACEDO
> >
> > SR. SOFTWARE ENGINEER
> >
> > RED HAT Operator framework
> >
> > Red Hat UK <https://www.redhat.com/>
> >
> > She / Her / Hers
> >
> > IM: cmacedo
> >
> > Phone: +44 7853500035 <+44%207853%20500035>
> > <https://red.ht/sig>
> > > esource":"","Subresource":"","Name":"","Parts":null}}
> > > 2021-02-12T14:16:37.655-0500 INFO proxy Skipping cache lookup {"resource":
> > > {"IsResourceRequest":false,"Path":"/apis/ingress.operator.openshift.io/v1
> > > ","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","R
> > > esource":"","Subresource":"","Name":"","Parts":null}}
> > > 2021-02-12T14:16:37.699-0500 INFO proxy Skipping cache lookup {"resource":
> > > {"IsResourceRequest":false,"Path":"/apis/k8s.cni.cncf.io/v1
> > > ","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","R
> > > esource":"","Subresource":"","Name":"","Parts":null}}
> > > 2021-02-12T14:16:37.745-0500 INFO proxy Skipping cache lookup {"resource":
> > > {"IsResourceRequest":false,"Path":"/apis/
> > > machineconfiguration.openshift.io/v1
> > > ","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","R
> > > esource":"","Subresource":"","Name":"","Parts":null}}
> > > 2021-02-12T14:16:37.788-0500 INFO proxy Skipping cache lookup {"resource":
> > > {"IsResourceRequest":false,"Path":"/apis/metering.ibm.com/v1
> > > ","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","R
> > > esource":"","Subresource":"","Name":"","Parts":null}}
> > > 2021-02-12T14:16:37.836-0500 INFO proxy Skipping cache lookup {"resource":
> > > {"IsResourceRequest":false,"Path":"/apis/monitoring.coreos.com/v1
> > > ","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","R
> > > esource":"","Subresource":"","Name":"","Parts":null}}
> > > 2021-02-12T14:16:37.881-0500 INFO proxy Skipping cache lookup {"resource":
> > > {"IsResourceRequest":false,"Path":"/apis/
> > > monitoringcontroller.cloud.ibm.com/v1
> > > ","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","R
> > > esource":"","Subresource":"","Name":"","Parts":null}}
> > > 2021-02-12T14:16:38.281-0500 INFO proxy Skipping cache lookup {"resource":
> > > {"IsResourceRequest":false,"Path":"/apis/
> > > security.internal.openshift.io/v1
> > > ","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","R
> > > esource":"","Subresource":"","Name":"","Parts":null}}
> > > 2021-02-12T14:16:38.328-0500 INFO proxy Skipping cache lookup {"resource":
> > > {"IsResourceRequest":false,"Path":"/apis/tuned.openshift.io/v1
> > > ","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","R
> > > esource":"","Subresource":"","Name":"","Parts":null}}
> > > 2021-02-12T14:16:38.501-0500 INFO proxy Read object from cache {"resource":
> > > {"IsResourceRequest":true,"Path":"/api/v1/namespaces/hb-
> > > test/services","Verb":"list","APIPrefix":"api","APIGroup":"","APIVersion":"v1","N
> > > amespace":"hb-
> > > test","Resource":"services","Subresource":"","Name":"","Parts":["services"]}}
> > >
> > >
> > > Task #2: This task involves creating a ConfigMap from a Jinja2 template.
> > >
> > > TASK [Create events configMap_serviceEndpoints.yaml.j2] **************
> > > task path: /roles/events/tasks/3.0.0/main.yml:30
> > >
> > >
> > > -------------------------------------------------------------------------------
> > > 2021-02-12T14:20:18.484-0500 INFO proxy Skipping cache lookup {"resource":
> > > {"IsResourceRequest":false,"Path":"/version","Verb":"get","APIPrefix":"","APIGrou
> > > p":"","APIVersion":"","Namespace":"","Resource":"","Subresource":"","Name":"","Pa
> > > ","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","R
> > > esource":"","Subresource":"","Name":"","Parts":null}}
> > > 2021-02-12T14:20:20.589-0500 INFO proxy Skipping cache lookup {"resource":
> > > {"IsResourceRequest":false,"Path":"/apis/ingress.operator.openshift.io/v1
> > > ","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","R
> > > esource":"","Subresource":"","Name":"","Parts":null}}
> > > 2021-02-12T14:20:20.635-0500 INFO proxy Skipping cache lookup {"resource":
> > > {"IsResourceRequest":false,"Path":"/apis/k8s.cni.cncf.io/v1
> > > ","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","R
> > > esource":"","Subresource":"","Name":"","Parts":null}}
> > > 2021-02-12T14:20:20.682-0500 INFO proxy Skipping cache lookup {"resource":
> > > {"IsResourceRequest":false,"Path":"/apis/
> > > machineconfiguration.openshift.io/v1
> > > ","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","R
> > > esource":"","Subresource":"","Name":"","Parts":null}}
> > > 2021-02-12T14:20:20.729-0500 INFO proxy Skipping cache lookup {"resource":
> > > {"IsResourceRequest":false,"Path":"/apis/metering.ibm.com/v1
> > > ","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","R
> > > esource":"","Subresource":"","Name":"","Parts":null}}
> > > 2021-02-12T14:20:20.783-0500 INFO proxy Skipping cache lookup {"resource":
> > > {"IsResourceRequest":false,"Path":"/apis/monitoring.coreos.com/v1
> > > ","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","R
> > > esource":"","Subresource":"","Name":"","Parts":null}}
> > > 2021-02-12T14:20:20.827-0500 INFO proxy Skipping cache lookup {"resource":
> > > {"IsResourceRequest":false,"Path":"/apis/
> > > monitoringcontroller.cloud.ibm.com/v1
> > > ","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","R
> > > ","Verb":"get","APIPrefix":"apis","APIGroup":"","APIVersion":"","Namespace":"","R
> > > > > <https://groups.google.com/d/msgid/operator-framework/5ef622fb-2311-46c0-bb50-4bc515f817d5n%40googlegroups.com?utm_medium=email&utm_source=footer
> > > > > >
> > > > > .
> > > > >
> > > >
> > > >
> > > > --
> > > >
> > > > *Fabian* *v**on Feilitzsch*
> > > >
> > > > Senior Software Engineer, OpenShift Engineering
> > > >
> > > > Red Hat <https://www.redhat.com/>
> > > >
> > > > fab...@redhat.com    IM: fabianvf
> > > > <https://www.redhat.com/>
> > > >
> > > --
> > > You received this message because you are subscribed to the Google Groups
> > > "Operator Framework" group.
> > > To unsubscribe from this group and stop receiving emails from it, send an
> > > email to operator-framew...@googlegroups.com.
> > >
> > To view this discussion on the web visit
> > > https://groups.google.com/d/msgid/operator-framework/12a9a578-99a1-4efc-a2ae-7ada40f2c73an%40googlegroups.com
> > >  
> > > <https://groups.google.com/d/msgid/operator-framework/12a9a578-99a1-4efc-a2ae-7ada40f2c73an%40googlegroups.com?utm_medium=email&utm_source=footer
> > > >
> > > .
> > >
> >
>

Harihara Narayanan B

unread,
Feb 17, 2021, 9:46:05 AM2/17/21
to Operator Framework

Hi, 

Based on the comment from Fabian in the thread (pasted below on this comment), the issue seems to be a bug that is causing the cache not storing the API endpoints discovery after the initial run, so the subsequent task runs still take longer running the API discovery again. In my understanding, the PR #4511 is only providing a way to reduce the lines in the logs (by changing the log levels) by the cache, and is not fixing the issue with not caching the API discovery, please correct me if thats not true. 

The number of lines produced to the logs is one issue but the cache lookup is also slowing down each task that connects to the API server involving the Proxy and its cache. 

Earlier comment from Fabian:
"""
Oh yeah that's definitely a bug, those are API discovery requests which are issued by the dynamic client to determine what APIs the cluster supports. They should only be issued a single time (on the first use of the module) and then cached for quite a while, if you're seeing that more than one time then something is up. Would you mind filing an issue with the above log as well as the ansible-operator base image you are using? This is behavior I would only expect on very old versions of the Python client (before we supported caching).
"""

regards,
Hari

Reply all
Reply to author
Forward
0 new messages