{"level":"warn","ts":"2026-05-05T12:48:52.322787Z","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-05T12:48:52.322875Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.227:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.227:2380","--initial-cluster=instance=https://199.204.45.227:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.227:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.227: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-05T12:48:52.323024Z","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-05T12:48:52.323042Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.227:2380"]} {"level":"info","ts":"2026-05-05T12:48:52.323072Z","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-05T12:48:52.323747Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.227:2379"]} {"level":"info","ts":"2026-05-05T12:48:52.323835Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.227:2380"],"listen-peer-urls":["https://199.204.45.227:2380"],"advertise-client-urls":["https://199.204.45.227:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.227:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.227: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-05T12:48:52.328481Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.410264ms"} {"level":"info","ts":"2026-05-05T12:48:52.337446Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"572db18442eba1c2","cluster-id":"fb1028a037bf3cd6"} {"level":"info","ts":"2026-05-05T12:48:52.337539Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 switched to configuration voters=()"} {"level":"info","ts":"2026-05-05T12:48:52.337567Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became follower at term 0"} {"level":"info","ts":"2026-05-05T12:48:52.337581Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 572db18442eba1c2 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-05T12:48:52.3376Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became follower at term 1"} {"level":"info","ts":"2026-05-05T12:48:52.337641Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 switched to configuration voters=(6281872236868248002)"} {"level":"warn","ts":"2026-05-05T12:48:52.34985Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-05T12:48:52.351813Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-05T12:48:52.355278Z","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-05T12:48:52.361098Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"572db18442eba1c2","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-05T12:48:52.361325Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"572db18442eba1c2","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-05T12:48:52.361475Z","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-05T12:48:52.361547Z","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-05T12:48:52.361561Z","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-05T12:48:52.362568Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 switched to configuration voters=(6281872236868248002)"} {"level":"info","ts":"2026-05-05T12:48:52.362697Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"fb1028a037bf3cd6","local-member-id":"572db18442eba1c2","added-peer-id":"572db18442eba1c2","added-peer-peer-urls":["https://199.204.45.227:2380"]} {"level":"info","ts":"2026-05-05T12:48:52.3653Z","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-05T12:48:52.365461Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"572db18442eba1c2","initial-advertise-peer-urls":["https://199.204.45.227:2380"],"listen-peer-urls":["https://199.204.45.227:2380"],"advertise-client-urls":["https://199.204.45.227:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.227:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-05T12:48:52.36548Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-05T12:48:52.365549Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.227:2380"} {"level":"info","ts":"2026-05-05T12:48:52.365564Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.227:2380"} {"level":"info","ts":"2026-05-05T12:48:53.338701Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 is starting a new election at term 1"} {"level":"info","ts":"2026-05-05T12:48:53.338768Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-05T12:48:53.33879Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 received MsgPreVoteResp from 572db18442eba1c2 at term 1"} {"level":"info","ts":"2026-05-05T12:48:53.33881Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became candidate at term 2"} {"level":"info","ts":"2026-05-05T12:48:53.338822Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 received MsgVoteResp from 572db18442eba1c2 at term 2"} {"level":"info","ts":"2026-05-05T12:48:53.338838Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became leader at term 2"} {"level":"info","ts":"2026-05-05T12:48:53.338853Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 572db18442eba1c2 elected leader 572db18442eba1c2 at term 2"} {"level":"info","ts":"2026-05-05T12:48:53.806711Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"572db18442eba1c2","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.227:2379]}","request-path":"/0/members/572db18442eba1c2/attributes","cluster-id":"fb1028a037bf3cd6","publish-timeout":"7s"} {"level":"info","ts":"2026-05-05T12:48:53.806959Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T12:48:53.806987Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T12:48:53.807098Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T12:48:53.809168Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-05T12:48:53.80924Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-05T12:48:53.811176Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-05T12:48:53.814275Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.227:2379"} {"level":"info","ts":"2026-05-05T12:48:53.971907Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"fb1028a037bf3cd6","local-member-id":"572db18442eba1c2","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T12:48:53.972076Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T12:48:53.972139Z","caller":"traceutil/trace.go:171","msg":"trace[1055057458] linearizableReadLoop","detail":"{readStateIndex:4; appliedIndex:3; }","duration":"111.233085ms","start":"2026-05-05T12:48:53.860891Z","end":"2026-05-05T12:48:53.972124Z","steps":["trace[1055057458] 'read index received' (duration: 111.002418ms)","trace[1055057458] 'applied index is now lower than readState.Index' (duration: 229.437µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T12:48:53.972322Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-05-05T12:48:53.97249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.505604ms","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:4"} {"level":"warn","ts":"2026-05-05T12:48:53.972609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.544745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T12:48:53.973276Z","caller":"traceutil/trace.go:171","msg":"trace[1977646975] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1; }","duration":"112.357972ms","start":"2026-05-05T12:48:53.86088Z","end":"2026-05-05T12:48:53.973238Z","steps":["trace[1977646975] 'agreement among raft nodes before linearized reading' (duration: 111.39219ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:48:53.974415Z","caller":"traceutil/trace.go:171","msg":"trace[296442341] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1; }","duration":"111.67682ms","start":"2026-05-05T12:48:53.86089Z","end":"2026-05-05T12:48:53.972567Z","steps":["trace[296442341] 'agreement among raft nodes before linearized reading' (duration: 111.436722ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:48:54.275547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.516059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T12:48:54.275623Z","caller":"traceutil/trace.go:171","msg":"trace[980104468] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1; }","duration":"213.618512ms","start":"2026-05-05T12:48:54.061989Z","end":"2026-05-05T12:48:54.275608Z","steps":["trace[980104468] 'count revisions from in-memory index tree' (duration: 213.454607ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:48:54.275659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.126891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T12:48:54.275723Z","caller":"traceutil/trace.go:171","msg":"trace[1090403805] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:1; }","duration":"103.222174ms","start":"2026-05-05T12:48:54.172488Z","end":"2026-05-05T12:48:54.27571Z","steps":["trace[1090403805] 'range keys from in-memory index tree' (duration: 103.078529ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:48:54.27581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.154671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-05T12:48:54.27581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.617107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T12:48:54.275843Z","caller":"traceutil/trace.go:171","msg":"trace[522978297] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:1; }","duration":"103.238935ms","start":"2026-05-05T12:48:54.172595Z","end":"2026-05-05T12:48:54.275834Z","steps":["trace[522978297] 'count revisions from in-memory index tree' (duration: 103.084139ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:48:54.27587Z","caller":"traceutil/trace.go:171","msg":"trace[1643543601] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:1; }","duration":"149.73018ms","start":"2026-05-05T12:48:54.126123Z","end":"2026-05-05T12:48:54.275854Z","steps":["trace[1643543601] 'range keys from in-memory index tree' (duration: 149.499902ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:48:54.275889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.869942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T12:48:54.275951Z","caller":"traceutil/trace.go:171","msg":"trace[1548824820] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:1; }","duration":"121.946014ms","start":"2026-05-05T12:48:54.153987Z","end":"2026-05-05T12:48:54.275933Z","steps":["trace[1548824820] 'range keys from in-memory index tree' (duration: 121.83328ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:48:54.276014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.457928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T12:48:54.276059Z","caller":"traceutil/trace.go:171","msg":"trace[1627562933] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1; }","duration":"171.5201ms","start":"2026-05-05T12:48:54.104524Z","end":"2026-05-05T12:48:54.276044Z","steps":["trace[1627562933] 'count revisions from in-memory index tree' (duration: 171.411117ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:48:54.276072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.423697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T12:48:54.276114Z","caller":"traceutil/trace.go:171","msg":"trace[2044053812] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1; }","duration":"171.492919ms","start":"2026-05-05T12:48:54.104603Z","end":"2026-05-05T12:48:54.276096Z","steps":["trace[2044053812] 'range keys from in-memory index tree' (duration: 171.339304ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:48:54.276143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.077165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T12:48:54.276163Z","caller":"traceutil/trace.go:171","msg":"trace[77470538] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:1; }","duration":"113.104806ms","start":"2026-05-05T12:48:54.163052Z","end":"2026-05-05T12:48:54.276156Z","steps":["trace[77470538] 'count revisions from in-memory index tree' (duration: 113.049684ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:48:54.276191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.191841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T12:48:54.276214Z","caller":"traceutil/trace.go:171","msg":"trace[595716640] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1; }","duration":"214.243563ms","start":"2026-05-05T12:48:54.061964Z","end":"2026-05-05T12:48:54.276207Z","steps":["trace[595716640] 'range keys from in-memory index tree' (duration: 214.105118ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:48:54.276261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.921759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-05T12:48:54.276283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.063355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T12:48:54.276301Z","caller":"traceutil/trace.go:171","msg":"trace[796169526] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:1; }","duration":"113.111227ms","start":"2026-05-05T12:48:54.163185Z","end":"2026-05-05T12:48:54.276296Z","steps":["trace[796169526] 'range keys from in-memory index tree' (duration: 113.002693ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:48:54.276302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.067498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T12:48:54.276298Z","caller":"traceutil/trace.go:171","msg":"trace[1022672007] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1; }","duration":"158.980821ms","start":"2026-05-05T12:48:54.117308Z","end":"2026-05-05T12:48:54.276289Z","steps":["trace[1022672007] 'range keys from in-memory index tree' (duration: 158.879968ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:48:54.276325Z","caller":"traceutil/trace.go:171","msg":"trace[11191297] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:1; }","duration":"199.090978ms","start":"2026-05-05T12:48:54.077228Z","end":"2026-05-05T12:48:54.276319Z","steps":["trace[11191297] 'range keys from in-memory index tree' (duration: 199.019736ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:48:54.27629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.135093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T12:48:54.276364Z","caller":"traceutil/trace.go:171","msg":"trace[1432857154] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:1; }","duration":"150.234316ms","start":"2026-05-05T12:48:54.126117Z","end":"2026-05-05T12:48:54.276351Z","steps":["trace[1432857154] 'count revisions from in-memory index tree' (duration: 150.028379ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:48:54.276427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.146811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-05T12:48:54.276438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.056904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T12:48:54.276494Z","caller":"traceutil/trace.go:171","msg":"trace[2050139320] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1; }","duration":"159.125106ms","start":"2026-05-05T12:48:54.11736Z","end":"2026-05-05T12:48:54.276485Z","steps":["trace[2050139320] 'count revisions from in-memory index tree' (duration: 159.021483ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:48:54.276446Z","caller":"traceutil/trace.go:171","msg":"trace[1592745273] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:1; }","duration":"199.165561ms","start":"2026-05-05T12:48:54.077274Z","end":"2026-05-05T12:48:54.27644Z","steps":["trace[1592745273] 'count revisions from in-memory index tree' (duration: 199.049398ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:48:54.276623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.000405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-05T12:48:54.276117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.864891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-05T12:48:54.276659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.950993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T12:48:54.276668Z","caller":"traceutil/trace.go:171","msg":"trace[166343232] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:1; }","duration":"122.43764ms","start":"2026-05-05T12:48:54.154221Z","end":"2026-05-05T12:48:54.276659Z","steps":["trace[166343232] 'count revisions from in-memory index tree' (duration: 121.82169ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:48:54.276682Z","caller":"traceutil/trace.go:171","msg":"trace[2126235045] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:1; }","duration":"136.987944ms","start":"2026-05-05T12:48:54.139688Z","end":"2026-05-05T12:48:54.276676Z","steps":["trace[2126235045] 'range keys from in-memory index tree' (duration: 136.917582ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:48:54.276773Z","caller":"traceutil/trace.go:171","msg":"trace[1975751137] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:1; }","duration":"137.039656ms","start":"2026-05-05T12:48:54.139608Z","end":"2026-05-05T12:48:54.276647Z","steps":["trace[1975751137] 'count revisions from in-memory index tree' (duration: 136.965363ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:49:23.490255Z","caller":"traceutil/trace.go:171","msg":"trace[915150840] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"215.839445ms","start":"2026-05-05T12:49:23.274319Z","end":"2026-05-05T12:49:23.490158Z","steps":["trace[915150840] 'process raft request' (duration: 215.758172ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:49:23.942729Z","caller":"traceutil/trace.go:171","msg":"trace[672297797] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"629.545438ms","start":"2026-05-05T12:49:23.313165Z","end":"2026-05-05T12:49:23.942711Z","steps":["trace[672297797] 'process raft request' (duration: 629.494416ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:49:23.942747Z","caller":"traceutil/trace.go:171","msg":"trace[1132177304] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"667.562607ms","start":"2026-05-05T12:49:23.275143Z","end":"2026-05-05T12:49:23.942706Z","steps":["trace[1132177304] 'process raft request' (duration: 667.389431ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:49:23.943129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:49:23.313145Z","time spent":"629.642961ms","remote":"127.0.0.1:40060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T12:49:23.943239Z","caller":"traceutil/trace.go:171","msg":"trace[1172852175] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"629.93366ms","start":"2026-05-05T12:49:23.313298Z","end":"2026-05-05T12:49:23.943231Z","steps":["trace[1172852175] 'process raft request' (duration: 629.632ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:49:23.943275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:49:23.313295Z","time spent":"629.963931ms","remote":"127.0.0.1:40060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T12:49:23.943322Z","caller":"traceutil/trace.go:171","msg":"trace[965329287] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"630.048394ms","start":"2026-05-05T12:49:23.313267Z","end":"2026-05-05T12:49:23.943316Z","steps":["trace[965329287] 'process raft request' (duration: 629.422533ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:49:23.94335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:49:23.313263Z","time spent":"630.074864ms","remote":"127.0.0.1:40060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T12:49:23.943422Z","caller":"traceutil/trace.go:171","msg":"trace[580000618] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"621.849427ms","start":"2026-05-05T12:49:23.321567Z","end":"2026-05-05T12:49:23.943417Z","steps":["trace[580000618] 'process raft request' (duration: 621.570308ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:49:23.943453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:49:23.321561Z","time spent":"621.878628ms","remote":"127.0.0.1:40060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T12:49:23.94351Z","caller":"traceutil/trace.go:171","msg":"trace[869965772] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"622.002412ms","start":"2026-05-05T12:49:23.321503Z","end":"2026-05-05T12:49:23.943505Z","steps":["trace[869965772] 'process raft request' (duration: 621.560838ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:49:23.943535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:49:23.32148Z","time spent":"622.044674ms","remote":"127.0.0.1:40060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T12:49:23.943592Z","caller":"traceutil/trace.go:171","msg":"trace[85513470] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"616.651388ms","start":"2026-05-05T12:49:23.326935Z","end":"2026-05-05T12:49:23.943586Z","steps":["trace[85513470] 'process raft request' (duration: 616.392699ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:49:23.943625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:49:23.326928Z","time spent":"616.677818ms","remote":"127.0.0.1:40060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T12:49:23.943621Z","caller":"traceutil/trace.go:171","msg":"trace[120214286] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"620.627647ms","start":"2026-05-05T12:49:23.322958Z","end":"2026-05-05T12:49:23.943586Z","steps":["trace[120214286] 'process raft request' (duration: 620.311306ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:49:23.943724Z","caller":"traceutil/trace.go:171","msg":"trace[1546438167] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"615.580652ms","start":"2026-05-05T12:49:23.328085Z","end":"2026-05-05T12:49:23.943666Z","steps":["trace[1546438167] 'process raft request' (duration: 615.306143ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:49:23.943797Z","caller":"traceutil/trace.go:171","msg":"trace[64990012] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"622.180867ms","start":"2026-05-05T12:49:23.321609Z","end":"2026-05-05T12:49:23.94379Z","steps":["trace[64990012] 'process raft request' (duration: 621.597458ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:49:23.943834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:49:23.321605Z","time spent":"622.212748ms","remote":"127.0.0.1:40060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T12:49:23.943922Z","caller":"traceutil/trace.go:171","msg":"trace[1375407012] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"578.351039ms","start":"2026-05-05T12:49:23.365553Z","end":"2026-05-05T12:49:23.943904Z","steps":["trace[1375407012] 'process raft request' (duration: 578.055059ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:49:23.944875Z","caller":"traceutil/trace.go:171","msg":"trace[1186763247] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"603.120926ms","start":"2026-05-05T12:49:23.341736Z","end":"2026-05-05T12:49:23.944857Z","steps":["trace[1186763247] 'process raft request' (duration: 601.730101ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:49:23.944964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:49:23.341718Z","time spent":"603.203899ms","remote":"127.0.0.1:40060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T12:49:23.944936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:49:23.365535Z","time spent":"579.252338ms","remote":"127.0.0.1:40060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T12:49:23.945364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:49:23.328079Z","time spent":"615.720356ms","remote":"127.0.0.1:40060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T12:49:23.945389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:49:23.275105Z","time spent":"667.745373ms","remote":"127.0.0.1:40290","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-05T12:49:23.943761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:49:23.322948Z","time spent":"620.745241ms","remote":"127.0.0.1:40060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T12:49:23.947374Z","caller":"traceutil/trace.go:171","msg":"trace[1361610515] linearizableReadLoop","detail":"{readStateIndex:537; appliedIndex:525; }","duration":"544.01815ms","start":"2026-05-05T12:49:23.403295Z","end":"2026-05-05T12:49:23.947313Z","steps":["trace[1361610515] 'read index received' (duration: 86.963274ms)","trace[1361610515] 'applied index is now lower than readState.Index' (duration: 457.052896ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T12:49:23.947668Z","caller":"traceutil/trace.go:171","msg":"trace[340752839] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"484.047635ms","start":"2026-05-05T12:49:23.463587Z","end":"2026-05-05T12:49:23.947635Z","steps":["trace[340752839] 'process raft request' (duration: 480.272132ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:49:23.947759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:49:23.463569Z","time spent":"484.145849ms","remote":"127.0.0.1:40060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T12:49:23.948445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.93185ms","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-05T12:49:23.948524Z","caller":"traceutil/trace.go:171","msg":"trace[292768951] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:519; }","duration":"437.042093ms","start":"2026-05-05T12:49:23.511458Z","end":"2026-05-05T12:49:23.9485Z","steps":["trace[292768951] 'agreement among raft nodes before linearized reading' (duration: 436.910459ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:49:23.948671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:49:23.511434Z","time spent":"437.150427ms","remote":"127.0.0.1:40290","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-05T12:49:23.948221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.912219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-05T12:49:23.950989Z","caller":"traceutil/trace.go:171","msg":"trace[2056270472] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:519; }","duration":"547.729341ms","start":"2026-05-05T12:49:23.403241Z","end":"2026-05-05T12:49:23.950971Z","steps":["trace[2056270472] 'agreement among raft nodes before linearized reading' (duration: 544.726463ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:49:23.951034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:49:23.403227Z","time spent":"547.793863ms","remote":"127.0.0.1:40290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-05T12:49:25.618576Z","caller":"traceutil/trace.go:171","msg":"trace[192616595] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"119.312948ms","start":"2026-05-05T12:49:25.499236Z","end":"2026-05-05T12:49:25.618549Z","steps":["trace[192616595] 'process raft request' (duration: 119.162693ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:49:30.274616Z","caller":"traceutil/trace.go:171","msg":"trace[1099838340] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"295.521161ms","start":"2026-05-05T12:49:29.97906Z","end":"2026-05-05T12:49:30.274581Z","steps":["trace[1099838340] 'process raft request' (duration: 295.329945ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:49:30.274616Z","caller":"traceutil/trace.go:171","msg":"trace[1331130670] linearizableReadLoop","detail":"{readStateIndex:636; appliedIndex:635; }","duration":"287.358045ms","start":"2026-05-05T12:49:29.987192Z","end":"2026-05-05T12:49:30.27455Z","steps":["trace[1331130670] 'read index received' (duration: 287.254002ms)","trace[1331130670] 'applied index is now lower than readState.Index' (duration: 103.103µs)"],"step_count":2} {"level":"warn","ts":"2026-05-05T12:49:30.275091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.866912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-05T12:49:30.27517Z","caller":"traceutil/trace.go:171","msg":"trace[771149560] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:614; }","duration":"287.979696ms","start":"2026-05-05T12:49:29.987173Z","end":"2026-05-05T12:49:30.275153Z","steps":["trace[771149560] 'agreement among raft nodes before linearized reading' (duration: 287.449619ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:49:30.775032Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656052474856634113,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-05T12:49:31.156532Z","caller":"traceutil/trace.go:171","msg":"trace[507507296] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"1.168183522s","start":"2026-05-05T12:49:29.988323Z","end":"2026-05-05T12:49:31.156507Z","steps":["trace[507507296] 'process raft request' (duration: 1.167664815s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:49:31.156662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:49:29.988291Z","time spent":"1.168302936s","remote":"127.0.0.1:40116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T12:49:31.563783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.953876ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"warn","ts":"2026-05-05T12:49:31.563947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.533955613s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T12:49:31.563996Z","caller":"traceutil/trace.go:171","msg":"trace[392827350] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:616; }","duration":"1.534045896s","start":"2026-05-05T12:49:30.029938Z","end":"2026-05-05T12:49:31.563984Z","steps":["trace[392827350] 'agreement among raft nodes before linearized reading' (duration: 1.533957803s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:49:31.564033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:49:30.029924Z","time spent":"1.534100867s","remote":"127.0.0.1:50260","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-05T12:49:31.563903Z","caller":"traceutil/trace.go:171","msg":"trace[1730622824] linearizableReadLoop","detail":"{readStateIndex:638; appliedIndex:636; }","duration":"1.288954758s","start":"2026-05-05T12:49:30.274922Z","end":"2026-05-05T12:49:31.563877Z","steps":["trace[1730622824] 'read index received' (duration: 880.889259ms)","trace[1730622824] 'applied index is now lower than readState.Index' (duration: 408.064109ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T12:49:31.564409Z","caller":"traceutil/trace.go:171","msg":"trace[2142687821] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"1.285493225s","start":"2026-05-05T12:49:30.278893Z","end":"2026-05-05T12:49:31.564387Z","steps":["trace[2142687821] 'process raft request' (duration: 980.687471ms)","trace[2142687821] 'compare' (duration: 303.748969ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T12:49:31.564521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:49:30.278873Z","time spent":"1.285579718s","remote":"127.0.0.1:40290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T12:49:31.564751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.569682807s","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-05T12:49:31.564912Z","caller":"traceutil/trace.go:171","msg":"trace[693312975] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:616; }","duration":"1.569877753s","start":"2026-05-05T12:49:29.99501Z","end":"2026-05-05T12:49:31.564888Z","steps":["trace[693312975] 'agreement among raft nodes before linearized reading' (duration: 1.569384347s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:49:31.56504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:49:29.995002Z","time spent":"1.570023488s","remote":"127.0.0.1:40290","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-05T12:49:32.010543Z","caller":"traceutil/trace.go:171","msg":"trace[1208776699] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"794.564545ms","start":"2026-05-05T12:49:31.215937Z","end":"2026-05-05T12:49:32.010501Z","steps":["trace[1208776699] 'process raft request' (duration: 728.066998ms)","trace[1208776699] 'compare' (duration: 66.084054ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T12:49:32.010604Z","caller":"traceutil/trace.go:171","msg":"trace[284533162] linearizableReadLoop","detail":"{readStateIndex:639; appliedIndex:638; }","duration":"446.245563ms","start":"2026-05-05T12:49:31.564324Z","end":"2026-05-05T12:49:32.010569Z","steps":["trace[284533162] 'read index received' (duration: 379.649373ms)","trace[284533162] 'applied index is now lower than readState.Index' (duration: 66.59263ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T12:49:32.010683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:49:31.215918Z","time spent":"794.70339ms","remote":"127.0.0.1:40572","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-05T12:49:32.011044Z","caller":"traceutil/trace.go:171","msg":"trace[977228588] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"441.607702ms","start":"2026-05-05T12:49:31.569411Z","end":"2026-05-05T12:49:32.011019Z","steps":["trace[977228588] 'process raft request' (duration: 440.94815ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:49:32.011141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:49:31.569393Z","time spent":"441.701465ms","remote":"127.0.0.1:40290","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-05T12:49:32.939062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"928.624315ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-05T12:49:32.939158Z","caller":"traceutil/trace.go:171","msg":"trace[787718032] linearizableReadLoop","detail":"{readStateIndex:641; appliedIndex:640; }","duration":"928.468209ms","start":"2026-05-05T12:49:32.010673Z","end":"2026-05-05T12:49:32.939141Z","steps":["trace[787718032] 'read index received' (duration: 70.782µs)","trace[787718032] 'applied index is now lower than readState.Index' (duration: 928.395977ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T12:49:32.939415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.531805333s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-6w6sj\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-05T12:49:32.939459Z","caller":"traceutil/trace.go:171","msg":"trace[814168300] range","detail":"{range_begin:/registry/pods/kube-system/cilium-6w6sj; range_end:; response_count:1; response_revision:618; }","duration":"2.531881916s","start":"2026-05-05T12:49:30.407562Z","end":"2026-05-05T12:49:32.939444Z","steps":["trace[814168300] 'agreement among raft nodes before linearized reading' (duration: 1.603159938s)","trace[814168300] 'range keys from in-memory index tree' (duration: 928.577313ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T12:49:32.939494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:49:30.407546Z","time spent":"2.531939937s","remote":"127.0.0.1:40218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-6w6sj\" "} {"level":"warn","ts":"2026-05-05T12:49:32.939645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.285279158s","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-05T12:49:32.939685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.374469765s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T12:49:32.939698Z","caller":"traceutil/trace.go:171","msg":"trace[1692607898] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:618; }","duration":"1.28536993s","start":"2026-05-05T12:49:31.654312Z","end":"2026-05-05T12:49:32.939682Z","steps":["trace[1692607898] 'agreement among raft nodes before linearized reading' (duration: 1.285223166s)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:49:32.939735Z","caller":"traceutil/trace.go:171","msg":"trace[953763332] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:618; }","duration":"1.374551378s","start":"2026-05-05T12:49:31.565168Z","end":"2026-05-05T12:49:32.939719Z","steps":["trace[953763332] 'agreement among raft nodes before linearized reading' (duration: 1.374471915s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:49:32.939742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:49:31.654292Z","time spent":"1.285437543s","remote":"127.0.0.1:40290","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-05T12:49:32.939774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:49:31.565141Z","time spent":"1.37462282s","remote":"127.0.0.1:50268","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-05T12:49:32.939986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"917.681918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T12:49:32.940035Z","caller":"traceutil/trace.go:171","msg":"trace[11138036] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:618; }","duration":"917.751379ms","start":"2026-05-05T12:49:32.022267Z","end":"2026-05-05T12:49:32.940018Z","steps":["trace[11138036] 'agreement among raft nodes before linearized reading' (duration: 917.684387ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:49:32.940077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:49:32.022255Z","time spent":"917.810692ms","remote":"127.0.0.1:40260","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"warn","ts":"2026-05-05T12:49:32.940199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"661.268881ms","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-05T12:49:32.940251Z","caller":"traceutil/trace.go:171","msg":"trace[1817951138] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:618; }","duration":"661.356403ms","start":"2026-05-05T12:49:32.278878Z","end":"2026-05-05T12:49:32.940235Z","steps":["trace[1817951138] 'agreement among raft nodes before linearized reading' (duration: 661.217839ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:49:32.940301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:49:32.278857Z","time spent":"661.429866ms","remote":"127.0.0.1:40290","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":"info","ts":"2026-05-05T12:49:33.43913Z","caller":"traceutil/trace.go:171","msg":"trace[2080285802] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"493.41917ms","start":"2026-05-05T12:49:32.945674Z","end":"2026-05-05T12:49:33.439093Z","steps":["trace[2080285802] 'process raft request' (duration: 493.177252ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:49:33.439317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:49:32.945648Z","time spent":"493.573415ms","remote":"127.0.0.1:40290","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-05T12:49:33.718388Z","caller":"traceutil/trace.go:171","msg":"trace[384416895] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"772.212098ms","start":"2026-05-05T12:49:32.946135Z","end":"2026-05-05T12:49:33.718347Z","steps":["trace[384416895] 'process raft request' (duration: 771.769844ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:49:33.718584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:49:32.946117Z","time spent":"772.367233ms","remote":"127.0.0.1:40290","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-05T12:49:33.718969Z","caller":"traceutil/trace.go:171","msg":"trace[956462182] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"763.278656ms","start":"2026-05-05T12:49:32.955664Z","end":"2026-05-05T12:49:33.718943Z","steps":["trace[956462182] 'process raft request' (duration: 762.381628ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:49:33.719128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:49:32.955648Z","time spent":"763.40277ms","remote":"127.0.0.1:40218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T12:49:33.719398Z","caller":"traceutil/trace.go:171","msg":"trace[668610268] linearizableReadLoop","detail":"{readStateIndex:643; appliedIndex:642; }","duration":"770.821661ms","start":"2026-05-05T12:49:32.948525Z","end":"2026-05-05T12:49:33.719346Z","steps":["trace[668610268] 'read index received' (duration: 490.947409ms)","trace[668610268] 'applied index is now lower than readState.Index' (duration: 279.869492ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T12:49:33.719565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"771.033568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T12:49:33.719618Z","caller":"traceutil/trace.go:171","msg":"trace[1105128205] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:622; }","duration":"771.10956ms","start":"2026-05-05T12:49:32.948494Z","end":"2026-05-05T12:49:33.719603Z","steps":["trace[1105128205] 'agreement among raft nodes before linearized reading' (duration: 771.015197ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:49:33.719654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:49:32.948481Z","time spent":"771.166243ms","remote":"127.0.0.1:50260","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-05T12:49:33.719795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.602379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-05T12:49:33.719847Z","caller":"traceutil/trace.go:171","msg":"trace[354257261] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:622; }","duration":"150.718412ms","start":"2026-05-05T12:49:33.569112Z","end":"2026-05-05T12:49:33.719831Z","steps":["trace[354257261] 'agreement among raft nodes before linearized reading' (duration: 150.427443ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:49:33.720422Z","caller":"traceutil/trace.go:171","msg":"trace[1086030042] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"383.122732ms","start":"2026-05-05T12:49:33.337282Z","end":"2026-05-05T12:49:33.720405Z","steps":["trace[1086030042] 'process raft request' (duration: 381.151619ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:49:33.720638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:49:33.337253Z","time spent":"383.303187ms","remote":"127.0.0.1:40116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T12:49:35.840013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.481513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-05T12:49:35.840046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.137505ms","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-05T12:49:35.840114Z","caller":"traceutil/trace.go:171","msg":"trace[1512664381] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:654; }","duration":"107.630598ms","start":"2026-05-05T12:49:35.732462Z","end":"2026-05-05T12:49:35.840093Z","steps":["trace[1512664381] 'range keys from in-memory index tree' (duration: 107.335489ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:49:35.840127Z","caller":"traceutil/trace.go:171","msg":"trace[1569090355] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:654; }","duration":"114.280439ms","start":"2026-05-05T12:49:35.725828Z","end":"2026-05-05T12:49:35.840109Z","steps":["trace[1569090355] 'range keys from in-memory index tree' (duration: 113.934837ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:49:35.840466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.065859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T12:49:35.840588Z","caller":"traceutil/trace.go:171","msg":"trace[1361916098] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:654; }","duration":"238.243704ms","start":"2026-05-05T12:49:35.602311Z","end":"2026-05-05T12:49:35.840555Z","steps":["trace[1361916098] 'range keys from in-memory index tree' (duration: 237.980106ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:49:36.030995Z","caller":"traceutil/trace.go:171","msg":"trace[1920824081] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"187.206268ms","start":"2026-05-05T12:49:35.843763Z","end":"2026-05-05T12:49:36.030969Z","steps":["trace[1920824081] 'process raft request' (duration: 114.033261ms)","trace[1920824081] 'compare' (duration: 72.922509ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T12:49:36.031105Z","caller":"traceutil/trace.go:171","msg":"trace[491120104] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"186.839616ms","start":"2026-05-05T12:49:35.844241Z","end":"2026-05-05T12:49:36.03108Z","steps":["trace[491120104] 'process raft request' (duration: 186.661581ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:49:36.12829Z","caller":"traceutil/trace.go:171","msg":"trace[1471266787] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"248.399005ms","start":"2026-05-05T12:49:35.879859Z","end":"2026-05-05T12:49:36.128258Z","steps":["trace[1471266787] 'process raft request' (duration: 248.314843ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:49:36.128337Z","caller":"traceutil/trace.go:171","msg":"trace[1826048454] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"249.687887ms","start":"2026-05-05T12:49:35.878617Z","end":"2026-05-05T12:49:36.128305Z","steps":["trace[1826048454] 'process raft request' (duration: 249.387537ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:49:36.267177Z","caller":"traceutil/trace.go:171","msg":"trace[1425388342] linearizableReadLoop","detail":"{readStateIndex:683; appliedIndex:682; }","duration":"245.390521ms","start":"2026-05-05T12:49:36.021764Z","end":"2026-05-05T12:49:36.267155Z","steps":["trace[1425388342] 'read index received' (duration: 107.111152ms)","trace[1425388342] 'applied index is now lower than readState.Index' (duration: 138.278409ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T12:49:36.26727Z","caller":"traceutil/trace.go:171","msg":"trace[782819541] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"263.0681ms","start":"2026-05-05T12:49:36.004194Z","end":"2026-05-05T12:49:36.267262Z","steps":["trace[782819541] 'process raft request' (duration: 262.839813ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:49:36.267509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.723731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-05T12:49:36.267609Z","caller":"traceutil/trace.go:171","msg":"trace[2113934955] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:660; }","duration":"245.837675ms","start":"2026-05-05T12:49:36.02175Z","end":"2026-05-05T12:49:36.267587Z","steps":["trace[2113934955] 'agreement among raft nodes before linearized reading' (duration: 245.552346ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:49:36.27256Z","caller":"traceutil/trace.go:171","msg":"trace[1628010345] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"133.554329ms","start":"2026-05-05T12:49:36.138977Z","end":"2026-05-05T12:49:36.272531Z","steps":["trace[1628010345] 'process raft request' (duration: 133.356963ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:49:36.272912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.818291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T12:49:36.272972Z","caller":"traceutil/trace.go:171","msg":"trace[119854065] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:662; }","duration":"243.951615ms","start":"2026-05-05T12:49:36.029007Z","end":"2026-05-05T12:49:36.272959Z","steps":["trace[119854065] 'agreement among raft nodes before linearized reading' (duration: 243.4951ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:49:38.947681Z","caller":"traceutil/trace.go:171","msg":"trace[1102732805] transaction","detail":"{read_only:false; response_revision:699; number_of_response:1; }","duration":"114.776664ms","start":"2026-05-05T12:49:38.832882Z","end":"2026-05-05T12:49:38.947659Z","steps":["trace[1102732805] 'process raft request' (duration: 114.514706ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:49:40.767193Z","caller":"traceutil/trace.go:171","msg":"trace[1048347731] linearizableReadLoop","detail":"{readStateIndex:749; appliedIndex:748; }","duration":"264.359162ms","start":"2026-05-05T12:49:40.502808Z","end":"2026-05-05T12:49:40.767167Z","steps":["trace[1048347731] 'read index received' (duration: 264.103234ms)","trace[1048347731] 'applied index is now lower than readState.Index' (duration: 254.868µs)"],"step_count":2} {"level":"warn","ts":"2026-05-05T12:49:40.767453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.62216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/coredns\" ","response":"range_response_count:1 size:179"} {"level":"info","ts":"2026-05-05T12:49:40.767541Z","caller":"traceutil/trace.go:171","msg":"trace[17848377] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/coredns; range_end:; response_count:1; response_revision:725; }","duration":"264.760204ms","start":"2026-05-05T12:49:40.502762Z","end":"2026-05-05T12:49:40.767522Z","steps":["trace[17848377] 'agreement among raft nodes before linearized reading' (duration: 264.568778ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:49:40.767624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.274658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:1 size:833"} {"level":"warn","ts":"2026-05-05T12:49:40.767641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.196231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T12:49:40.767744Z","caller":"traceutil/trace.go:171","msg":"trace[1201655526] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:725; }","duration":"177.334785ms","start":"2026-05-05T12:49:40.590386Z","end":"2026-05-05T12:49:40.76772Z","steps":["trace[1201655526] 'agreement among raft nodes before linearized reading' (duration: 177.132259ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:49:40.767714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.571193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T12:49:40.767704Z","caller":"traceutil/trace.go:171","msg":"trace[362333281] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:1; response_revision:725; }","duration":"264.436723ms","start":"2026-05-05T12:49:40.503252Z","end":"2026-05-05T12:49:40.767689Z","steps":["trace[362333281] 'agreement among raft nodes before linearized reading' (duration: 264.167635ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:49:40.767965Z","caller":"traceutil/trace.go:171","msg":"trace[1805937630] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:725; }","duration":"177.80358ms","start":"2026-05-05T12:49:40.590037Z","end":"2026-05-05T12:49:40.767841Z","steps":["trace[1805937630] 'agreement among raft nodes before linearized reading' (duration: 177.47699ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:49:40.768244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.308095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T12:49:40.768346Z","caller":"traceutil/trace.go:171","msg":"trace[247095512] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:725; }","duration":"177.427889ms","start":"2026-05-05T12:49:40.590901Z","end":"2026-05-05T12:49:40.768328Z","steps":["trace[247095512] 'agreement among raft nodes before linearized reading' (duration: 176.565441ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:49:40.768432Z","caller":"traceutil/trace.go:171","msg":"trace[145708864] transaction","detail":"{read_only:false; response_revision:725; number_of_response:1; }","duration":"282.082013ms","start":"2026-05-05T12:49:40.485219Z","end":"2026-05-05T12:49:40.767301Z","steps":["trace[145708864] 'process raft request' (duration: 281.758773ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:49:47.41257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.653339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T12:49:47.412672Z","caller":"traceutil/trace.go:171","msg":"trace[1504750403] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:762; }","duration":"384.864625ms","start":"2026-05-05T12:49:47.02778Z","end":"2026-05-05T12:49:47.412645Z","steps":["trace[1504750403] 'range keys from in-memory index tree' (duration: 384.574176ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:49:47.412726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:49:47.02776Z","time spent":"384.951818ms","remote":"127.0.0.1:50260","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-05T12:49:47.760496Z","caller":"traceutil/trace.go:171","msg":"trace[1558346377] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"721.623636ms","start":"2026-05-05T12:49:47.038859Z","end":"2026-05-05T12:49:47.760482Z","steps":["trace[1558346377] 'process raft request' (duration: 721.41897ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:49:47.760614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:49:47.038826Z","time spent":"721.72283ms","remote":"127.0.0.1:40290","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-05T12:49:47.76071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.57254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T12:49:47.760792Z","caller":"traceutil/trace.go:171","msg":"trace[42998009] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:763; }","duration":"346.652482ms","start":"2026-05-05T12:49:47.414122Z","end":"2026-05-05T12:49:47.760775Z","steps":["trace[42998009] 'agreement among raft nodes before linearized reading' (duration: 346.545189ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:49:47.760865Z","caller":"traceutil/trace.go:171","msg":"trace[905865074] linearizableReadLoop","detail":"{readStateIndex:790; appliedIndex:789; }","duration":"346.313922ms","start":"2026-05-05T12:49:47.414137Z","end":"2026-05-05T12:49:47.760451Z","steps":["trace[905865074] 'read index received' (duration: 346.112516ms)","trace[905865074] 'applied index is now lower than readState.Index' (duration: 200.756µs)"],"step_count":2} {"level":"warn","ts":"2026-05-05T12:49:47.760916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:49:47.414066Z","time spent":"346.810188ms","remote":"127.0.0.1:50268","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-05T12:49:47.761017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.087892ms","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-05T12:49:47.761038Z","caller":"traceutil/trace.go:171","msg":"trace[425646700] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:763; }","duration":"225.132284ms","start":"2026-05-05T12:49:47.5359Z","end":"2026-05-05T12:49:47.761033Z","steps":["trace[425646700] 'agreement among raft nodes before linearized reading' (duration: 225.055281ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:49:47.761167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.77135ms","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-05T12:49:47.761187Z","caller":"traceutil/trace.go:171","msg":"trace[458929662] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:763; }","duration":"282.821431ms","start":"2026-05-05T12:49:47.478359Z","end":"2026-05-05T12:49:47.76118Z","steps":["trace[458929662] 'agreement among raft nodes before linearized reading' (duration: 282.553413ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:49:47.915258Z","caller":"traceutil/trace.go:171","msg":"trace[1182704355] transaction","detail":"{read_only:false; response_revision:765; number_of_response:1; }","duration":"149.957845ms","start":"2026-05-05T12:49:47.765272Z","end":"2026-05-05T12:49:47.91523Z","steps":["trace[1182704355] 'process raft request' (duration: 82.17727ms)","trace[1182704355] 'compare' (duration: 67.665272ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T12:49:48.294942Z","caller":"traceutil/trace.go:171","msg":"trace[1924964383] transaction","detail":"{read_only:false; response_revision:766; number_of_response:1; }","duration":"110.372697ms","start":"2026-05-05T12:49:48.18454Z","end":"2026-05-05T12:49:48.294913Z","steps":["trace[1924964383] 'process raft request' (duration: 110.219233ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:49:48.459185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.261122ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T12:49:48.459301Z","caller":"traceutil/trace.go:171","msg":"trace[1645578100] linearizableReadLoop","detail":"{readStateIndex:794; appliedIndex:793; }","duration":"125.467764ms","start":"2026-05-05T12:49:48.333814Z","end":"2026-05-05T12:49:48.459282Z","steps":["trace[1645578100] 'read index received' (duration: 22.933353ms)","trace[1645578100] 'applied index is now lower than readState.Index' (duration: 102.533331ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T12:49:48.459453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.644099ms","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-05T12:49:48.459493Z","caller":"traceutil/trace.go:171","msg":"trace[1981363322] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:767; }","duration":"125.720801ms","start":"2026-05-05T12:49:48.333761Z","end":"2026-05-05T12:49:48.459482Z","steps":["trace[1981363322] 'agreement among raft nodes before linearized reading' (duration: 125.571796ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:49:48.459368Z","caller":"traceutil/trace.go:171","msg":"trace[541979859] transaction","detail":"{read_only:false; response_revision:767; number_of_response:1; }","duration":"273.839698ms","start":"2026-05-05T12:49:48.185464Z","end":"2026-05-05T12:49:48.459304Z","steps":["trace[541979859] 'process raft request' (duration: 171.37857ms)","trace[541979859] 'compare' (duration: 102.143178ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T12:49:51.755885Z","caller":"traceutil/trace.go:171","msg":"trace[1167854702] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"233.779426ms","start":"2026-05-05T12:49:51.522085Z","end":"2026-05-05T12:49:51.755864Z","steps":["trace[1167854702] 'process raft request' (duration: 233.648872ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:49:52.807555Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656052474856634511,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T12:49:52.857242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.046585042s","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-05T12:49:52.857325Z","caller":"traceutil/trace.go:171","msg":"trace[419293186] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:788; }","duration":"1.046733277s","start":"2026-05-05T12:49:51.810575Z","end":"2026-05-05T12:49:52.857308Z","steps":["trace[419293186] 'range keys from in-memory index tree' (duration: 1.046424068s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:49:52.857449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:49:51.81056Z","time spent":"1.046802139s","remote":"127.0.0.1:40290","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-05T12:49:52.857662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"928.579174ms","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-05T12:49:52.857765Z","caller":"traceutil/trace.go:171","msg":"trace[542825365] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:788; }","duration":"928.748189ms","start":"2026-05-05T12:49:51.928992Z","end":"2026-05-05T12:49:52.85774Z","steps":["trace[542825365] 'range keys from in-memory index tree' (duration: 928.302285ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:49:52.857827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:49:51.928966Z","time spent":"928.848283ms","remote":"127.0.0.1:40290","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-05T12:49:52.862192Z","caller":"traceutil/trace.go:171","msg":"trace[1993089067] linearizableReadLoop","detail":"{readStateIndex:817; appliedIndex:815; }","duration":"555.418128ms","start":"2026-05-05T12:49:52.306749Z","end":"2026-05-05T12:49:52.862167Z","steps":["trace[1993089067] 'read index received' (duration: 533.834458ms)","trace[1993089067] 'applied index is now lower than readState.Index' (duration: 21.5826ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T12:49:52.862799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.526502ms","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-05T12:49:52.862838Z","caller":"traceutil/trace.go:171","msg":"trace[1742819346] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:789; }","duration":"389.777759ms","start":"2026-05-05T12:49:52.473051Z","end":"2026-05-05T12:49:52.862829Z","steps":["trace[1742819346] 'agreement among raft nodes before linearized reading' (duration: 389.47765ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:49:52.862859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:49:52.47303Z","time spent":"389.824141ms","remote":"127.0.0.1:40290","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-05T12:49:52.862907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"556.14238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-05T12:49:52.862978Z","caller":"traceutil/trace.go:171","msg":"trace[1753516036] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:789; }","duration":"556.263974ms","start":"2026-05-05T12:49:52.306698Z","end":"2026-05-05T12:49:52.862962Z","steps":["trace[1753516036] 'agreement among raft nodes before linearized reading' (duration: 555.888552ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:49:52.863001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.790719ms","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-05T12:49:52.863016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:49:52.306678Z","time spent":"556.327266ms","remote":"127.0.0.1:40290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-05T12:49:52.863029Z","caller":"traceutil/trace.go:171","msg":"trace[1000635094] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:789; }","duration":"362.85372ms","start":"2026-05-05T12:49:52.500169Z","end":"2026-05-05T12:49:52.863022Z","steps":["trace[1000635094] 'agreement among raft nodes before linearized reading' (duration: 362.789068ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:49:52.86305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:49:52.500149Z","time spent":"362.897222ms","remote":"127.0.0.1:40290","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-05T12:50:23.443425Z","caller":"traceutil/trace.go:171","msg":"trace[1494641627] transaction","detail":"{read_only:false; response_revision:983; number_of_response:1; }","duration":"351.194236ms","start":"2026-05-05T12:50:23.092207Z","end":"2026-05-05T12:50:23.443401Z","steps":["trace[1494641627] 'process raft request' (duration: 350.98556ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:50:23.443562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:50:23.092186Z","time spent":"351.305149ms","remote":"127.0.0.1:40290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T12:50:23.443548Z","caller":"traceutil/trace.go:171","msg":"trace[2044348175] linearizableReadLoop","detail":"{readStateIndex:1019; appliedIndex:1018; }","duration":"260.2455ms","start":"2026-05-05T12:50:23.183262Z","end":"2026-05-05T12:50:23.443507Z","steps":["trace[2044348175] 'read index received' (duration: 259.95002ms)","trace[2044348175] 'applied index is now lower than readState.Index' (duration: 293.38µs)"],"step_count":2} {"level":"warn","ts":"2026-05-05T12:50:23.443724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.437316ms","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-05T12:50:23.443748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.825939ms","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-05T12:50:23.443791Z","caller":"traceutil/trace.go:171","msg":"trace[1483469370] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:983; }","duration":"260.547429ms","start":"2026-05-05T12:50:23.183228Z","end":"2026-05-05T12:50:23.443776Z","steps":["trace[1483469370] 'agreement among raft nodes before linearized reading' (duration: 260.312322ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:50:23.443791Z","caller":"traceutil/trace.go:171","msg":"trace[1309160679] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:983; }","duration":"194.8794ms","start":"2026-05-05T12:50:23.248901Z","end":"2026-05-05T12:50:23.443781Z","steps":["trace[1309160679] 'agreement among raft nodes before linearized reading' (duration: 194.774107ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:50:23.443798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.151549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-05-05T12:50:23.443869Z","caller":"traceutil/trace.go:171","msg":"trace[1632678578] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:983; }","duration":"195.254443ms","start":"2026-05-05T12:50:23.248596Z","end":"2026-05-05T12:50:23.443851Z","steps":["trace[1632678578] 'agreement among raft nodes before linearized reading' (duration: 195.061336ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:50:23.443946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.236851ms","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-05T12:50:23.44398Z","caller":"traceutil/trace.go:171","msg":"trace[1079746878] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:983; }","duration":"195.307954ms","start":"2026-05-05T12:50:23.248663Z","end":"2026-05-05T12:50:23.443971Z","steps":["trace[1079746878] 'agreement among raft nodes before linearized reading' (duration: 195.205821ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:50:23.568689Z","caller":"traceutil/trace.go:171","msg":"trace[2092449451] transaction","detail":"{read_only:false; response_revision:984; number_of_response:1; }","duration":"121.347823ms","start":"2026-05-05T12:50:23.447316Z","end":"2026-05-05T12:50:23.568664Z","steps":["trace[2092449451] 'process raft request' (duration: 87.429764ms)","trace[2092449451] 'compare' (duration: 33.790055ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T12:50:23.598763Z","caller":"traceutil/trace.go:171","msg":"trace[519868315] transaction","detail":"{read_only:false; response_revision:986; number_of_response:1; }","duration":"150.527313ms","start":"2026-05-05T12:50:23.44821Z","end":"2026-05-05T12:50:23.598738Z","steps":["trace[519868315] 'process raft request' (duration: 150.459501ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:50:23.5988Z","caller":"traceutil/trace.go:171","msg":"trace[1062027671] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"150.769651ms","start":"2026-05-05T12:50:23.44801Z","end":"2026-05-05T12:50:23.59878Z","steps":["trace[1062027671] 'process raft request' (duration: 150.526843ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:50:23.598956Z","caller":"traceutil/trace.go:171","msg":"trace[2141000990] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"150.575355ms","start":"2026-05-05T12:50:23.448354Z","end":"2026-05-05T12:50:23.598929Z","steps":["trace[2141000990] 'process raft request' (duration: 150.352078ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:50:29.17432Z","caller":"traceutil/trace.go:171","msg":"trace[1557868339] linearizableReadLoop","detail":"{readStateIndex:1043; appliedIndex:1042; }","duration":"147.619531ms","start":"2026-05-05T12:50:29.026675Z","end":"2026-05-05T12:50:29.174294Z","steps":["trace[1557868339] 'read index received' (duration: 147.346882ms)","trace[1557868339] 'applied index is now lower than readState.Index' (duration: 271.509µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T12:50:29.174382Z","caller":"traceutil/trace.go:171","msg":"trace[1847474846] transaction","detail":"{read_only:false; response_revision:1006; number_of_response:1; }","duration":"149.558932ms","start":"2026-05-05T12:50:29.024799Z","end":"2026-05-05T12:50:29.174358Z","steps":["trace[1847474846] 'process raft request' (duration: 149.327215ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:50:29.174454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.759256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T12:50:29.174489Z","caller":"traceutil/trace.go:171","msg":"trace[2086601977] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1006; }","duration":"147.841388ms","start":"2026-05-05T12:50:29.026637Z","end":"2026-05-05T12:50:29.174478Z","steps":["trace[2086601977] 'agreement among raft nodes before linearized reading' (duration: 147.766355ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:50:30.097686Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656052474856634978,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-05T12:50:30.224563Z","caller":"traceutil/trace.go:171","msg":"trace[1418393442] transaction","detail":"{read_only:false; response_revision:1007; number_of_response:1; }","duration":"752.84122ms","start":"2026-05-05T12:50:29.4717Z","end":"2026-05-05T12:50:30.224541Z","steps":["trace[1418393442] 'process raft request' (duration: 752.719576ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:50:30.224689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:50:29.471688Z","time spent":"752.936683ms","remote":"127.0.0.1:40290","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-05T12:50:31.159725Z","caller":"traceutil/trace.go:171","msg":"trace[1108013132] transaction","detail":"{read_only:false; response_revision:1008; number_of_response:1; }","duration":"1.452541256s","start":"2026-05-05T12:50:29.707156Z","end":"2026-05-05T12:50:31.159697Z","steps":["trace[1108013132] 'process raft request' (duration: 1.45233851s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:50:31.159881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:50:29.707132Z","time spent":"1.452677271s","remote":"127.0.0.1:40290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T12:50:31.159884Z","caller":"traceutil/trace.go:171","msg":"trace[1645368064] linearizableReadLoop","detail":"{readStateIndex:1046; appliedIndex:1043; }","duration":"1.562952165s","start":"2026-05-05T12:50:29.596893Z","end":"2026-05-05T12:50:31.159845Z","steps":["trace[1645368064] 'read index received' (duration: 627.556732ms)","trace[1645368064] 'applied index is now lower than readState.Index' (duration: 935.393733ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T12:50:31.160029Z","caller":"traceutil/trace.go:171","msg":"trace[191251350] transaction","detail":"{read_only:false; response_revision:1009; number_of_response:1; }","duration":"1.360120445s","start":"2026-05-05T12:50:29.799897Z","end":"2026-05-05T12:50:31.160017Z","steps":["trace[191251350] 'process raft request' (duration: 1.359746503s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:50:31.16016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:50:29.799872Z","time spent":"1.360193737s","remote":"127.0.0.1:40290","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-05T12:50:31.160169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.536068669s","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-05T12:50:31.160223Z","caller":"traceutil/trace.go:171","msg":"trace[1529838163] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1009; }","duration":"1.536158922s","start":"2026-05-05T12:50:29.624048Z","end":"2026-05-05T12:50:31.160207Z","steps":["trace[1529838163] 'agreement among raft nodes before linearized reading' (duration: 1.535993356s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:50:31.160275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:50:29.624026Z","time spent":"1.536237474s","remote":"127.0.0.1:40290","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-05T12:50:31.160351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.132884866s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T12:50:31.160412Z","caller":"traceutil/trace.go:171","msg":"trace[381074278] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1009; }","duration":"1.132970328s","start":"2026-05-05T12:50:30.027424Z","end":"2026-05-05T12:50:31.160394Z","steps":["trace[381074278] 'agreement among raft nodes before linearized reading' (duration: 1.132883146s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:50:31.160443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.527817799s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-05T12:50:31.160453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:50:30.027408Z","time spent":"1.133037s","remote":"127.0.0.1:50260","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-05T12:50:31.160476Z","caller":"traceutil/trace.go:171","msg":"trace[759279441] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1009; }","duration":"1.52786517s","start":"2026-05-05T12:50:29.632601Z","end":"2026-05-05T12:50:31.160466Z","steps":["trace[759279441] 'agreement among raft nodes before linearized reading' (duration: 1.527768707s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:50:31.160505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:50:29.632592Z","time spent":"1.527907022s","remote":"127.0.0.1:40290","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-05T12:50:31.160631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.527999634s","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-05T12:50:31.160657Z","caller":"traceutil/trace.go:171","msg":"trace[1786257028] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1009; }","duration":"1.528068396s","start":"2026-05-05T12:50:29.632581Z","end":"2026-05-05T12:50:31.160649Z","steps":["trace[1786257028] 'agreement among raft nodes before linearized reading' (duration: 1.527981244s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:50:31.160684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:50:29.632565Z","time spent":"1.528112897s","remote":"127.0.0.1:40290","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-05T12:50:31.160742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.563841224s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-05T12:50:31.160795Z","caller":"traceutil/trace.go:171","msg":"trace[839800401] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1009; }","duration":"1.563919076s","start":"2026-05-05T12:50:29.596861Z","end":"2026-05-05T12:50:31.16078Z","steps":["trace[839800401] 'agreement among raft nodes before linearized reading' (duration: 1.563762691s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:50:31.160878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:50:29.596846Z","time spent":"1.564018689s","remote":"127.0.0.1:40290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-05T12:50:32.667822Z","caller":"traceutil/trace.go:171","msg":"trace[1374999960] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"435.700708ms","start":"2026-05-05T12:50:32.232097Z","end":"2026-05-05T12:50:32.667798Z","steps":["trace[1374999960] 'process raft request' (duration: 435.542483ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:50:32.667973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:50:32.232067Z","time spent":"435.828802ms","remote":"127.0.0.1:40290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T12:50:35.406471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.274249ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T12:50:35.406573Z","caller":"traceutil/trace.go:171","msg":"trace[2089369924] transaction","detail":"{read_only:false; response_revision:1022; number_of_response:1; }","duration":"181.377085ms","start":"2026-05-05T12:50:35.225174Z","end":"2026-05-05T12:50:35.406551Z","steps":["trace[2089369924] 'process raft request' (duration: 27.948331ms)","trace[2089369924] 'compare' (duration: 153.103424ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T12:50:35.678527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.9464ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T12:50:35.678722Z","caller":"traceutil/trace.go:171","msg":"trace[55108570] linearizableReadLoop","detail":"{readStateIndex:1063; appliedIndex:1060; }","duration":"275.284094ms","start":"2026-05-05T12:50:35.403415Z","end":"2026-05-05T12:50:35.678699Z","steps":["trace[55108570] 'read index received' (duration: 108.009254ms)","trace[55108570] 'applied index is now lower than readState.Index' (duration: 167.27417ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T12:50:35.678785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.371057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T12:50:35.678803Z","caller":"traceutil/trace.go:171","msg":"trace[1694782485] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1025; }","duration":"275.432748ms","start":"2026-05-05T12:50:35.403364Z","end":"2026-05-05T12:50:35.678797Z","steps":["trace[1694782485] 'agreement among raft nodes before linearized reading' (duration: 275.381327ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:50:35.678833Z","caller":"traceutil/trace.go:171","msg":"trace[699726416] transaction","detail":"{read_only:false; response_revision:1024; number_of_response:1; }","duration":"453.602333ms","start":"2026-05-05T12:50:35.225206Z","end":"2026-05-05T12:50:35.678808Z","steps":["trace[699726416] 'process raft request' (duration: 453.396786ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:50:35.67888Z","caller":"traceutil/trace.go:171","msg":"trace[595849158] transaction","detail":"{read_only:false; response_revision:1023; number_of_response:1; }","duration":"453.652504ms","start":"2026-05-05T12:50:35.225208Z","end":"2026-05-05T12:50:35.67886Z","steps":["trace[595849158] 'process raft request' (duration: 286.295731ms)","trace[595849158] 'compare' (duration: 166.788475ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T12:50:35.678915Z","caller":"traceutil/trace.go:171","msg":"trace[177094628] transaction","detail":"{read_only:false; response_revision:1025; number_of_response:1; }","duration":"453.074466ms","start":"2026-05-05T12:50:35.225802Z","end":"2026-05-05T12:50:35.678876Z","steps":["trace[177094628] 'process raft request' (duration: 452.865399ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:50:35.678964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:50:35.225187Z","time spent":"453.733226ms","remote":"127.0.0.1:40290","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-05T12:50:35.67897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:50:35.225184Z","time spent":"453.707165ms","remote":"127.0.0.1:40290","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-05T12:50:35.679037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:50:35.22579Z","time spent":"453.200179ms","remote":"127.0.0.1:40290","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-05T12:50:44.897871Z","caller":"traceutil/trace.go:171","msg":"trace[674829977] transaction","detail":"{read_only:false; response_revision:1053; number_of_response:1; }","duration":"103.431899ms","start":"2026-05-05T12:50:44.794414Z","end":"2026-05-05T12:50:44.897846Z","steps":["trace[674829977] 'process raft request' (duration: 103.247124ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:50:51.068256Z","caller":"traceutil/trace.go:171","msg":"trace[398383082] transaction","detail":"{read_only:false; response_revision:1073; number_of_response:1; }","duration":"120.298637ms","start":"2026-05-05T12:50:50.94794Z","end":"2026-05-05T12:50:51.068238Z","steps":["trace[398383082] 'process raft request' (duration: 120.189274ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:50:54.102852Z","caller":"traceutil/trace.go:171","msg":"trace[1631805276] linearizableReadLoop","detail":"{readStateIndex:1125; appliedIndex:1124; }","duration":"244.601338ms","start":"2026-05-05T12:50:53.858181Z","end":"2026-05-05T12:50:54.102783Z","steps":["trace[1631805276] 'read index received' (duration: 244.431153ms)","trace[1631805276] 'applied index is now lower than readState.Index' (duration: 169.125µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T12:50:54.10296Z","caller":"traceutil/trace.go:171","msg":"trace[1233625052] transaction","detail":"{read_only:false; response_revision:1083; number_of_response:1; }","duration":"265.829095ms","start":"2026-05-05T12:50:53.83707Z","end":"2026-05-05T12:50:54.102899Z","steps":["trace[1233625052] 'process raft request' (duration: 265.542287ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:50:54.103064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.860876ms","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-05T12:50:54.103136Z","caller":"traceutil/trace.go:171","msg":"trace[698562370] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1083; }","duration":"244.972259ms","start":"2026-05-05T12:50:53.858146Z","end":"2026-05-05T12:50:54.103118Z","steps":["trace[698562370] 'agreement among raft nodes before linearized reading' (duration: 244.789614ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:50:54.103142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.786975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-05T12:50:54.103198Z","caller":"traceutil/trace.go:171","msg":"trace[1086186694] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1083; }","duration":"205.904179ms","start":"2026-05-05T12:50:53.897279Z","end":"2026-05-05T12:50:54.103183Z","steps":["trace[1086186694] 'agreement among raft nodes before linearized reading' (duration: 205.701023ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:50:56.472489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.113268ms","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-05T12:50:56.472631Z","caller":"traceutil/trace.go:171","msg":"trace[1479888435] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1086; }","duration":"209.268663ms","start":"2026-05-05T12:50:56.26334Z","end":"2026-05-05T12:50:56.472609Z","steps":["trace[1479888435] 'range keys from in-memory index tree' (duration: 209.096948ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:50:56.472884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.152071ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T12:50:56.473045Z","caller":"traceutil/trace.go:171","msg":"trace[1789051926] linearizableReadLoop","detail":"{readStateIndex:1129; appliedIndex:1128; }","duration":"912.128908ms","start":"2026-05-05T12:50:55.5609Z","end":"2026-05-05T12:50:56.473028Z","steps":["trace[1789051926] 'read index received' (duration: 476.650217ms)","trace[1789051926] 'applied index is now lower than readState.Index' (duration: 435.477081ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T12:50:56.473409Z","caller":"traceutil/trace.go:171","msg":"trace[1784140579] transaction","detail":"{read_only:false; response_revision:1087; number_of_response:1; }","duration":"1.028613056s","start":"2026-05-05T12:50:55.444779Z","end":"2026-05-05T12:50:56.473393Z","steps":["trace[1784140579] 'process raft request' (duration: 592.839216ms)","trace[1784140579] 'compare' (duration: 434.7959ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T12:50:56.473513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:50:55.444754Z","time spent":"1.028709869s","remote":"127.0.0.1:40290","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-05T12:50:56.473997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"913.105347ms","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-05T12:50:56.47406Z","caller":"traceutil/trace.go:171","msg":"trace[722706844] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1087; }","duration":"913.253913ms","start":"2026-05-05T12:50:55.560773Z","end":"2026-05-05T12:50:56.474027Z","steps":["trace[722706844] 'agreement among raft nodes before linearized reading' (duration: 913.094148ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:50:56.474112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.960073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-05T12:50:56.474161Z","caller":"traceutil/trace.go:171","msg":"trace[73974135] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1087; }","duration":"311.050216ms","start":"2026-05-05T12:50:56.163098Z","end":"2026-05-05T12:50:56.474149Z","steps":["trace[73974135] 'agreement among raft nodes before linearized reading' (duration: 310.908902ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:50:56.474159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:50:55.560753Z","time spent":"913.395976ms","remote":"127.0.0.1:40290","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-05T12:50:56.474197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:50:56.163078Z","time spent":"311.111568ms","remote":"127.0.0.1:40290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-05T12:50:56.474352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.640764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-05T12:50:56.474413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.778758ms","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-05T12:50:56.474448Z","caller":"traceutil/trace.go:171","msg":"trace[1788261059] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1087; }","duration":"311.84939ms","start":"2026-05-05T12:50:56.162588Z","end":"2026-05-05T12:50:56.474438Z","steps":["trace[1788261059] 'agreement among raft nodes before linearized reading' (duration: 311.743707ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:50:56.47445Z","caller":"traceutil/trace.go:171","msg":"trace[1901088019] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:1087; }","duration":"106.791518ms","start":"2026-05-05T12:50:56.367636Z","end":"2026-05-05T12:50:56.474427Z","steps":["trace[1901088019] 'agreement among raft nodes before linearized reading' (duration: 106.604702ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:50:56.474483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:50:56.162567Z","time spent":"311.908963ms","remote":"127.0.0.1:40290","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-05T12:50:56.474354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.862761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T12:50:56.474583Z","caller":"traceutil/trace.go:171","msg":"trace[1400728697] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1087; }","duration":"445.141991ms","start":"2026-05-05T12:50:56.029432Z","end":"2026-05-05T12:50:56.474574Z","steps":["trace[1400728697] 'agreement among raft nodes before linearized reading' (duration: 444.553962ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:50:56.47461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:50:56.029401Z","time spent":"445.202053ms","remote":"127.0.0.1:50260","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-05T12:50:56.475519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.710092ms","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-05T12:50:56.475756Z","caller":"traceutil/trace.go:171","msg":"trace[272887019] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1087; }","duration":"367.855986ms","start":"2026-05-05T12:50:56.107732Z","end":"2026-05-05T12:50:56.475588Z","steps":["trace[272887019] 'agreement among raft nodes before linearized reading' (duration: 366.607047ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:50:56.475814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:50:56.107709Z","time spent":"368.091933ms","remote":"127.0.0.1:40290","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":"info","ts":"2026-05-05T12:50:56.612778Z","caller":"traceutil/trace.go:171","msg":"trace[124566238] transaction","detail":"{read_only:false; response_revision:1089; number_of_response:1; }","duration":"134.197337ms","start":"2026-05-05T12:50:56.478543Z","end":"2026-05-05T12:50:56.612741Z","steps":["trace[124566238] 'process raft request' (duration: 120.07791ms)","trace[124566238] 'compare' (duration: 13.88964ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T12:50:56.612771Z","caller":"traceutil/trace.go:171","msg":"trace[1412035768] transaction","detail":"{read_only:false; response_revision:1090; number_of_response:1; }","duration":"133.134284ms","start":"2026-05-05T12:50:56.479611Z","end":"2026-05-05T12:50:56.612745Z","steps":["trace[1412035768] 'process raft request' (duration: 133.058572ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:50:56.61297Z","caller":"traceutil/trace.go:171","msg":"trace[436259950] transaction","detail":"{read_only:false; response_revision:1091; number_of_response:1; }","duration":"132.489534ms","start":"2026-05-05T12:50:56.480447Z","end":"2026-05-05T12:50:56.612936Z","steps":["trace[436259950] 'process raft request' (duration: 132.263657ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:50:58.591127Z","caller":"traceutil/trace.go:171","msg":"trace[945866224] transaction","detail":"{read_only:false; response_revision:1094; number_of_response:1; }","duration":"111.433143ms","start":"2026-05-05T12:50:58.479668Z","end":"2026-05-05T12:50:58.591101Z","steps":["trace[945866224] 'process raft request' (duration: 111.02322ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:50:58.811862Z","caller":"traceutil/trace.go:171","msg":"trace[1663055301] linearizableReadLoop","detail":"{readStateIndex:1138; appliedIndex:1137; }","duration":"211.206592ms","start":"2026-05-05T12:50:58.600632Z","end":"2026-05-05T12:50:58.811839Z","steps":["trace[1663055301] 'read index received' (duration: 211.008646ms)","trace[1663055301] 'applied index is now lower than readState.Index' (duration: 196.876µs)"],"step_count":2} {"level":"warn","ts":"2026-05-05T12:50:58.812563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.669311ms","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-05T12:50:58.81263Z","caller":"traceutil/trace.go:171","msg":"trace[578484718] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1095; }","duration":"195.767824ms","start":"2026-05-05T12:50:58.616843Z","end":"2026-05-05T12:50:58.81261Z","steps":["trace[578484718] 'agreement among raft nodes before linearized reading' (duration: 195.589699ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:50:58.812696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.044358ms","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-05T12:50:58.81273Z","caller":"traceutil/trace.go:171","msg":"trace[1131795883] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1095; }","duration":"212.132281ms","start":"2026-05-05T12:50:58.600589Z","end":"2026-05-05T12:50:58.812721Z","steps":["trace[1131795883] 'agreement among raft nodes before linearized reading' (duration: 212.038578ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:50:58.812774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.251938ms","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-05T12:50:58.812795Z","caller":"traceutil/trace.go:171","msg":"trace[1454850314] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1095; }","duration":"195.31424ms","start":"2026-05-05T12:50:58.617474Z","end":"2026-05-05T12:50:58.812788Z","steps":["trace[1454850314] 'agreement among raft nodes before linearized reading' (duration: 195.248718ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:50:58.812898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.413433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-05-05T12:50:58.812892Z","caller":"traceutil/trace.go:171","msg":"trace[1389155833] transaction","detail":"{read_only:false; response_revision:1095; number_of_response:1; }","duration":"216.933739ms","start":"2026-05-05T12:50:58.595945Z","end":"2026-05-05T12:50:58.812879Z","steps":["trace[1389155833] 'process raft request' (duration: 215.747683ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:50:58.812914Z","caller":"traceutil/trace.go:171","msg":"trace[1629593376] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1095; }","duration":"195.441443ms","start":"2026-05-05T12:50:58.617467Z","end":"2026-05-05T12:50:58.812909Z","steps":["trace[1629593376] 'agreement among raft nodes before linearized reading' (duration: 195.396172ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:51:01.317772Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656052474856635222,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-05T12:51:01.325126Z","caller":"traceutil/trace.go:171","msg":"trace[1215052392] linearizableReadLoop","detail":"{readStateIndex:1144; appliedIndex:1143; }","duration":"507.73698ms","start":"2026-05-05T12:51:00.817367Z","end":"2026-05-05T12:51:01.325104Z","steps":["trace[1215052392] 'read index received' (duration: 507.567115ms)","trace[1215052392] 'applied index is now lower than readState.Index' (duration: 169.085µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T12:51:01.325187Z","caller":"traceutil/trace.go:171","msg":"trace[1714756566] transaction","detail":"{read_only:false; response_revision:1101; number_of_response:1; }","duration":"729.525481ms","start":"2026-05-05T12:51:00.595639Z","end":"2026-05-05T12:51:01.325164Z","steps":["trace[1714756566] 'process raft request' (duration: 729.337295ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:51:01.325309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:51:00.595602Z","time spent":"729.629644ms","remote":"127.0.0.1:40290","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-05T12:51:01.325508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.106641ms","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-05T12:51:01.325544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.624221ms","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-05T12:51:01.32553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.460858ms","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-05T12:51:01.325587Z","caller":"traceutil/trace.go:171","msg":"trace[577260052] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1101; }","duration":"508.241876ms","start":"2026-05-05T12:51:00.817327Z","end":"2026-05-05T12:51:01.325569Z","steps":["trace[577260052] 'agreement among raft nodes before linearized reading' (duration: 507.975047ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:51:01.325592Z","caller":"traceutil/trace.go:171","msg":"trace[392979002] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1101; }","duration":"431.696974ms","start":"2026-05-05T12:51:00.893882Z","end":"2026-05-05T12:51:01.325579Z","steps":["trace[392979002] 'agreement among raft nodes before linearized reading' (duration: 431.547969ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:51:01.325609Z","caller":"traceutil/trace.go:171","msg":"trace[390418621] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1101; }","duration":"442.584691ms","start":"2026-05-05T12:51:00.883007Z","end":"2026-05-05T12:51:01.325592Z","steps":["trace[390418621] 'agreement among raft nodes before linearized reading' (duration: 442.390115ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:51:01.325586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.885127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-05-05T12:51:01.325632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:51:00.817307Z","time spent":"508.316128ms","remote":"127.0.0.1:40290","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-05T12:51:01.325665Z","caller":"traceutil/trace.go:171","msg":"trace[1054453469] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1101; }","duration":"143.00411ms","start":"2026-05-05T12:51:01.182649Z","end":"2026-05-05T12:51:01.325653Z","steps":["trace[1054453469] 'agreement among raft nodes before linearized reading' (duration: 142.818434ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:51:01.325665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:51:00.89387Z","time spent":"431.782587ms","remote":"127.0.0.1:40290","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-05T12:51:01.325676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.555659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-05T12:51:01.32568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:51:00.882987Z","time spent":"442.665304ms","remote":"127.0.0.1:40290","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":"info","ts":"2026-05-05T12:51:01.325898Z","caller":"traceutil/trace.go:171","msg":"trace[2111483731] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1101; }","duration":"431.822968ms","start":"2026-05-05T12:51:00.893995Z","end":"2026-05-05T12:51:01.325818Z","steps":["trace[2111483731] 'agreement among raft nodes before linearized reading' (duration: 431.444697ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:51:01.32603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:51:00.893965Z","time spent":"432.046085ms","remote":"127.0.0.1:40290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-05T12:51:01.660735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.716959ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T12:51:01.661049Z","caller":"traceutil/trace.go:171","msg":"trace[930492292] transaction","detail":"{read_only:false; response_revision:1102; number_of_response:1; }","duration":"330.704945ms","start":"2026-05-05T12:51:01.330171Z","end":"2026-05-05T12:51:01.660875Z","steps":["trace[930492292] 'process raft request' (duration: 67.76786ms)","trace[930492292] 'compare' (duration: 262.560003ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T12:51:01.661147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:51:01.330148Z","time spent":"330.956513ms","remote":"127.0.0.1:40290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T12:51:01.662868Z","caller":"traceutil/trace.go:171","msg":"trace[942679871] transaction","detail":"{read_only:false; response_revision:1103; number_of_response:1; }","duration":"332.271523ms","start":"2026-05-05T12:51:01.330571Z","end":"2026-05-05T12:51:01.662843Z","steps":["trace[942679871] 'process raft request' (duration: 332.120438ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:51:01.663323Z","caller":"traceutil/trace.go:171","msg":"trace[1088560391] transaction","detail":"{read_only:false; response_revision:1106; number_of_response:1; }","duration":"332.48502ms","start":"2026-05-05T12:51:01.330807Z","end":"2026-05-05T12:51:01.663292Z","steps":["trace[1088560391] 'process raft request' (duration: 332.420288ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:51:01.66336Z","caller":"traceutil/trace.go:171","msg":"trace[977894601] transaction","detail":"{read_only:false; response_revision:1105; number_of_response:1; }","duration":"332.565572ms","start":"2026-05-05T12:51:01.330768Z","end":"2026-05-05T12:51:01.663334Z","steps":["trace[977894601] 'process raft request' (duration: 332.339545ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:51:01.663415Z","caller":"traceutil/trace.go:171","msg":"trace[1453324166] transaction","detail":"{read_only:false; response_revision:1104; number_of_response:1; }","duration":"332.692536ms","start":"2026-05-05T12:51:01.330702Z","end":"2026-05-05T12:51:01.663395Z","steps":["trace[1453324166] 'process raft request' (duration: 332.093338ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:51:01.663464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:51:01.330757Z","time spent":"332.653586ms","remote":"127.0.0.1:40290","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-05T12:51:01.663474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:51:01.330785Z","time spent":"332.593054ms","remote":"127.0.0.1:40290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T12:51:01.663521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:51:01.330691Z","time spent":"332.78391ms","remote":"127.0.0.1:40290","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-05T12:51:01.663524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:51:01.33056Z","time spent":"332.381897ms","remote":"127.0.0.1:40290","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-05T12:51:05.907625Z","caller":"traceutil/trace.go:171","msg":"trace[1253615848] transaction","detail":"{read_only:false; response_revision:1118; number_of_response:1; }","duration":"175.30926ms","start":"2026-05-05T12:51:05.73229Z","end":"2026-05-05T12:51:05.907599Z","steps":["trace[1253615848] 'process raft request' (duration: 175.166775ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:51:05.945795Z","caller":"traceutil/trace.go:171","msg":"trace[1576297470] transaction","detail":"{read_only:false; response_revision:1119; number_of_response:1; }","duration":"212.968256ms","start":"2026-05-05T12:51:05.732804Z","end":"2026-05-05T12:51:05.945773Z","steps":["trace[1576297470] 'process raft request' (duration: 212.871723ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:51:13.854774Z","caller":"traceutil/trace.go:171","msg":"trace[446638919] transaction","detail":"{read_only:false; response_revision:1194; number_of_response:1; }","duration":"124.878588ms","start":"2026-05-05T12:51:13.729851Z","end":"2026-05-05T12:51:13.854729Z","steps":["trace[446638919] 'process raft request' (duration: 124.572339ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:51:14.198721Z","caller":"traceutil/trace.go:171","msg":"trace[1597769392] linearizableReadLoop","detail":"{readStateIndex:1244; appliedIndex:1243; }","duration":"257.654993ms","start":"2026-05-05T12:51:13.941043Z","end":"2026-05-05T12:51:14.198698Z","steps":["trace[1597769392] 'read index received' (duration: 175.161377ms)","trace[1597769392] 'applied index is now lower than readState.Index' (duration: 82.492615ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T12:51:14.198846Z","caller":"traceutil/trace.go:171","msg":"trace[290504037] transaction","detail":"{read_only:false; response_revision:1196; number_of_response:1; }","duration":"338.293491ms","start":"2026-05-05T12:51:13.860541Z","end":"2026-05-05T12:51:14.198834Z","steps":["trace[290504037] 'process raft request' (duration: 255.726873ms)","trace[290504037] 'compare' (duration: 82.253048ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T12:51:14.198957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.543283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-05T12:51:14.198948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:51:13.860527Z","time spent":"338.343752ms","remote":"127.0.0.1:40290","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-05T12:51:14.198985Z","caller":"traceutil/trace.go:171","msg":"trace[80787220] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1196; }","duration":"211.606535ms","start":"2026-05-05T12:51:13.987371Z","end":"2026-05-05T12:51:14.198977Z","steps":["trace[80787220] 'agreement among raft nodes before linearized reading' (duration: 211.502862ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:51:14.199101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.046944ms","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-05T12:51:14.199178Z","caller":"traceutil/trace.go:171","msg":"trace[1843007093] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1196; }","duration":"258.186289ms","start":"2026-05-05T12:51:13.940972Z","end":"2026-05-05T12:51:14.199158Z","steps":["trace[1843007093] 'agreement among raft nodes before linearized reading' (duration: 257.968732ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:51:16.380516Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656052474856635442,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T12:51:16.881376Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656052474856635442,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T12:51:17.050155Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.187554079s","expected-duration":"1s"} {"level":"info","ts":"2026-05-05T12:51:17.050679Z","caller":"traceutil/trace.go:171","msg":"trace[576043982] linearizableReadLoop","detail":"{readStateIndex:1248; appliedIndex:1247; }","duration":"1.170805611s","start":"2026-05-05T12:51:15.879843Z","end":"2026-05-05T12:51:17.050648Z","steps":["trace[576043982] 'read index received' (duration: 1.170485211s)","trace[576043982] 'applied index is now lower than readState.Index' (duration: 318.31µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T12:51:17.050759Z","caller":"traceutil/trace.go:171","msg":"trace[1791335460] transaction","detail":"{read_only:false; response_revision:1200; number_of_response:1; }","duration":"1.188256681s","start":"2026-05-05T12:51:15.862491Z","end":"2026-05-05T12:51:17.050747Z","steps":["trace[1791335460] 'process raft request' (duration: 1.18789624s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:51:17.050844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:51:15.862461Z","time spent":"1.188326713s","remote":"127.0.0.1:40290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T12:51:17.050906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.171064348s","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-05T12:51:17.050952Z","caller":"traceutil/trace.go:171","msg":"trace[907747724] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1200; }","duration":"1.171155722s","start":"2026-05-05T12:51:15.879781Z","end":"2026-05-05T12:51:17.050937Z","steps":["trace[907747724] 'agreement among raft nodes before linearized reading' (duration: 1.170989106s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:51:17.051003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:51:15.879756Z","time spent":"1.171236494s","remote":"127.0.0.1:40290","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-05T12:51:17.051064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"795.683969ms","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-05T12:51:17.051085Z","caller":"traceutil/trace.go:171","msg":"trace[935616890] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1200; }","duration":"795.726821ms","start":"2026-05-05T12:51:16.255352Z","end":"2026-05-05T12:51:17.051079Z","steps":["trace[935616890] 'agreement among raft nodes before linearized reading' (duration: 795.666829ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:51:17.051107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:51:16.255336Z","time spent":"795.764852ms","remote":"127.0.0.1:40290","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-05T12:51:17.051229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.957748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T12:51:17.051265Z","caller":"traceutil/trace.go:171","msg":"trace[610909768] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:1200; }","duration":"163.02937ms","start":"2026-05-05T12:51:16.888224Z","end":"2026-05-05T12:51:17.051254Z","steps":["trace[610909768] 'agreement among raft nodes before linearized reading' (duration: 162.966778ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:51:17.051271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"796.816295ms","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-05T12:51:17.051326Z","caller":"traceutil/trace.go:171","msg":"trace[977787673] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1200; }","duration":"796.888706ms","start":"2026-05-05T12:51:16.254419Z","end":"2026-05-05T12:51:17.051307Z","steps":["trace[977787673] 'agreement among raft nodes before linearized reading' (duration: 796.6809ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:51:17.051376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:51:16.254402Z","time spent":"796.961849ms","remote":"127.0.0.1:40290","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-05T12:51:17.051435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.216657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.227\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-05T12:51:17.051456Z","caller":"traceutil/trace.go:171","msg":"trace[825607762] range","detail":"{range_begin:/registry/masterleases/199.204.45.227; range_end:; response_count:1; response_revision:1200; }","duration":"317.28038ms","start":"2026-05-05T12:51:16.73417Z","end":"2026-05-05T12:51:17.05145Z","steps":["trace[825607762] 'agreement among raft nodes before linearized reading' (duration: 317.218288ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:51:17.051472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:51:16.734148Z","time spent":"317.32093ms","remote":"127.0.0.1:50312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.227\" "} {"level":"warn","ts":"2026-05-05T12:51:17.051564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"850.049234ms","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-05T12:51:17.051625Z","caller":"traceutil/trace.go:171","msg":"trace[177155211] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1200; }","duration":"850.135166ms","start":"2026-05-05T12:51:16.201476Z","end":"2026-05-05T12:51:17.051611Z","steps":["trace[177155211] 'agreement among raft nodes before linearized reading' (duration: 850.021313ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:51:17.051667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:51:16.201458Z","time spent":"850.200588ms","remote":"127.0.0.1:40290","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-05T12:51:17.05157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"744.142822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T12:51:17.05173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.022595739s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T12:51:17.051753Z","caller":"traceutil/trace.go:171","msg":"trace[1663349028] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1200; }","duration":"1.022642881s","start":"2026-05-05T12:51:16.029104Z","end":"2026-05-05T12:51:17.051747Z","steps":["trace[1663349028] 'agreement among raft nodes before linearized reading' (duration: 1.02260996s)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:51:17.051757Z","caller":"traceutil/trace.go:171","msg":"trace[1746985297] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1200; }","duration":"744.348949ms","start":"2026-05-05T12:51:16.307397Z","end":"2026-05-05T12:51:17.051746Z","steps":["trace[1746985297] 'agreement among raft nodes before linearized reading' (duration: 744.154923ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:51:17.051774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:51:16.029093Z","time spent":"1.022677281s","remote":"127.0.0.1:50260","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-05T12:51:17.051809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:51:16.307383Z","time spent":"744.411461ms","remote":"127.0.0.1:36316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-05T12:51:17.051051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.163963739s","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-05T12:51:17.051988Z","caller":"traceutil/trace.go:171","msg":"trace[1506442404] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1200; }","duration":"1.16496102s","start":"2026-05-05T12:51:15.887017Z","end":"2026-05-05T12:51:17.051978Z","steps":["trace[1506442404] 'agreement among raft nodes before linearized reading' (duration: 1.163915417s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:51:17.05202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:51:15.887007Z","time spent":"1.165002721s","remote":"127.0.0.1:40060","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":31,"response size":30,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"warn","ts":"2026-05-05T12:51:17.84251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"687.583501ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-05-05T12:51:17.842605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:51:17.052672Z","time spent":"789.929042ms","remote":"127.0.0.1:50312","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-05T12:51:17.842732Z","caller":"traceutil/trace.go:171","msg":"trace[1314984438] transaction","detail":"{read_only:false; response_revision:1204; number_of_response:1; }","duration":"786.081033ms","start":"2026-05-05T12:51:17.056638Z","end":"2026-05-05T12:51:17.842719Z","steps":["trace[1314984438] 'process raft request' (duration: 786.057772ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:51:17.842827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:51:17.056626Z","time spent":"786.137354ms","remote":"127.0.0.1:40290","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-05T12:51:17.842934Z","caller":"traceutil/trace.go:171","msg":"trace[892414074] linearizableReadLoop","detail":"{readStateIndex:1249; appliedIndex:1248; }","duration":"790.033635ms","start":"2026-05-05T12:51:17.052894Z","end":"2026-05-05T12:51:17.842927Z","steps":["trace[892414074] 'read index received' (duration: 102.035661ms)","trace[892414074] 'applied index is now lower than readState.Index' (duration: 687.997164ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T12:51:17.842995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"790.098517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T12:51:17.843021Z","caller":"traceutil/trace.go:171","msg":"trace[1117731834] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1204; }","duration":"790.124878ms","start":"2026-05-05T12:51:17.05289Z","end":"2026-05-05T12:51:17.843015Z","steps":["trace[1117731834] 'agreement among raft nodes before linearized reading' (duration: 790.067156ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:51:17.843037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:51:17.052868Z","time spent":"790.166229ms","remote":"127.0.0.1:50268","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-05T12:51:17.843116Z","caller":"traceutil/trace.go:171","msg":"trace[229530410] transaction","detail":"{read_only:false; response_revision:1203; number_of_response:1; }","duration":"786.782464ms","start":"2026-05-05T12:51:17.056329Z","end":"2026-05-05T12:51:17.843111Z","steps":["trace[229530410] 'process raft request' (duration: 786.34886ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:51:17.843149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:51:17.056295Z","time spent":"786.836965ms","remote":"127.0.0.1:40290","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-05T12:51:17.842976Z","caller":"traceutil/trace.go:171","msg":"trace[1650085029] transaction","detail":"{read_only:false; response_revision:1202; number_of_response:1; }","duration":"786.97212ms","start":"2026-05-05T12:51:17.055988Z","end":"2026-05-05T12:51:17.84296Z","steps":["trace[1650085029] 'process raft request' (duration: 786.664681ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:51:17.843202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:51:17.055976Z","time spent":"787.212897ms","remote":"127.0.0.1:40290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T12:51:17.843142Z","caller":"traceutil/trace.go:171","msg":"trace[99973523] transaction","detail":"{read_only:false; response_revision:1201; number_of_response:1; }","duration":"787.397583ms","start":"2026-05-05T12:51:17.055732Z","end":"2026-05-05T12:51:17.843129Z","steps":["trace[99973523] 'process raft request' (duration: 786.850036ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:51:17.843313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:51:17.055715Z","time spent":"787.582318ms","remote":"127.0.0.1:40290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T12:51:17.856691Z","caller":"traceutil/trace.go:171","msg":"trace[1428090592] transaction","detail":"{read_only:false; response_revision:1205; number_of_response:1; }","duration":"457.39853ms","start":"2026-05-05T12:51:17.399274Z","end":"2026-05-05T12:51:17.856673Z","steps":["trace[1428090592] 'process raft request' (duration: 457.234655ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:51:17.856998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:51:17.399255Z","time spent":"457.486463ms","remote":"127.0.0.1:40290","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-05T12:51:22.027274Z","caller":"traceutil/trace.go:171","msg":"trace[1450823525] transaction","detail":"{read_only:false; response_revision:1216; number_of_response:1; }","duration":"148.729857ms","start":"2026-05-05T12:51:21.878516Z","end":"2026-05-05T12:51:22.027246Z","steps":["trace[1450823525] 'process raft request' (duration: 148.555452ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:51:22.054632Z","caller":"traceutil/trace.go:171","msg":"trace[1500546626] transaction","detail":"{read_only:false; response_revision:1217; number_of_response:1; }","duration":"175.499817ms","start":"2026-05-05T12:51:21.879107Z","end":"2026-05-05T12:51:22.054607Z","steps":["trace[1500546626] 'process raft request' (duration: 175.29803ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:51:22.054642Z","caller":"traceutil/trace.go:171","msg":"trace[2095086458] transaction","detail":"{read_only:false; response_revision:1218; number_of_response:1; }","duration":"175.479516ms","start":"2026-05-05T12:51:21.87914Z","end":"2026-05-05T12:51:22.05462Z","steps":["trace[2095086458] 'process raft request' (duration: 175.394073ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:51:22.054773Z","caller":"traceutil/trace.go:171","msg":"trace[972560924] transaction","detail":"{read_only:false; response_revision:1219; number_of_response:1; }","duration":"175.564359ms","start":"2026-05-05T12:51:21.87916Z","end":"2026-05-05T12:51:22.054724Z","steps":["trace[972560924] 'process raft request' (duration: 175.420545ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:51:30.307767Z","caller":"traceutil/trace.go:171","msg":"trace[1837840518] linearizableReadLoop","detail":"{readStateIndex:1292; appliedIndex:1291; }","duration":"227.291992ms","start":"2026-05-05T12:51:30.080461Z","end":"2026-05-05T12:51:30.307753Z","steps":["trace[1837840518] 'read index received' (duration: 227.216679ms)","trace[1837840518] 'applied index is now lower than readState.Index' (duration: 74.743µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T12:51:30.30782Z","caller":"traceutil/trace.go:171","msg":"trace[617463693] transaction","detail":"{read_only:false; response_revision:1241; number_of_response:1; }","duration":"341.894112ms","start":"2026-05-05T12:51:29.965904Z","end":"2026-05-05T12:51:30.307798Z","steps":["trace[617463693] 'process raft request' (duration: 341.727357ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:51:30.307929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:51:29.965882Z","time spent":"341.983864ms","remote":"127.0.0.1:40290","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-05T12:51:30.308026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.540449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-05-05T12:51:30.308097Z","caller":"traceutil/trace.go:171","msg":"trace[478708126] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1241; }","duration":"227.651043ms","start":"2026-05-05T12:51:30.080428Z","end":"2026-05-05T12:51:30.308079Z","steps":["trace[478708126] 'agreement among raft nodes before linearized reading' (duration: 227.410865ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:51:30.364608Z","caller":"traceutil/trace.go:171","msg":"trace[2031914189] transaction","detail":"{read_only:false; response_revision:1242; number_of_response:1; }","duration":"147.292763ms","start":"2026-05-05T12:51:30.217291Z","end":"2026-05-05T12:51:30.364584Z","steps":["trace[2031914189] 'process raft request' (duration: 147.092997ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:51:30.364659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.402913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-05T12:51:30.364733Z","caller":"traceutil/trace.go:171","msg":"trace[1985105314] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1242; }","duration":"256.494846ms","start":"2026-05-05T12:51:30.108207Z","end":"2026-05-05T12:51:30.364702Z","steps":["trace[1985105314] 'agreement among raft nodes before linearized reading' (duration: 256.30134ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:51:30.364827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.746493ms","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-05T12:51:30.364932Z","caller":"traceutil/trace.go:171","msg":"trace[1246597680] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1242; }","duration":"194.910798ms","start":"2026-05-05T12:51:30.169984Z","end":"2026-05-05T12:51:30.364894Z","steps":["trace[1246597680] 'agreement among raft nodes before linearized reading' (duration: 194.6766ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:51:30.365165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.426931ms","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-05T12:51:30.365197Z","caller":"traceutil/trace.go:171","msg":"trace[639406125] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1242; }","duration":"192.484763ms","start":"2026-05-05T12:51:30.172703Z","end":"2026-05-05T12:51:30.365188Z","steps":["trace[639406125] 'agreement among raft nodes before linearized reading' (duration: 191.896045ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:51:30.541018Z","caller":"traceutil/trace.go:171","msg":"trace[1595694030] transaction","detail":"{read_only:false; response_revision:1245; number_of_response:1; }","duration":"172.927477ms","start":"2026-05-05T12:51:30.368069Z","end":"2026-05-05T12:51:30.540997Z","steps":["trace[1595694030] 'process raft request' (duration: 172.849305ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:51:30.541071Z","caller":"traceutil/trace.go:171","msg":"trace[589738338] transaction","detail":"{read_only:false; response_revision:1244; number_of_response:1; }","duration":"173.095192ms","start":"2026-05-05T12:51:30.367925Z","end":"2026-05-05T12:51:30.541021Z","steps":["trace[589738338] 'process raft request' (duration: 172.782052ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:51:30.541187Z","caller":"traceutil/trace.go:171","msg":"trace[1389659691] transaction","detail":"{read_only:false; response_revision:1246; number_of_response:1; }","duration":"172.39452ms","start":"2026-05-05T12:51:30.368761Z","end":"2026-05-05T12:51:30.541155Z","steps":["trace[1389659691] 'process raft request' (duration: 172.200304ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:51:32.280322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.932534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T12:51:32.280565Z","caller":"traceutil/trace.go:171","msg":"trace[688152977] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1265; }","duration":"251.203162ms","start":"2026-05-05T12:51:32.029324Z","end":"2026-05-05T12:51:32.280527Z","steps":["trace[688152977] 'range keys from in-memory index tree' (duration: 250.851371ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:51:32.684859Z","caller":"traceutil/trace.go:171","msg":"trace[1408917800] linearizableReadLoop","detail":"{readStateIndex:1318; appliedIndex:1316; }","duration":"302.891724ms","start":"2026-05-05T12:51:32.381889Z","end":"2026-05-05T12:51:32.684781Z","steps":["trace[1408917800] 'read index received' (duration: 30.593718ms)","trace[1408917800] 'applied index is now lower than readState.Index' (duration: 272.296576ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T12:51:32.684988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.073539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T12:51:32.685048Z","caller":"traceutil/trace.go:171","msg":"trace[1810201325] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:1266; }","duration":"303.148642ms","start":"2026-05-05T12:51:32.381883Z","end":"2026-05-05T12:51:32.685031Z","steps":["trace[1810201325] 'agreement among raft nodes before linearized reading' (duration: 303.030088ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:51:32.685098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:51:32.381819Z","time spent":"303.264015ms","remote":"127.0.0.1:36198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true "} {"level":"info","ts":"2026-05-05T12:51:32.696553Z","caller":"traceutil/trace.go:171","msg":"trace[1999021465] transaction","detail":"{read_only:false; response_revision:1267; number_of_response:1; }","duration":"159.074878ms","start":"2026-05-05T12:51:32.537437Z","end":"2026-05-05T12:51:32.696512Z","steps":["trace[1999021465] 'process raft request' (duration: 158.81675ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:51:32.696593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.697979ms","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-05T12:51:32.696594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.42614ms","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-05T12:51:32.696659Z","caller":"traceutil/trace.go:171","msg":"trace[79368796] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1267; }","duration":"151.798592ms","start":"2026-05-05T12:51:32.544842Z","end":"2026-05-05T12:51:32.696641Z","steps":["trace[79368796] 'agreement among raft nodes before linearized reading' (duration: 151.609936ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:51:32.696668Z","caller":"traceutil/trace.go:171","msg":"trace[630740081] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1267; }","duration":"150.522103ms","start":"2026-05-05T12:51:32.546132Z","end":"2026-05-05T12:51:32.696654Z","steps":["trace[630740081] 'agreement among raft nodes before linearized reading' (duration: 150.272485ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:51:32.696672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.257206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5331"} {"level":"info","ts":"2026-05-05T12:51:32.696762Z","caller":"traceutil/trace.go:171","msg":"trace[626001574] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1267; }","duration":"223.363059ms","start":"2026-05-05T12:51:32.473377Z","end":"2026-05-05T12:51:32.69674Z","steps":["trace[626001574] 'agreement among raft nodes before linearized reading' (duration: 223.08088ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:51:32.696915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.983106ms","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-05T12:51:32.696981Z","caller":"traceutil/trace.go:171","msg":"trace[163680231] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1267; }","duration":"150.088399ms","start":"2026-05-05T12:51:32.546878Z","end":"2026-05-05T12:51:32.696966Z","steps":["trace[163680231] 'agreement among raft nodes before linearized reading' (duration: 149.896233ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:51:32.698719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.334245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-05-05T12:51:32.698785Z","caller":"traceutil/trace.go:171","msg":"trace[510967488] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1267; }","duration":"311.418038ms","start":"2026-05-05T12:51:32.387346Z","end":"2026-05-05T12:51:32.698764Z","steps":["trace[510967488] 'agreement among raft nodes before linearized reading' (duration: 309.049325ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:51:32.698846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:51:32.387337Z","time spent":"311.49268ms","remote":"127.0.0.1:40290","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-05T12:51:33.397059Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656052474856635587,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T12:51:33.722474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.448954ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T12:51:33.722829Z","caller":"traceutil/trace.go:171","msg":"trace[577516739] linearizableReadLoop","detail":"{readStateIndex:1324; appliedIndex:1320; }","duration":"826.841135ms","start":"2026-05-05T12:51:32.895971Z","end":"2026-05-05T12:51:33.722812Z","steps":["trace[577516739] 'read index received' (duration: 703.989849ms)","trace[577516739] 'applied index is now lower than readState.Index' (duration: 122.850476ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T12:51:33.722969Z","caller":"traceutil/trace.go:171","msg":"trace[201695026] transaction","detail":"{read_only:false; response_revision:1269; number_of_response:1; }","duration":"1.016430509s","start":"2026-05-05T12:51:32.706526Z","end":"2026-05-05T12:51:33.722956Z","steps":["trace[201695026] 'process raft request' (duration: 893.414699ms)","trace[201695026] 'compare' (duration: 122.217426ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T12:51:33.723086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:51:32.706506Z","time spent":"1.016543362s","remote":"127.0.0.1:40216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5457,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T12:51:33.723453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"827.464225ms","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":"info","ts":"2026-05-05T12:51:33.723536Z","caller":"traceutil/trace.go:171","msg":"trace[655278731] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:1272; }","duration":"827.599249ms","start":"2026-05-05T12:51:32.895919Z","end":"2026-05-05T12:51:33.723519Z","steps":["trace[655278731] 'agreement among raft nodes before linearized reading' (duration: 827.461875ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:51:33.723579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:51:32.895898Z","time spent":"827.670861ms","remote":"127.0.0.1:36154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true "} {"level":"warn","ts":"2026-05-05T12:51:33.723584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"694.056111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T12:51:33.723674Z","caller":"traceutil/trace.go:171","msg":"trace[1504913844] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1272; }","duration":"694.243047ms","start":"2026-05-05T12:51:33.029418Z","end":"2026-05-05T12:51:33.723661Z","steps":["trace[1504913844] 'agreement among raft nodes before linearized reading' (duration: 694.068952ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:51:33.72374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:51:33.029395Z","time spent":"694.33493ms","remote":"127.0.0.1:50260","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-05T12:51:33.723803Z","caller":"traceutil/trace.go:171","msg":"trace[1323455913] transaction","detail":"{read_only:false; response_revision:1270; number_of_response:1; }","duration":"1.015361146s","start":"2026-05-05T12:51:32.70843Z","end":"2026-05-05T12:51:33.723791Z","steps":["trace[1323455913] 'process raft request' (duration: 1.014219351s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:51:33.723896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:51:32.708412Z","time spent":"1.015427048s","remote":"127.0.0.1:40290","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-05T12:51:33.724032Z","caller":"traceutil/trace.go:171","msg":"trace[56151560] transaction","detail":"{read_only:false; response_revision:1272; number_of_response:1; }","duration":"1.013769857s","start":"2026-05-05T12:51:32.710224Z","end":"2026-05-05T12:51:33.723994Z","steps":["trace[56151560] 'process raft request' (duration: 1.012549719s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:51:33.724095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"574.303322ms","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-05T12:51:33.724193Z","caller":"traceutil/trace.go:171","msg":"trace[761903487] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1272; }","duration":"574.429736ms","start":"2026-05-05T12:51:33.149751Z","end":"2026-05-05T12:51:33.724181Z","steps":["trace[761903487] 'agreement among raft nodes before linearized reading' (duration: 574.247791ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:51:33.724249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:51:33.149735Z","time spent":"574.503388ms","remote":"127.0.0.1:40290","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-05T12:51:33.724256Z","caller":"traceutil/trace.go:171","msg":"trace[1100995893] transaction","detail":"{read_only:false; response_revision:1271; number_of_response:1; }","duration":"1.015652585s","start":"2026-05-05T12:51:32.708584Z","end":"2026-05-05T12:51:33.724237Z","steps":["trace[1100995893] 'process raft request' (duration: 1.014157559s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:51:33.724362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:51:32.708577Z","time spent":"1.015742598s","remote":"127.0.0.1:40290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T12:51:33.724193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:51:32.710216Z","time spent":"1.01389728s","remote":"127.0.0.1:40290","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-05T12:51:34.323705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.592406ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T12:51:34.323869Z","caller":"traceutil/trace.go:171","msg":"trace[580541174] transaction","detail":"{read_only:false; response_revision:1274; number_of_response:1; }","duration":"592.453164ms","start":"2026-05-05T12:51:33.7314Z","end":"2026-05-05T12:51:34.323853Z","steps":["trace[580541174] 'process raft request' (duration: 592.397052ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:51:34.323913Z","caller":"traceutil/trace.go:171","msg":"trace[988095102] transaction","detail":"{read_only:false; response_revision:1273; number_of_response:1; }","duration":"594.540549ms","start":"2026-05-05T12:51:33.729328Z","end":"2026-05-05T12:51:34.323868Z","steps":["trace[988095102] 'process raft request' (duration: 442.692544ms)","trace[988095102] 'compare' (duration: 151.496734ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T12:51:34.323937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:51:33.731386Z","time spent":"592.516736ms","remote":"127.0.0.1:40424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":797,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T12:51:34.324036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:51:33.729313Z","time spent":"594.655403ms","remote":"127.0.0.1:40290","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-05T12:51:35.000752Z","caller":"traceutil/trace.go:171","msg":"trace[397185583] transaction","detail":"{read_only:false; response_revision:1277; number_of_response:1; }","duration":"134.103925ms","start":"2026-05-05T12:51:34.866623Z","end":"2026-05-05T12:51:35.000727Z","steps":["trace[397185583] 'process raft request' (duration: 134.009722ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:51:37.236895Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656052474856635612,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T12:51:37.737841Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656052474856635612,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T12:51:37.824031Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.167065246s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-05T12:51:38.00061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.672591487s","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-05T12:51:38.000692Z","caller":"traceutil/trace.go:171","msg":"trace[925389554] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1280; }","duration":"1.672731491s","start":"2026-05-05T12:51:36.327942Z","end":"2026-05-05T12:51:38.000674Z","steps":["trace[925389554] 'range keys from in-memory index tree' (duration: 1.672404461s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:51:38.000736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:51:36.32792Z","time spent":"1.672805714s","remote":"127.0.0.1:40290","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-05T12:51:38.001257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.7375527s","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-05T12:51:38.001358Z","caller":"traceutil/trace.go:171","msg":"trace[1450458045] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1280; }","duration":"1.737623282s","start":"2026-05-05T12:51:36.263691Z","end":"2026-05-05T12:51:38.001315Z","steps":["trace[1450458045] 'range keys from in-memory index tree' (duration: 1.737543319s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:51:38.001383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.972500208s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T12:51:38.001456Z","caller":"traceutil/trace.go:171","msg":"trace[468426136] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1280; }","duration":"1.972633762s","start":"2026-05-05T12:51:36.028804Z","end":"2026-05-05T12:51:38.001437Z","steps":["trace[468426136] 'range keys from in-memory index tree' (duration: 1.972412175s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:51:38.001496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:51:36.028759Z","time spent":"1.972728715s","remote":"127.0.0.1:50260","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-05T12:51:38.001479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.151518ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T12:51:38.001571Z","caller":"traceutil/trace.go:171","msg":"trace[2103212307] linearizableReadLoop","detail":"{readStateIndex:1333; appliedIndex:1332; }","duration":"1.265374921s","start":"2026-05-05T12:51:36.73618Z","end":"2026-05-05T12:51:38.001555Z","steps":["trace[2103212307] 'read index received' (duration: 1.088005916s)","trace[2103212307] 'applied index is now lower than readState.Index' (duration: 177.367835ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T12:51:38.001627Z","caller":"traceutil/trace.go:171","msg":"trace[53699360] transaction","detail":"{read_only:false; response_revision:1281; number_of_response:1; }","duration":"1.481114884s","start":"2026-05-05T12:51:36.520478Z","end":"2026-05-05T12:51:38.001593Z","steps":["trace[53699360] 'process raft request' (duration: 1.30376636s)","trace[53699360] 'compare' (duration: 176.22965ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T12:51:38.001757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.242377439s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-05T12:51:38.001768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.265594388s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.227\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-05T12:51:38.001792Z","caller":"traceutil/trace.go:171","msg":"trace[1717161513] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1281; }","duration":"1.242458171s","start":"2026-05-05T12:51:36.759324Z","end":"2026-05-05T12:51:38.001782Z","steps":["trace[1717161513] 'agreement among raft nodes before linearized reading' (duration: 1.242359208s)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:51:38.001802Z","caller":"traceutil/trace.go:171","msg":"trace[1879048410] range","detail":"{range_begin:/registry/masterleases/199.204.45.227; range_end:; response_count:1; response_revision:1281; }","duration":"1.26566451s","start":"2026-05-05T12:51:36.736127Z","end":"2026-05-05T12:51:38.001791Z","steps":["trace[1879048410] 'agreement among raft nodes before linearized reading' (duration: 1.265532446s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:51:38.001791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:51:36.520452Z","time spent":"1.481256799s","remote":"127.0.0.1:40290","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-05T12:51:38.00182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:51:36.7593Z","time spent":"1.242514163s","remote":"127.0.0.1:40290","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-05T12:51:38.001834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:51:36.736105Z","time spent":"1.265722332s","remote":"127.0.0.1:50312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.227\" "} {"level":"warn","ts":"2026-05-05T12:51:38.001942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.331586ms","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-05T12:51:38.001983Z","caller":"traceutil/trace.go:171","msg":"trace[866163555] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1281; }","duration":"210.402228ms","start":"2026-05-05T12:51:37.791569Z","end":"2026-05-05T12:51:38.001972Z","steps":["trace[866163555] 'agreement among raft nodes before linearized reading' (duration: 210.241383ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:51:38.002051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.176391ms","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-05T12:51:38.002066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.438582ms","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-05T12:51:38.002089Z","caller":"traceutil/trace.go:171","msg":"trace[1438071644] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1281; }","duration":"210.267824ms","start":"2026-05-05T12:51:37.791811Z","end":"2026-05-05T12:51:38.002078Z","steps":["trace[1438071644] 'agreement among raft nodes before linearized reading' (duration: 210.112459ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:51:38.002097Z","caller":"traceutil/trace.go:171","msg":"trace[1384475251] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1281; }","duration":"233.509924ms","start":"2026-05-05T12:51:37.768579Z","end":"2026-05-05T12:51:38.002089Z","steps":["trace[1384475251] 'agreement among raft nodes before linearized reading' (duration: 233.422061ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:51:38.002123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"899.349602ms","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-05T12:51:38.002152Z","caller":"traceutil/trace.go:171","msg":"trace[1094495800] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:1281; }","duration":"899.426425ms","start":"2026-05-05T12:51:37.102716Z","end":"2026-05-05T12:51:38.002143Z","steps":["trace[1094495800] 'agreement among raft nodes before linearized reading' (duration: 899.377083ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:51:38.002181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:51:37.102692Z","time spent":"899.480986ms","remote":"127.0.0.1:36228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true "} {"level":"warn","ts":"2026-05-05T12:51:38.523628Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656052474856635616,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T12:51:38.699499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.038495ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-05-05T12:51:38.699614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:51:38.018746Z","time spent":"680.864193ms","remote":"127.0.0.1:50312","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-05T12:51:38.892118Z","caller":"traceutil/trace.go:171","msg":"trace[1387512195] transaction","detail":"{read_only:false; response_revision:1287; number_of_response:1; }","duration":"191.661248ms","start":"2026-05-05T12:51:38.700439Z","end":"2026-05-05T12:51:38.892101Z","steps":["trace[1387512195] 'process raft request' (duration: 191.633377ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:51:38.892436Z","caller":"traceutil/trace.go:171","msg":"trace[2095927230] transaction","detail":"{read_only:false; response_revision:1282; number_of_response:1; }","duration":"872.123839ms","start":"2026-05-05T12:51:38.020302Z","end":"2026-05-05T12:51:38.892426Z","steps":["trace[2095927230] 'process raft request' (duration: 780.844111ms)","trace[2095927230] 'compare' (duration: 90.620137ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T12:51:38.892522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:51:38.020292Z","time spent":"872.17899ms","remote":"127.0.0.1:40290","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-05T12:51:38.892722Z","caller":"traceutil/trace.go:171","msg":"trace[132610330] transaction","detail":"{read_only:false; response_revision:1283; number_of_response:1; }","duration":"872.367106ms","start":"2026-05-05T12:51:38.020344Z","end":"2026-05-05T12:51:38.892711Z","steps":["trace[132610330] 'process raft request' (duration: 871.630404ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:51:38.892772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:51:38.020338Z","time spent":"872.410508ms","remote":"127.0.0.1:40290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T12:51:38.892936Z","caller":"traceutil/trace.go:171","msg":"trace[2078360074] transaction","detail":"{read_only:false; response_revision:1284; number_of_response:1; }","duration":"871.898472ms","start":"2026-05-05T12:51:38.021028Z","end":"2026-05-05T12:51:38.892926Z","steps":["trace[2078360074] 'process raft request' (duration: 870.982774ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:51:38.892999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:51:38.021017Z","time spent":"871.951363ms","remote":"127.0.0.1:40290","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-05T12:51:38.893124Z","caller":"traceutil/trace.go:171","msg":"trace[160724896] transaction","detail":"{read_only:false; response_revision:1285; number_of_response:1; }","duration":"871.498699ms","start":"2026-05-05T12:51:38.021615Z","end":"2026-05-05T12:51:38.893114Z","steps":["trace[160724896] 'process raft request' (duration: 870.415265ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:51:38.89318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:51:38.02161Z","time spent":"871.541991ms","remote":"127.0.0.1:40290","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-05T12:51:38.893322Z","caller":"traceutil/trace.go:171","msg":"trace[632844973] transaction","detail":"{read_only:false; response_revision:1286; number_of_response:1; }","duration":"871.023575ms","start":"2026-05-05T12:51:38.022286Z","end":"2026-05-05T12:51:38.893309Z","steps":["trace[632844973] 'process raft request' (duration: 869.763976ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:51:38.893384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:51:38.022281Z","time spent":"871.076396ms","remote":"127.0.0.1:40290","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-05T12:51:38.893494Z","caller":"traceutil/trace.go:171","msg":"trace[1601846626] linearizableReadLoop","detail":"{readStateIndex:1339; appliedIndex:1333; }","duration":"870.316352ms","start":"2026-05-05T12:51:38.023168Z","end":"2026-05-05T12:51:38.893484Z","steps":["trace[1601846626] 'read index received' (duration: 560.352729ms)","trace[1601846626] 'applied index is now lower than readState.Index' (duration: 309.962423ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T12:51:38.893892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"870.713445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T12:51:38.893939Z","caller":"traceutil/trace.go:171","msg":"trace[162036066] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1287; }","duration":"870.774417ms","start":"2026-05-05T12:51:38.023155Z","end":"2026-05-05T12:51:38.89393Z","steps":["trace[162036066] 'agreement among raft nodes before linearized reading' (duration: 870.703435ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:51:38.896037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:51:38.023151Z","time spent":"870.808898ms","remote":"127.0.0.1:50260","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-05T12:51:38.935015Z","caller":"traceutil/trace.go:171","msg":"trace[2118743449] transaction","detail":"{read_only:false; response_revision:1288; number_of_response:1; }","duration":"128.958445ms","start":"2026-05-05T12:51:38.806027Z","end":"2026-05-05T12:51:38.934985Z","steps":["trace[2118743449] 'process raft request' (duration: 128.81006ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:51:38.93517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"752.44393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T12:51:38.935293Z","caller":"traceutil/trace.go:171","msg":"trace[940547429] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1288; }","duration":"752.572734ms","start":"2026-05-05T12:51:38.18266Z","end":"2026-05-05T12:51:38.935233Z","steps":["trace[940547429] 'agreement among raft nodes before linearized reading' (duration: 752.395619ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:51:38.935407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:51:38.182637Z","time spent":"752.75418ms","remote":"127.0.0.1:36268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-05-05T12:51:38.935683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"536.442338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T12:51:38.935751Z","caller":"traceutil/trace.go:171","msg":"trace[908549320] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:1288; }","duration":"536.725997ms","start":"2026-05-05T12:51:38.399009Z","end":"2026-05-05T12:51:38.935735Z","steps":["trace[908549320] 'agreement among raft nodes before linearized reading' (duration: 535.986624ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:51:38.936658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:51:38.39899Z","time spent":"537.645886ms","remote":"127.0.0.1:40510","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"info","ts":"2026-05-05T12:51:39.042952Z","caller":"traceutil/trace.go:171","msg":"trace[1490059439] transaction","detail":"{read_only:false; response_revision:1289; number_of_response:1; }","duration":"102.613639ms","start":"2026-05-05T12:51:38.940307Z","end":"2026-05-05T12:51:39.042921Z","steps":["trace[1490059439] 'process raft request' (duration: 73.568539ms)","trace[1490059439] 'compare' (duration: 28.928816ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T12:51:39.324401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.652328ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-05T12:51:39.324505Z","caller":"traceutil/trace.go:171","msg":"trace[1838771896] linearizableReadLoop","detail":"{readStateIndex:1345; appliedIndex:1344; }","duration":"200.956486ms","start":"2026-05-05T12:51:39.123532Z","end":"2026-05-05T12:51:39.324488Z","steps":["trace[1838771896] 'read index received' (duration: 80.147254ms)","trace[1838771896] 'applied index is now lower than readState.Index' (duration: 120.808062ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T12:51:39.324584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.046158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T12:51:39.324617Z","caller":"traceutil/trace.go:171","msg":"trace[671147838] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1292; }","duration":"271.098139ms","start":"2026-05-05T12:51:39.05351Z","end":"2026-05-05T12:51:39.324608Z","steps":["trace[671147838] 'agreement among raft nodes before linearized reading' (duration: 271.023346ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:51:39.324768Z","caller":"traceutil/trace.go:171","msg":"trace[402733028] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"269.543501ms","start":"2026-05-05T12:51:39.055192Z","end":"2026-05-05T12:51:39.324735Z","steps":["trace[402733028] 'process raft request' (duration: 148.47568ms)","trace[402733028] 'compare' (duration: 120.556885ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T12:51:39.394177Z","caller":"traceutil/trace.go:171","msg":"trace[311826630] transaction","detail":"{read_only:false; response_revision:1293; number_of_response:1; }","duration":"266.396543ms","start":"2026-05-05T12:51:39.127756Z","end":"2026-05-05T12:51:39.394153Z","steps":["trace[311826630] 'process raft request' (duration: 266.275369ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:51:40.47954Z","caller":"traceutil/trace.go:171","msg":"trace[1707877975] transaction","detail":"{read_only:false; response_revision:1294; number_of_response:1; }","duration":"458.213546ms","start":"2026-05-05T12:51:40.021301Z","end":"2026-05-05T12:51:40.479515Z","steps":["trace[1707877975] 'process raft request' (duration: 458.068481ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:51:40.479756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:51:40.021281Z","time spent":"458.339219ms","remote":"127.0.0.1:40290","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-05T12:51:41.269906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.954019ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T12:51:41.269993Z","caller":"traceutil/trace.go:171","msg":"trace[1133174086] linearizableReadLoop","detail":"{readStateIndex:1348; appliedIndex:1347; }","duration":"387.963049ms","start":"2026-05-05T12:51:40.882018Z","end":"2026-05-05T12:51:41.269981Z","steps":["trace[1133174086] 'read index received' (duration: 8.778322ms)","trace[1133174086] 'applied index is now lower than readState.Index' (duration: 379.183667ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T12:51:41.270056Z","caller":"traceutil/trace.go:171","msg":"trace[1578869131] transaction","detail":"{read_only:false; response_revision:1295; number_of_response:1; }","duration":"623.078812ms","start":"2026-05-05T12:51:40.646946Z","end":"2026-05-05T12:51:41.270025Z","steps":["trace[1578869131] 'process raft request' (duration: 243.941417ms)","trace[1578869131] 'compare' (duration: 378.733643ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T12:51:41.270181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:51:40.646922Z","time spent":"623.201396ms","remote":"127.0.0.1:40290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T12:51:41.27023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.214686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T12:51:41.270289Z","caller":"traceutil/trace.go:171","msg":"trace[1031521122] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:1295; }","duration":"388.31976ms","start":"2026-05-05T12:51:40.881961Z","end":"2026-05-05T12:51:41.270281Z","steps":["trace[1031521122] 'agreement among raft nodes before linearized reading' (duration: 388.081113ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:51:41.270382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:51:40.881939Z","time spent":"388.432993ms","remote":"127.0.0.1:40270","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":28,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"warn","ts":"2026-05-05T12:51:41.270555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.119888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-05T12:51:41.270663Z","caller":"traceutil/trace.go:171","msg":"trace[1928993143] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1295; }","duration":"372.222281ms","start":"2026-05-05T12:51:40.898384Z","end":"2026-05-05T12:51:41.270606Z","steps":["trace[1928993143] 'agreement among raft nodes before linearized reading' (duration: 371.933222ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:51:41.270693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.192941ms","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-05T12:51:41.270742Z","caller":"traceutil/trace.go:171","msg":"trace[815510374] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1295; }","duration":"372.255692ms","start":"2026-05-05T12:51:40.898472Z","end":"2026-05-05T12:51:41.270728Z","steps":["trace[815510374] 'agreement among raft nodes before linearized reading' (duration: 372.122548ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:51:41.270763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:51:40.898367Z","time spent":"372.343035ms","remote":"127.0.0.1:40290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-05T12:51:41.270859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.344327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T12:51:41.270907Z","caller":"traceutil/trace.go:171","msg":"trace[1597274357] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1295; }","duration":"241.418079ms","start":"2026-05-05T12:51:41.029477Z","end":"2026-05-05T12:51:41.270895Z","steps":["trace[1597274357] 'agreement among raft nodes before linearized reading' (duration: 241.349077ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:51:41.271123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.641234ms","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-05T12:51:41.271154Z","caller":"traceutil/trace.go:171","msg":"trace[697774022] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1295; }","duration":"372.708476ms","start":"2026-05-05T12:51:40.898438Z","end":"2026-05-05T12:51:41.271146Z","steps":["trace[697774022] 'agreement among raft nodes before linearized reading' (duration: 372.608473ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:51:41.271201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.695405ms","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-05T12:51:41.271175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:51:40.898432Z","time spent":"372.737617ms","remote":"127.0.0.1:40290","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":"info","ts":"2026-05-05T12:51:41.271262Z","caller":"traceutil/trace.go:171","msg":"trace[844129822] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1295; }","duration":"372.779428ms","start":"2026-05-05T12:51:40.898468Z","end":"2026-05-05T12:51:41.271247Z","steps":["trace[844129822] 'agreement among raft nodes before linearized reading' (duration: 372.642144ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:51:41.271112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.636264ms","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-05T12:51:41.271312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:51:40.898457Z","time spent":"372.84343ms","remote":"127.0.0.1:40290","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-05T12:51:41.271322Z","caller":"traceutil/trace.go:171","msg":"trace[370592919] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1295; }","duration":"372.868871ms","start":"2026-05-05T12:51:40.898437Z","end":"2026-05-05T12:51:41.271305Z","steps":["trace[370592919] 'agreement among raft nodes before linearized reading' (duration: 372.537401ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:51:41.271375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:51:40.89842Z","time spent":"372.939124ms","remote":"127.0.0.1:40290","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-05T12:51:41.270802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:51:40.898469Z","time spent":"372.317964ms","remote":"127.0.0.1:40290","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-05T12:51:41.934794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:51:41.517045Z","time spent":"417.740951ms","remote":"127.0.0.1:59268","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-05-05T12:51:41.935414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.133165ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T12:51:41.935721Z","caller":"traceutil/trace.go:171","msg":"trace[1297934152] transaction","detail":"{read_only:false; response_revision:1300; number_of_response:1; }","duration":"659.311154ms","start":"2026-05-05T12:51:41.276393Z","end":"2026-05-05T12:51:41.935704Z","steps":["trace[1297934152] 'process raft request' (duration: 659.280013ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:51:41.935792Z","caller":"traceutil/trace.go:171","msg":"trace[1290469456] transaction","detail":"{read_only:false; response_revision:1297; number_of_response:1; }","duration":"660.048447ms","start":"2026-05-05T12:51:41.275723Z","end":"2026-05-05T12:51:41.935771Z","steps":["trace[1290469456] 'process raft request' (duration: 659.836531ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:51:41.935832Z","caller":"traceutil/trace.go:171","msg":"trace[848647585] transaction","detail":"{read_only:false; response_revision:1298; number_of_response:1; }","duration":"659.929464ms","start":"2026-05-05T12:51:41.275894Z","end":"2026-05-05T12:51:41.935824Z","steps":["trace[848647585] 'process raft request' (duration: 659.721457ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:51:41.935879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:51:41.275784Z","time spent":"660.071208ms","remote":"127.0.0.1:40290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T12:51:41.9359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:51:41.275716Z","time spent":"660.111759ms","remote":"127.0.0.1:40290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T12:51:41.936078Z","caller":"traceutil/trace.go:171","msg":"trace[1430923159] transaction","detail":"{read_only:false; response_revision:1296; number_of_response:1; }","duration":"660.44439ms","start":"2026-05-05T12:51:41.275609Z","end":"2026-05-05T12:51:41.936053Z","steps":["trace[1430923159] 'process raft request' (duration: 167.587072ms)","trace[1430923159] 'compare' (duration: 492.010472ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T12:51:41.936133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:51:41.275585Z","time spent":"660.525822ms","remote":"127.0.0.1:40290","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-05T12:51:41.935793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:51:41.276384Z","time spent":"659.373247ms","remote":"127.0.0.1:40290","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-05T12:51:41.936418Z","caller":"traceutil/trace.go:171","msg":"trace[1976430681] transaction","detail":"{read_only:false; response_revision:1299; number_of_response:1; }","duration":"660.194172ms","start":"2026-05-05T12:51:41.27617Z","end":"2026-05-05T12:51:41.936364Z","steps":["trace[1976430681] 'process raft request' (duration: 659.474359ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:51:41.936465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:51:41.276157Z","time spent":"660.285145ms","remote":"127.0.0.1:40290","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-05T12:51:42.229292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.70226ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-05T12:51:42.229423Z","caller":"traceutil/trace.go:171","msg":"trace[180427068] linearizableReadLoop","detail":"{readStateIndex:1354; appliedIndex:1353; }","duration":"199.14661ms","start":"2026-05-05T12:51:42.030253Z","end":"2026-05-05T12:51:42.2294Z","steps":["trace[180427068] 'read index received' (duration: 188.126µs)","trace[180427068] 'applied index is now lower than readState.Index' (duration: 198.956554ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T12:51:42.229538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.302585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T12:51:42.229588Z","caller":"traceutil/trace.go:171","msg":"trace[1671592850] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1300; }","duration":"199.405537ms","start":"2026-05-05T12:51:42.030168Z","end":"2026-05-05T12:51:42.229573Z","steps":["trace[1671592850] 'agreement among raft nodes before linearized reading' (duration: 199.308594ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:51:48.103976Z","caller":"traceutil/trace.go:171","msg":"trace[53057515] linearizableReadLoop","detail":"{readStateIndex:1405; appliedIndex:1404; }","duration":"143.371661ms","start":"2026-05-05T12:51:47.960573Z","end":"2026-05-05T12:51:48.103944Z","steps":["trace[53057515] 'read index received' (duration: 143.135614ms)","trace[53057515] 'applied index is now lower than readState.Index' (duration: 234.377µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T12:51:48.104025Z","caller":"traceutil/trace.go:171","msg":"trace[1707499233] transaction","detail":"{read_only:false; response_revision:1350; number_of_response:1; }","duration":"144.881308ms","start":"2026-05-05T12:51:47.959112Z","end":"2026-05-05T12:51:48.103994Z","steps":["trace[1707499233] 'process raft request' (duration: 144.590889ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:51:48.104244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.642ms","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-05T12:51:48.104449Z","caller":"traceutil/trace.go:171","msg":"trace[1938561358] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1350; }","duration":"143.960769ms","start":"2026-05-05T12:51:47.960475Z","end":"2026-05-05T12:51:48.104436Z","steps":["trace[1938561358] 'agreement among raft nodes before linearized reading' (duration: 143.622889ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:51:48.604991Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656052474856635746,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T12:51:49.105515Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656052474856635746,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T12:51:49.27593Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.172246745s","expected-duration":"1s"} {"level":"info","ts":"2026-05-05T12:51:49.359188Z","caller":"traceutil/trace.go:171","msg":"trace[881987970] transaction","detail":"{read_only:false; response_revision:1351; number_of_response:1; }","duration":"1.397976488s","start":"2026-05-05T12:51:47.961187Z","end":"2026-05-05T12:51:49.359163Z","steps":["trace[881987970] 'process raft request' (duration: 1.315022688s)","trace[881987970] 'compare' (duration: 82.762764ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T12:51:49.359204Z","caller":"traceutil/trace.go:171","msg":"trace[1087344003] transaction","detail":"{read_only:false; response_revision:1352; number_of_response:1; }","duration":"1.397871565s","start":"2026-05-05T12:51:47.961301Z","end":"2026-05-05T12:51:49.359173Z","steps":["trace[1087344003] 'process raft request' (duration: 1.397767392s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:51:49.359329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:51:47.961173Z","time spent":"1.398082981s","remote":"127.0.0.1:40290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T12:51:49.359345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:51:47.961293Z","time spent":"1.397973508s","remote":"127.0.0.1:40290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T12:51:49.359194Z","caller":"traceutil/trace.go:171","msg":"trace[2142509747] transaction","detail":"{read_only:false; response_revision:1353; number_of_response:1; }","duration":"1.397537484s","start":"2026-05-05T12:51:47.961607Z","end":"2026-05-05T12:51:49.359144Z","steps":["trace[2142509747] 'process raft request' (duration: 1.397497093s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:51:49.359556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:51:47.961598Z","time spent":"1.397925137s","remote":"127.0.0.1:40290","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-05T12:51:49.561393Z","caller":"traceutil/trace.go:171","msg":"trace[1708436894] transaction","detail":"{read_only:false; response_revision:1354; number_of_response:1; }","duration":"1.452404655s","start":"2026-05-05T12:51:48.108964Z","end":"2026-05-05T12:51:49.561368Z","steps":["trace[1708436894] 'process raft request' (duration: 1.42673968s)","trace[1708436894] 'compare' (duration: 25.51052ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T12:51:49.56152Z","caller":"traceutil/trace.go:171","msg":"trace[570312186] linearizableReadLoop","detail":"{readStateIndex:1410; appliedIndex:1405; }","duration":"1.457417319s","start":"2026-05-05T12:51:48.10407Z","end":"2026-05-05T12:51:49.561487Z","steps":["trace[570312186] 'read index received' (duration: 1.17207651s)","trace[570312186] 'applied index is now lower than readState.Index' (duration: 285.338199ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T12:51:49.561651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:51:48.10894Z","time spent":"1.4525936s","remote":"127.0.0.1:40290","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-05T12:51:49.561832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.460095523s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T12:51:49.561597Z","caller":"traceutil/trace.go:171","msg":"trace[1917821256] transaction","detail":"{read_only:false; response_revision:1355; number_of_response:1; }","duration":"1.058597814s","start":"2026-05-05T12:51:48.502973Z","end":"2026-05-05T12:51:49.561571Z","steps":["trace[1917821256] 'process raft request' (duration: 1.058353876s)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:51:49.561913Z","caller":"traceutil/trace.go:171","msg":"trace[2132346943] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1355; }","duration":"1.460214206s","start":"2026-05-05T12:51:48.10166Z","end":"2026-05-05T12:51:49.561875Z","steps":["trace[2132346943] 'agreement among raft nodes before linearized reading' (duration: 1.460048451s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:51:49.561967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.554434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-05T12:51:49.561959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:51:48.101646Z","time spent":"1.460301679s","remote":"127.0.0.1:40168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"warn","ts":"2026-05-05T12:51:49.561986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:51:48.502948Z","time spent":"1.058957215s","remote":"127.0.0.1:40290","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-05T12:51:49.562009Z","caller":"traceutil/trace.go:171","msg":"trace[600669242] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:1355; }","duration":"466.628236ms","start":"2026-05-05T12:51:49.095371Z","end":"2026-05-05T12:51:49.561999Z","steps":["trace[600669242] 'agreement among raft nodes before linearized reading' (duration: 466.512733ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:51:49.562039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:51:49.095354Z","time spent":"466.676717ms","remote":"127.0.0.1:40368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":70,"response size":30,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"warn","ts":"2026-05-05T12:51:49.562027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"893.753227ms","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:532"} {"level":"warn","ts":"2026-05-05T12:51:49.562088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"625.003883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-05T12:51:49.561834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.533019391s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T12:51:49.562147Z","caller":"traceutil/trace.go:171","msg":"trace[875530267] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:1355; }","duration":"625.094145ms","start":"2026-05-05T12:51:48.937032Z","end":"2026-05-05T12:51:49.562126Z","steps":["trace[875530267] 'agreement among raft nodes before linearized reading' (duration: 625.025963ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:51:49.562155Z","caller":"traceutil/trace.go:171","msg":"trace[1342775238] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1355; }","duration":"1.533389943s","start":"2026-05-05T12:51:48.028748Z","end":"2026-05-05T12:51:49.562138Z","steps":["trace[1342775238] 'agreement among raft nodes before linearized reading' (duration: 1.533014231s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:51:49.562188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"840.481547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-05-05T12:51:49.562202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:51:48.937008Z","time spent":"625.180048ms","remote":"127.0.0.1:36384","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":4,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"warn","ts":"2026-05-05T12:51:49.562208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:51:48.028731Z","time spent":"1.533460215s","remote":"127.0.0.1:50260","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-05T12:51:49.562232Z","caller":"traceutil/trace.go:171","msg":"trace[574486576] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1355; }","duration":"840.559909ms","start":"2026-05-05T12:51:48.721658Z","end":"2026-05-05T12:51:49.562217Z","steps":["trace[574486576] 'agreement among raft nodes before linearized reading' (duration: 840.462096ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:51:49.562011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"678.706597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T12:51:49.562277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:51:48.721634Z","time spent":"840.634282ms","remote":"127.0.0.1:40290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-05-05T12:51:49.562307Z","caller":"traceutil/trace.go:171","msg":"trace[233561857] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:1355; }","duration":"679.067938ms","start":"2026-05-05T12:51:48.883229Z","end":"2026-05-05T12:51:49.562297Z","steps":["trace[233561857] 'agreement among raft nodes before linearized reading' (duration: 678.699286ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:51:49.562339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:51:48.883206Z","time spent":"679.12375ms","remote":"127.0.0.1:40558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"info","ts":"2026-05-05T12:51:49.562095Z","caller":"traceutil/trace.go:171","msg":"trace[552738228] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1355; }","duration":"893.874562ms","start":"2026-05-05T12:51:48.668203Z","end":"2026-05-05T12:51:49.562077Z","steps":["trace[552738228] 'agreement among raft nodes before linearized reading' (duration: 893.662955ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:51:49.562383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:51:48.668181Z","time spent":"894.192531ms","remote":"127.0.0.1:40290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-05-05T12:51:53.479593Z","caller":"traceutil/trace.go:171","msg":"trace[884258540] transaction","detail":"{read_only:false; response_revision:1373; number_of_response:1; }","duration":"102.837534ms","start":"2026-05-05T12:51:53.376725Z","end":"2026-05-05T12:51:53.479562Z","steps":["trace[884258540] 'process raft request' (duration: 102.731291ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:51:53.479686Z","caller":"traceutil/trace.go:171","msg":"trace[819229647] transaction","detail":"{read_only:false; response_revision:1372; number_of_response:1; }","duration":"102.908086ms","start":"2026-05-05T12:51:53.376725Z","end":"2026-05-05T12:51:53.479633Z","steps":["trace[819229647] 'process raft request' (duration: 61.839221ms)","trace[819229647] 'compare' (duration: 40.662142ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T12:52:16.10692Z","caller":"traceutil/trace.go:171","msg":"trace[1132559853] transaction","detail":"{read_only:false; response_revision:1479; number_of_response:1; }","duration":"158.657319ms","start":"2026-05-05T12:52:15.94824Z","end":"2026-05-05T12:52:16.106897Z","steps":["trace[1132559853] 'process raft request' (duration: 74.085179ms)","trace[1132559853] 'compare' (duration: 84.115845ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T12:52:20.383336Z","caller":"traceutil/trace.go:171","msg":"trace[1479458741] linearizableReadLoop","detail":"{readStateIndex:1559; appliedIndex:1558; }","duration":"109.027196ms","start":"2026-05-05T12:52:20.274271Z","end":"2026-05-05T12:52:20.383298Z","steps":["trace[1479458741] 'read index received' (duration: 108.764357ms)","trace[1479458741] 'applied index is now lower than readState.Index' (duration: 258.158µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T12:52:20.383622Z","caller":"traceutil/trace.go:171","msg":"trace[545904552] transaction","detail":"{read_only:false; response_revision:1498; number_of_response:1; }","duration":"171.575179ms","start":"2026-05-05T12:52:20.212021Z","end":"2026-05-05T12:52:20.383596Z","steps":["trace[545904552] 'process raft request' (duration: 171.092533ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:52:20.383661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.360166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T12:52:20.383733Z","caller":"traceutil/trace.go:171","msg":"trace[243349188] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:1498; }","duration":"109.48869ms","start":"2026-05-05T12:52:20.274231Z","end":"2026-05-05T12:52:20.383719Z","steps":["trace[243349188] 'agreement among raft nodes before linearized reading' (duration: 109.287144ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:52:23.819113Z","caller":"traceutil/trace.go:171","msg":"trace[738915564] linearizableReadLoop","detail":"{readStateIndex:1571; appliedIndex:1570; }","duration":"183.939421ms","start":"2026-05-05T12:52:23.635152Z","end":"2026-05-05T12:52:23.819091Z","steps":["trace[738915564] 'read index received' (duration: 183.798287ms)","trace[738915564] 'applied index is now lower than readState.Index' (duration: 140.234µs)"],"step_count":2} {"level":"warn","ts":"2026-05-05T12:52:23.819391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.270962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-05T12:52:23.819563Z","caller":"traceutil/trace.go:171","msg":"trace[591724272] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1509; }","duration":"184.408256ms","start":"2026-05-05T12:52:23.635065Z","end":"2026-05-05T12:52:23.819473Z","steps":["trace[591724272] 'agreement among raft nodes before linearized reading' (duration: 184.197179ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:52:23.820203Z","caller":"traceutil/trace.go:171","msg":"trace[1546701732] transaction","detail":"{read_only:false; response_revision:1509; number_of_response:1; }","duration":"221.71852ms","start":"2026-05-05T12:52:23.598464Z","end":"2026-05-05T12:52:23.820182Z","steps":["trace[1546701732] 'process raft request' (duration: 220.50789ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:52:23.822431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.749989ms","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-05T12:52:23.822565Z","caller":"traceutil/trace.go:171","msg":"trace[1647584622] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1510; }","duration":"185.977017ms","start":"2026-05-05T12:52:23.636549Z","end":"2026-05-05T12:52:23.822526Z","steps":["trace[1647584622] 'agreement among raft nodes before linearized reading' (duration: 185.743879ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:52:24.212285Z","caller":"traceutil/trace.go:171","msg":"trace[2140782677] transaction","detail":"{read_only:false; response_revision:1514; number_of_response:1; }","duration":"134.467543ms","start":"2026-05-05T12:52:24.077796Z","end":"2026-05-05T12:52:24.212264Z","steps":["trace[2140782677] 'process raft request' (duration: 104.54662ms)","trace[2140782677] 'compare' (duration: 29.83388ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T12:52:38.381317Z","caller":"traceutil/trace.go:171","msg":"trace[221330683] transaction","detail":"{read_only:false; response_revision:1577; number_of_response:1; }","duration":"216.023055ms","start":"2026-05-05T12:52:38.165256Z","end":"2026-05-05T12:52:38.381279Z","steps":["trace[221330683] 'process raft request' (duration: 215.86453ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:52:38.424472Z","caller":"traceutil/trace.go:171","msg":"trace[11455648] transaction","detail":"{read_only:false; response_revision:1578; number_of_response:1; }","duration":"250.088731ms","start":"2026-05-05T12:52:38.174353Z","end":"2026-05-05T12:52:38.424442Z","steps":["trace[11455648] 'process raft request' (duration: 249.851363ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:52:38.579786Z","caller":"traceutil/trace.go:171","msg":"trace[698666454] transaction","detail":"{read_only:false; response_revision:1579; number_of_response:1; }","duration":"151.210566ms","start":"2026-05-05T12:52:38.428543Z","end":"2026-05-05T12:52:38.579754Z","steps":["trace[698666454] 'process raft request' (duration: 88.260279ms)","trace[698666454] 'compare' (duration: 62.767441ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T12:52:40.126836Z","caller":"traceutil/trace.go:171","msg":"trace[2038577929] transaction","detail":"{read_only:false; response_revision:1583; number_of_response:1; }","duration":"232.668625ms","start":"2026-05-05T12:52:39.894136Z","end":"2026-05-05T12:52:40.126804Z","steps":["trace[2038577929] 'process raft request' (duration: 232.469349ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:52:40.504331Z","caller":"traceutil/trace.go:171","msg":"trace[35150871] transaction","detail":"{read_only:false; response_revision:1586; number_of_response:1; }","duration":"118.418991ms","start":"2026-05-05T12:52:40.385881Z","end":"2026-05-05T12:52:40.5043Z","steps":["trace[35150871] 'process raft request' (duration: 71.459534ms)","trace[35150871] 'compare' (duration: 46.800632ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T12:52:40.702803Z","caller":"traceutil/trace.go:171","msg":"trace[319179499] linearizableReadLoop","detail":"{readStateIndex:1653; appliedIndex:1652; }","duration":"119.33893ms","start":"2026-05-05T12:52:40.583444Z","end":"2026-05-05T12:52:40.702783Z","steps":["trace[319179499] 'read index received' (duration: 87.502225ms)","trace[319179499] 'applied index is now lower than readState.Index' (duration: 31.836095ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T12:52:40.70288Z","caller":"traceutil/trace.go:171","msg":"trace[172084050] transaction","detail":"{read_only:false; response_revision:1588; number_of_response:1; }","duration":"193.513303ms","start":"2026-05-05T12:52:40.509337Z","end":"2026-05-05T12:52:40.70285Z","steps":["trace[172084050] 'process raft request' (duration: 161.667187ms)","trace[172084050] 'compare' (duration: 31.691891ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T12:52:40.703082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.621009ms","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-05T12:52:40.703167Z","caller":"traceutil/trace.go:171","msg":"trace[2049692290] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1588; }","duration":"119.746443ms","start":"2026-05-05T12:52:40.583405Z","end":"2026-05-05T12:52:40.703152Z","steps":["trace[2049692290] 'agreement among raft nodes before linearized reading' (duration: 119.447324ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:52:52.399153Z","caller":"traceutil/trace.go:171","msg":"trace[1702687413] transaction","detail":"{read_only:false; response_revision:1639; number_of_response:1; }","duration":"194.967659ms","start":"2026-05-05T12:52:52.204155Z","end":"2026-05-05T12:52:52.399123Z","steps":["trace[1702687413] 'process raft request' (duration: 194.633168ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:53:27.296097Z","caller":"traceutil/trace.go:171","msg":"trace[318424082] transaction","detail":"{read_only:false; response_revision:1802; number_of_response:1; }","duration":"172.268019ms","start":"2026-05-05T12:53:27.123792Z","end":"2026-05-05T12:53:27.29606Z","steps":["trace[318424082] 'process raft request' (duration: 94.16842ms)","trace[318424082] 'compare' (duration: 77.876632ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T12:53:27.296066Z","caller":"traceutil/trace.go:171","msg":"trace[1046581410] linearizableReadLoop","detail":"{readStateIndex:1877; appliedIndex:1876; }","duration":"107.746181ms","start":"2026-05-05T12:53:27.188291Z","end":"2026-05-05T12:53:27.296037Z","steps":["trace[1046581410] 'read index received' (duration: 29.557978ms)","trace[1046581410] 'applied index is now lower than readState.Index' (duration: 78.186733ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T12:53:27.296311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.443729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T12:53:27.296367Z","caller":"traceutil/trace.go:171","msg":"trace[844320436] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:1802; }","duration":"106.525312ms","start":"2026-05-05T12:53:27.189827Z","end":"2026-05-05T12:53:27.296353Z","steps":["trace[844320436] 'agreement among raft nodes before linearized reading' (duration: 106.434599ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:53:27.297031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.727322ms","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-05T12:53:27.297094Z","caller":"traceutil/trace.go:171","msg":"trace[72676807] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1802; }","duration":"108.824115ms","start":"2026-05-05T12:53:27.188255Z","end":"2026-05-05T12:53:27.297079Z","steps":["trace[72676807] 'agreement among raft nodes before linearized reading' (duration: 107.864854ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:53:31.720978Z","caller":"traceutil/trace.go:171","msg":"trace[998575684] transaction","detail":"{read_only:false; response_revision:1820; number_of_response:1; }","duration":"291.263768ms","start":"2026-05-05T12:53:31.42969Z","end":"2026-05-05T12:53:31.720953Z","steps":["trace[998575684] 'process raft request' (duration: 291.093332ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:53:31.736179Z","caller":"traceutil/trace.go:171","msg":"trace[577680000] transaction","detail":"{read_only:false; response_revision:1821; number_of_response:1; }","duration":"306.311766ms","start":"2026-05-05T12:53:31.429844Z","end":"2026-05-05T12:53:31.736156Z","steps":["trace[577680000] 'process raft request' (duration: 306.206632ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:53:31.736387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:53:31.429835Z","time spent":"306.405399ms","remote":"127.0.0.1:40290","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-05T12:53:33.210359Z","caller":"traceutil/trace.go:171","msg":"trace[1899289347] transaction","detail":"{read_only:false; response_revision:1826; number_of_response:1; }","duration":"105.754008ms","start":"2026-05-05T12:53:33.104585Z","end":"2026-05-05T12:53:33.210339Z","steps":["trace[1899289347] 'process raft request' (duration: 105.642494ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:53:43.745543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.616794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-05T12:53:43.745646Z","caller":"traceutil/trace.go:171","msg":"trace[198797112] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1872; }","duration":"248.83209ms","start":"2026-05-05T12:53:43.496789Z","end":"2026-05-05T12:53:43.745621Z","steps":["trace[198797112] 'range keys from in-memory index tree' (duration: 248.416888ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:54:19.180379Z","caller":"traceutil/trace.go:171","msg":"trace[978501359] transaction","detail":"{read_only:false; response_revision:2037; number_of_response:1; }","duration":"134.932863ms","start":"2026-05-05T12:54:19.045407Z","end":"2026-05-05T12:54:19.18034Z","steps":["trace[978501359] 'process raft request' (duration: 71.324844ms)","trace[978501359] 'compare' (duration: 63.430244ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T12:54:52.344232Z","caller":"traceutil/trace.go:171","msg":"trace[20291128] transaction","detail":"{read_only:false; response_revision:2211; number_of_response:1; }","duration":"178.502148ms","start":"2026-05-05T12:54:52.165691Z","end":"2026-05-05T12:54:52.344193Z","steps":["trace[20291128] 'process raft request' (duration: 144.478168ms)","trace[20291128] 'compare' (duration: 33.628387ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T12:54:52.344565Z","caller":"traceutil/trace.go:171","msg":"trace[1940195175] transaction","detail":"{read_only:false; response_revision:2213; number_of_response:1; }","duration":"177.764904ms","start":"2026-05-05T12:54:52.166776Z","end":"2026-05-05T12:54:52.344541Z","steps":["trace[1940195175] 'process raft request' (duration: 177.375841ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:54:52.344233Z","caller":"traceutil/trace.go:171","msg":"trace[401760675] transaction","detail":"{read_only:false; response_revision:2212; number_of_response:1; }","duration":"178.334512ms","start":"2026-05-05T12:54:52.165869Z","end":"2026-05-05T12:54:52.344203Z","steps":["trace[401760675] 'process raft request' (duration: 178.178857ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:54:52.648359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.285444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:2723"} {"level":"info","ts":"2026-05-05T12:54:52.648453Z","caller":"traceutil/trace.go:171","msg":"trace[983847163] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:2213; }","duration":"117.612534ms","start":"2026-05-05T12:54:52.530817Z","end":"2026-05-05T12:54:52.64843Z","steps":["trace[983847163] 'range keys from in-memory index tree' (duration: 117.088887ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:54:52.648868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.634992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T12:54:52.649142Z","caller":"traceutil/trace.go:171","msg":"trace[1245877347] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:2213; }","duration":"139.384856ms","start":"2026-05-05T12:54:52.509736Z","end":"2026-05-05T12:54:52.649121Z","steps":["trace[1245877347] 'count revisions from in-memory index tree' (duration: 138.525299ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:54:55.423396Z","caller":"traceutil/trace.go:171","msg":"trace[347185425] transaction","detail":"{read_only:false; response_revision:2223; number_of_response:1; }","duration":"139.52839ms","start":"2026-05-05T12:54:55.283839Z","end":"2026-05-05T12:54:55.423367Z","steps":["trace[347185425] 'process raft request' (duration: 139.344364ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:54:55.468093Z","caller":"traceutil/trace.go:171","msg":"trace[2006115431] transaction","detail":"{read_only:false; response_revision:2225; number_of_response:1; }","duration":"130.164573ms","start":"2026-05-05T12:54:55.337911Z","end":"2026-05-05T12:54:55.468075Z","steps":["trace[2006115431] 'process raft request' (duration: 130.07876ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:54:55.468279Z","caller":"traceutil/trace.go:171","msg":"trace[478273273] transaction","detail":"{read_only:false; response_revision:2224; number_of_response:1; }","duration":"140.951146ms","start":"2026-05-05T12:54:55.327305Z","end":"2026-05-05T12:54:55.468256Z","steps":["trace[478273273] 'process raft request' (duration: 140.581334ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:54:59.330983Z","caller":"traceutil/trace.go:171","msg":"trace[889805006] transaction","detail":"{read_only:false; response_revision:2246; number_of_response:1; }","duration":"113.603777ms","start":"2026-05-05T12:54:59.217354Z","end":"2026-05-05T12:54:59.330958Z","steps":["trace[889805006] 'process raft request' (duration: 113.441642ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:55:08.810008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.544132ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T12:55:08.810118Z","caller":"traceutil/trace.go:171","msg":"trace[1986331497] transaction","detail":"{read_only:false; response_revision:2297; number_of_response:1; }","duration":"291.732861ms","start":"2026-05-05T12:55:08.518363Z","end":"2026-05-05T12:55:08.810096Z","steps":["trace[1986331497] 'process raft request' (duration: 154.021054ms)","trace[1986331497] 'compare' (duration: 137.415288ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T12:55:09.733257Z","caller":"traceutil/trace.go:171","msg":"trace[1069410708] transaction","detail":"{read_only:false; response_revision:2298; number_of_response:1; }","duration":"331.953727ms","start":"2026-05-05T12:55:09.401279Z","end":"2026-05-05T12:55:09.733232Z","steps":["trace[1069410708] 'process raft request' (duration: 331.805052ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:55:09.733601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:55:09.401257Z","time spent":"332.179094ms","remote":"127.0.0.1:40290","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-05T12:55:09.741859Z","caller":"traceutil/trace.go:171","msg":"trace[1615729223] linearizableReadLoop","detail":"{readStateIndex:2395; appliedIndex:2393; }","duration":"173.689458ms","start":"2026-05-05T12:55:09.568158Z","end":"2026-05-05T12:55:09.741847Z","steps":["trace[1615729223] 'read index received' (duration: 164.92843ms)","trace[1615729223] 'applied index is now lower than readState.Index' (duration: 8.760198ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T12:55:09.742078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.927035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-05T12:55:09.742113Z","caller":"traceutil/trace.go:171","msg":"trace[1302082337] transaction","detail":"{read_only:false; response_revision:2299; number_of_response:1; }","duration":"201.690436ms","start":"2026-05-05T12:55:09.540373Z","end":"2026-05-05T12:55:09.742064Z","steps":["trace[1302082337] 'process raft request' (duration: 201.420987ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:55:09.742136Z","caller":"traceutil/trace.go:171","msg":"trace[302243406] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2299; }","duration":"174.025339ms","start":"2026-05-05T12:55:09.5681Z","end":"2026-05-05T12:55:09.742125Z","steps":["trace[302243406] 'agreement among raft nodes before linearized reading' (duration: 173.832453ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:55:17.871202Z","caller":"traceutil/trace.go:171","msg":"trace[739154222] linearizableReadLoop","detail":"{readStateIndex:2443; appliedIndex:2442; }","duration":"105.042141ms","start":"2026-05-05T12:55:17.766137Z","end":"2026-05-05T12:55:17.871179Z","steps":["trace[739154222] 'read index received' (duration: 104.838855ms)","trace[739154222] 'applied index is now lower than readState.Index' (duration: 202.366µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T12:55:17.871298Z","caller":"traceutil/trace.go:171","msg":"trace[1301479566] transaction","detail":"{read_only:false; response_revision:2344; number_of_response:1; }","duration":"107.018143ms","start":"2026-05-05T12:55:17.764254Z","end":"2026-05-05T12:55:17.871272Z","steps":["trace[1301479566] 'process raft request' (duration: 106.770775ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:55:17.871914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.753283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-05T12:55:17.871986Z","caller":"traceutil/trace.go:171","msg":"trace[1608510100] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2344; }","duration":"105.855816ms","start":"2026-05-05T12:55:17.766114Z","end":"2026-05-05T12:55:17.871969Z","steps":["trace[1608510100] 'agreement among raft nodes before linearized reading' (duration: 105.199775ms)"],"step_count":1}