{"level":"warn","ts":"2026-04-17T15:05:02.157428Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-17T15:05:02.157574Z","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-04-17T15:05:02.157636Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-17T15:05:02.157644Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.227:2380"]} {"level":"info","ts":"2026-04-17T15:05:02.157659Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-17T15:05:02.158272Z","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-04-17T15:05:02.158341Z","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-04-17T15:05:02.163147Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.6072ms"} {"level":"info","ts":"2026-04-17T15:05:02.170258Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"572db18442eba1c2","cluster-id":"fb1028a037bf3cd6"} {"level":"info","ts":"2026-04-17T15:05:02.170331Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 switched to configuration voters=()"} {"level":"info","ts":"2026-04-17T15:05:02.170355Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became follower at term 0"} {"level":"info","ts":"2026-04-17T15:05:02.170368Z","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-04-17T15:05:02.17038Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became follower at term 1"} {"level":"info","ts":"2026-04-17T15:05:02.170414Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 switched to configuration voters=(6281872236868248002)"} {"level":"warn","ts":"2026-04-17T15:05:02.178697Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-17T15:05:02.180271Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-17T15:05:02.181607Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-17T15:05:02.183984Z","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-04-17T15:05:02.184195Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-17T15:05:02.18459Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-17T15:05:02.184619Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-17T15:05:02.184262Z","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-04-17T15:05:02.188417Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-17T15:05:02.188653Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.227:2380"} {"level":"info","ts":"2026-04-17T15:05:02.188727Z","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-04-17T15:05:02.188758Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-17T15:05:02.188751Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.227:2380"} {"level":"info","ts":"2026-04-17T15:05:02.188803Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 switched to configuration voters=(6281872236868248002)"} {"level":"info","ts":"2026-04-17T15:05:02.18896Z","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-04-17T15:05:03.17158Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 is starting a new election at term 1"} {"level":"info","ts":"2026-04-17T15:05:03.171672Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-17T15:05:03.171718Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 received MsgPreVoteResp from 572db18442eba1c2 at term 1"} {"level":"info","ts":"2026-04-17T15:05:03.171737Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became candidate at term 2"} {"level":"info","ts":"2026-04-17T15:05:03.171745Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 received MsgVoteResp from 572db18442eba1c2 at term 2"} {"level":"info","ts":"2026-04-17T15:05:03.171759Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became leader at term 2"} {"level":"info","ts":"2026-04-17T15:05:03.171771Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 572db18442eba1c2 elected leader 572db18442eba1c2 at term 2"} {"level":"info","ts":"2026-04-17T15:05:03.172805Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-17T15:05:03.173757Z","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-04-17T15:05:03.173772Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-17T15:05:03.173816Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-17T15:05:03.174143Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-17T15:05:03.174231Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-17T15:05:03.174351Z","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-04-17T15:05:03.17446Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-17T15:05:03.174489Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-17T15:05:03.176234Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-17T15:05:03.182473Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.227:2379"} {"level":"warn","ts":"2026-04-17T15:05:06.155771Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656052078816910071,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-17T15:05:06.368571Z","caller":"traceutil/trace.go:171","msg":"trace[798257040] transaction","detail":"{read_only:false; response_revision:85; number_of_response:1; }","duration":"713.625902ms","start":"2026-04-17T15:05:05.65489Z","end":"2026-04-17T15:05:06.368516Z","steps":["trace[798257040] 'process raft request' (duration: 657.757803ms)","trace[798257040] 'compare' (duration: 55.743365ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T15:05:06.369195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:05:05.654864Z","time spent":"713.841591ms","remote":"127.0.0.1:33808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":625,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T15:05:06.370152Z","caller":"traceutil/trace.go:171","msg":"trace[414505522] linearizableReadLoop","detail":"{readStateIndex:90; appliedIndex:88; }","duration":"714.656731ms","start":"2026-04-17T15:05:05.655462Z","end":"2026-04-17T15:05:06.370119Z","steps":["trace[414505522] 'read index received' (duration: 657.275914ms)","trace[414505522] 'applied index is now lower than readState.Index' (duration: 57.379607ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T15:05:06.370479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"714.899141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-17T15:05:06.370317Z","caller":"traceutil/trace.go:171","msg":"trace[1092818916] transaction","detail":"{read_only:false; response_revision:86; number_of_response:1; }","duration":"412.617724ms","start":"2026-04-17T15:05:05.957656Z","end":"2026-04-17T15:05:06.370273Z","steps":["trace[1092818916] 'process raft request' (duration: 412.274611ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:05:06.370809Z","caller":"traceutil/trace.go:171","msg":"trace[1341294370] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:86; }","duration":"715.327407ms","start":"2026-04-17T15:05:05.655459Z","end":"2026-04-17T15:05:06.370786Z","steps":["trace[1341294370] 'agreement among raft nodes before linearized reading' (duration: 714.796337ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:05:06.370889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:05:05.655429Z","time spent":"715.440721ms","remote":"127.0.0.1:33504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-17T15:05:06.370904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:05:05.957624Z","time spent":"413.231977ms","remote":"127.0.0.1:33698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3965,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T15:05:08.302761Z","caller":"traceutil/trace.go:171","msg":"trace[45814123] transaction","detail":"{read_only:false; response_revision:214; number_of_response:1; }","duration":"298.045264ms","start":"2026-04-17T15:05:08.004691Z","end":"2026-04-17T15:05:08.302736Z","steps":["trace[45814123] 'process raft request' (duration: 297.856997ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:05:08.317636Z","caller":"traceutil/trace.go:171","msg":"trace[769084970] transaction","detail":"{read_only:false; response_revision:215; number_of_response:1; }","duration":"269.41688ms","start":"2026-04-17T15:05:08.048195Z","end":"2026-04-17T15:05:08.317612Z","steps":["trace[769084970] 'process raft request' (duration: 269.307446ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:05:08.719646Z","caller":"traceutil/trace.go:171","msg":"trace[1921845097] linearizableReadLoop","detail":"{readStateIndex:222; appliedIndex:221; }","duration":"398.201911ms","start":"2026-04-17T15:05:08.321404Z","end":"2026-04-17T15:05:08.719606Z","steps":["trace[1921845097] 'read index received' (duration: 384.912241ms)","trace[1921845097] 'applied index is now lower than readState.Index' (duration: 13.28851ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T15:05:08.719667Z","caller":"traceutil/trace.go:171","msg":"trace[795805837] transaction","detail":"{read_only:false; response_revision:216; number_of_response:1; }","duration":"412.212468ms","start":"2026-04-17T15:05:08.307407Z","end":"2026-04-17T15:05:08.719619Z","steps":["trace[795805837] 'process raft request' (duration: 399.002501ms)","trace[795805837] 'compare' (duration: 12.74976ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T15:05:08.719811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.385708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/disruption-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T15:05:08.719897Z","caller":"traceutil/trace.go:171","msg":"trace[119643769] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/disruption-controller; range_end:; response_count:0; response_revision:216; }","duration":"398.503922ms","start":"2026-04-17T15:05:08.321368Z","end":"2026-04-17T15:05:08.719872Z","steps":["trace[119643769] 'agreement among raft nodes before linearized reading' (duration: 398.320165ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:05:08.719961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:05:08.307384Z","time spent":"412.456907ms","remote":"127.0.0.1:33802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":342,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-17T15:05:08.720003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:05:08.321352Z","time spent":"398.639657ms","remote":"127.0.0.1:33714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/kube-system/disruption-controller\" "} {"level":"warn","ts":"2026-04-17T15:05:08.720094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.352829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T15:05:08.720136Z","caller":"traceutil/trace.go:171","msg":"trace[669977881] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:216; }","duration":"102.445262ms","start":"2026-04-17T15:05:08.617679Z","end":"2026-04-17T15:05:08.720124Z","steps":["trace[669977881] 'agreement among raft nodes before linearized reading' (duration: 102.179862ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:05:15.983446Z","caller":"traceutil/trace.go:171","msg":"trace[385391964] transaction","detail":"{read_only:false; response_revision:399; number_of_response:1; }","duration":"245.920819ms","start":"2026-04-17T15:05:15.737507Z","end":"2026-04-17T15:05:15.983428Z","steps":["trace[385391964] 'process raft request' (duration: 245.796145ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:05:17.897292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.632125ms","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-04-17T15:05:17.89738Z","caller":"traceutil/trace.go:171","msg":"trace[461103529] range","detail":"{range_begin:/registry/masterleases/199.204.45.227; range_end:; response_count:1; response_revision:399; }","duration":"140.79724ms","start":"2026-04-17T15:05:17.75656Z","end":"2026-04-17T15:05:17.897357Z","steps":["trace[461103529] 'range keys from in-memory index tree' (duration: 140.52762ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:05:17.897439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.702717968s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T15:05:17.897561Z","caller":"traceutil/trace.go:171","msg":"trace[1372869467] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:399; }","duration":"1.702889054s","start":"2026-04-17T15:05:16.194652Z","end":"2026-04-17T15:05:17.897541Z","steps":["trace[1372869467] 'range keys from in-memory index tree' (duration: 1.702606134s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:05:17.897329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.065435324s","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-04-17T15:05:17.897593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"879.384203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-17T15:05:17.897678Z","caller":"traceutil/trace.go:171","msg":"trace[995112379] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:399; }","duration":"1.065825346s","start":"2026-04-17T15:05:16.831822Z","end":"2026-04-17T15:05:17.897648Z","steps":["trace[995112379] 'range keys from in-memory index tree' (duration: 1.065309398s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:05:17.89774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:05:16.831801Z","time spent":"1.06592482s","remote":"127.0.0.1:33760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-17T15:05:17.897738Z","caller":"traceutil/trace.go:171","msg":"trace[501926360] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:399; }","duration":"879.572619ms","start":"2026-04-17T15:05:17.018141Z","end":"2026-04-17T15:05:17.897714Z","steps":["trace[501926360] 'range keys from in-memory index tree' (duration: 879.228689ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:05:17.897796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:05:17.018124Z","time spent":"879.659033ms","remote":"127.0.0.1:33760","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-04-17T15:05:17.897856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.976347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-17T15:05:17.897628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:05:16.194638Z","time spent":"1.702979966s","remote":"127.0.0.1:33494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-17T15:05:17.897936Z","caller":"traceutil/trace.go:171","msg":"trace[904301917] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:399; }","duration":"542.089331ms","start":"2026-04-17T15:05:17.355825Z","end":"2026-04-17T15:05:17.897915Z","steps":["trace[904301917] 'range keys from in-memory index tree' (duration: 541.649597ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:05:17.897982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:05:17.355806Z","time spent":"542.164024ms","remote":"127.0.0.1:33760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-17T15:05:18.415139Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656052078816910723,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T15:05:18.915271Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656052078816910723,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T15:05:19.125156Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.14388042s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-17T15:05:19.125549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:05:17.899385Z","time spent":"1.226157524s","remote":"127.0.0.1:33530","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-17T15:05:20.01536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000368741s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-17T15:05:20.015498Z","caller":"traceutil/trace.go:171","msg":"trace[943143197] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000544347s","start":"2026-04-17T15:05:18.014925Z","end":"2026-04-17T15:05:20.01547Z","steps":["trace[943143197] 'agreement among raft nodes before linearized reading' (duration: 2.000399042s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:05:20.015578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:05:18.014906Z","time spent":"2.00065609s","remote":"127.0.0.1:33494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/17 15:05:20 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-17T15:05:20.557793Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.215191751s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-17T15:05:21.416281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"857.690888ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T15:05:21.416638Z","caller":"traceutil/trace.go:171","msg":"trace[412443131] transaction","detail":"{read_only:false; response_revision:400; number_of_response:1; }","duration":"3.510216344s","start":"2026-04-17T15:05:17.906309Z","end":"2026-04-17T15:05:21.416526Z","steps":["trace[412443131] 'process raft request' (duration: 2.651825064s)","trace[412443131] 'compare' (duration: 856.987995ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T15:05:21.416802Z","caller":"traceutil/trace.go:171","msg":"trace[1820824336] transaction","detail":"{read_only:false; response_revision:401; number_of_response:1; }","duration":"3.509750938s","start":"2026-04-17T15:05:17.907029Z","end":"2026-04-17T15:05:21.41678Z","steps":["trace[1820824336] 'process raft request' (duration: 3.509367466s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:05:21.416906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:05:17.907009Z","time spent":"3.509857151s","remote":"127.0.0.1:33760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T15:05:21.417064Z","caller":"traceutil/trace.go:171","msg":"trace[157451503] transaction","detail":"{read_only:false; response_revision:404; number_of_response:1; }","duration":"930.882974ms","start":"2026-04-17T15:05:20.486168Z","end":"2026-04-17T15:05:21.417051Z","steps":["trace[157451503] 'process raft request' (duration: 930.843363ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:05:21.417066Z","caller":"traceutil/trace.go:171","msg":"trace[1710732020] transaction","detail":"{read_only:false; response_revision:402; number_of_response:1; }","duration":"3.502682139s","start":"2026-04-17T15:05:17.914356Z","end":"2026-04-17T15:05:21.417038Z","steps":["trace[1710732020] 'process raft request' (duration: 3.502329488s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:05:21.41712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:05:20.486137Z","time spent":"930.956656ms","remote":"127.0.0.1:33760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T15:05:21.417196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:05:17.914339Z","time spent":"3.502791233s","remote":"127.0.0.1:33760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T15:05:21.417259Z","caller":"traceutil/trace.go:171","msg":"trace[119405772] linearizableReadLoop","detail":"{readStateIndex:418; appliedIndex:415; }","duration":"3.50300657s","start":"2026-04-17T15:05:17.914241Z","end":"2026-04-17T15:05:21.417248Z","steps":["trace[119405772] 'read index received' (duration: 1.211280264s)","trace[119405772] 'applied index is now lower than readState.Index' (duration: 2.291725106s)"],"step_count":2} {"level":"warn","ts":"2026-04-17T15:05:21.417064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:05:17.90629Z","time spent":"3.510531894s","remote":"127.0.0.1:33760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T15:05:21.417418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.430196004s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-17T15:05:21.417444Z","caller":"traceutil/trace.go:171","msg":"trace[91659463] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:404; }","duration":"3.430251435s","start":"2026-04-17T15:05:17.987185Z","end":"2026-04-17T15:05:21.417437Z","steps":["trace[91659463] 'agreement among raft nodes before linearized reading' (duration: 3.430213204s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:05:21.417469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:05:17.987166Z","time spent":"3.430293757s","remote":"127.0.0.1:33760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-17T15:05:21.417378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.503133924s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7303"} {"level":"info","ts":"2026-04-17T15:05:21.417485Z","caller":"traceutil/trace.go:171","msg":"trace[240564612] transaction","detail":"{read_only:false; response_revision:403; number_of_response:1; }","duration":"2.290636849s","start":"2026-04-17T15:05:19.126816Z","end":"2026-04-17T15:05:21.417453Z","steps":["trace[240564612] 'process raft request' (duration: 2.290109842s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:05:21.417525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.401769023s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T15:05:21.417563Z","caller":"traceutil/trace.go:171","msg":"trace[771150696] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:404; }","duration":"1.401845896s","start":"2026-04-17T15:05:20.015705Z","end":"2026-04-17T15:05:21.417551Z","steps":["trace[771150696] 'agreement among raft nodes before linearized reading' (duration: 1.401789474s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:05:21.417597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:05:20.015683Z","time spent":"1.401907288s","remote":"127.0.0.1:33504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-17T15:05:21.417638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:05:19.126793Z","time spent":"2.290754573s","remote":"127.0.0.1:33530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T15:05:21.418347Z","caller":"traceutil/trace.go:171","msg":"trace[2068619386] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:404; }","duration":"3.504099726s","start":"2026-04-17T15:05:17.914203Z","end":"2026-04-17T15:05:21.418303Z","steps":["trace[2068619386] 'agreement among raft nodes before linearized reading' (duration: 3.503117244s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:05:21.418427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:05:17.914185Z","time spent":"3.50422261s","remote":"127.0.0.1:33698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":7326,"request content":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" "} {"level":"warn","ts":"2026-04-17T15:05:21.92327Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656052078816910734,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T15:05:22.453731Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656052078816910734,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T15:05:22.954939Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656052078816910734,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T15:05:23.42591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000212694s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-17T15:05:23.426004Z","caller":"traceutil/trace.go:171","msg":"trace[1087972873] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000353949s","start":"2026-04-17T15:05:21.425629Z","end":"2026-04-17T15:05:23.425983Z","steps":["trace[1087972873] 'agreement among raft nodes before linearized reading' (duration: 2.000243015s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:05:23.426098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:05:21.425608Z","time spent":"2.000478203s","remote":"127.0.0.1:33494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-17T15:05:23.45574Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656052078816910734,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T15:05:23.477529Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.055313142s","expected-duration":"1s"} {"level":"info","ts":"2026-04-17T15:05:23.478141Z","caller":"traceutil/trace.go:171","msg":"trace[330901229] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"2.055974303s","start":"2026-04-17T15:05:21.422142Z","end":"2026-04-17T15:05:23.478116Z","steps":["trace[330901229] 'process raft request' (duration: 2.055608811s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:05:23.478294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:05:21.42213Z","time spent":"2.056091847s","remote":"127.0.0.1:33760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T15:05:23.487483Z","caller":"traceutil/trace.go:171","msg":"trace[487278688] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"2.061131023s","start":"2026-04-17T15:05:21.426325Z","end":"2026-04-17T15:05:23.487456Z","steps":["trace[487278688] 'process raft request' (duration: 2.060972588s)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:05:23.487488Z","caller":"traceutil/trace.go:171","msg":"trace[1681827000] transaction","detail":"{read_only:false; response_revision:406; number_of_response:1; }","duration":"2.064848034s","start":"2026-04-17T15:05:21.422597Z","end":"2026-04-17T15:05:23.487446Z","steps":["trace[1681827000] 'process raft request' (duration: 2.064598416s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:05:23.487659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:05:21.426303Z","time spent":"2.061251728s","remote":"127.0.0.1:33698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7096,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T15:05:23.487749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:05:21.422581Z","time spent":"2.065122602s","remote":"127.0.0.1:33596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T15:05:23.49996Z","caller":"traceutil/trace.go:171","msg":"trace[1836914715] linearizableReadLoop","detail":"{readStateIndex:424; appliedIndex:420; }","duration":"2.077692551s","start":"2026-04-17T15:05:21.422252Z","end":"2026-04-17T15:05:23.499945Z","steps":["trace[1836914715] 'read index received' (duration: 2.055521429s)","trace[1836914715] 'applied index is now lower than readState.Index' (duration: 22.170422ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T15:05:23.50009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.077838456s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-04-17T15:05:23.500115Z","caller":"traceutil/trace.go:171","msg":"trace[1711879296] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:407; }","duration":"2.077900918s","start":"2026-04-17T15:05:21.422206Z","end":"2026-04-17T15:05:23.500107Z","steps":["trace[1711879296] 'agreement among raft nodes before linearized reading' (duration: 2.077835326s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:05:23.500134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:05:21.422192Z","time spent":"2.077937289s","remote":"127.0.0.1:33764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"warn","ts":"2026-04-17T15:05:23.500107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.720787062s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T15:05:23.500249Z","caller":"traceutil/trace.go:171","msg":"trace[553477621] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:407; }","duration":"1.720928496s","start":"2026-04-17T15:05:21.779302Z","end":"2026-04-17T15:05:23.50023Z","steps":["trace[553477621] 'agreement among raft nodes before linearized reading' (duration: 1.720773582s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:05:23.500495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"653.357021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T15:05:23.500613Z","caller":"traceutil/trace.go:171","msg":"trace[1574285449] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:407; }","duration":"653.528196ms","start":"2026-04-17T15:05:22.847055Z","end":"2026-04-17T15:05:23.500583Z","steps":["trace[1574285449] 'agreement among raft nodes before linearized reading' (duration: 653.264998ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:05:23.500668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:05:22.847032Z","time spent":"653.62827ms","remote":"127.0.0.1:33494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-17T15:05:30.186792Z","caller":"traceutil/trace.go:171","msg":"trace[930385974] linearizableReadLoop","detail":"{readStateIndex:447; appliedIndex:446; }","duration":"173.079677ms","start":"2026-04-17T15:05:30.01369Z","end":"2026-04-17T15:05:30.186769Z","steps":["trace[930385974] 'read index received' (duration: 172.8646ms)","trace[930385974] 'applied index is now lower than readState.Index' (duration: 214.267µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T15:05:30.186888Z","caller":"traceutil/trace.go:171","msg":"trace[1786602600] transaction","detail":"{read_only:false; response_revision:429; number_of_response:1; }","duration":"173.581731ms","start":"2026-04-17T15:05:30.013284Z","end":"2026-04-17T15:05:30.186866Z","steps":["trace[1786602600] 'process raft request' (duration: 173.346034ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:05:30.18692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.21051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T15:05:30.18728Z","caller":"traceutil/trace.go:171","msg":"trace[162016599] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:429; }","duration":"173.512629ms","start":"2026-04-17T15:05:30.01366Z","end":"2026-04-17T15:05:30.187173Z","steps":["trace[162016599] 'agreement among raft nodes before linearized reading' (duration: 173.210781ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:05:35.786326Z","caller":"traceutil/trace.go:171","msg":"trace[14452863] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"169.028726ms","start":"2026-04-17T15:05:35.617271Z","end":"2026-04-17T15:05:35.786299Z","steps":["trace[14452863] 'process raft request' (duration: 95.472078ms)","trace[14452863] 'compare' (duration: 73.400663ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T15:05:35.798153Z","caller":"traceutil/trace.go:171","msg":"trace[674020210] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"177.811215ms","start":"2026-04-17T15:05:35.620321Z","end":"2026-04-17T15:05:35.798132Z","steps":["trace[674020210] 'process raft request' (duration: 177.769874ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:05:35.79825Z","caller":"traceutil/trace.go:171","msg":"trace[1466692034] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"177.966999ms","start":"2026-04-17T15:05:35.620278Z","end":"2026-04-17T15:05:35.798245Z","steps":["trace[1466692034] 'process raft request' (duration: 177.771514ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:05:35.798361Z","caller":"traceutil/trace.go:171","msg":"trace[497639985] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"149.069949ms","start":"2026-04-17T15:05:35.649273Z","end":"2026-04-17T15:05:35.798343Z","steps":["trace[497639985] 'process raft request' (duration: 148.838263ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:05:37.754595Z","caller":"traceutil/trace.go:171","msg":"trace[854024428] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"133.393868ms","start":"2026-04-17T15:05:37.62117Z","end":"2026-04-17T15:05:37.754564Z","steps":["trace[854024428] 'process raft request' (duration: 133.198453ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:05:38.00989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.871359ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T15:05:38.010215Z","caller":"traceutil/trace.go:171","msg":"trace[1474643920] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"199.887721ms","start":"2026-04-17T15:05:37.810315Z","end":"2026-04-17T15:05:38.010202Z","steps":["trace[1474643920] 'process raft request' (duration: 199.819389ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:05:38.010602Z","caller":"traceutil/trace.go:171","msg":"trace[831476591] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"201.485635ms","start":"2026-04-17T15:05:37.809109Z","end":"2026-04-17T15:05:38.010594Z","steps":["trace[831476591] 'process raft request' (duration: 70.844214ms)","trace[831476591] 'compare' (duration: 129.781177ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T15:05:38.010737Z","caller":"traceutil/trace.go:171","msg":"trace[960388107] linearizableReadLoop","detail":"{readStateIndex:496; appliedIndex:495; }","duration":"201.590578ms","start":"2026-04-17T15:05:37.809139Z","end":"2026-04-17T15:05:38.010729Z","steps":["trace[960388107] 'read index received' (duration: 71.182µs)","trace[960388107] 'applied index is now lower than readState.Index' (duration: 201.518676ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T15:05:38.01079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.6568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-17T15:05:38.010811Z","caller":"traceutil/trace.go:171","msg":"trace[1840899956] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:477; }","duration":"201.724422ms","start":"2026-04-17T15:05:37.809081Z","end":"2026-04-17T15:05:38.010805Z","steps":["trace[1840899956] 'agreement among raft nodes before linearized reading' (duration: 201.679021ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:05:38.010894Z","caller":"traceutil/trace.go:171","msg":"trace[1082649973] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"201.168966ms","start":"2026-04-17T15:05:37.809718Z","end":"2026-04-17T15:05:38.010887Z","steps":["trace[1082649973] 'process raft request' (duration: 200.260271ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:05:42.516596Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656052078816910895,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-17T15:05:42.748234Z","caller":"traceutil/trace.go:171","msg":"trace[297823225] linearizableReadLoop","detail":"{readStateIndex:504; appliedIndex:503; }","duration":"732.009878ms","start":"2026-04-17T15:05:42.016189Z","end":"2026-04-17T15:05:42.748199Z","steps":["trace[297823225] 'read index received' (duration: 731.605978ms)","trace[297823225] 'applied index is now lower than readState.Index' (duration: 402.28µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T15:05:42.748364Z","caller":"traceutil/trace.go:171","msg":"trace[355516515] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"786.584416ms","start":"2026-04-17T15:05:41.961746Z","end":"2026-04-17T15:05:42.74833Z","steps":["trace[355516515] 'process raft request' (duration: 786.238557ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:05:42.748471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"732.226414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T15:05:42.748526Z","caller":"traceutil/trace.go:171","msg":"trace[2096445120] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:483; }","duration":"732.358758ms","start":"2026-04-17T15:05:42.016152Z","end":"2026-04-17T15:05:42.74851Z","steps":["trace[2096445120] 'agreement among raft nodes before linearized reading' (duration: 732.227634ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:05:42.748553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:05:41.961723Z","time spent":"786.740689ms","remote":"127.0.0.1:33760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T15:05:42.748568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:05:42.016129Z","time spent":"732.42993ms","remote":"127.0.0.1:33494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-17T15:05:42.748616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"721.018814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-17T15:05:42.748664Z","caller":"traceutil/trace.go:171","msg":"trace[1910689183] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:483; }","duration":"721.056845ms","start":"2026-04-17T15:05:42.027591Z","end":"2026-04-17T15:05:42.748648Z","steps":["trace[1910689183] 'agreement among raft nodes before linearized reading' (duration: 720.999464ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:05:42.748695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:05:42.027481Z","time spent":"721.208749ms","remote":"127.0.0.1:33760","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-04-17T15:05:42.748649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"720.646824ms","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-04-17T15:05:42.748549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"721.063016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-17T15:05:42.748816Z","caller":"traceutil/trace.go:171","msg":"trace[438633124] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:483; }","duration":"721.401334ms","start":"2026-04-17T15:05:42.027407Z","end":"2026-04-17T15:05:42.748808Z","steps":["trace[438633124] 'agreement among raft nodes before linearized reading' (duration: 721.034225ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:05:42.748778Z","caller":"traceutil/trace.go:171","msg":"trace[1358438] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:483; }","duration":"720.801118ms","start":"2026-04-17T15:05:42.027943Z","end":"2026-04-17T15:05:42.748744Z","steps":["trace[1358438] 'agreement among raft nodes before linearized reading' (duration: 720.546441ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:05:42.748829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:05:42.02739Z","time spent":"721.436056ms","remote":"127.0.0.1:33760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-17T15:05:42.748869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:05:42.027903Z","time spent":"720.949812ms","remote":"127.0.0.1:33760","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-04-17T15:05:43.305188Z","caller":"traceutil/trace.go:171","msg":"trace[2048507894] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"551.974293ms","start":"2026-04-17T15:05:42.753159Z","end":"2026-04-17T15:05:43.305134Z","steps":["trace[2048507894] 'process raft request' (duration: 551.789768ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:05:43.305376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:05:42.753136Z","time spent":"552.145358ms","remote":"127.0.0.1:33760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T15:05:43.306471Z","caller":"traceutil/trace.go:171","msg":"trace[480740771] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"553.00544ms","start":"2026-04-17T15:05:42.753425Z","end":"2026-04-17T15:05:43.30643Z","steps":["trace[480740771] 'process raft request' (duration: 552.667631ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:05:43.30655Z","caller":"traceutil/trace.go:171","msg":"trace[1823757244] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"552.408505ms","start":"2026-04-17T15:05:42.754106Z","end":"2026-04-17T15:05:43.306515Z","steps":["trace[1823757244] 'process raft request' (duration: 552.186799ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:05:43.306751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:05:42.754101Z","time spent":"552.541958ms","remote":"127.0.0.1:33760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T15:05:43.306868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:05:42.753417Z","time spent":"553.162074ms","remote":"127.0.0.1:33760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T15:05:47.130847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.566672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T15:05:47.130969Z","caller":"traceutil/trace.go:171","msg":"trace[1038958900] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:526; }","duration":"117.776487ms","start":"2026-04-17T15:05:47.013165Z","end":"2026-04-17T15:05:47.130942Z","steps":["trace[1038958900] 'range keys from in-memory index tree' (duration: 117.436089ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:05:47.131037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"683.257093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T15:05:47.131016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.377746ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T15:05:47.131175Z","caller":"traceutil/trace.go:171","msg":"trace[1003102976] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:526; }","duration":"299.55046ms","start":"2026-04-17T15:05:46.831597Z","end":"2026-04-17T15:05:47.131147Z","steps":["trace[1003102976] 'range keys from in-memory index tree' (duration: 299.353805ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:05:47.13117Z","caller":"traceutil/trace.go:171","msg":"trace[69707194] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:526; }","duration":"683.437428ms","start":"2026-04-17T15:05:46.447697Z","end":"2026-04-17T15:05:47.131134Z","steps":["trace[69707194] 'range keys from in-memory index tree' (duration: 683.117609ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:05:47.131236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:05:46.447677Z","time spent":"683.54438ms","remote":"127.0.0.1:33614","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" "} {"level":"info","ts":"2026-04-17T15:05:47.452401Z","caller":"traceutil/trace.go:171","msg":"trace[1588195291] linearizableReadLoop","detail":"{readStateIndex:549; appliedIndex:548; }","duration":"116.215408ms","start":"2026-04-17T15:05:47.336151Z","end":"2026-04-17T15:05:47.452366Z","steps":["trace[1588195291] 'read index received' (duration: 115.940721ms)","trace[1588195291] 'applied index is now lower than readState.Index' (duration: 273.447µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T15:05:47.452635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.450664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-17T15:05:47.452575Z","caller":"traceutil/trace.go:171","msg":"trace[66016363] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"126.873141ms","start":"2026-04-17T15:05:47.325636Z","end":"2026-04-17T15:05:47.452509Z","steps":["trace[66016363] 'process raft request' (duration: 126.504261ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:05:47.452689Z","caller":"traceutil/trace.go:171","msg":"trace[1440495011] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:527; }","duration":"116.573397ms","start":"2026-04-17T15:05:47.336099Z","end":"2026-04-17T15:05:47.452673Z","steps":["trace[1440495011] 'agreement among raft nodes before linearized reading' (duration: 116.448374ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:05:47.452647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.401562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-17T15:05:47.452833Z","caller":"traceutil/trace.go:171","msg":"trace[127892380] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:527; }","duration":"116.606598ms","start":"2026-04-17T15:05:47.336203Z","end":"2026-04-17T15:05:47.45281Z","steps":["trace[127892380] 'agreement among raft nodes before linearized reading' (duration: 116.373452ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:05:48.770493Z","caller":"traceutil/trace.go:171","msg":"trace[1386309659] linearizableReadLoop","detail":"{readStateIndex:590; appliedIndex:587; }","duration":"929.292342ms","start":"2026-04-17T15:05:47.841178Z","end":"2026-04-17T15:05:48.77047Z","steps":["trace[1386309659] 'read index received' (duration: 160.174µs)","trace[1386309659] 'applied index is now lower than readState.Index' (duration: 929.131038ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T15:05:48.770537Z","caller":"traceutil/trace.go:171","msg":"trace[928085640] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"945.802035ms","start":"2026-04-17T15:05:47.824681Z","end":"2026-04-17T15:05:48.770483Z","steps":["trace[928085640] 'process raft request' (duration: 940.547843ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:05:48.770538Z","caller":"traceutil/trace.go:171","msg":"trace[683651721] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"935.403669ms","start":"2026-04-17T15:05:47.835096Z","end":"2026-04-17T15:05:48.770499Z","steps":["trace[683651721] 'process raft request' (duration: 935.315287ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:05:48.770655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"929.456687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-17T15:05:48.77069Z","caller":"traceutil/trace.go:171","msg":"trace[75657005] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:564; }","duration":"929.551289ms","start":"2026-04-17T15:05:47.841122Z","end":"2026-04-17T15:05:48.770674Z","steps":["trace[75657005] 'agreement among raft nodes before linearized reading' (duration: 929.477957ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:05:48.77072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:05:47.841098Z","time spent":"929.615721ms","remote":"127.0.0.1:33760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-17T15:05:48.770748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:05:47.824654Z","time spent":"945.979401ms","remote":"127.0.0.1:33530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T15:05:48.770748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:05:47.835072Z","time spent":"935.575784ms","remote":"127.0.0.1:33558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23066,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T15:05:48.795389Z","caller":"traceutil/trace.go:171","msg":"trace[96669639] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"906.442782ms","start":"2026-04-17T15:05:47.888931Z","end":"2026-04-17T15:05:48.795374Z","steps":["trace[96669639] 'process raft request' (duration: 906.290728ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:05:48.795479Z","caller":"traceutil/trace.go:171","msg":"trace[378305008] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"887.625989ms","start":"2026-04-17T15:05:47.90783Z","end":"2026-04-17T15:05:48.795456Z","steps":["trace[378305008] 'process raft request' (duration: 887.451165ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:05:48.79536Z","caller":"traceutil/trace.go:171","msg":"trace[88390979] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"942.894056ms","start":"2026-04-17T15:05:47.852427Z","end":"2026-04-17T15:05:48.795321Z","steps":["trace[88390979] 'process raft request' (duration: 942.68283ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:05:48.795565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:05:47.88889Z","time spent":"906.569215ms","remote":"127.0.0.1:33558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17062,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-17T15:05:48.795593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"780.281781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T15:05:48.795592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:05:47.907788Z","time spent":"887.742732ms","remote":"127.0.0.1:33558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":150480,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T15:05:48.795641Z","caller":"traceutil/trace.go:171","msg":"trace[1255005880] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:567; }","duration":"780.414334ms","start":"2026-04-17T15:05:48.015213Z","end":"2026-04-17T15:05:48.795627Z","steps":["trace[1255005880] 'agreement among raft nodes before linearized reading' (duration: 780.22208ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:05:48.795672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:05:48.015196Z","time spent":"780.469536ms","remote":"127.0.0.1:33494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-17T15:05:48.7956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:05:47.852397Z","time spent":"943.152821ms","remote":"127.0.0.1:33558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7684,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T15:05:49.213233Z","caller":"traceutil/trace.go:171","msg":"trace[117178238] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"113.43344ms","start":"2026-04-17T15:05:49.099749Z","end":"2026-04-17T15:05:49.213182Z","steps":["trace[117178238] 'process raft request' (duration: 84.760117ms)","trace[117178238] 'compare' (duration: 27.955935ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T15:05:49.677179Z","caller":"traceutil/trace.go:171","msg":"trace[1869873322] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"110.742802ms","start":"2026-04-17T15:05:49.566403Z","end":"2026-04-17T15:05:49.677146Z","steps":["trace[1869873322] 'process raft request' (duration: 94.83151ms)","trace[1869873322] 'compare' (duration: 15.774249ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T15:05:49.949745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.415651ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T15:05:49.949894Z","caller":"traceutil/trace.go:171","msg":"trace[899544084] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"239.720034ms","start":"2026-04-17T15:05:49.710155Z","end":"2026-04-17T15:05:49.949875Z","steps":["trace[899544084] 'process raft request' (duration: 72.066868ms)","trace[899544084] 'compare' (duration: 167.232846ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T15:05:49.95912Z","caller":"traceutil/trace.go:171","msg":"trace[1577407038] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"169.142665ms","start":"2026-04-17T15:05:49.789946Z","end":"2026-04-17T15:05:49.959089Z","steps":["trace[1577407038] 'process raft request' (duration: 168.969311ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:05:50.041639Z","caller":"traceutil/trace.go:171","msg":"trace[332514284] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"147.960622ms","start":"2026-04-17T15:05:49.893655Z","end":"2026-04-17T15:05:50.041615Z","steps":["trace[332514284] 'process raft request' (duration: 144.66386ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:05:50.140559Z","caller":"traceutil/trace.go:171","msg":"trace[1203243990] linearizableReadLoop","detail":"{readStateIndex:629; appliedIndex:627; }","duration":"127.457079ms","start":"2026-04-17T15:05:50.013056Z","end":"2026-04-17T15:05:50.140513Z","steps":["trace[1203243990] 'read index received' (duration: 21.598271ms)","trace[1203243990] 'applied index is now lower than readState.Index' (duration: 105.858008ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T15:05:50.140666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.593842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T15:05:50.140688Z","caller":"traceutil/trace.go:171","msg":"trace[2112628382] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:603; }","duration":"127.657964ms","start":"2026-04-17T15:05:50.013023Z","end":"2026-04-17T15:05:50.140681Z","steps":["trace[2112628382] 'agreement among raft nodes before linearized reading' (duration: 127.586192ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:05:50.140965Z","caller":"traceutil/trace.go:171","msg":"trace[1821334202] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"174.00869ms","start":"2026-04-17T15:05:49.966948Z","end":"2026-04-17T15:05:50.140957Z","steps":["trace[1821334202] 'process raft request' (duration: 157.2194ms)","trace[1821334202] 'compare' (duration: 16.249046ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T15:05:50.494807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.570611ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T15:05:50.495009Z","caller":"traceutil/trace.go:171","msg":"trace[1071728174] linearizableReadLoop","detail":"{readStateIndex:632; appliedIndex:631; }","duration":"165.080069ms","start":"2026-04-17T15:05:50.32991Z","end":"2026-04-17T15:05:50.49499Z","steps":["trace[1071728174] 'read index received' (duration: 39.234201ms)","trace[1071728174] 'applied index is now lower than readState.Index' (duration: 125.844708ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T15:05:50.495129Z","caller":"traceutil/trace.go:171","msg":"trace[931182294] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"165.326496ms","start":"2026-04-17T15:05:50.329724Z","end":"2026-04-17T15:05:50.49505Z","steps":["trace[931182294] 'process raft request' (duration: 39.391395ms)","trace[931182294] 'compare' (duration: 124.802403ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T15:05:50.495203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.288585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/grpcroutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:80703"} {"level":"info","ts":"2026-04-17T15:05:50.495393Z","caller":"traceutil/trace.go:171","msg":"trace[1999289529] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/grpcroutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:606; }","duration":"165.5064ms","start":"2026-04-17T15:05:50.329873Z","end":"2026-04-17T15:05:50.495379Z","steps":["trace[1999289529] 'agreement among raft nodes before linearized reading' (duration: 165.164301ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:05:50.813285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.646404ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T15:05:50.813476Z","caller":"traceutil/trace.go:171","msg":"trace[1717474777] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"456.797327ms","start":"2026-04-17T15:05:50.35663Z","end":"2026-04-17T15:05:50.813428Z","steps":["trace[1717474777] 'process raft request' (duration: 246.896836ms)","trace[1717474777] 'compare' (duration: 209.324816ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T15:05:50.813523Z","caller":"traceutil/trace.go:171","msg":"trace[298340747] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"241.828099ms","start":"2026-04-17T15:05:50.571667Z","end":"2026-04-17T15:05:50.813495Z","steps":["trace[298340747] 'process raft request' (duration: 241.746337ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:05:50.813784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:05:50.356604Z","time spent":"456.95742ms","remote":"127.0.0.1:33558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":208461,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T15:05:50.813832Z","caller":"traceutil/trace.go:171","msg":"trace[1153048858] linearizableReadLoop","detail":"{readStateIndex:633; appliedIndex:632; }","duration":"287.381699ms","start":"2026-04-17T15:05:50.526439Z","end":"2026-04-17T15:05:50.81382Z","steps":["trace[1153048858] 'read index received' (duration: 77.101479ms)","trace[1153048858] 'applied index is now lower than readState.Index' (duration: 210.27896ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T15:05:50.814016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.569634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/httproutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:151236"} {"level":"info","ts":"2026-04-17T15:05:50.81411Z","caller":"traceutil/trace.go:171","msg":"trace[518633943] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/httproutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:608; }","duration":"287.704477ms","start":"2026-04-17T15:05:50.526395Z","end":"2026-04-17T15:05:50.814099Z","steps":["trace[518633943] 'agreement among raft nodes before linearized reading' (duration: 287.463651ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:05:51.30148Z","caller":"traceutil/trace.go:171","msg":"trace[945548403] linearizableReadLoop","detail":"{readStateIndex:636; appliedIndex:635; }","duration":"403.061644ms","start":"2026-04-17T15:05:50.89839Z","end":"2026-04-17T15:05:51.301451Z","steps":["trace[945548403] 'read index received' (duration: 323.328694ms)","trace[945548403] 'applied index is now lower than readState.Index' (duration: 79.73191ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T15:05:51.301973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.886479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T15:05:51.301979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.543867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/udproutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:31126"} {"level":"info","ts":"2026-04-17T15:05:51.302096Z","caller":"traceutil/trace.go:171","msg":"trace[690997762] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:610; }","duration":"289.072824ms","start":"2026-04-17T15:05:51.012999Z","end":"2026-04-17T15:05:51.302072Z","steps":["trace[690997762] 'agreement among raft nodes before linearized reading' (duration: 288.868589ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:05:51.302114Z","caller":"traceutil/trace.go:171","msg":"trace[1370606451] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/udproutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:610; }","duration":"403.73303ms","start":"2026-04-17T15:05:50.898355Z","end":"2026-04-17T15:05:51.302088Z","steps":["trace[1370606451] 'agreement among raft nodes before linearized reading' (duration: 403.31854ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:05:51.302171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:05:50.898316Z","time spent":"403.841743ms","remote":"127.0.0.1:33558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":31149,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/udproutes.gateway.networking.k8s.io\" "} {"level":"info","ts":"2026-04-17T15:05:51.301925Z","caller":"traceutil/trace.go:171","msg":"trace[187255488] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"417.201448ms","start":"2026-04-17T15:05:50.884684Z","end":"2026-04-17T15:05:51.301885Z","steps":["trace[187255488] 'process raft request' (duration: 337.161833ms)","trace[187255488] 'compare' (duration: 79.39123ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T15:05:51.302382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:05:50.884665Z","time spent":"417.566027ms","remote":"127.0.0.1:33558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":208470,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T15:05:55.988727Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656052078816911170,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T15:05:56.489611Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656052078816911170,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T15:05:56.850307Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.57622394s","expected-duration":"1s"} {"level":"info","ts":"2026-04-17T15:05:56.856111Z","caller":"traceutil/trace.go:171","msg":"trace[1794536205] linearizableReadLoop","detail":"{readStateIndex:660; appliedIndex:659; }","duration":"1.368059501s","start":"2026-04-17T15:05:55.487979Z","end":"2026-04-17T15:05:56.856039Z","steps":["trace[1794536205] 'read index received' (duration: 1.362751914s)","trace[1794536205] 'applied index is now lower than readState.Index' (duration: 5.306277ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T15:05:56.856244Z","caller":"traceutil/trace.go:171","msg":"trace[244688338] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"1.58372446s","start":"2026-04-17T15:05:55.272479Z","end":"2026-04-17T15:05:56.856203Z","steps":["trace[244688338] 'process raft request' (duration: 1.578990697s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:05:56.874315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:05:55.272448Z","time spent":"1.601787681s","remote":"127.0.0.1:33614","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036225,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T15:05:57.439943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.951930654s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-17T15:05:57.440016Z","caller":"traceutil/trace.go:171","msg":"trace[1588825865] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:633; }","duration":"1.952048687s","start":"2026-04-17T15:05:55.487952Z","end":"2026-04-17T15:05:57.440001Z","steps":["trace[1588825865] 'agreement among raft nodes before linearized reading' (duration: 1.368251436s)","trace[1588825865] 'range keys from in-memory index tree' (duration: 583.604907ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T15:05:57.440059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:05:55.487937Z","time spent":"1.952116128s","remote":"127.0.0.1:33760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-17T15:05:57.440045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.038806ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T15:05:57.440197Z","caller":"traceutil/trace.go:171","msg":"trace[373738786] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"503.015101ms","start":"2026-04-17T15:05:56.937169Z","end":"2026-04-17T15:05:57.440184Z","steps":["trace[373738786] 'process raft request' (duration: 502.96728ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:05:57.440249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:05:56.93715Z","time spent":"503.078773ms","remote":"127.0.0.1:33808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1092,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T15:05:57.440235Z","caller":"traceutil/trace.go:171","msg":"trace[595394068] linearizableReadLoop","detail":"{readStateIndex:661; appliedIndex:660; }","duration":"583.982585ms","start":"2026-04-17T15:05:56.856212Z","end":"2026-04-17T15:05:57.440194Z","steps":["trace[595394068] 'read index received' (duration: 214.730384ms)","trace[595394068] 'applied index is now lower than readState.Index' (duration: 369.251091ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T15:05:57.440373Z","caller":"traceutil/trace.go:171","msg":"trace[331025481] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"905.182875ms","start":"2026-04-17T15:05:56.535179Z","end":"2026-04-17T15:05:57.440362Z","steps":["trace[331025481] 'process raft request' (duration: 535.74944ms)","trace[331025481] 'compare' (duration: 368.940414ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T15:05:57.440438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.424021824s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T15:05:57.440459Z","caller":"traceutil/trace.go:171","msg":"trace[1478277111] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:635; }","duration":"1.424098375s","start":"2026-04-17T15:05:56.016355Z","end":"2026-04-17T15:05:57.440453Z","steps":["trace[1478277111] 'agreement among raft nodes before linearized reading' (duration: 1.424025334s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:05:57.44049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:05:56.535155Z","time spent":"905.237417ms","remote":"127.0.0.1:33760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T15:05:57.440493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:05:56.016326Z","time spent":"1.424144087s","remote":"127.0.0.1:33494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-17T15:05:57.440583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.839221581s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-17T15:05:57.440574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"564.980251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-17T15:05:57.440576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.884506581s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-ldnmc\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-17T15:05:57.440621Z","caller":"traceutil/trace.go:171","msg":"trace[1484669029] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:635; }","duration":"1.839294473s","start":"2026-04-17T15:05:55.601318Z","end":"2026-04-17T15:05:57.440612Z","steps":["trace[1484669029] 'agreement among raft nodes before linearized reading' (duration: 1.83917356s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:05:57.440645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:05:55.601274Z","time spent":"1.839365825s","remote":"127.0.0.1:33760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-17T15:05:57.440653Z","caller":"traceutil/trace.go:171","msg":"trace[780271817] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:635; }","duration":"565.087234ms","start":"2026-04-17T15:05:56.875543Z","end":"2026-04-17T15:05:57.44063Z","steps":["trace[780271817] 'agreement among raft nodes before linearized reading' (duration: 564.852488ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:05:57.440653Z","caller":"traceutil/trace.go:171","msg":"trace[1038506643] range","detail":"{range_begin:/registry/pods/kube-system/cilium-ldnmc; range_end:; response_count:1; response_revision:635; }","duration":"1.884627234s","start":"2026-04-17T15:05:55.556008Z","end":"2026-04-17T15:05:57.440635Z","steps":["trace[1038506643] 'agreement among raft nodes before linearized reading' (duration: 1.884417159s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:05:57.440622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.864168002s","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-04-17T15:05:57.44069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:05:56.875522Z","time spent":"565.160806ms","remote":"127.0.0.1:33760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-17T15:05:57.440692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:05:55.555987Z","time spent":"1.884697315s","remote":"127.0.0.1:33698","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-ldnmc\" "} {"level":"info","ts":"2026-04-17T15:05:57.440729Z","caller":"traceutil/trace.go:171","msg":"trace[461568833] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:635; }","duration":"1.864322895s","start":"2026-04-17T15:05:55.576377Z","end":"2026-04-17T15:05:57.440699Z","steps":["trace[461568833] 'agreement among raft nodes before linearized reading' (duration: 1.863987217s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:05:57.440779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:05:55.576361Z","time spent":"1.864407667s","remote":"127.0.0.1:33760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-17T15:05:57.95192Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656052078816911177,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T15:05:58.453181Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656052078816911177,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T15:05:58.953798Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656052078816911177,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T15:05:59.271823Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.827003128s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-17T15:05:59.45221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000252902s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-17T15:05:59.452311Z","caller":"traceutil/trace.go:171","msg":"trace[1055246505] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000363454s","start":"2026-04-17T15:05:57.451902Z","end":"2026-04-17T15:05:59.452266Z","steps":["trace[1055246505] 'agreement among raft nodes before linearized reading' (duration: 2.000269822s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:05:59.452364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:05:57.451897Z","time spent":"2.000455126s","remote":"127.0.0.1:33494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-17T15:05:59.516376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.12581ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T15:05:59.516546Z","caller":"traceutil/trace.go:171","msg":"trace[583904514] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"2.071800674s","start":"2026-04-17T15:05:57.444719Z","end":"2026-04-17T15:05:59.516519Z","steps":["trace[583904514] 'process raft request' (duration: 1.827439868s)","trace[583904514] 'compare' (duration: 243.942416ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T15:05:59.516628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:05:57.444699Z","time spent":"2.071894275s","remote":"127.0.0.1:33760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T15:06:01.454203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000094738s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-17T15:06:01.454295Z","caller":"traceutil/trace.go:171","msg":"trace[1385138334] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000262681s","start":"2026-04-17T15:05:59.454014Z","end":"2026-04-17T15:06:01.454277Z","steps":["trace[1385138334] 'agreement among raft nodes before linearized reading' (duration: 2.000146019s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:06:01.454344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:05:59.453998Z","time spent":"2.000336383s","remote":"127.0.0.1:33504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-17T15:06:01.881349Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.557729854s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-17T15:06:02.443469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:05:57.445015Z","time spent":"4.998447357s","remote":"127.0.0.1:33760","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-04-17T15:06:03.489794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000124988s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-17T15:06:03.489948Z","caller":"traceutil/trace.go:171","msg":"trace[558096410] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000322913s","start":"2026-04-17T15:06:01.48961Z","end":"2026-04-17T15:06:03.489933Z","steps":["trace[558096410] 'agreement among raft nodes before linearized reading' (duration: 2.000148899s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:06:03.490006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:06:01.489595Z","time spent":"2.000402735s","remote":"127.0.0.1:33494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-17T15:06:04.389602Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.122129951s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-17T15:06:04.426803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.545174314s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T15:06:04.428125Z","caller":"traceutil/trace.go:171","msg":"trace[1891660101] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"6.982413391s","start":"2026-04-17T15:05:57.44566Z","end":"2026-04-17T15:06:04.428073Z","steps":["trace[1891660101] 'process raft request' (duration: 6.982135255s)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:06:04.428276Z","caller":"traceutil/trace.go:171","msg":"trace[2011717046] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"6.982926783s","start":"2026-04-17T15:05:57.445342Z","end":"2026-04-17T15:06:04.428268Z","steps":["trace[2011717046] 'process raft request' (duration: 6.981559851s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:06:04.428382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:05:57.445321Z","time spent":"6.982991525s","remote":"127.0.0.1:33760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T15:06:04.428454Z","caller":"traceutil/trace.go:171","msg":"trace[390001292] linearizableReadLoop","detail":"{readStateIndex:667; appliedIndex:662; }","duration":"6.97692418s","start":"2026-04-17T15:05:57.451511Z","end":"2026-04-17T15:06:04.428436Z","steps":["trace[390001292] 'read index received' (duration: 1.820630997s)","trace[390001292] 'applied index is now lower than readState.Index' (duration: 5.156291233s)"],"step_count":2} {"level":"warn","ts":"2026-04-17T15:06:04.428548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:05:57.445649Z","time spent":"6.982707608s","remote":"127.0.0.1:33760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T15:06:04.428204Z","caller":"traceutil/trace.go:171","msg":"trace[1720538544] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"6.972854804s","start":"2026-04-17T15:05:57.455303Z","end":"2026-04-17T15:06:04.428157Z","steps":["trace[1720538544] 'process raft request' (duration: 6.972545837s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:06:04.428714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:05:57.455285Z","time spent":"6.973379936s","remote":"127.0.0.1:33698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T15:06:04.500323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.048778009s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/envoy-gateway-gateway-helm-certgen:envoy-gateway-system\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T15:06:04.500428Z","caller":"traceutil/trace.go:171","msg":"trace[1796796163] range","detail":"{range_begin:/registry/clusterrolebindings/envoy-gateway-gateway-helm-certgen:envoy-gateway-system; range_end:; response_count:0; response_revision:641; }","duration":"7.048933653s","start":"2026-04-17T15:05:57.45147Z","end":"2026-04-17T15:06:04.500404Z","steps":["trace[1796796163] 'agreement among raft nodes before linearized reading' (duration: 6.977051523s)","trace[1796796163] 'range keys from in-memory index tree' (duration: 71.728066ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T15:06:04.500688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:05:57.451456Z","time spent":"7.04921691s","remote":"127.0.0.1:33812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":87,"response count":0,"response size":28,"request content":"key:\"/registry/clusterrolebindings/envoy-gateway-gateway-helm-certgen:envoy-gateway-system\" "} {"level":"info","ts":"2026-04-17T15:06:04.502305Z","caller":"traceutil/trace.go:171","msg":"trace[960940762] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"1.157017963s","start":"2026-04-17T15:06:03.345264Z","end":"2026-04-17T15:06:04.502282Z","steps":["trace[960940762] 'process raft request' (duration: 1.156727807s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:06:04.50252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:06:03.345241Z","time spent":"1.157208108s","remote":"127.0.0.1:33760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T15:06:04.502793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.976073432s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-17T15:06:04.502956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"711.753974ms","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":"info","ts":"2026-04-17T15:06:04.503017Z","caller":"traceutil/trace.go:171","msg":"trace[661318345] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:642; }","duration":"711.897577ms","start":"2026-04-17T15:06:03.791105Z","end":"2026-04-17T15:06:04.503003Z","steps":["trace[661318345] 'agreement among raft nodes before linearized reading' (duration: 711.785515ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:06:04.503063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:06:03.791078Z","time spent":"711.974159ms","remote":"127.0.0.1:33802","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":14,"response size":30,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"warn","ts":"2026-04-17T15:06:04.503063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"648.690053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T15:06:04.503107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.012533977s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T15:06:04.503194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.015544251s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.18a72d3dc9a5b909\" ","response":"range_response_count:1 size:774"} {"level":"info","ts":"2026-04-17T15:06:04.503213Z","caller":"traceutil/trace.go:171","msg":"trace[943480333] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:642; }","duration":"1.0126548s","start":"2026-04-17T15:06:03.490542Z","end":"2026-04-17T15:06:04.503196Z","steps":["trace[943480333] 'agreement among raft nodes before linearized reading' (duration: 1.012453044s)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:06:04.5033Z","caller":"traceutil/trace.go:171","msg":"trace[468582448] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.18a72d3dc9a5b909; range_end:; response_count:1; response_revision:642; }","duration":"3.015635744s","start":"2026-04-17T15:06:01.487604Z","end":"2026-04-17T15:06:04.50324Z","steps":["trace[468582448] 'agreement among raft nodes before linearized reading' (duration: 3.015424258s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:06:04.503358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:06:01.487585Z","time spent":"3.015762836s","remote":"127.0.0.1:33596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":797,"request content":"key:\"/registry/events/kube-system/kube-apiserver-instance.18a72d3dc9a5b909\" "} {"level":"warn","ts":"2026-04-17T15:06:04.503388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.601721842s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T15:06:04.5032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.743005729s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.227\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T15:06:04.502967Z","caller":"traceutil/trace.go:171","msg":"trace[2024596418] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:642; }","duration":"2.976255267s","start":"2026-04-17T15:06:01.526623Z","end":"2026-04-17T15:06:04.502878Z","steps":["trace[2024596418] 'agreement among raft nodes before linearized reading' (duration: 2.975913809s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:06:04.503585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:06:01.526602Z","time spent":"2.976973923s","remote":"127.0.0.1:33760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-17T15:06:04.503128Z","caller":"traceutil/trace.go:171","msg":"trace[935581242] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:642; }","duration":"648.826847ms","start":"2026-04-17T15:06:03.854288Z","end":"2026-04-17T15:06:04.503115Z","steps":["trace[935581242] 'agreement among raft nodes before linearized reading' (duration: 648.684593ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:06:04.507868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:06:03.854268Z","time spent":"653.574063ms","remote":"127.0.0.1:33846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":28,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"warn","ts":"2026-04-17T15:06:04.503308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:06:03.490522Z","time spent":"1.012771263s","remote":"127.0.0.1:33504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-17T15:06:04.503531Z","caller":"traceutil/trace.go:171","msg":"trace[185790318] range","detail":"{range_begin:/registry/masterleases/199.204.45.227; range_end:; response_count:0; response_revision:642; }","duration":"6.743329586s","start":"2026-04-17T15:05:57.760188Z","end":"2026-04-17T15:06:04.503517Z","steps":["trace[185790318] 'agreement among raft nodes before linearized reading' (duration: 6.742989728s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:06:04.508214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:05:57.760104Z","time spent":"6.748098704s","remote":"127.0.0.1:33530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":28,"request content":"key:\"/registry/masterleases/199.204.45.227\" "} {"level":"info","ts":"2026-04-17T15:06:04.507672Z","caller":"traceutil/trace.go:171","msg":"trace[1747154457] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:642; }","duration":"1.606022888s","start":"2026-04-17T15:06:02.901632Z","end":"2026-04-17T15:06:04.507655Z","steps":["trace[1747154457] 'agreement among raft nodes before linearized reading' (duration: 1.601699322s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:06:04.508746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:06:02.901617Z","time spent":"1.607114133s","remote":"127.0.0.1:33494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-17T15:06:05.163848Z","caller":"traceutil/trace.go:171","msg":"trace[142409641] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"207.337584ms","start":"2026-04-17T15:06:04.956482Z","end":"2026-04-17T15:06:05.163819Z","steps":["trace[142409641] 'process raft request' (duration: 206.70149ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:06:06.641849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"627.410854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T15:06:06.642083Z","caller":"traceutil/trace.go:171","msg":"trace[1443325420] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:659; }","duration":"627.652969ms","start":"2026-04-17T15:06:06.014364Z","end":"2026-04-17T15:06:06.642017Z","steps":["trace[1443325420] 'range keys from in-memory index tree' (duration: 627.338012ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:06:06.642149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:06:06.01435Z","time spent":"627.783672ms","remote":"127.0.0.1:33494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-17T15:06:06.746263Z","caller":"traceutil/trace.go:171","msg":"trace[1791196342] linearizableReadLoop","detail":"{readStateIndex:688; appliedIndex:687; }","duration":"313.412921ms","start":"2026-04-17T15:06:06.432789Z","end":"2026-04-17T15:06:06.746202Z","steps":["trace[1791196342] 'read index received' (duration: 307.096641ms)","trace[1791196342] 'applied index is now lower than readState.Index' (duration: 6.31518ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T15:06:06.746297Z","caller":"traceutil/trace.go:171","msg":"trace[1778414304] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"530.205455ms","start":"2026-04-17T15:06:06.216062Z","end":"2026-04-17T15:06:06.746268Z","steps":["trace[1778414304] 'process raft request' (duration: 524.313795ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:06:06.746417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:06:06.216033Z","time spent":"530.324817ms","remote":"127.0.0.1:33614","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036317,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T15:06:06.746514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.718598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-17T15:06:06.746543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.888164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T15:06:06.746615Z","caller":"traceutil/trace.go:171","msg":"trace[1576002143] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:660; }","duration":"103.008777ms","start":"2026-04-17T15:06:06.643588Z","end":"2026-04-17T15:06:06.746597Z","steps":["trace[1576002143] 'agreement among raft nodes before linearized reading' (duration: 102.870644ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:06:06.746546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.601825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-17T15:06:06.746696Z","caller":"traceutil/trace.go:171","msg":"trace[373668305] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:660; }","duration":"313.80104ms","start":"2026-04-17T15:06:06.432883Z","end":"2026-04-17T15:06:06.746684Z","steps":["trace[373668305] 'agreement among raft nodes before linearized reading' (duration: 313.570214ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:06:06.746679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.195898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-17T15:06:06.74669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.495292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-17T15:06:06.746728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:06:06.432852Z","time spent":"313.866ms","remote":"127.0.0.1:33760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-17T15:06:06.746742Z","caller":"traceutil/trace.go:171","msg":"trace[803189381] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:660; }","duration":"159.586004ms","start":"2026-04-17T15:06:06.587142Z","end":"2026-04-17T15:06:06.746728Z","steps":["trace[803189381] 'agreement among raft nodes before linearized reading' (duration: 159.37667ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:06:06.746745Z","caller":"traceutil/trace.go:171","msg":"trace[2091833401] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:660; }","duration":"160.31718ms","start":"2026-04-17T15:06:06.586406Z","end":"2026-04-17T15:06:06.746723Z","steps":["trace[2091833401] 'agreement among raft nodes before linearized reading' (duration: 160.153097ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:06:06.746557Z","caller":"traceutil/trace.go:171","msg":"trace[505351469] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:660; }","duration":"313.797889ms","start":"2026-04-17T15:06:06.432745Z","end":"2026-04-17T15:06:06.746543Z","steps":["trace[505351469] 'agreement among raft nodes before linearized reading' (duration: 313.618245ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:06:06.746793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:06:06.432719Z","time spent":"314.058665ms","remote":"127.0.0.1:33760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-17T15:06:10.280755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.131312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:1 size:4083"} {"level":"info","ts":"2026-04-17T15:06:10.280827Z","caller":"traceutil/trace.go:171","msg":"trace[775469603] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:727; }","duration":"223.255324ms","start":"2026-04-17T15:06:10.057554Z","end":"2026-04-17T15:06:10.280809Z","steps":["trace[775469603] 'range keys from in-memory index tree' (duration: 222.941458ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:06:13.025986Z","caller":"traceutil/trace.go:171","msg":"trace[1047536830] transaction","detail":"{read_only:false; response_revision:760; number_of_response:1; }","duration":"140.231917ms","start":"2026-04-17T15:06:12.885739Z","end":"2026-04-17T15:06:13.025971Z","steps":["trace[1047536830] 'process raft request' (duration: 118.707771ms)","trace[1047536830] 'compare' (duration: 21.351143ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T15:06:13.026069Z","caller":"traceutil/trace.go:171","msg":"trace[381386004] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"139.830799ms","start":"2026-04-17T15:06:12.886183Z","end":"2026-04-17T15:06:13.026014Z","steps":["trace[381386004] 'process raft request' (duration: 139.734877ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:06:18.131318Z","caller":"traceutil/trace.go:171","msg":"trace[430399126] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"135.036372ms","start":"2026-04-17T15:06:17.99625Z","end":"2026-04-17T15:06:18.131287Z","steps":["trace[430399126] 'process raft request' (duration: 101.084945ms)","trace[430399126] 'compare' (duration: 33.775723ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T15:06:19.439707Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656052078816911547,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-17T15:06:19.660012Z","caller":"traceutil/trace.go:171","msg":"trace[285178900] linearizableReadLoop","detail":"{readStateIndex:835; appliedIndex:834; }","duration":"721.289799ms","start":"2026-04-17T15:06:18.938692Z","end":"2026-04-17T15:06:19.659982Z","steps":["trace[285178900] 'read index received' (duration: 721.062814ms)","trace[285178900] 'applied index is now lower than readState.Index' (duration: 225.635µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T15:06:19.660242Z","caller":"traceutil/trace.go:171","msg":"trace[2091033367] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"742.555026ms","start":"2026-04-17T15:06:18.917674Z","end":"2026-04-17T15:06:19.660229Z","steps":["trace[2091033367] 'process raft request' (duration: 742.146088ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:06:19.660339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"645.518155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T15:06:19.660363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:06:18.917647Z","time spent":"742.623278ms","remote":"127.0.0.1:33760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T15:06:19.660399Z","caller":"traceutil/trace.go:171","msg":"trace[2120461001] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:803; }","duration":"645.636948ms","start":"2026-04-17T15:06:19.014748Z","end":"2026-04-17T15:06:19.660385Z","steps":["trace[2120461001] 'agreement among raft nodes before linearized reading' (duration: 645.530926ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:06:19.660413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.239259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-04-17T15:06:19.660461Z","caller":"traceutil/trace.go:171","msg":"trace[436521654] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:803; }","duration":"490.341711ms","start":"2026-04-17T15:06:19.170106Z","end":"2026-04-17T15:06:19.660448Z","steps":["trace[436521654] 'agreement among raft nodes before linearized reading' (duration: 490.171178ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:06:19.660488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"536.89277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-17T15:06:19.660495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:06:19.170062Z","time spent":"490.425973ms","remote":"127.0.0.1:33760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-17T15:06:19.660505Z","caller":"traceutil/trace.go:171","msg":"trace[692834972] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:803; }","duration":"536.960361ms","start":"2026-04-17T15:06:19.123539Z","end":"2026-04-17T15:06:19.6605Z","steps":["trace[692834972] 'agreement among raft nodes before linearized reading' (duration: 536.90249ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:06:19.660525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:06:19.123509Z","time spent":"537.011733ms","remote":"127.0.0.1:33760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-17T15:06:19.660668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"721.971244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-17T15:06:19.660697Z","caller":"traceutil/trace.go:171","msg":"trace[1742092447] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:803; }","duration":"722.032944ms","start":"2026-04-17T15:06:18.938655Z","end":"2026-04-17T15:06:19.660688Z","steps":["trace[1742092447] 'agreement among raft nodes before linearized reading' (duration: 721.937782ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:06:19.660727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:06:18.938639Z","time spent":"722.081516ms","remote":"127.0.0.1:33760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-17T15:06:19.660736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"569.344903ms","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-04-17T15:06:19.660427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:06:19.014721Z","time spent":"645.70113ms","remote":"127.0.0.1:33494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-17T15:06:19.660821Z","caller":"traceutil/trace.go:171","msg":"trace[1314269671] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:803; }","duration":"569.468275ms","start":"2026-04-17T15:06:19.091339Z","end":"2026-04-17T15:06:19.660807Z","steps":["trace[1314269671] 'agreement among raft nodes before linearized reading' (duration: 569.25641ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:06:19.660856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:06:19.09132Z","time spent":"569.527916ms","remote":"127.0.0.1:33760","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-04-17T15:06:19.660988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.012647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-17T15:06:19.661034Z","caller":"traceutil/trace.go:171","msg":"trace[1848150220] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:803; }","duration":"118.096889ms","start":"2026-04-17T15:06:19.542922Z","end":"2026-04-17T15:06:19.661019Z","steps":["trace[1848150220] 'agreement among raft nodes before linearized reading' (duration: 118.026397ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:06:23.328891Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656052078816911586,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T15:06:23.829581Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656052078816911586,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T15:06:24.330187Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656052078816911586,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T15:06:24.833009Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656052078816911586,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T15:06:25.015689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.00004918s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-17T15:06:25.015857Z","caller":"traceutil/trace.go:171","msg":"trace[1608982567] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000278614s","start":"2026-04-17T15:06:23.01556Z","end":"2026-04-17T15:06:25.015838Z","steps":["trace[1608982567] 'agreement among raft nodes before linearized reading' (duration: 2.000084321s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:06:25.015932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:06:23.01554Z","time spent":"2.000379457s","remote":"127.0.0.1:33494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-17T15:06:25.294693Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.497165767s","expected-duration":"1s"} {"level":"info","ts":"2026-04-17T15:06:25.316591Z","caller":"traceutil/trace.go:171","msg":"trace[55639517] transaction","detail":"{read_only:false; response_revision:821; number_of_response:1; }","duration":"218.399268ms","start":"2026-04-17T15:06:25.09817Z","end":"2026-04-17T15:06:25.316569Z","steps":["trace[55639517] 'process raft request' (duration: 218.275385ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:06:25.316641Z","caller":"traceutil/trace.go:171","msg":"trace[570064804] linearizableReadLoop","detail":"{readStateIndex:854; appliedIndex:853; }","duration":"2.488175399s","start":"2026-04-17T15:06:22.828421Z","end":"2026-04-17T15:06:25.316596Z","steps":["trace[570064804] 'read index received' (duration: 2.466659866s)","trace[570064804] 'applied index is now lower than readState.Index' (duration: 21.510993ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T15:06:25.317044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.171656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T15:06:25.317085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.613059804s","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-04-17T15:06:25.317153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.078076491s","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-04-17T15:06:25.317168Z","caller":"traceutil/trace.go:171","msg":"trace[2034772619] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:821; }","duration":"1.613172197s","start":"2026-04-17T15:06:23.703981Z","end":"2026-04-17T15:06:25.317153Z","steps":["trace[2034772619] 'agreement among raft nodes before linearized reading' (duration: 1.612977633s)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:06:25.317123Z","caller":"traceutil/trace.go:171","msg":"trace[769543123] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:821; }","duration":"300.300039ms","start":"2026-04-17T15:06:25.016804Z","end":"2026-04-17T15:06:25.317104Z","steps":["trace[769543123] 'agreement among raft nodes before linearized reading' (duration: 300.168497ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:06:25.317206Z","caller":"traceutil/trace.go:171","msg":"trace[881731219] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:821; }","duration":"1.078161763s","start":"2026-04-17T15:06:24.239029Z","end":"2026-04-17T15:06:25.317191Z","steps":["trace[881731219] 'agreement among raft nodes before linearized reading' (duration: 1.078090871s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:06:25.31724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:06:23.703972Z","time spent":"1.613227438s","remote":"127.0.0.1:33760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-17T15:06:25.317266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:06:24.239008Z","time spent":"1.078239055s","remote":"127.0.0.1:33746","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-04-17T15:06:25.317473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.618622876s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-04-17T15:06:25.317488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.624944282s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-17T15:06:25.317511Z","caller":"traceutil/trace.go:171","msg":"trace[1222764519] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:821; }","duration":"1.618686308s","start":"2026-04-17T15:06:23.698816Z","end":"2026-04-17T15:06:25.317502Z","steps":["trace[1222764519] 'agreement among raft nodes before linearized reading' (duration: 1.618565355s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:06:25.317542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:06:23.698809Z","time spent":"1.618727028s","remote":"127.0.0.1:33760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-17T15:06:25.317044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.488593167s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-pglh8\" ","response":"range_response_count:1 size:3965"} {"level":"warn","ts":"2026-04-17T15:06:25.31759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.614703578s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-17T15:06:25.317683Z","caller":"traceutil/trace.go:171","msg":"trace[46897574] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:821; }","duration":"1.614822781s","start":"2026-04-17T15:06:23.702837Z","end":"2026-04-17T15:06:25.317659Z","steps":["trace[46897574] 'agreement among raft nodes before linearized reading' (duration: 1.614556606s)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:06:25.317748Z","caller":"traceutil/trace.go:171","msg":"trace[1866245505] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-pglh8; range_end:; response_count:1; response_revision:821; }","duration":"2.489286472s","start":"2026-04-17T15:06:22.828382Z","end":"2026-04-17T15:06:25.317668Z","steps":["trace[1866245505] 'agreement among raft nodes before linearized reading' (duration: 2.488422394s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:06:25.317867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:06:23.702821Z","time spent":"1.615030835s","remote":"127.0.0.1:33760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-17T15:06:25.317891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.641853448s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-17T15:06:25.317925Z","caller":"traceutil/trace.go:171","msg":"trace[992675007] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:821; }","duration":"1.64192839s","start":"2026-04-17T15:06:23.675989Z","end":"2026-04-17T15:06:25.317917Z","steps":["trace[992675007] 'agreement among raft nodes before linearized reading' (duration: 1.64096711s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:06:25.317936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:06:22.828362Z","time spent":"2.489504626s","remote":"127.0.0.1:33698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3988,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-pglh8\" "} {"level":"info","ts":"2026-04-17T15:06:25.317535Z","caller":"traceutil/trace.go:171","msg":"trace[45652359] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:821; }","duration":"1.625022563s","start":"2026-04-17T15:06:23.692499Z","end":"2026-04-17T15:06:25.317522Z","steps":["trace[45652359] 'agreement among raft nodes before linearized reading' (duration: 1.62487964s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:06:25.318059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:06:23.692479Z","time spent":"1.625572474s","remote":"127.0.0.1:33760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-17T15:06:25.317958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:06:23.675886Z","time spent":"1.642060792s","remote":"127.0.0.1:33760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-17T15:06:25.317253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:06:25.016786Z","time spent":"300.454793ms","remote":"127.0.0.1:33504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-17T15:06:29.376111Z","caller":"traceutil/trace.go:171","msg":"trace[1096824560] transaction","detail":"{read_only:false; response_revision:848; number_of_response:1; }","duration":"114.294498ms","start":"2026-04-17T15:06:29.261797Z","end":"2026-04-17T15:06:29.376092Z","steps":["trace[1096824560] 'process raft request' (duration: 114.043984ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:06:29.758708Z","caller":"traceutil/trace.go:171","msg":"trace[1759775916] linearizableReadLoop","detail":"{readStateIndex:886; appliedIndex:885; }","duration":"332.21584ms","start":"2026-04-17T15:06:29.42647Z","end":"2026-04-17T15:06:29.758686Z","steps":["trace[1759775916] 'read index received' (duration: 266.69902ms)","trace[1759775916] 'applied index is now lower than readState.Index' (duration: 65.51585ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T15:06:29.758751Z","caller":"traceutil/trace.go:171","msg":"trace[2141563154] transaction","detail":"{read_only:false; response_revision:851; number_of_response:1; }","duration":"360.28883ms","start":"2026-04-17T15:06:29.398432Z","end":"2026-04-17T15:06:29.758721Z","steps":["trace[2141563154] 'process raft request' (duration: 294.899593ms)","trace[2141563154] 'compare' (duration: 65.237954ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T15:06:29.758887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:06:29.398422Z","time spent":"360.390032ms","remote":"127.0.0.1:33574","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":95479,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T15:06:29.758954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.463025ms","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-04-17T15:06:29.758998Z","caller":"traceutil/trace.go:171","msg":"trace[995252828] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:851; }","duration":"332.558846ms","start":"2026-04-17T15:06:29.426427Z","end":"2026-04-17T15:06:29.758986Z","steps":["trace[995252828] 'agreement among raft nodes before linearized reading' (duration: 332.390843ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:06:29.759038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:06:29.426406Z","time spent":"332.624588ms","remote":"127.0.0.1:33760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-17T15:06:29.759193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.807793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-17T15:06:29.759291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.746506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-17T15:06:29.759332Z","caller":"traceutil/trace.go:171","msg":"trace[728748655] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:851; }","duration":"311.805236ms","start":"2026-04-17T15:06:29.447516Z","end":"2026-04-17T15:06:29.759321Z","steps":["trace[728748655] 'agreement among raft nodes before linearized reading' (duration: 311.713125ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:06:29.759382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:06:29.447507Z","time spent":"311.862738ms","remote":"127.0.0.1:33760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-17T15:06:29.759325Z","caller":"traceutil/trace.go:171","msg":"trace[699345985] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:851; }","duration":"316.947826ms","start":"2026-04-17T15:06:29.442314Z","end":"2026-04-17T15:06:29.759262Z","steps":["trace[699345985] 'agreement among raft nodes before linearized reading' (duration: 316.59081ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:06:29.759571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:06:29.442284Z","time spent":"317.269262ms","remote":"127.0.0.1:33760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-17T15:06:29.75958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.089422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-17T15:06:29.759716Z","caller":"traceutil/trace.go:171","msg":"trace[935015373] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:851; }","duration":"312.219375ms","start":"2026-04-17T15:06:29.447449Z","end":"2026-04-17T15:06:29.759668Z","steps":["trace[935015373] 'agreement among raft nodes before linearized reading' (duration: 311.916279ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:06:29.759554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.843477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-17T15:06:29.759791Z","caller":"traceutil/trace.go:171","msg":"trace[1587813603] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:851; }","duration":"313.115111ms","start":"2026-04-17T15:06:29.446666Z","end":"2026-04-17T15:06:29.759781Z","steps":["trace[1587813603] 'agreement among raft nodes before linearized reading' (duration: 312.770085ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:06:29.759825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:06:29.446641Z","time spent":"313.175573ms","remote":"127.0.0.1:33760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-17T15:06:29.759814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:06:29.447436Z","time spent":"312.323937ms","remote":"127.0.0.1:33760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-17T15:06:30.092598Z","caller":"traceutil/trace.go:171","msg":"trace[540790788] transaction","detail":"{read_only:false; response_revision:866; number_of_response:1; }","duration":"141.874479ms","start":"2026-04-17T15:06:29.950697Z","end":"2026-04-17T15:06:30.092572Z","steps":["trace[540790788] 'process raft request' (duration: 132.54413ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:06:30.092786Z","caller":"traceutil/trace.go:171","msg":"trace[2064282918] linearizableReadLoop","detail":"{readStateIndex:902; appliedIndex:899; }","duration":"138.214738ms","start":"2026-04-17T15:06:29.954552Z","end":"2026-04-17T15:06:30.092767Z","steps":["trace[2064282918] 'read index received' (duration: 44.807921ms)","trace[2064282918] 'applied index is now lower than readState.Index' (duration: 93.405097ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T15:06:30.092787Z","caller":"traceutil/trace.go:171","msg":"trace[987150545] transaction","detail":"{read_only:false; response_revision:867; number_of_response:1; }","duration":"140.762777ms","start":"2026-04-17T15:06:29.952008Z","end":"2026-04-17T15:06:30.092771Z","steps":["trace[987150545] 'process raft request' (duration: 140.507873ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:06:30.093125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.545574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/envoy-gateway-system/envoy-gateway\" ","response":"range_response_count:1 size:5266"} {"level":"info","ts":"2026-04-17T15:06:30.093212Z","caller":"traceutil/trace.go:171","msg":"trace[959392374] range","detail":"{range_begin:/registry/deployments/envoy-gateway-system/envoy-gateway; range_end:; response_count:1; response_revision:867; }","duration":"138.669407ms","start":"2026-04-17T15:06:29.954524Z","end":"2026-04-17T15:06:30.093194Z","steps":["trace[959392374] 'agreement among raft nodes before linearized reading' (duration: 138.417812ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:06:30.338243Z","caller":"traceutil/trace.go:171","msg":"trace[1920465558] transaction","detail":"{read_only:false; response_revision:872; number_of_response:1; }","duration":"165.025516ms","start":"2026-04-17T15:06:30.173195Z","end":"2026-04-17T15:06:30.33822Z","steps":["trace[1920465558] 'process raft request' (duration: 74.807711ms)","trace[1920465558] 'compare' (duration: 90.082163ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T15:06:30.358669Z","caller":"traceutil/trace.go:171","msg":"trace[1765711871] transaction","detail":"{read_only:false; response_revision:873; number_of_response:1; }","duration":"110.698267ms","start":"2026-04-17T15:06:30.247941Z","end":"2026-04-17T15:06:30.358639Z","steps":["trace[1765711871] 'process raft request' (duration: 109.045866ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:06:30.712723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.076828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:5 size:1050256"} {"level":"info","ts":"2026-04-17T15:06:30.712816Z","caller":"traceutil/trace.go:171","msg":"trace[1207568038] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:5; response_revision:873; }","duration":"234.209511ms","start":"2026-04-17T15:06:30.478586Z","end":"2026-04-17T15:06:30.712795Z","steps":["trace[1207568038] 'range keys from in-memory index tree' (duration: 233.514756ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:06:40.664873Z","caller":"traceutil/trace.go:171","msg":"trace[79443936] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"195.284195ms","start":"2026-04-17T15:06:40.469567Z","end":"2026-04-17T15:06:40.664851Z","steps":["trace[79443936] 'process raft request' (duration: 195.151272ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:08:18.748103Z","caller":"traceutil/trace.go:171","msg":"trace[1871287304] linearizableReadLoop","detail":"{readStateIndex:1418; appliedIndex:1417; }","duration":"168.419188ms","start":"2026-04-17T15:08:18.579659Z","end":"2026-04-17T15:08:18.748078Z","steps":["trace[1871287304] 'read index received' (duration: 168.216435ms)","trace[1871287304] 'applied index is now lower than readState.Index' (duration: 201.973µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T15:08:18.748247Z","caller":"traceutil/trace.go:171","msg":"trace[448302447] transaction","detail":"{read_only:false; response_revision:1357; number_of_response:1; }","duration":"283.579189ms","start":"2026-04-17T15:08:18.464654Z","end":"2026-04-17T15:08:18.748233Z","steps":["trace[448302447] 'process raft request' (duration: 283.273365ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:08:18.748489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.947768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-17T15:08:18.748551Z","caller":"traceutil/trace.go:171","msg":"trace[731806455] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1357; }","duration":"156.041369ms","start":"2026-04-17T15:08:18.592497Z","end":"2026-04-17T15:08:18.748538Z","steps":["trace[731806455] 'agreement among raft nodes before linearized reading' (duration: 155.857097ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:08:18.748672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.725441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-17T15:08:18.748787Z","caller":"traceutil/trace.go:171","msg":"trace[1284839568] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1357; }","duration":"113.892763ms","start":"2026-04-17T15:08:18.634865Z","end":"2026-04-17T15:08:18.748757Z","steps":["trace[1284839568] 'agreement among raft nodes before linearized reading' (duration: 113.6484ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:08:18.749004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.178538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-17T15:08:18.74914Z","caller":"traceutil/trace.go:171","msg":"trace[954265664] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1357; }","duration":"103.327179ms","start":"2026-04-17T15:08:18.645766Z","end":"2026-04-17T15:08:18.749093Z","steps":["trace[954265664] 'agreement among raft nodes before linearized reading' (duration: 102.59149ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:08:18.749241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.571533ms","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-04-17T15:08:18.749317Z","caller":"traceutil/trace.go:171","msg":"trace[171589650] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1357; }","duration":"169.695554ms","start":"2026-04-17T15:08:18.579604Z","end":"2026-04-17T15:08:18.7493Z","steps":["trace[171589650] 'agreement among raft nodes before linearized reading' (duration: 168.857383ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:08:19.040378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.992688ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T15:08:19.04074Z","caller":"traceutil/trace.go:171","msg":"trace[133981518] transaction","detail":"{read_only:false; response_revision:1358; number_of_response:1; }","duration":"287.686225ms","start":"2026-04-17T15:08:18.752989Z","end":"2026-04-17T15:08:19.040676Z","steps":["trace[133981518] 'process raft request' (duration: 164.287102ms)","trace[133981518] 'compare' (duration: 122.827815ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T15:08:19.040765Z","caller":"traceutil/trace.go:171","msg":"trace[92734607] transaction","detail":"{read_only:false; response_revision:1359; number_of_response:1; }","duration":"287.010785ms","start":"2026-04-17T15:08:18.753727Z","end":"2026-04-17T15:08:19.040738Z","steps":["trace[92734607] 'process raft request' (duration: 286.827503ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:08:19.040985Z","caller":"traceutil/trace.go:171","msg":"trace[1882176952] transaction","detail":"{read_only:false; response_revision:1360; number_of_response:1; }","duration":"287.148338ms","start":"2026-04-17T15:08:18.753819Z","end":"2026-04-17T15:08:19.040968Z","steps":["trace[1882176952] 'process raft request' (duration: 286.855774ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:08:19.041035Z","caller":"traceutil/trace.go:171","msg":"trace[2097153117] linearizableReadLoop","detail":"{readStateIndex:1422; appliedIndex:1418; }","duration":"220.693471ms","start":"2026-04-17T15:08:18.820327Z","end":"2026-04-17T15:08:19.04102Z","steps":["trace[2097153117] 'read index received' (duration: 96.968353ms)","trace[2097153117] 'applied index is now lower than readState.Index' (duration: 123.724058ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T15:08:19.041085Z","caller":"traceutil/trace.go:171","msg":"trace[1683518446] transaction","detail":"{read_only:false; response_revision:1361; number_of_response:1; }","duration":"286.850304ms","start":"2026-04-17T15:08:18.754177Z","end":"2026-04-17T15:08:19.041027Z","steps":["trace[1683518446] 'process raft request' (duration: 286.722552ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:08:19.041163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.819633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T15:08:19.041205Z","caller":"traceutil/trace.go:171","msg":"trace[965093982] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:1361; }","duration":"220.936534ms","start":"2026-04-17T15:08:18.820257Z","end":"2026-04-17T15:08:19.041194Z","steps":["trace[965093982] 'agreement among raft nodes before linearized reading' (duration: 220.818753ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:08:27.298245Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656052078816912937,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-17T15:08:27.423485Z","caller":"traceutil/trace.go:171","msg":"trace[1304665744] linearizableReadLoop","detail":"{readStateIndex:1450; appliedIndex:1449; }","duration":"626.268068ms","start":"2026-04-17T15:08:26.797186Z","end":"2026-04-17T15:08:27.423454Z","steps":["trace[1304665744] 'read index received' (duration: 626.049035ms)","trace[1304665744] 'applied index is now lower than readState.Index' (duration: 216.783µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T15:08:27.423594Z","caller":"traceutil/trace.go:171","msg":"trace[826993643] transaction","detail":"{read_only:false; response_revision:1388; number_of_response:1; }","duration":"640.796975ms","start":"2026-04-17T15:08:26.782759Z","end":"2026-04-17T15:08:27.423556Z","steps":["trace[826993643] 'process raft request' (duration: 640.47798ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:08:27.423755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.323319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T15:08:27.423811Z","caller":"traceutil/trace.go:171","msg":"trace[167402718] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1388; }","duration":"409.4366ms","start":"2026-04-17T15:08:27.014357Z","end":"2026-04-17T15:08:27.423794Z","steps":["trace[167402718] 'agreement among raft nodes before linearized reading' (duration: 409.299868ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:08:27.423866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:08:27.014339Z","time spent":"409.517482ms","remote":"127.0.0.1:33494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-17T15:08:27.42389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:08:26.782737Z","time spent":"641.010967ms","remote":"127.0.0.1:33760","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-04-17T15:08:27.424043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"626.548112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T15:08:27.424138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.777336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-17T15:08:27.424229Z","caller":"traceutil/trace.go:171","msg":"trace[629728114] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1388; }","duration":"346.882477ms","start":"2026-04-17T15:08:27.077322Z","end":"2026-04-17T15:08:27.424205Z","steps":["trace[629728114] 'agreement among raft nodes before linearized reading' (duration: 346.624414ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:08:27.424278Z","caller":"traceutil/trace.go:171","msg":"trace[1622402027] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1388; }","duration":"627.025978ms","start":"2026-04-17T15:08:26.79711Z","end":"2026-04-17T15:08:27.424136Z","steps":["trace[1622402027] 'agreement among raft nodes before linearized reading' (duration: 626.508831ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:08:27.424286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:08:27.077254Z","time spent":"347.020629ms","remote":"127.0.0.1:33760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-17T15:08:27.424374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.17069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-17T15:08:27.424422Z","caller":"traceutil/trace.go:171","msg":"trace[994297826] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1388; }","duration":"344.256172ms","start":"2026-04-17T15:08:27.080154Z","end":"2026-04-17T15:08:27.42441Z","steps":["trace[994297826] 'agreement among raft nodes before linearized reading' (duration: 344.068219ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:08:27.424424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:08:26.797073Z","time spent":"627.334773ms","remote":"127.0.0.1:33654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumes/test-pv\" "} {"level":"warn","ts":"2026-04-17T15:08:27.424414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.13674ms","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-04-17T15:08:27.424453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:08:27.080128Z","time spent":"344.318562ms","remote":"127.0.0.1:33760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-17T15:08:27.424496Z","caller":"traceutil/trace.go:171","msg":"trace[11559783] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1388; }","duration":"347.286303ms","start":"2026-04-17T15:08:27.077191Z","end":"2026-04-17T15:08:27.424477Z","steps":["trace[11559783] 'agreement among raft nodes before linearized reading' (duration: 347.014009ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:08:27.424558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:08:27.077149Z","time spent":"347.399315ms","remote":"127.0.0.1:33760","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-04-17T15:08:27.424596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.613117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-17T15:08:27.424631Z","caller":"traceutil/trace.go:171","msg":"trace[1327673156] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1388; }","duration":"100.680678ms","start":"2026-04-17T15:08:27.323939Z","end":"2026-04-17T15:08:27.424619Z","steps":["trace[1327673156] 'agreement among raft nodes before linearized reading' (duration: 100.622297ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:08:27.42512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.170927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-04-17T15:08:27.425303Z","caller":"traceutil/trace.go:171","msg":"trace[1361968799] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1388; }","duration":"343.336358ms","start":"2026-04-17T15:08:27.081887Z","end":"2026-04-17T15:08:27.425223Z","steps":["trace[1361968799] 'agreement among raft nodes before linearized reading' (duration: 342.036341ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:08:27.425395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:08:27.081873Z","time spent":"343.510901ms","remote":"127.0.0.1:33760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-17T15:08:27.610302Z","caller":"traceutil/trace.go:171","msg":"trace[557947413] transaction","detail":"{read_only:false; response_revision:1390; number_of_response:1; }","duration":"180.782007ms","start":"2026-04-17T15:08:27.429488Z","end":"2026-04-17T15:08:27.61027Z","steps":["trace[557947413] 'process raft request' (duration: 180.480103ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:08:27.610645Z","caller":"traceutil/trace.go:171","msg":"trace[598872288] transaction","detail":"{read_only:false; response_revision:1391; number_of_response:1; }","duration":"180.771148ms","start":"2026-04-17T15:08:27.429826Z","end":"2026-04-17T15:08:27.610598Z","steps":["trace[598872288] 'process raft request' (duration: 180.369421ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:08:27.61089Z","caller":"traceutil/trace.go:171","msg":"trace[1905298936] linearizableReadLoop","detail":"{readStateIndex:1452; appliedIndex:1450; }","duration":"181.103212ms","start":"2026-04-17T15:08:27.429761Z","end":"2026-04-17T15:08:27.610864Z","steps":["trace[1905298936] 'read index received' (duration: 12.828643ms)","trace[1905298936] 'applied index is now lower than readState.Index' (duration: 168.272239ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T15:08:27.611022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.235594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T15:08:27.611025Z","caller":"traceutil/trace.go:171","msg":"trace[1971526909] transaction","detail":"{read_only:false; response_revision:1392; number_of_response:1; }","duration":"179.711813ms","start":"2026-04-17T15:08:27.431297Z","end":"2026-04-17T15:08:27.611009Z","steps":["trace[1971526909] 'process raft request' (duration: 179.134105ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:08:27.611061Z","caller":"traceutil/trace.go:171","msg":"trace[698386890] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1392; }","duration":"181.316445ms","start":"2026-04-17T15:08:27.429734Z","end":"2026-04-17T15:08:27.61105Z","steps":["trace[698386890] 'agreement among raft nodes before linearized reading' (duration: 181.228643ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:08:39.670697Z","caller":"traceutil/trace.go:171","msg":"trace[333268819] transaction","detail":"{read_only:false; response_revision:1433; number_of_response:1; }","duration":"187.93645ms","start":"2026-04-17T15:08:39.482737Z","end":"2026-04-17T15:08:39.670674Z","steps":["trace[333268819] 'process raft request' (duration: 187.711797ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:08:39.670657Z","caller":"traceutil/trace.go:171","msg":"trace[249696248] linearizableReadLoop","detail":"{readStateIndex:1498; appliedIndex:1497; }","duration":"165.337379ms","start":"2026-04-17T15:08:39.505293Z","end":"2026-04-17T15:08:39.670631Z","steps":["trace[249696248] 'read index received' (duration: 165.129666ms)","trace[249696248] 'applied index is now lower than readState.Index' (duration: 206.403µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T15:08:39.671111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.796015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-17T15:08:39.671146Z","caller":"traceutil/trace.go:171","msg":"trace[620533534] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1433; }","duration":"165.897777ms","start":"2026-04-17T15:08:39.505236Z","end":"2026-04-17T15:08:39.671134Z","steps":["trace[620533534] 'agreement among raft nodes before linearized reading' (duration: 165.724784ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:08:39.671161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.233955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:persistent-volume-binder\" ","response":"range_response_count:1 size:1076"} {"level":"info","ts":"2026-04-17T15:08:39.671233Z","caller":"traceutil/trace.go:171","msg":"trace[2063813485] range","detail":"{range_begin:/registry/clusterroles/system:controller:persistent-volume-binder; range_end:; response_count:1; response_revision:1433; }","duration":"158.348917ms","start":"2026-04-17T15:08:39.512856Z","end":"2026-04-17T15:08:39.671205Z","steps":["trace[2063813485] 'agreement among raft nodes before linearized reading' (duration: 158.175954ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:08:39.6718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.550769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:pod-garbage-collector\" ","response":"range_response_count:1 size:663"} {"level":"info","ts":"2026-04-17T15:08:39.671871Z","caller":"traceutil/trace.go:171","msg":"trace[717079088] range","detail":"{range_begin:/registry/clusterroles/system:controller:pod-garbage-collector; range_end:; response_count:1; response_revision:1433; }","duration":"143.671741ms","start":"2026-04-17T15:08:39.52818Z","end":"2026-04-17T15:08:39.671852Z","steps":["trace[717079088] 'agreement among raft nodes before linearized reading' (duration: 143.56881ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:08:40.710009Z","caller":"traceutil/trace.go:171","msg":"trace[1343663704] linearizableReadLoop","detail":"{readStateIndex:1503; appliedIndex:1502; }","duration":"281.754184ms","start":"2026-04-17T15:08:40.428227Z","end":"2026-04-17T15:08:40.709981Z","steps":["trace[1343663704] 'read index received' (duration: 281.608862ms)","trace[1343663704] 'applied index is now lower than readState.Index' (duration: 144.082µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T15:08:40.710121Z","caller":"traceutil/trace.go:171","msg":"trace[75085152] transaction","detail":"{read_only:false; response_revision:1438; number_of_response:1; }","duration":"336.171476ms","start":"2026-04-17T15:08:40.373914Z","end":"2026-04-17T15:08:40.710086Z","steps":["trace[75085152] 'process raft request' (duration: 335.912323ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:08:40.710291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:08:40.373892Z","time spent":"336.307478ms","remote":"127.0.0.1:33760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T15:08:40.710496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.970627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:resourcequota-controller\" ","response":"range_response_count:1 size:698"} {"level":"info","ts":"2026-04-17T15:08:40.71068Z","caller":"traceutil/trace.go:171","msg":"trace[669902351] range","detail":"{range_begin:/registry/clusterroles/system:controller:resourcequota-controller; range_end:; response_count:1; response_revision:1438; }","duration":"282.389483ms","start":"2026-04-17T15:08:40.428176Z","end":"2026-04-17T15:08:40.710566Z","steps":["trace[669902351] 'agreement among raft nodes before linearized reading' (duration: 281.972937ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:08:40.718707Z","caller":"traceutil/trace.go:171","msg":"trace[126588947] transaction","detail":"{read_only:false; response_revision:1439; number_of_response:1; }","duration":"152.256504ms","start":"2026-04-17T15:08:40.566434Z","end":"2026-04-17T15:08:40.71869Z","steps":["trace[126588947] 'process raft request' (duration: 152.147212ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:08:40.718842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.064458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/cluster-admin\" ","response":"range_response_count:1 size:679"} {"level":"info","ts":"2026-04-17T15:08:40.718929Z","caller":"traceutil/trace.go:171","msg":"trace[1826048140] range","detail":"{range_begin:/registry/clusterrolebindings/cluster-admin; range_end:; response_count:1; response_revision:1439; }","duration":"282.21742ms","start":"2026-04-17T15:08:40.436691Z","end":"2026-04-17T15:08:40.718908Z","steps":["trace[1826048140] 'agreement among raft nodes before linearized reading' (duration: 282.049538ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:08:40.96629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.197025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/envoy-gateway-gateway-helm-certgen:envoy-gateway-system\" ","response":"range_response_count:1 size:1102"} {"level":"warn","ts":"2026-04-17T15:08:40.96629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.818481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/envoy-gateway-gateway-helm-envoy-gateway-rolebinding\" ","response":"range_response_count:1 size:842"} {"level":"info","ts":"2026-04-17T15:08:40.966351Z","caller":"traceutil/trace.go:171","msg":"trace[2035363710] range","detail":"{range_begin:/registry/clusterrolebindings/envoy-gateway-gateway-helm-certgen:envoy-gateway-system; range_end:; response_count:1; response_revision:1439; }","duration":"171.289886ms","start":"2026-04-17T15:08:40.795047Z","end":"2026-04-17T15:08:40.966337Z","steps":["trace[2035363710] 'range keys from in-memory index tree' (duration: 171.029733ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:08:40.966363Z","caller":"traceutil/trace.go:171","msg":"trace[2032175745] range","detail":"{range_begin:/registry/clusterrolebindings/envoy-gateway-gateway-helm-envoy-gateway-rolebinding; range_end:; response_count:1; response_revision:1439; }","duration":"161.944642ms","start":"2026-04-17T15:08:40.804399Z","end":"2026-04-17T15:08:40.966344Z","steps":["trace[2032175745] 'range keys from in-memory index tree' (duration: 161.618058ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:08:41.993723Z","caller":"traceutil/trace.go:171","msg":"trace[1483372943] linearizableReadLoop","detail":"{readStateIndex:1509; appliedIndex:1508; }","duration":"228.033016ms","start":"2026-04-17T15:08:41.765663Z","end":"2026-04-17T15:08:41.993696Z","steps":["trace[1483372943] 'read index received' (duration: 227.817043ms)","trace[1483372943] 'applied index is now lower than readState.Index' (duration: 214.883µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T15:08:41.99405Z","caller":"traceutil/trace.go:171","msg":"trace[2134935464] transaction","detail":"{read_only:false; response_revision:1444; number_of_response:1; }","duration":"228.844398ms","start":"2026-04-17T15:08:41.765158Z","end":"2026-04-17T15:08:41.994002Z","steps":["trace[2134935464] 'process raft request' (duration: 228.394552ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:08:41.994076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.608275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:route-controller\" ","response":"range_response_count:1 size:675"} {"level":"info","ts":"2026-04-17T15:08:41.994143Z","caller":"traceutil/trace.go:171","msg":"trace[1742133800] range","detail":"{range_begin:/registry/clusterroles/system:controller:route-controller; range_end:; response_count:1; response_revision:1444; }","duration":"158.758828ms","start":"2026-04-17T15:08:41.835367Z","end":"2026-04-17T15:08:41.994126Z","steps":["trace[1742133800] 'agreement among raft nodes before linearized reading' (duration: 158.587426ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:08:41.994219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.548333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/local-path-provisioner\" ","response":"range_response_count:1 size:1081"} {"level":"info","ts":"2026-04-17T15:08:41.994335Z","caller":"traceutil/trace.go:171","msg":"trace[1631850016] range","detail":"{range_begin:/registry/clusterrolebindings/local-path-provisioner; range_end:; response_count:1; response_revision:1444; }","duration":"228.719235ms","start":"2026-04-17T15:08:41.765597Z","end":"2026-04-17T15:08:41.994316Z","steps":["trace[1631850016] 'agreement among raft nodes before linearized reading' (duration: 228.479922ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:08:43.195725Z","caller":"traceutil/trace.go:171","msg":"trace[915772053] linearizableReadLoop","detail":"{readStateIndex:1511; appliedIndex:1510; }","duration":"180.317609ms","start":"2026-04-17T15:08:43.015366Z","end":"2026-04-17T15:08:43.195683Z","steps":["trace[915772053] 'read index received' (duration: 81.247597ms)","trace[915772053] 'applied index is now lower than readState.Index' (duration: 99.068002ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T15:08:43.195864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.469031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T15:08:43.195908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.422844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:ttl-controller\" ","response":"range_response_count:1 size:663"} {"level":"info","ts":"2026-04-17T15:08:43.19593Z","caller":"traceutil/trace.go:171","msg":"trace[1674295779] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1445; }","duration":"180.577252ms","start":"2026-04-17T15:08:43.015334Z","end":"2026-04-17T15:08:43.195911Z","steps":["trace[1674295779] 'agreement among raft nodes before linearized reading' (duration: 180.44748ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:08:43.195942Z","caller":"traceutil/trace.go:171","msg":"trace[272999207] range","detail":"{range_begin:/registry/clusterroles/system:controller:ttl-controller; range_end:; response_count:1; response_revision:1445; }","duration":"160.475926ms","start":"2026-04-17T15:08:43.035453Z","end":"2026-04-17T15:08:43.195929Z","steps":["trace[272999207] 'agreement among raft nodes before linearized reading' (duration: 160.393835ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:08:43.195941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.179065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:deployment-controller\" ","response":"range_response_count:1 size:759"} {"level":"info","ts":"2026-04-17T15:08:43.196017Z","caller":"traceutil/trace.go:171","msg":"trace[2070665258] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:deployment-controller; range_end:; response_count:1; response_revision:1445; }","duration":"170.282666ms","start":"2026-04-17T15:08:43.025714Z","end":"2026-04-17T15:08:43.195996Z","steps":["trace[2070665258] 'agreement among raft nodes before linearized reading' (duration: 170.135324ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:08:51.95929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.926212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/envoy-rate-limit\" ","response":"range_response_count:1 size:4456"} {"level":"info","ts":"2026-04-17T15:08:51.959394Z","caller":"traceutil/trace.go:171","msg":"trace[608624476] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/envoy-rate-limit; range_end:; response_count:1; response_revision:1475; }","duration":"144.071664ms","start":"2026-04-17T15:08:51.815301Z","end":"2026-04-17T15:08:51.959373Z","steps":["trace[608624476] 'range keys from in-memory index tree' (duration: 143.76186ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:08:51.959403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.887547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/kube-node-lease/\" range_end:\"/registry/services/endpoints/kube-node-lease0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T15:08:51.959465Z","caller":"traceutil/trace.go:171","msg":"trace[276672729] range","detail":"{range_begin:/registry/services/endpoints/kube-node-lease/; range_end:/registry/services/endpoints/kube-node-lease0; response_count:0; response_revision:1475; }","duration":"100.953878ms","start":"2026-04-17T15:08:51.858491Z","end":"2026-04-17T15:08:51.959445Z","steps":["trace[276672729] 'range keys from in-memory index tree' (duration: 100.835326ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:08:51.959588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.669764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-17T15:08:51.959661Z","caller":"traceutil/trace.go:171","msg":"trace[1722856725] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1475; }","duration":"164.775086ms","start":"2026-04-17T15:08:51.794868Z","end":"2026-04-17T15:08:51.959643Z","steps":["trace[1722856725] 'range keys from in-memory index tree' (duration: 164.513292ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:08:51.960002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.292175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/envoy-oidc-hmac\" ","response":"range_response_count:1 size:452"} {"level":"warn","ts":"2026-04-17T15:08:51.960017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.258301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-17T15:08:51.960069Z","caller":"traceutil/trace.go:171","msg":"trace[947991064] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/envoy-oidc-hmac; range_end:; response_count:1; response_revision:1475; }","duration":"175.576218ms","start":"2026-04-17T15:08:51.784476Z","end":"2026-04-17T15:08:51.960052Z","steps":["trace[947991064] 'range keys from in-memory index tree' (duration: 175.179644ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:08:51.960078Z","caller":"traceutil/trace.go:171","msg":"trace[1870995821] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1475; }","duration":"161.334421ms","start":"2026-04-17T15:08:51.798727Z","end":"2026-04-17T15:08:51.960061Z","steps":["trace[1870995821] 'range keys from in-memory index tree' (duration: 160.914686ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:08:52.067193Z","caller":"traceutil/trace.go:171","msg":"trace[686034532] transaction","detail":"{read_only:false; response_revision:1477; number_of_response:1; }","duration":"103.129556ms","start":"2026-04-17T15:08:51.964034Z","end":"2026-04-17T15:08:52.067163Z","steps":["trace[686034532] 'process raft request' (duration: 101.701387ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:08:53.883385Z","caller":"traceutil/trace.go:171","msg":"trace[1193140640] linearizableReadLoop","detail":"{readStateIndex:1548; appliedIndex:1547; }","duration":"132.062319ms","start":"2026-04-17T15:08:53.7513Z","end":"2026-04-17T15:08:53.883362Z","steps":["trace[1193140640] 'read index received' (duration: 131.874296ms)","trace[1193140640] 'applied index is now lower than readState.Index' (duration: 187.083µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T15:08:53.883605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.07696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-17T15:08:53.883644Z","caller":"traceutil/trace.go:171","msg":"trace[1563294019] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1480; }","duration":"127.152081ms","start":"2026-04-17T15:08:53.756482Z","end":"2026-04-17T15:08:53.883634Z","steps":["trace[1563294019] 'agreement among raft nodes before linearized reading' (duration: 127.005419ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:08:53.883675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.307411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/extension-apiserver-authentication\" ","response":"range_response_count:1 size:3017"} {"level":"info","ts":"2026-04-17T15:08:53.883748Z","caller":"traceutil/trace.go:171","msg":"trace[1788383666] range","detail":"{range_begin:/registry/configmaps/kube-system/extension-apiserver-authentication; range_end:; response_count:1; response_revision:1480; }","duration":"107.402983ms","start":"2026-04-17T15:08:53.776326Z","end":"2026-04-17T15:08:53.883729Z","steps":["trace[1788383666] 'agreement among raft nodes before linearized reading' (duration: 107.272331ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:08:53.883855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.503116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" limit:500 ","response":"range_response_count:4 size:329952"} {"level":"info","ts":"2026-04-17T15:08:53.884022Z","caller":"traceutil/trace.go:171","msg":"trace[1182519874] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:1480; }","duration":"132.715928ms","start":"2026-04-17T15:08:53.751248Z","end":"2026-04-17T15:08:53.883964Z","steps":["trace[1182519874] 'agreement among raft nodes before linearized reading' (duration: 132.212021ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:08:53.884034Z","caller":"traceutil/trace.go:171","msg":"trace[1502692679] transaction","detail":"{read_only:false; response_revision:1480; number_of_response:1; }","duration":"142.145346ms","start":"2026-04-17T15:08:53.741277Z","end":"2026-04-17T15:08:53.883422Z","steps":["trace[1502692679] 'process raft request' (duration: 141.940743ms)"],"step_count":1}