{"level":"warn","ts":"2026-04-16T00:11:13.187516Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-16T00:11:13.187616Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.74: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.204.45.74:2380","--initial-cluster=instance=https://199.204.45.74:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.74:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.74: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-04-16T00:11:13.187705Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-16T00:11:13.187717Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.74:2380"]} {"level":"info","ts":"2026-04-16T00:11:13.187733Z","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-04-16T00:11:13.188375Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.74:2379"]} {"level":"info","ts":"2026-04-16T00:11:13.188448Z","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.204.45.74:2380"],"listen-peer-urls":["https://199.204.45.74:2380"],"advertise-client-urls":["https://199.204.45.74:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.74:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.74: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-04-16T00:11:13.325077Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"136.400395ms"} {"level":"info","ts":"2026-04-16T00:11:13.433139Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"89ba38f964b95aa0","cluster-id":"54492f71bf65c400"} {"level":"info","ts":"2026-04-16T00:11:13.433385Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 switched to configuration voters=()"} {"level":"info","ts":"2026-04-16T00:11:13.433473Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became follower at term 0"} {"level":"info","ts":"2026-04-16T00:11:13.433584Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 89ba38f964b95aa0 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-16T00:11:13.433666Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became follower at term 1"} {"level":"info","ts":"2026-04-16T00:11:13.43379Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 switched to configuration voters=(9924307372652190368)"} {"level":"warn","ts":"2026-04-16T00:11:13.498421Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-16T00:11:13.50112Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-16T00:11:13.502327Z","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-04-16T00:11:13.504817Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"89ba38f964b95aa0","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-16T00:11:13.505002Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"89ba38f964b95aa0","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-16T00:11:13.505217Z","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-04-16T00:11:13.505304Z","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-04-16T00:11:13.505317Z","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-04-16T00:11:13.508494Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 switched to configuration voters=(9924307372652190368)"} {"level":"info","ts":"2026-04-16T00:11:13.509379Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"54492f71bf65c400","local-member-id":"89ba38f964b95aa0","added-peer-id":"89ba38f964b95aa0","added-peer-peer-urls":["https://199.204.45.74:2380"]} {"level":"info","ts":"2026-04-16T00:11:13.51472Z","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-04-16T00:11:13.514898Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.74:2380"} {"level":"info","ts":"2026-04-16T00:11:13.514996Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.74:2380"} {"level":"info","ts":"2026-04-16T00:11:13.51503Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"89ba38f964b95aa0","initial-advertise-peer-urls":["https://199.204.45.74:2380"],"listen-peer-urls":["https://199.204.45.74:2380"],"advertise-client-urls":["https://199.204.45.74:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.74:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-16T00:11:13.515078Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-16T00:11:13.83597Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 is starting a new election at term 1"} {"level":"info","ts":"2026-04-16T00:11:13.83604Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-16T00:11:13.836106Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 received MsgPreVoteResp from 89ba38f964b95aa0 at term 1"} {"level":"info","ts":"2026-04-16T00:11:13.836136Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became candidate at term 2"} {"level":"info","ts":"2026-04-16T00:11:13.836148Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 received MsgVoteResp from 89ba38f964b95aa0 at term 2"} {"level":"info","ts":"2026-04-16T00:11:13.836192Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became leader at term 2"} {"level":"info","ts":"2026-04-16T00:11:13.836214Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 89ba38f964b95aa0 elected leader 89ba38f964b95aa0 at term 2"} {"level":"info","ts":"2026-04-16T00:11:13.838436Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"89ba38f964b95aa0","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.74:2379]}","request-path":"/0/members/89ba38f964b95aa0/attributes","cluster-id":"54492f71bf65c400","publish-timeout":"7s"} {"level":"info","ts":"2026-04-16T00:11:13.838557Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-16T00:11:13.838574Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-16T00:11:13.838828Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T00:11:13.839037Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-16T00:11:13.839088Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-16T00:11:13.839847Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"54492f71bf65c400","local-member-id":"89ba38f964b95aa0","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T00:11:13.840104Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T00:11:13.84017Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T00:11:13.840403Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.74:2379"} {"level":"info","ts":"2026-04-16T00:11:13.840418Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-16T00:11:22.50589Z","caller":"traceutil/trace.go:171","msg":"trace[953599052] linearizableReadLoop","detail":"{readStateIndex:361; appliedIndex:359; }","duration":"178.90174ms","start":"2026-04-16T00:11:22.326964Z","end":"2026-04-16T00:11:22.505866Z","steps":["trace[953599052] 'read index received' (duration: 15.649141ms)","trace[953599052] 'applied index is now lower than readState.Index' (duration: 163.251559ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T00:11:22.506216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.914048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/bootstrap-token-g9zx9n\" ","response":"range_response_count:1 size:540"} {"level":"info","ts":"2026-04-16T00:11:22.506264Z","caller":"traceutil/trace.go:171","msg":"trace[123327070] transaction","detail":"{read_only:false; response_revision:351; number_of_response:1; }","duration":"221.333956ms","start":"2026-04-16T00:11:22.284916Z","end":"2026-04-16T00:11:22.50625Z","steps":["trace[123327070] 'process raft request' (duration: 171.78099ms)","trace[123327070] 'compare' (duration: 49.078531ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T00:11:22.506309Z","caller":"traceutil/trace.go:171","msg":"trace[898180932] range","detail":"{range_begin:/registry/secrets/kube-system/bootstrap-token-g9zx9n; range_end:; response_count:1; response_revision:351; }","duration":"151.049812ms","start":"2026-04-16T00:11:22.355242Z","end":"2026-04-16T00:11:22.506292Z","steps":["trace[898180932] 'agreement among raft nodes before linearized reading' (duration: 150.874336ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:11:22.506448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.456266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"warn","ts":"2026-04-16T00:11:22.50651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.667022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-04-16T00:11:22.50654Z","caller":"traceutil/trace.go:171","msg":"trace[1073757059] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:351; }","duration":"128.709894ms","start":"2026-04-16T00:11:22.377815Z","end":"2026-04-16T00:11:22.506525Z","steps":["trace[1073757059] 'agreement among raft nodes before linearized reading' (duration: 128.670023ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:11:22.506533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.225717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-04-16T00:11:22.506544Z","caller":"traceutil/trace.go:171","msg":"trace[611591858] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:351; }","duration":"179.596281ms","start":"2026-04-16T00:11:22.326927Z","end":"2026-04-16T00:11:22.506523Z","steps":["trace[611591858] 'agreement among raft nodes before linearized reading' (duration: 179.131137ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:11:22.506571Z","caller":"traceutil/trace.go:171","msg":"trace[2093049418] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:351; }","duration":"151.29524ms","start":"2026-04-16T00:11:22.355264Z","end":"2026-04-16T00:11:22.50656Z","steps":["trace[2093049418] 'agreement among raft nodes before linearized reading' (duration: 151.222648ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:11:37.415252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.705259ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-16T00:11:37.415517Z","caller":"traceutil/trace.go:171","msg":"trace[1078274667] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"444.150749ms","start":"2026-04-16T00:11:36.97135Z","end":"2026-04-16T00:11:37.4155Z","steps":["trace[1078274667] 'process raft request' (duration: 444.065577ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:11:37.415506Z","caller":"traceutil/trace.go:171","msg":"trace[301437116] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"459.272629ms","start":"2026-04-16T00:11:36.956197Z","end":"2026-04-16T00:11:37.415469Z","steps":["trace[301437116] 'process raft request' (duration: 199.886648ms)","trace[301437116] 'compare' (duration: 258.521323ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T00:11:37.415644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:11:36.956182Z","time spent":"459.399893ms","remote":"127.0.0.1:37162","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":73770,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-16T00:11:37.415751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:11:36.971326Z","time spent":"444.240272ms","remote":"127.0.0.1:37162","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18041,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T00:11:39.765575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.294237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T00:11:39.765695Z","caller":"traceutil/trace.go:171","msg":"trace[1838723493] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:574; }","duration":"132.461462ms","start":"2026-04-16T00:11:39.633209Z","end":"2026-04-16T00:11:39.76567Z","steps":["trace[1838723493] 'range keys from in-memory index tree' (duration: 132.225155ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:11:39.765693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.871691ms","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-04-16T00:11:39.765768Z","caller":"traceutil/trace.go:171","msg":"trace[1681420396] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:574; }","duration":"103.979134ms","start":"2026-04-16T00:11:39.661769Z","end":"2026-04-16T00:11:39.765748Z","steps":["trace[1681420396] 'range keys from in-memory index tree' (duration: 103.729336ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:11:39.765975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.498767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-16T00:11:39.766028Z","caller":"traceutil/trace.go:171","msg":"trace[827544546] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:574; }","duration":"199.588671ms","start":"2026-04-16T00:11:39.56643Z","end":"2026-04-16T00:11:39.766018Z","steps":["trace[827544546] 'range keys from in-memory index tree' (duration: 199.382344ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:11:39.882916Z","caller":"traceutil/trace.go:171","msg":"trace[815525997] linearizableReadLoop","detail":"{readStateIndex:595; appliedIndex:593; }","duration":"110.437249ms","start":"2026-04-16T00:11:39.77246Z","end":"2026-04-16T00:11:39.882897Z","steps":["trace[815525997] 'read index received' (duration: 59.94169ms)","trace[815525997] 'applied index is now lower than readState.Index' (duration: 50.494879ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T00:11:39.883013Z","caller":"traceutil/trace.go:171","msg":"trace[74024542] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"111.081911ms","start":"2026-04-16T00:11:39.771898Z","end":"2026-04-16T00:11:39.88298Z","steps":["trace[74024542] 'process raft request' (duration: 106.69986ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:11:39.883091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.605225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/envoy-gateway-gateway-helm-envoy-gateway-role\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T00:11:39.883164Z","caller":"traceutil/trace.go:171","msg":"trace[15313026] range","detail":"{range_begin:/registry/clusterroles/envoy-gateway-gateway-helm-envoy-gateway-role; range_end:; response_count:0; response_revision:576; }","duration":"110.712619ms","start":"2026-04-16T00:11:39.772434Z","end":"2026-04-16T00:11:39.883147Z","steps":["trace[15313026] 'agreement among raft nodes before linearized reading' (duration: 110.583735ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:11:40.090539Z","caller":"traceutil/trace.go:171","msg":"trace[1843880852] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"179.787814ms","start":"2026-04-16T00:11:39.910721Z","end":"2026-04-16T00:11:40.090509Z","steps":["trace[1843880852] 'process raft request' (duration: 179.629199ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:11:40.710176Z","caller":"traceutil/trace.go:171","msg":"trace[1777252751] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"238.404489ms","start":"2026-04-16T00:11:40.471732Z","end":"2026-04-16T00:11:40.710137Z","steps":["trace[1777252751] 'process raft request' (duration: 234.648238ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:11:40.960286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.205074ms","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":"info","ts":"2026-04-16T00:11:40.96036Z","caller":"traceutil/trace.go:171","msg":"trace[124060359] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:580; }","duration":"176.326248ms","start":"2026-04-16T00:11:40.784014Z","end":"2026-04-16T00:11:40.960341Z","steps":["trace[124060359] 'range keys from in-memory index tree' (duration: 176.132131ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:11:44.003072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.32143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T00:11:44.003156Z","caller":"traceutil/trace.go:171","msg":"trace[1921912709] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:599; }","duration":"361.442063ms","start":"2026-04-16T00:11:43.641696Z","end":"2026-04-16T00:11:44.003138Z","steps":["trace[1921912709] 'range keys from in-memory index tree' (duration: 361.253238ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:11:44.003195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:11:43.641681Z","time spent":"361.505656ms","remote":"127.0.0.1:37122","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-04-16T00:11:44.076336Z","caller":"traceutil/trace.go:171","msg":"trace[1752317935] linearizableReadLoop","detail":"{readStateIndex:620; appliedIndex:619; }","duration":"231.290865ms","start":"2026-04-16T00:11:43.84502Z","end":"2026-04-16T00:11:44.076311Z","steps":["trace[1752317935] 'read index received' (duration: 230.070786ms)","trace[1752317935] 'applied index is now lower than readState.Index' (duration: 1.198899ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T00:11:44.076388Z","caller":"traceutil/trace.go:171","msg":"trace[997285674] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"357.183319ms","start":"2026-04-16T00:11:43.719186Z","end":"2026-04-16T00:11:44.07637Z","steps":["trace[997285674] 'process raft request' (duration: 356.343622ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:11:44.076553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:11:43.719162Z","time spent":"357.278002ms","remote":"127.0.0.1:37220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T00:11:44.076729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.681558ms","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-04-16T00:11:44.07683Z","caller":"traceutil/trace.go:171","msg":"trace[1968124512] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:600; }","duration":"231.809463ms","start":"2026-04-16T00:11:43.844986Z","end":"2026-04-16T00:11:44.076795Z","steps":["trace[1968124512] 'agreement among raft nodes before linearized reading' (duration: 231.536763ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:11:44.076795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.372994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-16T00:11:44.076917Z","caller":"traceutil/trace.go:171","msg":"trace[1680026259] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:600; }","duration":"180.521559ms","start":"2026-04-16T00:11:43.896373Z","end":"2026-04-16T00:11:44.076895Z","steps":["trace[1680026259] 'agreement among raft nodes before linearized reading' (duration: 180.277761ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:11:44.300692Z","caller":"traceutil/trace.go:171","msg":"trace[1710998770] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"219.861235ms","start":"2026-04-16T00:11:44.080812Z","end":"2026-04-16T00:11:44.300673Z","steps":["trace[1710998770] 'process raft request' (duration: 219.743241ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:11:44.773964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.527577ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T00:11:44.774148Z","caller":"traceutil/trace.go:171","msg":"trace[1365504698] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"692.026186ms","start":"2026-04-16T00:11:44.0821Z","end":"2026-04-16T00:11:44.774126Z","steps":["trace[1365504698] 'process raft request' (duration: 365.278672ms)","trace[1365504698] 'compare' (duration: 326.394003ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T00:11:44.774197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:11:44.082057Z","time spent":"692.119631ms","remote":"127.0.0.1:37402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T00:11:44.774279Z","caller":"traceutil/trace.go:171","msg":"trace[1341441306] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"639.1388ms","start":"2026-04-16T00:11:44.135134Z","end":"2026-04-16T00:11:44.774273Z","steps":["trace[1341441306] 'process raft request' (duration: 638.914432ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:11:44.774311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:11:44.135093Z","time spent":"639.204753ms","remote":"127.0.0.1:37616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-16T00:11:44.774278Z","caller":"traceutil/trace.go:171","msg":"trace[763091804] linearizableReadLoop","detail":"{readStateIndex:623; appliedIndex:621; }","duration":"363.3649ms","start":"2026-04-16T00:11:44.410878Z","end":"2026-04-16T00:11:44.774243Z","steps":["trace[763091804] 'read index received' (duration: 36.431749ms)","trace[763091804] 'applied index is now lower than readState.Index' (duration: 326.928801ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T00:11:44.774534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.643019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-r76pb\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-04-16T00:11:44.774578Z","caller":"traceutil/trace.go:171","msg":"trace[2013387438] range","detail":"{range_begin:/registry/pods/kube-system/cilium-r76pb; range_end:; response_count:1; response_revision:603; }","duration":"363.729302ms","start":"2026-04-16T00:11:44.410837Z","end":"2026-04-16T00:11:44.774567Z","steps":["trace[2013387438] 'agreement among raft nodes before linearized reading' (duration: 363.518965ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:11:44.774611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:11:44.410809Z","time spent":"363.793484ms","remote":"127.0.0.1:37312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16984,"request content":"key:\"/registry/pods/kube-system/cilium-r76pb\" "} {"level":"warn","ts":"2026-04-16T00:11:46.041379Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530392717068509153,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-16T00:11:46.541778Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530392717068509153,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-16T00:11:47.042714Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530392717068509153,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-16T00:11:47.543179Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530392717068509153,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-16T00:11:47.636505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.00017362s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-16T00:11:47.636611Z","caller":"traceutil/trace.go:171","msg":"trace[222162909] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000354146s","start":"2026-04-16T00:11:45.636237Z","end":"2026-04-16T00:11:47.636591Z","steps":["trace[222162909] 'agreement among raft nodes before linearized reading' (duration: 2.000206162s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:11:47.636666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:11:45.63621Z","time spent":"2.000447028s","remote":"127.0.0.1:37122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-16T00:11:47.777428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.751215352s","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-04-16T00:11:47.777504Z","caller":"traceutil/trace.go:171","msg":"trace[621892720] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:604; }","duration":"2.751338836s","start":"2026-04-16T00:11:45.026149Z","end":"2026-04-16T00:11:47.777488Z","steps":["trace[621892720] 'range keys from in-memory index tree' (duration: 2.751034816s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:11:47.777542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:11:45.026129Z","time spent":"2.751404868s","remote":"127.0.0.1:37402","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-04-16T00:11:47.796057Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.627432295s","expected-duration":"1s"} {"level":"info","ts":"2026-04-16T00:11:47.797043Z","caller":"traceutil/trace.go:171","msg":"trace[1620302732] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"2.629288045s","start":"2026-04-16T00:11:45.167733Z","end":"2026-04-16T00:11:47.797021Z","steps":["trace[1620302732] 'process raft request' (duration: 2.628761237s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:11:47.797206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:11:45.167709Z","time spent":"2.629415099s","remote":"127.0.0.1:37220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036313,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T00:11:47.805197Z","caller":"traceutil/trace.go:171","msg":"trace[796305690] linearizableReadLoop","detail":"{readStateIndex:626; appliedIndex:624; }","duration":"2.264321859s","start":"2026-04-16T00:11:45.540855Z","end":"2026-04-16T00:11:47.805177Z","steps":["trace[796305690] 'read index received' (duration: 2.255509406s)","trace[796305690] 'applied index is now lower than readState.Index' (duration: 8.811823ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T00:11:47.805454Z","caller":"traceutil/trace.go:171","msg":"trace[151897248] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"1.836832345s","start":"2026-04-16T00:11:45.968612Z","end":"2026-04-16T00:11:47.805445Z","steps":["trace[151897248] 'process raft request' (duration: 1.836465522s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:11:47.805537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:11:45.968588Z","time spent":"1.836890067s","remote":"127.0.0.1:37402","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-04-16T00:11:47.805664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.464124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T00:11:47.805639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.500761806s","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-04-16T00:11:47.805696Z","caller":"traceutil/trace.go:171","msg":"trace[1163214837] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:607; }","duration":"167.541106ms","start":"2026-04-16T00:11:47.638145Z","end":"2026-04-16T00:11:47.805686Z","steps":["trace[1163214837] 'agreement among raft nodes before linearized reading' (duration: 167.498254ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:11:47.805707Z","caller":"traceutil/trace.go:171","msg":"trace[907293741] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:607; }","duration":"1.50088421s","start":"2026-04-16T00:11:46.30481Z","end":"2026-04-16T00:11:47.805694Z","steps":["trace[907293741] 'agreement among raft nodes before linearized reading' (duration: 1.500692324s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:11:47.805741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:11:46.304788Z","time spent":"1.500944232s","remote":"127.0.0.1:37402","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-04-16T00:11:47.805753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.264902089s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-16T00:11:47.805878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.050411699s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.74\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-16T00:11:47.805879Z","caller":"traceutil/trace.go:171","msg":"trace[1977754670] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:607; }","duration":"2.265065444s","start":"2026-04-16T00:11:45.540797Z","end":"2026-04-16T00:11:47.805863Z","steps":["trace[1977754670] 'agreement among raft nodes before linearized reading' (duration: 2.264852867s)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:11:47.805904Z","caller":"traceutil/trace.go:171","msg":"trace[518604873] range","detail":"{range_begin:/registry/masterleases/199.204.45.74; range_end:; response_count:1; response_revision:607; }","duration":"1.050475351s","start":"2026-04-16T00:11:46.755422Z","end":"2026-04-16T00:11:47.805897Z","steps":["trace[518604873] 'agreement among raft nodes before linearized reading' (duration: 1.050359138s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:11:47.805879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.027899195s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-16T00:11:47.80592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:11:45.540778Z","time spent":"2.265131316s","remote":"127.0.0.1:37402","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-04-16T00:11:47.805928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:11:46.755402Z","time spent":"1.050521413s","remote":"127.0.0.1:37148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.74\" "} {"level":"info","ts":"2026-04-16T00:11:47.805972Z","caller":"traceutil/trace.go:171","msg":"trace[388315316] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:607; }","duration":"1.02803396s","start":"2026-04-16T00:11:46.77791Z","end":"2026-04-16T00:11:47.805944Z","steps":["trace[388315316] 'agreement among raft nodes before linearized reading' (duration: 1.027775332s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:11:47.80607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:11:46.777889Z","time spent":"1.028165323s","remote":"127.0.0.1:37402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-16T00:11:49.943915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.293366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T00:11:49.944005Z","caller":"traceutil/trace.go:171","msg":"trace[1270108389] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:647; }","duration":"310.414589ms","start":"2026-04-16T00:11:49.633573Z","end":"2026-04-16T00:11:49.943988Z","steps":["trace[1270108389] 'range keys from in-memory index tree' (duration: 310.237584ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:11:49.944042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:11:49.633563Z","time spent":"310.470192ms","remote":"127.0.0.1:37134","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-04-16T00:11:49.943952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.654141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T00:11:49.944138Z","caller":"traceutil/trace.go:171","msg":"trace[1064792791] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:647; }","duration":"395.860707ms","start":"2026-04-16T00:11:49.548251Z","end":"2026-04-16T00:11:49.944112Z","steps":["trace[1064792791] 'range keys from in-memory index tree' (duration: 395.582727ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:11:49.944214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:11:49.548234Z","time spent":"395.9555ms","remote":"127.0.0.1:37162","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" "} {"level":"info","ts":"2026-04-16T00:11:49.945745Z","caller":"traceutil/trace.go:171","msg":"trace[472226632] linearizableReadLoop","detail":"{readStateIndex:669; appliedIndex:668; }","duration":"136.372252ms","start":"2026-04-16T00:11:49.809358Z","end":"2026-04-16T00:11:49.94573Z","steps":["trace[472226632] 'read index received' (duration: 136.172814ms)","trace[472226632] 'applied index is now lower than readState.Index' (duration: 198.568µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T00:11:49.945786Z","caller":"traceutil/trace.go:171","msg":"trace[1780229683] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"194.643603ms","start":"2026-04-16T00:11:49.751123Z","end":"2026-04-16T00:11:49.945767Z","steps":["trace[1780229683] 'process raft request' (duration: 194.355633ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:11:49.945941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.583089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-16T00:11:49.946012Z","caller":"traceutil/trace.go:171","msg":"trace[530957549] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:648; }","duration":"136.656021ms","start":"2026-04-16T00:11:49.809315Z","end":"2026-04-16T00:11:49.945971Z","steps":["trace[530957549] 'agreement among raft nodes before linearized reading' (duration: 136.510886ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:11:49.946061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.195601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-16T00:11:49.946081Z","caller":"traceutil/trace.go:171","msg":"trace[1049688550] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:648; }","duration":"112.245073ms","start":"2026-04-16T00:11:49.833829Z","end":"2026-04-16T00:11:49.946074Z","steps":["trace[1049688550] 'agreement among raft nodes before linearized reading' (duration: 112.093128ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:11:49.946272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.330096ms","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-04-16T00:11:49.946204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.649155ms","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-04-16T00:11:49.946353Z","caller":"traceutil/trace.go:171","msg":"trace[70459429] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:648; }","duration":"112.433069ms","start":"2026-04-16T00:11:49.8339Z","end":"2026-04-16T00:11:49.946333Z","steps":["trace[70459429] 'agreement among raft nodes before linearized reading' (duration: 112.185891ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:11:49.946392Z","caller":"traceutil/trace.go:171","msg":"trace[472696373] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:648; }","duration":"107.860171ms","start":"2026-04-16T00:11:49.838517Z","end":"2026-04-16T00:11:49.946377Z","steps":["trace[472696373] 'agreement among raft nodes before linearized reading' (duration: 107.541811ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:11:52.060217Z","caller":"traceutil/trace.go:171","msg":"trace[1170764698] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"108.932465ms","start":"2026-04-16T00:11:51.951265Z","end":"2026-04-16T00:11:52.060198Z","steps":["trace[1170764698] 'process raft request' (duration: 108.799601ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:11:52.060226Z","caller":"traceutil/trace.go:171","msg":"trace[1299550891] linearizableReadLoop","detail":"{readStateIndex:697; appliedIndex:696; }","duration":"105.939217ms","start":"2026-04-16T00:11:51.954265Z","end":"2026-04-16T00:11:52.060204Z","steps":["trace[1299550891] 'read index received' (duration: 105.914116ms)","trace[1299550891] 'applied index is now lower than readState.Index' (duration: 23.841µs)"],"step_count":2} {"level":"warn","ts":"2026-04-16T00:11:52.060559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.272687ms","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-04-16T00:11:52.060635Z","caller":"traceutil/trace.go:171","msg":"trace[476750684] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:676; }","duration":"106.400653ms","start":"2026-04-16T00:11:51.954221Z","end":"2026-04-16T00:11:52.060621Z","steps":["trace[476750684] 'agreement among raft nodes before linearized reading' (duration: 106.043881ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:11:52.070004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.398775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-16T00:11:52.070055Z","caller":"traceutil/trace.go:171","msg":"trace[1265461001] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:676; }","duration":"115.469326ms","start":"2026-04-16T00:11:51.954577Z","end":"2026-04-16T00:11:52.070046Z","steps":["trace[1265461001] 'agreement among raft nodes before linearized reading' (duration: 115.357903ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:11:52.070212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.202147ms","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-04-16T00:11:52.070237Z","caller":"traceutil/trace.go:171","msg":"trace[49542759] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:676; }","duration":"110.245097ms","start":"2026-04-16T00:11:51.959986Z","end":"2026-04-16T00:11:52.070231Z","steps":["trace[49542759] 'agreement among raft nodes before linearized reading' (duration: 110.178865ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:11:52.070428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.277131ms","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-04-16T00:11:52.070508Z","caller":"traceutil/trace.go:171","msg":"trace[501538090] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:676; }","duration":"115.379404ms","start":"2026-04-16T00:11:51.95511Z","end":"2026-04-16T00:11:52.070489Z","steps":["trace[501538090] 'agreement among raft nodes before linearized reading' (duration: 115.188707ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:11:52.679149Z","caller":"traceutil/trace.go:171","msg":"trace[1625512151] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"105.366411ms","start":"2026-04-16T00:11:52.573745Z","end":"2026-04-16T00:11:52.679111Z","steps":["trace[1625512151] 'process raft request' (duration: 104.515883ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:11:54.529468Z","caller":"traceutil/trace.go:171","msg":"trace[1552330754] linearizableReadLoop","detail":"{readStateIndex:724; appliedIndex:723; }","duration":"179.1289ms","start":"2026-04-16T00:11:54.35032Z","end":"2026-04-16T00:11:54.529449Z","steps":["trace[1552330754] 'read index received' (duration: 179.09848ms)","trace[1552330754] 'applied index is now lower than readState.Index' (duration: 29.41µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T00:11:54.529577Z","caller":"traceutil/trace.go:171","msg":"trace[1981681775] transaction","detail":"{read_only:false; response_revision:702; number_of_response:1; }","duration":"221.305998ms","start":"2026-04-16T00:11:54.30826Z","end":"2026-04-16T00:11:54.529566Z","steps":["trace[1981681775] 'process raft request' (duration: 221.05361ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:11:54.529669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.332717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T00:11:54.529726Z","caller":"traceutil/trace.go:171","msg":"trace[311638187] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:702; }","duration":"179.42432ms","start":"2026-04-16T00:11:54.350289Z","end":"2026-04-16T00:11:54.529713Z","steps":["trace[311638187] 'agreement among raft nodes before linearized reading' (duration: 179.341077ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:11:54.613758Z","caller":"traceutil/trace.go:171","msg":"trace[1779728943] transaction","detail":"{read_only:false; response_revision:703; number_of_response:1; }","duration":"106.595247ms","start":"2026-04-16T00:11:54.507136Z","end":"2026-04-16T00:11:54.613731Z","steps":["trace[1779728943] 'process raft request' (duration: 105.854233ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:11:54.613936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.553419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T00:11:54.614025Z","caller":"traceutil/trace.go:171","msg":"trace[200259472] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:703; }","duration":"263.661192ms","start":"2026-04-16T00:11:54.350348Z","end":"2026-04-16T00:11:54.61401Z","steps":["trace[200259472] 'agreement among raft nodes before linearized reading' (duration: 263.513557ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:11:54.61407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.706077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-r76pb\" ","response":"range_response_count:1 size:18512"} {"level":"warn","ts":"2026-04-16T00:11:54.614119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.142042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T00:11:54.614202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.941856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T00:11:54.614274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.38802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T00:11:54.614315Z","caller":"traceutil/trace.go:171","msg":"trace[982930853] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:703; }","duration":"237.453052ms","start":"2026-04-16T00:11:54.376852Z","end":"2026-04-16T00:11:54.614305Z","steps":["trace[982930853] 'agreement among raft nodes before linearized reading' (duration: 237.38208ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:11:54.614328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.942661ms","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-04-16T00:11:54.614388Z","caller":"traceutil/trace.go:171","msg":"trace[2102755857] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:703; }","duration":"264.011664ms","start":"2026-04-16T00:11:54.350361Z","end":"2026-04-16T00:11:54.614372Z","steps":["trace[2102755857] 'agreement among raft nodes before linearized reading' (duration: 263.91305ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:11:54.614404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.046154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T00:11:54.614452Z","caller":"traceutil/trace.go:171","msg":"trace[2139279197] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:703; }","duration":"112.151068ms","start":"2026-04-16T00:11:54.502288Z","end":"2026-04-16T00:11:54.614439Z","steps":["trace[2139279197] 'agreement among raft nodes before linearized reading' (duration: 112.061265ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:11:54.614145Z","caller":"traceutil/trace.go:171","msg":"trace[1651483926] range","detail":"{range_begin:/registry/pods/kube-system/cilium-r76pb; range_end:; response_count:1; response_revision:703; }","duration":"141.81438ms","start":"2026-04-16T00:11:54.472314Z","end":"2026-04-16T00:11:54.614128Z","steps":["trace[1651483926] 'agreement among raft nodes before linearized reading' (duration: 141.563862ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:11:54.614194Z","caller":"traceutil/trace.go:171","msg":"trace[765552273] transaction","detail":"{read_only:false; number_of_response:1; response_revision:703; }","duration":"102.97311ms","start":"2026-04-16T00:11:54.51121Z","end":"2026-04-16T00:11:54.614183Z","steps":["trace[765552273] 'process raft request' (duration: 102.576667ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:11:54.614255Z","caller":"traceutil/trace.go:171","msg":"trace[346870835] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:703; }","duration":"237.009387ms","start":"2026-04-16T00:11:54.377231Z","end":"2026-04-16T00:11:54.614241Z","steps":["trace[346870835] 'agreement among raft nodes before linearized reading' (duration: 236.933015ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:11:54.614603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.577849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T00:11:54.615347Z","caller":"traceutil/trace.go:171","msg":"trace[819199235] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:703; }","duration":"237.301378ms","start":"2026-04-16T00:11:54.376883Z","end":"2026-04-16T00:11:54.614185Z","steps":["trace[819199235] 'agreement among raft nodes before linearized reading' (duration: 237.092531ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:11:54.615538Z","caller":"traceutil/trace.go:171","msg":"trace[648755548] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:703; }","duration":"111.90284ms","start":"2026-04-16T00:11:54.502986Z","end":"2026-04-16T00:11:54.614889Z","steps":["trace[648755548] 'agreement among raft nodes before linearized reading' (duration: 111.222698ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:11:54.773284Z","caller":"traceutil/trace.go:171","msg":"trace[973834385] transaction","detail":"{read_only:false; response_revision:706; number_of_response:1; }","duration":"129.062687ms","start":"2026-04-16T00:11:54.644196Z","end":"2026-04-16T00:11:54.773259Z","steps":["trace[973834385] 'process raft request' (duration: 80.252233ms)","trace[973834385] 'compare' (duration: 48.649749ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T00:11:57.800341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.096946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T00:11:57.800435Z","caller":"traceutil/trace.go:171","msg":"trace[1031060414] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:719; }","duration":"164.23396ms","start":"2026-04-16T00:11:57.63617Z","end":"2026-04-16T00:11:57.800404Z","steps":["trace[1031060414] 'range keys from in-memory index tree' (duration: 163.996742ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:11:58.237535Z","caller":"traceutil/trace.go:171","msg":"trace[818222929] transaction","detail":"{read_only:false; response_revision:720; number_of_response:1; }","duration":"209.022513ms","start":"2026-04-16T00:11:58.028485Z","end":"2026-04-16T00:11:58.237507Z","steps":["trace[818222929] 'process raft request' (duration: 208.844647ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:12:08.017941Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530392717068509548,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-16T00:12:08.022488Z","caller":"traceutil/trace.go:171","msg":"trace[705631262] linearizableReadLoop","detail":"{readStateIndex:809; appliedIndex:808; }","duration":"505.679711ms","start":"2026-04-16T00:12:07.516786Z","end":"2026-04-16T00:12:08.022466Z","steps":["trace[705631262] 'read index received' (duration: 505.447674ms)","trace[705631262] 'applied index is now lower than readState.Index' (duration: 231.097µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T00:12:08.022909Z","caller":"traceutil/trace.go:171","msg":"trace[68277407] transaction","detail":"{read_only:false; response_revision:782; number_of_response:1; }","duration":"568.374434ms","start":"2026-04-16T00:12:07.454521Z","end":"2026-04-16T00:12:08.022895Z","steps":["trace[68277407] 'process raft request' (duration: 567.777775ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:12:08.023022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:12:07.454502Z","time spent":"568.448347ms","remote":"127.0.0.1:37402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T00:12:08.023335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.988476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T00:12:08.02339Z","caller":"traceutil/trace.go:171","msg":"trace[1607453625] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:782; }","duration":"387.079478ms","start":"2026-04-16T00:12:07.636294Z","end":"2026-04-16T00:12:08.023374Z","steps":["trace[1607453625] 'agreement among raft nodes before linearized reading' (duration: 386.974304ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:12:08.023429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:12:07.63628Z","time spent":"387.14187ms","remote":"127.0.0.1:37122","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-04-16T00:12:08.023779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.53962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-g6dzc\" ","response":"range_response_count:1 size:3964"} {"level":"info","ts":"2026-04-16T00:12:08.023944Z","caller":"traceutil/trace.go:171","msg":"trace[366449862] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-g6dzc; range_end:; response_count:1; response_revision:782; }","duration":"507.184861ms","start":"2026-04-16T00:12:07.516735Z","end":"2026-04-16T00:12:08.02392Z","steps":["trace[366449862] 'agreement among raft nodes before linearized reading' (duration: 506.368455ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:12:08.024119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:12:07.516715Z","time spent":"507.384147ms","remote":"127.0.0.1:37312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3987,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-g6dzc\" "} {"level":"info","ts":"2026-04-16T00:12:28.668377Z","caller":"traceutil/trace.go:171","msg":"trace[722658396] linearizableReadLoop","detail":"{readStateIndex:1009; appliedIndex:1008; }","duration":"103.490328ms","start":"2026-04-16T00:12:28.564866Z","end":"2026-04-16T00:12:28.668356Z","steps":["trace[722658396] 'read index received' (duration: 103.396097ms)","trace[722658396] 'applied index is now lower than readState.Index' (duration: 93.181µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T00:12:28.668444Z","caller":"traceutil/trace.go:171","msg":"trace[1868334783] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"111.392013ms","start":"2026-04-16T00:12:28.557024Z","end":"2026-04-16T00:12:28.668416Z","steps":["trace[1868334783] 'process raft request' (duration: 111.205312ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:12:28.669002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.105551ms","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-04-16T00:12:28.669322Z","caller":"traceutil/trace.go:171","msg":"trace[209927215] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:976; }","duration":"104.456003ms","start":"2026-04-16T00:12:28.56485Z","end":"2026-04-16T00:12:28.669306Z","steps":["trace[209927215] 'agreement among raft nodes before linearized reading' (duration: 103.615158ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:12:28.763968Z","caller":"traceutil/trace.go:171","msg":"trace[848748503] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"101.699029ms","start":"2026-04-16T00:12:28.662242Z","end":"2026-04-16T00:12:28.763941Z","steps":["trace[848748503] 'process raft request' (duration: 27.080003ms)","trace[848748503] 'compare' (duration: 74.208524ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T00:12:28.764391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.458176ms","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-04-16T00:12:28.764442Z","caller":"traceutil/trace.go:171","msg":"trace[1103295169] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:978; }","duration":"198.529897ms","start":"2026-04-16T00:12:28.565901Z","end":"2026-04-16T00:12:28.764431Z","steps":["trace[1103295169] 'agreement among raft nodes before linearized reading' (duration: 198.327496ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:12:28.764711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.334603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T00:12:28.764751Z","caller":"traceutil/trace.go:171","msg":"trace[2117387338] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:978; }","duration":"131.411313ms","start":"2026-04-16T00:12:28.633328Z","end":"2026-04-16T00:12:28.764739Z","steps":["trace[2117387338] 'agreement among raft nodes before linearized reading' (duration: 131.353783ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:12:37.037935Z","caller":"traceutil/trace.go:171","msg":"trace[446403222] linearizableReadLoop","detail":"{readStateIndex:1040; appliedIndex:1039; }","duration":"206.914065ms","start":"2026-04-16T00:12:36.830981Z","end":"2026-04-16T00:12:37.037895Z","steps":["trace[446403222] 'read index received' (duration: 165.00849ms)","trace[446403222] 'applied index is now lower than readState.Index' (duration: 41.903425ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T00:12:37.037954Z","caller":"traceutil/trace.go:171","msg":"trace[787176000] transaction","detail":"{read_only:false; response_revision:1005; number_of_response:1; }","duration":"207.306938ms","start":"2026-04-16T00:12:36.830607Z","end":"2026-04-16T00:12:37.037914Z","steps":["trace[787176000] 'process raft request' (duration: 165.425983ms)","trace[787176000] 'compare' (duration: 41.702084ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T00:12:37.038146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.139267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-16T00:12:37.038245Z","caller":"traceutil/trace.go:171","msg":"trace[547544117] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:1005; }","duration":"207.276508ms","start":"2026-04-16T00:12:36.830947Z","end":"2026-04-16T00:12:37.038224Z","steps":["trace[547544117] 'agreement among raft nodes before linearized reading' (duration: 207.076117ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:12:37.043613Z","caller":"traceutil/trace.go:171","msg":"trace[475856180] transaction","detail":"{read_only:false; response_revision:1006; number_of_response:1; }","duration":"211.189188ms","start":"2026-04-16T00:12:36.832403Z","end":"2026-04-16T00:12:37.043592Z","steps":["trace[475856180] 'process raft request' (duration: 211.087998ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:13:37.536099Z","caller":"traceutil/trace.go:171","msg":"trace[2087307909] transaction","detail":"{read_only:false; response_revision:1209; number_of_response:1; }","duration":"101.619652ms","start":"2026-04-16T00:13:37.43445Z","end":"2026-04-16T00:13:37.53607Z","steps":["trace[2087307909] 'process raft request' (duration: 63.636015ms)","trace[2087307909] 'compare' (duration: 37.862934ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T00:14:11.317799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.804534ms","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":"warn","ts":"2026-04-16T00:14:11.317867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.162362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-16T00:14:11.317895Z","caller":"traceutil/trace.go:171","msg":"trace[465042234] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1667; }","duration":"181.214793ms","start":"2026-04-16T00:14:11.136673Z","end":"2026-04-16T00:14:11.317888Z","steps":["trace[465042234] 'range keys from in-memory index tree' (duration: 180.993588ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:14:11.317888Z","caller":"traceutil/trace.go:171","msg":"trace[196064617] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1667; }","duration":"167.957608ms","start":"2026-04-16T00:14:11.149911Z","end":"2026-04-16T00:14:11.317869Z","steps":["trace[196064617] 'count revisions from in-memory index tree' (duration: 167.672942ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:14:11.317951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.813049ms","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-04-16T00:14:11.318126Z","caller":"traceutil/trace.go:171","msg":"trace[1339186881] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1667; }","duration":"224.969833ms","start":"2026-04-16T00:14:11.093068Z","end":"2026-04-16T00:14:11.318038Z","steps":["trace[1339186881] 'range keys from in-memory index tree' (duration: 224.631164ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:14:11.427417Z","caller":"traceutil/trace.go:171","msg":"trace[620856537] transaction","detail":"{read_only:false; response_revision:1668; number_of_response:1; }","duration":"277.043517ms","start":"2026-04-16T00:14:11.150335Z","end":"2026-04-16T00:14:11.427379Z","steps":["trace[620856537] 'process raft request' (duration: 276.902243ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:14:11.432159Z","caller":"traceutil/trace.go:171","msg":"trace[413945185] transaction","detail":"{read_only:false; response_revision:1670; number_of_response:1; }","duration":"108.159346ms","start":"2026-04-16T00:14:11.323975Z","end":"2026-04-16T00:14:11.432134Z","steps":["trace[413945185] 'process raft request' (duration: 108.123725ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:14:11.432176Z","caller":"traceutil/trace.go:171","msg":"trace[1556711037] transaction","detail":"{read_only:false; response_revision:1669; number_of_response:1; }","duration":"109.461477ms","start":"2026-04-16T00:14:11.322697Z","end":"2026-04-16T00:14:11.432158Z","steps":["trace[1556711037] 'process raft request' (duration: 109.278562ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:14:30.753346Z","caller":"traceutil/trace.go:171","msg":"trace[654919529] linearizableReadLoop","detail":"{readStateIndex:2286; appliedIndex:2285; }","duration":"117.100623ms","start":"2026-04-16T00:14:30.636226Z","end":"2026-04-16T00:14:30.753327Z","steps":["trace[654919529] 'read index received' (duration: 116.98185ms)","trace[654919529] 'applied index is now lower than readState.Index' (duration: 117.683µs)"],"step_count":2} {"level":"warn","ts":"2026-04-16T00:14:30.753468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.231987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T00:14:30.753505Z","caller":"traceutil/trace.go:171","msg":"trace[941806635] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2196; }","duration":"117.313628ms","start":"2026-04-16T00:14:30.636179Z","end":"2026-04-16T00:14:30.753493Z","steps":["trace[941806635] 'agreement among raft nodes before linearized reading' (duration: 117.243357ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:14:30.75342Z","caller":"traceutil/trace.go:171","msg":"trace[2010375502] transaction","detail":"{read_only:false; response_revision:2196; number_of_response:1; }","duration":"137.668112ms","start":"2026-04-16T00:14:30.615704Z","end":"2026-04-16T00:14:30.753372Z","steps":["trace[2010375502] 'process raft request' (duration: 137.470687ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:14:31.389789Z","caller":"traceutil/trace.go:171","msg":"trace[1022916110] transaction","detail":"{read_only:false; response_revision:2199; number_of_response:1; }","duration":"589.237742ms","start":"2026-04-16T00:14:30.800522Z","end":"2026-04-16T00:14:31.38976Z","steps":["trace[1022916110] 'process raft request' (duration: 535.824755ms)","trace[1022916110] 'compare' (duration: 52.982356ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T00:14:31.390425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:14:30.800506Z","time spent":"589.449958ms","remote":"127.0.0.1:37194","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":646,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T00:14:31.451192Z","caller":"traceutil/trace.go:171","msg":"trace[617390188] transaction","detail":"{read_only:false; response_revision:2200; number_of_response:1; }","duration":"649.23566ms","start":"2026-04-16T00:14:30.801936Z","end":"2026-04-16T00:14:31.451172Z","steps":["trace[617390188] 'process raft request' (duration: 648.901641ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:14:31.45129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:14:30.801925Z","time spent":"649.319473ms","remote":"127.0.0.1:37194","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":790,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-16T00:14:31.451629Z","caller":"traceutil/trace.go:171","msg":"trace[1728229867] transaction","detail":"{read_only:false; response_revision:2201; number_of_response:1; }","duration":"637.509458ms","start":"2026-04-16T00:14:30.814111Z","end":"2026-04-16T00:14:31.45162Z","steps":["trace[1728229867] 'process raft request' (duration: 636.856622ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:14:31.451746Z","caller":"traceutil/trace.go:171","msg":"trace[917548349] linearizableReadLoop","detail":"{readStateIndex:2291; appliedIndex:2288; }","duration":"486.740508ms","start":"2026-04-16T00:14:30.964995Z","end":"2026-04-16T00:14:31.451736Z","steps":["trace[917548349] 'read index received' (duration: 371.363073ms)","trace[917548349] 'applied index is now lower than readState.Index' (duration: 115.376395ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T00:14:31.4518Z","caller":"traceutil/trace.go:171","msg":"trace[529725772] transaction","detail":"{read_only:false; response_revision:2202; number_of_response:1; }","duration":"223.487802ms","start":"2026-04-16T00:14:31.228306Z","end":"2026-04-16T00:14:31.451793Z","steps":["trace[529725772] 'process raft request' (duration: 223.238135ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:14:31.452016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.022585ms","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-04-16T00:14:31.452046Z","caller":"traceutil/trace.go:171","msg":"trace[314624799] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2202; }","duration":"487.096658ms","start":"2026-04-16T00:14:30.964942Z","end":"2026-04-16T00:14:31.452039Z","steps":["trace[314624799] 'agreement among raft nodes before linearized reading' (duration: 486.984785ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:14:31.452067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:14:30.964924Z","time spent":"487.137449ms","remote":"127.0.0.1:37402","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":"warn","ts":"2026-04-16T00:14:31.452286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.47382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:528"} {"level":"info","ts":"2026-04-16T00:14:31.452319Z","caller":"traceutil/trace.go:171","msg":"trace[1564678576] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:2202; }","duration":"328.525481ms","start":"2026-04-16T00:14:31.123785Z","end":"2026-04-16T00:14:31.452311Z","steps":["trace[1564678576] 'agreement among raft nodes before linearized reading' (duration: 328.444939ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:14:31.452338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:14:31.123774Z","time spent":"328.559662ms","remote":"127.0.0.1:37402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":551,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-04-16T00:14:31.452333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.297805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T00:14:31.452438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.412198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9bc618ae-4c03-48f8-8f35-5e5ffaf72566\" ","response":"range_response_count:1 size:3616"} {"level":"info","ts":"2026-04-16T00:14:31.452456Z","caller":"traceutil/trace.go:171","msg":"trace[948000470] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:2202; }","duration":"238.485101ms","start":"2026-04-16T00:14:31.213946Z","end":"2026-04-16T00:14:31.452431Z","steps":["trace[948000470] 'agreement among raft nodes before linearized reading' (duration: 238.261124ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:14:31.452487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.072978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T00:14:31.452501Z","caller":"traceutil/trace.go:171","msg":"trace[709641571] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9bc618ae-4c03-48f8-8f35-5e5ffaf72566; range_end:; response_count:1; response_revision:2202; }","duration":"371.476869ms","start":"2026-04-16T00:14:31.08101Z","end":"2026-04-16T00:14:31.452486Z","steps":["trace[709641571] 'agreement among raft nodes before linearized reading' (duration: 371.296784ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:14:31.452541Z","caller":"traceutil/trace.go:171","msg":"trace[1790543932] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:2202; }","duration":"179.15245ms","start":"2026-04-16T00:14:31.273372Z","end":"2026-04-16T00:14:31.452525Z","steps":["trace[1790543932] 'agreement among raft nodes before linearized reading' (duration: 179.062398ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:14:31.452545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:14:31.080988Z","time spent":"371.547192ms","remote":"127.0.0.1:37312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3639,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9bc618ae-4c03-48f8-8f35-5e5ffaf72566\" "} {"level":"warn","ts":"2026-04-16T00:14:31.452591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.99334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c1370d71-cd65-493f-a4c9-f128a7273f0f\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-04-16T00:14:31.45262Z","caller":"traceutil/trace.go:171","msg":"trace[678484687] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c1370d71-cd65-493f-a4c9-f128a7273f0f; range_end:; response_count:1; response_revision:2202; }","duration":"183.041351ms","start":"2026-04-16T00:14:31.269571Z","end":"2026-04-16T00:14:31.452612Z","steps":["trace[678484687] 'agreement among raft nodes before linearized reading' (duration: 182.971039ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:14:31.452739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:14:30.814102Z","time spent":"637.58119ms","remote":"127.0.0.1:47058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7769,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T00:14:31.452281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.445817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9bc618ae-4c03-48f8-8f35-5e5ffaf72566\" ","response":"range_response_count:1 size:3616"} {"level":"info","ts":"2026-04-16T00:14:31.455201Z","caller":"traceutil/trace.go:171","msg":"trace[1753123906] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9bc618ae-4c03-48f8-8f35-5e5ffaf72566; range_end:; response_count:1; response_revision:2202; }","duration":"398.383693ms","start":"2026-04-16T00:14:31.056785Z","end":"2026-04-16T00:14:31.455168Z","steps":["trace[1753123906] 'agreement among raft nodes before linearized reading' (duration: 395.369805ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:14:31.455494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:14:31.056768Z","time spent":"398.61957ms","remote":"127.0.0.1:37312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3639,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9bc618ae-4c03-48f8-8f35-5e5ffaf72566\" "} {"level":"info","ts":"2026-04-16T00:14:31.65804Z","caller":"traceutil/trace.go:171","msg":"trace[421106827] transaction","detail":"{read_only:false; response_revision:2207; number_of_response:1; }","duration":"104.504585ms","start":"2026-04-16T00:14:31.553506Z","end":"2026-04-16T00:14:31.658011Z","steps":["trace[421106827] 'process raft request' (duration: 69.942524ms)","trace[421106827] 'compare' (duration: 34.248153ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T00:14:32.158881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.75944ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T00:14:32.159037Z","caller":"traceutil/trace.go:171","msg":"trace[585434960] linearizableReadLoop","detail":"{readStateIndex:2301; appliedIndex:2299; }","duration":"473.521819ms","start":"2026-04-16T00:14:31.685501Z","end":"2026-04-16T00:14:32.159023Z","steps":["trace[585434960] 'read index received' (duration: 247.543943ms)","trace[585434960] 'applied index is now lower than readState.Index' (duration: 225.977276ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T00:14:32.15908Z","caller":"traceutil/trace.go:171","msg":"trace[1794361522] transaction","detail":"{read_only:false; number_of_response:1; response_revision:2210; }","duration":"486.802852ms","start":"2026-04-16T00:14:31.672252Z","end":"2026-04-16T00:14:32.159055Z","steps":["trace[1794361522] 'process raft request' (duration: 486.717029ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:14:32.159111Z","caller":"traceutil/trace.go:171","msg":"trace[1874194963] transaction","detail":"{read_only:false; response_revision:2210; number_of_response:1; }","duration":"488.538297ms","start":"2026-04-16T00:14:31.670526Z","end":"2026-04-16T00:14:32.159065Z","steps":["trace[1874194963] 'process raft request' (duration: 262.50834ms)","trace[1874194963] 'compare' (duration: 225.617776ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T00:14:32.159199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:14:31.67223Z","time spent":"486.898164ms","remote":"127.0.0.1:47056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":66,"response count":0,"response size":2282,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T00:14:32.159245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:14:31.670482Z","time spent":"488.694521ms","remote":"127.0.0.1:47056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2233,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T00:14:32.159455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.720838ms","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-04-16T00:14:32.159481Z","caller":"traceutil/trace.go:171","msg":"trace[1431905978] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2210; }","duration":"363.796419ms","start":"2026-04-16T00:14:31.795677Z","end":"2026-04-16T00:14:32.159473Z","steps":["trace[1431905978] 'agreement among raft nodes before linearized reading' (duration: 363.640335ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:14:32.159503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:14:31.795649Z","time spent":"363.849241ms","remote":"127.0.0.1:37402","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-04-16T00:14:32.159508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.149682ms","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-04-16T00:14:32.159561Z","caller":"traceutil/trace.go:171","msg":"trace[577479507] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2210; }","duration":"354.223913ms","start":"2026-04-16T00:14:31.80532Z","end":"2026-04-16T00:14:32.159544Z","steps":["trace[577479507] 'agreement among raft nodes before linearized reading' (duration: 354.011748ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:14:32.159599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:14:31.805276Z","time spent":"354.315585ms","remote":"127.0.0.1:50608","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-04-16T00:14:32.159776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.285628ms","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-04-16T00:14:32.159799Z","caller":"traceutil/trace.go:171","msg":"trace[715010280] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2210; }","duration":"474.340189ms","start":"2026-04-16T00:14:31.685452Z","end":"2026-04-16T00:14:32.159792Z","steps":["trace[715010280] 'agreement among raft nodes before linearized reading' (duration: 473.640811ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:14:32.159816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:14:31.685439Z","time spent":"474.373131ms","remote":"127.0.0.1:37402","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-04-16T00:14:32.581177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.209327ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T00:14:32.581307Z","caller":"traceutil/trace.go:171","msg":"trace[967775373] transaction","detail":"{read_only:false; response_revision:2211; number_of_response:1; }","duration":"417.071757ms","start":"2026-04-16T00:14:32.164215Z","end":"2026-04-16T00:14:32.581287Z","steps":["trace[967775373] 'process raft request' (duration: 289.657915ms)","trace[967775373] 'compare' (duration: 126.991471ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T00:14:32.58137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:14:32.164195Z","time spent":"417.145859ms","remote":"127.0.0.1:37402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T00:14:32.586492Z","caller":"traceutil/trace.go:171","msg":"trace[1888129763] transaction","detail":"{read_only:false; response_revision:2212; number_of_response:1; }","duration":"420.772614ms","start":"2026-04-16T00:14:32.165679Z","end":"2026-04-16T00:14:32.586451Z","steps":["trace[1888129763] 'process raft request' (duration: 420.561488ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:14:32.586649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:14:32.165484Z","time spent":"421.083131ms","remote":"127.0.0.1:37402","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-04-16T00:14:32.587144Z","caller":"traceutil/trace.go:171","msg":"trace[202324717] transaction","detail":"{read_only:false; response_revision:2214; number_of_response:1; }","duration":"413.095916ms","start":"2026-04-16T00:14:32.174039Z","end":"2026-04-16T00:14:32.587135Z","steps":["trace[202324717] 'process raft request' (duration: 412.978233ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:14:32.587221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:14:32.174031Z","time spent":"413.153078ms","remote":"127.0.0.1:47056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2384,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T00:14:32.587092Z","caller":"traceutil/trace.go:171","msg":"trace[763812440] transaction","detail":"{read_only:false; response_revision:2213; number_of_response:1; }","duration":"419.596114ms","start":"2026-04-16T00:14:32.167481Z","end":"2026-04-16T00:14:32.587077Z","steps":["trace[763812440] 'process raft request' (duration: 418.872564ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:14:32.587576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:14:32.167472Z","time spent":"420.072486ms","remote":"127.0.0.1:37194","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":728,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-16T00:14:32.591759Z","caller":"traceutil/trace.go:171","msg":"trace[2041839243] linearizableReadLoop","detail":"{readStateIndex:2303; appliedIndex:2301; }","duration":"424.91328ms","start":"2026-04-16T00:14:32.166823Z","end":"2026-04-16T00:14:32.591737Z","steps":["trace[2041839243] 'read index received' (duration: 287.063518ms)","trace[2041839243] 'applied index is now lower than readState.Index' (duration: 137.848452ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T00:14:32.592167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.31735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/valkey-server-j8xw6\" ","response":"range_response_count:1 size:2450"} {"level":"info","ts":"2026-04-16T00:14:32.592291Z","caller":"traceutil/trace.go:171","msg":"trace[713317278] range","detail":"{range_begin:/registry/secrets/openstack/valkey-server-j8xw6; range_end:; response_count:1; response_revision:2214; }","duration":"425.477224ms","start":"2026-04-16T00:14:32.166795Z","end":"2026-04-16T00:14:32.592272Z","steps":["trace[713317278] 'agreement among raft nodes before linearized reading' (duration: 425.186557ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:14:32.592367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:14:32.166785Z","time spent":"425.569946ms","remote":"127.0.0.1:37220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":2473,"request content":"key:\"/registry/secrets/openstack/valkey-server-j8xw6\" "} {"level":"warn","ts":"2026-04-16T00:14:32.59417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.636234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/valkey-server-certs\" ","response":"range_response_count:1 size:5406"} {"level":"info","ts":"2026-04-16T00:14:32.594258Z","caller":"traceutil/trace.go:171","msg":"trace[1236656688] range","detail":"{range_begin:/registry/secrets/openstack/valkey-server-certs; range_end:; response_count:1; response_revision:2217; }","duration":"426.754438ms","start":"2026-04-16T00:14:32.167493Z","end":"2026-04-16T00:14:32.594248Z","steps":["trace[1236656688] 'agreement among raft nodes before linearized reading' (duration: 426.598064ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:14:32.594302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:14:32.167475Z","time spent":"426.8184ms","remote":"127.0.0.1:37220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":5429,"request content":"key:\"/registry/secrets/openstack/valkey-server-certs\" "} {"level":"warn","ts":"2026-04-16T00:14:32.594574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.373592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9bc618ae-4c03-48f8-8f35-5e5ffaf72566\" ","response":"range_response_count:1 size:3733"} {"level":"warn","ts":"2026-04-16T00:14:32.594605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.885338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c1370d71-cd65-493f-a4c9-f128a7273f0f\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-04-16T00:14:32.594631Z","caller":"traceutil/trace.go:171","msg":"trace[520479027] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9bc618ae-4c03-48f8-8f35-5e5ffaf72566; range_end:; response_count:1; response_revision:2217; }","duration":"134.454015ms","start":"2026-04-16T00:14:32.460164Z","end":"2026-04-16T00:14:32.594618Z","steps":["trace[520479027] 'agreement among raft nodes before linearized reading' (duration: 134.059455ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:14:32.595089Z","caller":"traceutil/trace.go:171","msg":"trace[751808983] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c1370d71-cd65-493f-a4c9-f128a7273f0f; range_end:; response_count:1; response_revision:2217; }","duration":"137.052141ms","start":"2026-04-16T00:14:32.457659Z","end":"2026-04-16T00:14:32.594711Z","steps":["trace[751808983] 'agreement among raft nodes before linearized reading' (duration: 136.840666ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:14:32.595107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.475025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T00:14:32.595292Z","caller":"traceutil/trace.go:171","msg":"trace[1987845042] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:2217; }","duration":"307.598458ms","start":"2026-04-16T00:14:32.28761Z","end":"2026-04-16T00:14:32.595209Z","steps":["trace[1987845042] 'agreement among raft nodes before linearized reading' (duration: 307.459495ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:14:32.595335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:14:32.287587Z","time spent":"307.735242ms","remote":"127.0.0.1:49622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"info","ts":"2026-04-16T00:14:42.851211Z","caller":"traceutil/trace.go:171","msg":"trace[1448018365] transaction","detail":"{read_only:false; response_revision:2328; number_of_response:1; }","duration":"214.484112ms","start":"2026-04-16T00:14:42.636699Z","end":"2026-04-16T00:14:42.851184Z","steps":["trace[1448018365] 'process raft request' (duration: 214.211455ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:14:42.976344Z","caller":"traceutil/trace.go:171","msg":"trace[293576645] transaction","detail":"{read_only:false; response_revision:2329; number_of_response:1; }","duration":"337.538915ms","start":"2026-04-16T00:14:42.638774Z","end":"2026-04-16T00:14:42.976313Z","steps":["trace[293576645] 'process raft request' (duration: 336.616631ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:14:42.976534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:14:42.638747Z","time spent":"337.68499ms","remote":"127.0.0.1:37402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T00:14:42.976606Z","caller":"traceutil/trace.go:171","msg":"trace[1405780213] transaction","detail":"{read_only:false; response_revision:2331; number_of_response:1; }","duration":"182.681185ms","start":"2026-04-16T00:14:42.793877Z","end":"2026-04-16T00:14:42.976558Z","steps":["trace[1405780213] 'process raft request' (duration: 182.556002ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:14:42.976686Z","caller":"traceutil/trace.go:171","msg":"trace[104221844] transaction","detail":"{read_only:false; response_revision:2330; number_of_response:1; }","duration":"229.995782ms","start":"2026-04-16T00:14:42.746671Z","end":"2026-04-16T00:14:42.976667Z","steps":["trace[104221844] 'process raft request' (duration: 229.591221ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:14:42.9766Z","caller":"traceutil/trace.go:171","msg":"trace[386760805] linearizableReadLoop","detail":"{readStateIndex:2425; appliedIndex:2423; }","duration":"330.733887ms","start":"2026-04-16T00:14:42.645847Z","end":"2026-04-16T00:14:42.976581Z","steps":["trace[386760805] 'read index received' (duration: 205.182798ms)","trace[386760805] 'applied index is now lower than readState.Index' (duration: 125.550289ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T00:14:42.976936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.078606ms","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-04-16T00:14:42.976984Z","caller":"traceutil/trace.go:171","msg":"trace[1423276813] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2332; }","duration":"331.166859ms","start":"2026-04-16T00:14:42.645804Z","end":"2026-04-16T00:14:42.976971Z","steps":["trace[1423276813] 'agreement among raft nodes before linearized reading' (duration: 330.84298ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:14:42.97702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:14:42.645792Z","time spent":"331.21993ms","remote":"127.0.0.1:37402","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":"info","ts":"2026-04-16T00:14:51.882466Z","caller":"traceutil/trace.go:171","msg":"trace[696312265] linearizableReadLoop","detail":"{readStateIndex:2483; appliedIndex:2482; }","duration":"130.362107ms","start":"2026-04-16T00:14:51.752075Z","end":"2026-04-16T00:14:51.882437Z","steps":["trace[696312265] 'read index received' (duration: 130.165542ms)","trace[696312265] 'applied index is now lower than readState.Index' (duration: 195.455µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T00:14:51.882514Z","caller":"traceutil/trace.go:171","msg":"trace[112704986] transaction","detail":"{read_only:false; response_revision:2386; number_of_response:1; }","duration":"133.757328ms","start":"2026-04-16T00:14:51.748722Z","end":"2026-04-16T00:14:51.882479Z","steps":["trace[112704986] 'process raft request' (duration: 133.537722ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:14:51.882683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.585673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-16T00:14:51.882718Z","caller":"traceutil/trace.go:171","msg":"trace[706410487] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2386; }","duration":"130.675085ms","start":"2026-04-16T00:14:51.752032Z","end":"2026-04-16T00:14:51.882707Z","steps":["trace[706410487] 'agreement among raft nodes before linearized reading' (duration: 130.514871ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:14:52.659256Z","caller":"traceutil/trace.go:171","msg":"trace[1311546439] linearizableReadLoop","detail":"{readStateIndex:2489; appliedIndex:2488; }","duration":"362.604717ms","start":"2026-04-16T00:14:52.296607Z","end":"2026-04-16T00:14:52.659212Z","steps":["trace[1311546439] 'read index received' (duration: 354.043547ms)","trace[1311546439] 'applied index is now lower than readState.Index' (duration: 8.558919ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T00:14:52.660122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.493071ms","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-04-16T00:14:52.660196Z","caller":"traceutil/trace.go:171","msg":"trace[463739497] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2391; }","duration":"363.625524ms","start":"2026-04-16T00:14:52.296536Z","end":"2026-04-16T00:14:52.660162Z","steps":["trace[463739497] 'agreement among raft nodes before linearized reading' (duration: 362.867064ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:14:52.660251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:14:52.296517Z","time spent":"363.722386ms","remote":"127.0.0.1:50608","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":"info","ts":"2026-04-16T00:14:52.671373Z","caller":"traceutil/trace.go:171","msg":"trace[1849553109] transaction","detail":"{read_only:false; response_revision:2392; number_of_response:1; }","duration":"340.635187ms","start":"2026-04-16T00:14:52.33071Z","end":"2026-04-16T00:14:52.671345Z","steps":["trace[1849553109] 'process raft request' (duration: 340.36195ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:14:52.671542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:14:52.330679Z","time spent":"340.774351ms","remote":"127.0.0.1:37402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T00:15:05.909478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.378197ms","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-04-16T00:15:05.910279Z","caller":"traceutil/trace.go:171","msg":"trace[96400882] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2478; }","duration":"116.279861ms","start":"2026-04-16T00:15:05.793972Z","end":"2026-04-16T00:15:05.910252Z","steps":["trace[96400882] 'range keys from in-memory index tree' (duration: 115.103249ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:15:06.085763Z","caller":"traceutil/trace.go:171","msg":"trace[1340588614] linearizableReadLoop","detail":"{readStateIndex:2581; appliedIndex:2580; }","duration":"113.597111ms","start":"2026-04-16T00:15:05.97214Z","end":"2026-04-16T00:15:06.085737Z","steps":["trace[1340588614] 'read index received' (duration: 96.216486ms)","trace[1340588614] 'applied index is now lower than readState.Index' (duration: 17.379695ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T00:15:06.085884Z","caller":"traceutil/trace.go:171","msg":"trace[1767281322] transaction","detail":"{read_only:false; response_revision:2480; number_of_response:1; }","duration":"134.966424ms","start":"2026-04-16T00:15:05.950889Z","end":"2026-04-16T00:15:06.085855Z","steps":["trace[1767281322] 'process raft request' (duration: 117.547108ms)","trace[1767281322] 'compare' (duration: 17.174549ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T00:15:06.085976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.813727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-16T00:15:06.086045Z","caller":"traceutil/trace.go:171","msg":"trace[238987784] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2480; }","duration":"113.9012ms","start":"2026-04-16T00:15:05.972133Z","end":"2026-04-16T00:15:06.086035Z","steps":["trace[238987784] 'agreement among raft nodes before linearized reading' (duration: 113.704573ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:15:06.950831Z","caller":"traceutil/trace.go:171","msg":"trace[826818209] transaction","detail":"{read_only:false; response_revision:2482; number_of_response:1; }","duration":"142.269695ms","start":"2026-04-16T00:15:06.808537Z","end":"2026-04-16T00:15:06.950807Z","steps":["trace[826818209] 'process raft request' (duration: 142.09463ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:15:07.183844Z","caller":"traceutil/trace.go:171","msg":"trace[2092084795] linearizableReadLoop","detail":"{readStateIndex:2585; appliedIndex:2584; }","duration":"230.562501ms","start":"2026-04-16T00:15:06.953258Z","end":"2026-04-16T00:15:07.18382Z","steps":["trace[2092084795] 'read index received' (duration: 197.983481ms)","trace[2092084795] 'applied index is now lower than readState.Index' (duration: 32.57798ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T00:15:07.183931Z","caller":"traceutil/trace.go:171","msg":"trace[1468428121] transaction","detail":"{read_only:false; response_revision:2483; number_of_response:1; }","duration":"255.158563ms","start":"2026-04-16T00:15:06.928764Z","end":"2026-04-16T00:15:07.183922Z","steps":["trace[1468428121] 'process raft request' (duration: 222.514851ms)","trace[1468428121] 'compare' (duration: 32.405845ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T00:15:07.184076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.813808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-04-16T00:15:07.184099Z","caller":"traceutil/trace.go:171","msg":"trace[37351360] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:2483; }","duration":"230.899451ms","start":"2026-04-16T00:15:06.953195Z","end":"2026-04-16T00:15:07.184094Z","steps":["trace[37351360] 'agreement among raft nodes before linearized reading' (duration: 230.832298ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:15:07.42163Z","caller":"traceutil/trace.go:171","msg":"trace[1785943837] linearizableReadLoop","detail":"{readStateIndex:2586; appliedIndex:2585; }","duration":"237.707648ms","start":"2026-04-16T00:15:07.183893Z","end":"2026-04-16T00:15:07.4216Z","steps":["trace[1785943837] 'read index received' (duration: 235.22148ms)","trace[1785943837] 'applied index is now lower than readState.Index' (duration: 2.484898ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T00:15:07.421963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.86253ms","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-04-16T00:15:07.421784Z","caller":"traceutil/trace.go:171","msg":"trace[73917058] transaction","detail":"{read_only:false; response_revision:2484; number_of_response:1; }","duration":"351.615311ms","start":"2026-04-16T00:15:07.070113Z","end":"2026-04-16T00:15:07.421728Z","steps":["trace[73917058] 'process raft request' (duration: 349.052581ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:15:07.422062Z","caller":"traceutil/trace.go:171","msg":"trace[1409113353] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2484; }","duration":"329.032324ms","start":"2026-04-16T00:15:07.093012Z","end":"2026-04-16T00:15:07.422044Z","steps":["trace[1409113353] 'agreement among raft nodes before linearized reading' (duration: 328.739886ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:15:07.422032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.687733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-16T00:15:07.422195Z","caller":"traceutil/trace.go:171","msg":"trace[2036676025] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2484; }","duration":"325.905949ms","start":"2026-04-16T00:15:07.096271Z","end":"2026-04-16T00:15:07.422177Z","steps":["trace[2036676025] 'agreement among raft nodes before linearized reading' (duration: 325.420575ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:15:07.422238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.29951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-04-16T00:15:07.422246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:15:07.070087Z","time spent":"352.012862ms","remote":"127.0.0.1:37402","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-04-16T00:15:07.422296Z","caller":"traceutil/trace.go:171","msg":"trace[1099998500] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:2484; }","duration":"236.384632ms","start":"2026-04-16T00:15:07.1859Z","end":"2026-04-16T00:15:07.422284Z","steps":["trace[1099998500] 'agreement among raft nodes before linearized reading' (duration: 236.252618ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:15:07.422116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:15:07.092989Z","time spent":"329.111846ms","remote":"127.0.0.1:37402","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-04-16T00:15:07.422835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:15:07.096259Z","time spent":"326.555248ms","remote":"127.0.0.1:37402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-16T00:15:07.6177Z","caller":"traceutil/trace.go:171","msg":"trace[1712746760] transaction","detail":"{read_only:false; response_revision:2486; number_of_response:1; }","duration":"190.685883ms","start":"2026-04-16T00:15:07.426982Z","end":"2026-04-16T00:15:07.617668Z","steps":["trace[1712746760] 'process raft request' (duration: 102.42003ms)","trace[1712746760] 'compare' (duration: 88.14606ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T00:15:08.529329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"685.203508ms","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-04-16T00:15:08.52946Z","caller":"traceutil/trace.go:171","msg":"trace[1833297340] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2487; }","duration":"685.347672ms","start":"2026-04-16T00:15:07.844094Z","end":"2026-04-16T00:15:08.529442Z","steps":["trace[1833297340] 'range keys from in-memory index tree' (duration: 685.189658ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:15:08.529557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"688.015855ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T00:15:08.529755Z","caller":"traceutil/trace.go:171","msg":"trace[634872293] transaction","detail":"{read_only:false; response_revision:2490; number_of_response:1; }","duration":"751.246263ms","start":"2026-04-16T00:15:07.778496Z","end":"2026-04-16T00:15:08.529742Z","steps":["trace[634872293] 'process raft request' (duration: 751.193521ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:15:08.529784Z","caller":"traceutil/trace.go:171","msg":"trace[1959557232] transaction","detail":"{read_only:false; response_revision:2488; number_of_response:1; }","duration":"844.332649ms","start":"2026-04-16T00:15:07.685403Z","end":"2026-04-16T00:15:08.529736Z","steps":["trace[1959557232] 'process raft request' (duration: 156.074747ms)","trace[1959557232] 'compare' (duration: 687.667556ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T00:15:08.529856Z","caller":"traceutil/trace.go:171","msg":"trace[2045840940] transaction","detail":"{read_only:false; response_revision:2489; number_of_response:1; }","duration":"825.609926ms","start":"2026-04-16T00:15:07.704235Z","end":"2026-04-16T00:15:08.529845Z","steps":["trace[2045840940] 'process raft request' (duration: 825.39175ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:15:08.529909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:15:07.704204Z","time spent":"825.680458ms","remote":"127.0.0.1:37402","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-04-16T00:15:08.529944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:15:07.685376Z","time spent":"844.479852ms","remote":"127.0.0.1:37402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T00:15:08.529816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:15:07.778471Z","time spent":"751.320824ms","remote":"127.0.0.1:37194","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":805,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-16T00:15:08.530176Z","caller":"traceutil/trace.go:171","msg":"trace[1107185811] linearizableReadLoop","detail":"{readStateIndex:2591; appliedIndex:2589; }","duration":"770.500789ms","start":"2026-04-16T00:15:07.759662Z","end":"2026-04-16T00:15:08.530162Z","steps":["trace[1107185811] 'read index received' (duration: 81.836297ms)","trace[1107185811] 'applied index is now lower than readState.Index' (duration: 688.663482ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T00:15:08.530424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.468174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-16T00:15:08.530418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"596.337299ms","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-04-16T00:15:08.53046Z","caller":"traceutil/trace.go:171","msg":"trace[111749210] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2490; }","duration":"391.547146ms","start":"2026-04-16T00:15:08.138904Z","end":"2026-04-16T00:15:08.530451Z","steps":["trace[111749210] 'agreement among raft nodes before linearized reading' (duration: 391.422863ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:15:08.530476Z","caller":"traceutil/trace.go:171","msg":"trace[738774540] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2490; }","duration":"596.506203ms","start":"2026-04-16T00:15:07.933955Z","end":"2026-04-16T00:15:08.530462Z","steps":["trace[738774540] 'agreement among raft nodes before linearized reading' (duration: 596.362929ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:15:08.53049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:15:08.138882Z","time spent":"391.601328ms","remote":"127.0.0.1:37402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-16T00:15:08.530509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:15:07.933934Z","time spent":"596.567374ms","remote":"127.0.0.1:37402","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":"warn","ts":"2026-04-16T00:15:08.530686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"770.838059ms","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-04-16T00:15:08.530844Z","caller":"traceutil/trace.go:171","msg":"trace[876811910] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2490; }","duration":"771.117326ms","start":"2026-04-16T00:15:07.759616Z","end":"2026-04-16T00:15:08.530733Z","steps":["trace[876811910] 'agreement among raft nodes before linearized reading' (duration: 770.739645ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:15:08.530929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:15:07.759602Z","time spent":"771.312952ms","remote":"127.0.0.1:50608","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-04-16T00:15:08.531584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.863078ms","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-04-16T00:15:08.531955Z","caller":"traceutil/trace.go:171","msg":"trace[1207454077] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2490; }","duration":"440.084585ms","start":"2026-04-16T00:15:08.091614Z","end":"2026-04-16T00:15:08.531699Z","steps":["trace[1207454077] 'agreement among raft nodes before linearized reading' (duration: 438.780829ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:15:08.532262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:15:08.09159Z","time spent":"440.512107ms","remote":"127.0.0.1:37402","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-04-16T00:15:08.926793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.997915ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T00:15:08.9269Z","caller":"traceutil/trace.go:171","msg":"trace[621849337] transaction","detail":"{read_only:false; response_revision:2491; number_of_response:1; }","duration":"392.813601ms","start":"2026-04-16T00:15:08.534067Z","end":"2026-04-16T00:15:08.926881Z","steps":["trace[621849337] 'process raft request' (duration: 120.632942ms)","trace[621849337] 'compare' (duration: 271.875981ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T00:15:08.926973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:15:08.534041Z","time spent":"392.903614ms","remote":"127.0.0.1:37402","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-04-16T00:15:09.009935Z","caller":"traceutil/trace.go:171","msg":"trace[217236282] linearizableReadLoop","detail":"{readStateIndex:2595; appliedIndex:2592; }","duration":"373.391759ms","start":"2026-04-16T00:15:08.636516Z","end":"2026-04-16T00:15:09.009908Z","steps":["trace[217236282] 'read index received' (duration: 18.195928ms)","trace[217236282] 'applied index is now lower than readState.Index' (duration: 355.194891ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T00:15:09.010092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.575454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T00:15:09.010133Z","caller":"traceutil/trace.go:171","msg":"trace[283034087] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2493; }","duration":"373.654656ms","start":"2026-04-16T00:15:08.636468Z","end":"2026-04-16T00:15:09.010122Z","steps":["trace[283034087] 'agreement among raft nodes before linearized reading' (duration: 373.585264ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:15:09.009978Z","caller":"traceutil/trace.go:171","msg":"trace[1261444784] transaction","detail":"{read_only:false; response_revision:2492; number_of_response:1; }","duration":"474.328992ms","start":"2026-04-16T00:15:08.535629Z","end":"2026-04-16T00:15:09.009958Z","steps":["trace[1261444784] 'process raft request' (duration: 474.135267ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:15:09.010162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:15:08.636448Z","time spent":"373.707278ms","remote":"127.0.0.1:37122","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-04-16T00:15:09.010106Z","caller":"traceutil/trace.go:171","msg":"trace[658085321] transaction","detail":"{read_only:false; response_revision:2493; number_of_response:1; }","duration":"474.047675ms","start":"2026-04-16T00:15:08.536019Z","end":"2026-04-16T00:15:09.010067Z","steps":["trace[658085321] 'process raft request' (duration: 473.818408ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:15:09.010588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:15:08.536012Z","time spent":"474.532018ms","remote":"127.0.0.1:37402","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-04-16T00:15:09.010297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:15:08.535621Z","time spent":"474.613359ms","remote":"127.0.0.1:37402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T00:15:09.011051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.143255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18a6ae13dbce2820\" ","response":"range_response_count:1 size:846"} {"level":"info","ts":"2026-04-16T00:15:09.011279Z","caller":"traceutil/trace.go:171","msg":"trace[1403592763] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.18a6ae13dbce2820; range_end:; response_count:1; response_revision:2493; }","duration":"167.398852ms","start":"2026-04-16T00:15:08.843847Z","end":"2026-04-16T00:15:09.011246Z","steps":["trace[1403592763] 'agreement among raft nodes before linearized reading' (duration: 166.595369ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:15:09.579873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.675668ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T00:15:09.580633Z","caller":"traceutil/trace.go:171","msg":"trace[662580071] transaction","detail":"{read_only:false; response_revision:2494; number_of_response:1; }","duration":"565.820563ms","start":"2026-04-16T00:15:09.014768Z","end":"2026-04-16T00:15:09.580588Z","steps":["trace[662580071] 'process raft request' (duration: 155.85238ms)","trace[662580071] 'compare' (duration: 408.564494ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T00:15:09.58073Z","caller":"traceutil/trace.go:171","msg":"trace[952052968] transaction","detail":"{read_only:false; response_revision:2495; number_of_response:1; }","duration":"391.62406ms","start":"2026-04-16T00:15:09.18906Z","end":"2026-04-16T00:15:09.580684Z","steps":["trace[952052968] 'process raft request' (duration: 391.444125ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:15:09.580847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:15:09.014752Z","time spent":"565.975308ms","remote":"127.0.0.1:37194","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-04-16T00:15:09.58089Z","caller":"traceutil/trace.go:171","msg":"trace[55270728] linearizableReadLoop","detail":"{readStateIndex:2597; appliedIndex:2595; }","duration":"373.599115ms","start":"2026-04-16T00:15:09.207272Z","end":"2026-04-16T00:15:09.580871Z","steps":["trace[55270728] 'read index received' (duration: 305.979063ms)","trace[55270728] 'applied index is now lower than readState.Index' (duration: 67.617882ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T00:15:09.581057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:15:09.189037Z","time spent":"391.840187ms","remote":"127.0.0.1:37402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T00:15:09.581432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.174496ms","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-04-16T00:15:09.581482Z","caller":"traceutil/trace.go:171","msg":"trace[896600104] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2496; }","duration":"111.269768ms","start":"2026-04-16T00:15:09.4702Z","end":"2026-04-16T00:15:09.58147Z","steps":["trace[896600104] 'agreement among raft nodes before linearized reading' (duration: 110.833606ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:15:09.581698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.412748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11987"} {"level":"info","ts":"2026-04-16T00:15:09.581788Z","caller":"traceutil/trace.go:171","msg":"trace[1091487256] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2496; }","duration":"374.538891ms","start":"2026-04-16T00:15:09.207231Z","end":"2026-04-16T00:15:09.58177Z","steps":["trace[1091487256] 'agreement among raft nodes before linearized reading' (duration: 373.799231ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:15:09.581823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:15:09.207219Z","time spent":"374.595833ms","remote":"127.0.0.1:37312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12010,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-04-16T00:15:09.582659Z","caller":"traceutil/trace.go:171","msg":"trace[1001795900] transaction","detail":"{read_only:false; response_revision:2496; number_of_response:1; }","duration":"153.217308ms","start":"2026-04-16T00:15:09.42942Z","end":"2026-04-16T00:15:09.582638Z","steps":["trace[1001795900] 'process raft request' (duration: 151.184392ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:15:14.7445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.596884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T00:15:14.744838Z","caller":"traceutil/trace.go:171","msg":"trace[2137631913] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2536; }","duration":"107.984344ms","start":"2026-04-16T00:15:14.636828Z","end":"2026-04-16T00:15:14.744813Z","steps":["trace[2137631913] 'range keys from in-memory index tree' (duration: 107.505521ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:15:14.744524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.369509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12384"} {"level":"warn","ts":"2026-04-16T00:15:14.744723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.980015ms","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-04-16T00:15:14.745327Z","caller":"traceutil/trace.go:171","msg":"trace[781688119] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2536; }","duration":"132.291565ms","start":"2026-04-16T00:15:14.612996Z","end":"2026-04-16T00:15:14.745288Z","steps":["trace[781688119] 'range keys from in-memory index tree' (duration: 131.133603ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:15:14.745402Z","caller":"traceutil/trace.go:171","msg":"trace[316178100] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2536; }","duration":"128.706845ms","start":"2026-04-16T00:15:14.616662Z","end":"2026-04-16T00:15:14.745368Z","steps":["trace[316178100] 'range keys from in-memory index tree' (duration: 127.699637ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:15:35.157264Z","caller":"traceutil/trace.go:171","msg":"trace[1153292398] transaction","detail":"{read_only:false; response_revision:2649; number_of_response:1; }","duration":"106.404946ms","start":"2026-04-16T00:15:35.05083Z","end":"2026-04-16T00:15:35.157235Z","steps":["trace[1153292398] 'process raft request' (duration: 106.254512ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:16:53.432736Z","caller":"traceutil/trace.go:171","msg":"trace[174065848] linearizableReadLoop","detail":"{readStateIndex:3241; appliedIndex:3240; }","duration":"339.291938ms","start":"2026-04-16T00:16:53.093426Z","end":"2026-04-16T00:16:53.432718Z","steps":["trace[174065848] 'read index received' (duration: 339.068424ms)","trace[174065848] 'applied index is now lower than readState.Index' (duration: 222.694µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T00:16:53.43301Z","caller":"traceutil/trace.go:171","msg":"trace[722143332] transaction","detail":"{read_only:false; response_revision:3117; number_of_response:1; }","duration":"435.500749ms","start":"2026-04-16T00:16:52.997496Z","end":"2026-04-16T00:16:53.432996Z","steps":["trace[722143332] 'process raft request' (duration: 435.077531ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:16:53.433111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:16:52.997471Z","time spent":"435.57417ms","remote":"127.0.0.1:37402","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-04-16T00:16:53.433415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.992232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-04-16T00:16:53.433458Z","caller":"traceutil/trace.go:171","msg":"trace[359352542] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:3117; }","duration":"340.067232ms","start":"2026-04-16T00:16:53.093379Z","end":"2026-04-16T00:16:53.433446Z","steps":["trace[359352542] 'agreement among raft nodes before linearized reading' (duration: 339.92445ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:16:53.433487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:16:53.093361Z","time spent":"340.119934ms","remote":"127.0.0.1:37328","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1392,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"info","ts":"2026-04-16T00:16:57.373503Z","caller":"traceutil/trace.go:171","msg":"trace[1091193965] transaction","detail":"{read_only:false; response_revision:3139; number_of_response:1; }","duration":"142.211426ms","start":"2026-04-16T00:16:57.231271Z","end":"2026-04-16T00:16:57.373483Z","steps":["trace[1091193965] 'process raft request' (duration: 142.093533ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:17:26.151298Z","caller":"traceutil/trace.go:171","msg":"trace[152273471] linearizableReadLoop","detail":"{readStateIndex:3425; appliedIndex:3424; }","duration":"291.047278ms","start":"2026-04-16T00:17:25.860227Z","end":"2026-04-16T00:17:26.151275Z","steps":["trace[152273471] 'read index received' (duration: 290.823104ms)","trace[152273471] 'applied index is now lower than readState.Index' (duration: 223.334µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T00:17:26.151392Z","caller":"traceutil/trace.go:171","msg":"trace[1059323759] transaction","detail":"{read_only:false; response_revision:3294; number_of_response:1; }","duration":"357.110756ms","start":"2026-04-16T00:17:25.794249Z","end":"2026-04-16T00:17:26.151359Z","steps":["trace[1059323759] 'process raft request' (duration: 356.891881ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:17:26.151635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:17:25.794236Z","time spent":"357.231049ms","remote":"127.0.0.1:37402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T00:17:26.15185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.600699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-16T00:17:26.151924Z","caller":"traceutil/trace.go:171","msg":"trace[933599445] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3294; }","duration":"291.729672ms","start":"2026-04-16T00:17:25.860174Z","end":"2026-04-16T00:17:26.151903Z","steps":["trace[933599445] 'agreement among raft nodes before linearized reading' (duration: 291.215082ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:17:26.526476Z","caller":"traceutil/trace.go:171","msg":"trace[1345127736] transaction","detail":"{read_only:false; response_revision:3296; number_of_response:1; }","duration":"404.814163ms","start":"2026-04-16T00:17:26.121638Z","end":"2026-04-16T00:17:26.526452Z","steps":["trace[1345127736] 'process raft request' (duration: 404.751042ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:17:26.526648Z","caller":"traceutil/trace.go:171","msg":"trace[449180800] transaction","detail":"{read_only:false; response_revision:3295; number_of_response:1; }","duration":"625.95755ms","start":"2026-04-16T00:17:25.900676Z","end":"2026-04-16T00:17:26.526634Z","steps":["trace[449180800] 'process raft request' (duration: 625.579602ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:17:26.526759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:17:25.900657Z","time spent":"626.051632ms","remote":"127.0.0.1:37296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9993,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T00:17:26.526927Z","caller":"traceutil/trace.go:171","msg":"trace[1754759815] linearizableReadLoop","detail":"{readStateIndex:3427; appliedIndex:3427; }","duration":"375.543867ms","start":"2026-04-16T00:17:26.151371Z","end":"2026-04-16T00:17:26.526914Z","steps":["trace[1754759815] 'read index received' (duration: 375.538107ms)","trace[1754759815] 'applied index is now lower than readState.Index' (duration: 4.64µs)"],"step_count":2} {"level":"warn","ts":"2026-04-16T00:17:26.527162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"608.692727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-16T00:17:26.527203Z","caller":"traceutil/trace.go:171","msg":"trace[1167291318] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3296; }","duration":"608.778709ms","start":"2026-04-16T00:17:25.918413Z","end":"2026-04-16T00:17:26.527191Z","steps":["trace[1167291318] 'agreement among raft nodes before linearized reading' (duration: 608.624386ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:17:26.527231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:17:25.918397Z","time spent":"608.82781ms","remote":"127.0.0.1:37402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-16T00:17:26.528055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:17:26.121613Z","time spent":"404.939347ms","remote":"127.0.0.1:37402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T00:17:26.648597Z","caller":"traceutil/trace.go:171","msg":"trace[1061376632] transaction","detail":"{read_only:false; response_revision:3298; number_of_response:1; }","duration":"455.441991ms","start":"2026-04-16T00:17:26.193138Z","end":"2026-04-16T00:17:26.64858Z","steps":["trace[1061376632] 'process raft request' (duration: 455.340839ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:17:26.648784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:17:26.193115Z","time spent":"455.545894ms","remote":"127.0.0.1:37402","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-04-16T00:17:26.648824Z","caller":"traceutil/trace.go:171","msg":"trace[1043778774] transaction","detail":"{read_only:false; response_revision:3299; number_of_response:1; }","duration":"454.298587ms","start":"2026-04-16T00:17:26.194513Z","end":"2026-04-16T00:17:26.648811Z","steps":["trace[1043778774] 'process raft request' (duration: 454.024402ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:17:26.648903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:17:26.194498Z","time spent":"454.360708ms","remote":"127.0.0.1:37402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T00:17:26.648593Z","caller":"traceutil/trace.go:171","msg":"trace[1545077149] transaction","detail":"{read_only:false; response_revision:3297; number_of_response:1; }","duration":"492.437621ms","start":"2026-04-16T00:17:26.156128Z","end":"2026-04-16T00:17:26.648565Z","steps":["trace[1545077149] 'process raft request' (duration: 486.927785ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:17:26.649034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:17:26.156101Z","time spent":"492.905711ms","remote":"127.0.0.1:37402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T00:17:26.649088Z","caller":"traceutil/trace.go:171","msg":"trace[252607679] transaction","detail":"{read_only:false; response_revision:3300; number_of_response:1; }","duration":"408.174675ms","start":"2026-04-16T00:17:26.24089Z","end":"2026-04-16T00:17:26.649064Z","steps":["trace[252607679] 'process raft request' (duration: 407.863679ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:17:26.649289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:17:26.240873Z","time spent":"408.336348ms","remote":"127.0.0.1:37402","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-04-16T00:17:26.649316Z","caller":"traceutil/trace.go:171","msg":"trace[1119392562] linearizableReadLoop","detail":"{readStateIndex:3432; appliedIndex:3427; }","duration":"122.319138ms","start":"2026-04-16T00:17:26.526985Z","end":"2026-04-16T00:17:26.649305Z","steps":["trace[1119392562] 'read index received' (duration: 116.058187ms)","trace[1119392562] 'applied index is now lower than readState.Index' (duration: 6.260281ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T00:17:26.64941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.547308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-16T00:17:26.649435Z","caller":"traceutil/trace.go:171","msg":"trace[567112033] transaction","detail":"{read_only:false; response_revision:3301; number_of_response:1; }","duration":"333.195144ms","start":"2026-04-16T00:17:26.316229Z","end":"2026-04-16T00:17:26.649424Z","steps":["trace[567112033] 'process raft request' (duration: 332.756565ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:17:26.649447Z","caller":"traceutil/trace.go:171","msg":"trace[265450488] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:3301; }","duration":"450.62079ms","start":"2026-04-16T00:17:26.198817Z","end":"2026-04-16T00:17:26.649438Z","steps":["trace[265450488] 'agreement among raft nodes before linearized reading' (duration: 450.538288ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:17:26.649486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:17:26.31621Z","time spent":"333.248186ms","remote":"127.0.0.1:37402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T00:17:26.649521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:17:26.198804Z","time spent":"450.667631ms","remote":"127.0.0.1:50554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"info","ts":"2026-04-16T00:17:26.649718Z","caller":"traceutil/trace.go:171","msg":"trace[1229380884] transaction","detail":"{read_only:false; response_revision:3302; number_of_response:1; }","duration":"117.14673ms","start":"2026-04-16T00:17:26.53256Z","end":"2026-04-16T00:17:26.649707Z","steps":["trace[1229380884] 'process raft request' (duration: 116.706351ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:17:26.649778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.986975ms","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-04-16T00:17:26.649814Z","caller":"traceutil/trace.go:171","msg":"trace[150394042] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3302; }","duration":"264.060187ms","start":"2026-04-16T00:17:26.385745Z","end":"2026-04-16T00:17:26.649805Z","steps":["trace[150394042] 'agreement among raft nodes before linearized reading' (duration: 263.934244ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:17:26.649884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.557399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-16T00:17:26.64992Z","caller":"traceutil/trace.go:171","msg":"trace[791392607] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3302; }","duration":"266.632541ms","start":"2026-04-16T00:17:26.383278Z","end":"2026-04-16T00:17:26.649911Z","steps":["trace[791392607] 'agreement among raft nodes before linearized reading' (duration: 266.514768ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:17:26.650072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.661892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18a6ae3080fb4f46\" ","response":"range_response_count:1 size:779"} {"level":"info","ts":"2026-04-16T00:17:26.650102Z","caller":"traceutil/trace.go:171","msg":"trace[502498069] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18a6ae3080fb4f46; range_end:; response_count:1; response_revision:3302; }","duration":"258.722974ms","start":"2026-04-16T00:17:26.391371Z","end":"2026-04-16T00:17:26.650094Z","steps":["trace[502498069] 'agreement among raft nodes before linearized reading' (duration: 258.033739ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:17:32.331309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"694.676518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T00:17:32.331379Z","caller":"traceutil/trace.go:171","msg":"trace[415627145] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3327; }","duration":"694.79429ms","start":"2026-04-16T00:17:31.63657Z","end":"2026-04-16T00:17:32.331365Z","steps":["trace[415627145] 'range keys from in-memory index tree' (duration: 694.599785ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:17:32.331412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:17:31.636549Z","time spent":"694.850351ms","remote":"127.0.0.1:37122","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-04-16T00:17:32.331885Z","caller":"traceutil/trace.go:171","msg":"trace[1068097598] transaction","detail":"{read_only:false; response_revision:3328; number_of_response:1; }","duration":"611.286898ms","start":"2026-04-16T00:17:31.720585Z","end":"2026-04-16T00:17:32.331872Z","steps":["trace[1068097598] 'process raft request' (duration: 610.336717ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:17:32.33196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:17:31.72057Z","time spent":"611.344569ms","remote":"127.0.0.1:37402","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-04-16T00:17:32.332184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"626.772138ms","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-04-16T00:17:32.33221Z","caller":"traceutil/trace.go:171","msg":"trace[1662021119] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:3327; }","duration":"626.798389ms","start":"2026-04-16T00:17:31.705405Z","end":"2026-04-16T00:17:32.332204Z","steps":["trace[1662021119] 'range keys from in-memory index tree' (duration: 626.175175ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:17:32.332228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T00:17:31.705353Z","time spent":"626.87129ms","remote":"127.0.0.1:37402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":552,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"info","ts":"2026-04-16T00:17:32.343256Z","caller":"traceutil/trace.go:171","msg":"trace[495077436] linearizableReadLoop","detail":"{readStateIndex:3461; appliedIndex:3460; }","duration":"207.820768ms","start":"2026-04-16T00:17:32.135419Z","end":"2026-04-16T00:17:32.34324Z","steps":["trace[495077436] 'read index received' (duration: 196.71027ms)","trace[495077436] 'applied index is now lower than readState.Index' (duration: 11.109488ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T00:17:32.343466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.039414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-04-16T00:17:32.343493Z","caller":"traceutil/trace.go:171","msg":"trace[707953403] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:3328; }","duration":"208.072934ms","start":"2026-04-16T00:17:32.135413Z","end":"2026-04-16T00:17:32.343486Z","steps":["trace[707953403] 'agreement among raft nodes before linearized reading' (duration: 207.933941ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:17:32.345907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.055112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-16T00:17:32.346308Z","caller":"traceutil/trace.go:171","msg":"trace[1568037559] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3329; }","duration":"172.174394ms","start":"2026-04-16T00:17:32.1738Z","end":"2026-04-16T00:17:32.345974Z","steps":["trace[1568037559] 'agreement among raft nodes before linearized reading' (duration: 171.951449ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T00:19:17.017176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.320046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-16T00:19:17.017272Z","caller":"traceutil/trace.go:171","msg":"trace[619997534] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4040; }","duration":"151.490901ms","start":"2026-04-16T00:19:16.865762Z","end":"2026-04-16T00:19:17.017253Z","steps":["trace[619997534] 'range keys from in-memory index tree' (duration: 151.07499ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:20:15.510981Z","caller":"traceutil/trace.go:171","msg":"trace[1644157437] transaction","detail":"{read_only:false; response_revision:4520; number_of_response:1; }","duration":"136.77157ms","start":"2026-04-16T00:20:15.374149Z","end":"2026-04-16T00:20:15.51092Z","steps":["trace[1644157437] 'process raft request' (duration: 136.652507ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:20:15.511293Z","caller":"traceutil/trace.go:171","msg":"trace[57484647] transaction","detail":"{read_only:false; response_revision:4519; number_of_response:1; }","duration":"137.688483ms","start":"2026-04-16T00:20:15.373534Z","end":"2026-04-16T00:20:15.511222Z","steps":["trace[57484647] 'process raft request' (duration: 119.21047ms)","trace[57484647] 'compare' (duration: 17.859407ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T00:21:13.989234Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2871} {"level":"info","ts":"2026-04-16T00:21:14.222326Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2871,"took":"231.103935ms","hash":3593712665,"current-db-size-bytes":53620736,"current-db-size":"54 MB","current-db-size-in-use-bytes":29982720,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-04-16T00:21:14.222393Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3593712665,"revision":2871,"compact-revision":-1} {"level":"info","ts":"2026-04-16T00:21:18.225517Z","caller":"traceutil/trace.go:171","msg":"trace[522567240] linearizableReadLoop","detail":"{readStateIndex:5826; appliedIndex:5825; }","duration":"147.107735ms","start":"2026-04-16T00:21:18.078371Z","end":"2026-04-16T00:21:18.225479Z","steps":["trace[522567240] 'read index received' (duration: 125.045662ms)","trace[522567240] 'applied index is now lower than readState.Index' (duration: 22.060803ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T00:21:18.225659Z","caller":"traceutil/trace.go:171","msg":"trace[1215888634] transaction","detail":"{read_only:false; response_revision:5617; number_of_response:1; }","duration":"159.948231ms","start":"2026-04-16T00:21:18.065639Z","end":"2026-04-16T00:21:18.225587Z","steps":["trace[1215888634] 'process raft request' (duration: 137.820936ms)","trace[1215888634] 'compare' (duration: 21.887319ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T00:21:18.225843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.426523ms","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-04-16T00:21:18.225968Z","caller":"traceutil/trace.go:171","msg":"trace[1419224148] 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:5617; }","duration":"147.564877ms","start":"2026-04-16T00:21:18.078364Z","end":"2026-04-16T00:21:18.225928Z","steps":["trace[1419224148] 'agreement among raft nodes before linearized reading' (duration: 147.233438ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:21:22.11973Z","caller":"traceutil/trace.go:171","msg":"trace[957426127] transaction","detail":"{read_only:false; response_revision:5667; number_of_response:1; }","duration":"103.131187ms","start":"2026-04-16T00:21:22.016569Z","end":"2026-04-16T00:21:22.1197Z","steps":["trace[957426127] 'process raft request' (duration: 102.936572ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:21:22.184684Z","caller":"traceutil/trace.go:171","msg":"trace[1939586806] transaction","detail":"{read_only:false; response_revision:5668; number_of_response:1; }","duration":"167.700025ms","start":"2026-04-16T00:21:22.016953Z","end":"2026-04-16T00:21:22.184653Z","steps":["trace[1939586806] 'process raft request' (duration: 167.462918ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:21:22.185055Z","caller":"traceutil/trace.go:171","msg":"trace[45279800] transaction","detail":"{read_only:false; response_revision:5670; number_of_response:1; }","duration":"165.629021ms","start":"2026-04-16T00:21:22.019397Z","end":"2026-04-16T00:21:22.185026Z","steps":["trace[45279800] 'process raft request' (duration: 165.423114ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:21:22.185384Z","caller":"traceutil/trace.go:171","msg":"trace[1841646661] transaction","detail":"{read_only:false; response_revision:5669; number_of_response:1; }","duration":"166.179775ms","start":"2026-04-16T00:21:22.019186Z","end":"2026-04-16T00:21:22.185366Z","steps":["trace[1841646661] 'process raft request' (duration: 165.418973ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:21:28.572028Z","caller":"traceutil/trace.go:171","msg":"trace[2110747438] transaction","detail":"{read_only:false; response_revision:5734; number_of_response:1; }","duration":"186.661574ms","start":"2026-04-16T00:21:28.385329Z","end":"2026-04-16T00:21:28.571991Z","steps":["trace[2110747438] 'process raft request' (duration: 137.124939ms)","trace[2110747438] 'compare' (duration: 49.365911ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T00:21:28.720639Z","caller":"traceutil/trace.go:171","msg":"trace[588354513] transaction","detail":"{read_only:false; response_revision:5735; number_of_response:1; }","duration":"135.955927ms","start":"2026-04-16T00:21:28.584651Z","end":"2026-04-16T00:21:28.720607Z","steps":["trace[588354513] 'process raft request' (duration: 116.535964ms)","trace[588354513] 'compare' (duration: 19.255529ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T00:21:28.735917Z","caller":"traceutil/trace.go:171","msg":"trace[1663110351] transaction","detail":"{read_only:false; number_of_response:1; response_revision:5735; }","duration":"151.196759ms","start":"2026-04-16T00:21:28.584688Z","end":"2026-04-16T00:21:28.735884Z","steps":["trace[1663110351] 'process raft request' (duration: 151.019634ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:21:28.736005Z","caller":"traceutil/trace.go:171","msg":"trace[2047057865] transaction","detail":"{read_only:false; response_revision:5736; number_of_response:1; }","duration":"132.276178ms","start":"2026-04-16T00:21:28.603704Z","end":"2026-04-16T00:21:28.73598Z","steps":["trace[2047057865] 'process raft request' (duration: 132.099654ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T00:21:28.735924Z","caller":"traceutil/trace.go:171","msg":"trace[1018166662] linearizableReadLoop","detail":"{readStateIndex:5949; appliedIndex:5946; }","duration":"100.08196ms","start":"2026-04-16T00:21:28.635819Z","end":"2026-04-16T00:21:28.735901Z","steps":["trace[1018166662] 'read index received' (duration: 65.447516ms)","trace[1018166662] 'applied index is now lower than readState.Index' (duration: 34.633184ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T00:21:28.73606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.222594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T00:21:28.736124Z","caller":"traceutil/trace.go:171","msg":"trace[1417655680] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5736; }","duration":"100.311426ms","start":"2026-04-16T00:21:28.635803Z","end":"2026-04-16T00:21:28.736114Z","steps":["trace[1417655680] 'agreement among raft nodes before linearized reading' (duration: 100.176962ms)"],"step_count":1}