{"level":"warn","ts":"2026-02-18T22:53:16.683459Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-18T22:53:16.68364Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.110: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://162.253.55.110:2380","--initial-cluster=instance=https://162.253.55.110:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.110:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.110: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-02-18T22:53:16.683765Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-18T22:53:16.683836Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.110:2380"]} {"level":"info","ts":"2026-02-18T22:53:16.683909Z","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-02-18T22:53:16.684569Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.110:2379"]} {"level":"info","ts":"2026-02-18T22:53:16.684722Z","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":2,"max-cpu-available":2,"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://162.253.55.110:2380"],"listen-peer-urls":["https://162.253.55.110:2380"],"advertise-client-urls":["https://162.253.55.110:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.110:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.110: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-02-18T22:53:16.690053Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.094428ms"} {"level":"info","ts":"2026-02-18T22:53:16.710965Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f52f30d5714009d5","cluster-id":"650981a7a383c714"} {"level":"info","ts":"2026-02-18T22:53:16.711066Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 switched to configuration voters=()"} {"level":"info","ts":"2026-02-18T22:53:16.71109Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 became follower at term 0"} {"level":"info","ts":"2026-02-18T22:53:16.711104Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f52f30d5714009d5 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-18T22:53:16.711116Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 became follower at term 1"} {"level":"info","ts":"2026-02-18T22:53:16.711161Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 switched to configuration voters=(17667393556483934677)"} {"level":"warn","ts":"2026-02-18T22:53:16.726413Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-18T22:53:16.741628Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-18T22:53:16.752896Z","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-02-18T22:53:16.758338Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f52f30d5714009d5","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-18T22:53:16.758749Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f52f30d5714009d5","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-18T22:53:16.759081Z","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-02-18T22:53:16.759301Z","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-02-18T22:53:16.759409Z","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-02-18T22:53:16.759723Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 switched to configuration voters=(17667393556483934677)"} {"level":"info","ts":"2026-02-18T22:53:16.763045Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"650981a7a383c714","local-member-id":"f52f30d5714009d5","added-peer-id":"f52f30d5714009d5","added-peer-peer-urls":["https://162.253.55.110:2380"]} {"level":"info","ts":"2026-02-18T22:53:16.773673Z","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-02-18T22:53:16.776381Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.110:2380"} {"level":"info","ts":"2026-02-18T22:53:16.776458Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.110:2380"} {"level":"info","ts":"2026-02-18T22:53:16.778486Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f52f30d5714009d5","initial-advertise-peer-urls":["https://162.253.55.110:2380"],"listen-peer-urls":["https://162.253.55.110:2380"],"advertise-client-urls":["https://162.253.55.110:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.110:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-18T22:53:16.778534Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-18T22:53:17.311409Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 is starting a new election at term 1"} {"level":"info","ts":"2026-02-18T22:53:17.311456Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-18T22:53:17.311469Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 received MsgPreVoteResp from f52f30d5714009d5 at term 1"} {"level":"info","ts":"2026-02-18T22:53:17.31171Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 became candidate at term 2"} {"level":"info","ts":"2026-02-18T22:53:17.311724Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 received MsgVoteResp from f52f30d5714009d5 at term 2"} {"level":"info","ts":"2026-02-18T22:53:17.311783Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 became leader at term 2"} {"level":"info","ts":"2026-02-18T22:53:17.311797Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f52f30d5714009d5 elected leader f52f30d5714009d5 at term 2"} {"level":"info","ts":"2026-02-18T22:53:17.315022Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f52f30d5714009d5","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.110:2379]}","request-path":"/0/members/f52f30d5714009d5/attributes","cluster-id":"650981a7a383c714","publish-timeout":"7s"} {"level":"info","ts":"2026-02-18T22:53:17.315112Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-18T22:53:17.315382Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T22:53:17.315948Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-18T22:53:17.316712Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-18T22:53:17.316725Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-18T22:53:17.317917Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-18T22:53:17.319253Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.110:2379"} {"level":"info","ts":"2026-02-18T22:53:17.31932Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"650981a7a383c714","local-member-id":"f52f30d5714009d5","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T22:53:17.319368Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T22:53:17.319384Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T22:53:30.459999Z","caller":"traceutil/trace.go:171","msg":"trace[2027206866] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"163.816437ms","start":"2026-02-18T22:53:30.29616Z","end":"2026-02-18T22:53:30.459976Z","steps":["trace[2027206866] 'process raft request' (duration: 163.695804ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:53:31.77894Z","caller":"traceutil/trace.go:171","msg":"trace[1985655979] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"201.387656ms","start":"2026-02-18T22:53:31.577202Z","end":"2026-02-18T22:53:31.77859Z","steps":["trace[1985655979] 'process raft request' (duration: 201.226492ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:53:36.351581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.501783ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-18T22:53:36.351693Z","caller":"traceutil/trace.go:171","msg":"trace[1706383510] linearizableReadLoop","detail":"{readStateIndex:473; appliedIndex:472; }","duration":"158.821102ms","start":"2026-02-18T22:53:36.192862Z","end":"2026-02-18T22:53:36.351683Z","steps":["trace[1706383510] 'read index received' (duration: 29.871µs)","trace[1706383510] 'applied index is now lower than readState.Index' (duration: 158.790321ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:53:36.35174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.871433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:53:36.351753Z","caller":"traceutil/trace.go:171","msg":"trace[1256910194] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:456; }","duration":"158.917264ms","start":"2026-02-18T22:53:36.19283Z","end":"2026-02-18T22:53:36.351748Z","steps":["trace[1256910194] 'agreement among raft nodes before linearized reading' (duration: 158.880023ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:53:37.163333Z","caller":"traceutil/trace.go:171","msg":"trace[1369292630] transaction","detail":"{read_only:false; response_revision:457; number_of_response:1; }","duration":"232.261982ms","start":"2026-02-18T22:53:36.931052Z","end":"2026-02-18T22:53:37.163314Z","steps":["trace[1369292630] 'process raft request' (duration: 232.16147ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:53:37.565508Z","caller":"traceutil/trace.go:171","msg":"trace[328310112] transaction","detail":"{read_only:false; response_revision:459; number_of_response:1; }","duration":"166.729958ms","start":"2026-02-18T22:53:37.398758Z","end":"2026-02-18T22:53:37.565488Z","steps":["trace[328310112] 'process raft request' (duration: 165.267808ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:53:42.816046Z","caller":"traceutil/trace.go:171","msg":"trace[438621210] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"101.008099ms","start":"2026-02-18T22:53:42.715007Z","end":"2026-02-18T22:53:42.816016Z","steps":["trace[438621210] 'process raft request' (duration: 100.861986ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:53:49.773352Z","caller":"traceutil/trace.go:171","msg":"trace[281566850] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"102.884658ms","start":"2026-02-18T22:53:49.67045Z","end":"2026-02-18T22:53:49.773335Z","steps":["trace[281566850] 'process raft request' (duration: 102.770286ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:53:49.774534Z","caller":"traceutil/trace.go:171","msg":"trace[1048322879] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"100.219792ms","start":"2026-02-18T22:53:49.674298Z","end":"2026-02-18T22:53:49.774518Z","steps":["trace[1048322879] 'process raft request' (duration: 99.891925ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:53:50.082945Z","caller":"traceutil/trace.go:171","msg":"trace[1684637527] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"126.82392ms","start":"2026-02-18T22:53:49.956106Z","end":"2026-02-18T22:53:50.08293Z","steps":["trace[1684637527] 'process raft request' (duration: 126.724538ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:53:50.758527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.574705ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-18T22:53:50.75861Z","caller":"traceutil/trace.go:171","msg":"trace[1938262385] linearizableReadLoop","detail":"{readStateIndex:572; appliedIndex:571; }","duration":"565.7882ms","start":"2026-02-18T22:53:50.192809Z","end":"2026-02-18T22:53:50.758597Z","steps":["trace[1938262385] 'read index received' (duration: 194.822526ms)","trace[1938262385] 'applied index is now lower than readState.Index' (duration: 370.964724ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:53:50.758683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.898162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:53:50.758654Z","caller":"traceutil/trace.go:171","msg":"trace[1466848718] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"641.884597ms","start":"2026-02-18T22:53:50.116727Z","end":"2026-02-18T22:53:50.758612Z","steps":["trace[1466848718] 'process raft request' (duration: 270.897363ms)","trace[1466848718] 'compare' (duration: 370.473983ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:53:50.758705Z","caller":"traceutil/trace.go:171","msg":"trace[763720733] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:552; }","duration":"565.957243ms","start":"2026-02-18T22:53:50.192742Z","end":"2026-02-18T22:53:50.758699Z","steps":["trace[763720733] 'agreement among raft nodes before linearized reading' (duration: 565.902272ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:53:50.758734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:53:50.192723Z","time spent":"566.005635ms","remote":"127.0.0.1:34278","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-02-18T22:53:50.758852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:53:50.11671Z","time spent":"642.086041ms","remote":"127.0.0.1:34320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23277,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-18T22:53:51.20953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.298907ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-18T22:53:51.209647Z","caller":"traceutil/trace.go:171","msg":"trace[1535478952] linearizableReadLoop","detail":"{readStateIndex:574; appliedIndex:572; }","duration":"451.010962ms","start":"2026-02-18T22:53:50.758627Z","end":"2026-02-18T22:53:51.209638Z","steps":["trace[1535478952] 'read index received' (duration: 215.581143ms)","trace[1535478952] 'applied index is now lower than readState.Index' (duration: 235.429249ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:53:51.209736Z","caller":"traceutil/trace.go:171","msg":"trace[337023166] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"895.461357ms","start":"2026-02-18T22:53:50.314266Z","end":"2026-02-18T22:53:51.209727Z","steps":["trace[337023166] 'process raft request' (duration: 659.932076ms)","trace[337023166] 'compare' (duration: 235.223904ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:53:51.209761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:53:50.314247Z","time spent":"895.501377ms","remote":"127.0.0.1:34320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4234,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-18T22:53:51.209848Z","caller":"traceutil/trace.go:171","msg":"trace[1231663087] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"695.706346ms","start":"2026-02-18T22:53:50.514127Z","end":"2026-02-18T22:53:51.209834Z","steps":["trace[1231663087] 'process raft request' (duration: 695.454511ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:53:51.209868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:53:50.51411Z","time spent":"695.748657ms","remote":"127.0.0.1:34320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3202,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-18T22:53:51.209923Z","caller":"traceutil/trace.go:171","msg":"trace[1141746875] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"435.17317ms","start":"2026-02-18T22:53:50.774746Z","end":"2026-02-18T22:53:51.209919Z","steps":["trace[1141746875] 'process raft request' (duration: 434.864113ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:53:51.210035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:53:50.774731Z","time spent":"435.20109ms","remote":"127.0.0.1:34320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":24220,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:53:51.21013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.085129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:53:51.210145Z","caller":"traceutil/trace.go:171","msg":"trace[1478366493] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:555; }","duration":"498.11537ms","start":"2026-02-18T22:53:50.712024Z","end":"2026-02-18T22:53:51.210139Z","steps":["trace[1478366493] 'agreement among raft nodes before linearized reading' (duration: 498.07771ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:53:51.210159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:53:50.71201Z","time spent":"498.145531ms","remote":"127.0.0.1:34320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-02-18T22:53:51.210213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"685.771077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.110\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-18T22:53:51.210224Z","caller":"traceutil/trace.go:171","msg":"trace[1328624294] range","detail":"{range_begin:/registry/masterleases/162.253.55.110; range_end:; response_count:1; response_revision:555; }","duration":"685.804218ms","start":"2026-02-18T22:53:50.524416Z","end":"2026-02-18T22:53:51.210221Z","steps":["trace[1328624294] 'agreement among raft nodes before linearized reading' (duration: 685.779138ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:53:51.210235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:53:50.524397Z","time spent":"685.835298ms","remote":"127.0.0.1:34302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/162.253.55.110\" "} {"level":"warn","ts":"2026-02-18T22:53:51.210301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.45024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:53:51.210312Z","caller":"traceutil/trace.go:171","msg":"trace[1189319010] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:555; }","duration":"450.482251ms","start":"2026-02-18T22:53:50.759827Z","end":"2026-02-18T22:53:51.210309Z","steps":["trace[1189319010] 'agreement among raft nodes before linearized reading' (duration: 450.463401ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:53:51.210323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:53:50.759815Z","time spent":"450.505301ms","remote":"127.0.0.1:34272","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-02-18T22:53:51.210591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.614695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:53:51.210604Z","caller":"traceutil/trace.go:171","msg":"trace[2108794709] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:555; }","duration":"298.651866ms","start":"2026-02-18T22:53:50.911948Z","end":"2026-02-18T22:53:51.2106Z","steps":["trace[2108794709] 'agreement among raft nodes before linearized reading' (duration: 298.631325ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:53:57.637171Z","caller":"traceutil/trace.go:171","msg":"trace[162672646] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"315.961785ms","start":"2026-02-18T22:53:57.321196Z","end":"2026-02-18T22:53:57.637158Z","steps":["trace[162672646] 'process raft request' (duration: 315.872383ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:53:57.637265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:53:57.32117Z","time spent":"316.046938ms","remote":"127.0.0.1:34590","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-02-18T22:53:57.767443Z","caller":"traceutil/trace.go:171","msg":"trace[1886216498] linearizableReadLoop","detail":"{readStateIndex:612; appliedIndex:610; }","duration":"215.937212ms","start":"2026-02-18T22:53:57.551483Z","end":"2026-02-18T22:53:57.76742Z","steps":["trace[1886216498] 'read index received' (duration: 85.542646ms)","trace[1886216498] 'applied index is now lower than readState.Index' (duration: 130.393666ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:53:57.767705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.620319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:53:57.767749Z","caller":"traceutil/trace.go:171","msg":"trace[1190218373] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:590; }","duration":"168.6757ms","start":"2026-02-18T22:53:57.599063Z","end":"2026-02-18T22:53:57.767739Z","steps":["trace[1190218373] 'agreement among raft nodes before linearized reading' (duration: 168.598808ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:53:57.767897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.413303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-18T22:53:57.767911Z","caller":"traceutil/trace.go:171","msg":"trace[760298498] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:590; }","duration":"216.442203ms","start":"2026-02-18T22:53:57.551463Z","end":"2026-02-18T22:53:57.767906Z","steps":["trace[760298498] 'agreement among raft nodes before linearized reading' (duration: 216.385272ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:53:57.767984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.747091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:53:57.767999Z","caller":"traceutil/trace.go:171","msg":"trace[154730763] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:590; }","duration":"118.760471ms","start":"2026-02-18T22:53:57.649233Z","end":"2026-02-18T22:53:57.767993Z","steps":["trace[154730763] 'agreement among raft nodes before linearized reading' (duration: 118.730621ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:53:57.768067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.841353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:53:57.768081Z","caller":"traceutil/trace.go:171","msg":"trace[1479055816] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:590; }","duration":"118.862883ms","start":"2026-02-18T22:53:57.649214Z","end":"2026-02-18T22:53:57.768077Z","steps":["trace[1479055816] 'agreement among raft nodes before linearized reading' (duration: 118.840642ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:53:57.768131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.930665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:53:57.768145Z","caller":"traceutil/trace.go:171","msg":"trace[1974713302] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:590; }","duration":"118.976175ms","start":"2026-02-18T22:53:57.649165Z","end":"2026-02-18T22:53:57.768141Z","steps":["trace[1974713302] 'agreement among raft nodes before linearized reading' (duration: 118.956995ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:53:57.768197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.472912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:53:57.768211Z","caller":"traceutil/trace.go:171","msg":"trace[1771046989] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:590; }","duration":"132.497713ms","start":"2026-02-18T22:53:57.635708Z","end":"2026-02-18T22:53:57.768205Z","steps":["trace[1771046989] 'agreement among raft nodes before linearized reading' (duration: 132.477752ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:53:57.768273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.579524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:53:57.768284Z","caller":"traceutil/trace.go:171","msg":"trace[1282242226] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:590; }","duration":"132.626475ms","start":"2026-02-18T22:53:57.635654Z","end":"2026-02-18T22:53:57.768281Z","steps":["trace[1282242226] 'agreement among raft nodes before linearized reading' (duration: 132.607665ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:53:57.768363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.519418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:53:57.76838Z","caller":"traceutil/trace.go:171","msg":"trace[1828745813] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:590; }","duration":"144.546098ms","start":"2026-02-18T22:53:57.623829Z","end":"2026-02-18T22:53:57.768375Z","steps":["trace[1828745813] 'agreement among raft nodes before linearized reading' (duration: 144.521388ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:53:57.768465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.09491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:53:57.768482Z","caller":"traceutil/trace.go:171","msg":"trace[2132595160] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:590; }","duration":"154.12267ms","start":"2026-02-18T22:53:57.614355Z","end":"2026-02-18T22:53:57.768477Z","steps":["trace[2132595160] 'agreement among raft nodes before linearized reading' (duration: 154.09616ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:53:57.768568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.213783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:53:57.768585Z","caller":"traceutil/trace.go:171","msg":"trace[2118229548] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:590; }","duration":"154.237153ms","start":"2026-02-18T22:53:57.614342Z","end":"2026-02-18T22:53:57.768579Z","steps":["trace[2118229548] 'agreement among raft nodes before linearized reading' (duration: 154.213463ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:53:57.768648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.334615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:53:57.768663Z","caller":"traceutil/trace.go:171","msg":"trace[1358064645] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:590; }","duration":"154.369916ms","start":"2026-02-18T22:53:57.614289Z","end":"2026-02-18T22:53:57.768658Z","steps":["trace[1358064645] 'agreement among raft nodes before linearized reading' (duration: 154.347776ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:53:57.768736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.61673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:53:57.768751Z","caller":"traceutil/trace.go:171","msg":"trace[664046466] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:590; }","duration":"169.64052ms","start":"2026-02-18T22:53:57.599106Z","end":"2026-02-18T22:53:57.768747Z","steps":["trace[664046466] 'agreement among raft nodes before linearized reading' (duration: 169.617009ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:53:57.768814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.713482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:53:57.768828Z","caller":"traceutil/trace.go:171","msg":"trace[908082881] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:590; }","duration":"169.741442ms","start":"2026-02-18T22:53:57.599082Z","end":"2026-02-18T22:53:57.768824Z","steps":["trace[908082881] 'agreement among raft nodes before linearized reading' (duration: 169.719642ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:53:57.767603Z","caller":"traceutil/trace.go:171","msg":"trace[722182431] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"445.419033ms","start":"2026-02-18T22:53:57.322173Z","end":"2026-02-18T22:53:57.767592Z","steps":["trace[722182431] 'process raft request' (duration: 374.16254ms)","trace[722182431] 'compare' (duration: 70.930105ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:53:57.769039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:53:57.322127Z","time spent":"446.792381ms","remote":"127.0.0.1:34590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:53:59.618632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.711454028s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T22:53:59.61872Z","caller":"traceutil/trace.go:171","msg":"trace[974004407] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"1.842270664s","start":"2026-02-18T22:53:57.776435Z","end":"2026-02-18T22:53:59.618706Z","steps":["trace[974004407] 'process raft request' (duration: 130.676173ms)","trace[974004407] 'compare' (duration: 1.710720073s)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:53:59.61872Z","caller":"traceutil/trace.go:171","msg":"trace[651477521] linearizableReadLoop","detail":"{readStateIndex:613; appliedIndex:612; }","duration":"1.793576141s","start":"2026-02-18T22:53:57.825127Z","end":"2026-02-18T22:53:59.618703Z","steps":["trace[651477521] 'read index received' (duration: 81.944879ms)","trace[651477521] 'applied index is now lower than readState.Index' (duration: 1.711630092s)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:53:59.618774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:53:57.776423Z","time spent":"1.842327315s","remote":"127.0.0.1:34590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:53:59.618909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.793781225s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-18T22:53:59.618946Z","caller":"traceutil/trace.go:171","msg":"trace[317701120] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:591; }","duration":"1.793817906s","start":"2026-02-18T22:53:57.825115Z","end":"2026-02-18T22:53:59.618933Z","steps":["trace[317701120] 'agreement among raft nodes before linearized reading' (duration: 1.793695983s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:53:59.61898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.743930907s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-02-18T22:53:59.61898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:53:57.825047Z","time spent":"1.793922578s","remote":"127.0.0.1:34590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-18T22:53:59.619Z","caller":"traceutil/trace.go:171","msg":"trace[63620449] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:591; }","duration":"1.743979108s","start":"2026-02-18T22:53:57.875014Z","end":"2026-02-18T22:53:59.618994Z","steps":["trace[63620449] 'agreement among raft nodes before linearized reading' (duration: 1.743920917s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:53:59.619017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:53:57.874996Z","time spent":"1.744016569s","remote":"127.0.0.1:34590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-18T22:53:59.619069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.420464603s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:53:59.619083Z","caller":"traceutil/trace.go:171","msg":"trace[1238596598] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:591; }","duration":"1.420510925s","start":"2026-02-18T22:53:58.198568Z","end":"2026-02-18T22:53:59.619079Z","steps":["trace[1238596598] 'agreement among raft nodes before linearized reading' (duration: 1.420486874s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:53:59.619098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:53:58.19855Z","time spent":"1.420543735s","remote":"127.0.0.1:34278","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-02-18T22:53:59.951208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.784941ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T22:53:59.95139Z","caller":"traceutil/trace.go:171","msg":"trace[1948706192] linearizableReadLoop","detail":"{readStateIndex:615; appliedIndex:613; }","duration":"314.043514ms","start":"2026-02-18T22:53:59.637334Z","end":"2026-02-18T22:53:59.951378Z","steps":["trace[1948706192] 'read index received' (duration: 128.988217ms)","trace[1948706192] 'applied index is now lower than readState.Index' (duration: 185.054637ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:53:59.951446Z","caller":"traceutil/trace.go:171","msg":"trace[1766953573] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"322.086445ms","start":"2026-02-18T22:53:59.62935Z","end":"2026-02-18T22:53:59.951436Z","steps":["trace[1766953573] 'process raft request' (duration: 137.020628ms)","trace[1766953573] 'compare' (duration: 184.72105ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:53:59.951485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:53:59.629336Z","time spent":"322.126266ms","remote":"127.0.0.1:34590","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-02-18T22:53:59.951573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.221828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:53:59.951626Z","caller":"traceutil/trace.go:171","msg":"trace[1144918900] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:594; }","duration":"314.31923ms","start":"2026-02-18T22:53:59.637293Z","end":"2026-02-18T22:53:59.951612Z","steps":["trace[1144918900] 'agreement among raft nodes before linearized reading' (duration: 314.236598ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:53:59.951642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.892067ms","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-02-18T22:53:59.951658Z","caller":"traceutil/trace.go:171","msg":"trace[436624996] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:594; }","duration":"310.936778ms","start":"2026-02-18T22:53:59.640715Z","end":"2026-02-18T22:53:59.951652Z","steps":["trace[436624996] 'agreement among raft nodes before linearized reading' (duration: 310.871297ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:53:59.951656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:53:59.63728Z","time spent":"314.371671ms","remote":"127.0.0.1:34278","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-02-18T22:53:59.951673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:53:59.640707Z","time spent":"310.963679ms","remote":"127.0.0.1:34590","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-02-18T22:53:59.951773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.814687ms","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-02-18T22:53:59.951781Z","caller":"traceutil/trace.go:171","msg":"trace[617346049] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"322.282919ms","start":"2026-02-18T22:53:59.62949Z","end":"2026-02-18T22:53:59.951773Z","steps":["trace[617346049] 'process raft request' (duration: 321.790839ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:53:59.951787Z","caller":"traceutil/trace.go:171","msg":"trace[136220656] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:594; }","duration":"172.859967ms","start":"2026-02-18T22:53:59.778923Z","end":"2026-02-18T22:53:59.951783Z","steps":["trace[136220656] 'agreement among raft nodes before linearized reading' (duration: 172.818976ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:53:59.95184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:53:59.629485Z","time spent":"322.31425ms","remote":"127.0.0.1:34590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:53:59.951937Z","caller":"traceutil/trace.go:171","msg":"trace[2108766942] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"227.965147ms","start":"2026-02-18T22:53:59.723968Z","end":"2026-02-18T22:53:59.951933Z","steps":["trace[2108766942] 'process raft request' (duration: 227.361835ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:54:00.540877Z","caller":"traceutil/trace.go:171","msg":"trace[781141819] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"585.116367ms","start":"2026-02-18T22:53:59.955747Z","end":"2026-02-18T22:54:00.540864Z","steps":["trace[781141819] 'process raft request' (duration: 494.559364ms)","trace[781141819] 'compare' (duration: 90.268476ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:54:00.540952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:53:59.955731Z","time spent":"585.181828ms","remote":"127.0.0.1:34590","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-02-18T22:54:00.541012Z","caller":"traceutil/trace.go:171","msg":"trace[1134143209] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"583.423752ms","start":"2026-02-18T22:53:59.957584Z","end":"2026-02-18T22:54:00.541008Z","steps":["trace[1134143209] 'process raft request' (duration: 583.057773ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:00.541035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:53:59.957577Z","time spent":"583.445482ms","remote":"127.0.0.1:34590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:54:05.567676Z","caller":"traceutil/trace.go:171","msg":"trace[315310133] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"109.728929ms","start":"2026-02-18T22:54:05.457928Z","end":"2026-02-18T22:54:05.567657Z","steps":["trace[315310133] 'process raft request' (duration: 109.28607ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:06.537447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.347873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:54:06.537523Z","caller":"traceutil/trace.go:171","msg":"trace[536266272] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:632; }","duration":"341.460886ms","start":"2026-02-18T22:54:06.196046Z","end":"2026-02-18T22:54:06.537507Z","steps":["trace[536266272] 'range keys from in-memory index tree' (duration: 341.262762ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:06.537572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:06.196029Z","time spent":"341.523767ms","remote":"127.0.0.1:34278","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-02-18T22:54:06.537825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.359304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-18T22:54:06.537865Z","caller":"traceutil/trace.go:171","msg":"trace[597377185] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:632; }","duration":"539.438176ms","start":"2026-02-18T22:54:05.998418Z","end":"2026-02-18T22:54:06.537856Z","steps":["trace[597377185] 'range keys from in-memory index tree' (duration: 539.221202ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:06.537895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:05.998394Z","time spent":"539.490058ms","remote":"127.0.0.1:34590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-18T22:54:06.538072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.834834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-18T22:54:06.538098Z","caller":"traceutil/trace.go:171","msg":"trace[756099454] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:632; }","duration":"538.870524ms","start":"2026-02-18T22:54:05.999219Z","end":"2026-02-18T22:54:06.53809Z","steps":["trace[756099454] 'range keys from in-memory index tree' (duration: 538.732251ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:06.538122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:05.999214Z","time spent":"538.902295ms","remote":"127.0.0.1:34590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-18T22:54:08.29542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.866094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:54:08.295504Z","caller":"traceutil/trace.go:171","msg":"trace[1895495763] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:642; }","duration":"104.000947ms","start":"2026-02-18T22:54:08.19149Z","end":"2026-02-18T22:54:08.29549Z","steps":["trace[1895495763] 'range keys from in-memory index tree' (duration: 103.785693ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:12.350088Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":708644533934369928,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-18T22:54:12.429599Z","caller":"traceutil/trace.go:171","msg":"trace[2012796372] linearizableReadLoop","detail":"{readStateIndex:689; appliedIndex:688; }","duration":"580.442827ms","start":"2026-02-18T22:54:11.849141Z","end":"2026-02-18T22:54:12.429584Z","steps":["trace[2012796372] 'read index received' (duration: 580.312065ms)","trace[2012796372] 'applied index is now lower than readState.Index' (duration: 130.352µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:54:12.429669Z","caller":"traceutil/trace.go:171","msg":"trace[1080360017] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"756.134915ms","start":"2026-02-18T22:54:11.67353Z","end":"2026-02-18T22:54:12.429665Z","steps":["trace[1080360017] 'process raft request' (duration: 755.953731ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:12.429749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:11.673521Z","time spent":"756.162025ms","remote":"127.0.0.1:34590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:54:12.429897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.752545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-2pgr5\" ","response":"range_response_count:1 size:18250"} {"level":"info","ts":"2026-02-18T22:54:12.429917Z","caller":"traceutil/trace.go:171","msg":"trace[282859048] range","detail":"{range_begin:/registry/pods/kube-system/cilium-2pgr5; range_end:; response_count:1; response_revision:663; }","duration":"580.775225ms","start":"2026-02-18T22:54:11.849136Z","end":"2026-02-18T22:54:12.429912Z","steps":["trace[282859048] 'agreement among raft nodes before linearized reading' (duration: 580.685073ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:12.429931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:11.84908Z","time spent":"580.848327ms","remote":"127.0.0.1:34496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":18273,"request content":"key:\"/registry/pods/kube-system/cilium-2pgr5\" "} {"level":"warn","ts":"2026-02-18T22:54:12.430012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.218605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:54:12.430065Z","caller":"traceutil/trace.go:171","msg":"trace[929509911] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:663; }","duration":"238.301567ms","start":"2026-02-18T22:54:12.191748Z","end":"2026-02-18T22:54:12.430049Z","steps":["trace[929509911] 'agreement among raft nodes before linearized reading' (duration: 238.224426ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:54:13.016403Z","caller":"traceutil/trace.go:171","msg":"trace[31512144] linearizableReadLoop","detail":"{readStateIndex:690; appliedIndex:689; }","duration":"443.659994ms","start":"2026-02-18T22:54:12.572728Z","end":"2026-02-18T22:54:13.016388Z","steps":["trace[31512144] 'read index received' (duration: 443.507031ms)","trace[31512144] 'applied index is now lower than readState.Index' (duration: 152.443µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:54:13.01675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.011571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-18T22:54:13.016779Z","caller":"traceutil/trace.go:171","msg":"trace[421625839] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:664; }","duration":"444.076303ms","start":"2026-02-18T22:54:12.572696Z","end":"2026-02-18T22:54:13.016772Z","steps":["trace[421625839] 'agreement among raft nodes before linearized reading' (duration: 443.946961ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:13.017155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:12.572678Z","time spent":"444.42104ms","remote":"127.0.0.1:34590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-18T22:54:13.017382Z","caller":"traceutil/trace.go:171","msg":"trace[1018468914] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"576.618187ms","start":"2026-02-18T22:54:12.440757Z","end":"2026-02-18T22:54:13.017375Z","steps":["trace[1018468914] 'process raft request' (duration: 575.519234ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:13.017494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:12.440742Z","time spent":"576.658557ms","remote":"127.0.0.1:34496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18235,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:54:13.017796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.106641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-18T22:54:13.017817Z","caller":"traceutil/trace.go:171","msg":"trace[1558312889] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:664; }","duration":"433.144591ms","start":"2026-02-18T22:54:12.584668Z","end":"2026-02-18T22:54:13.017812Z","steps":["trace[1558312889] 'agreement among raft nodes before linearized reading' (duration: 433.09295ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:13.017936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:12.58466Z","time spent":"433.268763ms","remote":"127.0.0.1:34590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-18T22:54:13.018124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.509834ms","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-02-18T22:54:13.018145Z","caller":"traceutil/trace.go:171","msg":"trace[96373559] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:664; }","duration":"404.548425ms","start":"2026-02-18T22:54:12.613592Z","end":"2026-02-18T22:54:13.01814Z","steps":["trace[96373559] 'agreement among raft nodes before linearized reading' (duration: 404.486273ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:13.018231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:12.613579Z","time spent":"404.646228ms","remote":"127.0.0.1:34590","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-02-18T22:54:13.01873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.905413ms","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-02-18T22:54:13.025841Z","caller":"traceutil/trace.go:171","msg":"trace[1533537034] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:664; }","duration":"437.020614ms","start":"2026-02-18T22:54:12.588808Z","end":"2026-02-18T22:54:13.025828Z","steps":["trace[1533537034] 'agreement among raft nodes before linearized reading' (duration: 429.884213ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:13.025901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:12.588801Z","time spent":"437.084945ms","remote":"127.0.0.1:34590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-18T22:54:22.583211Z","caller":"traceutil/trace.go:171","msg":"trace[995138583] transaction","detail":"{read_only:false; response_revision:745; number_of_response:1; }","duration":"116.688437ms","start":"2026-02-18T22:54:22.466504Z","end":"2026-02-18T22:54:22.583193Z","steps":["trace[995138583] 'process raft request' (duration: 116.593315ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:54:23.338932Z","caller":"traceutil/trace.go:171","msg":"trace[2124916718] linearizableReadLoop","detail":"{readStateIndex:774; appliedIndex:773; }","duration":"237.568541ms","start":"2026-02-18T22:54:23.101341Z","end":"2026-02-18T22:54:23.33891Z","steps":["trace[2124916718] 'read index received' (duration: 237.449609ms)","trace[2124916718] 'applied index is now lower than readState.Index' (duration: 118.052µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:54:23.339008Z","caller":"traceutil/trace.go:171","msg":"trace[1142817070] transaction","detail":"{read_only:false; response_revision:746; number_of_response:1; }","duration":"245.00591ms","start":"2026-02-18T22:54:23.093994Z","end":"2026-02-18T22:54:23.339Z","steps":["trace[1142817070] 'process raft request' (duration: 244.814146ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:23.339104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.741045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-18T22:54:23.339135Z","caller":"traceutil/trace.go:171","msg":"trace[128011777] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:746; }","duration":"237.800296ms","start":"2026-02-18T22:54:23.101326Z","end":"2026-02-18T22:54:23.339126Z","steps":["trace[128011777] 'agreement among raft nodes before linearized reading' (duration: 237.689104ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:23.339182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.853743ms","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-02-18T22:54:23.339197Z","caller":"traceutil/trace.go:171","msg":"trace[755104113] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:746; }","duration":"233.881684ms","start":"2026-02-18T22:54:23.105311Z","end":"2026-02-18T22:54:23.339193Z","steps":["trace[755104113] 'agreement among raft nodes before linearized reading' (duration: 233.834943ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:23.339236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.492935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:54:23.339246Z","caller":"traceutil/trace.go:171","msg":"trace[956004032] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:746; }","duration":"143.536475ms","start":"2026-02-18T22:54:23.195706Z","end":"2026-02-18T22:54:23.339243Z","steps":["trace[956004032] 'agreement among raft nodes before linearized reading' (duration: 143.520405ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:23.339323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.839518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-18T22:54:23.339336Z","caller":"traceutil/trace.go:171","msg":"trace[1220635553] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:746; }","duration":"229.862238ms","start":"2026-02-18T22:54:23.109469Z","end":"2026-02-18T22:54:23.339331Z","steps":["trace[1220635553] 'agreement among raft nodes before linearized reading' (duration: 229.828377ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:24.434319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.779998ms","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-02-18T22:54:24.434426Z","caller":"traceutil/trace.go:171","msg":"trace[1090537021] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:746; }","duration":"541.903501ms","start":"2026-02-18T22:54:23.892506Z","end":"2026-02-18T22:54:24.434409Z","steps":["trace[1090537021] 'range keys from in-memory index tree' (duration: 541.766688ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:24.434942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.906787ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T22:54:24.435016Z","caller":"traceutil/trace.go:171","msg":"trace[949637021] transaction","detail":"{read_only:false; response_revision:747; number_of_response:1; }","duration":"1.088046209s","start":"2026-02-18T22:54:23.346958Z","end":"2026-02-18T22:54:24.435004Z","steps":["trace[949637021] 'process raft request' (duration: 933.006919ms)","trace[949637021] 'compare' (duration: 154.756514ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:54:24.435084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:23.346942Z","time spent":"1.08809599s","remote":"127.0.0.1:34590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:54:24.914198Z","caller":"traceutil/trace.go:171","msg":"trace[723659568] linearizableReadLoop","detail":"{readStateIndex:780; appliedIndex:775; }","duration":"325.086539ms","start":"2026-02-18T22:54:24.589099Z","end":"2026-02-18T22:54:24.914185Z","steps":["trace[723659568] 'read index received' (duration: 324.895275ms)","trace[723659568] 'applied index is now lower than readState.Index' (duration: 190.744µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:54:24.91433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.233703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-18T22:54:24.91435Z","caller":"traceutil/trace.go:171","msg":"trace[1993071417] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:751; }","duration":"325.308164ms","start":"2026-02-18T22:54:24.589037Z","end":"2026-02-18T22:54:24.914345Z","steps":["trace[1993071417] 'agreement among raft nodes before linearized reading' (duration: 325.200342ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:24.914367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:24.589014Z","time spent":"325.348475ms","remote":"127.0.0.1:34590","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-02-18T22:54:24.914511Z","caller":"traceutil/trace.go:171","msg":"trace[751958981] transaction","detail":"{read_only:false; response_revision:748; number_of_response:1; }","duration":"1.567052614s","start":"2026-02-18T22:54:23.347452Z","end":"2026-02-18T22:54:24.914505Z","steps":["trace[751958981] 'process raft request' (duration: 1.566573965s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:24.914563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:23.347443Z","time spent":"1.567084295s","remote":"127.0.0.1:34590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:54:24.914628Z","caller":"traceutil/trace.go:171","msg":"trace[503606174] transaction","detail":"{read_only:false; response_revision:749; number_of_response:1; }","duration":"1.565154785s","start":"2026-02-18T22:54:23.349467Z","end":"2026-02-18T22:54:24.914622Z","steps":["trace[503606174] 'process raft request' (duration: 1.564621774s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:24.914656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:23.349458Z","time spent":"1.565182565s","remote":"127.0.0.1:34590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:54:24.914709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:23.414618Z","time spent":"1.500090013s","remote":"127.0.0.1:34354","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-18T22:54:24.91477Z","caller":"traceutil/trace.go:171","msg":"trace[575336820] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"1.245104312s","start":"2026-02-18T22:54:23.669662Z","end":"2026-02-18T22:54:24.914766Z","steps":["trace[575336820] 'process raft request' (duration: 1.244479999s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:24.914803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:23.669644Z","time spent":"1.245146483s","remote":"127.0.0.1:34590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:54:24.914925Z","caller":"traceutil/trace.go:171","msg":"trace[1549426065] transaction","detail":"{read_only:false; response_revision:751; number_of_response:1; }","duration":"678.306794ms","start":"2026-02-18T22:54:24.236614Z","end":"2026-02-18T22:54:24.914921Z","steps":["trace[1549426065] 'process raft request' (duration: 677.549658ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:24.914954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:24.236595Z","time spent":"678.343896ms","remote":"127.0.0.1:34480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4769,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:54:25.226385Z","caller":"traceutil/trace.go:171","msg":"trace[1896638111] transaction","detail":"{read_only:false; response_revision:752; number_of_response:1; }","duration":"308.343933ms","start":"2026-02-18T22:54:24.918013Z","end":"2026-02-18T22:54:25.226356Z","steps":["trace[1896638111] 'process raft request' (duration: 282.75084ms)","trace[1896638111] 'compare' (duration: 25.175434ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:54:25.226523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:24.917999Z","time spent":"308.461727ms","remote":"127.0.0.1:34354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":815,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-18T22:54:25.280772Z","caller":"traceutil/trace.go:171","msg":"trace[2074010248] linearizableReadLoop","detail":"{readStateIndex:782; appliedIndex:780; }","duration":"175.675798ms","start":"2026-02-18T22:54:25.105081Z","end":"2026-02-18T22:54:25.280757Z","steps":["trace[2074010248] 'read index received' (duration: 95.68914ms)","trace[2074010248] 'applied index is now lower than readState.Index' (duration: 79.985988ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:54:25.280926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.823051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T22:54:25.281013Z","caller":"traceutil/trace.go:171","msg":"trace[1272595825] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:753; }","duration":"175.925624ms","start":"2026-02-18T22:54:25.105075Z","end":"2026-02-18T22:54:25.281001Z","steps":["trace[1272595825] 'agreement among raft nodes before linearized reading' (duration: 175.778671ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:54:25.28116Z","caller":"traceutil/trace.go:171","msg":"trace[5805611] transaction","detail":"{read_only:false; response_revision:753; number_of_response:1; }","duration":"358.642161ms","start":"2026-02-18T22:54:24.922509Z","end":"2026-02-18T22:54:25.281151Z","steps":["trace[5805611] 'process raft request' (duration: 358.162271ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:25.281259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:24.922469Z","time spent":"358.710392ms","remote":"127.0.0.1:34590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:54:26.501625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.837595ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-18T22:54:26.501701Z","caller":"traceutil/trace.go:171","msg":"trace[123092679] linearizableReadLoop","detail":"{readStateIndex:784; appliedIndex:783; }","duration":"306.503315ms","start":"2026-02-18T22:54:26.195186Z","end":"2026-02-18T22:54:26.501689Z","steps":["trace[123092679] 'read index received' (duration: 26.631µs)","trace[123092679] 'applied index is now lower than readState.Index' (duration: 306.475704ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:54:26.501843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.649698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:54:26.501862Z","caller":"traceutil/trace.go:171","msg":"trace[258314462] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:754; }","duration":"306.673719ms","start":"2026-02-18T22:54:26.195182Z","end":"2026-02-18T22:54:26.501855Z","steps":["trace[258314462] 'agreement among raft nodes before linearized reading' (duration: 306.630098ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:26.501881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:26.19514Z","time spent":"306.73634ms","remote":"127.0.0.1:34278","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-02-18T22:54:26.5019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.748038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T22:54:26.501929Z","caller":"traceutil/trace.go:171","msg":"trace[1862517732] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:754; }","duration":"124.835159ms","start":"2026-02-18T22:54:26.377086Z","end":"2026-02-18T22:54:26.501922Z","steps":["trace[1862517732] 'agreement among raft nodes before linearized reading' (duration: 124.783148ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:54:26.783639Z","caller":"traceutil/trace.go:171","msg":"trace[1852320613] transaction","detail":"{read_only:false; response_revision:755; number_of_response:1; }","duration":"277.883067ms","start":"2026-02-18T22:54:26.505736Z","end":"2026-02-18T22:54:26.783619Z","steps":["trace[1852320613] 'process raft request' (duration: 277.735034ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:54:35.030066Z","caller":"traceutil/trace.go:171","msg":"trace[773826136] transaction","detail":"{read_only:false; response_revision:782; number_of_response:1; }","duration":"118.013874ms","start":"2026-02-18T22:54:34.912036Z","end":"2026-02-18T22:54:35.030049Z","steps":["trace[773826136] 'process raft request' (duration: 117.910022ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:35.287534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.172283ms","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-02-18T22:54:35.287591Z","caller":"traceutil/trace.go:171","msg":"trace[837751941] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:782; }","duration":"106.264205ms","start":"2026-02-18T22:54:35.181315Z","end":"2026-02-18T22:54:35.287579Z","steps":["trace[837751941] 'range keys from in-memory index tree' (duration: 106.05421ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:35.287672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.548687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-18T22:54:35.287711Z","caller":"traceutil/trace.go:171","msg":"trace[1772978999] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:782; }","duration":"185.64506ms","start":"2026-02-18T22:54:35.102058Z","end":"2026-02-18T22:54:35.287703Z","steps":["trace[1772978999] 'range keys from in-memory index tree' (duration: 185.457345ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:54:35.451597Z","caller":"traceutil/trace.go:171","msg":"trace[1689314185] linearizableReadLoop","detail":"{readStateIndex:816; appliedIndex:815; }","duration":"130.962959ms","start":"2026-02-18T22:54:35.320614Z","end":"2026-02-18T22:54:35.451576Z","steps":["trace[1689314185] 'read index received' (duration: 47.552229ms)","trace[1689314185] 'applied index is now lower than readState.Index' (duration: 83.40682ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:54:35.451882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.249576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-18T22:54:35.451934Z","caller":"traceutil/trace.go:171","msg":"trace[1407514501] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:785; }","duration":"131.324027ms","start":"2026-02-18T22:54:35.320596Z","end":"2026-02-18T22:54:35.45192Z","steps":["trace[1407514501] 'agreement among raft nodes before linearized reading' (duration: 131.069681ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:54:35.452342Z","caller":"traceutil/trace.go:171","msg":"trace[995244575] transaction","detail":"{read_only:false; response_revision:785; number_of_response:1; }","duration":"160.604808ms","start":"2026-02-18T22:54:35.291722Z","end":"2026-02-18T22:54:35.452327Z","steps":["trace[995244575] 'process raft request' (duration: 159.75012ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:38.17576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"718.573899ms","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-02-18T22:54:38.175894Z","caller":"traceutil/trace.go:171","msg":"trace[1936169490] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:788; }","duration":"718.767273ms","start":"2026-02-18T22:54:37.457088Z","end":"2026-02-18T22:54:38.175856Z","steps":["trace[1936169490] 'range keys from in-memory index tree' (duration: 718.315213ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:38.175951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:37.457068Z","time spent":"718.870976ms","remote":"127.0.0.1:34590","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":"warn","ts":"2026-02-18T22:54:38.17634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"981.013118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:54:38.176369Z","caller":"traceutil/trace.go:171","msg":"trace[1031519131] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:788; }","duration":"981.091279ms","start":"2026-02-18T22:54:37.195268Z","end":"2026-02-18T22:54:38.176359Z","steps":["trace[1031519131] 'range keys from in-memory index tree' (duration: 980.902446ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:38.176421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:37.195241Z","time spent":"981.163811ms","remote":"127.0.0.1:34278","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-02-18T22:54:38.178384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"806.402253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-18T22:54:38.17843Z","caller":"traceutil/trace.go:171","msg":"trace[1430264882] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:788; }","duration":"806.489464ms","start":"2026-02-18T22:54:37.371929Z","end":"2026-02-18T22:54:38.178418Z","steps":["trace[1430264882] 'range keys from in-memory index tree' (duration: 806.24288ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:38.178502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:37.371904Z","time spent":"806.588527ms","remote":"127.0.0.1:34590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-18T22:54:38.179579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"717.475695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-18T22:54:38.179611Z","caller":"traceutil/trace.go:171","msg":"trace[957734889] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:788; }","duration":"717.519256ms","start":"2026-02-18T22:54:37.462082Z","end":"2026-02-18T22:54:38.179601Z","steps":["trace[957734889] 'range keys from in-memory index tree' (duration: 717.385133ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:38.17963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:37.462075Z","time spent":"717.550097ms","remote":"127.0.0.1:34590","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-02-18T22:54:38.179885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"586.930345ms","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-02-18T22:54:38.179901Z","caller":"traceutil/trace.go:171","msg":"trace[1210126771] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:788; }","duration":"587.024987ms","start":"2026-02-18T22:54:37.59287Z","end":"2026-02-18T22:54:38.179895Z","steps":["trace[1210126771] 'range keys from in-memory index tree' (duration: 586.602648ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:38.179917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:37.592831Z","time spent":"587.081309ms","remote":"127.0.0.1:34590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-18T22:54:38.445815Z","caller":"traceutil/trace.go:171","msg":"trace[1277606824] linearizableReadLoop","detail":"{readStateIndex:824; appliedIndex:820; }","duration":"244.041869ms","start":"2026-02-18T22:54:38.201744Z","end":"2026-02-18T22:54:38.445786Z","steps":["trace[1277606824] 'read index received' (duration: 8.568972ms)","trace[1277606824] 'applied index is now lower than readState.Index' (duration: 235.471766ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:54:38.445854Z","caller":"traceutil/trace.go:171","msg":"trace[551375310] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"251.616498ms","start":"2026-02-18T22:54:38.194205Z","end":"2026-02-18T22:54:38.445821Z","steps":["trace[551375310] 'process raft request' (duration: 251.024906ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:54:38.44627Z","caller":"traceutil/trace.go:171","msg":"trace[1642309000] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"248.81381ms","start":"2026-02-18T22:54:38.197443Z","end":"2026-02-18T22:54:38.446256Z","steps":["trace[1642309000] 'process raft request' (duration: 248.309919ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:54:38.446421Z","caller":"traceutil/trace.go:171","msg":"trace[1545418303] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"250.695201ms","start":"2026-02-18T22:54:38.195718Z","end":"2026-02-18T22:54:38.446413Z","steps":["trace[1545418303] 'process raft request' (duration: 249.994715ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:38.446533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.769315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:54:38.446564Z","caller":"traceutil/trace.go:171","msg":"trace[1201364688] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:792; }","duration":"244.831926ms","start":"2026-02-18T22:54:38.201723Z","end":"2026-02-18T22:54:38.446555Z","steps":["trace[1201364688] 'agreement among raft nodes before linearized reading' (duration: 244.765894ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:54:55.080566Z","caller":"traceutil/trace.go:171","msg":"trace[1926232050] transaction","detail":"{read_only:false; response_revision:888; number_of_response:1; }","duration":"504.417034ms","start":"2026-02-18T22:54:54.576111Z","end":"2026-02-18T22:54:55.080528Z","steps":["trace[1926232050] 'process raft request' (duration: 504.284741ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:55.080751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:54.576102Z","time spent":"504.574738ms","remote":"127.0.0.1:34590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:54:55.084991Z","caller":"traceutil/trace.go:171","msg":"trace[1047517551] transaction","detail":"{read_only:false; response_revision:889; number_of_response:1; }","duration":"506.681032ms","start":"2026-02-18T22:54:54.578302Z","end":"2026-02-18T22:54:55.084983Z","steps":["trace[1047517551] 'process raft request' (duration: 506.623831ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:55.085101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:54.578296Z","time spent":"506.715504ms","remote":"127.0.0.1:34590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:54:57.69739Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":708644533934370440,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-18T22:54:57.759501Z","caller":"traceutil/trace.go:171","msg":"trace[606152889] transaction","detail":"{read_only:false; response_revision:894; number_of_response:1; }","duration":"668.163568ms","start":"2026-02-18T22:54:57.090956Z","end":"2026-02-18T22:54:57.759119Z","steps":["trace[606152889] 'process raft request' (duration: 667.895963ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:57.760444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:57.090942Z","time spent":"668.660178ms","remote":"127.0.0.1:34590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:54:57.785558Z","caller":"traceutil/trace.go:171","msg":"trace[669590770] transaction","detail":"{read_only:false; response_revision:895; number_of_response:1; }","duration":"693.85184ms","start":"2026-02-18T22:54:57.091699Z","end":"2026-02-18T22:54:57.785551Z","steps":["trace[669590770] 'process raft request' (duration: 692.029451ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:57.785631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:57.091693Z","time spent":"693.891151ms","remote":"127.0.0.1:34590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:54:57.785454Z","caller":"traceutil/trace.go:171","msg":"trace[1894396002] linearizableReadLoop","detail":"{readStateIndex:932; appliedIndex:930; }","duration":"588.891453ms","start":"2026-02-18T22:54:57.196546Z","end":"2026-02-18T22:54:57.785437Z","steps":["trace[1894396002] 'read index received' (duration: 562.467945ms)","trace[1894396002] 'applied index is now lower than readState.Index' (duration: 26.422178ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:54:57.785871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"589.328302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:54:57.785894Z","caller":"traceutil/trace.go:171","msg":"trace[254773998] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:895; }","duration":"589.419213ms","start":"2026-02-18T22:54:57.196468Z","end":"2026-02-18T22:54:57.785887Z","steps":["trace[254773998] 'agreement among raft nodes before linearized reading' (duration: 589.375802ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:57.785915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:57.196446Z","time spent":"589.462965ms","remote":"127.0.0.1:34278","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-02-18T22:54:57.786194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.864094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-18T22:54:57.786216Z","caller":"traceutil/trace.go:171","msg":"trace[496325842] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:895; }","duration":"467.924956ms","start":"2026-02-18T22:54:57.318283Z","end":"2026-02-18T22:54:57.786208Z","steps":["trace[496325842] 'agreement among raft nodes before linearized reading' (duration: 467.836924ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:57.786232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:57.318261Z","time spent":"467.967776ms","remote":"127.0.0.1:34590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-18T22:54:57.786371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"551.598841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T22:54:57.78639Z","caller":"traceutil/trace.go:171","msg":"trace[1964631162] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:895; }","duration":"551.636762ms","start":"2026-02-18T22:54:57.234748Z","end":"2026-02-18T22:54:57.786385Z","steps":["trace[1964631162] 'agreement among raft nodes before linearized reading' (duration: 551.599872ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:57.786407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:57.23474Z","time spent":"551.662443ms","remote":"127.0.0.1:34386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":15,"response size":30,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"warn","ts":"2026-02-18T22:54:57.786558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.006554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:54:57.786586Z","caller":"traceutil/trace.go:171","msg":"trace[509187123] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:895; }","duration":"580.516024ms","start":"2026-02-18T22:54:57.206055Z","end":"2026-02-18T22:54:57.786571Z","steps":["trace[509187123] 'agreement among raft nodes before linearized reading' (duration: 580.039714ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:57.786605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:57.206035Z","time spent":"580.563836ms","remote":"127.0.0.1:34362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"warn","ts":"2026-02-18T22:55:02.302532Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":708644533934370477,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-18T22:55:02.63461Z","caller":"traceutil/trace.go:171","msg":"trace[1403088529] transaction","detail":"{read_only:false; response_revision:906; number_of_response:1; }","duration":"853.970304ms","start":"2026-02-18T22:55:01.780616Z","end":"2026-02-18T22:55:02.634587Z","steps":["trace[1403088529] 'process raft request' (duration: 853.645477ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:02.634767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:01.780605Z","time spent":"854.064026ms","remote":"127.0.0.1:34590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:55:02.634894Z","caller":"traceutil/trace.go:171","msg":"trace[227220261] linearizableReadLoop","detail":"{readStateIndex:944; appliedIndex:943; }","duration":"832.850807ms","start":"2026-02-18T22:55:01.802037Z","end":"2026-02-18T22:55:02.634888Z","steps":["trace[227220261] 'read index received' (duration: 832.145853ms)","trace[227220261] 'applied index is now lower than readState.Index' (duration: 704.004µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:55:02.635002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"832.96679ms","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-02-18T22:55:02.635019Z","caller":"traceutil/trace.go:171","msg":"trace[1288470622] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:906; }","duration":"832.99679ms","start":"2026-02-18T22:55:01.802018Z","end":"2026-02-18T22:55:02.635014Z","steps":["trace[1288470622] 'agreement among raft nodes before linearized reading' (duration: 832.918908ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:02.635034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:01.802011Z","time spent":"833.01966ms","remote":"127.0.0.1:34590","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-02-18T22:55:02.635112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"791.3186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-18T22:55:02.635124Z","caller":"traceutil/trace.go:171","msg":"trace[376320722] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:906; }","duration":"791.33058ms","start":"2026-02-18T22:55:01.84379Z","end":"2026-02-18T22:55:02.63512Z","steps":["trace[376320722] 'agreement among raft nodes before linearized reading' (duration: 791.295309ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:02.635135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:01.843681Z","time spent":"791.452003ms","remote":"127.0.0.1:34590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-18T22:55:02.635299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.190245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-18T22:55:02.635314Z","caller":"traceutil/trace.go:171","msg":"trace[1676523190] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:906; }","duration":"126.247546ms","start":"2026-02-18T22:55:02.509063Z","end":"2026-02-18T22:55:02.635311Z","steps":["trace[1676523190] 'agreement among raft nodes before linearized reading' (duration: 126.208475ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:02.63539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.617277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:55:02.635402Z","caller":"traceutil/trace.go:171","msg":"trace[1073989772] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:906; }","duration":"442.666057ms","start":"2026-02-18T22:55:02.192732Z","end":"2026-02-18T22:55:02.635398Z","steps":["trace[1073989772] 'agreement among raft nodes before linearized reading' (duration: 442.645586ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:02.635413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:02.192712Z","time spent":"442.698058ms","remote":"127.0.0.1:34278","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-02-18T22:55:02.635483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"679.54835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:55:02.635497Z","caller":"traceutil/trace.go:171","msg":"trace[1885840110] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:906; }","duration":"679.602551ms","start":"2026-02-18T22:55:01.955889Z","end":"2026-02-18T22:55:02.635492Z","steps":["trace[1885840110] 'agreement among raft nodes before linearized reading' (duration: 679.58083ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:02.635508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:01.955868Z","time spent":"679.636621ms","remote":"127.0.0.1:34608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":28,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"info","ts":"2026-02-18T22:55:11.009655Z","caller":"traceutil/trace.go:171","msg":"trace[2022108843] linearizableReadLoop","detail":"{readStateIndex:966; appliedIndex:965; }","duration":"341.201966ms","start":"2026-02-18T22:55:10.668434Z","end":"2026-02-18T22:55:11.009636Z","steps":["trace[2022108843] 'read index received' (duration: 341.067243ms)","trace[2022108843] 'applied index is now lower than readState.Index' (duration: 133.733µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:55:11.009742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:10.529138Z","time spent":"480.602689ms","remote":"127.0.0.1:34302","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-18T22:55:11.009968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.520213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-18T22:55:11.009991Z","caller":"traceutil/trace.go:171","msg":"trace[1663528181] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:926; }","duration":"341.565703ms","start":"2026-02-18T22:55:10.668413Z","end":"2026-02-18T22:55:11.009979Z","steps":["trace[1663528181] 'agreement among raft nodes before linearized reading' (duration: 341.483382ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:11.010006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:10.668391Z","time spent":"341.610905ms","remote":"127.0.0.1:34590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-18T22:55:11.010104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.007201ms","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-02-18T22:55:11.010116Z","caller":"traceutil/trace.go:171","msg":"trace[179211153] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:926; }","duration":"215.040762ms","start":"2026-02-18T22:55:10.795071Z","end":"2026-02-18T22:55:11.010112Z","steps":["trace[179211153] 'agreement among raft nodes before linearized reading' (duration: 215.002441ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:12.68602Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.323576151s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-18T22:55:13.094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.660069ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T22:55:13.094066Z","caller":"traceutil/trace.go:171","msg":"trace[2022442985] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"2.078404112s","start":"2026-02-18T22:55:11.015651Z","end":"2026-02-18T22:55:13.094055Z","steps":["trace[2022442985] 'process raft request' (duration: 1.670636651s)","trace[2022442985] 'compare' (duration: 407.484975ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:55:13.094107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:11.015639Z","time spent":"2.078453563s","remote":"127.0.0.1:34302","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-02-18T22:55:13.740551Z","caller":"traceutil/trace.go:171","msg":"trace[1219435545] linearizableReadLoop","detail":"{readStateIndex:973; appliedIndex:966; }","duration":"1.425536844s","start":"2026-02-18T22:55:12.314989Z","end":"2026-02-18T22:55:13.740526Z","steps":["trace[1219435545] 'read index received' (duration: 371.307521ms)","trace[1219435545] 'applied index is now lower than readState.Index' (duration: 1.054228443s)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:55:13.740916Z","caller":"traceutil/trace.go:171","msg":"trace[204455251] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"2.725181372s","start":"2026-02-18T22:55:11.015719Z","end":"2026-02-18T22:55:13.740901Z","steps":["trace[204455251] 'process raft request' (duration: 2.724461916s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:13.741017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:11.015716Z","time spent":"2.725234413s","remote":"127.0.0.1:34590","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-02-18T22:55:13.741145Z","caller":"traceutil/trace.go:171","msg":"trace[1707395347] transaction","detail":"{read_only:false; response_revision:933; number_of_response:1; }","duration":"1.887549532s","start":"2026-02-18T22:55:11.853584Z","end":"2026-02-18T22:55:13.741134Z","steps":["trace[1707395347] 'process raft request' (duration: 1.886907548s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:13.741202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:11.853566Z","time spent":"1.887605284s","remote":"127.0.0.1:34590","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-02-18T22:55:13.741217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.42622267s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:55:13.741252Z","caller":"traceutil/trace.go:171","msg":"trace[805652766] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:933; }","duration":"1.42625585s","start":"2026-02-18T22:55:12.314986Z","end":"2026-02-18T22:55:13.741242Z","steps":["trace[805652766] 'agreement among raft nodes before linearized reading' (duration: 1.426201769s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:13.741281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:12.314946Z","time spent":"1.426328932s","remote":"127.0.0.1:34278","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-02-18T22:55:13.741302Z","caller":"traceutil/trace.go:171","msg":"trace[519688408] transaction","detail":"{read_only:false; response_revision:929; number_of_response:1; }","duration":"2.725547969s","start":"2026-02-18T22:55:11.015748Z","end":"2026-02-18T22:55:13.741296Z","steps":["trace[519688408] 'process raft request' (duration: 2.724581268s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:13.741341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:11.015745Z","time spent":"2.72557231s","remote":"127.0.0.1:34590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:55:13.741425Z","caller":"traceutil/trace.go:171","msg":"trace[444815586] transaction","detail":"{read_only:false; response_revision:930; number_of_response:1; }","duration":"2.725609111s","start":"2026-02-18T22:55:11.01581Z","end":"2026-02-18T22:55:13.741419Z","steps":["trace[444815586] 'process raft request' (duration: 2.724587319s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:13.741456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:11.015806Z","time spent":"2.725632741s","remote":"127.0.0.1:34590","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-02-18T22:55:13.741454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.395881568s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:55:13.74149Z","caller":"traceutil/trace.go:171","msg":"trace[946310831] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:933; }","duration":"1.39593541s","start":"2026-02-18T22:55:12.345545Z","end":"2026-02-18T22:55:13.74148Z","steps":["trace[946310831] 'agreement among raft nodes before linearized reading' (duration: 1.395883619s)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:55:13.741528Z","caller":"traceutil/trace.go:171","msg":"trace[921194479] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"2.72559318s","start":"2026-02-18T22:55:11.015929Z","end":"2026-02-18T22:55:13.741522Z","steps":["trace[921194479] 'process raft request' (duration: 2.724505106s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:13.74153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:12.345536Z","time spent":"1.395976831s","remote":"127.0.0.1:38246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"warn","ts":"2026-02-18T22:55:13.741556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:11.015906Z","time spent":"2.725634771s","remote":"127.0.0.1:34590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:55:13.741627Z","caller":"traceutil/trace.go:171","msg":"trace[595184104] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"2.724907516s","start":"2026-02-18T22:55:11.016713Z","end":"2026-02-18T22:55:13.74162Z","steps":["trace[595184104] 'process raft request' (duration: 2.723749541s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:13.741658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:11.016706Z","time spent":"2.724935707s","remote":"127.0.0.1:34590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:55:13.741785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.625484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T22:55:13.741803Z","caller":"traceutil/trace.go:171","msg":"trace[2138147495] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:933; }","duration":"457.671375ms","start":"2026-02-18T22:55:13.284126Z","end":"2026-02-18T22:55:13.741798Z","steps":["trace[2138147495] 'agreement among raft nodes before linearized reading' (duration: 457.633594ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:13.741824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:13.28411Z","time spent":"457.706016ms","remote":"127.0.0.1:34740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-02-18T22:55:13.74192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"645.190485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-02-18T22:55:13.741937Z","caller":"traceutil/trace.go:171","msg":"trace[1505907381] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:933; }","duration":"645.239587ms","start":"2026-02-18T22:55:13.096692Z","end":"2026-02-18T22:55:13.741932Z","steps":["trace[1505907381] 'agreement among raft nodes before linearized reading' (duration: 645.204456ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:13.741953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:13.096682Z","time spent":"645.267287ms","remote":"127.0.0.1:34468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":445,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-02-18T22:55:14.190508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.089625ms","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-02-18T22:55:14.190587Z","caller":"traceutil/trace.go:171","msg":"trace[1884463252] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:933; }","duration":"298.173957ms","start":"2026-02-18T22:55:13.892399Z","end":"2026-02-18T22:55:14.190573Z","steps":["trace[1884463252] 'range keys from in-memory index tree' (duration: 298.079395ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:16.656242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"632.239791ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-18T22:55:16.656467Z","caller":"traceutil/trace.go:171","msg":"trace[1488795596] linearizableReadLoop","detail":"{readStateIndex:974; appliedIndex:973; }","duration":"908.569627ms","start":"2026-02-18T22:55:15.747863Z","end":"2026-02-18T22:55:16.656433Z","steps":["trace[1488795596] 'read index received' (duration: 276.205683ms)","trace[1488795596] 'applied index is now lower than readState.Index' (duration: 632.363054ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:55:16.656569Z","caller":"traceutil/trace.go:171","msg":"trace[287294178] transaction","detail":"{read_only:false; response_revision:934; number_of_response:1; }","duration":"483.711034ms","start":"2026-02-18T22:55:16.172838Z","end":"2026-02-18T22:55:16.656549Z","steps":["trace[287294178] 'process raft request' (duration: 483.50858ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:16.656656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:16.172818Z","time spent":"483.790786ms","remote":"127.0.0.1:34590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:55:17.053973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.305192423s","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-02-18T22:55:17.054074Z","caller":"traceutil/trace.go:171","msg":"trace[1064779092] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:934; }","duration":"1.305280775s","start":"2026-02-18T22:55:15.748746Z","end":"2026-02-18T22:55:17.054027Z","steps":["trace[1064779092] 'agreement among raft nodes before linearized reading' (duration: 908.106518ms)","trace[1064779092] 'range keys from in-memory index tree' (duration: 396.992593ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:55:17.054114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:15.748742Z","time spent":"1.305363297s","remote":"127.0.0.1:34590","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-02-18T22:55:17.054356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.30648911s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-18T22:55:17.054386Z","caller":"traceutil/trace.go:171","msg":"trace[631073648] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:934; }","duration":"1.306539111s","start":"2026-02-18T22:55:15.747836Z","end":"2026-02-18T22:55:17.054375Z","steps":["trace[631073648] 'agreement among raft nodes before linearized reading' (duration: 908.974496ms)","trace[631073648] 'range keys from in-memory index tree' (duration: 397.490354ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:55:17.054414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:15.747822Z","time spent":"1.306583693s","remote":"127.0.0.1:34590","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-02-18T22:55:17.054569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.305365437s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-18T22:55:17.054597Z","caller":"traceutil/trace.go:171","msg":"trace[1780641928] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:934; }","duration":"1.305397467s","start":"2026-02-18T22:55:15.749189Z","end":"2026-02-18T22:55:17.054586Z","steps":["trace[1780641928] 'agreement among raft nodes before linearized reading' (duration: 907.660758ms)","trace[1780641928] 'range keys from in-memory index tree' (duration: 397.671237ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:55:17.054596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.304965859s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-18T22:55:17.054686Z","caller":"traceutil/trace.go:171","msg":"trace[1789381135] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:934; }","duration":"1.305050811s","start":"2026-02-18T22:55:15.749594Z","end":"2026-02-18T22:55:17.054645Z","steps":["trace[1789381135] 'agreement among raft nodes before linearized reading' (duration: 907.251ms)","trace[1789381135] 'range keys from in-memory index tree' (duration: 397.640206ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:55:17.054736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:15.74959Z","time spent":"1.305132342s","remote":"127.0.0.1:34590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-18T22:55:17.054841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"861.228709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-18T22:55:17.054622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:15.749184Z","time spent":"1.305432718s","remote":"127.0.0.1:34590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-18T22:55:17.054948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.306698776s","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-02-18T22:55:17.054973Z","caller":"traceutil/trace.go:171","msg":"trace[1283919873] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:934; }","duration":"1.306730676s","start":"2026-02-18T22:55:15.748235Z","end":"2026-02-18T22:55:17.054965Z","steps":["trace[1283919873] 'agreement among raft nodes before linearized reading' (duration: 908.601988ms)","trace[1283919873] 'range keys from in-memory index tree' (duration: 398.059406ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:55:17.054878Z","caller":"traceutil/trace.go:171","msg":"trace[258764224] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:934; }","duration":"861.293539ms","start":"2026-02-18T22:55:16.193573Z","end":"2026-02-18T22:55:17.054867Z","steps":["trace[258764224] 'agreement among raft nodes before linearized reading' (duration: 463.268374ms)","trace[258764224] 'range keys from in-memory index tree' (duration: 397.985264ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:55:17.055001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:15.74823Z","time spent":"1.306764417s","remote":"127.0.0.1:34590","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-02-18T22:55:17.055024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:16.193559Z","time spent":"861.455723ms","remote":"127.0.0.1:34278","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-02-18T22:55:17.41051Z","caller":"traceutil/trace.go:171","msg":"trace[1330094006] transaction","detail":"{read_only:false; response_revision:935; number_of_response:1; }","duration":"348.925259ms","start":"2026-02-18T22:55:17.061559Z","end":"2026-02-18T22:55:17.410485Z","steps":["trace[1330094006] 'process raft request' (duration: 348.690713ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:17.410878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:17.061547Z","time spent":"349.089312ms","remote":"127.0.0.1:34590","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-02-18T22:55:17.574229Z","caller":"traceutil/trace.go:171","msg":"trace[1056859955] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"510.636313ms","start":"2026-02-18T22:55:17.06357Z","end":"2026-02-18T22:55:17.574206Z","steps":["trace[1056859955] 'process raft request' (duration: 510.546662ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:17.574396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:17.063561Z","time spent":"510.739345ms","remote":"127.0.0.1:34590","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-02-18T22:55:17.574513Z","caller":"traceutil/trace.go:171","msg":"trace[324946325] transaction","detail":"{read_only:false; response_revision:938; number_of_response:1; }","duration":"509.102631ms","start":"2026-02-18T22:55:17.065392Z","end":"2026-02-18T22:55:17.574494Z","steps":["trace[324946325] 'process raft request' (duration: 508.760674ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:17.574625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:17.065383Z","time spent":"509.179034ms","remote":"127.0.0.1:34590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:55:17.57465Z","caller":"traceutil/trace.go:171","msg":"trace[1932588577] transaction","detail":"{read_only:false; response_revision:936; number_of_response:1; }","duration":"511.144904ms","start":"2026-02-18T22:55:17.063495Z","end":"2026-02-18T22:55:17.574639Z","steps":["trace[1932588577] 'process raft request' (duration: 510.403999ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:17.574702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:17.063476Z","time spent":"511.198565ms","remote":"127.0.0.1:34590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:55:17.574816Z","caller":"traceutil/trace.go:171","msg":"trace[799133580] linearizableReadLoop","detail":"{readStateIndex:980; appliedIndex:976; }","duration":"380.338733ms","start":"2026-02-18T22:55:17.194468Z","end":"2026-02-18T22:55:17.574807Z","steps":["trace[799133580] 'read index received' (duration: 217.066075ms)","trace[799133580] 'applied index is now lower than readState.Index' (duration: 163.271958ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:55:17.574899Z","caller":"traceutil/trace.go:171","msg":"trace[393705609] transaction","detail":"{read_only:false; response_revision:939; number_of_response:1; }","duration":"508.014019ms","start":"2026-02-18T22:55:17.066877Z","end":"2026-02-18T22:55:17.574891Z","steps":["trace[393705609] 'process raft request' (duration: 507.556739ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:17.574932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:17.06687Z","time spent":"508.041849ms","remote":"127.0.0.1:34590","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-02-18T22:55:17.574992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.532857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:55:17.575012Z","caller":"traceutil/trace.go:171","msg":"trace[1152695988] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:939; }","duration":"380.583318ms","start":"2026-02-18T22:55:17.194422Z","end":"2026-02-18T22:55:17.575005Z","steps":["trace[1152695988] 'agreement among raft nodes before linearized reading' (duration: 380.552737ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:17.575028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:17.194388Z","time spent":"380.637619ms","remote":"127.0.0.1:34278","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-02-18T22:55:17.575135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.476975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:55:17.575154Z","caller":"traceutil/trace.go:171","msg":"trace[579395307] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:939; }","duration":"318.522646ms","start":"2026-02-18T22:55:17.256625Z","end":"2026-02-18T22:55:17.575148Z","steps":["trace[579395307] 'agreement among raft nodes before linearized reading' (duration: 318.493505ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:17.575171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:17.256613Z","time spent":"318.553556ms","remote":"127.0.0.1:34824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":28,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-02-18T22:55:20.083807Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":708644533934370575,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T22:55:20.584381Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":708644533934370575,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T22:55:20.817299Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.397618157s","expected-duration":"1s"} {"level":"info","ts":"2026-02-18T22:55:20.817527Z","caller":"traceutil/trace.go:171","msg":"trace[1421497746] linearizableReadLoop","detail":"{readStateIndex:981; appliedIndex:980; }","duration":"1.234502143s","start":"2026-02-18T22:55:19.583011Z","end":"2026-02-18T22:55:20.817513Z","steps":["trace[1421497746] 'read index received' (duration: 1.2343733s)","trace[1421497746] 'applied index is now lower than readState.Index' (duration: 126.783µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:55:20.817732Z","caller":"traceutil/trace.go:171","msg":"trace[916369360] transaction","detail":"{read_only:false; response_revision:940; number_of_response:1; }","duration":"1.398121778s","start":"2026-02-18T22:55:19.419605Z","end":"2026-02-18T22:55:20.817726Z","steps":["trace[916369360] 'process raft request' (duration: 1.397827922s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:20.817813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:19.419589Z","time spent":"1.398157018s","remote":"127.0.0.1:34590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:55:20.817963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.234954852s","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-02-18T22:55:20.817979Z","caller":"traceutil/trace.go:171","msg":"trace[1302576555] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:940; }","duration":"1.235002823s","start":"2026-02-18T22:55:19.582971Z","end":"2026-02-18T22:55:20.817974Z","steps":["trace[1302576555] 'agreement among raft nodes before linearized reading' (duration: 1.234926732s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:20.817992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:19.582957Z","time spent":"1.235031644s","remote":"127.0.0.1:34590","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-02-18T22:55:20.818078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.23388637s","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-02-18T22:55:20.81809Z","caller":"traceutil/trace.go:171","msg":"trace[1858746538] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:940; }","duration":"1.233927371s","start":"2026-02-18T22:55:19.584159Z","end":"2026-02-18T22:55:20.818086Z","steps":["trace[1858746538] 'agreement among raft nodes before linearized reading' (duration: 1.23388746s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:20.818102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:19.584142Z","time spent":"1.233957032s","remote":"127.0.0.1:34590","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-02-18T22:55:20.818204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.903553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.110\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-18T22:55:20.818217Z","caller":"traceutil/trace.go:171","msg":"trace[1486583171] range","detail":"{range_begin:/registry/masterleases/162.253.55.110; range_end:; response_count:1; response_revision:940; }","duration":"288.944284ms","start":"2026-02-18T22:55:20.529269Z","end":"2026-02-18T22:55:20.818214Z","steps":["trace[1486583171] 'agreement among raft nodes before linearized reading' (duration: 288.906113ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:20.818359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"615.187044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T22:55:20.818402Z","caller":"traceutil/trace.go:171","msg":"trace[839417589] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:940; }","duration":"615.251335ms","start":"2026-02-18T22:55:20.203147Z","end":"2026-02-18T22:55:20.818398Z","steps":["trace[839417589] 'agreement among raft nodes before linearized reading' (duration: 615.192664ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:20.818425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:20.203136Z","time spent":"615.284926ms","remote":"127.0.0.1:34796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":4,"response size":30,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"warn","ts":"2026-02-18T22:55:20.819014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"626.108934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:55:20.819035Z","caller":"traceutil/trace.go:171","msg":"trace[964739244] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:940; }","duration":"626.153555ms","start":"2026-02-18T22:55:20.19287Z","end":"2026-02-18T22:55:20.819024Z","steps":["trace[964739244] 'agreement among raft nodes before linearized reading' (duration: 626.122864ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:20.819047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:20.192853Z","time spent":"626.191215ms","remote":"127.0.0.1:34272","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-02-18T22:55:20.819132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"950.992835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:55:20.819144Z","caller":"traceutil/trace.go:171","msg":"trace[1541567279] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:940; }","duration":"951.040195ms","start":"2026-02-18T22:55:19.8681Z","end":"2026-02-18T22:55:20.81914Z","steps":["trace[1541567279] 'agreement among raft nodes before linearized reading' (duration: 951.020395ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:20.819155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:19.868085Z","time spent":"951.066817ms","remote":"127.0.0.1:34278","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-02-18T22:55:20.81925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.233065982s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-18T22:55:20.819262Z","caller":"traceutil/trace.go:171","msg":"trace[675973059] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:940; }","duration":"1.233091323s","start":"2026-02-18T22:55:19.586167Z","end":"2026-02-18T22:55:20.819258Z","steps":["trace[675973059] 'agreement among raft nodes before linearized reading' (duration: 1.233047512s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:20.819273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:19.58616Z","time spent":"1.233109973s","remote":"127.0.0.1:34590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-18T22:55:20.819359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.234104464s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-18T22:55:20.819371Z","caller":"traceutil/trace.go:171","msg":"trace[2118752812] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:940; }","duration":"1.234130874s","start":"2026-02-18T22:55:19.585236Z","end":"2026-02-18T22:55:20.819367Z","steps":["trace[2118752812] 'agreement among raft nodes before linearized reading' (duration: 1.234084893s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:20.819381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:19.585229Z","time spent":"1.234149644s","remote":"127.0.0.1:34590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-18T22:55:23.05043Z","caller":"traceutil/trace.go:171","msg":"trace[419655115] linearizableReadLoop","detail":"{readStateIndex:988; appliedIndex:987; }","duration":"126.788747ms","start":"2026-02-18T22:55:22.923625Z","end":"2026-02-18T22:55:23.050413Z","steps":["trace[419655115] 'read index received' (duration: 126.655504ms)","trace[419655115] 'applied index is now lower than readState.Index' (duration: 132.593µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:55:23.050652Z","caller":"traceutil/trace.go:171","msg":"trace[6373658] transaction","detail":"{read_only:false; response_revision:946; number_of_response:1; }","duration":"223.540091ms","start":"2026-02-18T22:55:22.827104Z","end":"2026-02-18T22:55:23.050645Z","steps":["trace[6373658] 'process raft request' (duration: 223.222224ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:23.050828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.191245ms","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-02-18T22:55:23.050849Z","caller":"traceutil/trace.go:171","msg":"trace[1735634977] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:946; }","duration":"127.254467ms","start":"2026-02-18T22:55:22.923587Z","end":"2026-02-18T22:55:23.050842Z","steps":["trace[1735634977] 'agreement among raft nodes before linearized reading' (duration: 127.167545ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:23.050958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.95902ms","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-02-18T22:55:23.050974Z","caller":"traceutil/trace.go:171","msg":"trace[1061196880] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:946; }","duration":"126.007761ms","start":"2026-02-18T22:55:22.924961Z","end":"2026-02-18T22:55:23.050969Z","steps":["trace[1061196880] 'agreement among raft nodes before linearized reading' (duration: 125.95955ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:23.051102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.564429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-18T22:55:23.051119Z","caller":"traceutil/trace.go:171","msg":"trace[2099016676] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:946; }","duration":"122.602449ms","start":"2026-02-18T22:55:22.928512Z","end":"2026-02-18T22:55:23.051114Z","steps":["trace[2099016676] 'agreement among raft nodes before linearized reading' (duration: 122.555758ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:23.051234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.58269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-18T22:55:23.051249Z","caller":"traceutil/trace.go:171","msg":"trace[483825340] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:946; }","duration":"123.6181ms","start":"2026-02-18T22:55:22.927627Z","end":"2026-02-18T22:55:23.051245Z","steps":["trace[483825340] 'agreement among raft nodes before linearized reading' (duration: 123.575209ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:55:23.727917Z","caller":"traceutil/trace.go:171","msg":"trace[1309036117] linearizableReadLoop","detail":"{readStateIndex:992; appliedIndex:988; }","duration":"534.218792ms","start":"2026-02-18T22:55:23.193684Z","end":"2026-02-18T22:55:23.727902Z","steps":["trace[1309036117] 'read index received' (duration: 443.20722ms)","trace[1309036117] 'applied index is now lower than readState.Index' (duration: 91.010962ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:55:23.72839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.649521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:55:23.72848Z","caller":"traceutil/trace.go:171","msg":"trace[750997677] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:950; }","duration":"534.814714ms","start":"2026-02-18T22:55:23.193654Z","end":"2026-02-18T22:55:23.728469Z","steps":["trace[750997677] 'agreement among raft nodes before linearized reading' (duration: 534.63262ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:23.728562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:23.193642Z","time spent":"534.910806ms","remote":"127.0.0.1:34278","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-02-18T22:55:23.728817Z","caller":"traceutil/trace.go:171","msg":"trace[1996450535] transaction","detail":"{read_only:false; response_revision:947; number_of_response:1; }","duration":"673.027983ms","start":"2026-02-18T22:55:23.055779Z","end":"2026-02-18T22:55:23.728807Z","steps":["trace[1996450535] 'process raft request' (duration: 581.101432ms)","trace[1996450535] 'compare' (duration: 90.135233ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:55:23.728987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:23.055765Z","time spent":"673.175516ms","remote":"127.0.0.1:34590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:55:23.729312Z","caller":"traceutil/trace.go:171","msg":"trace[917904772] transaction","detail":"{read_only:false; response_revision:948; number_of_response:1; }","duration":"673.357929ms","start":"2026-02-18T22:55:23.055946Z","end":"2026-02-18T22:55:23.729304Z","steps":["trace[917904772] 'process raft request' (duration: 671.881978ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:23.729516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:23.055941Z","time spent":"673.550573ms","remote":"127.0.0.1:34590","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-02-18T22:55:23.730834Z","caller":"traceutil/trace.go:171","msg":"trace[120824606] transaction","detail":"{read_only:false; response_revision:949; number_of_response:1; }","duration":"673.192237ms","start":"2026-02-18T22:55:23.057632Z","end":"2026-02-18T22:55:23.730824Z","steps":["trace[120824606] 'process raft request' (duration: 670.230344ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:23.731008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:23.057624Z","time spent":"673.333001ms","remote":"127.0.0.1:34590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:55:23.731204Z","caller":"traceutil/trace.go:171","msg":"trace[353661466] transaction","detail":"{read_only:false; response_revision:950; number_of_response:1; }","duration":"673.030493ms","start":"2026-02-18T22:55:23.058166Z","end":"2026-02-18T22:55:23.731197Z","steps":["trace[353661466] 'process raft request' (duration: 669.716233ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:23.731403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:23.058158Z","time spent":"673.157575ms","remote":"127.0.0.1:34590","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-02-18T22:55:24.858596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"966.279906ms","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-02-18T22:55:24.858709Z","caller":"traceutil/trace.go:171","msg":"trace[32911292] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:950; }","duration":"966.402369ms","start":"2026-02-18T22:55:23.892296Z","end":"2026-02-18T22:55:24.858698Z","steps":["trace[32911292] 'range keys from in-memory index tree' (duration: 966.267876ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:24.859169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"555.607083ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T22:55:24.859198Z","caller":"traceutil/trace.go:171","msg":"trace[1803910326] linearizableReadLoop","detail":"{readStateIndex:993; appliedIndex:992; }","duration":"663.853079ms","start":"2026-02-18T22:55:24.19534Z","end":"2026-02-18T22:55:24.859193Z","steps":["trace[1803910326] 'read index received' (duration: 108.099062ms)","trace[1803910326] 'applied index is now lower than readState.Index' (duration: 555.753597ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:55:24.859298Z","caller":"traceutil/trace.go:171","msg":"trace[1663173758] transaction","detail":"{read_only:false; response_revision:951; number_of_response:1; }","duration":"985.013882ms","start":"2026-02-18T22:55:23.874278Z","end":"2026-02-18T22:55:24.859292Z","steps":["trace[1663173758] 'process raft request' (duration: 429.238265ms)","trace[1663173758] 'compare' (duration: 555.47888ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:55:24.859327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:23.874246Z","time spent":"985.066493ms","remote":"127.0.0.1:34590","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-02-18T22:55:24.860235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.2875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T22:55:24.860301Z","caller":"traceutil/trace.go:171","msg":"trace[159149005] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:951; }","duration":"209.380752ms","start":"2026-02-18T22:55:24.650904Z","end":"2026-02-18T22:55:24.860285Z","steps":["trace[159149005] 'agreement among raft nodes before linearized reading' (duration: 209.242589ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:24.860607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"665.264849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:55:24.86065Z","caller":"traceutil/trace.go:171","msg":"trace[693855497] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:951; }","duration":"665.34471ms","start":"2026-02-18T22:55:24.195296Z","end":"2026-02-18T22:55:24.86064Z","steps":["trace[693855497] 'agreement among raft nodes before linearized reading' (duration: 665.282349ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:24.860678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:24.195275Z","time spent":"665.395302ms","remote":"127.0.0.1:34278","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-02-18T22:55:25.236329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.904929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-18T22:55:25.236377Z","caller":"traceutil/trace.go:171","msg":"trace[239689375] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:951; }","duration":"181.025722ms","start":"2026-02-18T22:55:25.055341Z","end":"2026-02-18T22:55:25.236366Z","steps":["trace[239689375] 'range keys from in-memory index tree' (duration: 180.789467ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:55:27.175153Z","caller":"traceutil/trace.go:171","msg":"trace[721845529] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"442.872212ms","start":"2026-02-18T22:55:26.732242Z","end":"2026-02-18T22:55:27.175114Z","steps":["trace[721845529] 'process raft request' (duration: 442.733229ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:27.175321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:26.73223Z","time spent":"442.997236ms","remote":"127.0.0.1:34590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:55:32.216201Z","caller":"traceutil/trace.go:171","msg":"trace[854756823] linearizableReadLoop","detail":"{readStateIndex:1042; appliedIndex:1041; }","duration":"247.100488ms","start":"2026-02-18T22:55:31.969078Z","end":"2026-02-18T22:55:32.216179Z","steps":["trace[854756823] 'read index received' (duration: 246.939665ms)","trace[854756823] 'applied index is now lower than readState.Index' (duration: 159.943µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:55:32.216447Z","caller":"traceutil/trace.go:171","msg":"trace[129194614] transaction","detail":"{read_only:false; response_revision:997; number_of_response:1; }","duration":"254.976174ms","start":"2026-02-18T22:55:31.961457Z","end":"2026-02-18T22:55:32.216433Z","steps":["trace[129194614] 'process raft request' (duration: 254.612907ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:32.217589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.491797ms","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-02-18T22:55:32.217639Z","caller":"traceutil/trace.go:171","msg":"trace[1900481318] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:997; }","duration":"248.565138ms","start":"2026-02-18T22:55:31.969061Z","end":"2026-02-18T22:55:32.217626Z","steps":["trace[1900481318] 'agreement among raft nodes before linearized reading' (duration: 248.414115ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:32.217818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.941341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-18T22:55:32.217851Z","caller":"traceutil/trace.go:171","msg":"trace[2062671303] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:997; }","duration":"208.003793ms","start":"2026-02-18T22:55:32.00984Z","end":"2026-02-18T22:55:32.217844Z","steps":["trace[2062671303] 'agreement among raft nodes before linearized reading' (duration: 207.923411ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:32.218144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.575017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-18T22:55:32.21818Z","caller":"traceutil/trace.go:171","msg":"trace[1023299094] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:997; }","duration":"174.640867ms","start":"2026-02-18T22:55:32.043531Z","end":"2026-02-18T22:55:32.218172Z","steps":["trace[1023299094] 'agreement among raft nodes before linearized reading' (duration: 174.558766ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:55:35.390323Z","caller":"traceutil/trace.go:171","msg":"trace[1316949068] transaction","detail":"{read_only:false; response_revision:1013; number_of_response:1; }","duration":"171.582173ms","start":"2026-02-18T22:55:35.218719Z","end":"2026-02-18T22:55:35.390301Z","steps":["trace[1316949068] 'process raft request' (duration: 171.250706ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:36.585138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.682619ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-18T22:55:36.58529Z","caller":"traceutil/trace.go:171","msg":"trace[2096159801] linearizableReadLoop","detail":"{readStateIndex:1062; appliedIndex:1061; }","duration":"391.433646ms","start":"2026-02-18T22:55:36.193839Z","end":"2026-02-18T22:55:36.585273Z","steps":["trace[2096159801] 'read index received' (duration: 37.560973ms)","trace[2096159801] 'applied index is now lower than readState.Index' (duration: 353.871163ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:55:36.585444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.580459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:55:36.585476Z","caller":"traceutil/trace.go:171","msg":"trace[682050647] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1016; }","duration":"391.64816ms","start":"2026-02-18T22:55:36.193815Z","end":"2026-02-18T22:55:36.585463Z","steps":["trace[682050647] 'agreement among raft nodes before linearized reading' (duration: 391.570558ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:36.585512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:36.193803Z","time spent":"391.700261ms","remote":"127.0.0.1:34278","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-02-18T22:55:36.586495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.193845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-18T22:55:36.586532Z","caller":"traceutil/trace.go:171","msg":"trace[1114231634] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1016; }","duration":"358.270326ms","start":"2026-02-18T22:55:36.228252Z","end":"2026-02-18T22:55:36.586523Z","steps":["trace[1114231634] 'agreement among raft nodes before linearized reading' (duration: 358.098423ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:36.586577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:36.228242Z","time spent":"358.327247ms","remote":"127.0.0.1:34590","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-02-18T22:55:36.586952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.444987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-02-18T22:55:36.586987Z","caller":"traceutil/trace.go:171","msg":"trace[2062867554] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1016; }","duration":"212.481418ms","start":"2026-02-18T22:55:36.374495Z","end":"2026-02-18T22:55:36.586976Z","steps":["trace[2062867554] 'agreement among raft nodes before linearized reading' (duration: 212.323024ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:36.587297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.034673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:55:36.587394Z","caller":"traceutil/trace.go:171","msg":"trace[244026881] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1016; }","duration":"297.143785ms","start":"2026-02-18T22:55:36.290228Z","end":"2026-02-18T22:55:36.587371Z","steps":["trace[244026881] 'agreement among raft nodes before linearized reading' (duration: 297.037893ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:36.587569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.104375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-18T22:55:36.587588Z","caller":"traceutil/trace.go:171","msg":"trace[1889789933] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1016; }","duration":"333.132415ms","start":"2026-02-18T22:55:36.25445Z","end":"2026-02-18T22:55:36.587583Z","steps":["trace[1889789933] 'agreement among raft nodes before linearized reading' (duration: 333.069374ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:36.587606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:36.254448Z","time spent":"333.153086ms","remote":"127.0.0.1:34590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-18T22:55:36.587805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.357391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-18T22:55:36.587825Z","caller":"traceutil/trace.go:171","msg":"trace[2020550982] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1016; }","duration":"333.380021ms","start":"2026-02-18T22:55:36.25444Z","end":"2026-02-18T22:55:36.58782Z","steps":["trace[2020550982] 'agreement among raft nodes before linearized reading' (duration: 333.32191ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:36.587843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:36.254423Z","time spent":"333.414942ms","remote":"127.0.0.1:34590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-18T22:55:36.588439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.740697ms","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-02-18T22:55:36.588464Z","caller":"traceutil/trace.go:171","msg":"trace[777036912] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1016; }","duration":"341.799658ms","start":"2026-02-18T22:55:36.246658Z","end":"2026-02-18T22:55:36.588458Z","steps":["trace[777036912] 'agreement among raft nodes before linearized reading' (duration: 341.300108ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:36.588483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:36.246654Z","time spent":"341.824839ms","remote":"127.0.0.1:34590","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":"warn","ts":"2026-02-18T22:55:36.588662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.698049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T22:55:36.588684Z","caller":"traceutil/trace.go:171","msg":"trace[1245593005] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1016; }","duration":"344.73523ms","start":"2026-02-18T22:55:36.243943Z","end":"2026-02-18T22:55:36.588679Z","steps":["trace[1245593005] 'agreement among raft nodes before linearized reading' (duration: 344.689699ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:36.588714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:36.243938Z","time spent":"344.770921ms","remote":"127.0.0.1:34320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":11,"response size":30,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"warn","ts":"2026-02-18T22:55:38.854204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.953526ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-18T22:55:38.854296Z","caller":"traceutil/trace.go:171","msg":"trace[473643891] linearizableReadLoop","detail":"{readStateIndex:1080; appliedIndex:1079; }","duration":"250.748695ms","start":"2026-02-18T22:55:38.603524Z","end":"2026-02-18T22:55:38.854273Z","steps":["trace[473643891] 'read index received' (duration: 118.604535ms)","trace[473643891] 'applied index is now lower than readState.Index' (duration: 132.14303ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:55:38.854439Z","caller":"traceutil/trace.go:171","msg":"trace[2089369610] transaction","detail":"{read_only:false; response_revision:1034; number_of_response:1; }","duration":"328.926315ms","start":"2026-02-18T22:55:38.5255Z","end":"2026-02-18T22:55:38.854427Z","steps":["trace[2089369610] 'process raft request' (duration: 196.678803ms)","trace[2089369610] 'compare' (duration: 131.606829ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:55:38.85449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:38.525483Z","time spent":"328.984377ms","remote":"127.0.0.1:34590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":512,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-18T22:55:38.854772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.241795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-18T22:55:38.854801Z","caller":"traceutil/trace.go:171","msg":"trace[559295691] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1034; }","duration":"251.299976ms","start":"2026-02-18T22:55:38.603492Z","end":"2026-02-18T22:55:38.854792Z","steps":["trace[559295691] 'agreement among raft nodes before linearized reading' (duration: 251.159313ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:38.854975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.634989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-18T22:55:38.855064Z","caller":"traceutil/trace.go:171","msg":"trace[1699562758] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1034; }","duration":"246.673029ms","start":"2026-02-18T22:55:38.60832Z","end":"2026-02-18T22:55:38.854993Z","steps":["trace[1699562758] 'agreement among raft nodes before linearized reading' (duration: 246.601098ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:38.855246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.698149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:55:38.855273Z","caller":"traceutil/trace.go:171","msg":"trace[1079856343] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:0; response_revision:1034; }","duration":"237.736751ms","start":"2026-02-18T22:55:38.617527Z","end":"2026-02-18T22:55:38.855264Z","steps":["trace[1079856343] 'agreement among raft nodes before linearized reading' (duration: 237.69675ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:38.856199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.889256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-18T22:55:38.856222Z","caller":"traceutil/trace.go:171","msg":"trace[1113959894] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1034; }","duration":"239.972498ms","start":"2026-02-18T22:55:38.616243Z","end":"2026-02-18T22:55:38.856216Z","steps":["trace[1113959894] 'agreement among raft nodes before linearized reading' (duration: 239.905936ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:38.856403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.19372ms","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-02-18T22:55:38.856423Z","caller":"traceutil/trace.go:171","msg":"trace[1366514377] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1034; }","duration":"247.227561ms","start":"2026-02-18T22:55:38.60919Z","end":"2026-02-18T22:55:38.856417Z","steps":["trace[1366514377] 'agreement among raft nodes before linearized reading' (duration: 247.150269ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:05.375268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.441765ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T22:56:05.375403Z","caller":"traceutil/trace.go:171","msg":"trace[1570418128] linearizableReadLoop","detail":"{readStateIndex:1206; appliedIndex:1205; }","duration":"184.175931ms","start":"2026-02-18T22:56:05.191203Z","end":"2026-02-18T22:56:05.375379Z","steps":["trace[1570418128] 'read index received' (duration: 27.478699ms)","trace[1570418128] 'applied index is now lower than readState.Index' (duration: 156.696062ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:56:05.37568Z","caller":"traceutil/trace.go:171","msg":"trace[66193613] transaction","detail":"{read_only:false; response_revision:1155; number_of_response:1; }","duration":"195.912543ms","start":"2026-02-18T22:56:05.179753Z","end":"2026-02-18T22:56:05.375665Z","steps":["trace[66193613] 'process raft request' (duration: 38.966496ms)","trace[66193613] 'compare' (duration: 156.340383ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:56:05.375855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.65215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:56:05.375883Z","caller":"traceutil/trace.go:171","msg":"trace[1634475199] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1155; }","duration":"184.705992ms","start":"2026-02-18T22:56:05.191168Z","end":"2026-02-18T22:56:05.375874Z","steps":["trace[1634475199] 'agreement among raft nodes before linearized reading' (duration: 184.657151ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:05.37611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.814976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-18T22:56:05.376144Z","caller":"traceutil/trace.go:171","msg":"trace[2017111913] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1155; }","duration":"172.886957ms","start":"2026-02-18T22:56:05.203249Z","end":"2026-02-18T22:56:05.376136Z","steps":["trace[2017111913] 'agreement among raft nodes before linearized reading' (duration: 172.770185ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:05.376378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.226795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-02-18T22:56:05.376411Z","caller":"traceutil/trace.go:171","msg":"trace[857752292] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1155; }","duration":"132.287537ms","start":"2026-02-18T22:56:05.244116Z","end":"2026-02-18T22:56:05.376403Z","steps":["trace[857752292] 'agreement among raft nodes before linearized reading' (duration: 132.207495ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:05.376568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.324679ms","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-02-18T22:56:05.376598Z","caller":"traceutil/trace.go:171","msg":"trace[2016794620] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1155; }","duration":"134.37963ms","start":"2026-02-18T22:56:05.242211Z","end":"2026-02-18T22:56:05.376591Z","steps":["trace[2016794620] 'agreement among raft nodes before linearized reading' (duration: 134.301978ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:56:05.551008Z","caller":"traceutil/trace.go:171","msg":"trace[2132497040] transaction","detail":"{read_only:false; response_revision:1157; number_of_response:1; }","duration":"170.226332ms","start":"2026-02-18T22:56:05.380765Z","end":"2026-02-18T22:56:05.550991Z","steps":["trace[2132497040] 'process raft request' (duration: 109.305171ms)","trace[2132497040] 'compare' (duration: 60.821829ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:56:05.551145Z","caller":"traceutil/trace.go:171","msg":"trace[1825593820] transaction","detail":"{read_only:false; response_revision:1158; number_of_response:1; }","duration":"168.69693ms","start":"2026-02-18T22:56:05.382442Z","end":"2026-02-18T22:56:05.551139Z","steps":["trace[1825593820] 'process raft request' (duration: 168.528577ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:14.097005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.526711ms","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-02-18T22:56:14.097123Z","caller":"traceutil/trace.go:171","msg":"trace[1908900236] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1192; }","duration":"204.655704ms","start":"2026-02-18T22:56:13.892453Z","end":"2026-02-18T22:56:14.097108Z","steps":["trace[1908900236] 'range keys from in-memory index tree' (duration: 204.513291ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:14.330531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.739135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T22:56:14.333324Z","caller":"traceutil/trace.go:171","msg":"trace[788052867] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:1193; }","duration":"102.850007ms","start":"2026-02-18T22:56:14.227743Z","end":"2026-02-18T22:56:14.330593Z","steps":["trace[788052867] 'count revisions from in-memory index tree' (duration: 102.663083ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:22.303342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.31217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:56:22.30379Z","caller":"traceutil/trace.go:171","msg":"trace[1528589388] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1227; }","duration":"107.81124ms","start":"2026-02-18T22:56:22.195955Z","end":"2026-02-18T22:56:22.303766Z","steps":["trace[1528589388] 'range keys from in-memory index tree' (duration: 107.221668ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:57:24.098575Z","caller":"traceutil/trace.go:171","msg":"trace[2003999572] transaction","detail":"{read_only:false; response_revision:1474; number_of_response:1; }","duration":"323.930249ms","start":"2026-02-18T22:57:23.774628Z","end":"2026-02-18T22:57:24.098558Z","steps":["trace[2003999572] 'process raft request' (duration: 323.237715ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:57:24.09869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:57:23.774617Z","time spent":"324.020261ms","remote":"127.0.0.1:34590","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-02-18T22:58:45.252234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.659941ms","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-02-18T22:58:45.252333Z","caller":"traceutil/trace.go:171","msg":"trace[1859109940] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1832; }","duration":"275.787874ms","start":"2026-02-18T22:58:44.976527Z","end":"2026-02-18T22:58:45.252315Z","steps":["trace[1859109940] 'range keys from in-memory index tree' (duration: 275.534459ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:58:45.252402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.132918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-18T22:58:45.252485Z","caller":"traceutil/trace.go:171","msg":"trace[291948400] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1832; }","duration":"199.21407ms","start":"2026-02-18T22:58:45.053225Z","end":"2026-02-18T22:58:45.252439Z","steps":["trace[291948400] 'range keys from in-memory index tree' (duration: 198.648307ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:58:50.679747Z","caller":"traceutil/trace.go:171","msg":"trace[1879677300] transaction","detail":"{read_only:false; response_revision:1856; number_of_response:1; }","duration":"120.644883ms","start":"2026-02-18T22:58:50.559081Z","end":"2026-02-18T22:58:50.679726Z","steps":["trace[1879677300] 'process raft request' (duration: 113.533922ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:58:50.680125Z","caller":"traceutil/trace.go:171","msg":"trace[1383562459] transaction","detail":"{read_only:false; response_revision:1857; number_of_response:1; }","duration":"119.788106ms","start":"2026-02-18T22:58:50.560329Z","end":"2026-02-18T22:58:50.680117Z","steps":["trace[1383562459] 'process raft request' (duration: 119.360667ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:58:51.611599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.542992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:58:51.611667Z","caller":"traceutil/trace.go:171","msg":"trace[1155403479] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1862; }","duration":"418.667715ms","start":"2026-02-18T22:58:51.192984Z","end":"2026-02-18T22:58:51.611652Z","steps":["trace[1155403479] 'range keys from in-memory index tree' (duration: 418.42535ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:58:51.611689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:58:51.192965Z","time spent":"418.720165ms","remote":"127.0.0.1:34278","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-02-18T22:58:51.613292Z","caller":"traceutil/trace.go:171","msg":"trace[1237510684] transaction","detail":"{read_only:false; response_revision:1863; number_of_response:1; }","duration":"389.631962ms","start":"2026-02-18T22:58:51.223645Z","end":"2026-02-18T22:58:51.613277Z","steps":["trace[1237510684] 'process raft request' (duration: 389.265905ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:58:51.613436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:58:51.223626Z","time spent":"389.719354ms","remote":"127.0.0.1:34590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:58:51.613102Z","caller":"traceutil/trace.go:171","msg":"trace[2034492195] linearizableReadLoop","detail":"{readStateIndex:1949; appliedIndex:1948; }","duration":"329.30497ms","start":"2026-02-18T22:58:51.283778Z","end":"2026-02-18T22:58:51.613083Z","steps":["trace[2034492195] 'read index received' (duration: 329.062535ms)","trace[2034492195] 'applied index is now lower than readState.Index' (duration: 241.525µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:58:51.614114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.329591ms","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-02-18T22:58:51.614258Z","caller":"traceutil/trace.go:171","msg":"trace[351824208] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1863; }","duration":"330.497016ms","start":"2026-02-18T22:58:51.283747Z","end":"2026-02-18T22:58:51.614244Z","steps":["trace[351824208] 'agreement among raft nodes before linearized reading' (duration: 330.062786ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:58:51.614316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:58:51.283735Z","time spent":"330.569398ms","remote":"127.0.0.1:34590","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-02-18T22:58:51.615678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.002214ms","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-02-18T22:58:51.615785Z","caller":"traceutil/trace.go:171","msg":"trace[2099406570] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1863; }","duration":"329.137417ms","start":"2026-02-18T22:58:51.286639Z","end":"2026-02-18T22:58:51.615776Z","steps":["trace[2099406570] 'agreement among raft nodes before linearized reading' (duration: 328.237658ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:58:51.615869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:58:51.286623Z","time spent":"329.237409ms","remote":"127.0.0.1:34590","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-02-18T22:58:51.823999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.53703ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T22:58:51.82414Z","caller":"traceutil/trace.go:171","msg":"trace[182191809] transaction","detail":"{read_only:false; response_revision:1865; number_of_response:1; }","duration":"205.850949ms","start":"2026-02-18T22:58:51.61827Z","end":"2026-02-18T22:58:51.824121Z","steps":["trace[182191809] 'process raft request' (duration: 94.109034ms)","trace[182191809] 'compare' (duration: 111.387147ms)"],"step_count":2}