{"level":"warn","ts":"2026-05-12T01:40:07.844004Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-12T01:40:07.844091Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.214.167:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.214.167:2380","--initial-cluster=instance=https://199.19.214.167:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.214.167:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.214.167:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-12T01:40:07.844148Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-12T01:40:07.844158Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.214.167:2380"]} {"level":"info","ts":"2026-05-12T01:40:07.844177Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-12T01:40:07.844816Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.214.167:2379"]} {"level":"info","ts":"2026-05-12T01:40:07.844922Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.214.167:2380"],"listen-peer-urls":["https://199.19.214.167:2380"],"advertise-client-urls":["https://199.19.214.167:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.214.167:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.214.167:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-12T01:40:07.85098Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.862955ms"} {"level":"info","ts":"2026-05-12T01:40:07.86311Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4d7a4a4f26bda9c7","cluster-id":"a34ef8c652872f28"} {"level":"info","ts":"2026-05-12T01:40:07.863191Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4d7a4a4f26bda9c7 switched to configuration voters=()"} {"level":"info","ts":"2026-05-12T01:40:07.863213Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4d7a4a4f26bda9c7 became follower at term 0"} {"level":"info","ts":"2026-05-12T01:40:07.863226Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4d7a4a4f26bda9c7 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-12T01:40:07.863235Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4d7a4a4f26bda9c7 became follower at term 1"} {"level":"info","ts":"2026-05-12T01:40:07.863268Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4d7a4a4f26bda9c7 switched to configuration voters=(5582856391891986887)"} {"level":"warn","ts":"2026-05-12T01:40:07.872171Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-12T01:40:07.874327Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-12T01:40:07.875868Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-12T01:40:07.879166Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4d7a4a4f26bda9c7","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-12T01:40:07.879333Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4d7a4a4f26bda9c7","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-12T01:40:07.879439Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-12T01:40:07.879514Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-12T01:40:07.879525Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-12T01:40:07.88153Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4d7a4a4f26bda9c7 switched to configuration voters=(5582856391891986887)"} {"level":"info","ts":"2026-05-12T01:40:07.881843Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"a34ef8c652872f28","local-member-id":"4d7a4a4f26bda9c7","added-peer-id":"4d7a4a4f26bda9c7","added-peer-peer-urls":["https://199.19.214.167:2380"]} {"level":"info","ts":"2026-05-12T01:40:07.884747Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-12T01:40:07.884944Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.214.167:2380"} {"level":"info","ts":"2026-05-12T01:40:07.885902Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.214.167:2380"} {"level":"info","ts":"2026-05-12T01:40:07.885012Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4d7a4a4f26bda9c7","initial-advertise-peer-urls":["https://199.19.214.167:2380"],"listen-peer-urls":["https://199.19.214.167:2380"],"advertise-client-urls":["https://199.19.214.167:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.214.167:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-12T01:40:07.885055Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-12T01:40:08.664129Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4d7a4a4f26bda9c7 is starting a new election at term 1"} {"level":"info","ts":"2026-05-12T01:40:08.664248Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4d7a4a4f26bda9c7 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-12T01:40:08.664295Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4d7a4a4f26bda9c7 received MsgPreVoteResp from 4d7a4a4f26bda9c7 at term 1"} {"level":"info","ts":"2026-05-12T01:40:08.664324Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4d7a4a4f26bda9c7 became candidate at term 2"} {"level":"info","ts":"2026-05-12T01:40:08.664342Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4d7a4a4f26bda9c7 received MsgVoteResp from 4d7a4a4f26bda9c7 at term 2"} {"level":"info","ts":"2026-05-12T01:40:08.664365Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4d7a4a4f26bda9c7 became leader at term 2"} {"level":"info","ts":"2026-05-12T01:40:08.664385Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4d7a4a4f26bda9c7 elected leader 4d7a4a4f26bda9c7 at term 2"} {"level":"info","ts":"2026-05-12T01:40:08.665542Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-12T01:40:08.666586Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4d7a4a4f26bda9c7","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.214.167:2379]}","request-path":"/0/members/4d7a4a4f26bda9c7/attributes","cluster-id":"a34ef8c652872f28","publish-timeout":"7s"} {"level":"info","ts":"2026-05-12T01:40:08.666611Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-12T01:40:08.666989Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-12T01:40:08.667278Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"a34ef8c652872f28","local-member-id":"4d7a4a4f26bda9c7","cluster-version":"3.5"} {"level":"info","ts":"2026-05-12T01:40:08.667461Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-12T01:40:08.66751Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-12T01:40:08.668605Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.214.167:2379"} {"level":"info","ts":"2026-05-12T01:40:08.668963Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-12T01:40:08.668991Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-12T01:40:08.671801Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-12T01:40:33.89145Z","caller":"traceutil/trace.go:171","msg":"trace[1822631989] transaction","detail":"{read_only:false; response_revision:425; number_of_response:1; }","duration":"571.885944ms","start":"2026-05-12T01:40:33.319539Z","end":"2026-05-12T01:40:33.891424Z","steps":["trace[1822631989] 'process raft request' (duration: 571.773192ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T01:40:33.891452Z","caller":"traceutil/trace.go:171","msg":"trace[956950470] linearizableReadLoop","detail":"{readStateIndex:437; appliedIndex:437; }","duration":"479.661691ms","start":"2026-05-12T01:40:33.411767Z","end":"2026-05-12T01:40:33.891429Z","steps":["trace[956950470] 'read index received' (duration: 479.653151ms)","trace[956950470] 'applied index is now lower than readState.Index' (duration: 7.28µs)"],"step_count":2} {"level":"warn","ts":"2026-05-12T01:40:33.891635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.844894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T01:40:33.891721Z","caller":"traceutil/trace.go:171","msg":"trace[741829193] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:425; }","duration":"479.983687ms","start":"2026-05-12T01:40:33.411726Z","end":"2026-05-12T01:40:33.89171Z","steps":["trace[741829193] 'agreement among raft nodes before linearized reading' (duration: 479.809754ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T01:40:33.891754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T01:40:33.411701Z","time spent":"480.044788ms","remote":"127.0.0.1:45848","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-12T01:40:33.891922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T01:40:33.319521Z","time spent":"572.010426ms","remote":"127.0.0.1:40248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T01:40:33.892793Z","caller":"traceutil/trace.go:171","msg":"trace[1235870761] transaction","detail":"{read_only:false; response_revision:426; number_of_response:1; }","duration":"430.03497ms","start":"2026-05-12T01:40:33.462745Z","end":"2026-05-12T01:40:33.89278Z","steps":["trace[1235870761] 'process raft request' (duration: 429.977359ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T01:40:33.892852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T01:40:33.462703Z","time spent":"430.119002ms","remote":"127.0.0.1:40248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T01:40:44.350143Z","caller":"traceutil/trace.go:171","msg":"trace[1240232550] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"146.333538ms","start":"2026-05-12T01:40:44.203729Z","end":"2026-05-12T01:40:44.350062Z","steps":["trace[1240232550] 'process raft request' (duration: 146.006732ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T01:40:44.810709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.80368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-12T01:40:44.810745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.977982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-12T01:40:44.81079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.058371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T01:40:44.810821Z","caller":"traceutil/trace.go:171","msg":"trace[448394224] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:597; }","duration":"398.006813ms","start":"2026-05-12T01:40:44.412792Z","end":"2026-05-12T01:40:44.810799Z","steps":["trace[448394224] 'range keys from in-memory index tree' (duration: 397.705119ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T01:40:44.810846Z","caller":"traceutil/trace.go:171","msg":"trace[1325536338] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:597; }","duration":"144.153593ms","start":"2026-05-12T01:40:44.666673Z","end":"2026-05-12T01:40:44.810827Z","steps":["trace[1325536338] 'range keys from in-memory index tree' (duration: 143.98064ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T01:40:44.810876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T01:40:44.412773Z","time spent":"398.092564ms","remote":"127.0.0.1:40184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"info","ts":"2026-05-12T01:40:44.810848Z","caller":"traceutil/trace.go:171","msg":"trace[1746207756] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:597; }","duration":"344.131295ms","start":"2026-05-12T01:40:44.46669Z","end":"2026-05-12T01:40:44.810821Z","steps":["trace[1746207756] 'range keys from in-memory index tree' (duration: 343.88109ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T01:40:44.810907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.893596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-12T01:40:44.810932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T01:40:44.466666Z","time spent":"344.248047ms","remote":"127.0.0.1:40050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"info","ts":"2026-05-12T01:40:44.810958Z","caller":"traceutil/trace.go:171","msg":"trace[566329374] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:597; }","duration":"370.986248ms","start":"2026-05-12T01:40:44.439959Z","end":"2026-05-12T01:40:44.810945Z","steps":["trace[566329374] 'range keys from in-memory index tree' (duration: 370.725983ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T01:40:44.810989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T01:40:44.439939Z","time spent":"371.042498ms","remote":"127.0.0.1:40248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-12T01:40:44.81102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.203528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T01:40:44.811128Z","caller":"traceutil/trace.go:171","msg":"trace[617157609] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:597; }","duration":"400.497123ms","start":"2026-05-12T01:40:44.410604Z","end":"2026-05-12T01:40:44.811102Z","steps":["trace[617157609] 'range keys from in-memory index tree' (duration: 400.136447ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T01:40:44.81116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.457543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-12T01:40:44.811176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T01:40:44.410565Z","time spent":"400.600185ms","remote":"127.0.0.1:45848","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-12T01:40:44.811193Z","caller":"traceutil/trace.go:171","msg":"trace[1909635005] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:597; }","duration":"400.499443ms","start":"2026-05-12T01:40:44.410685Z","end":"2026-05-12T01:40:44.811185Z","steps":["trace[1909635005] 'range keys from in-memory index tree' (duration: 400.348991ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T01:40:44.811222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T01:40:44.410682Z","time spent":"400.533464ms","remote":"127.0.0.1:40248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-12T01:40:51.126669Z","caller":"traceutil/trace.go:171","msg":"trace[1465297940] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"268.492333ms","start":"2026-05-12T01:40:50.858153Z","end":"2026-05-12T01:40:51.126645Z","steps":["trace[1465297940] 'process raft request' (duration: 268.368941ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T01:40:51.23962Z","caller":"traceutil/trace.go:171","msg":"trace[537699461] linearizableReadLoop","detail":"{readStateIndex:667; appliedIndex:666; }","duration":"358.059413ms","start":"2026-05-12T01:40:50.881533Z","end":"2026-05-12T01:40:51.239593Z","steps":["trace[537699461] 'read index received' (duration: 245.112581ms)","trace[537699461] 'applied index is now lower than readState.Index' (duration: 112.945842ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T01:40:51.239696Z","caller":"traceutil/trace.go:171","msg":"trace[1618969588] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"377.821609ms","start":"2026-05-12T01:40:50.861829Z","end":"2026-05-12T01:40:51.239651Z","steps":["trace[1618969588] 'process raft request' (duration: 373.583373ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T01:40:51.239885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.329727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-12T01:40:51.239891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T01:40:50.861802Z","time spent":"377.977102ms","remote":"127.0.0.1:40248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T01:40:51.239934Z","caller":"traceutil/trace.go:171","msg":"trace[1092890143] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:649; }","duration":"358.427929ms","start":"2026-05-12T01:40:50.881491Z","end":"2026-05-12T01:40:51.239919Z","steps":["trace[1092890143] 'agreement among raft nodes before linearized reading' (duration: 358.252876ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T01:40:51.239972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T01:40:50.881478Z","time spent":"358.486489ms","remote":"127.0.0.1:40248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-12T01:40:51.240106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.505588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-05-12T01:40:51.240535Z","caller":"traceutil/trace.go:171","msg":"trace[1574539614] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:649; }","duration":"133.941105ms","start":"2026-05-12T01:40:51.106569Z","end":"2026-05-12T01:40:51.24051Z","steps":["trace[1574539614] 'agreement among raft nodes before linearized reading' (duration: 133.240134ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T01:40:51.839061Z","caller":"traceutil/trace.go:171","msg":"trace[971431334] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"318.021393ms","start":"2026-05-12T01:40:51.521009Z","end":"2026-05-12T01:40:51.83903Z","steps":["trace[971431334] 'process raft request' (duration: 317.81702ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T01:40:51.839393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T01:40:51.520991Z","time spent":"318.281688ms","remote":"127.0.0.1:40162","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T01:40:52.895623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.142149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T01:40:52.895748Z","caller":"traceutil/trace.go:171","msg":"trace[1417786702] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:654; }","duration":"482.263261ms","start":"2026-05-12T01:40:52.413423Z","end":"2026-05-12T01:40:52.895686Z","steps":["trace[1417786702] 'range keys from in-memory index tree' (duration: 482.069738ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T01:40:52.89579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T01:40:52.413406Z","time spent":"482.373883ms","remote":"127.0.0.1:45848","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-12T01:40:52.896107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"887.42125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-12T01:40:52.896211Z","caller":"traceutil/trace.go:171","msg":"trace[1491202572] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:654; }","duration":"887.579082ms","start":"2026-05-12T01:40:52.008612Z","end":"2026-05-12T01:40:52.896191Z","steps":["trace[1491202572] 'range keys from in-memory index tree' (duration: 887.194387ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T01:40:52.896255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T01:40:52.008582Z","time spent":"887.662754ms","remote":"127.0.0.1:40248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-12T01:41:10.464304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"846.724599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-rc8lr\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-05-12T01:41:10.464399Z","caller":"traceutil/trace.go:171","msg":"trace[1650724063] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-rc8lr; range_end:; response_count:1; response_revision:767; }","duration":"846.86743ms","start":"2026-05-12T01:41:09.617506Z","end":"2026-05-12T01:41:10.464374Z","steps":["trace[1650724063] 'range keys from in-memory index tree' (duration: 846.507875ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T01:41:10.464443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T01:41:09.617479Z","time spent":"846.952891ms","remote":"127.0.0.1:40162","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3988,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-rc8lr\" "} {"level":"warn","ts":"2026-05-12T01:41:10.46453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.70242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T01:41:10.464707Z","caller":"traceutil/trace.go:171","msg":"trace[509075998] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:767; }","duration":"258.884143ms","start":"2026-05-12T01:41:10.205733Z","end":"2026-05-12T01:41:10.464617Z","steps":["trace[509075998] 'range keys from in-memory index tree' (duration: 258.527077ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T01:41:28.895283Z","caller":"traceutil/trace.go:171","msg":"trace[206083634] transaction","detail":"{read_only:false; response_revision:1098; number_of_response:1; }","duration":"149.149077ms","start":"2026-05-12T01:41:28.7461Z","end":"2026-05-12T01:41:28.895249Z","steps":["trace[206083634] 'process raft request' (duration: 70.602753ms)","trace[206083634] 'compare' (duration: 78.274481ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T01:41:28.895324Z","caller":"traceutil/trace.go:171","msg":"trace[1036977597] transaction","detail":"{read_only:false; response_revision:1099; number_of_response:1; }","duration":"117.30181ms","start":"2026-05-12T01:41:28.777996Z","end":"2026-05-12T01:41:28.895298Z","steps":["trace[1036977597] 'process raft request' (duration: 117.220279ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T01:41:28.897965Z","caller":"traceutil/trace.go:171","msg":"trace[729419500] transaction","detail":"{read_only:false; response_revision:1100; number_of_response:1; }","duration":"106.970978ms","start":"2026-05-12T01:41:28.790978Z","end":"2026-05-12T01:41:28.897949Z","steps":["trace[729419500] 'process raft request' (duration: 106.870586ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T01:41:29.456143Z","caller":"traceutil/trace.go:171","msg":"trace[411902460] transaction","detail":"{read_only:false; response_revision:1102; number_of_response:1; }","duration":"110.73977ms","start":"2026-05-12T01:41:29.34537Z","end":"2026-05-12T01:41:29.45611Z","steps":["trace[411902460] 'process raft request' (duration: 110.546908ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T01:42:07.523918Z","caller":"traceutil/trace.go:171","msg":"trace[244219230] transaction","detail":"{read_only:false; response_revision:1468; number_of_response:1; }","duration":"134.167221ms","start":"2026-05-12T01:42:07.389728Z","end":"2026-05-12T01:42:07.523895Z","steps":["trace[244219230] 'process raft request' (duration: 133.980789ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T01:42:07.810552Z","caller":"traceutil/trace.go:171","msg":"trace[1156408336] transaction","detail":"{read_only:false; response_revision:1471; number_of_response:1; }","duration":"198.246043ms","start":"2026-05-12T01:42:07.612253Z","end":"2026-05-12T01:42:07.810499Z","steps":["trace[1156408336] 'process raft request' (duration: 182.584257ms)","trace[1156408336] 'compare' (duration: 15.360273ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T01:42:07.810727Z","caller":"traceutil/trace.go:171","msg":"trace[1947345563] linearizableReadLoop","detail":"{readStateIndex:1512; appliedIndex:1511; }","duration":"194.170587ms","start":"2026-05-12T01:42:07.616168Z","end":"2026-05-12T01:42:07.810338Z","steps":["trace[1947345563] 'read index received' (duration: 178.721383ms)","trace[1947345563] 'applied index is now lower than readState.Index' (duration: 15.448124ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T01:42:07.81079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.589632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/exchanges.rabbitmq.com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T01:42:07.81091Z","caller":"traceutil/trace.go:171","msg":"trace[1602181628] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/exchanges.rabbitmq.com; range_end:; response_count:0; response_revision:1471; }","duration":"194.730043ms","start":"2026-05-12T01:42:07.616152Z","end":"2026-05-12T01:42:07.810882Z","steps":["trace[1602181628] 'agreement among raft nodes before linearized reading' (duration: 194.535141ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T01:42:07.948881Z","caller":"traceutil/trace.go:171","msg":"trace[1826652250] transaction","detail":"{read_only:false; response_revision:1477; number_of_response:1; }","duration":"109.122599ms","start":"2026-05-12T01:42:07.839728Z","end":"2026-05-12T01:42:07.94885Z","steps":["trace[1826652250] 'process raft request' (duration: 85.662875ms)","trace[1826652250] 'compare' (duration: 23.260672ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T01:42:08.149291Z","caller":"traceutil/trace.go:171","msg":"trace[166511703] linearizableReadLoop","detail":"{readStateIndex:1524; appliedIndex:1522; }","duration":"104.20527ms","start":"2026-05-12T01:42:08.045061Z","end":"2026-05-12T01:42:08.149266Z","steps":["trace[166511703] 'read index received' (duration: 13.832675ms)","trace[166511703] 'applied index is now lower than readState.Index' (duration: 90.371805ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T01:42:08.149329Z","caller":"traceutil/trace.go:171","msg":"trace[347752791] transaction","detail":"{read_only:false; response_revision:1482; number_of_response:1; }","duration":"120.380632ms","start":"2026-05-12T01:42:08.028929Z","end":"2026-05-12T01:42:08.14931Z","steps":["trace[347752791] 'process raft request' (duration: 29.958266ms)","trace[347752791] 'compare' (duration: 90.138353ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T01:42:08.149415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.337952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T01:42:08.149442Z","caller":"traceutil/trace.go:171","msg":"trace[482149497] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:1483; }","duration":"104.394733ms","start":"2026-05-12T01:42:08.045038Z","end":"2026-05-12T01:42:08.149432Z","steps":["trace[482149497] 'agreement among raft nodes before linearized reading' (duration: 104.326892ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T01:42:08.149347Z","caller":"traceutil/trace.go:171","msg":"trace[1470489884] transaction","detail":"{read_only:false; response_revision:1483; number_of_response:1; }","duration":"119.226049ms","start":"2026-05-12T01:42:08.030101Z","end":"2026-05-12T01:42:08.149327Z","steps":["trace[1470489884] 'process raft request' (duration: 119.105338ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T01:42:22.73679Z","caller":"traceutil/trace.go:171","msg":"trace[1379017358] transaction","detail":"{read_only:false; response_revision:1746; number_of_response:1; }","duration":"125.339314ms","start":"2026-05-12T01:42:22.611432Z","end":"2026-05-12T01:42:22.736772Z","steps":["trace[1379017358] 'process raft request' (duration: 125.226493ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T01:42:31.23474Z","caller":"traceutil/trace.go:171","msg":"trace[1471371173] transaction","detail":"{read_only:false; response_revision:1859; number_of_response:1; }","duration":"592.372872ms","start":"2026-05-12T01:42:30.642332Z","end":"2026-05-12T01:42:31.234705Z","steps":["trace[1471371173] 'process raft request' (duration: 550.302112ms)","trace[1471371173] 'compare' (duration: 41.692546ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T01:42:31.234941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T01:42:30.642293Z","time spent":"592.559555ms","remote":"127.0.0.1:40248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T01:42:31.241647Z","caller":"traceutil/trace.go:171","msg":"trace[372500404] transaction","detail":"{read_only:false; response_revision:1860; number_of_response:1; }","duration":"474.805142ms","start":"2026-05-12T01:42:30.766821Z","end":"2026-05-12T01:42:31.241626Z","steps":["trace[372500404] 'process raft request' (duration: 474.64017ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T01:42:31.241794Z","caller":"traceutil/trace.go:171","msg":"trace[1904913493] linearizableReadLoop","detail":"{readStateIndex:1927; appliedIndex:1925; }","duration":"384.577426ms","start":"2026-05-12T01:42:30.857188Z","end":"2026-05-12T01:42:31.241765Z","steps":["trace[1904913493] 'read index received' (duration: 335.45606ms)","trace[1904913493] 'applied index is now lower than readState.Index' (duration: 49.119875ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T01:42:31.241845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T01:42:30.766798Z","time spent":"474.974894ms","remote":"127.0.0.1:40248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T01:42:31.241961Z","caller":"traceutil/trace.go:171","msg":"trace[1487854538] transaction","detail":"{read_only:false; response_revision:1861; number_of_response:1; }","duration":"238.802523ms","start":"2026-05-12T01:42:31.003143Z","end":"2026-05-12T01:42:31.241946Z","steps":["trace[1487854538] 'process raft request' (duration: 238.43969ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T01:42:31.242209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.01455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-12T01:42:31.242275Z","caller":"traceutil/trace.go:171","msg":"trace[1490044833] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1861; }","duration":"385.140132ms","start":"2026-05-12T01:42:30.857117Z","end":"2026-05-12T01:42:31.242257Z","steps":["trace[1490044833] 'agreement among raft nodes before linearized reading' (duration: 384.849379ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T01:42:31.242439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T01:42:30.857086Z","time spent":"385.218432ms","remote":"127.0.0.1:36402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-05-12T01:42:31.287367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.499317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5081fb0d-ce35-47b4-ba60-e5800de928e9\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-05-12T01:42:31.287435Z","caller":"traceutil/trace.go:171","msg":"trace[376109117] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-5081fb0d-ce35-47b4-ba60-e5800de928e9; range_end:; response_count:1; response_revision:1862; }","duration":"291.615828ms","start":"2026-05-12T01:42:30.995805Z","end":"2026-05-12T01:42:31.28742Z","steps":["trace[376109117] 'agreement among raft nodes before linearized reading' (duration: 291.353975ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T01:42:31.453531Z","caller":"traceutil/trace.go:171","msg":"trace[450750926] transaction","detail":"{read_only:false; response_revision:1863; number_of_response:1; }","duration":"158.352234ms","start":"2026-05-12T01:42:31.295157Z","end":"2026-05-12T01:42:31.45351Z","steps":["trace[450750926] 'process raft request' (duration: 145.995371ms)","trace[450750926] 'compare' (duration: 12.174901ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T01:42:31.453485Z","caller":"traceutil/trace.go:171","msg":"trace[784814591] linearizableReadLoop","detail":"{readStateIndex:1930; appliedIndex:1929; }","duration":"151.646762ms","start":"2026-05-12T01:42:31.301813Z","end":"2026-05-12T01:42:31.45346Z","steps":["trace[784814591] 'read index received' (duration: 139.230139ms)","trace[784814591] 'applied index is now lower than readState.Index' (duration: 12.415793ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T01:42:31.453897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.01683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5081fb0d-ce35-47b4-ba60-e5800de928e9\" ","response":"range_response_count:1 size:3747"} {"level":"info","ts":"2026-05-12T01:42:31.453939Z","caller":"traceutil/trace.go:171","msg":"trace[1536271536] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-5081fb0d-ce35-47b4-ba60-e5800de928e9; range_end:; response_count:1; response_revision:1863; }","duration":"101.109771ms","start":"2026-05-12T01:42:31.352818Z","end":"2026-05-12T01:42:31.453928Z","steps":["trace[1536271536] 'agreement among raft nodes before linearized reading' (duration: 101.01803ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T01:42:31.454119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.287009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-05-12T01:42:31.454376Z","caller":"traceutil/trace.go:171","msg":"trace[1871146068] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:1863; }","duration":"152.590583ms","start":"2026-05-12T01:42:31.301768Z","end":"2026-05-12T01:42:31.454359Z","steps":["trace[1871146068] 'agreement among raft nodes before linearized reading' (duration: 151.961746ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T01:42:32.037185Z","caller":"traceutil/trace.go:171","msg":"trace[1299104455] transaction","detail":"{read_only:false; response_revision:1864; number_of_response:1; }","duration":"578.154184ms","start":"2026-05-12T01:42:31.459007Z","end":"2026-05-12T01:42:32.037161Z","steps":["trace[1299104455] 'process raft request' (duration: 573.694666ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T01:42:32.037575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T01:42:31.458995Z","time spent":"578.254675ms","remote":"127.0.0.1:40248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":514,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T01:42:32.039069Z","caller":"traceutil/trace.go:171","msg":"trace[1222154264] linearizableReadLoop","detail":"{readStateIndex:1932; appliedIndex:1930; }","duration":"407.74204ms","start":"2026-05-12T01:42:31.631311Z","end":"2026-05-12T01:42:32.039053Z","steps":["trace[1222154264] 'read index received' (duration: 401.424442ms)","trace[1222154264] 'applied index is now lower than readState.Index' (duration: 6.316948ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T01:42:32.039192Z","caller":"traceutil/trace.go:171","msg":"trace[308526200] transaction","detail":"{read_only:false; response_revision:1865; number_of_response:1; }","duration":"573.900478ms","start":"2026-05-12T01:42:31.465279Z","end":"2026-05-12T01:42:32.03918Z","steps":["trace[308526200] 'process raft request' (duration: 573.635135ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T01:42:32.039317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T01:42:31.465269Z","time spent":"573.955199ms","remote":"127.0.0.1:40162","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7109,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T01:42:32.039693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.353114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.214.167\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-12T01:42:32.039772Z","caller":"traceutil/trace.go:171","msg":"trace[416376305] range","detail":"{range_begin:/registry/masterleases/199.19.214.167; range_end:; response_count:1; response_revision:1865; }","duration":"160.500896ms","start":"2026-05-12T01:42:31.879252Z","end":"2026-05-12T01:42:32.039753Z","steps":["trace[416376305] 'agreement among raft nodes before linearized reading' (duration: 160.152133ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T01:42:32.039864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.552579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-12T01:42:32.039944Z","caller":"traceutil/trace.go:171","msg":"trace[1642179223] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1865; }","duration":"408.66373ms","start":"2026-05-12T01:42:31.631266Z","end":"2026-05-12T01:42:32.039929Z","steps":["trace[1642179223] 'agreement among raft nodes before linearized reading' (duration: 408.490388ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T01:42:32.040017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T01:42:31.631246Z","time spent":"408.761881ms","remote":"127.0.0.1:40248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-12T01:42:37.57246Z","caller":"traceutil/trace.go:171","msg":"trace[265332377] transaction","detail":"{read_only:false; response_revision:1926; number_of_response:1; }","duration":"319.27946ms","start":"2026-05-12T01:42:37.25316Z","end":"2026-05-12T01:42:37.57244Z","steps":["trace[265332377] 'process raft request' (duration: 319.125018ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T01:42:37.572731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T01:42:37.253142Z","time spent":"319.468261ms","remote":"127.0.0.1:40248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T01:42:37.595107Z","caller":"traceutil/trace.go:171","msg":"trace[936838458] linearizableReadLoop","detail":"{readStateIndex:1997; appliedIndex:1995; }","duration":"312.509638ms","start":"2026-05-12T01:42:37.282554Z","end":"2026-05-12T01:42:37.595063Z","steps":["trace[936838458] 'read index received' (duration: 289.799758ms)","trace[936838458] 'applied index is now lower than readState.Index' (duration: 22.7089ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T01:42:37.59515Z","caller":"traceutil/trace.go:171","msg":"trace[861471795] transaction","detail":"{read_only:false; response_revision:1927; number_of_response:1; }","duration":"337.025678ms","start":"2026-05-12T01:42:37.258094Z","end":"2026-05-12T01:42:37.595119Z","steps":["trace[861471795] 'process raft request' (duration: 336.822795ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T01:42:37.59524Z","caller":"traceutil/trace.go:171","msg":"trace[1825582031] transaction","detail":"{read_only:false; response_revision:1928; number_of_response:1; }","duration":"286.686584ms","start":"2026-05-12T01:42:37.308502Z","end":"2026-05-12T01:42:37.595188Z","steps":["trace[1825582031] 'process raft request' (duration: 286.530963ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T01:42:37.595318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T01:42:37.258076Z","time spent":"337.147019ms","remote":"127.0.0.1:40248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T01:42:37.595361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.783111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-12T01:42:37.595397Z","caller":"traceutil/trace.go:171","msg":"trace[1235271156] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1928; }","duration":"312.857301ms","start":"2026-05-12T01:42:37.282526Z","end":"2026-05-12T01:42:37.595383Z","steps":["trace[1235271156] 'agreement among raft nodes before linearized reading' (duration: 312.69239ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T01:42:37.595429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T01:42:37.282512Z","time spent":"312.906182ms","remote":"127.0.0.1:36402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-05-12T01:42:37.595662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.149059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T01:42:37.595714Z","caller":"traceutil/trace.go:171","msg":"trace[1462853292] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1928; }","duration":"184.257041ms","start":"2026-05-12T01:42:37.411442Z","end":"2026-05-12T01:42:37.595699Z","steps":["trace[1462853292] 'agreement among raft nodes before linearized reading' (duration: 184.111479ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T01:42:44.317397Z","caller":"traceutil/trace.go:171","msg":"trace[571057867] transaction","detail":"{read_only:false; response_revision:1978; number_of_response:1; }","duration":"165.554643ms","start":"2026-05-12T01:42:44.151813Z","end":"2026-05-12T01:42:44.317368Z","steps":["trace[571057867] 'process raft request' (duration: 165.489312ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T01:42:44.317422Z","caller":"traceutil/trace.go:171","msg":"trace[94539427] transaction","detail":"{read_only:false; response_revision:1976; number_of_response:1; }","duration":"166.100568ms","start":"2026-05-12T01:42:44.151294Z","end":"2026-05-12T01:42:44.317394Z","steps":["trace[94539427] 'process raft request' (duration: 165.804255ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T01:42:44.317598Z","caller":"traceutil/trace.go:171","msg":"trace[530455069] transaction","detail":"{read_only:false; response_revision:1979; number_of_response:1; }","duration":"165.689354ms","start":"2026-05-12T01:42:44.151893Z","end":"2026-05-12T01:42:44.317582Z","steps":["trace[530455069] 'process raft request' (duration: 165.441222ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T01:42:44.317495Z","caller":"traceutil/trace.go:171","msg":"trace[405755602] transaction","detail":"{read_only:false; response_revision:1977; number_of_response:1; }","duration":"166.012678ms","start":"2026-05-12T01:42:44.151423Z","end":"2026-05-12T01:42:44.317435Z","steps":["trace[405755602] 'process raft request' (duration: 165.835586ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T01:43:11.101259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.231304ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-12T01:43:11.101338Z","caller":"traceutil/trace.go:171","msg":"trace[2058626124] linearizableReadLoop","detail":"{readStateIndex:2213; appliedIndex:2212; }","duration":"278.638992ms","start":"2026-05-12T01:43:10.822687Z","end":"2026-05-12T01:43:11.101326Z","steps":["trace[2058626124] 'read index received' (duration: 40.4µs)","trace[2058626124] 'applied index is now lower than readState.Index' (duration: 278.597742ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T01:43:11.101447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.758743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-12T01:43:11.10147Z","caller":"traceutil/trace.go:171","msg":"trace[1156885121] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2137; }","duration":"278.822734ms","start":"2026-05-12T01:43:10.822641Z","end":"2026-05-12T01:43:11.101463Z","steps":["trace[1156885121] 'agreement among raft nodes before linearized reading' (duration: 278.716603ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T01:43:11.101567Z","caller":"traceutil/trace.go:171","msg":"trace[1336538670] transaction","detail":"{read_only:false; response_revision:2137; number_of_response:1; }","duration":"453.436533ms","start":"2026-05-12T01:43:10.648099Z","end":"2026-05-12T01:43:11.101536Z","steps":["trace[1336538670] 'process raft request' (duration: 45.763554ms)","trace[1336538670] 'compare' (duration: 406.993992ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T01:43:11.101728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T01:43:10.648074Z","time spent":"453.554334ms","remote":"127.0.0.1:40248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T01:43:12.176543Z","caller":"traceutil/trace.go:171","msg":"trace[438353722] transaction","detail":"{read_only:false; response_revision:2138; number_of_response:1; }","duration":"488.135188ms","start":"2026-05-12T01:43:11.688381Z","end":"2026-05-12T01:43:12.176516Z","steps":["trace[438353722] 'process raft request' (duration: 487.933836ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T01:43:12.176682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T01:43:11.688356Z","time spent":"488.25959ms","remote":"127.0.0.1:40248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T01:43:12.366213Z","caller":"traceutil/trace.go:171","msg":"trace[470639997] transaction","detail":"{read_only:false; response_revision:2139; number_of_response:1; }","duration":"654.925804ms","start":"2026-05-12T01:43:11.71126Z","end":"2026-05-12T01:43:12.366186Z","steps":["trace[470639997] 'process raft request' (duration: 652.060435ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T01:43:12.366586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T01:43:11.711217Z","time spent":"655.080455ms","remote":"127.0.0.1:40248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T01:43:12.367166Z","caller":"traceutil/trace.go:171","msg":"trace[1702711465] linearizableReadLoop","detail":"{readStateIndex:2216; appliedIndex:2213; }","duration":"486.150446ms","start":"2026-05-12T01:43:11.880992Z","end":"2026-05-12T01:43:12.367142Z","steps":["trace[1702711465] 'read index received' (duration: 295.292306ms)","trace[1702711465] 'applied index is now lower than readState.Index' (duration: 190.85738ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T01:43:12.367374Z","caller":"traceutil/trace.go:171","msg":"trace[1620733199] transaction","detail":"{read_only:false; response_revision:2140; number_of_response:1; }","duration":"652.57995ms","start":"2026-05-12T01:43:11.714784Z","end":"2026-05-12T01:43:12.367364Z","steps":["trace[1620733199] 'process raft request' (duration: 652.206816ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T01:43:12.36746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T01:43:11.714756Z","time spent":"652.640111ms","remote":"127.0.0.1:40248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T01:43:12.367573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.579464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T01:43:12.367597Z","caller":"traceutil/trace.go:171","msg":"trace[112180530] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:2140; }","duration":"164.637284ms","start":"2026-05-12T01:43:12.202952Z","end":"2026-05-12T01:43:12.36759Z","steps":["trace[112180530] 'agreement among raft nodes before linearized reading' (duration: 164.593094ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T01:43:12.36778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.779272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.214.167\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-05-12T01:43:12.367864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.491233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-12T01:43:12.367889Z","caller":"traceutil/trace.go:171","msg":"trace[2011700646] range","detail":"{range_begin:/registry/masterleases/199.19.214.167; range_end:; response_count:1; response_revision:2140; }","duration":"486.918984ms","start":"2026-05-12T01:43:11.880953Z","end":"2026-05-12T01:43:12.367872Z","steps":["trace[2011700646] 'agreement among raft nodes before linearized reading' (duration: 486.587621ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T01:43:12.368069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T01:43:11.880939Z","time spent":"487.094136ms","remote":"127.0.0.1:40044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.214.167\" "} {"level":"info","ts":"2026-05-12T01:43:12.367953Z","caller":"traceutil/trace.go:171","msg":"trace[616434803] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2140; }","duration":"260.607425ms","start":"2026-05-12T01:43:12.107318Z","end":"2026-05-12T01:43:12.367926Z","steps":["trace[616434803] 'agreement among raft nodes before linearized reading' (duration: 260.059539ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T01:43:12.937236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.634264ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-12T01:43:12.937463Z","caller":"traceutil/trace.go:171","msg":"trace[1569114043] transaction","detail":"{read_only:false; response_revision:2142; number_of_response:1; }","duration":"508.289978ms","start":"2026-05-12T01:43:12.429151Z","end":"2026-05-12T01:43:12.937441Z","steps":["trace[1569114043] 'process raft request' (duration: 508.201167ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T01:43:12.937565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T01:43:12.429133Z","time spent":"508.3855ms","remote":"127.0.0.1:40044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T01:43:12.937668Z","caller":"traceutil/trace.go:171","msg":"trace[417527727] transaction","detail":"{read_only:false; response_revision:2141; number_of_response:1; }","duration":"564.581727ms","start":"2026-05-12T01:43:12.373055Z","end":"2026-05-12T01:43:12.937637Z","steps":["trace[417527727] 'process raft request' (duration: 128.448938ms)","trace[417527727] 'compare' (duration: 435.467742ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T01:43:12.93786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T01:43:12.373035Z","time spent":"564.74548ms","remote":"127.0.0.1:40248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T01:43:12.937864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.764415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T01:43:12.937917Z","caller":"traceutil/trace.go:171","msg":"trace[1021434023] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2142; }","duration":"524.858636ms","start":"2026-05-12T01:43:12.413046Z","end":"2026-05-12T01:43:12.937904Z","steps":["trace[1021434023] 'agreement among raft nodes before linearized reading' (duration: 524.718234ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T01:43:12.937711Z","caller":"traceutil/trace.go:171","msg":"trace[616161122] linearizableReadLoop","detail":"{readStateIndex:2218; appliedIndex:2216; }","duration":"524.558643ms","start":"2026-05-12T01:43:12.413085Z","end":"2026-05-12T01:43:12.937644Z","steps":["trace[616161122] 'read index received' (duration: 14.919821ms)","trace[616161122] 'applied index is now lower than readState.Index' (duration: 509.635652ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T01:43:12.937961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T01:43:12.413022Z","time spent":"524.928976ms","remote":"127.0.0.1:45848","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-12T01:43:12.938171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.078151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-05-12T01:43:12.938168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.174814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-12T01:43:12.9382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.787366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-12T01:43:12.938245Z","caller":"traceutil/trace.go:171","msg":"trace[373408809] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2142; }","duration":"433.853376ms","start":"2026-05-12T01:43:12.504376Z","end":"2026-05-12T01:43:12.93823Z","steps":["trace[373408809] 'agreement among raft nodes before linearized reading' (duration: 433.723715ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T01:43:12.93824Z","caller":"traceutil/trace.go:171","msg":"trace[271184518] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2142; }","duration":"465.174363ms","start":"2026-05-12T01:43:12.473045Z","end":"2026-05-12T01:43:12.93822Z","steps":["trace[271184518] 'agreement among raft nodes before linearized reading' (duration: 464.988341ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T01:43:12.938241Z","caller":"traceutil/trace.go:171","msg":"trace[731728641] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2142; }","duration":"390.281996ms","start":"2026-05-12T01:43:12.547945Z","end":"2026-05-12T01:43:12.938227Z","steps":["trace[731728641] 'agreement among raft nodes before linearized reading' (duration: 390.099604ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T01:43:12.938277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T01:43:12.504337Z","time spent":"433.932137ms","remote":"127.0.0.1:40248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-12T01:43:12.938289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T01:43:12.47303Z","time spent":"465.247344ms","remote":"127.0.0.1:40248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-12T01:43:12.938308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T01:43:12.547908Z","time spent":"390.383347ms","remote":"127.0.0.1:40248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-12T01:43:12.93842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.818451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18aeadf6a0ca7984\" ","response":"range_response_count:1 size:846"} {"level":"info","ts":"2026-05-12T01:43:12.938545Z","caller":"traceutil/trace.go:171","msg":"trace[1381790841] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.18aeadf6a0ca7984; range_end:; response_count:1; response_revision:2142; }","duration":"307.009534ms","start":"2026-05-12T01:43:12.631515Z","end":"2026-05-12T01:43:12.938525Z","steps":["trace[1381790841] 'agreement among raft nodes before linearized reading' (duration: 306.690351ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T01:43:12.938639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T01:43:12.631498Z","time spent":"307.125785ms","remote":"127.0.0.1:40080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":869,"request content":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18aeadf6a0ca7984\" "} {"level":"warn","ts":"2026-05-12T01:43:12.938687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.01621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-12T01:43:12.93875Z","caller":"traceutil/trace.go:171","msg":"trace[2081277102] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2142; }","duration":"454.111761ms","start":"2026-05-12T01:43:12.484625Z","end":"2026-05-12T01:43:12.938737Z","steps":["trace[2081277102] 'agreement among raft nodes before linearized reading' (duration: 453.657217ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T01:43:12.938784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T01:43:12.48461Z","time spent":"454.166162ms","remote":"127.0.0.1:40248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-12T01:43:13.566899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.02251ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-12T01:43:13.567335Z","caller":"traceutil/trace.go:171","msg":"trace[775355447] transaction","detail":"{read_only:false; response_revision:2148; number_of_response:1; }","duration":"460.450441ms","start":"2026-05-12T01:43:13.106864Z","end":"2026-05-12T01:43:13.567315Z","steps":["trace[775355447] 'process raft request' (duration: 460.40563ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T01:43:13.567421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T01:43:13.106827Z","time spent":"460.555681ms","remote":"127.0.0.1:40248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T01:43:13.567537Z","caller":"traceutil/trace.go:171","msg":"trace[964311015] transaction","detail":"{read_only:false; response_revision:2145; number_of_response:1; }","duration":"624.375786ms","start":"2026-05-12T01:43:12.943111Z","end":"2026-05-12T01:43:13.567487Z","steps":["trace[964311015] 'process raft request' (duration: 623.963062ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T01:43:13.567602Z","caller":"traceutil/trace.go:171","msg":"trace[1534304508] transaction","detail":"{read_only:false; response_revision:2146; number_of_response:1; }","duration":"624.200935ms","start":"2026-05-12T01:43:12.943393Z","end":"2026-05-12T01:43:13.567594Z","steps":["trace[1534304508] 'process raft request' (duration: 623.71826ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T01:43:13.567681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T01:43:12.94338Z","time spent":"624.245106ms","remote":"127.0.0.1:40248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T01:43:13.567683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T01:43:12.943102Z","time spent":"624.503587ms","remote":"127.0.0.1:40248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T01:43:13.567595Z","caller":"traceutil/trace.go:171","msg":"trace[1135936072] linearizableReadLoop","detail":"{readStateIndex:2221; appliedIndex:2219; }","duration":"625.299226ms","start":"2026-05-12T01:43:12.942261Z","end":"2026-05-12T01:43:13.56756Z","steps":["trace[1135936072] 'read index received' (duration: 116.530008ms)","trace[1135936072] 'applied index is now lower than readState.Index' (duration: 508.766088ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T01:43:13.567772Z","caller":"traceutil/trace.go:171","msg":"trace[2007541151] transaction","detail":"{read_only:false; response_revision:2143; number_of_response:1; }","duration":"626.244956ms","start":"2026-05-12T01:43:12.941512Z","end":"2026-05-12T01:43:13.567757Z","steps":["trace[2007541151] 'process raft request' (duration: 117.272506ms)","trace[2007541151] 'compare' (duration: 507.898669ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T01:43:13.56756Z","caller":"traceutil/trace.go:171","msg":"trace[323332643] transaction","detail":"{read_only:false; response_revision:2147; number_of_response:1; }","duration":"623.959053ms","start":"2026-05-12T01:43:12.943581Z","end":"2026-05-12T01:43:13.56754Z","steps":["trace[323332643] 'process raft request' (duration: 623.64283ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T01:43:13.56787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T01:43:12.941493Z","time spent":"626.313017ms","remote":"127.0.0.1:40080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":831,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T01:43:13.567851Z","caller":"traceutil/trace.go:171","msg":"trace[414234975] transaction","detail":"{read_only:false; response_revision:2144; number_of_response:1; }","duration":"625.589719ms","start":"2026-05-12T01:43:12.942203Z","end":"2026-05-12T01:43:13.567792Z","steps":["trace[414234975] 'process raft request' (duration: 624.797211ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T01:43:13.567912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T01:43:12.943565Z","time spent":"624.299686ms","remote":"127.0.0.1:40248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T01:43:13.567983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T01:43:12.942194Z","time spent":"625.731181ms","remote":"127.0.0.1:40248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T01:43:13.568131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"625.803861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-12T01:43:13.568186Z","caller":"traceutil/trace.go:171","msg":"trace[1937654662] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:2148; }","duration":"625.943782ms","start":"2026-05-12T01:43:12.942229Z","end":"2026-05-12T01:43:13.568172Z","steps":["trace[1937654662] 'agreement among raft nodes before linearized reading' (duration: 625.486168ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T01:43:13.568314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T01:43:12.942209Z","time spent":"626.005414ms","remote":"127.0.0.1:40044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" "} {"level":"warn","ts":"2026-05-12T01:43:13.568375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.474883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12386"} {"level":"info","ts":"2026-05-12T01:43:13.568465Z","caller":"traceutil/trace.go:171","msg":"trace[961219620] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2148; }","duration":"282.834446ms","start":"2026-05-12T01:43:13.285607Z","end":"2026-05-12T01:43:13.568441Z","steps":["trace[961219620] 'agreement among raft nodes before linearized reading' (duration: 282.305511ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T01:43:13.569423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.492034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-12T01:43:13.569582Z","caller":"traceutil/trace.go:171","msg":"trace[797183717] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2148; }","duration":"195.833358ms","start":"2026-05-12T01:43:13.373706Z","end":"2026-05-12T01:43:13.569539Z","steps":["trace[797183717] 'agreement among raft nodes before linearized reading' (duration: 194.279842ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T01:43:14.722303Z","caller":"traceutil/trace.go:171","msg":"trace[1810700208] transaction","detail":"{read_only:false; response_revision:2149; number_of_response:1; }","duration":"540.063959ms","start":"2026-05-12T01:43:14.182197Z","end":"2026-05-12T01:43:14.722261Z","steps":["trace[1810700208] 'process raft request' (duration: 539.534283ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T01:43:14.722564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T01:43:14.182175Z","time spent":"540.2476ms","remote":"127.0.0.1:40248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T01:43:14.855089Z","caller":"traceutil/trace.go:171","msg":"trace[430816403] linearizableReadLoop","detail":"{readStateIndex:2228; appliedIndex:2225; }","duration":"443.513274ms","start":"2026-05-12T01:43:14.411545Z","end":"2026-05-12T01:43:14.855058Z","steps":["trace[430816403] 'read index received' (duration: 310.174609ms)","trace[430816403] 'applied index is now lower than readState.Index' (duration: 133.337435ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T01:43:14.855139Z","caller":"traceutil/trace.go:171","msg":"trace[1884767780] transaction","detail":"{read_only:false; response_revision:2150; number_of_response:1; }","duration":"481.023353ms","start":"2026-05-12T01:43:14.37407Z","end":"2026-05-12T01:43:14.855093Z","steps":["trace[1884767780] 'process raft request' (duration: 456.583696ms)","trace[1884767780] 'compare' (duration: 24.127034ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T01:43:14.855253Z","caller":"traceutil/trace.go:171","msg":"trace[264097641] transaction","detail":"{read_only:false; response_revision:2151; number_of_response:1; }","duration":"481.115384ms","start":"2026-05-12T01:43:14.374126Z","end":"2026-05-12T01:43:14.855241Z","steps":["trace[264097641] 'process raft request' (duration: 480.872502ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T01:43:14.855426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T01:43:14.3741Z","time spent":"481.187355ms","remote":"127.0.0.1:40248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T01:43:14.855481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T01:43:14.374051Z","time spent":"481.171385ms","remote":"127.0.0.1:40248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T01:43:14.855603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.911053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-12T01:43:14.855683Z","caller":"traceutil/trace.go:171","msg":"trace[44843672] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2151; }","duration":"277.056756ms","start":"2026-05-12T01:43:14.578606Z","end":"2026-05-12T01:43:14.855663Z","steps":["trace[44843672] 'agreement among raft nodes before linearized reading' (duration: 276.829573ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T01:43:14.85591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.341733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T01:43:14.856084Z","caller":"traceutil/trace.go:171","msg":"trace[1560055475] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2151; }","duration":"444.509845ms","start":"2026-05-12T01:43:14.411499Z","end":"2026-05-12T01:43:14.856009Z","steps":["trace[1560055475] 'agreement among raft nodes before linearized reading' (duration: 443.936069ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T01:43:14.856268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T01:43:14.411482Z","time spent":"444.772697ms","remote":"127.0.0.1:45848","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-12T01:43:15.423183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.544545ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-12T01:43:15.423565Z","caller":"traceutil/trace.go:171","msg":"trace[926658801] transaction","detail":"{read_only:false; response_revision:2152; number_of_response:1; }","duration":"474.385392ms","start":"2026-05-12T01:43:14.949154Z","end":"2026-05-12T01:43:15.42354Z","steps":["trace[926658801] 'process raft request' (duration: 355.705126ms)","trace[926658801] 'compare' (duration: 117.441984ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T01:43:15.423743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T01:43:14.949141Z","time spent":"474.526823ms","remote":"127.0.0.1:40248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T01:43:15.424127Z","caller":"traceutil/trace.go:171","msg":"trace[1341097604] transaction","detail":"{read_only:false; response_revision:2153; number_of_response:1; }","duration":"430.728392ms","start":"2026-05-12T01:43:14.993385Z","end":"2026-05-12T01:43:15.424114Z","steps":["trace[1341097604] 'process raft request' (duration: 429.892114ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T01:43:15.424232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T01:43:14.99335Z","time spent":"430.847823ms","remote":"127.0.0.1:40248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T01:44:30.305655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.190692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-05-12T01:44:30.305758Z","caller":"traceutil/trace.go:171","msg":"trace[125617376] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2599; }","duration":"123.303485ms","start":"2026-05-12T01:44:30.182441Z","end":"2026-05-12T01:44:30.305745Z","steps":["trace[125617376] 'range keys from in-memory index tree' (duration: 123.015597ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T01:44:39.368134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.708164ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T01:44:39.368338Z","caller":"traceutil/trace.go:171","msg":"trace[1129259211] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2702; }","duration":"308.937391ms","start":"2026-05-12T01:44:39.059375Z","end":"2026-05-12T01:44:39.368313Z","steps":["trace[1129259211] 'range keys from in-memory index tree' (duration: 308.630192ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T01:44:39.371522Z","caller":"traceutil/trace.go:171","msg":"trace[764760216] transaction","detail":"{read_only:false; response_revision:2703; number_of_response:1; }","duration":"241.974775ms","start":"2026-05-12T01:44:39.129528Z","end":"2026-05-12T01:44:39.371502Z","steps":["trace[764760216] 'process raft request' (duration: 241.854442ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T01:44:39.372091Z","caller":"traceutil/trace.go:171","msg":"trace[1662423514] transaction","detail":"{read_only:false; response_revision:2704; number_of_response:1; }","duration":"238.304562ms","start":"2026-05-12T01:44:39.133766Z","end":"2026-05-12T01:44:39.372071Z","steps":["trace[1662423514] 'process raft request' (duration: 238.188119ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T01:44:40.169406Z","caller":"traceutil/trace.go:171","msg":"trace[909037912] transaction","detail":"{read_only:false; response_revision:2705; number_of_response:1; }","duration":"364.995838ms","start":"2026-05-12T01:44:39.804386Z","end":"2026-05-12T01:44:40.169382Z","steps":["trace[909037912] 'process raft request' (duration: 364.747722ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T01:44:40.170346Z","caller":"traceutil/trace.go:171","msg":"trace[759024252] linearizableReadLoop","detail":"{readStateIndex:2803; appliedIndex:2801; }","duration":"297.209162ms","start":"2026-05-12T01:44:39.873122Z","end":"2026-05-12T01:44:40.170331Z","steps":["trace[759024252] 'read index received' (duration: 296.011862ms)","trace[759024252] 'applied index is now lower than readState.Index' (duration: 1.17593ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T01:44:40.170367Z","caller":"traceutil/trace.go:171","msg":"trace[2010176187] transaction","detail":"{read_only:false; response_revision:2706; number_of_response:1; }","duration":"317.112882ms","start":"2026-05-12T01:44:39.853237Z","end":"2026-05-12T01:44:40.17035Z","steps":["trace[2010176187] 'process raft request' (duration: 316.990398ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T01:44:40.17049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.354446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-12T01:44:40.170529Z","caller":"traceutil/trace.go:171","msg":"trace[206305966] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:2706; }","duration":"297.438358ms","start":"2026-05-12T01:44:39.873081Z","end":"2026-05-12T01:44:40.170519Z","steps":["trace[206305966] 'agreement among raft nodes before linearized reading' (duration: 297.335505ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T01:44:40.170531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T01:44:39.853201Z","time spent":"317.203884ms","remote":"127.0.0.1:40248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T01:44:40.172106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.061854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-12T01:44:40.172151Z","caller":"traceutil/trace.go:171","msg":"trace[1174176514] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2706; }","duration":"115.320521ms","start":"2026-05-12T01:44:40.056818Z","end":"2026-05-12T01:44:40.172138Z","steps":["trace[1174176514] 'agreement among raft nodes before linearized reading' (duration: 113.973147ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T01:44:40.172965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T01:44:39.80437Z","time spent":"365.169262ms","remote":"127.0.0.1:40248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T01:45:11.959336Z","caller":"traceutil/trace.go:171","msg":"trace[1324805778] transaction","detail":"{read_only:false; response_revision:2880; number_of_response:1; }","duration":"482.165153ms","start":"2026-05-12T01:45:11.47714Z","end":"2026-05-12T01:45:11.959305Z","steps":["trace[1324805778] 'process raft request' (duration: 481.864727ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T01:45:11.959519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T01:45:11.477115Z","time spent":"482.311407ms","remote":"127.0.0.1:40248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T01:45:11.983729Z","caller":"traceutil/trace.go:171","msg":"trace[1907577796] transaction","detail":"{read_only:false; response_revision:2881; number_of_response:1; }","duration":"503.620621ms","start":"2026-05-12T01:45:11.48008Z","end":"2026-05-12T01:45:11.983701Z","steps":["trace[1907577796] 'process raft request' (duration: 503.351565ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T01:45:11.983861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T01:45:11.480056Z","time spent":"503.739013ms","remote":"127.0.0.1:40248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T01:45:12.362469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.842968ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-12T01:45:12.362595Z","caller":"traceutil/trace.go:171","msg":"trace[1574590687] transaction","detail":"{read_only:false; response_revision:2882; number_of_response:1; }","duration":"357.007673ms","start":"2026-05-12T01:45:12.00557Z","end":"2026-05-12T01:45:12.362577Z","steps":["trace[1574590687] 'process raft request' (duration: 222.975191ms)","trace[1574590687] 'compare' (duration: 133.715385ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T01:45:12.362653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T01:45:12.005546Z","time spent":"357.080285ms","remote":"127.0.0.1:40044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T01:45:12.407089Z","caller":"traceutil/trace.go:171","msg":"trace[358653137] transaction","detail":"{read_only:false; response_revision:2883; number_of_response:1; }","duration":"100.456342ms","start":"2026-05-12T01:45:12.30661Z","end":"2026-05-12T01:45:12.407067Z","steps":["trace[358653137] 'process raft request' (duration: 99.834058ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T01:45:12.652812Z","caller":"traceutil/trace.go:171","msg":"trace[957101791] linearizableReadLoop","detail":"{readStateIndex:2988; appliedIndex:2987; }","duration":"246.201628ms","start":"2026-05-12T01:45:12.406589Z","end":"2026-05-12T01:45:12.652791Z","steps":["trace[957101791] 'read index received' (duration: 221.028999ms)","trace[957101791] 'applied index is now lower than readState.Index' (duration: 25.171599ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T01:45:12.652837Z","caller":"traceutil/trace.go:171","msg":"trace[767144503] transaction","detail":"{read_only:false; response_revision:2884; number_of_response:1; }","duration":"287.836141ms","start":"2026-05-12T01:45:12.364984Z","end":"2026-05-12T01:45:12.65282Z","steps":["trace[767144503] 'process raft request' (duration: 262.61607ms)","trace[767144503] 'compare' (duration: 24.896593ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T01:45:12.652968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.544663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-05-12T01:45:12.653465Z","caller":"traceutil/trace.go:171","msg":"trace[268746762] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:2884; }","duration":"288.034515ms","start":"2026-05-12T01:45:12.365381Z","end":"2026-05-12T01:45:12.653415Z","steps":["trace[268746762] 'agreement among raft nodes before linearized reading' (duration: 287.511223ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T01:45:12.673597Z","caller":"traceutil/trace.go:171","msg":"trace[1992962747] transaction","detail":"{read_only:false; response_revision:2886; number_of_response:1; }","duration":"260.744177ms","start":"2026-05-12T01:45:12.412836Z","end":"2026-05-12T01:45:12.67358Z","steps":["trace[1992962747] 'process raft request' (duration: 260.651105ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T01:45:12.673607Z","caller":"traceutil/trace.go:171","msg":"trace[1055914970] transaction","detail":"{read_only:false; response_revision:2885; number_of_response:1; }","duration":"260.831369ms","start":"2026-05-12T01:45:12.412747Z","end":"2026-05-12T01:45:12.673578Z","steps":["trace[1055914970] 'process raft request' (duration: 260.651645ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T01:45:12.674001Z","caller":"traceutil/trace.go:171","msg":"trace[1519678511] transaction","detail":"{read_only:false; response_revision:2887; number_of_response:1; }","duration":"261.110625ms","start":"2026-05-12T01:45:12.412874Z","end":"2026-05-12T01:45:12.673985Z","steps":["trace[1519678511] 'process raft request' (duration: 260.672235ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T01:45:12.674136Z","caller":"traceutil/trace.go:171","msg":"trace[365226725] transaction","detail":"{read_only:false; response_revision:2888; number_of_response:1; }","duration":"260.576604ms","start":"2026-05-12T01:45:12.413517Z","end":"2026-05-12T01:45:12.674094Z","steps":["trace[365226725] 'process raft request' (duration: 260.363749ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T01:45:12.674185Z","caller":"traceutil/trace.go:171","msg":"trace[471663760] transaction","detail":"{read_only:false; response_revision:2889; number_of_response:1; }","duration":"259.252624ms","start":"2026-05-12T01:45:12.41492Z","end":"2026-05-12T01:45:12.674086Z","steps":["trace[471663760] 'process raft request' (duration: 259.08294ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T01:45:12.6746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.012904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T01:45:12.674661Z","caller":"traceutil/trace.go:171","msg":"trace[1990717060] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2889; }","duration":"261.123046ms","start":"2026-05-12T01:45:12.413528Z","end":"2026-05-12T01:45:12.674651Z","steps":["trace[1990717060] 'agreement among raft nodes before linearized reading' (duration: 261.029444ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T01:46:23.506285Z","caller":"traceutil/trace.go:171","msg":"trace[28799380] transaction","detail":"{read_only:false; response_revision:3258; number_of_response:1; }","duration":"457.019473ms","start":"2026-05-12T01:46:23.049239Z","end":"2026-05-12T01:46:23.506258Z","steps":["trace[28799380] 'process raft request' (duration: 456.84758ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T01:46:23.506567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T01:46:23.049225Z","time spent":"457.255207ms","remote":"127.0.0.1:40248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T01:46:23.507217Z","caller":"traceutil/trace.go:171","msg":"trace[1221571235] transaction","detail":"{read_only:false; response_revision:3259; number_of_response:1; }","duration":"457.828538ms","start":"2026-05-12T01:46:23.049369Z","end":"2026-05-12T01:46:23.507197Z","steps":["trace[1221571235] 'process raft request' (duration: 457.692606ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T01:46:23.507394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T01:46:23.04936Z","time spent":"457.92728ms","remote":"127.0.0.1:40248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T01:46:23.507497Z","caller":"traceutil/trace.go:171","msg":"trace[1225688138] transaction","detail":"{read_only:false; response_revision:3260; number_of_response:1; }","duration":"111.47445ms","start":"2026-05-12T01:46:23.395923Z","end":"2026-05-12T01:46:23.507398Z","steps":["trace[1225688138] 'process raft request' (duration: 111.241276ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T01:46:55.35438Z","caller":"traceutil/trace.go:171","msg":"trace[2100693344] linearizableReadLoop","detail":"{readStateIndex:3544; appliedIndex:3543; }","duration":"121.977095ms","start":"2026-05-12T01:46:55.232382Z","end":"2026-05-12T01:46:55.35436Z","steps":["trace[2100693344] 'read index received' (duration: 121.904644ms)","trace[2100693344] 'applied index is now lower than readState.Index' (duration: 71.681µs)"],"step_count":2} {"level":"info","ts":"2026-05-12T01:46:55.354477Z","caller":"traceutil/trace.go:171","msg":"trace[1262434379] transaction","detail":"{read_only:false; response_revision:3419; number_of_response:1; }","duration":"122.323082ms","start":"2026-05-12T01:46:55.232123Z","end":"2026-05-12T01:46:55.354446Z","steps":["trace[1262434379] 'process raft request' (duration: 122.111488ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T01:46:55.354592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.184429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-12T01:46:55.354635Z","caller":"traceutil/trace.go:171","msg":"trace[378701813] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3419; }","duration":"122.292651ms","start":"2026-05-12T01:46:55.232332Z","end":"2026-05-12T01:46:55.354625Z","steps":["trace[378701813] 'agreement among raft nodes before linearized reading' (duration: 122.135558ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T01:46:55.377019Z","caller":"traceutil/trace.go:171","msg":"trace[598626293] transaction","detail":"{read_only:false; response_revision:3420; number_of_response:1; }","duration":"143.755116ms","start":"2026-05-12T01:46:55.233239Z","end":"2026-05-12T01:46:55.376994Z","steps":["trace[598626293] 'process raft request' (duration: 143.629834ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T01:49:12.083866Z","caller":"traceutil/trace.go:171","msg":"trace[1961795188] transaction","detail":"{read_only:false; response_revision:4735; number_of_response:1; }","duration":"108.130889ms","start":"2026-05-12T01:49:11.975616Z","end":"2026-05-12T01:49:12.083746Z","steps":["trace[1961795188] 'process raft request' (duration: 100.362631ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T01:49:17.117553Z","caller":"traceutil/trace.go:171","msg":"trace[668621182] transaction","detail":"{read_only:false; response_revision:4844; number_of_response:1; }","duration":"117.801578ms","start":"2026-05-12T01:49:16.999727Z","end":"2026-05-12T01:49:17.117528Z","steps":["trace[668621182] 'process raft request' (duration: 117.660276ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T01:49:17.141445Z","caller":"traceutil/trace.go:171","msg":"trace[84972771] transaction","detail":"{read_only:false; response_revision:4845; number_of_response:1; }","duration":"136.070193ms","start":"2026-05-12T01:49:17.00534Z","end":"2026-05-12T01:49:17.14141Z","steps":["trace[84972771] 'process raft request' (duration: 135.778159ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T01:49:17.194898Z","caller":"traceutil/trace.go:171","msg":"trace[1588137991] transaction","detail":"{read_only:false; response_revision:4846; number_of_response:1; }","duration":"118.314184ms","start":"2026-05-12T01:49:17.07654Z","end":"2026-05-12T01:49:17.194854Z","steps":["trace[1588137991] 'process raft request' (duration: 117.517163ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T01:49:17.195302Z","caller":"traceutil/trace.go:171","msg":"trace[203086884] linearizableReadLoop","detail":"{readStateIndex:5027; appliedIndex:5025; }","duration":"148.880842ms","start":"2026-05-12T01:49:17.046391Z","end":"2026-05-12T01:49:17.195272Z","steps":["trace[203086884] 'read index received' (duration: 70.912572ms)","trace[203086884] 'applied index is now lower than readState.Index' (duration: 77.96576ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T01:49:17.195481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.064345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T01:49:17.195539Z","caller":"traceutil/trace.go:171","msg":"trace[321617538] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:4847; }","duration":"149.228907ms","start":"2026-05-12T01:49:17.046297Z","end":"2026-05-12T01:49:17.195526Z","steps":["trace[321617538] 'agreement among raft nodes before linearized reading' (duration: 149.123045ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T01:49:17.544039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.11321ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-12T01:49:17.544166Z","caller":"traceutil/trace.go:171","msg":"trace[180790675] linearizableReadLoop","detail":"{readStateIndex:5032; appliedIndex:5031; }","duration":"279.931585ms","start":"2026-05-12T01:49:17.264214Z","end":"2026-05-12T01:49:17.544146Z","steps":["trace[180790675] 'read index received' (duration: 86.609032ms)","trace[180790675] 'applied index is now lower than readState.Index' (duration: 193.320743ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T01:49:17.544301Z","caller":"traceutil/trace.go:171","msg":"trace[1325329688] transaction","detail":"{read_only:false; response_revision:4849; number_of_response:1; }","duration":"298.996181ms","start":"2026-05-12T01:49:17.245285Z","end":"2026-05-12T01:49:17.544281Z","steps":["trace[1325329688] 'process raft request' (duration: 105.527716ms)","trace[1325329688] 'compare' (duration: 192.982839ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T01:49:17.544641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.398412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1131"} {"level":"info","ts":"2026-05-12T01:49:17.544778Z","caller":"traceutil/trace.go:171","msg":"trace[945914510] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:4849; }","duration":"280.575654ms","start":"2026-05-12T01:49:17.264181Z","end":"2026-05-12T01:49:17.544757Z","steps":["trace[945914510] 'agreement among raft nodes before linearized reading' (duration: 280.27595ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T01:49:17.582888Z","caller":"traceutil/trace.go:171","msg":"trace[177143525] transaction","detail":"{read_only:false; response_revision:4850; number_of_response:1; }","duration":"309.274576ms","start":"2026-05-12T01:49:17.273562Z","end":"2026-05-12T01:49:17.582837Z","steps":["trace[177143525] 'process raft request' (duration: 309.112883ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T01:49:17.583052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T01:49:17.273542Z","time spent":"309.435538ms","remote":"127.0.0.1:49146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3387,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-12T01:49:17.5832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.220418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T01:49:17.583288Z","caller":"traceutil/trace.go:171","msg":"trace[1765277606] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4850; }","duration":"172.334821ms","start":"2026-05-12T01:49:17.410929Z","end":"2026-05-12T01:49:17.583263Z","steps":["trace[1765277606] 'agreement among raft nodes before linearized reading' (duration: 172.082447ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T01:49:17.583299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.714911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-05-12T01:49:17.583413Z","caller":"traceutil/trace.go:171","msg":"trace[74333768] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:4850; }","duration":"294.892684ms","start":"2026-05-12T01:49:17.288494Z","end":"2026-05-12T01:49:17.583386Z","steps":["trace[74333768] 'agreement among raft nodes before linearized reading' (duration: 294.514179ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T01:49:17.583996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.273468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2195929"} {"level":"info","ts":"2026-05-12T01:49:17.584081Z","caller":"traceutil/trace.go:171","msg":"trace[581711315] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4850; }","duration":"159.394969ms","start":"2026-05-12T01:49:17.424665Z","end":"2026-05-12T01:49:17.584059Z","steps":["trace[581711315] 'agreement among raft nodes before linearized reading' (duration: 158.183252ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T01:49:24.459584Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12233920746600555319,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-12T01:49:24.973429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.247769ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-12T01:49:24.973574Z","caller":"traceutil/trace.go:171","msg":"trace[1481659105] transaction","detail":"{read_only:false; response_revision:4978; number_of_response:1; }","duration":"1.058518372s","start":"2026-05-12T01:49:23.915043Z","end":"2026-05-12T01:49:24.973561Z","steps":["trace[1481659105] 'process raft request' (duration: 1.058467411s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T01:49:24.973617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T01:49:23.91503Z","time spent":"1.058566562s","remote":"127.0.0.1:40162","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13278,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T01:49:24.97389Z","caller":"traceutil/trace.go:171","msg":"trace[48832193] transaction","detail":"{read_only:false; response_revision:4977; number_of_response:1; }","duration":"1.065875773s","start":"2026-05-12T01:49:23.908005Z","end":"2026-05-12T01:49:24.97388Z","steps":["trace[48832193] 'process raft request' (duration: 887.118278ms)","trace[48832193] 'compare' (duration: 177.956584ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T01:49:24.97394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T01:49:23.907986Z","time spent":"1.065931075s","remote":"127.0.0.1:40250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1563,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T01:49:24.991245Z","caller":"traceutil/trace.go:171","msg":"trace[1038688275] transaction","detail":"{read_only:false; response_revision:4979; number_of_response:1; }","duration":"1.027780974s","start":"2026-05-12T01:49:23.963441Z","end":"2026-05-12T01:49:24.991222Z","steps":["trace[1038688275] 'process raft request' (duration: 1.02752685s)"],"step_count":1} {"level":"info","ts":"2026-05-12T01:49:24.991266Z","caller":"traceutil/trace.go:171","msg":"trace[1220595143] linearizableReadLoop","detail":"{readStateIndex:5166; appliedIndex:5161; }","duration":"1.032683352s","start":"2026-05-12T01:49:23.958547Z","end":"2026-05-12T01:49:24.991231Z","steps":["trace[1220595143] 'read index received' (duration: 836.585695ms)","trace[1220595143] 'applied index is now lower than readState.Index' (duration: 196.096747ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T01:49:24.991319Z","caller":"traceutil/trace.go:171","msg":"trace[1267341872] transaction","detail":"{read_only:false; response_revision:4980; number_of_response:1; }","duration":"863.088753ms","start":"2026-05-12T01:49:24.128151Z","end":"2026-05-12T01:49:24.99124Z","steps":["trace[1267341872] 'process raft request' (duration: 862.938391ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T01:49:24.991378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T01:49:23.963422Z","time spent":"1.027893855s","remote":"127.0.0.1:36614","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2229,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-12T01:49:24.991391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T01:49:24.128131Z","time spent":"863.217304ms","remote":"127.0.0.1:40248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T01:49:24.991421Z","caller":"traceutil/trace.go:171","msg":"trace[1830719811] transaction","detail":"{read_only:false; response_revision:4981; number_of_response:1; }","duration":"794.890344ms","start":"2026-05-12T01:49:24.196402Z","end":"2026-05-12T01:49:24.991292Z","steps":["trace[1830719811] 'process raft request' (duration: 794.744802ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T01:49:24.991636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T01:49:24.196379Z","time spent":"795.184698ms","remote":"127.0.0.1:40248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T01:49:24.991901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.0333125s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-05-12T01:49:24.991981Z","caller":"traceutil/trace.go:171","msg":"trace[984925068] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4981; }","duration":"1.033418932s","start":"2026-05-12T01:49:23.958541Z","end":"2026-05-12T01:49:24.99196Z","steps":["trace[984925068] 'agreement among raft nodes before linearized reading' (duration: 1.032824864s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T01:49:24.992026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T01:49:23.958523Z","time spent":"1.033493553s","remote":"127.0.0.1:40144","response type":"/etcdserverpb.KV/Range","request count":0,"request size":147,"response count":1,"response size":1958,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" "} {"level":"info","ts":"2026-05-12T01:49:25.025023Z","caller":"traceutil/trace.go:171","msg":"trace[737498589] transaction","detail":"{read_only:false; response_revision:4982; number_of_response:1; }","duration":"131.056492ms","start":"2026-05-12T01:49:24.893944Z","end":"2026-05-12T01:49:25.025001Z","steps":["trace[737498589] 'process raft request' (duration: 130.89478ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T01:49:25.025383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"738.912435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-12T01:49:25.0254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"745.446267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-12T01:49:25.025417Z","caller":"traceutil/trace.go:171","msg":"trace[1182121854] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4986; }","duration":"738.996477ms","start":"2026-05-12T01:49:24.286414Z","end":"2026-05-12T01:49:25.02541Z","steps":["trace[1182121854] 'agreement among raft nodes before linearized reading' (duration: 738.890426ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T01:49:25.025428Z","caller":"traceutil/trace.go:171","msg":"trace[503891215] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4986; }","duration":"745.508017ms","start":"2026-05-12T01:49:24.279913Z","end":"2026-05-12T01:49:25.025421Z","steps":["trace[503891215] 'agreement among raft nodes before linearized reading' (duration: 745.437146ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T01:49:25.025439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T01:49:24.286394Z","time spent":"739.039397ms","remote":"127.0.0.1:40248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-12T01:49:25.025456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T01:49:24.279896Z","time spent":"745.555709ms","remote":"127.0.0.1:40248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-12T01:49:25.025714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"668.923382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-12T01:49:25.02574Z","caller":"traceutil/trace.go:171","msg":"trace[266025379] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4986; }","duration":"668.985094ms","start":"2026-05-12T01:49:24.356749Z","end":"2026-05-12T01:49:25.025734Z","steps":["trace[266025379] 'agreement among raft nodes before linearized reading' (duration: 668.908472ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T01:49:25.02577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T01:49:24.35673Z","time spent":"669.036094ms","remote":"127.0.0.1:40248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-12T01:49:25.025777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"738.852205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-12T01:49:25.025799Z","caller":"traceutil/trace.go:171","msg":"trace[1969267928] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4986; }","duration":"738.896105ms","start":"2026-05-12T01:49:24.286897Z","end":"2026-05-12T01:49:25.025793Z","steps":["trace[1969267928] 'agreement among raft nodes before linearized reading' (duration: 738.837075ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T01:49:25.025825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T01:49:24.286887Z","time spent":"738.932436ms","remote":"127.0.0.1:40248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-12T01:49:25.025625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.866865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-57e7b202-391b-4360-bfb3-6b155a435d82\" ","response":"range_response_count:1 size:3646"} {"level":"warn","ts":"2026-05-12T01:49:25.025872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"569.323587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-12T01:49:25.02589Z","caller":"traceutil/trace.go:171","msg":"trace[1834184035] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4986; }","duration":"569.374718ms","start":"2026-05-12T01:49:24.45651Z","end":"2026-05-12T01:49:25.025885Z","steps":["trace[1834184035] 'agreement among raft nodes before linearized reading' (duration: 569.327487ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T01:49:25.025915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T01:49:24.456491Z","time spent":"569.418849ms","remote":"127.0.0.1:40248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-12T01:49:25.025913Z","caller":"traceutil/trace.go:171","msg":"trace[692153048] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-57e7b202-391b-4360-bfb3-6b155a435d82; range_end:; response_count:1; response_revision:4986; }","duration":"483.16541ms","start":"2026-05-12T01:49:24.542714Z","end":"2026-05-12T01:49:25.025879Z","steps":["trace[692153048] 'agreement among raft nodes before linearized reading' (duration: 482.869315ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T01:49:25.02597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"612.444557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T01:49:25.025987Z","caller":"traceutil/trace.go:171","msg":"trace[989111263] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4986; }","duration":"612.489578ms","start":"2026-05-12T01:49:24.413493Z","end":"2026-05-12T01:49:25.025983Z","steps":["trace[989111263] 'agreement among raft nodes before linearized reading' (duration: 612.461098ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T01:49:25.026007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T01:49:24.413478Z","time spent":"612.526008ms","remote":"127.0.0.1:45848","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-12T01:49:25.026021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T01:49:24.542695Z","time spent":"483.289471ms","remote":"127.0.0.1:40162","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3669,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-57e7b202-391b-4360-bfb3-6b155a435d82\" "} {"level":"warn","ts":"2026-05-12T01:49:25.026428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"999.462899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2195929"} {"level":"info","ts":"2026-05-12T01:49:25.026507Z","caller":"traceutil/trace.go:171","msg":"trace[1730411833] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4986; }","duration":"999.571871ms","start":"2026-05-12T01:49:24.026913Z","end":"2026-05-12T01:49:25.026485Z","steps":["trace[1730411833] 'agreement among raft nodes before linearized reading' (duration: 998.531807ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T01:49:25.026567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T01:49:24.026895Z","time spent":"999.655872ms","remote":"127.0.0.1:40106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":47,"response size":2195952,"request content":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" "} {"level":"info","ts":"2026-05-12T01:49:25.139263Z","caller":"traceutil/trace.go:171","msg":"trace[1940647010] transaction","detail":"{read_only:false; response_revision:4988; number_of_response:1; }","duration":"106.853184ms","start":"2026-05-12T01:49:25.032367Z","end":"2026-05-12T01:49:25.13922Z","steps":["trace[1940647010] 'process raft request' (duration: 53.885528ms)","trace[1940647010] 'compare' (duration: 52.771814ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T01:49:25.139586Z","caller":"traceutil/trace.go:171","msg":"trace[249407317] transaction","detail":"{read_only:false; response_revision:4989; number_of_response:1; }","duration":"107.129618ms","start":"2026-05-12T01:49:25.032443Z","end":"2026-05-12T01:49:25.139573Z","steps":["trace[249407317] 'process raft request' (duration: 106.718513ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T01:49:25.140056Z","caller":"traceutil/trace.go:171","msg":"trace[1938543191] transaction","detail":"{read_only:false; response_revision:4990; number_of_response:1; }","duration":"107.474504ms","start":"2026-05-12T01:49:25.032555Z","end":"2026-05-12T01:49:25.14003Z","steps":["trace[1938543191] 'process raft request' (duration: 106.928606ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T01:49:25.140404Z","caller":"traceutil/trace.go:171","msg":"trace[755868974] transaction","detail":"{read_only:false; response_revision:4991; number_of_response:1; }","duration":"107.678477ms","start":"2026-05-12T01:49:25.032702Z","end":"2026-05-12T01:49:25.140381Z","steps":["trace[755868974] 'process raft request' (duration: 107.20447ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T01:49:25.650222Z","caller":"traceutil/trace.go:171","msg":"trace[225829486] linearizableReadLoop","detail":"{readStateIndex:5179; appliedIndex:5178; }","duration":"415.93596ms","start":"2026-05-12T01:49:25.234264Z","end":"2026-05-12T01:49:25.6502Z","steps":["trace[225829486] 'read index received' (duration: 415.778177ms)","trace[225829486] 'applied index is now lower than readState.Index' (duration: 156.273µs)"],"step_count":2} {"level":"info","ts":"2026-05-12T01:49:25.650276Z","caller":"traceutil/trace.go:171","msg":"trace[526284731] transaction","detail":"{read_only:false; response_revision:4994; number_of_response:1; }","duration":"432.021043ms","start":"2026-05-12T01:49:25.218231Z","end":"2026-05-12T01:49:25.650252Z","steps":["trace[526284731] 'process raft request' (duration: 431.79966ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T01:49:25.650394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T01:49:25.218217Z","time spent":"432.103734ms","remote":"127.0.0.1:40248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T01:49:25.650504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.234154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/prometheus-operated\" ","response":"range_response_count:1 size:993"} {"level":"info","ts":"2026-05-12T01:49:25.650561Z","caller":"traceutil/trace.go:171","msg":"trace[954897580] range","detail":"{range_begin:/registry/services/specs/monitoring/prometheus-operated; range_end:; response_count:1; response_revision:4994; }","duration":"416.325455ms","start":"2026-05-12T01:49:25.23422Z","end":"2026-05-12T01:49:25.650546Z","steps":["trace[954897580] 'agreement among raft nodes before linearized reading' (duration: 416.093582ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T01:49:25.650603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T01:49:25.234203Z","time spent":"416.388356ms","remote":"127.0.0.1:40178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":1,"response size":1016,"request content":"key:\"/registry/services/specs/monitoring/prometheus-operated\" "} {"level":"warn","ts":"2026-05-12T01:49:25.669796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.573204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T01:49:25.669876Z","caller":"traceutil/trace.go:171","msg":"trace[38277025] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretimports/; range_end:/registry/secretgen.carvel.dev/secretimports0; response_count:0; response_revision:4995; }","duration":"402.680776ms","start":"2026-05-12T01:49:25.267167Z","end":"2026-05-12T01:49:25.669848Z","steps":["trace[38277025] 'agreement among raft nodes before linearized reading' (duration: 402.572574ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T01:49:25.669914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T01:49:25.267152Z","time spent":"402.752286ms","remote":"127.0.0.1:46252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true "} {"level":"warn","ts":"2026-05-12T01:49:25.917057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.117603ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-12T01:49:25.917147Z","caller":"traceutil/trace.go:171","msg":"trace[1091534714] linearizableReadLoop","detail":"{readStateIndex:5182; appliedIndex:5181; }","duration":"181.298689ms","start":"2026-05-12T01:49:25.735835Z","end":"2026-05-12T01:49:25.917134Z","steps":["trace[1091534714] 'read index received' (duration: 23.971693ms)","trace[1091534714] 'applied index is now lower than readState.Index' (duration: 157.325836ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T01:49:25.917208Z","caller":"traceutil/trace.go:171","msg":"trace[811631511] transaction","detail":"{read_only:false; response_revision:4997; number_of_response:1; }","duration":"201.134645ms","start":"2026-05-12T01:49:25.716065Z","end":"2026-05-12T01:49:25.9172Z","steps":["trace[811631511] 'process raft request' (duration: 43.816449ms)","trace[811631511] 'compare' (duration: 157.011262ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T01:49:25.917417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.627373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-12T01:49:25.917489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.644264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T01:49:25.917514Z","caller":"traceutil/trace.go:171","msg":"trace[850426892] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:4997; }","duration":"181.717945ms","start":"2026-05-12T01:49:25.735789Z","end":"2026-05-12T01:49:25.917507Z","steps":["trace[850426892] 'agreement among raft nodes before linearized reading' (duration: 181.653294ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T01:49:25.917514Z","caller":"traceutil/trace.go:171","msg":"trace[101149833] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:4997; }","duration":"168.788196ms","start":"2026-05-12T01:49:25.748706Z","end":"2026-05-12T01:49:25.917494Z","steps":["trace[101149833] 'agreement among raft nodes before linearized reading' (duration: 168.550283ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T01:49:28.027293Z","caller":"traceutil/trace.go:171","msg":"trace[1985260069] transaction","detail":"{read_only:false; response_revision:5019; number_of_response:1; }","duration":"372.754032ms","start":"2026-05-12T01:49:27.654515Z","end":"2026-05-12T01:49:28.027269Z","steps":["trace[1985260069] 'process raft request' (duration: 372.597029ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T01:49:28.027446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T01:49:27.654496Z","time spent":"372.870413ms","remote":"127.0.0.1:40248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T01:49:28.031631Z","caller":"traceutil/trace.go:171","msg":"trace[1350035451] linearizableReadLoop","detail":"{readStateIndex:5206; appliedIndex:5204; }","duration":"188.283102ms","start":"2026-05-12T01:49:27.843332Z","end":"2026-05-12T01:49:28.031616Z","steps":["trace[1350035451] 'read index received' (duration: 183.82845ms)","trace[1350035451] 'applied index is now lower than readState.Index' (duration: 4.453752ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T01:49:28.031775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.439774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-12T01:49:28.031814Z","caller":"traceutil/trace.go:171","msg":"trace[1305051109] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:5020; }","duration":"188.515875ms","start":"2026-05-12T01:49:27.843289Z","end":"2026-05-12T01:49:28.031805Z","steps":["trace[1305051109] 'agreement among raft nodes before linearized reading' (duration: 188.399644ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T01:49:28.032106Z","caller":"traceutil/trace.go:171","msg":"trace[201692893] transaction","detail":"{read_only:false; response_revision:5020; number_of_response:1; }","duration":"356.612237ms","start":"2026-05-12T01:49:27.675478Z","end":"2026-05-12T01:49:28.03209Z","steps":["trace[201692893] 'process raft request' (duration: 356.018769ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T01:49:28.032206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T01:49:27.675457Z","time spent":"356.696188ms","remote":"127.0.0.1:40248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T01:49:39.019325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"811.211963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T01:49:39.019398Z","caller":"traceutil/trace.go:171","msg":"trace[228710960] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:5146; }","duration":"811.332415ms","start":"2026-05-12T01:49:38.20805Z","end":"2026-05-12T01:49:39.019382Z","steps":["trace[228710960] 'count revisions from in-memory index tree' (duration: 811.127782ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T01:49:39.019429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T01:49:38.20803Z","time spent":"811.391286ms","remote":"127.0.0.1:47526","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true "} {"level":"warn","ts":"2026-05-12T01:49:39.019454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"692.155535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-grafana-6647f466d7-ftgmr\" ","response":"range_response_count:1 size:11848"} {"level":"info","ts":"2026-05-12T01:49:39.019518Z","caller":"traceutil/trace.go:171","msg":"trace[1604163277] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-grafana-6647f466d7-ftgmr; range_end:; response_count:1; response_revision:5146; }","duration":"692.259196ms","start":"2026-05-12T01:49:38.327242Z","end":"2026-05-12T01:49:39.019501Z","steps":["trace[1604163277] 'range keys from in-memory index tree' (duration: 692.002522ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T01:49:39.01958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"606.362304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T01:49:39.01962Z","caller":"traceutil/trace.go:171","msg":"trace[160183294] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5146; }","duration":"606.429305ms","start":"2026-05-12T01:49:38.413181Z","end":"2026-05-12T01:49:39.01961Z","steps":["trace[160183294] 'range keys from in-memory index tree' (duration: 606.308874ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T01:49:39.019645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T01:49:38.413166Z","time spent":"606.474416ms","remote":"127.0.0.1:45848","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-12T01:49:39.019583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"625.908203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-12T01:49:39.019732Z","caller":"traceutil/trace.go:171","msg":"trace[1686780682] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:5146; }","duration":"626.160997ms","start":"2026-05-12T01:49:38.393554Z","end":"2026-05-12T01:49:39.019715Z","steps":["trace[1686780682] 'count revisions from in-memory index tree' (duration: 625.821311ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T01:49:39.019858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"871.450962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3316"} {"level":"info","ts":"2026-05-12T01:49:39.019898Z","caller":"traceutil/trace.go:171","msg":"trace[2108227755] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5146; }","duration":"872.120121ms","start":"2026-05-12T01:49:38.147767Z","end":"2026-05-12T01:49:39.019888Z","steps":["trace[2108227755] 'range keys from in-memory index tree' (duration: 871.246559ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T01:49:39.019921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T01:49:38.147748Z","time spent":"872.166841ms","remote":"127.0.0.1:36416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":3339,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" "} {"level":"warn","ts":"2026-05-12T01:49:39.019985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T01:49:38.393538Z","time spent":"626.42799ms","remote":"127.0.0.1:40152","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":37,"response size":30,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"warn","ts":"2026-05-12T01:49:39.019644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"549.042125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3316"} {"level":"info","ts":"2026-05-12T01:49:39.020253Z","caller":"traceutil/trace.go:171","msg":"trace[1819550608] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5146; }","duration":"549.694944ms","start":"2026-05-12T01:49:38.470542Z","end":"2026-05-12T01:49:39.020237Z","steps":["trace[1819550608] 'range keys from in-memory index tree' (duration: 548.821272ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T01:49:39.020322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T01:49:38.470522Z","time spent":"549.787386ms","remote":"127.0.0.1:36416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":3339,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" "} {"level":"warn","ts":"2026-05-12T01:49:39.01965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T01:49:38.327226Z","time spent":"692.318557ms","remote":"127.0.0.1:40162","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":11871,"request content":"key:\"/registry/pods/monitoring/kube-prometheus-stack-grafana-6647f466d7-ftgmr\" "} {"level":"info","ts":"2026-05-12T01:49:39.159728Z","caller":"traceutil/trace.go:171","msg":"trace[829561519] transaction","detail":"{read_only:false; response_revision:5148; number_of_response:1; }","duration":"120.399605ms","start":"2026-05-12T01:49:39.039281Z","end":"2026-05-12T01:49:39.159681Z","steps":["trace[829561519] 'process raft request' (duration: 90.497164ms)","trace[829561519] 'compare' (duration: 29.470135ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T01:49:39.160277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.217899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:1451"} {"level":"info","ts":"2026-05-12T01:49:39.160342Z","caller":"traceutil/trace.go:171","msg":"trace[665901774] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5148; }","duration":"119.921449ms","start":"2026-05-12T01:49:39.040402Z","end":"2026-05-12T01:49:39.160323Z","steps":["trace[665901774] 'agreement among raft nodes before linearized reading' (duration: 119.050437ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T01:49:39.314337Z","caller":"traceutil/trace.go:171","msg":"trace[1792287935] transaction","detail":"{read_only:false; response_revision:5154; number_of_response:1; }","duration":"126.762843ms","start":"2026-05-12T01:49:39.187538Z","end":"2026-05-12T01:49:39.314301Z","steps":["trace[1792287935] 'process raft request' (duration: 72.887742ms)","trace[1792287935] 'compare' (duration: 53.556987ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T01:49:39.314375Z","caller":"traceutil/trace.go:171","msg":"trace[427358720] linearizableReadLoop","detail":"{readStateIndex:5346; appliedIndex:5345; }","duration":"124.484002ms","start":"2026-05-12T01:49:39.189861Z","end":"2026-05-12T01:49:39.314345Z","steps":["trace[427358720] 'read index received' (duration: 70.53541ms)","trace[427358720] 'applied index is now lower than readState.Index' (duration: 53.942742ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T01:49:39.314602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.887008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/rabbitmq-keystone.18aeae507a0fcc0f\" ","response":"range_response_count:1 size:740"} {"level":"info","ts":"2026-05-12T01:49:39.315206Z","caller":"traceutil/trace.go:171","msg":"trace[1328146154] range","detail":"{range_begin:/registry/events/openstack/rabbitmq-keystone.18aeae507a0fcc0f; range_end:; response_count:1; response_revision:5155; }","duration":"136.531687ms","start":"2026-05-12T01:49:39.178644Z","end":"2026-05-12T01:49:39.315176Z","steps":["trace[1328146154] 'agreement among raft nodes before linearized reading' (duration: 135.783057ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T01:49:39.315464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.441704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-05-12T01:49:39.315492Z","caller":"traceutil/trace.go:171","msg":"trace[847125078] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5155; }","duration":"122.495115ms","start":"2026-05-12T01:49:39.192989Z","end":"2026-05-12T01:49:39.315484Z","steps":["trace[847125078] 'agreement among raft nodes before linearized reading' (duration: 122.420684ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T01:49:39.601618Z","caller":"traceutil/trace.go:171","msg":"trace[2089167080] transaction","detail":"{read_only:false; response_revision:5161; number_of_response:1; }","duration":"231.26733ms","start":"2026-05-12T01:49:39.370323Z","end":"2026-05-12T01:49:39.601591Z","steps":["trace[2089167080] 'process raft request' (duration: 158.769553ms)","trace[2089167080] 'compare' (duration: 72.366145ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T01:49:39.788383Z","caller":"traceutil/trace.go:171","msg":"trace[784054144] linearizableReadLoop","detail":"{readStateIndex:5354; appliedIndex:5353; }","duration":"103.950299ms","start":"2026-05-12T01:49:39.684402Z","end":"2026-05-12T01:49:39.788353Z","steps":["trace[784054144] 'read index received' (duration: 66.566625ms)","trace[784054144] 'applied index is now lower than readState.Index' (duration: 37.382264ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T01:49:39.788411Z","caller":"traceutil/trace.go:171","msg":"trace[210444368] transaction","detail":"{read_only:false; response_revision:5162; number_of_response:1; }","duration":"127.492153ms","start":"2026-05-12T01:49:39.660878Z","end":"2026-05-12T01:49:39.788371Z","steps":["trace[210444368] 'process raft request' (duration: 90.099429ms)","trace[210444368] 'compare' (duration: 37.228822ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T01:49:39.7886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.160592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T01:49:39.788742Z","caller":"traceutil/trace.go:171","msg":"trace[887646848] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:5162; }","duration":"104.353855ms","start":"2026-05-12T01:49:39.684365Z","end":"2026-05-12T01:49:39.788718Z","steps":["trace[887646848] 'agreement among raft nodes before linearized reading' (duration: 104.096792ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T01:49:40.748016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.271999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T01:49:40.74814Z","caller":"traceutil/trace.go:171","msg":"trace[1129535946] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5166; }","duration":"333.404261ms","start":"2026-05-12T01:49:40.414713Z","end":"2026-05-12T01:49:40.748117Z","steps":["trace[1129535946] 'range keys from in-memory index tree' (duration: 333.198018ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T01:49:40.748207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T01:49:40.414454Z","time spent":"333.738025ms","remote":"127.0.0.1:45850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-12T01:50:08.69488Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2868} {"level":"info","ts":"2026-05-12T01:50:08.768691Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2868,"took":"72.027922ms","hash":1915375591,"current-db-size-bytes":51859456,"current-db-size":"52 MB","current-db-size-in-use-bytes":27312128,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-05-12T01:50:08.76876Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1915375591,"revision":2868,"compact-revision":-1} {"level":"info","ts":"2026-05-12T01:50:19.582076Z","caller":"traceutil/trace.go:171","msg":"trace[595316402] transaction","detail":"{read_only:false; response_revision:5499; number_of_response:1; }","duration":"132.832682ms","start":"2026-05-12T01:50:19.449221Z","end":"2026-05-12T01:50:19.582053Z","steps":["trace[595316402] 'process raft request' (duration: 100.65289ms)","trace[595316402] 'compare' (duration: 31.535013ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T01:50:19.801Z","caller":"traceutil/trace.go:171","msg":"trace[1854237790] transaction","detail":"{read_only:false; response_revision:5501; number_of_response:1; }","duration":"213.363913ms","start":"2026-05-12T01:50:19.587619Z","end":"2026-05-12T01:50:19.800983Z","steps":["trace[1854237790] 'process raft request' (duration: 213.016658ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T01:50:19.801163Z","caller":"traceutil/trace.go:171","msg":"trace[2045714282] transaction","detail":"{read_only:false; response_revision:5502; number_of_response:1; }","duration":"213.432683ms","start":"2026-05-12T01:50:19.587723Z","end":"2026-05-12T01:50:19.801156Z","steps":["trace[2045714282] 'process raft request' (duration: 213.033568ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T01:50:19.801252Z","caller":"traceutil/trace.go:171","msg":"trace[268086599] transaction","detail":"{read_only:false; response_revision:5503; number_of_response:1; }","duration":"213.16812ms","start":"2026-05-12T01:50:19.588077Z","end":"2026-05-12T01:50:19.801245Z","steps":["trace[268086599] 'process raft request' (duration: 212.707614ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T01:52:32.994482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"579.524436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T01:52:32.99456Z","caller":"traceutil/trace.go:171","msg":"trace[85266856] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6324; }","duration":"579.651101ms","start":"2026-05-12T01:52:32.414895Z","end":"2026-05-12T01:52:32.994546Z","steps":["trace[85266856] 'range keys from in-memory index tree' (duration: 579.380451ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T01:52:32.994595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T01:52:32.41488Z","time spent":"579.706162ms","remote":"127.0.0.1:45848","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-12T01:52:32.99464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.785448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-12T01:52:32.994643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.616478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-12T01:52:32.9947Z","caller":"traceutil/trace.go:171","msg":"trace[1803887696] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:6324; }","duration":"535.871751ms","start":"2026-05-12T01:52:32.458808Z","end":"2026-05-12T01:52:32.994679Z","steps":["trace[1803887696] 'range keys from in-memory index tree' (duration: 535.634374ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T01:52:32.994748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T01:52:32.458786Z","time spent":"535.952984ms","remote":"127.0.0.1:40248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-12T01:52:32.994764Z","caller":"traceutil/trace.go:171","msg":"trace[1302491319] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6324; }","duration":"523.842676ms","start":"2026-05-12T01:52:32.470882Z","end":"2026-05-12T01:52:32.994724Z","steps":["trace[1302491319] 'range keys from in-memory index tree' (duration: 523.476573ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T01:52:32.99484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T01:52:32.470861Z","time spent":"523.963449ms","remote":"127.0.0.1:40248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-12T01:52:32.994504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.152996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-12T01:52:32.994964Z","caller":"traceutil/trace.go:171","msg":"trace[1700341727] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6324; }","duration":"524.662053ms","start":"2026-05-12T01:52:32.470292Z","end":"2026-05-12T01:52:32.994955Z","steps":["trace[1700341727] 'range keys from in-memory index tree' (duration: 523.993381ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T01:52:32.995052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T01:52:32.470281Z","time spent":"524.762376ms","remote":"127.0.0.1:40248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-12T01:52:32.997029Z","caller":"traceutil/trace.go:171","msg":"trace[555904546] linearizableReadLoop","detail":"{readStateIndex:6556; appliedIndex:6555; }","duration":"264.155173ms","start":"2026-05-12T01:52:32.732859Z","end":"2026-05-12T01:52:32.997014Z","steps":["trace[555904546] 'read index received' (duration: 263.964726ms)","trace[555904546] 'applied index is now lower than readState.Index' (duration: 189.557µs)"],"step_count":2} {"level":"info","ts":"2026-05-12T01:52:32.997217Z","caller":"traceutil/trace.go:171","msg":"trace[491345965] transaction","detail":"{read_only:false; response_revision:6325; number_of_response:1; }","duration":"266.192402ms","start":"2026-05-12T01:52:32.73101Z","end":"2026-05-12T01:52:32.997202Z","steps":["trace[491345965] 'process raft request' (duration: 265.868611ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T01:52:32.997786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.924589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-12T01:52:32.997844Z","caller":"traceutil/trace.go:171","msg":"trace[58220562] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:6325; }","duration":"265.030603ms","start":"2026-05-12T01:52:32.732805Z","end":"2026-05-12T01:52:32.997836Z","steps":["trace[58220562] 'agreement among raft nodes before linearized reading' (duration: 264.895638ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T01:52:32.999472Z","caller":"traceutil/trace.go:171","msg":"trace[1432036612] transaction","detail":"{read_only:false; response_revision:6326; number_of_response:1; }","duration":"204.746366ms","start":"2026-05-12T01:52:32.79471Z","end":"2026-05-12T01:52:32.999457Z","steps":["trace[1432036612] 'process raft request' (duration: 204.387304ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T01:52:32.999597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.29026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-05-12T01:52:32.999643Z","caller":"traceutil/trace.go:171","msg":"trace[1688094785] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:6327; }","duration":"241.374233ms","start":"2026-05-12T01:52:32.758261Z","end":"2026-05-12T01:52:32.999635Z","steps":["trace[1688094785] 'agreement among raft nodes before linearized reading' (duration: 241.136715ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T01:52:32.999734Z","caller":"traceutil/trace.go:171","msg":"trace[2053976926] transaction","detail":"{read_only:false; response_revision:6327; number_of_response:1; }","duration":"167.780677ms","start":"2026-05-12T01:52:32.831945Z","end":"2026-05-12T01:52:32.999726Z","steps":["trace[2053976926] 'process raft request' (duration: 167.213898ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T01:52:34.586454Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12233920746600558664,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-12T01:52:35.087029Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12233920746600558664,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-12T01:52:35.588086Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12233920746600558664,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-12T01:52:35.842151Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.763710182s","expected-duration":"1s"} {"level":"info","ts":"2026-05-12T01:52:35.855127Z","caller":"traceutil/trace.go:171","msg":"trace[1870206660] transaction","detail":"{read_only:false; response_revision:6333; number_of_response:1; }","duration":"1.777379091s","start":"2026-05-12T01:52:34.077723Z","end":"2026-05-12T01:52:35.855103Z","steps":["trace[1870206660] 'process raft request' (duration: 1.764598752s)","trace[1870206660] 'compare' (duration: 12.560291ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T01:52:35.855158Z","caller":"traceutil/trace.go:171","msg":"trace[734072519] transaction","detail":"{read_only:false; response_revision:6334; number_of_response:1; }","duration":"849.024036ms","start":"2026-05-12T01:52:35.006122Z","end":"2026-05-12T01:52:35.855146Z","steps":["trace[734072519] 'process raft request' (duration: 848.908292ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T01:52:35.855242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T01:52:35.006086Z","time spent":"849.102718ms","remote":"127.0.0.1:40248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T01:52:35.855242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T01:52:34.077703Z","time spent":"1.777477454s","remote":"127.0.0.1:40248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T01:52:35.855148Z","caller":"traceutil/trace.go:171","msg":"trace[1155203403] linearizableReadLoop","detail":"{readStateIndex:6566; appliedIndex:6563; }","duration":"1.769139273s","start":"2026-05-12T01:52:34.085989Z","end":"2026-05-12T01:52:35.855129Z","steps":["trace[1155203403] 'read index received' (duration: 1.756346124s)","trace[1155203403] 'applied index is now lower than readState.Index' (duration: 12.792429ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T01:52:35.85533Z","caller":"traceutil/trace.go:171","msg":"trace[468850356] transaction","detail":"{read_only:false; response_revision:6335; number_of_response:1; }","duration":"848.994515ms","start":"2026-05-12T01:52:35.006329Z","end":"2026-05-12T01:52:35.855324Z","steps":["trace[468850356] 'process raft request' (duration: 848.767537ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T01:52:35.855384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T01:52:35.0063Z","time spent":"849.052947ms","remote":"127.0.0.1:40248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T01:52:35.855561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.769556538s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-12T01:52:35.855606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.004538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T01:52:35.855636Z","caller":"traceutil/trace.go:171","msg":"trace[637959542] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:6335; }","duration":"412.073741ms","start":"2026-05-12T01:52:35.443554Z","end":"2026-05-12T01:52:35.855627Z","steps":["trace[637959542] 'agreement among raft nodes before linearized reading' (duration: 412.0347ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T01:52:35.855616Z","caller":"traceutil/trace.go:171","msg":"trace[680557271] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:6335; }","duration":"1.769655562s","start":"2026-05-12T01:52:34.085951Z","end":"2026-05-12T01:52:35.855606Z","steps":["trace[680557271] 'agreement among raft nodes before linearized reading' (duration: 1.769430224s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T01:52:35.855695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"909.245261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T01:52:35.85572Z","caller":"traceutil/trace.go:171","msg":"trace[743362795] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:6335; }","duration":"909.298003ms","start":"2026-05-12T01:52:34.946416Z","end":"2026-05-12T01:52:35.855714Z","steps":["trace[743362795] 'agreement among raft nodes before linearized reading' (duration: 909.259612ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T01:52:35.855741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T01:52:34.9464Z","time spent":"909.335274ms","remote":"127.0.0.1:36190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true "} {"level":"warn","ts":"2026-05-12T01:52:35.855708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T01:52:34.085934Z","time spent":"1.769765345s","remote":"127.0.0.1:40248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-12T01:52:35.855859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.43664782s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T01:52:35.855885Z","caller":"traceutil/trace.go:171","msg":"trace[1817013185] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6335; }","duration":"1.436707562s","start":"2026-05-12T01:52:34.419172Z","end":"2026-05-12T01:52:35.85588Z","steps":["trace[1817013185] 'agreement among raft nodes before linearized reading' (duration: 1.43666958s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T01:52:35.855903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T01:52:34.419156Z","time spent":"1.436742673s","remote":"127.0.0.1:45848","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-12T01:52:35.856034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.585970973s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-05-12T01:52:35.856057Z","caller":"traceutil/trace.go:171","msg":"trace[244610959] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:6335; }","duration":"1.586040394s","start":"2026-05-12T01:52:34.270011Z","end":"2026-05-12T01:52:35.856051Z","steps":["trace[244610959] 'agreement among raft nodes before linearized reading' (duration: 1.585980562s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T01:52:35.856075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T01:52:34.269979Z","time spent":"1.586091726s","remote":"127.0.0.1:40248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":581,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-05-12T01:52:35.856154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.592665748s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-12T01:52:35.856175Z","caller":"traceutil/trace.go:171","msg":"trace[2051237805] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6335; }","duration":"1.592720609s","start":"2026-05-12T01:52:34.263448Z","end":"2026-05-12T01:52:35.856169Z","steps":["trace[2051237805] 'agreement among raft nodes before linearized reading' (duration: 1.592671378s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T01:52:35.856193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T01:52:34.263427Z","time spent":"1.592761781s","remote":"127.0.0.1:40248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-12T01:52:35.856383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"757.303617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-12T01:52:35.856433Z","caller":"traceutil/trace.go:171","msg":"trace[1644936988] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:6335; }","duration":"757.407461ms","start":"2026-05-12T01:52:35.099018Z","end":"2026-05-12T01:52:35.856426Z","steps":["trace[1644936988] 'agreement among raft nodes before linearized reading' (duration: 757.342279ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T01:52:35.856479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T01:52:35.098989Z","time spent":"757.481514ms","remote":"127.0.0.1:40272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":4,"response size":30,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"warn","ts":"2026-05-12T01:52:35.856673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"734.521583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-12T01:52:35.856709Z","caller":"traceutil/trace.go:171","msg":"trace[1006201500] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:6335; }","duration":"734.599335ms","start":"2026-05-12T01:52:35.122103Z","end":"2026-05-12T01:52:35.856702Z","steps":["trace[1006201500] 'agreement among raft nodes before linearized reading' (duration: 734.547344ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T01:52:35.856729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T01:52:35.12207Z","time spent":"734.652477ms","remote":"127.0.0.1:36726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":30,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-05-12T01:52:35.855572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.627058955s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"warn","ts":"2026-05-12T01:52:35.85911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"853.489336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-12T01:52:35.859129Z","caller":"traceutil/trace.go:171","msg":"trace[1253012571] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:6335; }","duration":"1.630636815s","start":"2026-05-12T01:52:34.228479Z","end":"2026-05-12T01:52:35.859116Z","steps":["trace[1253012571] 'agreement among raft nodes before linearized reading' (duration: 1.627013023s)"],"step_count":1} {"level":"info","ts":"2026-05-12T01:52:35.859145Z","caller":"traceutil/trace.go:171","msg":"trace[1109269865] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6335; }","duration":"853.574818ms","start":"2026-05-12T01:52:35.005561Z","end":"2026-05-12T01:52:35.859136Z","steps":["trace[1109269865] 'agreement among raft nodes before linearized reading' (duration: 850.075531ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T01:52:35.859166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T01:52:35.005534Z","time spent":"853.62692ms","remote":"127.0.0.1:40248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-12T01:52:35.859247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T01:52:34.228464Z","time spent":"1.63077277s","remote":"127.0.0.1:40162","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-05-12T01:52:35.859344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"852.938507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-12T01:52:35.859372Z","caller":"traceutil/trace.go:171","msg":"trace[74996842] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:6335; }","duration":"852.987959ms","start":"2026-05-12T01:52:35.006372Z","end":"2026-05-12T01:52:35.85936Z","steps":["trace[74996842] 'agreement among raft nodes before linearized reading' (duration: 852.879335ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T01:52:35.859397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T01:52:35.006365Z","time spent":"853.027561ms","remote":"127.0.0.1:40248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-12T01:52:35.859485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"853.463705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-05-12T01:52:35.855663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T01:52:35.443526Z","time spent":"412.130393ms","remote":"127.0.0.1:47536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"info","ts":"2026-05-12T01:52:35.863638Z","caller":"traceutil/trace.go:171","msg":"trace[1727354418] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6335; }","duration":"853.502526ms","start":"2026-05-12T01:52:35.005999Z","end":"2026-05-12T01:52:35.859502Z","steps":["trace[1727354418] 'agreement among raft nodes before linearized reading' (duration: 853.445235ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T01:52:35.863733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T01:52:35.005989Z","time spent":"857.713707ms","remote":"127.0.0.1:40248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-12T01:53:32.402463Z","caller":"traceutil/trace.go:171","msg":"trace[1732131232] transaction","detail":"{read_only:false; response_revision:6818; number_of_response:1; }","duration":"101.818854ms","start":"2026-05-12T01:53:32.300616Z","end":"2026-05-12T01:53:32.402435Z","steps":["trace[1732131232] 'process raft request' (duration: 98.842515ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T01:53:32.413798Z","caller":"traceutil/trace.go:171","msg":"trace[1212149181] transaction","detail":"{read_only:false; response_revision:6819; number_of_response:1; }","duration":"113.000008ms","start":"2026-05-12T01:53:32.300775Z","end":"2026-05-12T01:53:32.413775Z","steps":["trace[1212149181] 'process raft request' (duration: 112.418281ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T01:53:32.413951Z","caller":"traceutil/trace.go:171","msg":"trace[2769376] transaction","detail":"{read_only:false; response_revision:6820; number_of_response:1; }","duration":"113.115941ms","start":"2026-05-12T01:53:32.300828Z","end":"2026-05-12T01:53:32.413944Z","steps":["trace[2769376] 'process raft request' (duration: 112.472352ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T01:53:32.41401Z","caller":"traceutil/trace.go:171","msg":"trace[1942879470] transaction","detail":"{read_only:false; response_revision:6821; number_of_response:1; }","duration":"112.116302ms","start":"2026-05-12T01:53:32.301888Z","end":"2026-05-12T01:53:32.414004Z","steps":["trace[1942879470] 'process raft request' (duration: 111.455582ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T01:53:32.414069Z","caller":"traceutil/trace.go:171","msg":"trace[669671920] transaction","detail":"{read_only:false; response_revision:6822; number_of_response:1; }","duration":"111.947107ms","start":"2026-05-12T01:53:32.302116Z","end":"2026-05-12T01:53:32.414063Z","steps":["trace[669671920] 'process raft request' (duration: 111.258626ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T01:53:50.454869Z","caller":"traceutil/trace.go:171","msg":"trace[161768057] transaction","detail":"{read_only:false; response_revision:6963; number_of_response:1; }","duration":"112.730428ms","start":"2026-05-12T01:53:50.342121Z","end":"2026-05-12T01:53:50.454852Z","steps":["trace[161768057] 'process raft request' (duration: 93.992196ms)","trace[161768057] 'compare' (duration: 18.292589ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T01:53:54.525997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.270237ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-12T01:53:54.526159Z","caller":"traceutil/trace.go:171","msg":"trace[276186078] linearizableReadLoop","detail":"{readStateIndex:7237; appliedIndex:7236; }","duration":"112.380303ms","start":"2026-05-12T01:53:54.413767Z","end":"2026-05-12T01:53:54.526148Z","steps":["trace[276186078] 'read index received' (duration: 51.181µs)","trace[276186078] 'applied index is now lower than readState.Index' (duration: 112.328492ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T01:53:54.526214Z","caller":"traceutil/trace.go:171","msg":"trace[1068148130] transaction","detail":"{read_only:false; response_revision:6982; number_of_response:1; }","duration":"151.131955ms","start":"2026-05-12T01:53:54.375074Z","end":"2026-05-12T01:53:54.526206Z","steps":["trace[1068148130] 'process raft request' (duration: 28.59207ms)","trace[1068148130] 'compare' (duration: 122.188345ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T01:53:54.526599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.829077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T01:53:54.52663Z","caller":"traceutil/trace.go:171","msg":"trace[560611751] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6983; }","duration":"112.907419ms","start":"2026-05-12T01:53:54.413713Z","end":"2026-05-12T01:53:54.52662Z","steps":["trace[560611751] 'agreement among raft nodes before linearized reading' (duration: 112.664682ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T01:53:54.755323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.550168ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-12T01:53:54.755505Z","caller":"traceutil/trace.go:171","msg":"trace[1987974920] linearizableReadLoop","detail":"{readStateIndex:7239; appliedIndex:7238; }","duration":"229.293896ms","start":"2026-05-12T01:53:54.526181Z","end":"2026-05-12T01:53:54.755475Z","steps":["trace[1987974920] 'read index received' (duration: 117.47828ms)","trace[1987974920] 'applied index is now lower than readState.Index' (duration: 111.813716ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T01:53:54.755666Z","caller":"traceutil/trace.go:171","msg":"trace[142455467] transaction","detail":"{read_only:false; response_revision:6984; number_of_response:1; }","duration":"280.443673ms","start":"2026-05-12T01:53:54.475199Z","end":"2026-05-12T01:53:54.755642Z","steps":["trace[142455467] 'process raft request' (duration: 168.414841ms)","trace[142455467] 'compare' (duration: 111.316922ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T01:53:54.756227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.12735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/bindings.rabbitmq.com\" ","response":"range_response_count:1 size:5746"} {"level":"info","ts":"2026-05-12T01:53:54.756277Z","caller":"traceutil/trace.go:171","msg":"trace[2052853247] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/bindings.rabbitmq.com; range_end:; response_count:1; response_revision:6984; }","duration":"290.207923ms","start":"2026-05-12T01:53:54.466053Z","end":"2026-05-12T01:53:54.756261Z","steps":["trace[2052853247] 'agreement among raft nodes before linearized reading' (duration: 290.086099ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T01:53:54.756552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.539404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T01:53:54.756579Z","caller":"traceutil/trace.go:171","msg":"trace[578301627] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6984; }","duration":"228.588646ms","start":"2026-05-12T01:53:54.527982Z","end":"2026-05-12T01:53:54.756571Z","steps":["trace[578301627] 'agreement among raft nodes before linearized reading' (duration: 228.536194ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T01:53:54.757583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.944575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-12T01:53:54.76021Z","caller":"traceutil/trace.go:171","msg":"trace[351207849] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6984; }","duration":"216.035816ms","start":"2026-05-12T01:53:54.54357Z","end":"2026-05-12T01:53:54.759605Z","steps":["trace[351207849] 'agreement among raft nodes before linearized reading' (duration: 213.947756ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T01:53:54.760628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.074378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-12T01:53:54.760782Z","caller":"traceutil/trace.go:171","msg":"trace[480603575] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:6984; }","duration":"139.303845ms","start":"2026-05-12T01:53:54.621411Z","end":"2026-05-12T01:53:54.760714Z","steps":["trace[480603575] 'agreement among raft nodes before linearized reading' (duration: 135.923028ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T01:53:54.986433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.785118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/permissions.rabbitmq.com\" ","response":"range_response_count:1 size:6485"} {"level":"info","ts":"2026-05-12T01:53:54.986523Z","caller":"traceutil/trace.go:171","msg":"trace[1376355251] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/permissions.rabbitmq.com; range_end:; response_count:1; response_revision:6989; }","duration":"125.905221ms","start":"2026-05-12T01:53:54.860594Z","end":"2026-05-12T01:53:54.986499Z","steps":["trace[1376355251] 'range keys from in-memory index tree' (duration: 125.641594ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T01:55:08.701336Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5347} {"level":"info","ts":"2026-05-12T01:55:08.791441Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5347,"took":"88.9191ms","hash":3230230553,"current-db-size-bytes":51859456,"current-db-size":"52 MB","current-db-size-in-use-bytes":18395136,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-05-12T01:55:08.791491Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3230230553,"revision":5347,"compact-revision":2868}