{"level":"warn","ts":"2026-04-21T14:40:06.471748Z","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-21T14:40:06.471885Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.235: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.235:2380","--initial-cluster=instance=https://199.204.45.235:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.235:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.235: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-21T14:40:06.472025Z","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-21T14:40:06.47205Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.235:2380"]} {"level":"info","ts":"2026-04-21T14:40:06.472722Z","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-21T14:40:06.473793Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.235:2379"]} {"level":"info","ts":"2026-04-21T14:40:06.474206Z","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.235:2380"],"listen-peer-urls":["https://199.204.45.235:2380"],"advertise-client-urls":["https://199.204.45.235:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.235:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.235: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-21T14:40:06.478181Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.53844ms"} {"level":"info","ts":"2026-04-21T14:40:06.483971Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"aab6040abcf11","cluster-id":"2bae7d3d8857c059"} {"level":"info","ts":"2026-04-21T14:40:06.484054Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 switched to configuration voters=()"} {"level":"info","ts":"2026-04-21T14:40:06.484112Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became follower at term 0"} {"level":"info","ts":"2026-04-21T14:40:06.484133Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft aab6040abcf11 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-21T14:40:06.484146Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became follower at term 1"} {"level":"info","ts":"2026-04-21T14:40:06.484248Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 switched to configuration voters=(3003179657318161)"} {"level":"warn","ts":"2026-04-21T14:40:06.490213Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-21T14:40:06.49204Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-21T14:40:06.493128Z","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-21T14:40:06.496425Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"aab6040abcf11","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-21T14:40:06.496723Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"aab6040abcf11","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-21T14:40:06.49704Z","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-21T14:40:06.49717Z","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-21T14:40:06.497189Z","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-21T14:40:06.501141Z","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-21T14:40:06.501212Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.235:2380"} {"level":"info","ts":"2026-04-21T14:40:06.501254Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.235:2380"} {"level":"info","ts":"2026-04-21T14:40:06.501498Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"aab6040abcf11","initial-advertise-peer-urls":["https://199.204.45.235:2380"],"listen-peer-urls":["https://199.204.45.235:2380"],"advertise-client-urls":["https://199.204.45.235:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.235:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-21T14:40:06.501546Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-21T14:40:06.502363Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 switched to configuration voters=(3003179657318161)"} {"level":"info","ts":"2026-04-21T14:40:06.502465Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"2bae7d3d8857c059","local-member-id":"aab6040abcf11","added-peer-id":"aab6040abcf11","added-peer-peer-urls":["https://199.204.45.235:2380"]} {"level":"info","ts":"2026-04-21T14:40:06.785121Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 is starting a new election at term 1"} {"level":"info","ts":"2026-04-21T14:40:06.785203Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-21T14:40:06.785251Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 received MsgPreVoteResp from aab6040abcf11 at term 1"} {"level":"info","ts":"2026-04-21T14:40:06.785287Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became candidate at term 2"} {"level":"info","ts":"2026-04-21T14:40:06.785295Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 received MsgVoteResp from aab6040abcf11 at term 2"} {"level":"info","ts":"2026-04-21T14:40:06.785313Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became leader at term 2"} {"level":"info","ts":"2026-04-21T14:40:06.785324Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: aab6040abcf11 elected leader aab6040abcf11 at term 2"} {"level":"info","ts":"2026-04-21T14:40:06.786391Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"aab6040abcf11","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.235:2379]}","request-path":"/0/members/aab6040abcf11/attributes","cluster-id":"2bae7d3d8857c059","publish-timeout":"7s"} {"level":"info","ts":"2026-04-21T14:40:06.786564Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-21T14:40:06.786748Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-21T14:40:06.786751Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-21T14:40:06.786883Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-21T14:40:06.786709Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-21T14:40:06.788131Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"2bae7d3d8857c059","local-member-id":"aab6040abcf11","cluster-version":"3.5"} {"level":"info","ts":"2026-04-21T14:40:06.788331Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-21T14:40:06.788407Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-21T14:40:06.790253Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.235:2379"} {"level":"info","ts":"2026-04-21T14:40:06.791441Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-21T14:40:22.596076Z","caller":"traceutil/trace.go:171","msg":"trace[1848187566] linearizableReadLoop","detail":"{readStateIndex:427; appliedIndex:426; }","duration":"245.633103ms","start":"2026-04-21T14:40:22.350421Z","end":"2026-04-21T14:40:22.596054Z","steps":["trace[1848187566] 'read index received' (duration: 245.474758ms)","trace[1848187566] 'applied index is now lower than readState.Index' (duration: 157.705µs)"],"step_count":2} {"level":"info","ts":"2026-04-21T14:40:22.596143Z","caller":"traceutil/trace.go:171","msg":"trace[1821521417] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"283.203114ms","start":"2026-04-21T14:40:22.312902Z","end":"2026-04-21T14:40:22.596105Z","steps":["trace[1821521417] 'process raft request' (duration: 283.039579ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T14:40:22.596281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.842079ms","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-21T14:40:22.596343Z","caller":"traceutil/trace.go:171","msg":"trace[173262676] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:413; }","duration":"245.951722ms","start":"2026-04-21T14:40:22.350383Z","end":"2026-04-21T14:40:22.596335Z","steps":["trace[173262676] 'agreement among raft nodes before linearized reading' (duration: 245.787707ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T14:40:22.596372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.265034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-21T14:40:22.596386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.756181ms","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-21T14:40:22.596422Z","caller":"traceutil/trace.go:171","msg":"trace[1741229892] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:413; }","duration":"176.351938ms","start":"2026-04-21T14:40:22.420056Z","end":"2026-04-21T14:40:22.596408Z","steps":["trace[1741229892] 'agreement among raft nodes before linearized reading' (duration: 176.262145ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T14:40:22.596443Z","caller":"traceutil/trace.go:171","msg":"trace[1239505251] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:413; }","duration":"196.846434ms","start":"2026-04-21T14:40:22.399585Z","end":"2026-04-21T14:40:22.596431Z","steps":["trace[1239505251] 'agreement among raft nodes before linearized reading' (duration: 196.705469ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T14:40:22.805515Z","caller":"traceutil/trace.go:171","msg":"trace[1758352559] linearizableReadLoop","detail":"{readStateIndex:428; appliedIndex:427; }","duration":"203.56415ms","start":"2026-04-21T14:40:22.601845Z","end":"2026-04-21T14:40:22.805409Z","steps":["trace[1758352559] 'read index received' (duration: 140.472495ms)","trace[1758352559] 'applied index is now lower than readState.Index' (duration: 63.088725ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T14:40:22.807233Z","caller":"traceutil/trace.go:171","msg":"trace[74784428] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"206.523644ms","start":"2026-04-21T14:40:22.600686Z","end":"2026-04-21T14:40:22.807209Z","steps":["trace[74784428] 'process raft request' (duration: 141.649438ms)","trace[74784428] 'compare' (duration: 60.933592ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T14:40:22.8076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.724772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7303"} {"level":"info","ts":"2026-04-21T14:40:22.807708Z","caller":"traceutil/trace.go:171","msg":"trace[327591057] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:414; }","duration":"205.868966ms","start":"2026-04-21T14:40:22.601816Z","end":"2026-04-21T14:40:22.807685Z","steps":["trace[327591057] 'agreement among raft nodes before linearized reading' (duration: 205.455364ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T14:40:23.135678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.143606ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-21T14:40:23.13586Z","caller":"traceutil/trace.go:171","msg":"trace[1834779097] linearizableReadLoop","detail":"{readStateIndex:429; appliedIndex:428; }","duration":"330.208047ms","start":"2026-04-21T14:40:22.805624Z","end":"2026-04-21T14:40:23.135832Z","steps":["trace[1834779097] 'read index received' (duration: 150.32498ms)","trace[1834779097] 'applied index is now lower than readState.Index' (duration: 179.879287ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T14:40:23.135918Z","caller":"traceutil/trace.go:171","msg":"trace[1633848845] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"533.679504ms","start":"2026-04-21T14:40:22.602188Z","end":"2026-04-21T14:40:23.135868Z","steps":["trace[1633848845] 'process raft request' (duration: 353.683243ms)","trace[1633848845] 'compare' (duration: 177.503568ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T14:40:23.136178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.168897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4425"} {"level":"warn","ts":"2026-04-21T14:40:23.136263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T14:40:22.602177Z","time spent":"533.91513ms","remote":"127.0.0.1:54530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T14:40:23.136357Z","caller":"traceutil/trace.go:171","msg":"trace[1913705412] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:415; }","duration":"394.367962ms","start":"2026-04-21T14:40:22.74194Z","end":"2026-04-21T14:40:23.136308Z","steps":["trace[1913705412] 'agreement among raft nodes before linearized reading' (duration: 394.129456ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T14:40:23.136448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T14:40:22.741918Z","time spent":"394.517057ms","remote":"127.0.0.1:54460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4446,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-04-21T14:40:23.349963Z","caller":"traceutil/trace.go:171","msg":"trace[593334351] linearizableReadLoop","detail":"{readStateIndex:430; appliedIndex:429; }","duration":"214.037121ms","start":"2026-04-21T14:40:23.135897Z","end":"2026-04-21T14:40:23.349934Z","steps":["trace[593334351] 'read index received' (duration: 179.517236ms)","trace[593334351] 'applied index is now lower than readState.Index' (duration: 34.518375ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T14:40:23.349967Z","caller":"traceutil/trace.go:171","msg":"trace[459450852] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"532.659952ms","start":"2026-04-21T14:40:22.817267Z","end":"2026-04-21T14:40:23.349927Z","steps":["trace[459450852] 'process raft request' (duration: 498.035164ms)","trace[459450852] 'compare' (duration: 34.405471ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T14:40:23.350163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T14:40:22.817248Z","time spent":"532.817706ms","remote":"127.0.0.1:54464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7096,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T14:40:23.350445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.389084ms","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-21T14:40:23.3505Z","caller":"traceutil/trace.go:171","msg":"trace[255624016] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:417; }","duration":"302.494867ms","start":"2026-04-21T14:40:23.047991Z","end":"2026-04-21T14:40:23.350486Z","steps":["trace[255624016] 'agreement among raft nodes before linearized reading' (duration: 302.120456ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T14:40:23.350683Z","caller":"traceutil/trace.go:171","msg":"trace[1088453338] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"197.901132ms","start":"2026-04-21T14:40:23.15228Z","end":"2026-04-21T14:40:23.350182Z","steps":["trace[1088453338] 'process raft request' (duration: 197.575333ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T14:40:23.350541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T14:40:23.047973Z","time spent":"302.559178ms","remote":"127.0.0.1:54530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-21T14:40:40.321924Z","caller":"traceutil/trace.go:171","msg":"trace[2098377927] transaction","detail":"{read_only:false; number_of_response:1; response_revision:539; }","duration":"400.471278ms","start":"2026-04-21T14:40:39.921412Z","end":"2026-04-21T14:40:40.321883Z","steps":["trace[2098377927] 'process raft request' (duration: 355.340191ms)","trace[2098377927] 'compare' (duration: 44.88093ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T14:40:40.322089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T14:40:39.921378Z","time spent":"400.626553ms","remote":"127.0.0.1:56250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":101,"response count":0,"response size":10110,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T14:40:40.322124Z","caller":"traceutil/trace.go:171","msg":"trace[929484091] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"390.242105ms","start":"2026-04-21T14:40:39.931846Z","end":"2026-04-21T14:40:40.322088Z","steps":["trace[929484091] 'process raft request' (duration: 390.169083ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T14:40:40.322215Z","caller":"traceutil/trace.go:171","msg":"trace[823260905] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"400.047264ms","start":"2026-04-21T14:40:39.922125Z","end":"2026-04-21T14:40:40.322173Z","steps":["trace[823260905] 'process raft request' (duration: 399.638192ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T14:40:40.322351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T14:40:39.931813Z","time spent":"390.41341ms","remote":"127.0.0.1:56250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28739,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T14:40:40.322408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T14:40:39.922089Z","time spent":"400.21565ms","remote":"127.0.0.1:56250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30393,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T14:40:40.322441Z","caller":"traceutil/trace.go:171","msg":"trace[375242900] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"399.247292ms","start":"2026-04-21T14:40:39.923185Z","end":"2026-04-21T14:40:40.322432Z","steps":["trace[375242900] 'process raft request' (duration: 398.716636ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T14:40:40.322486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T14:40:39.923174Z","time spent":"399.289894ms","remote":"127.0.0.1:56250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-21T14:40:40.322567Z","caller":"traceutil/trace.go:171","msg":"trace[1741588809] transaction","detail":"{read_only:false; number_of_response:0; response_revision:541; }","duration":"397.591983ms","start":"2026-04-21T14:40:39.924963Z","end":"2026-04-21T14:40:40.322555Z","steps":["trace[1741588809] 'process raft request' (duration: 396.994354ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T14:40:40.322615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T14:40:39.924947Z","time spent":"397.647045ms","remote":"127.0.0.1:54752","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":26,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-21T14:40:40.360293Z","caller":"traceutil/trace.go:171","msg":"trace[1651842755] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"407.91999ms","start":"2026-04-21T14:40:39.952365Z","end":"2026-04-21T14:40:40.360285Z","steps":["trace[1651842755] 'process raft request' (duration: 394.226164ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T14:40:40.360468Z","caller":"traceutil/trace.go:171","msg":"trace[960233346] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"404.038834ms","start":"2026-04-21T14:40:39.956408Z","end":"2026-04-21T14:40:40.360447Z","steps":["trace[960233346] 'process raft request' (duration: 391.514234ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T14:40:40.360533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T14:40:39.946531Z","time spent":"413.99572ms","remote":"127.0.0.1:56238","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-21T14:40:40.360594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T14:40:39.956388Z","time spent":"404.113257ms","remote":"127.0.0.1:56250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7693,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T14:40:40.360667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T14:40:39.952348Z","time spent":"408.031323ms","remote":"127.0.0.1:56250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58844,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T14:40:40.360261Z","caller":"traceutil/trace.go:171","msg":"trace[1289739731] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"400.16908ms","start":"2026-04-21T14:40:39.960027Z","end":"2026-04-21T14:40:40.360196Z","steps":["trace[1289739731] 'process raft request' (duration: 389.039981ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T14:40:40.360924Z","caller":"traceutil/trace.go:171","msg":"trace[1259415854] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"396.973025ms","start":"2026-04-21T14:40:39.963932Z","end":"2026-04-21T14:40:40.360905Z","steps":["trace[1259415854] 'process raft request' (duration: 387.030091ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T14:40:40.361032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T14:40:39.963923Z","time spent":"397.040698ms","remote":"127.0.0.1:56250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30374,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T14:40:40.3611Z","caller":"traceutil/trace.go:171","msg":"trace[907567967] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"346.275734ms","start":"2026-04-21T14:40:40.014814Z","end":"2026-04-21T14:40:40.36109Z","steps":["trace[907567967] 'process raft request' (duration: 344.07969ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T14:40:40.361145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T14:40:40.014781Z","time spent":"346.343916ms","remote":"127.0.0.1:56250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-21T14:40:40.361378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T14:40:39.960016Z","time spent":"401.002495ms","remote":"127.0.0.1:56250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64462,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T14:40:41.728998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.076835ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-21T14:40:41.72907Z","caller":"traceutil/trace.go:171","msg":"trace[807761791] linearizableReadLoop","detail":"{readStateIndex:621; appliedIndex:620; }","duration":"172.985347ms","start":"2026-04-21T14:40:41.556073Z","end":"2026-04-21T14:40:41.729058Z","steps":["trace[807761791] 'read index received' (duration: 13.871181ms)","trace[807761791] 'applied index is now lower than readState.Index' (duration: 159.113496ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T14:40:41.729171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.099141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/httproutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:151236"} {"level":"info","ts":"2026-04-21T14:40:41.729192Z","caller":"traceutil/trace.go:171","msg":"trace[998024627] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/httproutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:598; }","duration":"173.154272ms","start":"2026-04-21T14:40:41.55603Z","end":"2026-04-21T14:40:41.729184Z","steps":["trace[998024627] 'agreement among raft nodes before linearized reading' (duration: 173.055609ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T14:40:41.729194Z","caller":"traceutil/trace.go:171","msg":"trace[1028065188] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"192.99914ms","start":"2026-04-21T14:40:41.536124Z","end":"2026-04-21T14:40:41.729123Z","steps":["trace[1028065188] 'process raft request' (duration: 33.73925ms)","trace[1028065188] 'compare' (duration: 158.963752ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T14:40:41.744511Z","caller":"traceutil/trace.go:171","msg":"trace[2130424804] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"145.060249ms","start":"2026-04-21T14:40:41.599433Z","end":"2026-04-21T14:40:41.744494Z","steps":["trace[2130424804] 'process raft request' (duration: 144.686948ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T14:40:47.120318Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920880421774514513,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-21T14:40:47.198891Z","caller":"traceutil/trace.go:171","msg":"trace[562618570] linearizableReadLoop","detail":"{readStateIndex:652; appliedIndex:651; }","duration":"579.024291ms","start":"2026-04-21T14:40:46.619841Z","end":"2026-04-21T14:40:47.198865Z","steps":["trace[562618570] 'read index received' (duration: 578.204537ms)","trace[562618570] 'applied index is now lower than readState.Index' (duration: 818.604µs)"],"step_count":2} {"level":"info","ts":"2026-04-21T14:40:47.198949Z","caller":"traceutil/trace.go:171","msg":"trace[2089897884] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"601.683376ms","start":"2026-04-21T14:40:46.597239Z","end":"2026-04-21T14:40:47.198922Z","steps":["trace[2089897884] 'process raft request' (duration: 601.014566ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T14:40:47.199543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T14:40:46.597199Z","time spent":"602.040086ms","remote":"127.0.0.1:54392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T14:40:47.199545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"579.549166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-21T14:40:47.199647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"579.210227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-21T14:40:47.199682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.409454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T14:40:47.199964Z","caller":"traceutil/trace.go:171","msg":"trace[2039987317] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:628; }","duration":"579.957029ms","start":"2026-04-21T14:40:46.61982Z","end":"2026-04-21T14:40:47.199777Z","steps":["trace[2039987317] 'agreement among raft nodes before linearized reading' (duration: 579.198486ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T14:40:47.200005Z","caller":"traceutil/trace.go:171","msg":"trace[31521629] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:628; }","duration":"500.762985ms","start":"2026-04-21T14:40:46.699204Z","end":"2026-04-21T14:40:47.199967Z","steps":["trace[31521629] 'agreement among raft nodes before linearized reading' (duration: 500.348702ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T14:40:47.200053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T14:40:46.699198Z","time spent":"500.840487ms","remote":"127.0.0.1:57454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":26,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 "} {"level":"warn","ts":"2026-04-21T14:40:47.200079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T14:40:46.619805Z","time spent":"580.238508ms","remote":"127.0.0.1:57410","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":26,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:500 "} {"level":"warn","ts":"2026-04-21T14:40:47.200056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.328007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T14:40:47.20014Z","caller":"traceutil/trace.go:171","msg":"trace[633763437] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:628; }","duration":"579.66079ms","start":"2026-04-21T14:40:46.61995Z","end":"2026-04-21T14:40:47.199611Z","steps":["trace[633763437] 'agreement among raft nodes before linearized reading' (duration: 579.102484ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T14:40:47.200268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T14:40:46.619947Z","time spent":"580.260519ms","remote":"127.0.0.1:57410","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":26,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:10000 "} {"level":"warn","ts":"2026-04-21T14:40:47.200609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.996611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T14:40:47.20068Z","caller":"traceutil/trace.go:171","msg":"trace[784716373] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:628; }","duration":"502.089794ms","start":"2026-04-21T14:40:46.698559Z","end":"2026-04-21T14:40:47.200649Z","steps":["trace[784716373] 'agreement among raft nodes before linearized reading' (duration: 501.774825ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T14:40:47.200747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T14:40:46.698536Z","time spent":"502.196357ms","remote":"127.0.0.1:57454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":26,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"warn","ts":"2026-04-21T14:40:47.200866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.161479ms","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-21T14:40:47.200945Z","caller":"traceutil/trace.go:171","msg":"trace[1761269762] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:628; }","duration":"225.272402ms","start":"2026-04-21T14:40:46.975654Z","end":"2026-04-21T14:40:47.200926Z","steps":["trace[1761269762] 'agreement among raft nodes before linearized reading' (duration: 224.993434ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T14:40:47.20094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.139453ms","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-21T14:40:47.201007Z","caller":"traceutil/trace.go:171","msg":"trace[787761791] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:628; }","duration":"474.219915ms","start":"2026-04-21T14:40:46.726772Z","end":"2026-04-21T14:40:47.200992Z","steps":["trace[787761791] 'agreement among raft nodes before linearized reading' (duration: 473.761601ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T14:40:47.201101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T14:40:46.726761Z","time spent":"474.314848ms","remote":"127.0.0.1:54530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-21T14:40:47.201161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.553207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T14:40:47.201229Z","caller":"traceutil/trace.go:171","msg":"trace[1008020307] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:628; }","duration":"502.62406ms","start":"2026-04-21T14:40:46.698574Z","end":"2026-04-21T14:40:47.201198Z","steps":["trace[1008020307] 'agreement among raft nodes before linearized reading' (duration: 502.392063ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T14:40:47.201276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T14:40:46.698542Z","time spent":"502.721943ms","remote":"127.0.0.1:57454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":26,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 "} {"level":"info","ts":"2026-04-21T14:40:47.200295Z","caller":"traceutil/trace.go:171","msg":"trace[1672230758] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:628; }","duration":"519.866513ms","start":"2026-04-21T14:40:46.680332Z","end":"2026-04-21T14:40:47.200198Z","steps":["trace[1672230758] 'agreement among raft nodes before linearized reading' (duration: 519.199722ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T14:40:47.201448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T14:40:46.680317Z","time spent":"521.095509ms","remote":"127.0.0.1:57438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":26,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 "} {"level":"warn","ts":"2026-04-21T14:40:47.200593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.273798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-fthxt\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-21T14:40:47.201872Z","caller":"traceutil/trace.go:171","msg":"trace[1558978278] range","detail":"{range_begin:/registry/pods/kube-system/cilium-fthxt; range_end:; response_count:1; response_revision:628; }","duration":"321.637858ms","start":"2026-04-21T14:40:46.880192Z","end":"2026-04-21T14:40:47.20183Z","steps":["trace[1558978278] 'agreement among raft nodes before linearized reading' (duration: 320.103843ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T14:40:47.201927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T14:40:46.880158Z","time spent":"321.755802ms","remote":"127.0.0.1:54464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16986,"request content":"key:\"/registry/pods/kube-system/cilium-fthxt\" "} {"level":"warn","ts":"2026-04-21T14:40:47.202048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"520.894973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T14:40:47.202108Z","caller":"traceutil/trace.go:171","msg":"trace[56379035] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:628; }","duration":"520.975056ms","start":"2026-04-21T14:40:46.681113Z","end":"2026-04-21T14:40:47.202088Z","steps":["trace[56379035] 'agreement among raft nodes before linearized reading' (duration: 520.879523ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T14:40:47.202157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"562.955124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-21T14:40:47.202165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T14:40:46.681108Z","time spent":"521.044018ms","remote":"127.0.0.1:57422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":26,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:500 "} {"level":"info","ts":"2026-04-21T14:40:47.202226Z","caller":"traceutil/trace.go:171","msg":"trace[462305631] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:628; }","duration":"563.023076ms","start":"2026-04-21T14:40:46.639174Z","end":"2026-04-21T14:40:47.202197Z","steps":["trace[462305631] 'agreement among raft nodes before linearized reading' (duration: 562.909103ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T14:40:47.202272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T14:40:46.639143Z","time spent":"563.116509ms","remote":"127.0.0.1:57416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":26,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:500 "} {"level":"warn","ts":"2026-04-21T14:40:47.202434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"564.025806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-21T14:40:47.202497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.572419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-21T14:40:47.202525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.695973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T14:40:47.202562Z","caller":"traceutil/trace.go:171","msg":"trace[1665216742] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:628; }","duration":"538.695823ms","start":"2026-04-21T14:40:46.663849Z","end":"2026-04-21T14:40:47.202545Z","steps":["trace[1665216742] 'agreement among raft nodes before linearized reading' (duration: 538.531458ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T14:40:47.202593Z","caller":"traceutil/trace.go:171","msg":"trace[1898822055] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:628; }","duration":"538.760275ms","start":"2026-04-21T14:40:46.663802Z","end":"2026-04-21T14:40:47.202562Z","steps":["trace[1898822055] 'agreement among raft nodes before linearized reading' (duration: 538.684183ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T14:40:47.202601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T14:40:46.663819Z","time spent":"538.769786ms","remote":"127.0.0.1:57422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":26,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 "} {"level":"warn","ts":"2026-04-21T14:40:47.202619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"563.861351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-21T14:40:47.202592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"582.598818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-21T14:40:47.202638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T14:40:46.663793Z","time spent":"538.833227ms","remote":"127.0.0.1:57422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":26,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"info","ts":"2026-04-21T14:40:47.202669Z","caller":"traceutil/trace.go:171","msg":"trace[1519223898] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:628; }","duration":"563.973405ms","start":"2026-04-21T14:40:46.638679Z","end":"2026-04-21T14:40:47.202653Z","steps":["trace[1519223898] 'agreement among raft nodes before linearized reading' (duration: 563.873452ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T14:40:47.202683Z","caller":"traceutil/trace.go:171","msg":"trace[774821335] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:628; }","duration":"582.696981ms","start":"2026-04-21T14:40:46.619967Z","end":"2026-04-21T14:40:47.202664Z","steps":["trace[774821335] 'agreement among raft nodes before linearized reading' (duration: 582.543816ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T14:40:47.202735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T14:40:46.619964Z","time spent":"582.757073ms","remote":"127.0.0.1:57410","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":26,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true "} {"level":"warn","ts":"2026-04-21T14:40:47.202732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T14:40:46.63864Z","time spent":"564.078777ms","remote":"127.0.0.1:57416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":26,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true "} {"level":"info","ts":"2026-04-21T14:40:47.202523Z","caller":"traceutil/trace.go:171","msg":"trace[146010136] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:628; }","duration":"564.116249ms","start":"2026-04-21T14:40:46.638368Z","end":"2026-04-21T14:40:47.202485Z","steps":["trace[146010136] 'agreement among raft nodes before linearized reading' (duration: 563.999424ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T14:40:47.202817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T14:40:46.638355Z","time spent":"564.447539ms","remote":"127.0.0.1:57416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":26,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-04-21T14:40:47.202922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.757689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T14:40:47.202968Z","caller":"traceutil/trace.go:171","msg":"trace[399969607] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:628; }","duration":"522.839291ms","start":"2026-04-21T14:40:46.680117Z","end":"2026-04-21T14:40:47.202956Z","steps":["trace[399969607] 'agreement among raft nodes before linearized reading' (duration: 522.749159ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T14:40:47.203015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T14:40:46.680101Z","time spent":"522.903543ms","remote":"127.0.0.1:57438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":26,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-04-21T14:40:47.380785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.705899ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-21T14:40:47.380897Z","caller":"traceutil/trace.go:171","msg":"trace[699773838] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"172.607085ms","start":"2026-04-21T14:40:47.20827Z","end":"2026-04-21T14:40:47.380877Z","steps":["trace[699773838] 'process raft request' (duration: 43.71626ms)","trace[699773838] 'compare' (duration: 128.575546ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T14:40:47.767838Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920880421774514516,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-21T14:40:48.035964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.563536ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-21T14:40:48.036236Z","caller":"traceutil/trace.go:171","msg":"trace[1075631725] linearizableReadLoop","detail":"{readStateIndex:656; appliedIndex:653; }","duration":"768.459276ms","start":"2026-04-21T14:40:47.267742Z","end":"2026-04-21T14:40:48.036201Z","steps":["trace[1075631725] 'read index received' (duration: 514.598161ms)","trace[1075631725] 'applied index is now lower than readState.Index' (duration: 253.860465ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T14:40:48.036434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"768.702984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T14:40:48.036473Z","caller":"traceutil/trace.go:171","msg":"trace[709034129] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:632; }","duration":"768.786296ms","start":"2026-04-21T14:40:47.267677Z","end":"2026-04-21T14:40:48.036464Z","steps":["trace[709034129] 'agreement among raft nodes before linearized reading' (duration: 768.727594ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T14:40:48.036498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T14:40:47.26765Z","time spent":"768.842158ms","remote":"127.0.0.1:56190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-21T14:40:48.036584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"744.646568ms","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-21T14:40:48.036561Z","caller":"traceutil/trace.go:171","msg":"trace[699670701] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"817.891668ms","start":"2026-04-21T14:40:47.2186Z","end":"2026-04-21T14:40:48.036492Z","steps":["trace[699670701] 'process raft request' (duration: 817.455705ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T14:40:48.036653Z","caller":"traceutil/trace.go:171","msg":"trace[157636464] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:632; }","duration":"744.835404ms","start":"2026-04-21T14:40:47.2918Z","end":"2026-04-21T14:40:48.036636Z","steps":["trace[157636464] 'agreement among raft nodes before linearized reading' (duration: 744.602537ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T14:40:48.036702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T14:40:47.291778Z","time spent":"744.913866ms","remote":"127.0.0.1:54530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-21T14:40:48.036617Z","caller":"traceutil/trace.go:171","msg":"trace[452635890] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"824.316459ms","start":"2026-04-21T14:40:47.212279Z","end":"2026-04-21T14:40:48.036595Z","steps":["trace[452635890] 'process raft request' (duration: 570.025811ms)","trace[452635890] 'compare' (duration: 253.442623ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T14:40:48.036785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T14:40:47.212261Z","time spent":"824.495153ms","remote":"127.0.0.1:54530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T14:40:48.03661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.465939ms","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-21T14:40:48.03684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T14:40:47.218576Z","time spent":"818.108063ms","remote":"127.0.0.1:54464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T14:40:48.036404Z","caller":"traceutil/trace.go:171","msg":"trace[1502910863] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"775.041311ms","start":"2026-04-21T14:40:47.261331Z","end":"2026-04-21T14:40:48.036373Z","steps":["trace[1502910863] 'process raft request' (duration: 774.841805ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T14:40:48.03716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T14:40:47.261287Z","time spent":"775.796444ms","remote":"127.0.0.1:54728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-21T14:40:48.036924Z","caller":"traceutil/trace.go:171","msg":"trace[548926508] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:632; }","duration":"268.808989ms","start":"2026-04-21T14:40:47.768073Z","end":"2026-04-21T14:40:48.036882Z","steps":["trace[548926508] 'agreement among raft nodes before linearized reading' (duration: 268.441188ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T14:41:03.099016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.63945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-9m4mt\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-04-21T14:41:03.099121Z","caller":"traceutil/trace.go:171","msg":"trace[1451529704] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-9m4mt; range_end:; response_count:1; response_revision:796; }","duration":"124.821443ms","start":"2026-04-21T14:41:02.974281Z","end":"2026-04-21T14:41:03.099102Z","steps":["trace[1451529704] 'range keys from in-memory index tree' (duration: 124.437318ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T14:41:31.87849Z","caller":"traceutil/trace.go:171","msg":"trace[466338364] transaction","detail":"{read_only:false; response_revision:1191; number_of_response:1; }","duration":"232.173721ms","start":"2026-04-21T14:41:31.646279Z","end":"2026-04-21T14:41:31.878453Z","steps":["trace[466338364] 'process raft request' (duration: 231.935176ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T14:42:19.00654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.999312ms","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-21T14:42:19.006623Z","caller":"traceutil/trace.go:171","msg":"trace[1852743105] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1866; }","duration":"357.146105ms","start":"2026-04-21T14:42:18.64946Z","end":"2026-04-21T14:42:19.006606Z","steps":["trace[1852743105] 'range keys from in-memory index tree' (duration: 356.738205ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T14:42:19.006666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T14:42:18.649434Z","time spent":"357.221667ms","remote":"127.0.0.1:54530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-21T14:42:19.006735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.337112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-21T14:42:19.006784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.989977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-982b15d9-6ce8-420a-8966-6cc09f1899d5\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-04-21T14:42:19.00684Z","caller":"traceutil/trace.go:171","msg":"trace[1930519744] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1866; }","duration":"423.603857ms","start":"2026-04-21T14:42:18.583215Z","end":"2026-04-21T14:42:19.006819Z","steps":["trace[1930519744] 'range keys from in-memory index tree' (duration: 423.168858ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T14:42:19.006916Z","caller":"traceutil/trace.go:171","msg":"trace[1254093903] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-982b15d9-6ce8-420a-8966-6cc09f1899d5; range_end:; response_count:1; response_revision:1866; }","duration":"433.181902ms","start":"2026-04-21T14:42:18.573708Z","end":"2026-04-21T14:42:19.006889Z","steps":["trace[1254093903] 'range keys from in-memory index tree' (duration: 432.760522ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T14:42:19.006984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T14:42:18.583201Z","time spent":"423.740811ms","remote":"127.0.0.1:54530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-21T14:42:19.006986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T14:42:18.573689Z","time spent":"433.283255ms","remote":"127.0.0.1:54464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3648,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-982b15d9-6ce8-420a-8966-6cc09f1899d5\" "} {"level":"warn","ts":"2026-04-21T14:42:19.007331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.974242ms","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-21T14:42:19.007718Z","caller":"traceutil/trace.go:171","msg":"trace[749604815] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1866; }","duration":"362.392693ms","start":"2026-04-21T14:42:18.645309Z","end":"2026-04-21T14:42:19.007702Z","steps":["trace[749604815] 'range keys from in-memory index tree' (duration: 361.693485ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T14:42:19.007775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T14:42:18.645293Z","time spent":"362.467124ms","remote":"127.0.0.1:54530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-21T14:42:19.270854Z","caller":"traceutil/trace.go:171","msg":"trace[825046795] transaction","detail":"{read_only:false; response_revision:1867; number_of_response:1; }","duration":"260.113631ms","start":"2026-04-21T14:42:19.010719Z","end":"2026-04-21T14:42:19.270832Z","steps":["trace[825046795] 'process raft request' (duration: 260.001368ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T14:42:19.302108Z","caller":"traceutil/trace.go:171","msg":"trace[283310737] transaction","detail":"{read_only:false; response_revision:1869; number_of_response:1; }","duration":"290.34451ms","start":"2026-04-21T14:42:19.011735Z","end":"2026-04-21T14:42:19.30208Z","steps":["trace[283310737] 'process raft request' (duration: 290.186476ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T14:42:19.302297Z","caller":"traceutil/trace.go:171","msg":"trace[373068097] transaction","detail":"{read_only:false; response_revision:1868; number_of_response:1; }","duration":"290.619527ms","start":"2026-04-21T14:42:19.011638Z","end":"2026-04-21T14:42:19.302257Z","steps":["trace[373068097] 'process raft request' (duration: 290.072704ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T14:42:19.3026Z","caller":"traceutil/trace.go:171","msg":"trace[172999949] transaction","detail":"{read_only:false; response_revision:1870; number_of_response:1; }","duration":"142.315094ms","start":"2026-04-21T14:42:19.160271Z","end":"2026-04-21T14:42:19.302586Z","steps":["trace[172999949] 'process raft request' (duration: 141.740841ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T14:42:19.698941Z","caller":"traceutil/trace.go:171","msg":"trace[704255426] linearizableReadLoop","detail":"{readStateIndex:1944; appliedIndex:1943; }","duration":"394.990963ms","start":"2026-04-21T14:42:19.303926Z","end":"2026-04-21T14:42:19.698917Z","steps":["trace[704255426] 'read index received' (duration: 394.828149ms)","trace[704255426] 'applied index is now lower than readState.Index' (duration: 161.614µs)"],"step_count":2} {"level":"warn","ts":"2026-04-21T14:42:19.699093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.150928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T14:42:19.699192Z","caller":"traceutil/trace.go:171","msg":"trace[937926726] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1871; }","duration":"395.24414ms","start":"2026-04-21T14:42:19.303874Z","end":"2026-04-21T14:42:19.699118Z","steps":["trace[937926726] 'agreement among raft nodes before linearized reading' (duration: 395.175768ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T14:42:19.699207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.16828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-982b15d9-6ce8-420a-8966-6cc09f1899d5\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-04-21T14:42:19.699236Z","caller":"traceutil/trace.go:171","msg":"trace[974274207] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-982b15d9-6ce8-420a-8966-6cc09f1899d5; range_end:; response_count:1; response_revision:1871; }","duration":"351.228472ms","start":"2026-04-21T14:42:19.347997Z","end":"2026-04-21T14:42:19.699226Z","steps":["trace[974274207] 'agreement among raft nodes before linearized reading' (duration: 351.14146ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T14:42:19.699264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T14:42:19.347981Z","time spent":"351.276042ms","remote":"127.0.0.1:54464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3648,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-982b15d9-6ce8-420a-8966-6cc09f1899d5\" "} {"level":"warn","ts":"2026-04-21T14:42:19.699382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T14:42:19.303847Z","time spent":"395.525626ms","remote":"127.0.0.1:56202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-21T14:42:19.699612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.50164ms","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-21T14:42:19.69981Z","caller":"traceutil/trace.go:171","msg":"trace[142091949] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1871; }","duration":"289.655234ms","start":"2026-04-21T14:42:19.410029Z","end":"2026-04-21T14:42:19.699685Z","steps":["trace[142091949] 'agreement among raft nodes before linearized reading' (duration: 289.307175ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T14:42:19.700022Z","caller":"traceutil/trace.go:171","msg":"trace[1159089566] transaction","detail":"{read_only:false; response_revision:1871; number_of_response:1; }","duration":"400.737434ms","start":"2026-04-21T14:42:19.299217Z","end":"2026-04-21T14:42:19.699954Z","steps":["trace[1159089566] 'process raft request' (duration: 399.492044ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T14:42:19.700236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T14:42:19.29918Z","time spent":"400.920789ms","remote":"127.0.0.1:54530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T14:42:34.607375Z","caller":"traceutil/trace.go:171","msg":"trace[1138950711] transaction","detail":"{read_only:false; response_revision:1995; number_of_response:1; }","duration":"151.65627ms","start":"2026-04-21T14:42:34.455687Z","end":"2026-04-21T14:42:34.607344Z","steps":["trace[1138950711] 'process raft request' (duration: 151.459045ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T14:42:57.616538Z","caller":"traceutil/trace.go:171","msg":"trace[1628636340] transaction","detail":"{read_only:false; response_revision:2129; number_of_response:1; }","duration":"117.264741ms","start":"2026-04-21T14:42:57.499247Z","end":"2026-04-21T14:42:57.616511Z","steps":["trace[1628636340] 'process raft request' (duration: 117.094076ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T14:42:57.627393Z","caller":"traceutil/trace.go:171","msg":"trace[1925920467] transaction","detail":"{read_only:false; response_revision:2130; number_of_response:1; }","duration":"127.611192ms","start":"2026-04-21T14:42:57.499756Z","end":"2026-04-21T14:42:57.627367Z","steps":["trace[1925920467] 'process raft request' (duration: 127.401446ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T14:42:58.204788Z","caller":"traceutil/trace.go:171","msg":"trace[1066634037] linearizableReadLoop","detail":"{readStateIndex:2214; appliedIndex:2213; }","duration":"146.109453ms","start":"2026-04-21T14:42:58.05864Z","end":"2026-04-21T14:42:58.204749Z","steps":["trace[1066634037] 'read index received' (duration: 145.944029ms)","trace[1066634037] 'applied index is now lower than readState.Index' (duration: 163.594µs)"],"step_count":2} {"level":"info","ts":"2026-04-21T14:42:58.204834Z","caller":"traceutil/trace.go:171","msg":"trace[1853341100] transaction","detail":"{read_only:false; response_revision:2133; number_of_response:1; }","duration":"194.082995ms","start":"2026-04-21T14:42:58.010725Z","end":"2026-04-21T14:42:58.204808Z","steps":["trace[1853341100] 'process raft request' (duration: 193.804718ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T14:42:58.204957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.292679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T14:42:58.205014Z","caller":"traceutil/trace.go:171","msg":"trace[88673847] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2133; }","duration":"146.412282ms","start":"2026-04-21T14:42:58.058587Z","end":"2026-04-21T14:42:58.204999Z","steps":["trace[88673847] 'agreement among raft nodes before linearized reading' (duration: 146.296549ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T14:42:58.473981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.494666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-21T14:42:58.474205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.266964ms","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-21T14:42:58.474319Z","caller":"traceutil/trace.go:171","msg":"trace[490210478] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2133; }","duration":"179.434868ms","start":"2026-04-21T14:42:58.294868Z","end":"2026-04-21T14:42:58.474303Z","steps":["trace[490210478] 'range keys from in-memory index tree' (duration: 178.031671ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T14:42:58.474069Z","caller":"traceutil/trace.go:171","msg":"trace[482141085] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2133; }","duration":"207.64772ms","start":"2026-04-21T14:42:58.266399Z","end":"2026-04-21T14:42:58.474047Z","steps":["trace[482141085] 'range keys from in-memory index tree' (duration: 207.327342ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T14:42:58.810194Z","caller":"traceutil/trace.go:171","msg":"trace[1933221710] linearizableReadLoop","detail":"{readStateIndex:2216; appliedIndex:2215; }","duration":"205.115484ms","start":"2026-04-21T14:42:58.605056Z","end":"2026-04-21T14:42:58.810172Z","steps":["trace[1933221710] 'read index received' (duration: 204.973911ms)","trace[1933221710] 'applied index is now lower than readState.Index' (duration: 140.713µs)"],"step_count":2} {"level":"warn","ts":"2026-04-21T14:42:58.810313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.233787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T14:42:58.810338Z","caller":"traceutil/trace.go:171","msg":"trace[150205623] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:2135; }","duration":"205.310869ms","start":"2026-04-21T14:42:58.605019Z","end":"2026-04-21T14:42:58.81033Z","steps":["trace[150205623] 'agreement among raft nodes before linearized reading' (duration: 205.241798ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T14:42:58.810319Z","caller":"traceutil/trace.go:171","msg":"trace[2012639327] transaction","detail":"{read_only:false; response_revision:2135; number_of_response:1; }","duration":"330.363458ms","start":"2026-04-21T14:42:58.47992Z","end":"2026-04-21T14:42:58.810284Z","steps":["trace[2012639327] 'process raft request' (duration: 330.097041ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T14:42:58.810507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T14:42:58.479908Z","time spent":"330.481312ms","remote":"127.0.0.1:54530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T14:42:58.840789Z","caller":"traceutil/trace.go:171","msg":"trace[986940494] transaction","detail":"{read_only:false; response_revision:2136; number_of_response:1; }","duration":"153.798895ms","start":"2026-04-21T14:42:58.686964Z","end":"2026-04-21T14:42:58.840763Z","steps":["trace[986940494] 'process raft request' (duration: 153.653221ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T14:42:59.578615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.519974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T14:42:59.578686Z","caller":"traceutil/trace.go:171","msg":"trace[2012089945] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2136; }","duration":"311.638508ms","start":"2026-04-21T14:42:59.267028Z","end":"2026-04-21T14:42:59.578666Z","steps":["trace[2012089945] 'range keys from in-memory index tree' (duration: 311.449913ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T14:42:59.578734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T14:42:59.267007Z","time spent":"311.71278ms","remote":"127.0.0.1:56190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-21T14:42:59.578614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"610.194966ms","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-21T14:42:59.579395Z","caller":"traceutil/trace.go:171","msg":"trace[1461259495] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2136; }","duration":"611.025838ms","start":"2026-04-21T14:42:58.968349Z","end":"2026-04-21T14:42:59.579375Z","steps":["trace[1461259495] 'range keys from in-memory index tree' (duration: 609.996251ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T14:42:59.579637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T14:42:58.96832Z","time spent":"611.188872ms","remote":"127.0.0.1:39072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5433,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-04-21T14:42:59.588378Z","caller":"traceutil/trace.go:171","msg":"trace[228148598] linearizableReadLoop","detail":"{readStateIndex:2218; appliedIndex:2217; }","duration":"148.062329ms","start":"2026-04-21T14:42:59.440296Z","end":"2026-04-21T14:42:59.588358Z","steps":["trace[228148598] 'read index received' (duration: 147.855304ms)","trace[228148598] 'applied index is now lower than readState.Index' (duration: 206.275µs)"],"step_count":2} {"level":"info","ts":"2026-04-21T14:42:59.588474Z","caller":"traceutil/trace.go:171","msg":"trace[1742052926] transaction","detail":"{read_only:false; response_revision:2137; number_of_response:1; }","duration":"155.605197ms","start":"2026-04-21T14:42:59.432826Z","end":"2026-04-21T14:42:59.588432Z","steps":["trace[1742052926] 'process raft request' (duration: 155.375901ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T14:42:59.588633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.313536ms","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-21T14:42:59.588712Z","caller":"traceutil/trace.go:171","msg":"trace[835281702] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2137; }","duration":"148.421908ms","start":"2026-04-21T14:42:59.440271Z","end":"2026-04-21T14:42:59.588693Z","steps":["trace[835281702] 'agreement among raft nodes before linearized reading' (duration: 148.159061ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T14:43:00.155132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.609588ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-21T14:43:00.155349Z","caller":"traceutil/trace.go:171","msg":"trace[1750728032] transaction","detail":"{read_only:false; response_revision:2139; number_of_response:1; }","duration":"301.316061ms","start":"2026-04-21T14:42:59.854014Z","end":"2026-04-21T14:43:00.15533Z","steps":["trace[1750728032] 'process raft request' (duration: 301.232179ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T14:43:00.155339Z","caller":"traceutil/trace.go:171","msg":"trace[514337321] transaction","detail":"{read_only:false; response_revision:2138; number_of_response:1; }","duration":"562.312193ms","start":"2026-04-21T14:42:59.592984Z","end":"2026-04-21T14:43:00.155297Z","steps":["trace[514337321] 'process raft request' (duration: 99.447788ms)","trace[514337321] 'compare' (duration: 462.383123ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T14:43:00.155421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T14:42:59.853991Z","time spent":"301.399023ms","remote":"127.0.0.1:54530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T14:43:00.155556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T14:42:59.592963Z","time spent":"562.475748ms","remote":"127.0.0.1:54530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T14:43:00.155392Z","caller":"traceutil/trace.go:171","msg":"trace[409276695] linearizableReadLoop","detail":"{readStateIndex:2219; appliedIndex:2218; }","duration":"535.382675ms","start":"2026-04-21T14:42:59.619983Z","end":"2026-04-21T14:43:00.155366Z","steps":["trace[409276695] 'read index received' (duration: 72.380756ms)","trace[409276695] 'applied index is now lower than readState.Index' (duration: 462.999729ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T14:43:00.155903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.923639ms","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-21T14:43:00.155965Z","caller":"traceutil/trace.go:171","msg":"trace[1399916774] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2139; }","duration":"536.027252ms","start":"2026-04-21T14:42:59.619921Z","end":"2026-04-21T14:43:00.155949Z","steps":["trace[1399916774] 'agreement among raft nodes before linearized reading' (duration: 535.831187ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T14:43:00.156047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T14:42:59.619899Z","time spent":"536.140625ms","remote":"127.0.0.1:54530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-21T14:43:00.156059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.64874ms","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-21T14:43:00.156201Z","caller":"traceutil/trace.go:171","msg":"trace[579183698] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2139; }","duration":"522.807924ms","start":"2026-04-21T14:42:59.633355Z","end":"2026-04-21T14:43:00.156163Z","steps":["trace[579183698] 'agreement among raft nodes before linearized reading' (duration: 522.483945ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T14:43:00.156373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T14:42:59.633341Z","time spent":"523.004059ms","remote":"127.0.0.1:54530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-21T14:43:00.15804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.315339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.235\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-21T14:43:00.158153Z","caller":"traceutil/trace.go:171","msg":"trace[1978888868] range","detail":"{range_begin:/registry/masterleases/199.204.45.235; range_end:; response_count:1; response_revision:2139; }","duration":"236.451303ms","start":"2026-04-21T14:42:59.921689Z","end":"2026-04-21T14:43:00.15814Z","steps":["trace[1978888868] 'agreement among raft nodes before linearized reading' (duration: 234.872952ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T14:43:00.364054Z","caller":"traceutil/trace.go:171","msg":"trace[1543457404] transaction","detail":"{read_only:false; response_revision:2140; number_of_response:1; }","duration":"202.438558ms","start":"2026-04-21T14:43:00.161592Z","end":"2026-04-21T14:43:00.36403Z","steps":["trace[1543457404] 'process raft request' (duration: 202.14602ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T14:43:00.364336Z","caller":"traceutil/trace.go:171","msg":"trace[1996698457] transaction","detail":"{read_only:false; response_revision:2141; number_of_response:1; }","duration":"202.623353ms","start":"2026-04-21T14:43:00.161676Z","end":"2026-04-21T14:43:00.3643Z","steps":["trace[1996698457] 'process raft request' (duration: 202.313745ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T14:43:00.36631Z","caller":"traceutil/trace.go:171","msg":"trace[231291404] transaction","detail":"{read_only:false; response_revision:2143; number_of_response:1; }","duration":"155.778398ms","start":"2026-04-21T14:43:00.210493Z","end":"2026-04-21T14:43:00.366271Z","steps":["trace[231291404] 'process raft request' (duration: 155.49268ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T14:43:00.367284Z","caller":"traceutil/trace.go:171","msg":"trace[962115506] transaction","detail":"{read_only:false; response_revision:2142; number_of_response:1; }","duration":"170.011683ms","start":"2026-04-21T14:43:00.197044Z","end":"2026-04-21T14:43:00.367056Z","steps":["trace[962115506] 'process raft request' (duration: 168.803862ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T14:43:57.316045Z","caller":"traceutil/trace.go:171","msg":"trace[1758317547] transaction","detail":"{read_only:false; response_revision:2556; number_of_response:1; }","duration":"229.970485ms","start":"2026-04-21T14:43:57.086042Z","end":"2026-04-21T14:43:57.316012Z","steps":["trace[1758317547] 'process raft request' (duration: 214.257841ms)","trace[1758317547] 'compare' (duration: 14.981724ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T14:44:30.105674Z","caller":"traceutil/trace.go:171","msg":"trace[1815374359] linearizableReadLoop","detail":"{readStateIndex:2838; appliedIndex:2837; }","duration":"130.909537ms","start":"2026-04-21T14:44:29.97474Z","end":"2026-04-21T14:44:30.105649Z","steps":["trace[1815374359] 'read index received' (duration: 120.813445ms)","trace[1815374359] 'applied index is now lower than readState.Index' (duration: 10.094882ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T14:44:30.105768Z","caller":"traceutil/trace.go:171","msg":"trace[397724701] transaction","detail":"{read_only:false; response_revision:2735; number_of_response:1; }","duration":"148.412956ms","start":"2026-04-21T14:44:29.957328Z","end":"2026-04-21T14:44:30.105741Z","steps":["trace[397724701] 'process raft request' (duration: 138.345444ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T14:44:30.105939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.149443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-04-21T14:44:30.106236Z","caller":"traceutil/trace.go:171","msg":"trace[1926706835] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:2735; }","duration":"131.523134ms","start":"2026-04-21T14:44:29.974692Z","end":"2026-04-21T14:44:30.106215Z","steps":["trace[1926706835] 'agreement among raft nodes before linearized reading' (duration: 131.055371ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T14:44:30.463531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.478492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T14:44:30.46363Z","caller":"traceutil/trace.go:171","msg":"trace[2072672728] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2735; }","duration":"199.616036ms","start":"2026-04-21T14:44:30.263995Z","end":"2026-04-21T14:44:30.463611Z","steps":["trace[2072672728] 'range keys from in-memory index tree' (duration: 199.40998ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T14:44:51.243792Z","caller":"traceutil/trace.go:171","msg":"trace[291392109] transaction","detail":"{read_only:false; response_revision:2847; number_of_response:1; }","duration":"115.77945ms","start":"2026-04-21T14:44:51.127991Z","end":"2026-04-21T14:44:51.243771Z","steps":["trace[291392109] 'process raft request' (duration: 115.658916ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T14:48:11.988699Z","caller":"traceutil/trace.go:171","msg":"trace[1063902393] linearizableReadLoop","detail":"{readStateIndex:4603; appliedIndex:4602; }","duration":"189.58209ms","start":"2026-04-21T14:48:11.799089Z","end":"2026-04-21T14:48:11.988671Z","steps":["trace[1063902393] 'read index received' (duration: 188.902482ms)","trace[1063902393] 'applied index is now lower than readState.Index' (duration: 678.708µs)"],"step_count":2} {"level":"info","ts":"2026-04-21T14:48:11.988786Z","caller":"traceutil/trace.go:171","msg":"trace[1307285129] transaction","detail":"{read_only:false; response_revision:4430; number_of_response:1; }","duration":"207.356844ms","start":"2026-04-21T14:48:11.781422Z","end":"2026-04-21T14:48:11.988779Z","steps":["trace[1307285129] 'process raft request' (duration: 206.618434ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T14:48:11.988989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.887849ms","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-21T14:48:11.98902Z","caller":"traceutil/trace.go:171","msg":"trace[2023998348] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4430; }","duration":"189.971222ms","start":"2026-04-21T14:48:11.799042Z","end":"2026-04-21T14:48:11.989013Z","steps":["trace[2023998348] 'agreement among raft nodes before linearized reading' (duration: 189.837707ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T14:48:18.229532Z","caller":"traceutil/trace.go:171","msg":"trace[2076338207] transaction","detail":"{read_only:false; response_revision:4574; number_of_response:1; }","duration":"103.007892ms","start":"2026-04-21T14:48:18.126508Z","end":"2026-04-21T14:48:18.229516Z","steps":["trace[2076338207] 'process raft request' (duration: 102.812916ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T14:48:18.338009Z","caller":"traceutil/trace.go:171","msg":"trace[1484673489] transaction","detail":"{read_only:false; response_revision:4576; number_of_response:1; }","duration":"104.380069ms","start":"2026-04-21T14:48:18.233599Z","end":"2026-04-21T14:48:18.337979Z","steps":["trace[1484673489] 'process raft request' (duration: 68.484961ms)","trace[1484673489] 'compare' (duration: 35.743243ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T14:48:19.196773Z","caller":"traceutil/trace.go:171","msg":"trace[2029930448] transaction","detail":"{read_only:false; response_revision:4605; number_of_response:1; }","duration":"122.146193ms","start":"2026-04-21T14:48:19.074579Z","end":"2026-04-21T14:48:19.196725Z","steps":["trace[2029930448] 'process raft request' (duration: 121.107174ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T14:48:24.519698Z","caller":"traceutil/trace.go:171","msg":"trace[5167107] linearizableReadLoop","detail":"{readStateIndex:4851; appliedIndex:4849; }","duration":"142.803729ms","start":"2026-04-21T14:48:24.376852Z","end":"2026-04-21T14:48:24.519656Z","steps":["trace[5167107] 'read index received' (duration: 69.958915ms)","trace[5167107] 'applied index is now lower than readState.Index' (duration: 72.843524ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T14:48:24.519745Z","caller":"traceutil/trace.go:171","msg":"trace[952751275] transaction","detail":"{read_only:false; response_revision:4672; number_of_response:1; }","duration":"151.036048ms","start":"2026-04-21T14:48:24.368678Z","end":"2026-04-21T14:48:24.519714Z","steps":["trace[952751275] 'process raft request' (duration: 150.80098ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T14:48:24.519921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.042306ms","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-21T14:48:24.519962Z","caller":"traceutil/trace.go:171","msg":"trace[649887179] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4672; }","duration":"143.126888ms","start":"2026-04-21T14:48:24.376825Z","end":"2026-04-21T14:48:24.519952Z","steps":["trace[649887179] 'agreement among raft nodes before linearized reading' (duration: 142.959494ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T14:48:26.439875Z","caller":"traceutil/trace.go:171","msg":"trace[1608065076] transaction","detail":"{read_only:false; response_revision:4727; number_of_response:1; }","duration":"149.111795ms","start":"2026-04-21T14:48:26.290743Z","end":"2026-04-21T14:48:26.439855Z","steps":["trace[1608065076] 'process raft request' (duration: 87.608705ms)","trace[1608065076] 'compare' (duration: 61.420378ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T14:48:26.466473Z","caller":"traceutil/trace.go:171","msg":"trace[1160585732] linearizableReadLoop","detail":"{readStateIndex:4908; appliedIndex:4906; }","duration":"160.818331ms","start":"2026-04-21T14:48:26.305631Z","end":"2026-04-21T14:48:26.46645Z","steps":["trace[1160585732] 'read index received' (duration: 72.838615ms)","trace[1160585732] 'applied index is now lower than readState.Index' (duration: 87.978696ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T14:48:26.466599Z","caller":"traceutil/trace.go:171","msg":"trace[1853718746] transaction","detail":"{read_only:false; response_revision:4728; number_of_response:1; }","duration":"161.207983ms","start":"2026-04-21T14:48:26.305373Z","end":"2026-04-21T14:48:26.466581Z","steps":["trace[1853718746] 'process raft request' (duration: 160.872982ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T14:48:26.466528Z","caller":"traceutil/trace.go:171","msg":"trace[1710091104] transaction","detail":"{read_only:false; response_revision:4729; number_of_response:1; }","duration":"152.354155ms","start":"2026-04-21T14:48:26.314136Z","end":"2026-04-21T14:48:26.46649Z","steps":["trace[1710091104] 'process raft request' (duration: 152.238852ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T14:48:26.466903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.256334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9639"} {"level":"info","ts":"2026-04-21T14:48:26.466957Z","caller":"traceutil/trace.go:171","msg":"trace[1085356896] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:4729; }","duration":"161.353287ms","start":"2026-04-21T14:48:26.305592Z","end":"2026-04-21T14:48:26.466946Z","steps":["trace[1085356896] 'agreement among raft nodes before linearized reading' (duration: 161.006507ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T14:48:27.80679Z","caller":"traceutil/trace.go:171","msg":"trace[891262160] transaction","detail":"{read_only:false; response_revision:4746; number_of_response:1; }","duration":"333.929905ms","start":"2026-04-21T14:48:27.472838Z","end":"2026-04-21T14:48:27.806768Z","steps":["trace[891262160] 'process raft request' (duration: 333.76163ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T14:48:27.806937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T14:48:27.472809Z","time spent":"334.051918ms","remote":"127.0.0.1:54530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T14:48:27.84729Z","caller":"traceutil/trace.go:171","msg":"trace[1058739544] transaction","detail":"{read_only:false; response_revision:4747; number_of_response:1; }","duration":"189.577381ms","start":"2026-04-21T14:48:27.657685Z","end":"2026-04-21T14:48:27.847262Z","steps":["trace[1058739544] 'process raft request' (duration: 158.061475ms)","trace[1058739544] 'compare' (duration: 31.398452ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T14:48:28.345975Z","caller":"traceutil/trace.go:171","msg":"trace[187178780] transaction","detail":"{read_only:false; response_revision:4753; number_of_response:1; }","duration":"187.481964ms","start":"2026-04-21T14:48:28.158476Z","end":"2026-04-21T14:48:28.345958Z","steps":["trace[187178780] 'process raft request' (duration: 135.91438ms)","trace[187178780] 'compare' (duration: 51.094081ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T14:48:28.346205Z","caller":"traceutil/trace.go:171","msg":"trace[1437975403] linearizableReadLoop","detail":"{readStateIndex:4933; appliedIndex:4932; }","duration":"131.641281ms","start":"2026-04-21T14:48:28.214515Z","end":"2026-04-21T14:48:28.346156Z","steps":["trace[1437975403] 'read index received' (duration: 79.821691ms)","trace[1437975403] 'applied index is now lower than readState.Index' (duration: 51.8173ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T14:48:28.346395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.859218ms","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-21T14:48:28.346487Z","caller":"traceutil/trace.go:171","msg":"trace[180921319] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4754; }","duration":"132.012342ms","start":"2026-04-21T14:48:28.21446Z","end":"2026-04-21T14:48:28.346473Z","steps":["trace[180921319] 'agreement among raft nodes before linearized reading' (duration: 131.787865ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T14:48:28.531428Z","caller":"traceutil/trace.go:171","msg":"trace[540756923] transaction","detail":"{read_only:false; response_revision:4758; number_of_response:1; }","duration":"130.343885ms","start":"2026-04-21T14:48:28.401046Z","end":"2026-04-21T14:48:28.53139Z","steps":["trace[540756923] 'process raft request' (duration: 62.013574ms)","trace[540756923] 'compare' (duration: 68.084923ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T14:48:39.694822Z","caller":"traceutil/trace.go:171","msg":"trace[1456304252] transaction","detail":"{read_only:false; response_revision:4901; number_of_response:1; }","duration":"101.062005ms","start":"2026-04-21T14:48:39.593698Z","end":"2026-04-21T14:48:39.69476Z","steps":["trace[1456304252] 'process raft request' (duration: 100.432406ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T14:48:42.793693Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920880421774524246,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-21T14:48:42.892513Z","caller":"traceutil/trace.go:171","msg":"trace[1610173614] transaction","detail":"{read_only:false; response_revision:4926; number_of_response:1; }","duration":"627.535226ms","start":"2026-04-21T14:48:42.26496Z","end":"2026-04-21T14:48:42.892495Z","steps":["trace[1610173614] 'process raft request' (duration: 627.361661ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T14:48:42.892686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T14:48:42.264948Z","time spent":"627.639769ms","remote":"127.0.0.1:54464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10670,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T14:48:42.903597Z","caller":"traceutil/trace.go:171","msg":"trace[1608104953] linearizableReadLoop","detail":"{readStateIndex:5115; appliedIndex:5112; }","duration":"610.156463ms","start":"2026-04-21T14:48:42.293429Z","end":"2026-04-21T14:48:42.903585Z","steps":["trace[1608104953] 'read index received' (duration: 598.861258ms)","trace[1608104953] 'applied index is now lower than readState.Index' (duration: 11.294555ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T14:48:42.903764Z","caller":"traceutil/trace.go:171","msg":"trace[1130877889] transaction","detail":"{read_only:false; response_revision:4928; number_of_response:1; }","duration":"468.311932ms","start":"2026-04-21T14:48:42.435418Z","end":"2026-04-21T14:48:42.90373Z","steps":["trace[1130877889] 'process raft request' (duration: 468.140897ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T14:48:42.903855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"610.43113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-04-21T14:48:42.903897Z","caller":"traceutil/trace.go:171","msg":"trace[1791190997] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4928; }","duration":"610.490482ms","start":"2026-04-21T14:48:42.293388Z","end":"2026-04-21T14:48:42.903878Z","steps":["trace[1791190997] 'agreement among raft nodes before linearized reading' (duration: 610.391819ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T14:48:42.903921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T14:48:42.293375Z","time spent":"610.540393ms","remote":"127.0.0.1:54450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1886,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-04-21T14:48:42.904015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T14:48:42.43539Z","time spent":"468.450356ms","remote":"127.0.0.1:54530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T14:48:42.90414Z","caller":"traceutil/trace.go:171","msg":"trace[1863199141] transaction","detail":"{read_only:false; response_revision:4927; number_of_response:1; }","duration":"571.515217ms","start":"2026-04-21T14:48:42.332616Z","end":"2026-04-21T14:48:42.904131Z","steps":["trace[1863199141] 'process raft request' (duration: 570.865839ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T14:48:42.904203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T14:48:42.332602Z","time spent":"571.564038ms","remote":"127.0.0.1:54530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T14:48:42.979673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.814957ms","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-21T14:48:42.979731Z","caller":"traceutil/trace.go:171","msg":"trace[454408440] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4932; }","duration":"207.921179ms","start":"2026-04-21T14:48:42.771798Z","end":"2026-04-21T14:48:42.97972Z","steps":["trace[454408440] 'agreement among raft nodes before linearized reading' (duration: 207.788316ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T14:48:42.979865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.773592ms","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-21T14:48:42.979888Z","caller":"traceutil/trace.go:171","msg":"trace[401846772] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4932; }","duration":"274.825993ms","start":"2026-04-21T14:48:42.705057Z","end":"2026-04-21T14:48:42.979883Z","steps":["trace[401846772] 'agreement among raft nodes before linearized reading' (duration: 274.768921ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T14:48:42.980003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.185542ms","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-21T14:48:42.980025Z","caller":"traceutil/trace.go:171","msg":"trace[700704821] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4932; }","duration":"294.236154ms","start":"2026-04-21T14:48:42.685784Z","end":"2026-04-21T14:48:42.98002Z","steps":["trace[700704821] 'agreement among raft nodes before linearized reading' (duration: 294.179862ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T14:48:42.980588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"536.202432ms","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-21T14:48:42.980616Z","caller":"traceutil/trace.go:171","msg":"trace[385877887] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4932; }","duration":"536.268374ms","start":"2026-04-21T14:48:42.444341Z","end":"2026-04-21T14:48:42.98061Z","steps":["trace[385877887] 'agreement among raft nodes before linearized reading' (duration: 536.200622ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T14:48:42.980635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T14:48:42.444327Z","time spent":"536.303275ms","remote":"127.0.0.1:54530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-21T14:48:43.434331Z","caller":"traceutil/trace.go:171","msg":"trace[2116789792] transaction","detail":"{read_only:false; response_revision:4942; number_of_response:1; }","duration":"123.988433ms","start":"2026-04-21T14:48:43.310311Z","end":"2026-04-21T14:48:43.434299Z","steps":["trace[2116789792] 'process raft request' (duration: 123.760907ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T14:50:06.838857Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2926} {"level":"info","ts":"2026-04-21T14:50:06.927326Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2926,"took":"87.473551ms","hash":1997570939,"current-db-size-bytes":54542336,"current-db-size":"54 MB","current-db-size-in-use-bytes":29491200,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-04-21T14:50:06.927386Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1997570939,"revision":2926,"compact-revision":-1} {"level":"info","ts":"2026-04-21T14:52:33.115096Z","caller":"traceutil/trace.go:171","msg":"trace[815933909] linearizableReadLoop","detail":"{readStateIndex:6837; appliedIndex:6835; }","duration":"279.175708ms","start":"2026-04-21T14:52:32.835891Z","end":"2026-04-21T14:52:33.115067Z","steps":["trace[815933909] 'read index received' (duration: 20.308882ms)","trace[815933909] 'applied index is now lower than readState.Index' (duration: 258.865816ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T14:52:33.115159Z","caller":"traceutil/trace.go:171","msg":"trace[1197797003] transaction","detail":"{read_only:false; response_revision:6594; number_of_response:1; }","duration":"318.134704ms","start":"2026-04-21T14:52:32.796994Z","end":"2026-04-21T14:52:33.115129Z","steps":["trace[1197797003] 'process raft request' (duration: 257.817265ms)","trace[1197797003] 'compare' (duration: 60.145354ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T14:52:33.115405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.501296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-04-21T14:52:33.115453Z","caller":"traceutil/trace.go:171","msg":"trace[2541061] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:6594; }","duration":"279.605109ms","start":"2026-04-21T14:52:32.835834Z","end":"2026-04-21T14:52:33.115439Z","steps":["trace[2541061] 'agreement among raft nodes before linearized reading' (duration: 279.387963ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T14:52:33.115534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T14:52:32.796964Z","time spent":"318.397821ms","remote":"127.0.0.1:54530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T14:55:06.850122Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5622} {"level":"info","ts":"2026-04-21T14:55:06.938238Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5622,"took":"86.846329ms","hash":3220151207,"current-db-size-bytes":54542336,"current-db-size":"54 MB","current-db-size-in-use-bytes":18382848,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-04-21T14:55:06.938308Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3220151207,"revision":5622,"compact-revision":2926}