{"level":"warn","ts":"2026-05-21T08:54:55.888456Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-21T08:54:55.889007Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.95:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.95:2380","--initial-cluster=instance=https://199.19.213.95:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.95:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.95:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-21T08:54:55.889121Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-21T08:54:55.889142Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.95:2380"]} {"level":"info","ts":"2026-05-21T08:54:55.889167Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-21T08:54:55.893445Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.95:2379"]} {"level":"info","ts":"2026-05-21T08:54:55.893606Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.95:2380"],"listen-peer-urls":["https://199.19.213.95:2380"],"advertise-client-urls":["https://199.19.213.95:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.95:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.95:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-21T08:54:55.899541Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.640503ms"} {"level":"info","ts":"2026-05-21T08:54:55.929335Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"371c7c2e52231793","cluster-id":"c115742627eabb7c"} {"level":"info","ts":"2026-05-21T08:54:55.929466Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"371c7c2e52231793 switched to configuration voters=()"} {"level":"info","ts":"2026-05-21T08:54:55.929496Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"371c7c2e52231793 became follower at term 0"} {"level":"info","ts":"2026-05-21T08:54:55.929519Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 371c7c2e52231793 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-21T08:54:55.929535Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"371c7c2e52231793 became follower at term 1"} {"level":"info","ts":"2026-05-21T08:54:55.929623Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"371c7c2e52231793 switched to configuration voters=(3971185509822306195)"} {"level":"warn","ts":"2026-05-21T08:54:55.940692Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-21T08:54:55.942265Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-21T08:54:55.943563Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-21T08:54:55.946972Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"371c7c2e52231793","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-21T08:54:55.947237Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"371c7c2e52231793","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-21T08:54:55.947489Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-21T08:54:55.947691Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-21T08:54:55.94775Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-21T08:54:55.951522Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"371c7c2e52231793 switched to configuration voters=(3971185509822306195)"} {"level":"info","ts":"2026-05-21T08:54:55.952037Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c115742627eabb7c","local-member-id":"371c7c2e52231793","added-peer-id":"371c7c2e52231793","added-peer-peer-urls":["https://199.19.213.95:2380"]} {"level":"info","ts":"2026-05-21T08:54:55.955181Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-21T08:54:55.955311Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.95:2380"} {"level":"info","ts":"2026-05-21T08:54:55.955407Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.95:2380"} {"level":"info","ts":"2026-05-21T08:54:55.955723Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"371c7c2e52231793","initial-advertise-peer-urls":["https://199.19.213.95:2380"],"listen-peer-urls":["https://199.19.213.95:2380"],"advertise-client-urls":["https://199.19.213.95:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.95:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-21T08:54:55.955827Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-21T08:54:56.13224Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"371c7c2e52231793 is starting a new election at term 1"} {"level":"info","ts":"2026-05-21T08:54:56.132324Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"371c7c2e52231793 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-21T08:54:56.132347Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"371c7c2e52231793 received MsgPreVoteResp from 371c7c2e52231793 at term 1"} {"level":"info","ts":"2026-05-21T08:54:56.132365Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"371c7c2e52231793 became candidate at term 2"} {"level":"info","ts":"2026-05-21T08:54:56.13239Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"371c7c2e52231793 received MsgVoteResp from 371c7c2e52231793 at term 2"} {"level":"info","ts":"2026-05-21T08:54:56.132403Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"371c7c2e52231793 became leader at term 2"} {"level":"info","ts":"2026-05-21T08:54:56.132413Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 371c7c2e52231793 elected leader 371c7c2e52231793 at term 2"} {"level":"info","ts":"2026-05-21T08:54:56.14046Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"371c7c2e52231793","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.95:2379]}","request-path":"/0/members/371c7c2e52231793/attributes","cluster-id":"c115742627eabb7c","publish-timeout":"7s"} {"level":"info","ts":"2026-05-21T08:54:56.140612Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-21T08:54:56.140794Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-21T08:54:56.140799Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-21T08:54:56.141289Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-21T08:54:56.141337Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-21T08:54:56.142378Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c115742627eabb7c","local-member-id":"371c7c2e52231793","cluster-version":"3.5"} {"level":"info","ts":"2026-05-21T08:54:56.142536Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-21T08:54:56.142573Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-21T08:54:56.149067Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.95:2379"} {"level":"info","ts":"2026-05-21T08:54:56.14924Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-05-21T08:54:58.750769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"591.365191ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-05-21T08:54:58.750962Z","caller":"traceutil/trace.go:171","msg":"trace[1670613822] transaction","detail":"{read_only:false; response_revision:51; number_of_response:1; }","duration":"794.359226ms","start":"2026-05-21T08:54:57.956573Z","end":"2026-05-21T08:54:58.750933Z","steps":["trace[1670613822] 'process raft request' (duration: 202.165514ms)","trace[1670613822] 'compare' (duration: 591.216867ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T08:54:58.75107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:54:57.956551Z","time spent":"794.477599ms","remote":"127.0.0.1:36848","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":671,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-21T08:54:59.837383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"701.921759ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-05-21T08:54:59.83757Z","caller":"traceutil/trace.go:171","msg":"trace[290902718] transaction","detail":"{read_only:false; response_revision:53; number_of_response:1; }","duration":"1.082421383s","start":"2026-05-21T08:54:58.755131Z","end":"2026-05-21T08:54:59.837552Z","steps":["trace[290902718] 'process raft request' (duration: 1.082365412s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:54:59.837633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:54:58.75511Z","time spent":"1.082495496s","remote":"127.0.0.1:36848","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":702,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T08:54:59.837781Z","caller":"traceutil/trace.go:171","msg":"trace[257575953] transaction","detail":"{read_only:false; response_revision:52; number_of_response:1; }","duration":"1.878890994s","start":"2026-05-21T08:54:57.958877Z","end":"2026-05-21T08:54:59.837768Z","steps":["trace[257575953] 'process raft request' (duration: 1.176129763s)","trace[257575953] 'compare' (duration: 701.428247ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T08:54:59.837837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:54:57.958841Z","time spent":"1.878966336s","remote":"127.0.0.1:36848","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1116,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T08:54:59.837845Z","caller":"traceutil/trace.go:171","msg":"trace[701835997] linearizableReadLoop","detail":"{readStateIndex:56; appliedIndex:54; }","duration":"1.839737899s","start":"2026-05-21T08:54:57.998022Z","end":"2026-05-21T08:54:59.83776Z","steps":["trace[701835997] 'read index received' (duration: 160.790004ms)","trace[701835997] 'applied index is now lower than readState.Index' (duration: 1.678938355s)"],"step_count":2} {"level":"warn","ts":"2026-05-21T08:54:59.838148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.840132579s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-21T08:54:59.838189Z","caller":"traceutil/trace.go:171","msg":"trace[1716607864] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:0; response_revision:53; }","duration":"1.840227711s","start":"2026-05-21T08:54:57.997947Z","end":"2026-05-21T08:54:59.838175Z","steps":["trace[1716607864] 'agreement among raft nodes before linearized reading' (duration: 1.840051566s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:54:59.83822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:54:57.997913Z","time spent":"1.840299674s","remote":"127.0.0.1:36690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" "} {"level":"warn","ts":"2026-05-21T08:54:59.838233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.824896262s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-21T08:54:59.838283Z","caller":"traceutil/trace.go:171","msg":"trace[980521060] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:53; }","duration":"1.825027346s","start":"2026-05-21T08:54:58.013238Z","end":"2026-05-21T08:54:59.838265Z","steps":["trace[980521060] 'agreement among raft nodes before linearized reading' (duration: 1.82480753s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:54:59.838342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:54:58.013207Z","time spent":"1.825124869s","remote":"127.0.0.1:36554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":27,"request content":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" "} {"level":"warn","ts":"2026-05-21T08:54:59.838346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.114265223s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-21T08:54:59.838402Z","caller":"traceutil/trace.go:171","msg":"trace[1143777694] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:53; }","duration":"1.114362385s","start":"2026-05-21T08:54:58.724023Z","end":"2026-05-21T08:54:59.838386Z","steps":["trace[1143777694] 'agreement among raft nodes before linearized reading' (duration: 1.114242132s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:54:59.838438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:54:58.723977Z","time spent":"1.114452217s","remote":"127.0.0.1:36744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" "} {"level":"warn","ts":"2026-05-21T08:54:59.838497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.095727343s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-21T08:54:59.838552Z","caller":"traceutil/trace.go:171","msg":"trace[1604260951] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:53; }","duration":"1.095763663s","start":"2026-05-21T08:54:58.74276Z","end":"2026-05-21T08:54:59.838524Z","steps":["trace[1604260951] 'agreement among raft nodes before linearized reading' (duration: 1.095712272s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:54:59.83861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:54:58.742726Z","time spent":"1.095868686s","remote":"127.0.0.1:59424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-21T08:54:59.838684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.11497219s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-21T08:54:59.838701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.760714633s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-21T08:54:59.838727Z","caller":"traceutil/trace.go:171","msg":"trace[633540024] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:53; }","duration":"1.115127724s","start":"2026-05-21T08:54:58.723583Z","end":"2026-05-21T08:54:59.83871Z","steps":["trace[633540024] 'agreement among raft nodes before linearized reading' (duration: 1.115052422s)"],"step_count":1} {"level":"info","ts":"2026-05-21T08:54:59.838737Z","caller":"traceutil/trace.go:171","msg":"trace[1339281586] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:53; }","duration":"1.760816136s","start":"2026-05-21T08:54:58.077911Z","end":"2026-05-21T08:54:59.838727Z","steps":["trace[1339281586] 'agreement among raft nodes before linearized reading' (duration: 1.760764784s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:54:59.838768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:54:58.72355Z","time spent":"1.115206636s","remote":"127.0.0.1:36762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":27,"request content":"key:\"/registry/priorityclasses/system-node-critical\" "} {"level":"warn","ts":"2026-05-21T08:54:59.83877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:54:58.077878Z","time spent":"1.760885678s","remote":"127.0.0.1:36798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":27,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-05-21T08:55:00.285333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.64262ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-05-21T08:55:00.285684Z","caller":"traceutil/trace.go:171","msg":"trace[146812136] linearizableReadLoop","detail":"{readStateIndex:60; appliedIndex:57; }","duration":"441.904285ms","start":"2026-05-21T08:54:59.843762Z","end":"2026-05-21T08:55:00.285666Z","steps":["trace[146812136] 'read index received' (duration: 295.859785ms)","trace[146812136] 'applied index is now lower than readState.Index' (duration: 146.04363ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T08:55:00.285845Z","caller":"traceutil/trace.go:171","msg":"trace[1072554192] transaction","detail":"{read_only:false; response_revision:55; number_of_response:1; }","duration":"442.923851ms","start":"2026-05-21T08:54:59.842855Z","end":"2026-05-21T08:55:00.285778Z","steps":["trace[1072554192] 'process raft request' (duration: 442.604703ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T08:55:00.285982Z","caller":"traceutil/trace.go:171","msg":"trace[457618889] transaction","detail":"{read_only:false; response_revision:56; number_of_response:1; }","duration":"442.819088ms","start":"2026-05-21T08:54:59.843139Z","end":"2026-05-21T08:55:00.285958Z","steps":["trace[457618889] 'process raft request' (duration: 442.373267ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:55:00.286041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:54:59.842834Z","time spent":"443.114076ms","remote":"127.0.0.1:36606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3453,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-21T08:55:00.286092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:54:59.843126Z","time spent":"442.89898ms","remote":"127.0.0.1:36690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-21T08:55:00.286115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.339616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-21T08:55:00.286105Z","caller":"traceutil/trace.go:171","msg":"trace[1328609515] transaction","detail":"{read_only:false; response_revision:59; number_of_response:1; }","duration":"439.528095ms","start":"2026-05-21T08:54:59.84654Z","end":"2026-05-21T08:55:00.286068Z","steps":["trace[1328609515] 'process raft request' (duration: 439.097184ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T08:55:00.286254Z","caller":"traceutil/trace.go:171","msg":"trace[195884349] transaction","detail":"{read_only:false; response_revision:58; number_of_response:1; }","duration":"441.32154ms","start":"2026-05-21T08:54:59.844901Z","end":"2026-05-21T08:55:00.286222Z","steps":["trace[195884349] 'process raft request' (duration: 440.693394ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:55:00.286262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:54:59.846532Z","time spent":"439.655268ms","remote":"127.0.0.1:36798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":651,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T08:55:00.286316Z","caller":"traceutil/trace.go:171","msg":"trace[1512947540] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:59; }","duration":"442.407718ms","start":"2026-05-21T08:54:59.843737Z","end":"2026-05-21T08:55:00.286145Z","steps":["trace[1512947540] 'agreement among raft nodes before linearized reading' (duration: 442.337946ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:55:00.286344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:54:59.844891Z","time spent":"441.391521ms","remote":"127.0.0.1:36848","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1199,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T08:55:00.286349Z","caller":"traceutil/trace.go:171","msg":"trace[1527784691] transaction","detail":"{read_only:false; response_revision:57; number_of_response:1; }","duration":"441.73261ms","start":"2026-05-21T08:54:59.844606Z","end":"2026-05-21T08:55:00.286339Z","steps":["trace[1527784691] 'process raft request' (duration: 440.94123ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:55:00.286363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:54:59.843727Z","time spent":"442.625123ms","remote":"127.0.0.1:36760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":27,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" "} {"level":"warn","ts":"2026-05-21T08:55:00.286404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:54:59.844588Z","time spent":"441.797302ms","remote":"127.0.0.1:36848","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":671,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-21T08:55:00.286563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.316302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-21T08:55:00.286621Z","caller":"traceutil/trace.go:171","msg":"trace[1639376524] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:59; }","duration":"393.512066ms","start":"2026-05-21T08:54:59.893091Z","end":"2026-05-21T08:55:00.286603Z","steps":["trace[1639376524] 'agreement among raft nodes before linearized reading' (duration: 393.379733ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:55:00.286653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:54:59.893067Z","time spent":"393.578498ms","remote":"127.0.0.1:59424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-21T08:55:00.286537Z","caller":"traceutil/trace.go:171","msg":"trace[1582687124] transaction","detail":"{read_only:false; response_revision:54; number_of_response:1; }","duration":"443.948327ms","start":"2026-05-21T08:54:59.842567Z","end":"2026-05-21T08:55:00.286515Z","steps":["trace[1582687124] 'process raft request' (duration: 297.039545ms)","trace[1582687124] 'compare' (duration: 145.514726ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T08:55:00.286805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:54:59.842549Z","time spent":"444.221274ms","remote":"127.0.0.1:36762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":429,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T08:55:00.56936Z","caller":"traceutil/trace.go:171","msg":"trace[480411601] transaction","detail":"{read_only:false; response_revision:63; number_of_response:1; }","duration":"236.089256ms","start":"2026-05-21T08:55:00.333215Z","end":"2026-05-21T08:55:00.569304Z","steps":["trace[480411601] 'process raft request' (duration: 181.261534ms)","trace[480411601] 'compare' (duration: 54.503154ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T08:55:00.569467Z","caller":"traceutil/trace.go:171","msg":"trace[1803573957] transaction","detail":"{read_only:false; response_revision:65; number_of_response:1; }","duration":"122.529282ms","start":"2026-05-21T08:55:00.446901Z","end":"2026-05-21T08:55:00.56943Z","steps":["trace[1803573957] 'process raft request' (duration: 122.469411ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T08:55:00.569724Z","caller":"traceutil/trace.go:171","msg":"trace[414563588] linearizableReadLoop","detail":"{readStateIndex:67; appliedIndex:66; }","duration":"213.955495ms","start":"2026-05-21T08:55:00.355724Z","end":"2026-05-21T08:55:00.569679Z","steps":["trace[414563588] 'read index received' (duration: 158.872086ms)","trace[414563588] 'applied index is now lower than readState.Index' (duration: 55.076239ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T08:55:00.569955Z","caller":"traceutil/trace.go:171","msg":"trace[362920331] transaction","detail":"{read_only:false; response_revision:64; number_of_response:1; }","duration":"208.557598ms","start":"2026-05-21T08:55:00.361381Z","end":"2026-05-21T08:55:00.569938Z","steps":["trace[362920331] 'process raft request' (duration: 207.89922ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:55:00.569989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.909648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-view\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-21T08:55:00.570047Z","caller":"traceutil/trace.go:171","msg":"trace[1668185424] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-view; range_end:; response_count:0; response_revision:65; }","duration":"211.038491ms","start":"2026-05-21T08:55:00.358991Z","end":"2026-05-21T08:55:00.57003Z","steps":["trace[1668185424] 'agreement among raft nodes before linearized reading' (duration: 210.942299ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:55:00.57006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.037891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-21T08:55:00.570157Z","caller":"traceutil/trace.go:171","msg":"trace[1420810728] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:65; }","duration":"263.251547ms","start":"2026-05-21T08:55:00.306889Z","end":"2026-05-21T08:55:00.57014Z","steps":["trace[1420810728] 'agreement among raft nodes before linearized reading' (duration: 263.013681ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T08:55:18.258886Z","caller":"traceutil/trace.go:171","msg":"trace[1110527118] linearizableReadLoop","detail":"{readStateIndex:437; appliedIndex:436; }","duration":"102.822892ms","start":"2026-05-21T08:55:18.15604Z","end":"2026-05-21T08:55:18.258863Z","steps":["trace[1110527118] 'read index received' (duration: 102.621467ms)","trace[1110527118] 'applied index is now lower than readState.Index' (duration: 200.365µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T08:55:18.258983Z","caller":"traceutil/trace.go:171","msg":"trace[675885912] transaction","detail":"{read_only:false; response_revision:423; number_of_response:1; }","duration":"118.767436ms","start":"2026-05-21T08:55:18.140141Z","end":"2026-05-21T08:55:18.258908Z","steps":["trace[675885912] 'process raft request' (duration: 118.602622ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:55:18.259305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.209391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-05-21T08:55:18.259445Z","caller":"traceutil/trace.go:171","msg":"trace[1027839215] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:423; }","duration":"103.421187ms","start":"2026-05-21T08:55:18.155992Z","end":"2026-05-21T08:55:18.259413Z","steps":["trace[1027839215] 'agreement among raft nodes before linearized reading' (duration: 103.017327ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T08:55:18.433773Z","caller":"traceutil/trace.go:171","msg":"trace[1203530442] transaction","detail":"{read_only:false; response_revision:424; number_of_response:1; }","duration":"168.91548ms","start":"2026-05-21T08:55:18.264806Z","end":"2026-05-21T08:55:18.433722Z","steps":["trace[1203530442] 'process raft request' (duration: 95.831634ms)","trace[1203530442] 'compare' (duration: 72.86411ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T08:55:19.18758Z","caller":"traceutil/trace.go:171","msg":"trace[940568509] transaction","detail":"{read_only:false; response_revision:425; number_of_response:1; }","duration":"484.132457ms","start":"2026-05-21T08:55:18.703408Z","end":"2026-05-21T08:55:19.18754Z","steps":["trace[940568509] 'process raft request' (duration: 483.946542ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:55:19.187827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:55:18.703386Z","time spent":"484.289741ms","remote":"127.0.0.1:36690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T08:55:25.378561Z","caller":"traceutil/trace.go:171","msg":"trace[680070001] transaction","detail":"{read_only:false; response_revision:440; number_of_response:1; }","duration":"161.349249ms","start":"2026-05-21T08:55:25.217181Z","end":"2026-05-21T08:55:25.37853Z","steps":["trace[680070001] 'process raft request' (duration: 161.168403ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:55:43.562645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"697.776584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T08:55:43.562749Z","caller":"traceutil/trace.go:171","msg":"trace[1126089717] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:543; }","duration":"697.923258ms","start":"2026-05-21T08:55:42.864803Z","end":"2026-05-21T08:55:43.562726Z","steps":["trace[1126089717] 'range keys from in-memory index tree' (duration: 697.695862ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:55:43.562788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:55:42.864781Z","time spent":"697.997969ms","remote":"127.0.0.1:59424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-21T08:55:43.562684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"974.939264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-05-21T08:55:43.562647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"975.395966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T08:55:43.562856Z","caller":"traceutil/trace.go:171","msg":"trace[1419533273] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:543; }","duration":"975.145089ms","start":"2026-05-21T08:55:42.58769Z","end":"2026-05-21T08:55:43.562835Z","steps":["trace[1419533273] 'range keys from in-memory index tree' (duration: 974.829221ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:55:43.562913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:55:42.587664Z","time spent":"975.232561ms","remote":"127.0.0.1:36690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-21T08:55:43.56294Z","caller":"traceutil/trace.go:171","msg":"trace[110714205] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:543; }","duration":"975.716284ms","start":"2026-05-21T08:55:42.58718Z","end":"2026-05-21T08:55:43.562897Z","steps":["trace[110714205] 'range keys from in-memory index tree' (duration: 975.312333ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:55:43.563066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:55:42.587154Z","time spent":"975.856298ms","remote":"127.0.0.1:36502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" "} {"level":"info","ts":"2026-05-21T08:55:44.125863Z","caller":"traceutil/trace.go:171","msg":"trace[506433548] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"126.936785ms","start":"2026-05-21T08:55:43.998891Z","end":"2026-05-21T08:55:44.125828Z","steps":["trace[506433548] 'process raft request' (duration: 126.690639ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:55:44.531055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.146609ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T08:55:44.531375Z","caller":"traceutil/trace.go:171","msg":"trace[802994350] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"527.303564ms","start":"2026-05-21T08:55:44.004029Z","end":"2026-05-21T08:55:44.531332Z","steps":["trace[802994350] 'process raft request' (duration: 360.776265ms)","trace[802994350] 'compare' (duration: 165.953414ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T08:55:44.531618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:55:44.003999Z","time spent":"527.501409ms","remote":"127.0.0.1:36472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T08:55:44.532663Z","caller":"traceutil/trace.go:171","msg":"trace[971935030] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"450.087572ms","start":"2026-05-21T08:55:44.082549Z","end":"2026-05-21T08:55:44.532636Z","steps":["trace[971935030] 'process raft request' (duration: 448.773919ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:55:44.532778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:55:44.082484Z","time spent":"450.246447ms","remote":"127.0.0.1:36472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T08:55:44.5328Z","caller":"traceutil/trace.go:171","msg":"trace[647174188] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"395.28113ms","start":"2026-05-21T08:55:44.137499Z","end":"2026-05-21T08:55:44.53278Z","steps":["trace[647174188] 'process raft request' (duration: 394.86682ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:55:44.532905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:55:44.137473Z","time spent":"395.387043ms","remote":"127.0.0.1:36472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31102,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T08:55:45.066979Z","caller":"traceutil/trace.go:171","msg":"trace[1437226952] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"519.25775ms","start":"2026-05-21T08:55:44.547692Z","end":"2026-05-21T08:55:45.06695Z","steps":["trace[1437226952] 'process raft request' (duration: 519.152457ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T08:55:45.067144Z","caller":"traceutil/trace.go:171","msg":"trace[795067932] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"522.766648ms","start":"2026-05-21T08:55:44.544366Z","end":"2026-05-21T08:55:45.067133Z","steps":["trace[795067932] 'process raft request' (duration: 519.66918ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:55:45.068633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:55:44.547683Z","time spent":"519.368592ms","remote":"127.0.0.1:36472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31121,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T08:55:45.068874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:55:44.544353Z","time spent":"522.81063ms","remote":"127.0.0.1:36472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29461,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T08:55:45.072644Z","caller":"traceutil/trace.go:171","msg":"trace[704382664] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"475.804696ms","start":"2026-05-21T08:55:44.596781Z","end":"2026-05-21T08:55:45.072586Z","steps":["trace[704382664] 'process raft request' (duration: 475.55514ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:55:45.072879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:55:44.596757Z","time spent":"476.028911ms","remote":"127.0.0.1:36472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17062,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T08:55:45.078335Z","caller":"traceutil/trace.go:171","msg":"trace[2107460838] linearizableReadLoop","detail":"{readStateIndex:612; appliedIndex:608; }","duration":"212.433146ms","start":"2026-05-21T08:55:44.865847Z","end":"2026-05-21T08:55:45.078281Z","steps":["trace[2107460838] 'read index received' (duration: 198.184795ms)","trace[2107460838] 'applied index is now lower than readState.Index' (duration: 14.246661ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T08:55:45.078506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.638611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T08:55:45.078533Z","caller":"traceutil/trace.go:171","msg":"trace[1649911913] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:589; }","duration":"212.730763ms","start":"2026-05-21T08:55:44.865789Z","end":"2026-05-21T08:55:45.07852Z","steps":["trace[1649911913] 'agreement among raft nodes before linearized reading' (duration: 212.60325ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T08:55:45.078976Z","caller":"traceutil/trace.go:171","msg":"trace[755298177] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"471.056925ms","start":"2026-05-21T08:55:44.607902Z","end":"2026-05-21T08:55:45.078959Z","steps":["trace[755298177] 'process raft request' (duration: 464.633663ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:55:45.079047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:55:44.607872Z","time spent":"471.124556ms","remote":"127.0.0.1:36472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":150480,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T08:55:45.360289Z","caller":"traceutil/trace.go:171","msg":"trace[1567219448] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"272.06221ms","start":"2026-05-21T08:55:45.088198Z","end":"2026-05-21T08:55:45.36026Z","steps":["trace[1567219448] 'process raft request' (duration: 271.732872ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T08:55:45.360288Z","caller":"traceutil/trace.go:171","msg":"trace[1983697871] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"266.961031ms","start":"2026-05-21T08:55:45.093295Z","end":"2026-05-21T08:55:45.360256Z","steps":["trace[1983697871] 'process raft request' (duration: 266.817377ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T08:55:45.360528Z","caller":"traceutil/trace.go:171","msg":"trace[735481385] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"275.010115ms","start":"2026-05-21T08:55:45.085487Z","end":"2026-05-21T08:55:45.360497Z","steps":["trace[735481385] 'process raft request' (duration: 262.013156ms)","trace[735481385] 'compare' (duration: 12.038625ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T08:55:45.360697Z","caller":"traceutil/trace.go:171","msg":"trace[1755819037] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"183.178113ms","start":"2026-05-21T08:55:45.177194Z","end":"2026-05-21T08:55:45.360372Z","steps":["trace[1755819037] 'process raft request' (duration: 183.008159ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T08:55:50.824375Z","caller":"traceutil/trace.go:171","msg":"trace[1096351369] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"519.26196ms","start":"2026-05-21T08:55:50.305065Z","end":"2026-05-21T08:55:50.824327Z","steps":["trace[1096351369] 'process raft request' (duration: 518.946512ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:55:50.824698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:55:50.30502Z","time spent":"519.508336ms","remote":"127.0.0.1:36606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T08:55:51.790229Z","caller":"traceutil/trace.go:171","msg":"trace[1373315025] linearizableReadLoop","detail":"{readStateIndex:673; appliedIndex:672; }","duration":"1.166220041s","start":"2026-05-21T08:55:50.623881Z","end":"2026-05-21T08:55:51.790101Z","steps":["trace[1373315025] 'read index received' (duration: 200.495142ms)","trace[1373315025] 'applied index is now lower than readState.Index' (duration: 965.721949ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T08:55:51.791904Z","caller":"traceutil/trace.go:171","msg":"trace[1294159755] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"1.406907675s","start":"2026-05-21T08:55:50.384941Z","end":"2026-05-21T08:55:51.791849Z","steps":["trace[1294159755] 'process raft request' (duration: 1.401890688s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:55:51.79198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.168073088s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-21T08:55:51.792052Z","caller":"traceutil/trace.go:171","msg":"trace[1291441345] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:649; }","duration":"1.168204381s","start":"2026-05-21T08:55:50.623813Z","end":"2026-05-21T08:55:51.792017Z","steps":["trace[1291441345] 'agreement among raft nodes before linearized reading' (duration: 1.168020727s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:55:51.792091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:55:50.623782Z","time spent":"1.168301134s","remote":"127.0.0.1:36690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-21T08:55:51.792112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:55:50.384911Z","time spent":"1.407112511s","remote":"127.0.0.1:36502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036221,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T08:55:51.815144Z","caller":"traceutil/trace.go:171","msg":"trace[1447284876] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"106.827254ms","start":"2026-05-21T08:55:51.708294Z","end":"2026-05-21T08:55:51.815121Z","steps":["trace[1447284876] 'process raft request' (duration: 106.71588ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:55:51.815548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.888741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.95\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-21T08:55:51.815578Z","caller":"traceutil/trace.go:171","msg":"trace[97660814] range","detail":"{range_begin:/registry/masterleases/199.19.213.95; range_end:; response_count:1; response_revision:650; }","duration":"368.986773ms","start":"2026-05-21T08:55:51.44658Z","end":"2026-05-21T08:55:51.815567Z","steps":["trace[97660814] 'agreement among raft nodes before linearized reading' (duration: 368.921391ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:55:51.815599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:55:51.446548Z","time spent":"369.044795ms","remote":"127.0.0.1:59440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.19.213.95\" "} {"level":"warn","ts":"2026-05-21T08:55:51.815569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.385233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-21T08:55:51.815662Z","caller":"traceutil/trace.go:171","msg":"trace[1448369173] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:650; }","duration":"206.554397ms","start":"2026-05-21T08:55:51.609084Z","end":"2026-05-21T08:55:51.815638Z","steps":["trace[1448369173] 'agreement among raft nodes before linearized reading' (duration: 206.27288ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:55:51.815639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.228756ms","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-05-21T08:55:51.815756Z","caller":"traceutil/trace.go:171","msg":"trace[846906646] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:650; }","duration":"200.397231ms","start":"2026-05-21T08:55:51.615334Z","end":"2026-05-21T08:55:51.815732Z","steps":["trace[846906646] 'agreement among raft nodes before linearized reading' (duration: 200.003011ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:55:51.815891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"949.654931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T08:55:51.815913Z","caller":"traceutil/trace.go:171","msg":"trace[1390968824] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:650; }","duration":"949.721602ms","start":"2026-05-21T08:55:50.866185Z","end":"2026-05-21T08:55:51.815907Z","steps":["trace[1390968824] 'agreement among raft nodes before linearized reading' (duration: 949.688652ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:55:51.81593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:55:50.866156Z","time spent":"949.770624ms","remote":"127.0.0.1:59418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-21T08:55:52.187757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.262969ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T08:55:52.187928Z","caller":"traceutil/trace.go:171","msg":"trace[1544118974] linearizableReadLoop","detail":"{readStateIndex:675; appliedIndex:674; }","duration":"372.584693ms","start":"2026-05-21T08:55:51.815315Z","end":"2026-05-21T08:55:52.187899Z","steps":["trace[1544118974] 'read index received' (duration: 107.191901ms)","trace[1544118974] 'applied index is now lower than readState.Index' (duration: 265.390682ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T08:55:52.187968Z","caller":"traceutil/trace.go:171","msg":"trace[708839147] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"391.117604ms","start":"2026-05-21T08:55:51.796801Z","end":"2026-05-21T08:55:52.187919Z","steps":["trace[708839147] 'process raft request' (duration: 125.551679ms)","trace[708839147] 'compare' (duration: 265.096993ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T08:55:52.188158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:55:51.796776Z","time spent":"391.280638ms","remote":"127.0.0.1:36690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T08:55:52.188209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.29639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-21T08:55:52.188507Z","caller":"traceutil/trace.go:171","msg":"trace[1958961059] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:651; }","duration":"395.604738ms","start":"2026-05-21T08:55:51.792853Z","end":"2026-05-21T08:55:52.188458Z","steps":["trace[1958961059] 'agreement among raft nodes before linearized reading' (duration: 395.145376ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:55:52.188634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:55:51.792832Z","time spent":"395.759771ms","remote":"127.0.0.1:36690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-21T08:55:52.472736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.973365ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-05-21T08:55:52.473204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:55:51.817424Z","time spent":"655.769936ms","remote":"127.0.0.1:59440","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-21T08:55:52.473288Z","caller":"traceutil/trace.go:171","msg":"trace[1328795083] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"653.259092ms","start":"2026-05-21T08:55:51.820003Z","end":"2026-05-21T08:55:52.473262Z","steps":["trace[1328795083] 'process raft request' (duration: 652.867292ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:55:52.473487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:55:51.819982Z","time spent":"653.403547ms","remote":"127.0.0.1:36690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T08:55:52.47386Z","caller":"traceutil/trace.go:171","msg":"trace[1680506354] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"653.53105ms","start":"2026-05-21T08:55:51.820289Z","end":"2026-05-21T08:55:52.47382Z","steps":["trace[1680506354] 'process raft request' (duration: 652.912293ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T08:55:52.473986Z","caller":"traceutil/trace.go:171","msg":"trace[1976932198] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"281.012328ms","start":"2026-05-21T08:55:52.192958Z","end":"2026-05-21T08:55:52.47397Z","steps":["trace[1976932198] 'process raft request' (duration: 280.904915ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T08:55:52.473983Z","caller":"traceutil/trace.go:171","msg":"trace[923482481] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"621.110316ms","start":"2026-05-21T08:55:51.85284Z","end":"2026-05-21T08:55:52.47395Z","steps":["trace[923482481] 'process raft request' (duration: 620.745457ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:55:52.474107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:55:51.82028Z","time spent":"653.697334ms","remote":"127.0.0.1:36690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T08:55:52.474326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:55:51.852813Z","time spent":"621.334692ms","remote":"127.0.0.1:36744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1092,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-21T08:55:52.987297Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1698875524018257528,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-21T08:55:53.81053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.129057ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T08:55:53.810661Z","caller":"traceutil/trace.go:171","msg":"trace[201345118] linearizableReadLoop","detail":"{readStateIndex:681; appliedIndex:680; }","duration":"1.324234667s","start":"2026-05-21T08:55:52.48641Z","end":"2026-05-21T08:55:53.810644Z","steps":["trace[201345118] 'read index received' (duration: 854.846364ms)","trace[201345118] 'applied index is now lower than readState.Index' (duration: 469.387203ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T08:55:53.810783Z","caller":"traceutil/trace.go:171","msg":"trace[1946367516] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"1.335971945s","start":"2026-05-21T08:55:52.474799Z","end":"2026-05-21T08:55:53.810771Z","steps":["trace[1946367516] 'process raft request' (duration: 866.51258ms)","trace[1946367516] 'compare' (duration: 469.028265ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T08:55:53.810845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:55:52.474775Z","time spent":"1.336036986s","remote":"127.0.0.1:59440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T08:55:53.811104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.324697429s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/envoy-gateway-gateway-helm-certgen:envoy-gateway-system\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T08:55:53.811138Z","caller":"traceutil/trace.go:171","msg":"trace[1135087370] range","detail":"{range_begin:/registry/clusterrolebindings/envoy-gateway-gateway-helm-certgen:envoy-gateway-system; range_end:; response_count:0; response_revision:656; }","duration":"1.32476837s","start":"2026-05-21T08:55:52.486358Z","end":"2026-05-21T08:55:53.811127Z","steps":["trace[1135087370] 'agreement among raft nodes before linearized reading' (duration: 1.324681488s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:55:53.811167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:55:52.486332Z","time spent":"1.324828182s","remote":"127.0.0.1:36760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":87,"response count":0,"response size":28,"request content":"key:\"/registry/clusterrolebindings/envoy-gateway-gateway-helm-certgen:envoy-gateway-system\" "} {"level":"warn","ts":"2026-05-21T08:55:53.811381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"945.109806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T08:55:53.811489Z","caller":"traceutil/trace.go:171","msg":"trace[1825858187] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:656; }","duration":"945.282841ms","start":"2026-05-21T08:55:52.866182Z","end":"2026-05-21T08:55:53.811464Z","steps":["trace[1825858187] 'agreement among raft nodes before linearized reading' (duration: 945.100626ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:55:53.811543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:55:52.866152Z","time spent":"945.381492ms","remote":"127.0.0.1:59424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-21T08:55:54.333587Z","caller":"traceutil/trace.go:171","msg":"trace[1887438087] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"104.610248ms","start":"2026-05-21T08:55:54.228947Z","end":"2026-05-21T08:55:54.333557Z","steps":["trace[1887438087] 'process raft request' (duration: 103.858558ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T08:55:54.499623Z","caller":"traceutil/trace.go:171","msg":"trace[394930019] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"100.306818ms","start":"2026-05-21T08:55:54.399298Z","end":"2026-05-21T08:55:54.499605Z","steps":["trace[394930019] 'process raft request' (duration: 100.227776ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T08:55:54.499848Z","caller":"traceutil/trace.go:171","msg":"trace[1452484762] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"135.057581ms","start":"2026-05-21T08:55:54.364637Z","end":"2026-05-21T08:55:54.499694Z","steps":["trace[1452484762] 'process raft request' (duration: 128.175175ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T08:55:55.153004Z","caller":"traceutil/trace.go:171","msg":"trace[556531082] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"145.114566ms","start":"2026-05-21T08:55:55.007861Z","end":"2026-05-21T08:55:55.152975Z","steps":["trace[556531082] 'process raft request' (duration: 143.67096ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:55:55.53659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.266175ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-21T08:55:55.536699Z","caller":"traceutil/trace.go:171","msg":"trace[6673330] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"313.281907ms","start":"2026-05-21T08:55:55.223399Z","end":"2026-05-21T08:55:55.536681Z","steps":["trace[6673330] 'process raft request' (duration: 116.848928ms)","trace[6673330] 'compare' (duration: 196.104531ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T08:55:55.536754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:55:55.223373Z","time spent":"313.35952ms","remote":"127.0.0.1:36900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T08:55:56.105721Z","caller":"traceutil/trace.go:171","msg":"trace[1403498149] transaction","detail":"{read_only:false; response_revision:669; number_of_response:1; }","duration":"130.695229ms","start":"2026-05-21T08:55:55.974962Z","end":"2026-05-21T08:55:56.105657Z","steps":["trace[1403498149] 'process raft request' (duration: 130.062544ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T08:55:56.642251Z","caller":"traceutil/trace.go:171","msg":"trace[877145235] transaction","detail":"{read_only:false; response_revision:670; number_of_response:1; }","duration":"517.68711ms","start":"2026-05-21T08:55:56.124518Z","end":"2026-05-21T08:55:56.642205Z","steps":["trace[877145235] 'process raft request' (duration: 474.265687ms)","trace[877145235] 'compare' (duration: 42.792056ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T08:55:56.642474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:55:56.124492Z","time spent":"517.887685ms","remote":"127.0.0.1:36488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":700,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T08:55:56.642853Z","caller":"traceutil/trace.go:171","msg":"trace[1201646779] transaction","detail":"{read_only:false; response_revision:671; number_of_response:1; }","duration":"472.658357ms","start":"2026-05-21T08:55:56.170163Z","end":"2026-05-21T08:55:56.642821Z","steps":["trace[1201646779] 'process raft request' (duration: 471.959089ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:55:56.642946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:55:56.170125Z","time spent":"472.77885ms","remote":"127.0.0.1:36666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-21T08:55:56.940529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.325098ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-21T08:55:56.94069Z","caller":"traceutil/trace.go:171","msg":"trace[315830363] linearizableReadLoop","detail":"{readStateIndex:696; appliedIndex:694; }","duration":"735.677677ms","start":"2026-05-21T08:55:56.204985Z","end":"2026-05-21T08:55:56.940662Z","steps":["trace[315830363] 'read index received' (duration: 394.228974ms)","trace[315830363] 'applied index is now lower than readState.Index' (duration: 341.446973ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T08:55:56.940961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.018043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"warn","ts":"2026-05-21T08:55:56.941035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"666.11619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-5q6gc\" ","response":"range_response_count:1 size:17243"} {"level":"info","ts":"2026-05-21T08:55:56.941074Z","caller":"traceutil/trace.go:171","msg":"trace[1363228159] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:671; }","duration":"293.174377ms","start":"2026-05-21T08:55:56.647873Z","end":"2026-05-21T08:55:56.941047Z","steps":["trace[1363228159] 'agreement among raft nodes before linearized reading' (duration: 292.961842ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T08:55:56.941089Z","caller":"traceutil/trace.go:171","msg":"trace[68328831] range","detail":"{range_begin:/registry/pods/kube-system/cilium-5q6gc; range_end:; response_count:1; response_revision:671; }","duration":"666.238324ms","start":"2026-05-21T08:55:56.274835Z","end":"2026-05-21T08:55:56.941073Z","steps":["trace[68328831] 'agreement among raft nodes before linearized reading' (duration: 665.971017ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:55:56.94114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:55:56.274805Z","time spent":"666.326036ms","remote":"127.0.0.1:36606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":17266,"request content":"key:\"/registry/pods/kube-system/cilium-5q6gc\" "} {"level":"warn","ts":"2026-05-21T08:55:56.941037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.793181ms","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-05-21T08:55:56.941331Z","caller":"traceutil/trace.go:171","msg":"trace[938653141] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:671; }","duration":"340.13694ms","start":"2026-05-21T08:55:56.601163Z","end":"2026-05-21T08:55:56.9413Z","steps":["trace[938653141] 'agreement among raft nodes before linearized reading' (duration: 339.686799ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:55:56.941484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:55:56.601135Z","time spent":"340.329604ms","remote":"127.0.0.1:36690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-21T08:55:56.941044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"735.997545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-05-21T08:55:56.941685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.093756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-21T08:55:56.941706Z","caller":"traceutil/trace.go:171","msg":"trace[569315784] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:671; }","duration":"736.738395ms","start":"2026-05-21T08:55:56.204919Z","end":"2026-05-21T08:55:56.941657Z","steps":["trace[569315784] 'agreement among raft nodes before linearized reading' (duration: 735.837412ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T08:55:56.941752Z","caller":"traceutil/trace.go:171","msg":"trace[108984775] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:671; }","duration":"417.206518ms","start":"2026-05-21T08:55:56.524525Z","end":"2026-05-21T08:55:56.941731Z","steps":["trace[108984775] 'agreement among raft nodes before linearized reading' (duration: 416.90291ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:55:56.941792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:55:56.20484Z","time spent":"736.9276ms","remote":"127.0.0.1:36690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-21T08:55:56.941884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:55:56.524485Z","time spent":"417.312581ms","remote":"127.0.0.1:36690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-21T08:55:56.942127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.657314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-21T08:55:56.942267Z","caller":"traceutil/trace.go:171","msg":"trace[529990390] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:671; }","duration":"340.90373ms","start":"2026-05-21T08:55:56.601309Z","end":"2026-05-21T08:55:56.942213Z","steps":["trace[529990390] 'agreement among raft nodes before linearized reading' (duration: 340.256754ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:55:56.942462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:55:56.601274Z","time spent":"341.126286ms","remote":"127.0.0.1:36690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-21T08:55:57.083465Z","caller":"traceutil/trace.go:171","msg":"trace[865769772] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"135.018629ms","start":"2026-05-21T08:55:56.948361Z","end":"2026-05-21T08:55:57.083379Z","steps":["trace[865769772] 'process raft request' (duration: 110.312561ms)","trace[865769772] 'compare' (duration: 24.325918ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T08:55:57.083654Z","caller":"traceutil/trace.go:171","msg":"trace[1342918925] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"135.211754ms","start":"2026-05-21T08:55:56.948402Z","end":"2026-05-21T08:55:57.083614Z","steps":["trace[1342918925] 'process raft request' (duration: 134.839445ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T08:55:57.083806Z","caller":"traceutil/trace.go:171","msg":"trace[1221080167] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"135.06315ms","start":"2026-05-21T08:55:56.948699Z","end":"2026-05-21T08:55:57.083762Z","steps":["trace[1221080167] 'process raft request' (duration: 134.635659ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T08:55:57.084586Z","caller":"traceutil/trace.go:171","msg":"trace[1692973923] linearizableReadLoop","detail":"{readStateIndex:701; appliedIndex:697; }","duration":"129.895249ms","start":"2026-05-21T08:55:56.954658Z","end":"2026-05-21T08:55:57.084553Z","steps":["trace[1692973923] 'read index received' (duration: 20.835219ms)","trace[1692973923] 'applied index is now lower than readState.Index' (duration: 109.05851ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T08:55:57.084762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.077685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/envoy-gateway-system/\" range_end:\"/registry/limitranges/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T08:55:57.08482Z","caller":"traceutil/trace.go:171","msg":"trace[796615737] range","detail":"{range_begin:/registry/limitranges/envoy-gateway-system/; range_end:/registry/limitranges/envoy-gateway-system0; response_count:0; response_revision:676; }","duration":"130.205468ms","start":"2026-05-21T08:55:56.9546Z","end":"2026-05-21T08:55:57.084805Z","steps":["trace[796615737] 'agreement among raft nodes before linearized reading' (duration: 130.080635ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T08:55:57.088121Z","caller":"traceutil/trace.go:171","msg":"trace[1699930282] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"128.813622ms","start":"2026-05-21T08:55:56.956206Z","end":"2026-05-21T08:55:57.08502Z","steps":["trace[1699930282] 'process raft request' (duration: 127.274003ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T08:55:58.967459Z","caller":"traceutil/trace.go:171","msg":"trace[1806232398] transaction","detail":"{read_only:false; response_revision:712; number_of_response:1; }","duration":"330.222695ms","start":"2026-05-21T08:55:58.637201Z","end":"2026-05-21T08:55:58.967423Z","steps":["trace[1806232398] 'process raft request' (duration: 324.272595ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:55:58.967694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:55:58.637168Z","time spent":"330.39576ms","remote":"127.0.0.1:36472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4059,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T08:55:58.969557Z","caller":"traceutil/trace.go:171","msg":"trace[218741274] linearizableReadLoop","detail":"{readStateIndex:740; appliedIndex:737; }","duration":"103.883945ms","start":"2026-05-21T08:55:58.865642Z","end":"2026-05-21T08:55:58.969525Z","steps":["trace[218741274] 'read index received' (duration: 95.831182ms)","trace[218741274] 'applied index is now lower than readState.Index' (duration: 8.051073ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T08:55:58.96977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.080111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T08:55:58.969926Z","caller":"traceutil/trace.go:171","msg":"trace[280782774] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:714; }","duration":"104.318087ms","start":"2026-05-21T08:55:58.865588Z","end":"2026-05-21T08:55:58.969907Z","steps":["trace[280782774] 'agreement among raft nodes before linearized reading' (duration: 104.06827ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T08:55:58.970278Z","caller":"traceutil/trace.go:171","msg":"trace[555465972] transaction","detail":"{read_only:false; response_revision:713; number_of_response:1; }","duration":"305.470329ms","start":"2026-05-21T08:55:58.664795Z","end":"2026-05-21T08:55:58.970265Z","steps":["trace[555465972] 'process raft request' (duration: 304.434682ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:55:58.97038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:55:58.664769Z","time spent":"305.539721ms","remote":"127.0.0.1:36488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":700,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T08:55:58.970712Z","caller":"traceutil/trace.go:171","msg":"trace[1853576093] transaction","detail":"{read_only:false; response_revision:714; number_of_response:1; }","duration":"191.959462ms","start":"2026-05-21T08:55:58.778735Z","end":"2026-05-21T08:55:58.970694Z","steps":["trace[1853576093] 'process raft request' (duration: 190.71221ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T08:56:01.545725Z","caller":"traceutil/trace.go:171","msg":"trace[2060510523] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"134.837487ms","start":"2026-05-21T08:56:01.410854Z","end":"2026-05-21T08:56:01.545692Z","steps":["trace[2060510523] 'process raft request' (duration: 134.53396ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T08:56:01.628151Z","caller":"traceutil/trace.go:171","msg":"trace[2018679870] linearizableReadLoop","detail":"{readStateIndex:777; appliedIndex:775; }","duration":"181.167277ms","start":"2026-05-21T08:56:01.446969Z","end":"2026-05-21T08:56:01.628137Z","steps":["trace[2018679870] 'read index received' (duration: 98.331745ms)","trace[2018679870] 'applied index is now lower than readState.Index' (duration: 82.834802ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T08:56:01.62824Z","caller":"traceutil/trace.go:171","msg":"trace[549859600] transaction","detail":"{read_only:false; response_revision:751; number_of_response:1; }","duration":"215.765452ms","start":"2026-05-21T08:56:01.412468Z","end":"2026-05-21T08:56:01.628233Z","steps":["trace[549859600] 'process raft request' (duration: 215.581137ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:56:01.628501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.522097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.95\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-21T08:56:01.628531Z","caller":"traceutil/trace.go:171","msg":"trace[1742363716] range","detail":"{range_begin:/registry/masterleases/199.19.213.95; range_end:; response_count:1; response_revision:751; }","duration":"181.607729ms","start":"2026-05-21T08:56:01.446917Z","end":"2026-05-21T08:56:01.628525Z","steps":["trace[1742363716] 'agreement among raft nodes before linearized reading' (duration: 181.465245ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:56:11.650242Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1698875524018257934,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-21T08:56:11.836235Z","caller":"traceutil/trace.go:171","msg":"trace[1816343900] linearizableReadLoop","detail":"{readStateIndex:854; appliedIndex:853; }","duration":"686.424436ms","start":"2026-05-21T08:56:11.149741Z","end":"2026-05-21T08:56:11.836166Z","steps":["trace[1816343900] 'read index received' (duration: 686.148419ms)","trace[1816343900] 'applied index is now lower than readState.Index' (duration: 274.947µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T08:56:11.836678Z","caller":"traceutil/trace.go:171","msg":"trace[826828510] transaction","detail":"{read_only:false; response_revision:824; number_of_response:1; }","duration":"763.351051ms","start":"2026-05-21T08:56:11.073305Z","end":"2026-05-21T08:56:11.836656Z","steps":["trace[826828510] 'process raft request' (duration: 762.673583ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:56:11.836933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.543344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.95\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-05-21T08:56:11.836942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"685.321619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-21T08:56:11.837004Z","caller":"traceutil/trace.go:171","msg":"trace[418452135] range","detail":"{range_begin:/registry/masterleases/199.19.213.95; range_end:; response_count:1; response_revision:824; }","duration":"389.624186ms","start":"2026-05-21T08:56:11.447362Z","end":"2026-05-21T08:56:11.836986Z","steps":["trace[418452135] 'agreement among raft nodes before linearized reading' (duration: 389.335589ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:56:11.83704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:56:11.447091Z","time spent":"389.940684ms","remote":"127.0.0.1:59440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.19.213.95\" "} {"level":"info","ts":"2026-05-21T08:56:11.837074Z","caller":"traceutil/trace.go:171","msg":"trace[807319581] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:824; }","duration":"685.511613ms","start":"2026-05-21T08:56:11.151509Z","end":"2026-05-21T08:56:11.837021Z","steps":["trace[807319581] 'agreement among raft nodes before linearized reading' (duration: 685.161604ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:56:11.837158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:56:11.15148Z","time spent":"685.659637ms","remote":"127.0.0.1:36690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-21T08:56:11.837457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.458599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-qdcvz\" ","response":"range_response_count:1 size:3964"} {"level":"info","ts":"2026-05-21T08:56:11.837541Z","caller":"traceutil/trace.go:171","msg":"trace[856140039] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-qdcvz; range_end:; response_count:1; response_revision:824; }","duration":"453.591622ms","start":"2026-05-21T08:56:11.383932Z","end":"2026-05-21T08:56:11.837523Z","steps":["trace[856140039] 'agreement among raft nodes before linearized reading' (duration: 452.998708ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:56:11.837436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"682.794574ms","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-05-21T08:56:11.837593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:56:11.383899Z","time spent":"453.682405ms","remote":"127.0.0.1:36606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3987,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-qdcvz\" "} {"level":"info","ts":"2026-05-21T08:56:11.837635Z","caller":"traceutil/trace.go:171","msg":"trace[1399836032] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:824; }","duration":"683.03667ms","start":"2026-05-21T08:56:11.154555Z","end":"2026-05-21T08:56:11.837591Z","steps":["trace[1399836032] 'agreement among raft nodes before linearized reading' (duration: 682.487056ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:56:11.837706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:56:11.15453Z","time spent":"683.155903ms","remote":"127.0.0.1:36690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-21T08:56:11.837605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"687.75923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-05-21T08:56:11.838003Z","caller":"traceutil/trace.go:171","msg":"trace[233472023] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:824; }","duration":"688.305074ms","start":"2026-05-21T08:56:11.149669Z","end":"2026-05-21T08:56:11.837974Z","steps":["trace[233472023] 'agreement among raft nodes before linearized reading' (duration: 687.020762ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:56:11.836911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:56:11.073267Z","time spent":"763.464194ms","remote":"127.0.0.1:36690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T08:56:11.838151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:56:11.149634Z","time spent":"688.440037ms","remote":"127.0.0.1:36690","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-05-21T08:56:12.364702Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1698875524018257937,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-21T08:56:12.590888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:56:11.839493Z","time spent":"751.388959ms","remote":"127.0.0.1:59440","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-21T08:56:12.594382Z","caller":"traceutil/trace.go:171","msg":"trace[1405327558] transaction","detail":"{read_only:false; response_revision:825; number_of_response:1; }","duration":"751.750157ms","start":"2026-05-21T08:56:11.842605Z","end":"2026-05-21T08:56:12.594355Z","steps":["trace[1405327558] 'process raft request' (duration: 751.558912ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T08:56:12.594659Z","caller":"traceutil/trace.go:171","msg":"trace[203072001] transaction","detail":"{read_only:false; response_revision:826; number_of_response:1; }","duration":"750.786873ms","start":"2026-05-21T08:56:11.843846Z","end":"2026-05-21T08:56:12.594633Z","steps":["trace[203072001] 'process raft request' (duration: 750.454365ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:56:12.594813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:56:11.843835Z","time spent":"750.868495ms","remote":"127.0.0.1:36690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T08:56:12.595184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:56:11.842574Z","time spent":"751.980223ms","remote":"127.0.0.1:36690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T08:56:12.595266Z","caller":"traceutil/trace.go:171","msg":"trace[1912109482] linearizableReadLoop","detail":"{readStateIndex:860; appliedIndex:855; }","duration":"730.992103ms","start":"2026-05-21T08:56:11.864258Z","end":"2026-05-21T08:56:12.59525Z","steps":["trace[1912109482] 'read index received' (duration: 727.148147ms)","trace[1912109482] 'applied index is now lower than readState.Index' (duration: 3.842656ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T08:56:12.595218Z","caller":"traceutil/trace.go:171","msg":"trace[1666763370] transaction","detail":"{read_only:false; response_revision:827; number_of_response:1; }","duration":"751.263425ms","start":"2026-05-21T08:56:11.84393Z","end":"2026-05-21T08:56:12.595193Z","steps":["trace[1666763370] 'process raft request' (duration: 750.633639ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T08:56:12.595296Z","caller":"traceutil/trace.go:171","msg":"trace[606021884] transaction","detail":"{read_only:false; response_revision:828; number_of_response:1; }","duration":"747.225392ms","start":"2026-05-21T08:56:11.848059Z","end":"2026-05-21T08:56:12.595285Z","steps":["trace[606021884] 'process raft request' (duration: 747.033658ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T08:56:12.59552Z","caller":"traceutil/trace.go:171","msg":"trace[1495202214] transaction","detail":"{read_only:false; response_revision:829; number_of_response:1; }","duration":"471.2997ms","start":"2026-05-21T08:56:12.124212Z","end":"2026-05-21T08:56:12.595511Z","steps":["trace[1495202214] 'process raft request' (duration: 470.968222ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:56:12.595633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:56:11.848051Z","time spent":"747.502679ms","remote":"127.0.0.1:36606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4027,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T08:56:12.595659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:56:12.124152Z","time spent":"471.412103ms","remote":"127.0.0.1:36690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T08:56:12.595618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:56:11.843903Z","time spent":"751.529652ms","remote":"127.0.0.1:36690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T08:56:12.595829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"731.579779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T08:56:12.595871Z","caller":"traceutil/trace.go:171","msg":"trace[1512880495] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:829; }","duration":"731.651591ms","start":"2026-05-21T08:56:11.864208Z","end":"2026-05-21T08:56:12.595859Z","steps":["trace[1512880495] 'agreement among raft nodes before linearized reading' (duration: 731.588599ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:56:12.595897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:56:11.864194Z","time spent":"731.697161ms","remote":"127.0.0.1:59418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-21T08:56:12.598537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"530.862176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-21T08:56:12.598585Z","caller":"traceutil/trace.go:171","msg":"trace[989251219] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:830; }","duration":"530.976739ms","start":"2026-05-21T08:56:12.067598Z","end":"2026-05-21T08:56:12.598575Z","steps":["trace[989251219] 'agreement among raft nodes before linearized reading' (duration: 530.739752ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:56:12.598619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:56:12.067555Z","time spent":"531.056911ms","remote":"127.0.0.1:36690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-21T08:56:13.594132Z","caller":"traceutil/trace.go:171","msg":"trace[1849948402] transaction","detail":"{read_only:false; response_revision:832; number_of_response:1; }","duration":"192.670589ms","start":"2026-05-21T08:56:13.401434Z","end":"2026-05-21T08:56:13.594104Z","steps":["trace[1849948402] 'process raft request' (duration: 192.449424ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T08:56:14.749003Z","caller":"traceutil/trace.go:171","msg":"trace[1393140055] transaction","detail":"{read_only:false; response_revision:838; number_of_response:1; }","duration":"105.735543ms","start":"2026-05-21T08:56:14.643218Z","end":"2026-05-21T08:56:14.748954Z","steps":["trace[1393140055] 'process raft request' (duration: 54.775935ms)","trace[1393140055] 'compare' (duration: 50.569778ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T08:56:48.066046Z","caller":"traceutil/trace.go:171","msg":"trace[10905332] transaction","detail":"{read_only:false; response_revision:1042; number_of_response:1; }","duration":"412.839552ms","start":"2026-05-21T08:56:47.653187Z","end":"2026-05-21T08:56:48.066027Z","steps":["trace[10905332] 'process raft request' (duration: 412.556765ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:56:48.066154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.956563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-21T08:56:48.06621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:56:47.653162Z","time spent":"412.950006ms","remote":"127.0.0.1:36690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T08:56:48.066212Z","caller":"traceutil/trace.go:171","msg":"trace[1996978745] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:1042; }","duration":"293.052266ms","start":"2026-05-21T08:56:47.773141Z","end":"2026-05-21T08:56:48.066193Z","steps":["trace[1996978745] 'agreement among raft nodes before linearized reading' (duration: 292.943553ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T08:56:48.066012Z","caller":"traceutil/trace.go:171","msg":"trace[1196538488] linearizableReadLoop","detail":"{readStateIndex:1081; appliedIndex:1080; }","duration":"292.774018ms","start":"2026-05-21T08:56:47.773187Z","end":"2026-05-21T08:56:48.065961Z","steps":["trace[1196538488] 'read index received' (duration: 292.520151ms)","trace[1196538488] 'applied index is now lower than readState.Index' (duration: 252.527µs)"],"step_count":2} {"level":"warn","ts":"2026-05-21T08:56:48.066403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.780122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-21T08:56:48.066429Z","caller":"traceutil/trace.go:171","msg":"trace[899195196] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1042; }","duration":"156.853094ms","start":"2026-05-21T08:56:47.909568Z","end":"2026-05-21T08:56:48.066421Z","steps":["trace[899195196] 'agreement among raft nodes before linearized reading' (duration: 156.772032ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:56:48.803856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.543069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-21T08:56:48.803958Z","caller":"traceutil/trace.go:171","msg":"trace[2143344785] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1043; }","duration":"183.783455ms","start":"2026-05-21T08:56:48.620151Z","end":"2026-05-21T08:56:48.803935Z","steps":["trace[2143344785] 'count revisions from in-memory index tree' (duration: 183.356554ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:56:49.367427Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1698875524018258396,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-21T08:56:49.425526Z","caller":"traceutil/trace.go:171","msg":"trace[539288952] transaction","detail":"{read_only:false; response_revision:1044; number_of_response:1; }","duration":"605.82232ms","start":"2026-05-21T08:56:48.819666Z","end":"2026-05-21T08:56:49.425488Z","steps":["trace[539288952] 'process raft request' (duration: 605.44369ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:56:49.425756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:56:48.819643Z","time spent":"605.975984ms","remote":"127.0.0.1:36690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T08:56:49.425963Z","caller":"traceutil/trace.go:171","msg":"trace[9356770] linearizableReadLoop","detail":"{readStateIndex:1085; appliedIndex:1082; }","duration":"559.223153ms","start":"2026-05-21T08:56:48.866719Z","end":"2026-05-21T08:56:49.425942Z","steps":["trace[9356770] 'read index received' (duration: 558.396861ms)","trace[9356770] 'applied index is now lower than readState.Index' (duration: 825.441µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T08:56:49.42607Z","caller":"traceutil/trace.go:171","msg":"trace[1150901090] transaction","detail":"{read_only:false; response_revision:1045; number_of_response:1; }","duration":"603.44293ms","start":"2026-05-21T08:56:48.822617Z","end":"2026-05-21T08:56:49.42606Z","steps":["trace[1150901090] 'process raft request' (duration: 603.142153ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:56:49.426186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:56:48.8226Z","time spent":"603.499321ms","remote":"127.0.0.1:36690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T08:56:49.426151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"559.412317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T08:56:49.426252Z","caller":"traceutil/trace.go:171","msg":"trace[1517036005] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1046; }","duration":"559.780847ms","start":"2026-05-21T08:56:48.866448Z","end":"2026-05-21T08:56:49.426229Z","steps":["trace[1517036005] 'agreement among raft nodes before linearized reading' (duration: 559.620793ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:56:49.426294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:56:48.866417Z","time spent":"559.868639ms","remote":"127.0.0.1:59424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-21T08:56:49.426335Z","caller":"traceutil/trace.go:171","msg":"trace[1336768025] transaction","detail":"{read_only:false; response_revision:1046; number_of_response:1; }","duration":"603.260926ms","start":"2026-05-21T08:56:48.823054Z","end":"2026-05-21T08:56:49.426315Z","steps":["trace[1336768025] 'process raft request' (duration: 602.850746ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:56:49.426442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:56:48.823024Z","time spent":"603.360649ms","remote":"127.0.0.1:36690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T08:56:49.426527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.310242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-21T08:56:49.426556Z","caller":"traceutil/trace.go:171","msg":"trace[896291040] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1046; }","duration":"494.453126ms","start":"2026-05-21T08:56:48.932094Z","end":"2026-05-21T08:56:49.426548Z","steps":["trace[896291040] 'agreement among raft nodes before linearized reading' (duration: 494.349363ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:56:49.426577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:56:48.93204Z","time spent":"494.531268ms","remote":"127.0.0.1:36690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-21T08:56:55.615158Z","caller":"traceutil/trace.go:171","msg":"trace[1180599447] transaction","detail":"{read_only:false; response_revision:1067; number_of_response:1; }","duration":"125.634955ms","start":"2026-05-21T08:56:55.489495Z","end":"2026-05-21T08:56:55.61513Z","steps":["trace[1180599447] 'process raft request' (duration: 125.591574ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T08:56:55.615418Z","caller":"traceutil/trace.go:171","msg":"trace[11432487] transaction","detail":"{read_only:false; response_revision:1066; number_of_response:1; }","duration":"125.899021ms","start":"2026-05-21T08:56:55.489495Z","end":"2026-05-21T08:56:55.615394Z","steps":["trace[11432487] 'process raft request' (duration: 123.612423ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:57:15.367606Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1698875524018258618,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-21T08:57:15.756337Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.342717171s","expected-duration":"1s"} {"level":"info","ts":"2026-05-21T08:57:15.756663Z","caller":"traceutil/trace.go:171","msg":"trace[1158010526] linearizableReadLoop","detail":"{readStateIndex:1173; appliedIndex:1172; }","duration":"889.87694ms","start":"2026-05-21T08:57:14.866766Z","end":"2026-05-21T08:57:15.756643Z","steps":["trace[1158010526] 'read index received' (duration: 889.717366ms)","trace[1158010526] 'applied index is now lower than readState.Index' (duration: 158.694µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T08:57:15.756767Z","caller":"traceutil/trace.go:171","msg":"trace[1279505182] transaction","detail":"{read_only:false; response_revision:1129; number_of_response:1; }","duration":"1.343240564s","start":"2026-05-21T08:57:14.413515Z","end":"2026-05-21T08:57:15.756756Z","steps":["trace[1279505182] 'process raft request' (duration: 1.342990518s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:57:15.756878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:57:14.413494Z","time spent":"1.343305926s","remote":"127.0.0.1:36690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T08:57:15.757242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.782424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-21T08:57:15.757297Z","caller":"traceutil/trace.go:171","msg":"trace[896901308] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1129; }","duration":"172.872436ms","start":"2026-05-21T08:57:15.58441Z","end":"2026-05-21T08:57:15.757283Z","steps":["trace[896901308] 'agreement among raft nodes before linearized reading' (duration: 172.736843ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:57:15.757666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.601003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-05-21T08:57:15.757626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"890.788733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T08:57:15.757706Z","caller":"traceutil/trace.go:171","msg":"trace[1459877836] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1129; }","duration":"140.664825ms","start":"2026-05-21T08:57:15.617031Z","end":"2026-05-21T08:57:15.757696Z","steps":["trace[1459877836] 'agreement among raft nodes before linearized reading' (duration: 140.576492ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T08:57:15.757766Z","caller":"traceutil/trace.go:171","msg":"trace[1929855350] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1129; }","duration":"890.999367ms","start":"2026-05-21T08:57:14.866729Z","end":"2026-05-21T08:57:15.757728Z","steps":["trace[1929855350] 'agreement among raft nodes before linearized reading' (duration: 890.71279ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:57:15.757848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:57:14.86671Z","time spent":"891.123741ms","remote":"127.0.0.1:59424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-21T08:57:16.449283Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1698875524018258622,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-21T08:57:16.949693Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1698875524018258622,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-21T08:57:17.028112Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.265199705s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-21T08:57:17.369242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.713663ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T08:57:17.369411Z","caller":"traceutil/trace.go:171","msg":"trace[102350261] transaction","detail":"{read_only:false; response_revision:1130; number_of_response:1; }","duration":"1.606541425s","start":"2026-05-21T08:57:15.762842Z","end":"2026-05-21T08:57:17.369383Z","steps":["trace[102350261] 'process raft request' (duration: 1.265557895s)","trace[102350261] 'compare' (duration: 340.166819ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T08:57:17.369486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:57:15.762828Z","time spent":"1.606622197s","remote":"127.0.0.1:36690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T08:57:17.563344Z","caller":"traceutil/trace.go:171","msg":"trace[2000298683] transaction","detail":"{read_only:false; response_revision:1132; number_of_response:1; }","duration":"1.799068261s","start":"2026-05-21T08:57:15.764224Z","end":"2026-05-21T08:57:17.563292Z","steps":["trace[2000298683] 'process raft request' (duration: 1.79863464s)"],"step_count":1} {"level":"info","ts":"2026-05-21T08:57:17.563376Z","caller":"traceutil/trace.go:171","msg":"trace[1143720979] transaction","detail":"{read_only:false; response_revision:1134; number_of_response:1; }","duration":"1.688903124s","start":"2026-05-21T08:57:15.874433Z","end":"2026-05-21T08:57:17.563336Z","steps":["trace[1143720979] 'process raft request' (duration: 1.688519904s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:57:17.563611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:57:15.874409Z","time spent":"1.689069668s","remote":"127.0.0.1:36690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T08:57:17.563852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:57:15.764209Z","time spent":"1.799232176s","remote":"127.0.0.1:36690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T08:57:17.563883Z","caller":"traceutil/trace.go:171","msg":"trace[747313255] transaction","detail":"{read_only:false; response_revision:1133; number_of_response:1; }","duration":"1.797964944s","start":"2026-05-21T08:57:15.765904Z","end":"2026-05-21T08:57:17.563869Z","steps":["trace[747313255] 'process raft request' (duration: 1.797009389s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:57:17.56395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:57:15.765891Z","time spent":"1.798023795s","remote":"127.0.0.1:36690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T08:57:17.563833Z","caller":"traceutil/trace.go:171","msg":"trace[1295759536] transaction","detail":"{read_only:false; response_revision:1135; number_of_response:1; }","duration":"1.336172534s","start":"2026-05-21T08:57:16.22761Z","end":"2026-05-21T08:57:17.563782Z","steps":["trace[1295759536] 'process raft request' (duration: 1.335376264s)"],"step_count":1} {"level":"info","ts":"2026-05-21T08:57:17.564131Z","caller":"traceutil/trace.go:171","msg":"trace[1646533668] transaction","detail":"{read_only:false; response_revision:1131; number_of_response:1; }","duration":"1.801150134s","start":"2026-05-21T08:57:15.762956Z","end":"2026-05-21T08:57:17.564106Z","steps":["trace[1646533668] 'process raft request' (duration: 1.77705251s)","trace[1646533668] 'compare' (duration: 22.36908ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T08:57:17.564337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:57:15.762931Z","time spent":"1.80134777s","remote":"127.0.0.1:36690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T08:57:17.564479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:57:16.227584Z","time spent":"1.336580224s","remote":"127.0.0.1:36690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T08:57:17.951697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.638954ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-21T08:57:17.951856Z","caller":"traceutil/trace.go:171","msg":"trace[1693967464] linearizableReadLoop","detail":"{readStateIndex:1180; appliedIndex:1173; }","duration":"2.00266961s","start":"2026-05-21T08:57:15.949161Z","end":"2026-05-21T08:57:17.951831Z","steps":["trace[1693967464] 'read index received' (duration: 1.079239936s)","trace[1693967464] 'applied index is now lower than readState.Index' (duration: 923.427703ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T08:57:17.952304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.003129042s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T08:57:17.952391Z","caller":"traceutil/trace.go:171","msg":"trace[92252706] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:1135; }","duration":"2.003275385s","start":"2026-05-21T08:57:15.949098Z","end":"2026-05-21T08:57:17.952374Z","steps":["trace[92252706] 'agreement among raft nodes before linearized reading' (duration: 2.003121871s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:57:17.952419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.085906556s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T08:57:17.952507Z","caller":"traceutil/trace.go:171","msg":"trace[2090077189] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1135; }","duration":"1.08608773s","start":"2026-05-21T08:57:16.866399Z","end":"2026-05-21T08:57:17.952487Z","steps":["trace[2090077189] 'agreement among raft nodes before linearized reading' (duration: 1.085912446s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:57:17.952435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:57:15.949064Z","time spent":"2.003363188s","remote":"127.0.0.1:34992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true "} {"level":"warn","ts":"2026-05-21T08:57:17.952568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:57:16.866355Z","time spent":"1.086200074s","remote":"127.0.0.1:59424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-21T08:57:17.953244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.875225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-21T08:57:17.95342Z","caller":"traceutil/trace.go:171","msg":"trace[890351732] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1135; }","duration":"191.109411ms","start":"2026-05-21T08:57:17.762281Z","end":"2026-05-21T08:57:17.95339Z","steps":["trace[890351732] 'agreement among raft nodes before linearized reading' (duration: 190.052284ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T08:57:19.486622Z","caller":"traceutil/trace.go:171","msg":"trace[1925934892] transaction","detail":"{read_only:false; response_revision:1137; number_of_response:1; }","duration":"106.559495ms","start":"2026-05-21T08:57:19.380032Z","end":"2026-05-21T08:57:19.486591Z","steps":["trace[1925934892] 'process raft request' (duration: 106.372001ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:57:20.981903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.413200106s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-21T08:57:20.981991Z","caller":"traceutil/trace.go:171","msg":"trace[2122583703] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1137; }","duration":"1.413298299s","start":"2026-05-21T08:57:19.568672Z","end":"2026-05-21T08:57:20.98197Z","steps":["trace[2122583703] 'range keys from in-memory index tree' (duration: 1.412991512s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:57:20.982034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:57:19.568599Z","time spent":"1.413426572s","remote":"127.0.0.1:36690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-21T08:57:20.982465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.412903449s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-21T08:57:20.982588Z","caller":"traceutil/trace.go:171","msg":"trace[1354577213] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1137; }","duration":"1.413096044s","start":"2026-05-21T08:57:19.569464Z","end":"2026-05-21T08:57:20.98256Z","steps":["trace[1354577213] 'range keys from in-memory index tree' (duration: 1.412647162s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:57:20.982597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.412854377s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-05-21T08:57:20.982644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:57:19.569439Z","time spent":"1.413190036s","remote":"127.0.0.1:36690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-21T08:57:20.982632Z","caller":"traceutil/trace.go:171","msg":"trace[42331053] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1137; }","duration":"1.41294942s","start":"2026-05-21T08:57:19.569673Z","end":"2026-05-21T08:57:20.982623Z","steps":["trace[42331053] 'range keys from in-memory index tree' (duration: 1.412686563s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:57:20.983221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"934.670931ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T08:57:20.983305Z","caller":"traceutil/trace.go:171","msg":"trace[1319432014] linearizableReadLoop","detail":"{readStateIndex:1183; appliedIndex:1182; }","duration":"1.116446634s","start":"2026-05-21T08:57:19.86684Z","end":"2026-05-21T08:57:20.983286Z","steps":["trace[1319432014] 'read index received' (duration: 181.493226ms)","trace[1319432014] 'applied index is now lower than readState.Index' (duration: 934.952108ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T08:57:20.983329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:57:19.569632Z","time spent":"1.413638568s","remote":"127.0.0.1:36690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-21T08:57:20.983557Z","caller":"traceutil/trace.go:171","msg":"trace[821783384] transaction","detail":"{read_only:false; response_revision:1138; number_of_response:1; }","duration":"1.412016226s","start":"2026-05-21T08:57:19.571523Z","end":"2026-05-21T08:57:20.983539Z","steps":["trace[821783384] 'process raft request' (duration: 476.935485ms)","trace[821783384] 'compare' (duration: 933.89278ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T08:57:20.983631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:57:19.571503Z","time spent":"1.412092208s","remote":"127.0.0.1:36690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T08:57:20.983784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.116957367s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T08:57:20.983817Z","caller":"traceutil/trace.go:171","msg":"trace[1954338292] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1138; }","duration":"1.117037829s","start":"2026-05-21T08:57:19.86677Z","end":"2026-05-21T08:57:20.983808Z","steps":["trace[1954338292] 'agreement among raft nodes before linearized reading' (duration: 1.116978377s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:57:20.983844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:57:19.86673Z","time spent":"1.117108321s","remote":"127.0.0.1:59424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-21T08:57:20.98411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"969.787776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-21T08:57:20.984215Z","caller":"traceutil/trace.go:171","msg":"trace[660214868] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1138; }","duration":"969.884039ms","start":"2026-05-21T08:57:20.014273Z","end":"2026-05-21T08:57:20.984157Z","steps":["trace[660214868] 'agreement among raft nodes before linearized reading' (duration: 969.678373ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:57:20.984263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:57:20.01425Z","time spent":"970.003521ms","remote":"127.0.0.1:36690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-21T08:57:21.492453Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1698875524018258644,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-21T08:57:21.993311Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1698875524018258644,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-21T08:57:22.14275Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.154274689s","expected-duration":"1s"} {"level":"info","ts":"2026-05-21T08:57:22.210276Z","caller":"traceutil/trace.go:171","msg":"trace[2140069740] linearizableReadLoop","detail":"{readStateIndex:1187; appliedIndex:1183; }","duration":"1.218118466s","start":"2026-05-21T08:57:20.992134Z","end":"2026-05-21T08:57:22.210253Z","steps":["trace[2140069740] 'read index received' (duration: 1.150910453s)","trace[2140069740] 'applied index is now lower than readState.Index' (duration: 67.206753ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T08:57:22.21042Z","caller":"traceutil/trace.go:171","msg":"trace[968172846] transaction","detail":"{read_only:false; response_revision:1139; number_of_response:1; }","duration":"1.222071567s","start":"2026-05-21T08:57:20.988336Z","end":"2026-05-21T08:57:22.210407Z","steps":["trace[968172846] 'process raft request' (duration: 1.15469696s)","trace[968172846] 'compare' (duration: 66.922825ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T08:57:22.21053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:57:20.988287Z","time spent":"1.22215829s","remote":"127.0.0.1:36690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T08:57:22.21057Z","caller":"traceutil/trace.go:171","msg":"trace[581631520] transaction","detail":"{read_only:false; response_revision:1142; number_of_response:1; }","duration":"1.220577659s","start":"2026-05-21T08:57:20.989947Z","end":"2026-05-21T08:57:22.210525Z","steps":["trace[581631520] 'process raft request' (duration: 1.220266061s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:57:22.210759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:57:20.98994Z","time spent":"1.220714712s","remote":"127.0.0.1:36690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T08:57:22.210771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.218632379s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T08:57:22.210808Z","caller":"traceutil/trace.go:171","msg":"trace[1708807734] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1142; }","duration":"1.21868541s","start":"2026-05-21T08:57:20.992112Z","end":"2026-05-21T08:57:22.210797Z","steps":["trace[1708807734] 'agreement among raft nodes before linearized reading' (duration: 1.218628398s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:57:22.210836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:57:20.992104Z","time spent":"1.218726061s","remote":"127.0.0.1:59424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-21T08:57:22.210986Z","caller":"traceutil/trace.go:171","msg":"trace[604428326] transaction","detail":"{read_only:false; response_revision:1140; number_of_response:1; }","duration":"1.221259226s","start":"2026-05-21T08:57:20.989714Z","end":"2026-05-21T08:57:22.210973Z","steps":["trace[604428326] 'process raft request' (duration: 1.220396504s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:57:22.211045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:57:20.989682Z","time spent":"1.221328988s","remote":"127.0.0.1:36690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T08:57:22.211204Z","caller":"traceutil/trace.go:171","msg":"trace[1342279824] transaction","detail":"{read_only:false; response_revision:1141; number_of_response:1; }","duration":"1.221409409s","start":"2026-05-21T08:57:20.989782Z","end":"2026-05-21T08:57:22.211192Z","steps":["trace[1342279824] 'process raft request' (duration: 1.220395604s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:57:22.211275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:57:20.989763Z","time spent":"1.22146851s","remote":"127.0.0.1:36690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T08:57:22.211336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"760.595175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.95\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-21T08:57:22.211413Z","caller":"traceutil/trace.go:171","msg":"trace[238333886] range","detail":"{range_begin:/registry/masterleases/199.19.213.95; range_end:; response_count:1; response_revision:1142; }","duration":"760.735188ms","start":"2026-05-21T08:57:21.450657Z","end":"2026-05-21T08:57:22.211392Z","steps":["trace[238333886] 'agreement among raft nodes before linearized reading' (duration: 760.4119ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:57:22.211455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:57:21.45063Z","time spent":"760.813961ms","remote":"127.0.0.1:59440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.19.213.95\" "} {"level":"warn","ts":"2026-05-21T08:57:22.211664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"720.105583ms","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-05-21T08:57:22.211684Z","caller":"traceutil/trace.go:171","msg":"trace[990002458] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1142; }","duration":"720.163195ms","start":"2026-05-21T08:57:21.491515Z","end":"2026-05-21T08:57:22.211678Z","steps":["trace[990002458] 'agreement among raft nodes before linearized reading' (duration: 720.100683ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:57:22.211702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:57:21.491493Z","time spent":"720.205065ms","remote":"127.0.0.1:36690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-21T08:57:22.211713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"901.966728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-21T08:57:22.211766Z","caller":"traceutil/trace.go:171","msg":"trace[1756458898] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:1142; }","duration":"902.080631ms","start":"2026-05-21T08:57:21.309668Z","end":"2026-05-21T08:57:22.211749Z","steps":["trace[1756458898] 'agreement among raft nodes before linearized reading' (duration: 901.991319ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:57:22.211738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"927.995261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-21T08:57:22.211827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:57:21.309635Z","time spent":"902.171614ms","remote":"127.0.0.1:36762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":30,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"info","ts":"2026-05-21T08:57:22.211843Z","caller":"traceutil/trace.go:171","msg":"trace[1291187417] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:1142; }","duration":"928.77037ms","start":"2026-05-21T08:57:21.283046Z","end":"2026-05-21T08:57:22.211816Z","steps":["trace[1291187417] 'agreement among raft nodes before linearized reading' (duration: 927.97017ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:57:22.212029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:57:21.283025Z","time spent":"928.869063ms","remote":"127.0.0.1:43402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-21T08:57:22.5886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.42901ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T08:57:22.588738Z","caller":"traceutil/trace.go:171","msg":"trace[1861343725] transaction","detail":"{read_only:false; response_revision:1144; number_of_response:1; }","duration":"308.096612ms","start":"2026-05-21T08:57:22.280617Z","end":"2026-05-21T08:57:22.588713Z","steps":["trace[1861343725] 'process raft request' (duration: 103.403155ms)","trace[1861343725] 'compare' (duration: 204.297177ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T08:57:22.588816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:57:22.280591Z","time spent":"308.193035ms","remote":"127.0.0.1:59440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T08:57:24.426888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.335626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-21T08:57:24.426959Z","caller":"traceutil/trace.go:171","msg":"trace[1106907266] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1146; }","duration":"209.473379ms","start":"2026-05-21T08:57:24.217469Z","end":"2026-05-21T08:57:24.426943Z","steps":["trace[1106907266] 'range keys from in-memory index tree' (duration: 209.188512ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:57:24.426928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.29356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-21T08:57:24.427024Z","caller":"traceutil/trace.go:171","msg":"trace[579032632] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1146; }","duration":"210.439434ms","start":"2026-05-21T08:57:24.216565Z","end":"2026-05-21T08:57:24.427004Z","steps":["trace[579032632] 'range keys from in-memory index tree' (duration: 210.084885ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:57:24.426928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.52396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-05-21T08:57:24.427076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.385526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-21T08:57:24.427185Z","caller":"traceutil/trace.go:171","msg":"trace[1623173718] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1146; }","duration":"209.526581ms","start":"2026-05-21T08:57:24.217638Z","end":"2026-05-21T08:57:24.427165Z","steps":["trace[1623173718] 'range keys from in-memory index tree' (duration: 209.218142ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T08:57:24.42715Z","caller":"traceutil/trace.go:171","msg":"trace[1940412531] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1146; }","duration":"209.816048ms","start":"2026-05-21T08:57:24.217322Z","end":"2026-05-21T08:57:24.427138Z","steps":["trace[1940412531] 'range keys from in-memory index tree' (duration: 209.337716ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:57:24.427084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.443063ms","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-05-21T08:57:24.427315Z","caller":"traceutil/trace.go:171","msg":"trace[1320854330] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1146; }","duration":"117.7011ms","start":"2026-05-21T08:57:24.309604Z","end":"2026-05-21T08:57:24.427305Z","steps":["trace[1320854330] 'range keys from in-memory index tree' (duration: 117.337901ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T08:57:24.796387Z","caller":"traceutil/trace.go:171","msg":"trace[1165280438] transaction","detail":"{read_only:false; response_revision:1148; number_of_response:1; }","duration":"364.607872ms","start":"2026-05-21T08:57:24.431754Z","end":"2026-05-21T08:57:24.796362Z","steps":["trace[1165280438] 'process raft request' (duration: 364.343005ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:57:24.796555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:57:24.431735Z","time spent":"364.737076ms","remote":"127.0.0.1:36690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T08:57:24.796599Z","caller":"traceutil/trace.go:171","msg":"trace[1419268486] transaction","detail":"{read_only:false; response_revision:1149; number_of_response:1; }","duration":"364.438338ms","start":"2026-05-21T08:57:24.432149Z","end":"2026-05-21T08:57:24.796587Z","steps":["trace[1419268486] 'process raft request' (duration: 364.163121ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:57:24.796684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:57:24.432139Z","time spent":"364.49428ms","remote":"127.0.0.1:36690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T08:57:24.797017Z","caller":"traceutil/trace.go:171","msg":"trace[1173616623] transaction","detail":"{read_only:false; response_revision:1150; number_of_response:1; }","duration":"364.742916ms","start":"2026-05-21T08:57:24.43226Z","end":"2026-05-21T08:57:24.797003Z","steps":["trace[1173616623] 'process raft request' (duration: 364.285505ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:57:24.797096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:57:24.432248Z","time spent":"364.810908ms","remote":"127.0.0.1:36690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T08:57:24.797129Z","caller":"traceutil/trace.go:171","msg":"trace[1507326786] transaction","detail":"{read_only:false; response_revision:1151; number_of_response:1; }","duration":"364.968341ms","start":"2026-05-21T08:57:24.432118Z","end":"2026-05-21T08:57:24.797087Z","steps":["trace[1507326786] 'process raft request' (duration: 364.834468ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:57:24.797352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:57:24.432105Z","time spent":"365.125896ms","remote":"127.0.0.1:36690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T08:57:25.188673Z","caller":"traceutil/trace.go:171","msg":"trace[1733199612] transaction","detail":"{read_only:false; response_revision:1152; number_of_response:1; }","duration":"170.906136ms","start":"2026-05-21T08:57:25.017736Z","end":"2026-05-21T08:57:25.188642Z","steps":["trace[1733199612] 'process raft request' (duration: 170.714141ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:57:37.21774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.899567ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T08:57:37.217913Z","caller":"traceutil/trace.go:171","msg":"trace[360089550] transaction","detail":"{read_only:false; response_revision:1188; number_of_response:1; }","duration":"262.308045ms","start":"2026-05-21T08:57:36.955577Z","end":"2026-05-21T08:57:37.217885Z","steps":["trace[360089550] 'process raft request' (duration: 58.141492ms)","trace[360089550] 'compare' (duration: 203.595099ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T08:57:37.270461Z","caller":"traceutil/trace.go:171","msg":"trace[726148463] transaction","detail":"{read_only:false; response_revision:1189; number_of_response:1; }","duration":"312.965656ms","start":"2026-05-21T08:57:36.957459Z","end":"2026-05-21T08:57:37.270424Z","steps":["trace[726148463] 'process raft request' (duration: 312.469213ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:57:37.270658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:57:36.957416Z","time spent":"313.13595ms","remote":"127.0.0.1:36690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T08:57:37.270822Z","caller":"traceutil/trace.go:171","msg":"trace[1464608558] transaction","detail":"{read_only:false; response_revision:1190; number_of_response:1; }","duration":"311.457698ms","start":"2026-05-21T08:57:36.959304Z","end":"2026-05-21T08:57:37.270762Z","steps":["trace[1464608558] 'process raft request' (duration: 311.033377ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:57:37.271291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:57:36.959287Z","time spent":"311.709294ms","remote":"127.0.0.1:36690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T08:57:41.617447Z","caller":"traceutil/trace.go:171","msg":"trace[1274444161] transaction","detail":"{read_only:false; response_revision:1252; number_of_response:1; }","duration":"374.819192ms","start":"2026-05-21T08:57:41.242601Z","end":"2026-05-21T08:57:41.61742Z","steps":["trace[1274444161] 'process raft request' (duration: 374.669818ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:57:41.617609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:57:41.242576Z","time spent":"374.951406ms","remote":"127.0.0.1:36690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T08:57:41.618243Z","caller":"traceutil/trace.go:171","msg":"trace[429518479] linearizableReadLoop","detail":"{readStateIndex:1303; appliedIndex:1303; }","duration":"330.953135ms","start":"2026-05-21T08:57:41.287274Z","end":"2026-05-21T08:57:41.618227Z","steps":["trace[429518479] 'read index received' (duration: 330.944795ms)","trace[429518479] 'applied index is now lower than readState.Index' (duration: 7.1µs)"],"step_count":2} {"level":"warn","ts":"2026-05-21T08:57:41.618405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.123419ms","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-05-21T08:57:41.618437Z","caller":"traceutil/trace.go:171","msg":"trace[1212635903] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1252; }","duration":"331.196261ms","start":"2026-05-21T08:57:41.287231Z","end":"2026-05-21T08:57:41.618428Z","steps":["trace[1212635903] 'agreement among raft nodes before linearized reading' (duration: 331.064098ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:57:41.618464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:57:41.287211Z","time spent":"331.245833ms","remote":"127.0.0.1:36690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-21T08:57:41.623721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.191698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-21T08:57:41.623776Z","caller":"traceutil/trace.go:171","msg":"trace[1566976175] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1253; }","duration":"336.339472ms","start":"2026-05-21T08:57:41.287421Z","end":"2026-05-21T08:57:41.623761Z","steps":["trace[1566976175] 'agreement among raft nodes before linearized reading' (duration: 336.185958ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:57:41.623807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:57:41.287349Z","time spent":"336.450845ms","remote":"127.0.0.1:36690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-21T08:57:41.623851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.035319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-21T08:57:41.62389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.118177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.95\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-21T08:57:41.623909Z","caller":"traceutil/trace.go:171","msg":"trace[1220213260] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:1253; }","duration":"335.130851ms","start":"2026-05-21T08:57:41.288764Z","end":"2026-05-21T08:57:41.623895Z","steps":["trace[1220213260] 'agreement among raft nodes before linearized reading' (duration: 335.032729ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T08:57:41.623701Z","caller":"traceutil/trace.go:171","msg":"trace[624219533] transaction","detail":"{read_only:false; response_revision:1253; number_of_response:1; }","duration":"290.585516ms","start":"2026-05-21T08:57:41.333087Z","end":"2026-05-21T08:57:41.623673Z","steps":["trace[624219533] 'process raft request' (duration: 290.417931ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T08:57:41.623923Z","caller":"traceutil/trace.go:171","msg":"trace[803192840] range","detail":"{range_begin:/registry/masterleases/199.19.213.95; range_end:; response_count:1; response_revision:1253; }","duration":"172.194389ms","start":"2026-05-21T08:57:41.451721Z","end":"2026-05-21T08:57:41.623915Z","steps":["trace[803192840] 'agreement among raft nodes before linearized reading' (duration: 172.037015ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:57:41.623939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:57:41.28873Z","time spent":"335.202033ms","remote":"127.0.0.1:35092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true "} {"level":"warn","ts":"2026-05-21T08:57:43.475499Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1698875524018258909,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-21T08:57:43.976164Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1698875524018258909,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-21T08:57:44.061784Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.432750115s","expected-duration":"1s"} {"level":"info","ts":"2026-05-21T08:57:44.062233Z","caller":"traceutil/trace.go:171","msg":"trace[1137880952] transaction","detail":"{read_only:false; response_revision:1257; number_of_response:1; }","duration":"1.433287229s","start":"2026-05-21T08:57:42.628916Z","end":"2026-05-21T08:57:44.062203Z","steps":["trace[1137880952] 'process raft request' (duration: 1.433083504s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:57:44.062429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:57:42.628892Z","time spent":"1.433435183s","remote":"127.0.0.1:36690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T08:57:44.070567Z","caller":"traceutil/trace.go:171","msg":"trace[1725143802] linearizableReadLoop","detail":"{readStateIndex:1310; appliedIndex:1308; }","duration":"1.095430839s","start":"2026-05-21T08:57:42.975106Z","end":"2026-05-21T08:57:44.070537Z","steps":["trace[1725143802] 'read index received' (duration: 1.086945472s)","trace[1725143802] 'applied index is now lower than readState.Index' (duration: 8.484006ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T08:57:44.07076Z","caller":"traceutil/trace.go:171","msg":"trace[1939112113] transaction","detail":"{read_only:false; response_revision:1258; number_of_response:1; }","duration":"441.584975ms","start":"2026-05-21T08:57:43.62916Z","end":"2026-05-21T08:57:44.070745Z","steps":["trace[1939112113] 'process raft request' (duration: 441.216325ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:57:44.070961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.278415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-05-21T08:57:44.070989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:57:43.629141Z","time spent":"441.677448ms","remote":"127.0.0.1:36690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T08:57:44.071029Z","caller":"traceutil/trace.go:171","msg":"trace[62328425] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1258; }","duration":"448.392317ms","start":"2026-05-21T08:57:43.622621Z","end":"2026-05-21T08:57:44.071013Z","steps":["trace[62328425] 'agreement among raft nodes before linearized reading' (duration: 448.217883ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:57:44.071061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:57:43.622589Z","time spent":"448.46596ms","remote":"127.0.0.1:36690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-21T08:57:44.071095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.027068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-21T08:57:44.071111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.735189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-21T08:57:44.070953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.371524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T08:57:44.071164Z","caller":"traceutil/trace.go:171","msg":"trace[1903991838] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1258; }","duration":"437.09512ms","start":"2026-05-21T08:57:43.634048Z","end":"2026-05-21T08:57:44.071143Z","steps":["trace[1903991838] 'agreement among raft nodes before linearized reading' (duration: 436.915635ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T08:57:44.071178Z","caller":"traceutil/trace.go:171","msg":"trace[988704096] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1258; }","duration":"205.663631ms","start":"2026-05-21T08:57:43.865507Z","end":"2026-05-21T08:57:44.07117Z","steps":["trace[988704096] 'agreement among raft nodes before linearized reading' (duration: 205.360884ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T08:57:44.071174Z","caller":"traceutil/trace.go:171","msg":"trace[1265978136] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1258; }","duration":"443.809371ms","start":"2026-05-21T08:57:43.627344Z","end":"2026-05-21T08:57:44.071154Z","steps":["trace[1265978136] 'agreement among raft nodes before linearized reading' (duration: 443.663787ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:57:44.071202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:57:43.634017Z","time spent":"437.176852ms","remote":"127.0.0.1:36690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-21T08:57:44.071218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:57:43.627333Z","time spent":"443.874332ms","remote":"127.0.0.1:36690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-21T08:57:44.071381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.0962743s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-21T08:57:44.071415Z","caller":"traceutil/trace.go:171","msg":"trace[730518591] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1258; }","duration":"1.096343501s","start":"2026-05-21T08:57:42.97506Z","end":"2026-05-21T08:57:44.071403Z","steps":["trace[730518591] 'agreement among raft nodes before linearized reading' (duration: 1.095834238s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:57:44.071455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:57:42.975037Z","time spent":"1.096399192s","remote":"127.0.0.1:36690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-21T08:57:44.854887Z","caller":"traceutil/trace.go:171","msg":"trace[968101236] transaction","detail":"{read_only:false; response_revision:1259; number_of_response:1; }","duration":"779.411574ms","start":"2026-05-21T08:57:44.075377Z","end":"2026-05-21T08:57:44.854788Z","steps":["trace[968101236] 'process raft request' (duration: 779.229529ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:57:44.855082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:57:44.075358Z","time spent":"779.610789ms","remote":"127.0.0.1:36690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T08:57:44.85555Z","caller":"traceutil/trace.go:171","msg":"trace[1989917364] transaction","detail":"{read_only:false; response_revision:1260; number_of_response:1; }","duration":"778.245175ms","start":"2026-05-21T08:57:44.07727Z","end":"2026-05-21T08:57:44.855515Z","steps":["trace[1989917364] 'process raft request' (duration: 778.04902ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T08:57:44.85569Z","caller":"traceutil/trace.go:171","msg":"trace[159540769] transaction","detail":"{read_only:false; response_revision:1261; number_of_response:1; }","duration":"778.318026ms","start":"2026-05-21T08:57:44.077361Z","end":"2026-05-21T08:57:44.855679Z","steps":["trace[159540769] 'process raft request' (duration: 778.099931ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:57:44.855759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:57:44.077356Z","time spent":"778.364717ms","remote":"127.0.0.1:36690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T08:57:44.855831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:57:44.077246Z","time spent":"778.391908ms","remote":"127.0.0.1:36690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T08:57:44.856132Z","caller":"traceutil/trace.go:171","msg":"trace[84850126] transaction","detail":"{read_only:false; response_revision:1262; number_of_response:1; }","duration":"778.576632ms","start":"2026-05-21T08:57:44.077545Z","end":"2026-05-21T08:57:44.856122Z","steps":["trace[84850126] 'process raft request' (duration: 778.08341ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:57:44.856229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:57:44.077519Z","time spent":"778.641834ms","remote":"127.0.0.1:36690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T08:57:44.856346Z","caller":"traceutil/trace.go:171","msg":"trace[81012913] transaction","detail":"{read_only:false; response_revision:1263; number_of_response:1; }","duration":"166.675869ms","start":"2026-05-21T08:57:44.689663Z","end":"2026-05-21T08:57:44.856338Z","steps":["trace[81012913] 'process raft request' (duration: 166.314829ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T08:57:55.090244Z","caller":"traceutil/trace.go:171","msg":"trace[35707562] linearizableReadLoop","detail":"{readStateIndex:1347; appliedIndex:1345; }","duration":"163.317883ms","start":"2026-05-21T08:57:54.926901Z","end":"2026-05-21T08:57:55.090219Z","steps":["trace[35707562] 'read index received' (duration: 163.073377ms)","trace[35707562] 'applied index is now lower than readState.Index' (duration: 243.156µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T08:57:55.090275Z","caller":"traceutil/trace.go:171","msg":"trace[316113465] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"168.255828ms","start":"2026-05-21T08:57:54.921995Z","end":"2026-05-21T08:57:55.09025Z","steps":["trace[316113465] 'process raft request' (duration: 168.033342ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T08:57:55.090322Z","caller":"traceutil/trace.go:171","msg":"trace[316965328] transaction","detail":"{read_only:false; response_revision:1293; number_of_response:1; }","duration":"168.257099ms","start":"2026-05-21T08:57:54.922027Z","end":"2026-05-21T08:57:55.090284Z","steps":["trace[316965328] 'process raft request' (duration: 168.141536ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:57:55.090505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.577469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-21T08:57:55.090607Z","caller":"traceutil/trace.go:171","msg":"trace[1617175403] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1293; }","duration":"163.730853ms","start":"2026-05-21T08:57:54.926854Z","end":"2026-05-21T08:57:55.090585Z","steps":["trace[1617175403] 'agreement among raft nodes before linearized reading' (duration: 163.509808ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:57:55.090722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.768948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-21T08:57:55.090833Z","caller":"traceutil/trace.go:171","msg":"trace[910883088] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1293; }","duration":"160.937562ms","start":"2026-05-21T08:57:54.929866Z","end":"2026-05-21T08:57:55.090803Z","steps":["trace[910883088] 'agreement among raft nodes before linearized reading' (duration: 160.603203ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:57:55.090722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.257071ms","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-05-21T08:57:55.090968Z","caller":"traceutil/trace.go:171","msg":"trace[1450526695] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1293; }","duration":"132.570258ms","start":"2026-05-21T08:57:54.958375Z","end":"2026-05-21T08:57:55.090945Z","steps":["trace[1450526695] 'agreement among raft nodes before linearized reading' (duration: 132.134377ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T08:57:55.317883Z","caller":"traceutil/trace.go:171","msg":"trace[1839249310] transaction","detail":"{read_only:false; response_revision:1294; number_of_response:1; }","duration":"222.728997ms","start":"2026-05-21T08:57:55.095122Z","end":"2026-05-21T08:57:55.317851Z","steps":["trace[1839249310] 'process raft request' (duration: 222.513092ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T08:57:55.391773Z","caller":"traceutil/trace.go:171","msg":"trace[705639072] transaction","detail":"{read_only:false; response_revision:1296; number_of_response:1; }","duration":"197.122523ms","start":"2026-05-21T08:57:55.194623Z","end":"2026-05-21T08:57:55.391745Z","steps":["trace[705639072] 'process raft request' (duration: 197.074022ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T08:57:55.392033Z","caller":"traceutil/trace.go:171","msg":"trace[1102347620] transaction","detail":"{read_only:false; response_revision:1295; number_of_response:1; }","duration":"296.126426ms","start":"2026-05-21T08:57:55.09587Z","end":"2026-05-21T08:57:55.391996Z","steps":["trace[1102347620] 'process raft request' (duration: 295.686325ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T08:57:57.207849Z","caller":"traceutil/trace.go:171","msg":"trace[856230194] linearizableReadLoop","detail":"{readStateIndex:1353; appliedIndex:1352; }","duration":"113.76887ms","start":"2026-05-21T08:57:57.094047Z","end":"2026-05-21T08:57:57.207816Z","steps":["trace[856230194] 'read index received' (duration: 52.818947ms)","trace[856230194] 'applied index is now lower than readState.Index' (duration: 60.948193ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T08:57:57.208114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.034867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-05-21T08:57:57.208165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.75809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-21T08:57:57.208227Z","caller":"traceutil/trace.go:171","msg":"trace[1411241296] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1298; }","duration":"113.863003ms","start":"2026-05-21T08:57:57.094353Z","end":"2026-05-21T08:57:57.208216Z","steps":["trace[1411241296] 'agreement among raft nodes before linearized reading' (duration: 113.653437ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T08:57:57.208224Z","caller":"traceutil/trace.go:171","msg":"trace[1717277062] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1298; }","duration":"114.203531ms","start":"2026-05-21T08:57:57.094Z","end":"2026-05-21T08:57:57.208204Z","steps":["trace[1717277062] 'agreement among raft nodes before linearized reading' (duration: 113.907304ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T08:57:57.336529Z","caller":"traceutil/trace.go:171","msg":"trace[751142725] transaction","detail":"{read_only:false; response_revision:1299; number_of_response:1; }","duration":"123.904619ms","start":"2026-05-21T08:57:57.212597Z","end":"2026-05-21T08:57:57.336502Z","steps":["trace[751142725] 'process raft request' (duration: 123.648461ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T08:57:57.337797Z","caller":"traceutil/trace.go:171","msg":"trace[1039698857] transaction","detail":"{read_only:false; response_revision:1300; number_of_response:1; }","duration":"124.478132ms","start":"2026-05-21T08:57:57.213297Z","end":"2026-05-21T08:57:57.337775Z","steps":["trace[1039698857] 'process raft request' (duration: 124.029611ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:57:58.499967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.958928ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T08:57:58.500102Z","caller":"traceutil/trace.go:171","msg":"trace[1049826548] transaction","detail":"{read_only:false; response_revision:1305; number_of_response:1; }","duration":"218.166771ms","start":"2026-05-21T08:57:58.281911Z","end":"2026-05-21T08:57:58.500078Z","steps":["trace[1049826548] 'process raft request' (duration: 108.980848ms)","trace[1049826548] 'compare' (duration: 108.811263ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T08:57:59.851082Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1698875524018259045,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-21T08:57:59.854611Z","caller":"traceutil/trace.go:171","msg":"trace[1003906408] transaction","detail":"{read_only:false; response_revision:1306; number_of_response:1; }","duration":"507.934135ms","start":"2026-05-21T08:57:59.346646Z","end":"2026-05-21T08:57:59.85458Z","steps":["trace[1003906408] 'process raft request' (duration: 507.74409ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:57:59.85493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:57:59.346593Z","time spent":"508.084708ms","remote":"127.0.0.1:36690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T08:57:59.95129Z","caller":"traceutil/trace.go:171","msg":"trace[707054765] linearizableReadLoop","detail":"{readStateIndex:1362; appliedIndex:1360; }","duration":"601.002627ms","start":"2026-05-21T08:57:59.35026Z","end":"2026-05-21T08:57:59.951262Z","steps":["trace[707054765] 'read index received' (duration: 504.112428ms)","trace[707054765] 'applied index is now lower than readState.Index' (duration: 96.888859ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T08:57:59.951362Z","caller":"traceutil/trace.go:171","msg":"trace[91381379] transaction","detail":"{read_only:false; response_revision:1307; number_of_response:1; }","duration":"603.061ms","start":"2026-05-21T08:57:59.348258Z","end":"2026-05-21T08:57:59.951319Z","steps":["trace[91381379] 'process raft request' (duration: 602.839264ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:57:59.951559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.36881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-21T08:57:59.951594Z","caller":"traceutil/trace.go:171","msg":"trace[1300224945] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1307; }","duration":"545.476972ms","start":"2026-05-21T08:57:59.406106Z","end":"2026-05-21T08:57:59.951583Z","steps":["trace[1300224945] 'agreement among raft nodes before linearized reading' (duration: 545.318598ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:57:59.951583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:57:59.348227Z","time spent":"603.231133ms","remote":"127.0.0.1:36690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T08:57:59.951616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.481162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-21T08:57:59.951654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:57:59.406044Z","time spent":"545.592766ms","remote":"127.0.0.1:36690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-21T08:57:59.9517Z","caller":"traceutil/trace.go:171","msg":"trace[137469449] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:1307; }","duration":"102.621016ms","start":"2026-05-21T08:57:59.849054Z","end":"2026-05-21T08:57:59.951675Z","steps":["trace[137469449] 'agreement among raft nodes before linearized reading' (duration: 102.463632ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:57:59.952783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"602.491155ms","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-05-21T08:57:59.952899Z","caller":"traceutil/trace.go:171","msg":"trace[380916060] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1307; }","duration":"602.66816ms","start":"2026-05-21T08:57:59.350207Z","end":"2026-05-21T08:57:59.952875Z","steps":["trace[380916060] 'agreement among raft nodes before linearized reading' (duration: 601.197783ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:57:59.952961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:57:59.350189Z","time spent":"602.754311ms","remote":"127.0.0.1:36690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-21T08:58:00.495561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.246671ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T08:58:00.495843Z","caller":"traceutil/trace.go:171","msg":"trace[772209922] transaction","detail":"{read_only:false; response_revision:1310; number_of_response:1; }","duration":"291.766165ms","start":"2026-05-21T08:58:00.204054Z","end":"2026-05-21T08:58:00.49582Z","steps":["trace[772209922] 'process raft request' (duration: 291.703314ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T08:58:00.495877Z","caller":"traceutil/trace.go:171","msg":"trace[300780793] transaction","detail":"{read_only:false; response_revision:1308; number_of_response:1; }","duration":"539.256472ms","start":"2026-05-21T08:57:59.956566Z","end":"2026-05-21T08:58:00.495822Z","steps":["trace[300780793] 'process raft request' (duration: 437.629213ms)","trace[300780793] 'compare' (duration: 101.067105ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T08:58:00.495904Z","caller":"traceutil/trace.go:171","msg":"trace[1229349263] transaction","detail":"{read_only:false; response_revision:1309; number_of_response:1; }","duration":"539.11536ms","start":"2026-05-21T08:57:59.956762Z","end":"2026-05-21T08:58:00.495877Z","steps":["trace[1229349263] 'process raft request' (duration: 538.937235ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:58:00.496136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:57:59.956552Z","time spent":"539.457578ms","remote":"127.0.0.1:36690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T08:58:00.496187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:57:59.956754Z","time spent":"539.338035ms","remote":"127.0.0.1:36690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T08:58:01.799727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:58:01.45428Z","time spent":"345.441414ms","remote":"127.0.0.1:59440","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-05-21T08:58:02.814732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"658.729728ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T08:58:02.815103Z","caller":"traceutil/trace.go:171","msg":"trace[1542416117] transaction","detail":"{read_only:false; response_revision:1313; number_of_response:1; }","duration":"1.0139225s","start":"2026-05-21T08:58:01.801166Z","end":"2026-05-21T08:58:02.815088Z","steps":["trace[1542416117] 'process raft request' (duration: 354.71736ms)","trace[1542416117] 'compare' (duration: 658.289057ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T08:58:02.815128Z","caller":"traceutil/trace.go:171","msg":"trace[434453849] linearizableReadLoop","detail":"{readStateIndex:1369; appliedIndex:1368; }","duration":"955.449771ms","start":"2026-05-21T08:58:01.859611Z","end":"2026-05-21T08:58:02.815061Z","steps":["trace[434453849] 'read index received' (duration: 296.290952ms)","trace[434453849] 'applied index is now lower than readState.Index' (duration: 659.154669ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T08:58:02.815186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:58:01.801141Z","time spent":"1.014004343s","remote":"127.0.0.1:59440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T08:58:02.81545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"949.856567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T08:58:02.81552Z","caller":"traceutil/trace.go:171","msg":"trace[1296861991] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1314; }","duration":"949.999432ms","start":"2026-05-21T08:58:01.865503Z","end":"2026-05-21T08:58:02.815503Z","steps":["trace[1296861991] 'agreement among raft nodes before linearized reading' (duration: 949.857088ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:58:02.815508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"955.878261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-21T08:58:02.815027Z","caller":"traceutil/trace.go:171","msg":"trace[1455020062] transaction","detail":"{read_only:false; response_revision:1314; number_of_response:1; }","duration":"308.836621ms","start":"2026-05-21T08:58:02.506171Z","end":"2026-05-21T08:58:02.815007Z","steps":["trace[1455020062] 'process raft request' (duration: 308.708488ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:58:02.815636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.846223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-21T08:58:02.815658Z","caller":"traceutil/trace.go:171","msg":"trace[1891223439] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1314; }","duration":"312.914975ms","start":"2026-05-21T08:58:02.502739Z","end":"2026-05-21T08:58:02.815654Z","steps":["trace[1891223439] 'agreement among raft nodes before linearized reading' (duration: 312.862473ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:58:02.815669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"858.954581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-05-21T08:58:02.815681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:58:02.502707Z","time spent":"312.968456ms","remote":"127.0.0.1:36690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-21T08:58:02.815696Z","caller":"traceutil/trace.go:171","msg":"trace[290211946] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1314; }","duration":"859.049103ms","start":"2026-05-21T08:58:01.956637Z","end":"2026-05-21T08:58:02.815686Z","steps":["trace[290211946] 'agreement among raft nodes before linearized reading' (duration: 858.991692ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:58:02.815719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:58:01.95661Z","time spent":"859.103254ms","remote":"127.0.0.1:36690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-21T08:58:02.81558Z","caller":"traceutil/trace.go:171","msg":"trace[1671269809] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1314; }","duration":"956.007034ms","start":"2026-05-21T08:58:01.859558Z","end":"2026-05-21T08:58:02.815565Z","steps":["trace[1671269809] 'agreement among raft nodes before linearized reading' (duration: 955.681136ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:58:02.815706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:58:01.865479Z","time spent":"950.208196ms","remote":"127.0.0.1:59418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-21T08:58:02.815781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:58:01.859537Z","time spent":"956.22753ms","remote":"127.0.0.1:36690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-21T08:58:02.815508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.702509ms","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-05-21T08:58:02.815929Z","caller":"traceutil/trace.go:171","msg":"trace[1732663029] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1314; }","duration":"313.172531ms","start":"2026-05-21T08:58:02.502739Z","end":"2026-05-21T08:58:02.815911Z","steps":["trace[1732663029] 'agreement among raft nodes before linearized reading' (duration: 312.670748ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:58:02.815967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:58:02.502707Z","time spent":"313.249293ms","remote":"127.0.0.1:36690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-21T08:58:02.815598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.077496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-21T08:58:02.816093Z","caller":"traceutil/trace.go:171","msg":"trace[150212265] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1314; }","duration":"245.584629ms","start":"2026-05-21T08:58:02.570489Z","end":"2026-05-21T08:58:02.816073Z","steps":["trace[150212265] 'agreement among raft nodes before linearized reading' (duration: 245.065275ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:58:02.816823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:58:02.506137Z","time spent":"309.494037ms","remote":"127.0.0.1:36690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T08:58:03.091566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.316578ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T08:58:03.09179Z","caller":"traceutil/trace.go:171","msg":"trace[673437751] transaction","detail":"{read_only:false; response_revision:1316; number_of_response:1; }","duration":"270.201766ms","start":"2026-05-21T08:58:02.821565Z","end":"2026-05-21T08:58:03.091767Z","steps":["trace[673437751] 'process raft request' (duration: 270.124324ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T08:58:03.092224Z","caller":"traceutil/trace.go:171","msg":"trace[1013640917] transaction","detail":"{read_only:false; response_revision:1315; number_of_response:1; }","duration":"270.663218ms","start":"2026-05-21T08:58:02.821551Z","end":"2026-05-21T08:58:03.092215Z","steps":["trace[1013640917] 'process raft request' (duration: 94.530839ms)","trace[1013640917] 'compare' (duration: 175.187935ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T08:58:03.300139Z","caller":"traceutil/trace.go:171","msg":"trace[1343184946] transaction","detail":"{read_only:false; response_revision:1317; number_of_response:1; }","duration":"478.298569ms","start":"2026-05-21T08:58:02.821783Z","end":"2026-05-21T08:58:03.300081Z","steps":["trace[1343184946] 'process raft request' (duration: 426.854058ms)","trace[1343184946] 'compare' (duration: 51.144263ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T08:58:03.300431Z","caller":"traceutil/trace.go:171","msg":"trace[1757872740] transaction","detail":"{read_only:false; response_revision:1318; number_of_response:1; }","duration":"478.604367ms","start":"2026-05-21T08:58:02.821808Z","end":"2026-05-21T08:58:03.300412Z","steps":["trace[1757872740] 'process raft request' (duration: 478.442043ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:58:03.300465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:58:02.821766Z","time spent":"478.570796ms","remote":"127.0.0.1:36690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T08:58:03.300573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.962915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T08:58:03.300621Z","caller":"traceutil/trace.go:171","msg":"trace[733726513] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1319; }","duration":"435.085129ms","start":"2026-05-21T08:58:02.865526Z","end":"2026-05-21T08:58:03.300611Z","steps":["trace[733726513] 'agreement among raft nodes before linearized reading' (duration: 434.944685ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:58:03.300658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:58:02.865499Z","time spent":"435.148131ms","remote":"127.0.0.1:59424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-21T08:58:03.300662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:58:02.821793Z","time spent":"478.696229ms","remote":"127.0.0.1:36690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T08:58:03.300803Z","caller":"traceutil/trace.go:171","msg":"trace[1315131590] transaction","detail":"{read_only:false; response_revision:1319; number_of_response:1; }","duration":"478.954436ms","start":"2026-05-21T08:58:02.821824Z","end":"2026-05-21T08:58:03.300778Z","steps":["trace[1315131590] 'process raft request' (duration: 478.491544ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:58:03.300897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:58:02.821812Z","time spent":"479.038168ms","remote":"127.0.0.1:36690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T08:58:03.300416Z","caller":"traceutil/trace.go:171","msg":"trace[1318884746] linearizableReadLoop","detail":"{readStateIndex:1375; appliedIndex:1370; }","duration":"434.804441ms","start":"2026-05-21T08:58:02.865582Z","end":"2026-05-21T08:58:03.300387Z","steps":["trace[1318884746] 'read index received' (duration: 50.473607ms)","trace[1318884746] 'applied index is now lower than readState.Index' (duration: 384.329734ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T08:58:04.477051Z","caller":"traceutil/trace.go:171","msg":"trace[14018740] transaction","detail":"{read_only:false; response_revision:1320; number_of_response:1; }","duration":"205.094677ms","start":"2026-05-21T08:58:04.271922Z","end":"2026-05-21T08:58:04.477017Z","steps":["trace[14018740] 'process raft request' (duration: 204.855981ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:58:04.969867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.910199ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-21T08:58:04.969957Z","caller":"traceutil/trace.go:171","msg":"trace[1923308025] linearizableReadLoop","detail":"{readStateIndex:1377; appliedIndex:1376; }","duration":"401.179965ms","start":"2026-05-21T08:58:04.568761Z","end":"2026-05-21T08:58:04.969941Z","steps":["trace[1923308025] 'read index received' (duration: 142.02311ms)","trace[1923308025] 'applied index is now lower than readState.Index' (duration: 259.155635ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T08:58:04.970014Z","caller":"traceutil/trace.go:171","msg":"trace[926746851] transaction","detail":"{read_only:false; response_revision:1321; number_of_response:1; }","duration":"430.397269ms","start":"2026-05-21T08:58:04.53961Z","end":"2026-05-21T08:58:04.970008Z","steps":["trace[926746851] 'process raft request' (duration: 171.256884ms)","trace[926746851] 'compare' (duration: 258.416996ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T08:58:04.970051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:58:04.539586Z","time spent":"430.44968ms","remote":"127.0.0.1:36488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":751,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-21T08:58:04.970277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.521514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T08:58:04.970304Z","caller":"traceutil/trace.go:171","msg":"trace[154030160] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:1321; }","duration":"401.602516ms","start":"2026-05-21T08:58:04.568696Z","end":"2026-05-21T08:58:04.970299Z","steps":["trace[154030160] 'agreement among raft nodes before linearized reading' (duration: 401.508143ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:58:04.970327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:58:04.56867Z","time spent":"401.652258ms","remote":"127.0.0.1:36642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"warn","ts":"2026-05-21T08:58:04.977773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.621489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T08:58:04.977867Z","caller":"traceutil/trace.go:171","msg":"trace[1281259086] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1322; }","duration":"110.763602ms","start":"2026-05-21T08:58:04.867082Z","end":"2026-05-21T08:58:04.977846Z","steps":["trace[1281259086] 'agreement among raft nodes before linearized reading' (duration: 110.638939ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:58:04.978287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.569472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T08:58:04.978342Z","caller":"traceutil/trace.go:171","msg":"trace[635993953] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:1322; }","duration":"129.670894ms","start":"2026-05-21T08:58:04.848655Z","end":"2026-05-21T08:58:04.978326Z","steps":["trace[635993953] 'agreement among raft nodes before linearized reading' (duration: 129.590172ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:58:04.978773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.468676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T08:58:04.978865Z","caller":"traceutil/trace.go:171","msg":"trace[1947603963] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1322; }","duration":"390.705248ms","start":"2026-05-21T08:58:04.588148Z","end":"2026-05-21T08:58:04.978853Z","steps":["trace[1947603963] 'agreement among raft nodes before linearized reading' (duration: 389.484386ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:58:04.978909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:58:04.588122Z","time spent":"390.77667ms","remote":"127.0.0.1:36784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":0,"response size":28,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" "} {"level":"info","ts":"2026-05-21T08:58:04.979837Z","caller":"traceutil/trace.go:171","msg":"trace[376401344] transaction","detail":"{read_only:false; response_revision:1322; number_of_response:1; }","duration":"155.764519ms","start":"2026-05-21T08:58:04.822389Z","end":"2026-05-21T08:58:04.978154Z","steps":["trace[376401344] 'process raft request' (duration: 155.094902ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:58:06.539495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.239922ms","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":"info","ts":"2026-05-21T08:58:06.539624Z","caller":"traceutil/trace.go:171","msg":"trace[1490522872] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:1341; }","duration":"114.447017ms","start":"2026-05-21T08:58:06.425155Z","end":"2026-05-21T08:58:06.539602Z","steps":["trace[1490522872] 'count revisions from in-memory index tree' (duration: 114.111258ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T08:58:11.344646Z","caller":"traceutil/trace.go:171","msg":"trace[675970814] linearizableReadLoop","detail":"{readStateIndex:1439; appliedIndex:1438; }","duration":"131.246677ms","start":"2026-05-21T08:58:11.213369Z","end":"2026-05-21T08:58:11.344616Z","steps":["trace[675970814] 'read index received' (duration: 131.016281ms)","trace[675970814] 'applied index is now lower than readState.Index' (duration: 228.926µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T08:58:11.344744Z","caller":"traceutil/trace.go:171","msg":"trace[1693324359] transaction","detail":"{read_only:false; response_revision:1382; number_of_response:1; }","duration":"133.70861ms","start":"2026-05-21T08:58:11.210993Z","end":"2026-05-21T08:58:11.344702Z","steps":["trace[1693324359] 'process raft request' (duration: 133.378191ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:58:11.344965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.574135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-21T08:58:11.345012Z","caller":"traceutil/trace.go:171","msg":"trace[589734583] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1382; }","duration":"131.681608ms","start":"2026-05-21T08:58:11.213317Z","end":"2026-05-21T08:58:11.344998Z","steps":["trace[589734583] 'agreement among raft nodes before linearized reading' (duration: 131.482643ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T08:58:15.317012Z","caller":"traceutil/trace.go:171","msg":"trace[1586329559] transaction","detail":"{read_only:false; response_revision:1416; number_of_response:1; }","duration":"297.596466ms","start":"2026-05-21T08:58:15.01939Z","end":"2026-05-21T08:58:15.316986Z","steps":["trace[1586329559] 'process raft request' (duration: 297.419141ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:58:16.527788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.047757578s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T08:58:16.527966Z","caller":"traceutil/trace.go:171","msg":"trace[666481369] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1416; }","duration":"1.047971453s","start":"2026-05-21T08:58:15.47997Z","end":"2026-05-21T08:58:16.527942Z","steps":["trace[666481369] 'range keys from in-memory index tree' (duration: 1.047727997s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:58:16.528337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.097119613s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-21T08:58:16.528412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"864.120559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"warn","ts":"2026-05-21T08:58:16.528384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.099425632s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-21T08:58:16.528557Z","caller":"traceutil/trace.go:171","msg":"trace[1053422570] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1416; }","duration":"1.099871883s","start":"2026-05-21T08:58:15.428646Z","end":"2026-05-21T08:58:16.528518Z","steps":["trace[1053422570] 'range keys from in-memory index tree' (duration: 1.099100373s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:58:16.528564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.164114917s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-05-21T08:58:16.528568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.095457261s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-21T08:58:16.528628Z","caller":"traceutil/trace.go:171","msg":"trace[1962166682] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1416; }","duration":"1.095566004s","start":"2026-05-21T08:58:15.433049Z","end":"2026-05-21T08:58:16.528615Z","steps":["trace[1962166682] 'range keys from in-memory index tree' (duration: 1.095295287s)"],"step_count":1} {"level":"info","ts":"2026-05-21T08:58:16.528626Z","caller":"traceutil/trace.go:171","msg":"trace[1241918756] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1416; }","duration":"1.164209429s","start":"2026-05-21T08:58:15.364397Z","end":"2026-05-21T08:58:16.528606Z","steps":["trace[1241918756] 'range keys from in-memory index tree' (duration: 1.163902021s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:58:16.528686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:58:15.433014Z","time spent":"1.095656636s","remote":"127.0.0.1:36690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-21T08:58:16.528692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:58:15.364372Z","time spent":"1.164303301s","remote":"127.0.0.1:36690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-21T08:58:16.528697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:58:15.428587Z","time spent":"1.100032577s","remote":"127.0.0.1:36690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-21T08:58:16.528851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"661.5031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T08:58:16.528905Z","caller":"traceutil/trace.go:171","msg":"trace[1240284176] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1416; }","duration":"661.604022ms","start":"2026-05-21T08:58:15.867287Z","end":"2026-05-21T08:58:16.528891Z","steps":["trace[1240284176] 'range keys from in-memory index tree' (duration: 661.407846ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:58:16.528948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:58:15.867258Z","time spent":"661.678163ms","remote":"127.0.0.1:59424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-21T08:58:16.528467Z","caller":"traceutil/trace.go:171","msg":"trace[1969653094] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1416; }","duration":"864.283553ms","start":"2026-05-21T08:58:15.664166Z","end":"2026-05-21T08:58:16.528449Z","steps":["trace[1969653094] 'range keys from in-memory index tree' (duration: 863.570405ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:58:16.529066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.100428658s","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-05-21T08:58:16.529119Z","caller":"traceutil/trace.go:171","msg":"trace[752773894] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1416; }","duration":"1.10053357s","start":"2026-05-21T08:58:15.428574Z","end":"2026-05-21T08:58:16.529107Z","steps":["trace[752773894] 'range keys from in-memory index tree' (duration: 1.100220243s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:58:16.529084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:58:15.664142Z","time spent":"864.9008ms","remote":"127.0.0.1:36690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":558,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-21T08:58:16.529153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:58:15.428547Z","time spent":"1.100598022s","remote":"127.0.0.1:36690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-21T08:58:16.52845Z","caller":"traceutil/trace.go:171","msg":"trace[703760640] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1416; }","duration":"1.097268187s","start":"2026-05-21T08:58:15.431151Z","end":"2026-05-21T08:58:16.528419Z","steps":["trace[703760640] 'range keys from in-memory index tree' (duration: 1.096911428s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:58:16.529316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:58:15.431139Z","time spent":"1.09815772s","remote":"127.0.0.1:36690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-21T08:58:16.527654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"615.245623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-21T08:58:16.529395Z","caller":"traceutil/trace.go:171","msg":"trace[1835381048] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:1416; }","duration":"617.072229ms","start":"2026-05-21T08:58:15.912306Z","end":"2026-05-21T08:58:16.529378Z","steps":["trace[1835381048] 'count revisions from in-memory index tree' (duration: 615.019896ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:58:16.529447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:58:15.912272Z","time spent":"617.151521ms","remote":"127.0.0.1:36690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":13,"response size":30,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"warn","ts":"2026-05-21T08:58:16.744968Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1698875524018259256,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-21T08:58:16.898828Z","caller":"traceutil/trace.go:171","msg":"trace[1642100860] transaction","detail":"{read_only:false; response_revision:1417; number_of_response:1; }","duration":"971.833018ms","start":"2026-05-21T08:58:15.926972Z","end":"2026-05-21T08:58:16.898805Z","steps":["trace[1642100860] 'process raft request' (duration: 971.650384ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:58:16.898983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:58:15.92694Z","time spent":"971.957412ms","remote":"127.0.0.1:36690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T08:58:16.904503Z","caller":"traceutil/trace.go:171","msg":"trace[133034921] transaction","detail":"{read_only:false; response_revision:1419; number_of_response:1; }","duration":"370.09422ms","start":"2026-05-21T08:58:16.534376Z","end":"2026-05-21T08:58:16.904471Z","steps":["trace[133034921] 'process raft request' (duration: 370.012428ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T08:58:16.904538Z","caller":"traceutil/trace.go:171","msg":"trace[957039969] transaction","detail":"{read_only:false; response_revision:1418; number_of_response:1; }","duration":"370.497311ms","start":"2026-05-21T08:58:16.53401Z","end":"2026-05-21T08:58:16.904508Z","steps":["trace[957039969] 'process raft request' (duration: 370.284695ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:58:16.904631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:58:16.534369Z","time spent":"370.197812ms","remote":"127.0.0.1:36690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T08:58:16.904708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:58:16.533988Z","time spent":"370.624434ms","remote":"127.0.0.1:36690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T08:58:16.905116Z","caller":"traceutil/trace.go:171","msg":"trace[582296832] transaction","detail":"{read_only:false; response_revision:1420; number_of_response:1; }","duration":"370.502979ms","start":"2026-05-21T08:58:16.5346Z","end":"2026-05-21T08:58:16.905103Z","steps":["trace[582296832] 'process raft request' (duration: 369.829533ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:58:16.905256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:58:16.534566Z","time spent":"370.594672ms","remote":"127.0.0.1:36690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T08:58:16.905602Z","caller":"traceutil/trace.go:171","msg":"trace[200172360] transaction","detail":"{read_only:false; response_revision:1421; number_of_response:1; }","duration":"370.409977ms","start":"2026-05-21T08:58:16.535163Z","end":"2026-05-21T08:58:16.905573Z","steps":["trace[200172360] 'process raft request' (duration: 369.875874ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:58:16.905718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:58:16.53515Z","time spent":"370.511479ms","remote":"127.0.0.1:36690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T08:58:16.906089Z","caller":"traceutil/trace.go:171","msg":"trace[994671630] transaction","detail":"{read_only:false; response_revision:1422; number_of_response:1; }","duration":"370.89022ms","start":"2026-05-21T08:58:16.535176Z","end":"2026-05-21T08:58:16.906066Z","steps":["trace[994671630] 'process raft request' (duration: 370.331796ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:58:16.906201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:58:16.535138Z","time spent":"371.004872ms","remote":"127.0.0.1:36690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T08:58:16.906161Z","caller":"traceutil/trace.go:171","msg":"trace[1302583877] transaction","detail":"{read_only:false; response_revision:1423; number_of_response:1; }","duration":"369.487344ms","start":"2026-05-21T08:58:16.536654Z","end":"2026-05-21T08:58:16.906141Z","steps":["trace[1302583877] 'process raft request' (duration: 369.009813ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:58:16.906361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:58:16.536646Z","time spent":"369.612727ms","remote":"127.0.0.1:36690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T08:58:16.9068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"662.545814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T08:58:16.905916Z","caller":"traceutil/trace.go:171","msg":"trace[1364318982] linearizableReadLoop","detail":"{readStateIndex:1481; appliedIndex:1474; }","duration":"661.751214ms","start":"2026-05-21T08:58:16.244133Z","end":"2026-05-21T08:58:16.905885Z","steps":["trace[1364318982] 'read index received' (duration: 654.462509ms)","trace[1364318982] 'applied index is now lower than readState.Index' (duration: 7.286915ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T08:58:16.90791Z","caller":"traceutil/trace.go:171","msg":"trace[554939775] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:1423; }","duration":"663.761035ms","start":"2026-05-21T08:58:16.244049Z","end":"2026-05-21T08:58:16.90781Z","steps":["trace[554939775] 'agreement among raft nodes before linearized reading' (duration: 662.564204ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:58:16.908022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.203614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T08:58:16.908052Z","caller":"traceutil/trace.go:171","msg":"trace[974946400] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1423; }","duration":"376.260976ms","start":"2026-05-21T08:58:16.531781Z","end":"2026-05-21T08:58:16.908042Z","steps":["trace[974946400] 'agreement among raft nodes before linearized reading' (duration: 376.225505ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:58:16.908076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:58:16.531573Z","time spent":"376.495072ms","remote":"127.0.0.1:59418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-21T08:58:16.907978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"644.177098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-05-21T08:58:16.908301Z","caller":"traceutil/trace.go:171","msg":"trace[1760369482] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1423; }","duration":"644.545437ms","start":"2026-05-21T08:58:16.263741Z","end":"2026-05-21T08:58:16.908287Z","steps":["trace[1760369482] 'agreement among raft nodes before linearized reading' (duration: 644.143587ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:58:16.908353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:58:16.263679Z","time spent":"644.658359ms","remote":"127.0.0.1:36690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":553,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-05-21T08:58:16.908082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T08:58:16.244019Z","time spent":"664.037202ms","remote":"127.0.0.1:34962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":130,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true "} {"level":"info","ts":"2026-05-21T08:58:21.057441Z","caller":"traceutil/trace.go:171","msg":"trace[800307878] transaction","detail":"{read_only:false; response_revision:1438; number_of_response:1; }","duration":"131.013721ms","start":"2026-05-21T08:58:20.92639Z","end":"2026-05-21T08:58:21.057404Z","steps":["trace[800307878] 'process raft request' (duration: 129.935074ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T08:58:21.058307Z","caller":"traceutil/trace.go:171","msg":"trace[1242832559] linearizableReadLoop","detail":"{readStateIndex:1500; appliedIndex:1496; }","duration":"124.743261ms","start":"2026-05-21T08:58:20.933529Z","end":"2026-05-21T08:58:21.058272Z","steps":["trace[1242832559] 'read index received' (duration: 68.661035ms)","trace[1242832559] 'applied index is now lower than readState.Index' (duration: 56.080696ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T08:58:21.058947Z","caller":"traceutil/trace.go:171","msg":"trace[1333505295] transaction","detail":"{read_only:false; response_revision:1439; number_of_response:1; }","duration":"132.497159ms","start":"2026-05-21T08:58:20.926418Z","end":"2026-05-21T08:58:21.058915Z","steps":["trace[1333505295] 'process raft request' (duration: 131.521854ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T08:58:21.059278Z","caller":"traceutil/trace.go:171","msg":"trace[2029531741] transaction","detail":"{read_only:false; response_revision:1440; number_of_response:1; }","duration":"132.159509ms","start":"2026-05-21T08:58:20.927094Z","end":"2026-05-21T08:58:21.059253Z","steps":["trace[2029531741] 'process raft request' (duration: 131.01488ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T08:58:21.059432Z","caller":"traceutil/trace.go:171","msg":"trace[668963728] transaction","detail":"{read_only:false; response_revision:1441; number_of_response:1; }","duration":"132.074558ms","start":"2026-05-21T08:58:20.927344Z","end":"2026-05-21T08:58:21.059419Z","steps":["trace[668963728] 'process raft request' (duration: 130.875227ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T08:58:21.060993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.43443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-05-21T08:58:21.061062Z","caller":"traceutil/trace.go:171","msg":"trace[1271030913] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1441; }","duration":"127.570753ms","start":"2026-05-21T08:58:20.933475Z","end":"2026-05-21T08:58:21.061046Z","steps":["trace[1271030913] 'agreement among raft nodes before linearized reading' (duration: 124.932416ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T08:58:29.058345Z","caller":"traceutil/trace.go:171","msg":"trace[1557004795] transaction","detail":"{read_only:false; response_revision:1474; number_of_response:1; }","duration":"120.446142ms","start":"2026-05-21T08:58:28.93787Z","end":"2026-05-21T08:58:29.058316Z","steps":["trace[1557004795] 'process raft request' (duration: 120.219786ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T09:02:23.641334Z","caller":"traceutil/trace.go:171","msg":"trace[1470480535] transaction","detail":"{read_only:false; response_revision:2580; number_of_response:1; }","duration":"205.619735ms","start":"2026-05-21T09:02:23.435686Z","end":"2026-05-21T09:02:23.641306Z","steps":["trace[1470480535] 'process raft request' (duration: 205.45088ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T09:02:46.57899Z","caller":"traceutil/trace.go:171","msg":"trace[694449461] linearizableReadLoop","detail":"{readStateIndex:2802; appliedIndex:2801; }","duration":"187.049382ms","start":"2026-05-21T09:02:46.391918Z","end":"2026-05-21T09:02:46.578968Z","steps":["trace[694449461] 'read index received' (duration: 186.790436ms)","trace[694449461] 'applied index is now lower than readState.Index' (duration: 258.026µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T09:02:46.579082Z","caller":"traceutil/trace.go:171","msg":"trace[722402898] transaction","detail":"{read_only:false; response_revision:2689; number_of_response:1; }","duration":"201.156191ms","start":"2026-05-21T09:02:46.37788Z","end":"2026-05-21T09:02:46.579036Z","steps":["trace[722402898] 'process raft request' (duration: 200.951915ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T09:02:46.579155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.210467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/kube-node-lease/\" range_end:\"/registry/persistentvolumeclaims/kube-node-lease0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T09:02:46.579194Z","caller":"traceutil/trace.go:171","msg":"trace[516105965] range","detail":"{range_begin:/registry/persistentvolumeclaims/kube-node-lease/; range_end:/registry/persistentvolumeclaims/kube-node-lease0; response_count:0; response_revision:2689; }","duration":"187.30232ms","start":"2026-05-21T09:02:46.391882Z","end":"2026-05-21T09:02:46.579184Z","steps":["trace[516105965] 'agreement among raft nodes before linearized reading' (duration: 187.210527ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T09:02:46.57923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.676719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/envoy-gateway-system/envoy-gateway\" ","response":"range_response_count:1 size:1912"} {"level":"info","ts":"2026-05-21T09:02:46.579298Z","caller":"traceutil/trace.go:171","msg":"trace[1192248775] range","detail":"{range_begin:/registry/services/specs/envoy-gateway-system/envoy-gateway; range_end:; response_count:1; response_revision:2689; }","duration":"176.784402ms","start":"2026-05-21T09:02:46.402498Z","end":"2026-05-21T09:02:46.579283Z","steps":["trace[1192248775] 'agreement among raft nodes before linearized reading' (duration: 176.644619ms)"],"step_count":1}