{"level":"warn","ts":"2026-02-18T22:55:31.075058Z","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:55:31.075192Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.153:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.153:2380","--initial-cluster=instance=https://199.204.45.153:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.153:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.153: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:55:31.075283Z","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:55:31.075297Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.153:2380"]} {"level":"info","ts":"2026-02-18T22:55:31.075325Z","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:55:31.076045Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.153:2379"]} {"level":"info","ts":"2026-02-18T22:55:31.07614Z","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://199.204.45.153:2380"],"listen-peer-urls":["https://199.204.45.153:2380"],"advertise-client-urls":["https://199.204.45.153:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.153:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.153: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:55:31.083177Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.747478ms"} {"level":"info","ts":"2026-02-18T22:55:31.11183Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"355195d5aae85944","cluster-id":"e424441517bc0b32"} {"level":"info","ts":"2026-02-18T22:55:31.111944Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 switched to configuration voters=()"} {"level":"info","ts":"2026-02-18T22:55:31.111972Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 became follower at term 0"} {"level":"info","ts":"2026-02-18T22:55:31.111982Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 355195d5aae85944 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-18T22:55:31.111989Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 became follower at term 1"} {"level":"info","ts":"2026-02-18T22:55:31.112032Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 switched to configuration voters=(3842016702051670340)"} {"level":"warn","ts":"2026-02-18T22:55:31.127548Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-18T22:55:31.13195Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-18T22:55:31.139362Z","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:55:31.149541Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"355195d5aae85944","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-18T22:55:31.151767Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"355195d5aae85944","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-18T22:55:31.151853Z","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:55:31.151866Z","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:55:31.151877Z","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:55:31.18393Z","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:55:31.184196Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"355195d5aae85944","initial-advertise-peer-urls":["https://199.204.45.153:2380"],"listen-peer-urls":["https://199.204.45.153:2380"],"advertise-client-urls":["https://199.204.45.153:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.153:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-18T22:55:31.184229Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-18T22:55:31.18446Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 switched to configuration voters=(3842016702051670340)"} {"level":"info","ts":"2026-02-18T22:55:31.184563Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e424441517bc0b32","local-member-id":"355195d5aae85944","added-peer-id":"355195d5aae85944","added-peer-peer-urls":["https://199.204.45.153:2380"]} {"level":"info","ts":"2026-02-18T22:55:31.184678Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.153:2380"} {"level":"info","ts":"2026-02-18T22:55:31.184693Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.153:2380"} {"level":"info","ts":"2026-02-18T22:55:31.213189Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 is starting a new election at term 1"} {"level":"info","ts":"2026-02-18T22:55:31.213294Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-18T22:55:31.213327Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 received MsgPreVoteResp from 355195d5aae85944 at term 1"} {"level":"info","ts":"2026-02-18T22:55:31.213362Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 became candidate at term 2"} {"level":"info","ts":"2026-02-18T22:55:31.213369Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 received MsgVoteResp from 355195d5aae85944 at term 2"} {"level":"info","ts":"2026-02-18T22:55:31.213377Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 became leader at term 2"} {"level":"info","ts":"2026-02-18T22:55:31.213386Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 355195d5aae85944 elected leader 355195d5aae85944 at term 2"} {"level":"info","ts":"2026-02-18T22:55:31.216206Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"355195d5aae85944","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.153:2379]}","request-path":"/0/members/355195d5aae85944/attributes","cluster-id":"e424441517bc0b32","publish-timeout":"7s"} {"level":"info","ts":"2026-02-18T22:55:31.216334Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-18T22:55:31.216772Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-18T22:55:31.217138Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-18T22:55:31.217156Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-18T22:55:31.22068Z","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:55:31.248619Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T22:55:31.25098Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e424441517bc0b32","local-member-id":"355195d5aae85944","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T22:55:31.251075Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T22:55:31.251105Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T22:55:31.254367Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.153:2379"} {"level":"info","ts":"2026-02-18T22:55:39.996908Z","caller":"traceutil/trace.go:171","msg":"trace[868247193] transaction","detail":"{read_only:false; response_revision:335; number_of_response:1; }","duration":"120.220801ms","start":"2026-02-18T22:55:39.876646Z","end":"2026-02-18T22:55:39.996867Z","steps":["trace[868247193] 'process raft request' (duration: 96.888892ms)","trace[868247193] 'compare' (duration: 23.090094ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:55:41.346344Z","caller":"traceutil/trace.go:171","msg":"trace[594918593] transaction","detail":"{read_only:false; response_revision:345; number_of_response:1; }","duration":"120.757481ms","start":"2026-02-18T22:55:41.225559Z","end":"2026-02-18T22:55:41.346316Z","steps":["trace[594918593] 'process raft request' (duration: 83.834842ms)","trace[594918593] 'compare' (duration: 36.608073ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:55:41.347307Z","caller":"traceutil/trace.go:171","msg":"trace[128587647] linearizableReadLoop","detail":"{readStateIndex:353; appliedIndex:352; }","duration":"119.768902ms","start":"2026-02-18T22:55:41.226376Z","end":"2026-02-18T22:55:41.346145Z","steps":["trace[128587647] 'read index received' (duration: 82.965095ms)","trace[128587647] 'applied index is now lower than readState.Index' (duration: 36.802407ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:55:41.347116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.69629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-02-18T22:55:41.3478Z","caller":"traceutil/trace.go:171","msg":"trace[234185027] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:345; }","duration":"121.411225ms","start":"2026-02-18T22:55:41.226371Z","end":"2026-02-18T22:55:41.347782Z","steps":["trace[234185027] 'agreement among raft nodes before linearized reading' (duration: 120.590938ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:55:41.567027Z","caller":"traceutil/trace.go:171","msg":"trace[1226425922] transaction","detail":"{read_only:false; response_revision:349; number_of_response:1; }","duration":"133.065065ms","start":"2026-02-18T22:55:41.433931Z","end":"2026-02-18T22:55:41.566996Z","steps":["trace[1226425922] 'process raft request' (duration: 108.267455ms)","trace[1226425922] 'compare' (duration: 24.648857ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:55:41.788599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.851324ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-18T22:55:41.788799Z","caller":"traceutil/trace.go:171","msg":"trace[909356684] linearizableReadLoop","detail":"{readStateIndex:359; appliedIndex:357; }","duration":"206.483494ms","start":"2026-02-18T22:55:41.582297Z","end":"2026-02-18T22:55:41.788781Z","steps":["trace[909356684] 'read index received' (duration: 51.251794ms)","trace[909356684] 'applied index is now lower than readState.Index' (duration: 155.23049ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:55:41.790758Z","caller":"traceutil/trace.go:171","msg":"trace[271595060] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"207.903884ms","start":"2026-02-18T22:55:41.58283Z","end":"2026-02-18T22:55:41.790733Z","steps":["trace[271595060] 'process raft request' (duration: 205.910942ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:55:41.791182Z","caller":"traceutil/trace.go:171","msg":"trace[832981687] transaction","detail":"{read_only:false; response_revision:351; number_of_response:1; }","duration":"208.842902ms","start":"2026-02-18T22:55:41.582231Z","end":"2026-02-18T22:55:41.791074Z","steps":["trace[832981687] 'process raft request' (duration: 102.898335ms)","trace[832981687] 'compare' (duration: 102.723302ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:55:41.792094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.780762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-02-18T22:55:41.792165Z","caller":"traceutil/trace.go:171","msg":"trace[159389801] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:353; }","duration":"209.872243ms","start":"2026-02-18T22:55:41.582263Z","end":"2026-02-18T22:55:41.792135Z","steps":["trace[159389801] 'agreement among raft nodes before linearized reading' (duration: 208.72244ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:41.793629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.576624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-02-18T22:55:41.793687Z","caller":"traceutil/trace.go:171","msg":"trace[583436362] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:353; }","duration":"165.657285ms","start":"2026-02-18T22:55:41.628006Z","end":"2026-02-18T22:55:41.793663Z","steps":["trace[583436362] 'agreement among raft nodes before linearized reading' (duration: 165.261166ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:55:41.794001Z","caller":"traceutil/trace.go:171","msg":"trace[205202613] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"211.360754ms","start":"2026-02-18T22:55:41.582618Z","end":"2026-02-18T22:55:41.793979Z","steps":["trace[205202613] 'process raft request' (duration: 206.072775ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:41.796026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.516834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-02-18T22:55:41.796083Z","caller":"traceutil/trace.go:171","msg":"trace[743017701] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:353; }","duration":"117.610507ms","start":"2026-02-18T22:55:41.67845Z","end":"2026-02-18T22:55:41.796061Z","steps":["trace[743017701] 'agreement among raft nodes before linearized reading' (duration: 117.531106ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:42.376099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.596548ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T22:55:42.376366Z","caller":"traceutil/trace.go:171","msg":"trace[1425783837] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"496.75267ms","start":"2026-02-18T22:55:41.879592Z","end":"2026-02-18T22:55:42.376344Z","steps":["trace[1425783837] 'process raft request' (duration: 67.401285ms)","trace[1425783837] 'compare' (duration: 428.435615ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:55:42.376439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:41.87957Z","time spent":"496.834891ms","remote":"127.0.0.1:58244","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3401,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:55:42.394857Z","caller":"traceutil/trace.go:171","msg":"trace[989694697] linearizableReadLoop","detail":"{readStateIndex:373; appliedIndex:371; }","duration":"504.332245ms","start":"2026-02-18T22:55:41.890507Z","end":"2026-02-18T22:55:42.394839Z","steps":["trace[989694697] 'read index received' (duration: 56.517642ms)","trace[989694697] 'applied index is now lower than readState.Index' (duration: 447.813683ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:55:42.395119Z","caller":"traceutil/trace.go:171","msg":"trace[1455628853] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"504.677671ms","start":"2026-02-18T22:55:41.890427Z","end":"2026-02-18T22:55:42.395104Z","steps":["trace[1455628853] 'process raft request' (duration: 503.972317ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:55:42.395246Z","caller":"traceutil/trace.go:171","msg":"trace[952879063] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"504.525999ms","start":"2026-02-18T22:55:41.890703Z","end":"2026-02-18T22:55:42.395229Z","steps":["trace[952879063] 'process raft request' (duration: 503.946267ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:42.399145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:41.890699Z","time spent":"508.388149ms","remote":"127.0.0.1:58416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3461,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:55:42.395462Z","caller":"traceutil/trace.go:171","msg":"trace[870501140] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"504.909776ms","start":"2026-02-18T22:55:41.890542Z","end":"2026-02-18T22:55:42.395452Z","steps":["trace[870501140] 'process raft request' (duration: 503.953977ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:42.399904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:41.890538Z","time spent":"509.336398ms","remote":"127.0.0.1:58320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2263,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:55:42.395576Z","caller":"traceutil/trace.go:171","msg":"trace[555329994] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"504.989328ms","start":"2026-02-18T22:55:41.890578Z","end":"2026-02-18T22:55:42.395567Z","steps":["trace[555329994] 'process raft request' (duration: 503.985078ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:42.400235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:41.890574Z","time spent":"509.636373ms","remote":"127.0.0.1:58202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1731,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-18T22:55:42.395699Z","caller":"traceutil/trace.go:171","msg":"trace[175365585] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"505.084981ms","start":"2026-02-18T22:55:41.890604Z","end":"2026-02-18T22:55:42.395689Z","steps":["trace[175365585] 'process raft request' (duration: 504.004078ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:42.400602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:41.890602Z","time spent":"509.976131ms","remote":"127.0.0.1:58416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2264,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:55:42.395809Z","caller":"traceutil/trace.go:171","msg":"trace[752852602] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"501.569967ms","start":"2026-02-18T22:55:41.89423Z","end":"2026-02-18T22:55:42.3958Z","steps":["trace[752852602] 'process raft request' (duration: 500.542886ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:42.401749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:41.894221Z","time spent":"507.298225ms","remote":"127.0.0.1:58172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":669,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-18T22:55:42.395957Z","caller":"traceutil/trace.go:171","msg":"trace[445978224] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"502.769083ms","start":"2026-02-18T22:55:41.893179Z","end":"2026-02-18T22:55:42.395948Z","steps":["trace[445978224] 'process raft request' (duration: 501.549778ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:42.402551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:41.893164Z","time spent":"509.327208ms","remote":"127.0.0.1:58542","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3669,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:55:42.396018Z","caller":"traceutil/trace.go:171","msg":"trace[965308957] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"501.472346ms","start":"2026-02-18T22:55:41.894539Z","end":"2026-02-18T22:55:42.396011Z","steps":["trace[965308957] 'process raft request' (duration: 500.26265ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:42.402895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:41.89453Z","time spent":"508.340326ms","remote":"127.0.0.1:58562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2864,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:55:42.398263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.742005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-18T22:55:42.404627Z","caller":"traceutil/trace.go:171","msg":"trace[2057744609] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:372; }","duration":"514.124016ms","start":"2026-02-18T22:55:41.89048Z","end":"2026-02-18T22:55:42.404604Z","steps":["trace[2057744609] 'agreement among raft nodes before linearized reading' (duration: 507.726164ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:42.404698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:41.890475Z","time spent":"514.213618ms","remote":"127.0.0.1:58270","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" "} {"level":"warn","ts":"2026-02-18T22:55:42.405136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:41.890406Z","time spent":"508.479861ms","remote":"127.0.0.1:58172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":680,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-18T22:55:42.445103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.313046ms","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:55:42.445207Z","caller":"traceutil/trace.go:171","msg":"trace[470314179] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:373; }","duration":"397.444008ms","start":"2026-02-18T22:55:42.047743Z","end":"2026-02-18T22:55:42.445187Z","steps":["trace[470314179] 'agreement among raft nodes before linearized reading' (duration: 397.300365ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:42.445575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.077471ms","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:55:42.445667Z","caller":"traceutil/trace.go:171","msg":"trace[487873067] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:373; }","duration":"508.205844ms","start":"2026-02-18T22:55:41.937446Z","end":"2026-02-18T22:55:42.445652Z","steps":["trace[487873067] 'agreement among raft nodes before linearized reading' (duration: 507.948209ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:42.445776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:41.937434Z","time spent":"508.330726ms","remote":"127.0.0.1:58332","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:55:42.445533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:42.047732Z","time spent":"397.694374ms","remote":"127.0.0.1:58332","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:55:42.44726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.48948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-18T22:55:42.447348Z","caller":"traceutil/trace.go:171","msg":"trace[2077769588] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:373; }","duration":"417.640393ms","start":"2026-02-18T22:55:42.029694Z","end":"2026-02-18T22:55:42.447335Z","steps":["trace[2077769588] 'agreement among raft nodes before linearized reading' (duration: 417.48586ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:42.447627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:42.029675Z","time spent":"417.856837ms","remote":"127.0.0.1:58332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-18T22:55:42.680874Z","caller":"traceutil/trace.go:171","msg":"trace[1336496589] transaction","detail":"{read_only:false; response_revision:385; number_of_response:1; }","duration":"100.174018ms","start":"2026-02-18T22:55:42.580674Z","end":"2026-02-18T22:55:42.680848Z","steps":["trace[1336496589] 'process raft request' (duration: 68.277723ms)","trace[1336496589] 'compare' (duration: 31.452986ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:55:43.295826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.217184ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T22:55:43.295954Z","caller":"traceutil/trace.go:171","msg":"trace[187488904] transaction","detail":"{read_only:false; response_revision:391; number_of_response:1; }","duration":"522.901847ms","start":"2026-02-18T22:55:42.773028Z","end":"2026-02-18T22:55:43.295929Z","steps":["trace[187488904] 'process raft request' (duration: 15.456428ms)","trace[187488904] 'compare' (duration: 506.572451ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:55:43.296136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:42.773009Z","time spent":"523.0754ms","remote":"127.0.0.1:58542","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4019,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:55:44.493514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.590934ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T22:55:44.493623Z","caller":"traceutil/trace.go:171","msg":"trace[375560030] transaction","detail":"{read_only:false; response_revision:392; number_of_response:1; }","duration":"1.158331673s","start":"2026-02-18T22:55:43.335271Z","end":"2026-02-18T22:55:44.493603Z","steps":["trace[375560030] 'process raft request' (duration: 952.548234ms)","trace[375560030] 'compare' (duration: 204.662309ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:55:44.493691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:43.335251Z","time spent":"1.158406096s","remote":"127.0.0.1:58244","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4245,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:55:44.497623Z","caller":"traceutil/trace.go:171","msg":"trace[825321273] transaction","detail":"{read_only:false; response_revision:393; number_of_response:1; }","duration":"664.713729ms","start":"2026-02-18T22:55:43.832878Z","end":"2026-02-18T22:55:44.497592Z","steps":["trace[825321273] 'process raft request' (duration: 664.544635ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:44.497862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:43.832847Z","time spent":"664.890224ms","remote":"127.0.0.1:58332","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:48.551886Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432438185379965822,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-18T22:55:48.555851Z","caller":"traceutil/trace.go:171","msg":"trace[119229800] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"990.704728ms","start":"2026-02-18T22:55:47.565121Z","end":"2026-02-18T22:55:48.555825Z","steps":["trace[119229800] 'process raft request' (duration: 990.377669ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:48.556014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:47.565103Z","time spent":"990.806632ms","remote":"127.0.0.1:58244","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4583,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:55:50.051777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000830255s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-18T22:55:50.052018Z","caller":"traceutil/trace.go:171","msg":"trace[1263177603] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.001140605s","start":"2026-02-18T22:55:48.050851Z","end":"2026-02-18T22:55:50.051991Z","steps":["trace[1263177603] 'agreement among raft nodes before linearized reading' (duration: 2.000861006s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:50.052114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:48.050834Z","time spent":"2.001262678s","remote":"127.0.0.1:58094","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T22:55:50.486156Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.901038411s","expected-duration":"1s"} {"level":"info","ts":"2026-02-18T22:55:50.489439Z","caller":"traceutil/trace.go:171","msg":"trace[647411897] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"2.605664076s","start":"2026-02-18T22:55:47.883694Z","end":"2026-02-18T22:55:50.489359Z","steps":["trace[647411897] 'process raft request' (duration: 2.605309857s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:50.489692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:47.883672Z","time spent":"2.605867582s","remote":"127.0.0.1:58332","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:50.489804Z","caller":"traceutil/trace.go:171","msg":"trace[1862672424] linearizableReadLoop","detail":"{readStateIndex:424; appliedIndex:422; }","duration":"2.438835807s","start":"2026-02-18T22:55:48.050897Z","end":"2026-02-18T22:55:50.489733Z","steps":["trace[1862672424] 'read index received' (duration: 505.722357ms)","trace[1862672424] 'applied index is now lower than readState.Index' (duration: 1.93311252s)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:55:50.490079Z","caller":"traceutil/trace.go:171","msg":"trace[674960545] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"2.515799278s","start":"2026-02-18T22:55:47.97427Z","end":"2026-02-18T22:55:50.490069Z","steps":["trace[674960545] 'process raft request' (duration: 2.515020097s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:50.490225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:47.974247Z","time spent":"2.515856489s","remote":"127.0.0.1:58238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4042,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:55:50.490447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.902723817s","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:55:50.490474Z","caller":"traceutil/trace.go:171","msg":"trace[442467703] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:414; }","duration":"1.90279503s","start":"2026-02-18T22:55:48.587669Z","end":"2026-02-18T22:55:50.490464Z","steps":["trace[442467703] 'agreement among raft nodes before linearized reading' (duration: 1.902727248s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:50.490496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:48.587645Z","time spent":"1.90284608s","remote":"127.0.0.1:58332","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:55:50.490714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.112345ms","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:50.490734Z","caller":"traceutil/trace.go:171","msg":"trace[1109598651] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:414; }","duration":"437.222519ms","start":"2026-02-18T22:55:50.053506Z","end":"2026-02-18T22:55:50.490728Z","steps":["trace[1109598651] 'agreement among raft nodes before linearized reading' (duration: 437.137077ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:50.490781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:50.053477Z","time spent":"437.299501ms","remote":"127.0.0.1:58108","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:50.490972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.506974307s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:55:50.491037Z","caller":"traceutil/trace.go:171","msg":"trace[2077303318] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:414; }","duration":"1.507041069s","start":"2026-02-18T22:55:48.983987Z","end":"2026-02-18T22:55:50.491029Z","steps":["trace[2077303318] 'agreement among raft nodes before linearized reading' (duration: 1.506895415s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:50.49158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.81074565s","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:50.491604Z","caller":"traceutil/trace.go:171","msg":"trace[670913415] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:414; }","duration":"1.810836713s","start":"2026-02-18T22:55:48.68076Z","end":"2026-02-18T22:55:50.491597Z","steps":["trace[670913415] 'agreement among raft nodes before linearized reading' (duration: 1.810787141s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:50.49164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:48.680743Z","time spent":"1.810892455s","remote":"127.0.0.1:58332","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:50.491722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.900458366s","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:55:50.491773Z","caller":"traceutil/trace.go:171","msg":"trace[526460604] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:414; }","duration":"1.900518287s","start":"2026-02-18T22:55:48.591239Z","end":"2026-02-18T22:55:50.491758Z","steps":["trace[526460604] 'agreement among raft nodes before linearized reading' (duration: 1.900416714s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:50.491808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:48.591236Z","time spent":"1.900566068s","remote":"127.0.0.1:58332","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:55:50.491962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.900730273s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-18T22:55:50.491995Z","caller":"traceutil/trace.go:171","msg":"trace[1693816392] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:414; }","duration":"1.900774924s","start":"2026-02-18T22:55:48.591211Z","end":"2026-02-18T22:55:50.491986Z","steps":["trace[1693816392] 'agreement among raft nodes before linearized reading' (duration: 1.900722153s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:50.492024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:48.591205Z","time spent":"1.900812535s","remote":"127.0.0.1:58332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-18T22:55:50.856101Z","caller":"traceutil/trace.go:171","msg":"trace[82059143] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"198.92659ms","start":"2026-02-18T22:55:50.657133Z","end":"2026-02-18T22:55:50.85606Z","steps":["trace[82059143] 'process raft request' (duration: 198.716694ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:55:51.001717Z","caller":"traceutil/trace.go:171","msg":"trace[1928640144] linearizableReadLoop","detail":"{readStateIndex:432; appliedIndex:431; }","duration":"139.072022ms","start":"2026-02-18T22:55:50.862602Z","end":"2026-02-18T22:55:51.001674Z","steps":["trace[1928640144] 'read index received' (duration: 40.914753ms)","trace[1928640144] 'applied index is now lower than readState.Index' (duration: 98.154699ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:55:51.001894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.257927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6898"} {"level":"info","ts":"2026-02-18T22:55:51.00194Z","caller":"traceutil/trace.go:171","msg":"trace[1300520837] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:421; }","duration":"139.373831ms","start":"2026-02-18T22:55:50.862554Z","end":"2026-02-18T22:55:51.001928Z","steps":["trace[1300520837] 'agreement among raft nodes before linearized reading' (duration: 139.243817ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:55.300882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.825444ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T22:55:55.301052Z","caller":"traceutil/trace.go:171","msg":"trace[262658236] transaction","detail":"{read_only:false; response_revision:431; number_of_response:1; }","duration":"589.730962ms","start":"2026-02-18T22:55:54.711308Z","end":"2026-02-18T22:55:55.301039Z","steps":["trace[262658236] 'process raft request' (duration: 46.454976ms)","trace[262658236] 'compare' (duration: 542.67267ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:55:55.301137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:54.711255Z","time spent":"589.830255ms","remote":"127.0.0.1:58332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:55:55.300999Z","caller":"traceutil/trace.go:171","msg":"trace[1688187227] linearizableReadLoop","detail":"{readStateIndex:442; appliedIndex:441; }","duration":"249.88084ms","start":"2026-02-18T22:55:55.051102Z","end":"2026-02-18T22:55:55.300982Z","steps":["trace[1688187227] 'read index received' (duration: 28.2µs)","trace[1688187227] 'applied index is now lower than readState.Index' (duration: 249.85135ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:55:55.301524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.417175ms","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:55.301565Z","caller":"traceutil/trace.go:171","msg":"trace[204140656] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:431; }","duration":"250.480827ms","start":"2026-02-18T22:55:55.051063Z","end":"2026-02-18T22:55:55.301544Z","steps":["trace[204140656] 'agreement among raft nodes before linearized reading' (duration: 250.416065ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:55.301931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.144024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4056"} {"level":"info","ts":"2026-02-18T22:55:55.301964Z","caller":"traceutil/trace.go:171","msg":"trace[262495502] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:431; }","duration":"195.228607ms","start":"2026-02-18T22:55:55.106726Z","end":"2026-02-18T22:55:55.301955Z","steps":["trace[262495502] 'agreement among raft nodes before linearized reading' (duration: 195.129815ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:55:56.735766Z","caller":"traceutil/trace.go:171","msg":"trace[456080071] linearizableReadLoop","detail":"{readStateIndex:446; appliedIndex:445; }","duration":"111.013004ms","start":"2026-02-18T22:55:56.62473Z","end":"2026-02-18T22:55:56.735743Z","steps":["trace[456080071] 'read index received' (duration: 110.842959ms)","trace[456080071] 'applied index is now lower than readState.Index' (duration: 169.405µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:55:56.735858Z","caller":"traceutil/trace.go:171","msg":"trace[1001702564] transaction","detail":"{read_only:false; response_revision:434; number_of_response:1; }","duration":"124.343333ms","start":"2026-02-18T22:55:56.611509Z","end":"2026-02-18T22:55:56.735852Z","steps":["trace[1001702564] 'process raft request' (duration: 124.114207ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:56.736187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.441506ms","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:55:56.736208Z","caller":"traceutil/trace.go:171","msg":"trace[66137583] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:434; }","duration":"111.491698ms","start":"2026-02-18T22:55:56.624709Z","end":"2026-02-18T22:55:56.7362Z","steps":["trace[66137583] 'agreement among raft nodes before linearized reading' (duration: 111.435957ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:57.566193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.802442ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T22:55:57.566303Z","caller":"traceutil/trace.go:171","msg":"trace[503128900] transaction","detail":"{read_only:false; response_revision:449; number_of_response:1; }","duration":"158.578532ms","start":"2026-02-18T22:55:57.407714Z","end":"2026-02-18T22:55:57.566292Z","steps":["trace[503128900] 'process raft request' (duration: 158.548791ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:55:57.56656Z","caller":"traceutil/trace.go:171","msg":"trace[988341836] transaction","detail":"{read_only:false; response_revision:448; number_of_response:1; }","duration":"225.149495ms","start":"2026-02-18T22:55:57.341404Z","end":"2026-02-18T22:55:57.566554Z","steps":["trace[988341836] 'process raft request' (duration: 110.928181ms)","trace[988341836] 'compare' (duration: 113.71761ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:55:57.566623Z","caller":"traceutil/trace.go:171","msg":"trace[1374488473] linearizableReadLoop","detail":"{readStateIndex:460; appliedIndex:459; }","duration":"159.513428ms","start":"2026-02-18T22:55:57.407105Z","end":"2026-02-18T22:55:57.566618Z","steps":["trace[1374488473] 'read index received' (duration: 45.235823ms)","trace[1374488473] 'applied index is now lower than readState.Index' (duration: 114.277155ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:55:57.566748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.641271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4173"} {"level":"info","ts":"2026-02-18T22:55:57.566772Z","caller":"traceutil/trace.go:171","msg":"trace[194809585] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:449; }","duration":"159.686543ms","start":"2026-02-18T22:55:57.407078Z","end":"2026-02-18T22:55:57.566765Z","steps":["trace[194809585] 'agreement among raft nodes before linearized reading' (duration: 159.647351ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:57.566872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.679322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-xr4bp\" ","response":"range_response_count:1 size:3994"} {"level":"info","ts":"2026-02-18T22:55:57.566885Z","caller":"traceutil/trace.go:171","msg":"trace[1423590599] range","detail":"{range_begin:/registry/pods/kube-system/coredns-5dd5756b68-xr4bp; range_end:; response_count:1; response_revision:449; }","duration":"159.700913ms","start":"2026-02-18T22:55:57.407181Z","end":"2026-02-18T22:55:57.566882Z","steps":["trace[1423590599] 'agreement among raft nodes before linearized reading' (duration: 159.671282ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:56:12.444094Z","caller":"traceutil/trace.go:171","msg":"trace[1632859517] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"329.077505ms","start":"2026-02-18T22:56:12.115001Z","end":"2026-02-18T22:56:12.444078Z","steps":["trace[1632859517] 'process raft request' (duration: 328.887319ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:12.444263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:12.11498Z","time spent":"329.172368ms","remote":"127.0.0.1:58146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188471,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:56:12.44445Z","caller":"traceutil/trace.go:171","msg":"trace[684725511] linearizableReadLoop","detail":"{readStateIndex:568; appliedIndex:568; }","duration":"294.956959ms","start":"2026-02-18T22:56:12.149486Z","end":"2026-02-18T22:56:12.444443Z","steps":["trace[684725511] 'read index received' (duration: 294.952948ms)","trace[684725511] 'applied index is now lower than readState.Index' (duration: 3.351µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:56:12.444496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.00787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:56:12.444737Z","caller":"traceutil/trace.go:171","msg":"trace[116073464] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:552; }","duration":"295.282057ms","start":"2026-02-18T22:56:12.149449Z","end":"2026-02-18T22:56:12.444731Z","steps":["trace[116073464] 'agreement among raft nodes before linearized reading' (duration: 295.01652ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:56:12.780913Z","caller":"traceutil/trace.go:171","msg":"trace[969076292] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"430.931176ms","start":"2026-02-18T22:56:12.349964Z","end":"2026-02-18T22:56:12.780895Z","steps":["trace[969076292] 'process raft request' (duration: 404.938836ms)","trace[969076292] 'compare' (duration: 25.856886ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:56:12.781032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:12.349937Z","time spent":"431.033688ms","remote":"127.0.0.1:58146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3104,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-18T22:56:13.250619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.571141ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T22:56:13.250798Z","caller":"traceutil/trace.go:171","msg":"trace[1210655990] linearizableReadLoop","detail":"{readStateIndex:570; appliedIndex:568; }","duration":"701.434638ms","start":"2026-02-18T22:56:12.549349Z","end":"2026-02-18T22:56:13.250784Z","steps":["trace[1210655990] 'read index received' (duration: 205.614286ms)","trace[1210655990] 'applied index is now lower than readState.Index' (duration: 495.819542ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:56:13.253017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"703.616179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:56:13.254425Z","caller":"traceutil/trace.go:171","msg":"trace[1246954596] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:0; response_revision:555; }","duration":"704.03304ms","start":"2026-02-18T22:56:12.549296Z","end":"2026-02-18T22:56:13.253329Z","steps":["trace[1246954596] 'agreement among raft nodes before linearized reading' (duration: 703.167805ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:13.254481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:12.549286Z","time spent":"705.184431ms","remote":"127.0.0.1:58146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" "} {"level":"info","ts":"2026-02-18T22:56:13.256696Z","caller":"traceutil/trace.go:171","msg":"trace[2074776987] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"735.351496ms","start":"2026-02-18T22:56:12.521224Z","end":"2026-02-18T22:56:13.256576Z","steps":["trace[2074776987] 'process raft request' (duration: 509.747318ms)","trace[2074776987] 'compare' (duration: 219.316154ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:56:13.256899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:12.521207Z","time spent":"735.524482ms","remote":"127.0.0.1:58146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188480,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:56:13.257306Z","caller":"traceutil/trace.go:171","msg":"trace[839965589] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"505.132699ms","start":"2026-02-18T22:56:12.752102Z","end":"2026-02-18T22:56:13.257235Z","steps":["trace[839965589] 'process raft request' (duration: 498.626629ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:13.257447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:12.752083Z","time spent":"505.258723ms","remote":"127.0.0.1:58146","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:56:13.345869Z","caller":"traceutil/trace.go:171","msg":"trace[632898014] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"396.566184ms","start":"2026-02-18T22:56:12.949279Z","end":"2026-02-18T22:56:13.345845Z","steps":["trace[632898014] 'process raft request' (duration: 396.508472ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:56:13.346047Z","caller":"traceutil/trace.go:171","msg":"trace[1522212713] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"561.021349ms","start":"2026-02-18T22:56:12.785003Z","end":"2026-02-18T22:56:13.346024Z","steps":["trace[1522212713] 'process raft request' (duration: 560.567956ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:13.346392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:12.784988Z","time spent":"561.344367ms","remote":"127.0.0.1:58146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4059,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:56:13.346503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:12.949263Z","time spent":"397.010115ms","remote":"127.0.0.1:58146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3353,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-18T22:56:15.049447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000070626s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-18T22:56:15.049552Z","caller":"traceutil/trace.go:171","msg":"trace[717267211] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.00022679s","start":"2026-02-18T22:56:13.049304Z","end":"2026-02-18T22:56:15.049531Z","steps":["trace[717267211] 'agreement among raft nodes before linearized reading' (duration: 2.000086796s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:15.049603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:13.049278Z","time spent":"2.000315952s","remote":"127.0.0.1:58094","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/18 22:56:15 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-18T22:56:15.280052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.713623274s","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:15.280201Z","caller":"traceutil/trace.go:171","msg":"trace[462297463] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:557; }","duration":"1.713788138s","start":"2026-02-18T22:56:13.566389Z","end":"2026-02-18T22:56:15.280177Z","steps":["trace[462297463] 'range keys from in-memory index tree' (duration: 1.713608033s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:15.282423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.25997239s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-18T22:56:15.282477Z","caller":"traceutil/trace.go:171","msg":"trace[554397989] linearizableReadLoop","detail":"{readStateIndex:574; appliedIndex:571; }","duration":"2.031639781s","start":"2026-02-18T22:56:13.250826Z","end":"2026-02-18T22:56:15.282466Z","steps":["trace[554397989] 'read index received' (duration: 94.805416ms)","trace[554397989] 'applied index is now lower than readState.Index' (duration: 1.936833295s)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:56:15.282634Z","caller":"traceutil/trace.go:171","msg":"trace[789163902] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"2.127418723s","start":"2026-02-18T22:56:13.155204Z","end":"2026-02-18T22:56:15.282622Z","steps":["trace[789163902] 'process raft request' (duration: 867.177186ms)","trace[789163902] 'compare' (duration: 1.258925521s)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:56:15.282932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.330150448s","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:56:15.282958Z","caller":"traceutil/trace.go:171","msg":"trace[71473708] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:558; }","duration":"2.33021105s","start":"2026-02-18T22:56:12.952738Z","end":"2026-02-18T22:56:15.282949Z","steps":["trace[71473708] 'agreement among raft nodes before linearized reading' (duration: 2.330116688s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:15.282979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:12.952724Z","time spent":"2.330250162s","remote":"127.0.0.1:58332","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:56:15.283223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.226438106s","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:56:15.283253Z","caller":"traceutil/trace.go:171","msg":"trace[1649344575] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:558; }","duration":"2.226495688s","start":"2026-02-18T22:56:13.056751Z","end":"2026-02-18T22:56:15.283247Z","steps":["trace[1649344575] 'agreement among raft nodes before linearized reading' (duration: 2.226381775s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:15.283275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:13.056742Z","time spent":"2.226528699s","remote":"127.0.0.1:58332","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:56:15.283321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.229446209s","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:56:15.283337Z","caller":"traceutil/trace.go:171","msg":"trace[67336894] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:558; }","duration":"2.229495651s","start":"2026-02-18T22:56:13.053836Z","end":"2026-02-18T22:56:15.283331Z","steps":["trace[67336894] 'agreement among raft nodes before linearized reading' (duration: 2.22946433s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:15.283354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:13.053822Z","time spent":"2.229528392s","remote":"127.0.0.1:58332","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:56:15.283695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.571878873s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-7fcd8d6ffd-qfbgh\" ","response":"range_response_count:1 size:4822"} {"level":"info","ts":"2026-02-18T22:56:15.283716Z","caller":"traceutil/trace.go:171","msg":"trace[596063454] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-7fcd8d6ffd-qfbgh; range_end:; response_count:1; response_revision:558; }","duration":"2.571923095s","start":"2026-02-18T22:56:12.711786Z","end":"2026-02-18T22:56:15.283709Z","steps":["trace[596063454] 'agreement among raft nodes before linearized reading' (duration: 2.571859613s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:15.283737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:12.711773Z","time spent":"2.571956845s","remote":"127.0.0.1:58244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":4845,"request content":"key:\"/registry/pods/kube-system/cilium-operator-7fcd8d6ffd-qfbgh\" "} {"level":"warn","ts":"2026-02-18T22:56:15.283856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.375281917s","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:56:15.283873Z","caller":"traceutil/trace.go:171","msg":"trace[1227099913] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:558; }","duration":"2.375324808s","start":"2026-02-18T22:56:12.908543Z","end":"2026-02-18T22:56:15.283867Z","steps":["trace[1227099913] 'agreement among raft nodes before linearized reading' (duration: 2.375289837s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:15.28389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:12.908528Z","time spent":"2.375358419s","remote":"127.0.0.1:58332","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:56:15.282678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:13.155179Z","time spent":"2.127478755s","remote":"127.0.0.1:58146","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:56:15.783554Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432438185379966108,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T22:56:15.887439Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.416881424s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-18T22:56:16.514658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"626.699068ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T22:56:16.514905Z","caller":"traceutil/trace.go:171","msg":"trace[1251018331] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"3.163455759s","start":"2026-02-18T22:56:13.351417Z","end":"2026-02-18T22:56:16.514873Z","steps":["trace[1251018331] 'process raft request' (duration: 2.53638695s)","trace[1251018331] 'compare' (duration: 625.992979ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:56:16.515069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:13.351404Z","time spent":"3.163569841s","remote":"127.0.0.1:58146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4156,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:56:16.51509Z","caller":"traceutil/trace.go:171","msg":"trace[635675432] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"3.159217652s","start":"2026-02-18T22:56:13.355845Z","end":"2026-02-18T22:56:16.515063Z","steps":["trace[635675432] 'process raft request' (duration: 3.159130219s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:16.515192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:13.355839Z","time spent":"3.159307994s","remote":"127.0.0.1:58146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4068,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:56:16.515457Z","caller":"traceutil/trace.go:171","msg":"trace[1816382349] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"3.162654987s","start":"2026-02-18T22:56:13.35279Z","end":"2026-02-18T22:56:16.515445Z","steps":["trace[1816382349] 'process raft request' (duration: 3.162089492s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:16.515516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:13.352776Z","time spent":"3.162710828s","remote":"127.0.0.1:58146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9343,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-18T22:56:16.642002Z","caller":"traceutil/trace.go:171","msg":"trace[922554077] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"2.490774979s","start":"2026-02-18T22:56:14.151201Z","end":"2026-02-18T22:56:16.641976Z","steps":["trace[922554077] 'process raft request' (duration: 2.490298066s)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:56:16.642234Z","caller":"traceutil/trace.go:171","msg":"trace[1095267936] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"1.332744413s","start":"2026-02-18T22:56:15.309443Z","end":"2026-02-18T22:56:16.642187Z","steps":["trace[1095267936] 'process raft request' (duration: 1.332543098s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:16.642521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:15.309435Z","time spent":"1.332966819s","remote":"127.0.0.1:58332","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:56:16.642752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:14.151177Z","time spent":"2.49115793s","remote":"127.0.0.1:58146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12244,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-18T22:56:16.643003Z","caller":"traceutil/trace.go:171","msg":"trace[1790610688] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"2.09184303s","start":"2026-02-18T22:56:14.551145Z","end":"2026-02-18T22:56:16.642988Z","steps":["trace[1790610688] 'process raft request' (duration: 2.090721718s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:16.643071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:14.551126Z","time spent":"2.091914642s","remote":"127.0.0.1:58146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8270,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-18T22:56:16.64439Z","caller":"traceutil/trace.go:171","msg":"trace[1032358252] linearizableReadLoop","detail":"{readStateIndex:584; appliedIndex:574; }","duration":"1.36188191s","start":"2026-02-18T22:56:15.282492Z","end":"2026-02-18T22:56:16.644374Z","steps":["trace[1032358252] 'read index received' (duration: 605.324774ms)","trace[1032358252] 'applied index is now lower than readState.Index' (duration: 756.533665ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:56:16.644625Z","caller":"traceutil/trace.go:171","msg":"trace[39015590] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"1.335046776s","start":"2026-02-18T22:56:15.309567Z","end":"2026-02-18T22:56:16.644613Z","steps":["trace[39015590] 'process raft request' (duration: 1.332464075s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:16.644866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:15.309559Z","time spent":"1.335215292s","remote":"127.0.0.1:58332","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:56:16.645221Z","caller":"traceutil/trace.go:171","msg":"trace[1847416475] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"1.335577721s","start":"2026-02-18T22:56:15.309603Z","end":"2026-02-18T22:56:16.645181Z","steps":["trace[1847416475] 'process raft request' (duration: 1.334306696s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:16.645422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:15.309598Z","time spent":"1.335750435s","remote":"127.0.0.1:58332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:56:16.645695Z","caller":"traceutil/trace.go:171","msg":"trace[1799204086] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"1.336060276s","start":"2026-02-18T22:56:15.309623Z","end":"2026-02-18T22:56:16.645683Z","steps":["trace[1799204086] 'process raft request' (duration: 1.334579504s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:16.645894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:15.309587Z","time spent":"1.33622797s","remote":"127.0.0.1:58332","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:56:16.646458Z","caller":"traceutil/trace.go:171","msg":"trace[111842559] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"766.954034ms","start":"2026-02-18T22:56:15.879463Z","end":"2026-02-18T22:56:16.646417Z","steps":["trace[111842559] 'process raft request' (duration: 764.783223ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:16.646927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:15.879426Z","time spent":"767.453527ms","remote":"127.0.0.1:58332","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:56:16.647516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.497415534s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:3368"} {"level":"info","ts":"2026-02-18T22:56:16.648209Z","caller":"traceutil/trace.go:171","msg":"trace[1506360585] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:569; }","duration":"1.498130934s","start":"2026-02-18T22:56:15.150039Z","end":"2026-02-18T22:56:16.64817Z","steps":["trace[1506360585] 'agreement among raft nodes before linearized reading' (duration: 1.497240579s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:16.648352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:15.150021Z","time spent":"1.498294s","remote":"127.0.0.1:58146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":3391,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" "} {"level":"warn","ts":"2026-02-18T22:56:16.648561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.598604728s","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:16.648642Z","caller":"traceutil/trace.go:171","msg":"trace[56035989] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:569; }","duration":"1.598720451s","start":"2026-02-18T22:56:15.049913Z","end":"2026-02-18T22:56:16.648633Z","steps":["trace[56035989] 'agreement among raft nodes before linearized reading' (duration: 1.598621889s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:16.648713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:15.049897Z","time spent":"1.598808153s","remote":"127.0.0.1:58108","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:56:16.648774Z","caller":"traceutil/trace.go:171","msg":"trace[1654060640] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"1.340332804s","start":"2026-02-18T22:56:15.308429Z","end":"2026-02-18T22:56:16.648762Z","steps":["trace[1654060640] 'process raft request' (duration: 1.333486834s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:16.648858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:15.3084Z","time spent":"1.340423117s","remote":"127.0.0.1:58244","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4613,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:56:16.649364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.699488864s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:1 size:4171"} {"level":"info","ts":"2026-02-18T22:56:16.65088Z","caller":"traceutil/trace.go:171","msg":"trace[902315788] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:569; }","duration":"1.701052697s","start":"2026-02-18T22:56:14.94982Z","end":"2026-02-18T22:56:16.650873Z","steps":["trace[902315788] 'agreement among raft nodes before linearized reading' (duration: 1.699149715s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:16.650976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:14.949787Z","time spent":"1.70117931s","remote":"127.0.0.1:58146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4194,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" "} {"level":"warn","ts":"2026-02-18T22:56:16.649448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.899739269s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:1 size:4083"} {"level":"info","ts":"2026-02-18T22:56:16.651246Z","caller":"traceutil/trace.go:171","msg":"trace[486635441] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:569; }","duration":"1.901573801s","start":"2026-02-18T22:56:14.749663Z","end":"2026-02-18T22:56:16.651237Z","steps":["trace[486635441] 'agreement among raft nodes before linearized reading' (duration: 1.899659687s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:16.651355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:14.749635Z","time spent":"1.901709895s","remote":"127.0.0.1:58146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4106,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" "} {"level":"warn","ts":"2026-02-18T22:56:16.649593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.300024034s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188495"} {"level":"info","ts":"2026-02-18T22:56:16.651828Z","caller":"traceutil/trace.go:171","msg":"trace[2002073648] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:569; }","duration":"2.302266397s","start":"2026-02-18T22:56:14.349528Z","end":"2026-02-18T22:56:16.651794Z","steps":["trace[2002073648] 'agreement among raft nodes before linearized reading' (duration: 2.299816819s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:16.651931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:14.349482Z","time spent":"2.302436262s","remote":"127.0.0.1:58146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":1,"response size":188518,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-02-18T22:56:16.649709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.700531159s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:1 size:5177"} {"level":"info","ts":"2026-02-18T22:56:16.652499Z","caller":"traceutil/trace.go:171","msg":"trace[2042995920] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:569; }","duration":"2.703339447s","start":"2026-02-18T22:56:13.949149Z","end":"2026-02-18T22:56:16.652489Z","steps":["trace[2042995920] 'agreement among raft nodes before linearized reading' (duration: 2.700331714s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:16.652785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:13.949121Z","time spent":"2.703630075s","remote":"127.0.0.1:58146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":5200,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" "} {"level":"warn","ts":"2026-02-18T22:56:16.649737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.878845718s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-18T22:56:16.653539Z","caller":"traceutil/trace.go:171","msg":"trace[459506031] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:569; }","duration":"2.882664513s","start":"2026-02-18T22:56:13.770864Z","end":"2026-02-18T22:56:16.653529Z","steps":["trace[459506031] 'agreement among raft nodes before linearized reading' (duration: 2.878855108s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:16.653711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:13.770847Z","time spent":"2.882851369s","remote":"127.0.0.1:58332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-18T22:56:16.649779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.898988466s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"info","ts":"2026-02-18T22:56:16.654269Z","caller":"traceutil/trace.go:171","msg":"trace[1776201608] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:569; }","duration":"2.903487691s","start":"2026-02-18T22:56:13.750759Z","end":"2026-02-18T22:56:16.654247Z","steps":["trace[1776201608] 'agreement among raft nodes before linearized reading' (duration: 2.898986396s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:16.654384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:13.750746Z","time spent":"2.903622524s","remote":"127.0.0.1:58146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":92,"response count":1,"response size":5609,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" "} {"level":"warn","ts":"2026-02-18T22:56:16.6498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.100863617s","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:56:16.654604Z","caller":"traceutil/trace.go:171","msg":"trace[1484342872] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:569; }","duration":"3.105689s","start":"2026-02-18T22:56:13.548906Z","end":"2026-02-18T22:56:16.654595Z","steps":["trace[1484342872] 'agreement among raft nodes before linearized reading' (duration: 3.100881497s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:16.654643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:13.548893Z","time spent":"3.105731842s","remote":"127.0.0.1:58146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-02-18T22:56:16.650376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.204751898s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.153\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-18T22:56:16.654994Z","caller":"traceutil/trace.go:171","msg":"trace[1279071823] range","detail":"{range_begin:/registry/masterleases/199.204.45.153; range_end:; response_count:1; response_revision:571; }","duration":"1.209384637s","start":"2026-02-18T22:56:15.445569Z","end":"2026-02-18T22:56:16.654953Z","steps":["trace[1279071823] 'agreement among raft nodes before linearized reading' (duration: 1.204769839s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:16.655092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:15.445544Z","time spent":"1.209537211s","remote":"127.0.0.1:58130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.153\" "} {"level":"info","ts":"2026-02-18T22:56:16.650637Z","caller":"traceutil/trace.go:171","msg":"trace[1528409549] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"124.994092ms","start":"2026-02-18T22:56:16.52563Z","end":"2026-02-18T22:56:16.650624Z","steps":["trace[1528409549] 'process raft request' (duration: 124.618942ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:56:16.650695Z","caller":"traceutil/trace.go:171","msg":"trace[1258047216] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"123.130651ms","start":"2026-02-18T22:56:16.52753Z","end":"2026-02-18T22:56:16.65066Z","steps":["trace[1258047216] 'process raft request' (duration: 122.776501ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:16.65078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"840.86249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:1 size:23292"} {"level":"info","ts":"2026-02-18T22:56:16.656706Z","caller":"traceutil/trace.go:171","msg":"trace[2112046369] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:1; response_revision:571; }","duration":"846.834067ms","start":"2026-02-18T22:56:15.809864Z","end":"2026-02-18T22:56:16.656698Z","steps":["trace[2112046369] 'agreement among raft nodes before linearized reading' (duration: 840.84444ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:16.656785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:15.809841Z","time spent":"846.936089ms","remote":"127.0.0.1:58146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":23315,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" "} {"level":"warn","ts":"2026-02-18T22:56:24.916928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.919252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-18T22:56:24.917001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.995613ms","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:56:24.917002Z","caller":"traceutil/trace.go:171","msg":"trace[1959160605] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:609; }","duration":"220.067856ms","start":"2026-02-18T22:56:24.696917Z","end":"2026-02-18T22:56:24.916985Z","steps":["trace[1959160605] 'range keys from in-memory index tree' (duration: 219.755248ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:56:24.917038Z","caller":"traceutil/trace.go:171","msg":"trace[1109141506] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:609; }","duration":"216.036025ms","start":"2026-02-18T22:56:24.700987Z","end":"2026-02-18T22:56:24.917023Z","steps":["trace[1109141506] 'range keys from in-memory index tree' (duration: 215.958883ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:24.917142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.083434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-18T22:56:24.917165Z","caller":"traceutil/trace.go:171","msg":"trace[1576127045] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:609; }","duration":"208.118836ms","start":"2026-02-18T22:56:24.709039Z","end":"2026-02-18T22:56:24.917157Z","steps":["trace[1576127045] 'range keys from in-memory index tree' (duration: 208.048884ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:24.917324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.412843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-18T22:56:24.91735Z","caller":"traceutil/trace.go:171","msg":"trace[456078068] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:609; }","duration":"169.462184ms","start":"2026-02-18T22:56:24.74788Z","end":"2026-02-18T22:56:24.917342Z","steps":["trace[456078068] 'range keys from in-memory index tree' (duration: 169.360742ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:24.917408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"864.305609ms","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:24.917441Z","caller":"traceutil/trace.go:171","msg":"trace[8029238] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:609; }","duration":"864.379021ms","start":"2026-02-18T22:56:24.053052Z","end":"2026-02-18T22:56:24.917431Z","steps":["trace[8029238] 'range keys from in-memory index tree' (duration: 864.203046ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:24.917468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:24.053023Z","time spent":"864.437953ms","remote":"127.0.0.1:58094","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:56:24.916919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.140281ms","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:56:24.917734Z","caller":"traceutil/trace.go:171","msg":"trace[811564836] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:609; }","duration":"219.991114ms","start":"2026-02-18T22:56:24.697733Z","end":"2026-02-18T22:56:24.917724Z","steps":["trace[811564836] 'range keys from in-memory index tree' (duration: 219.078739ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:56:25.275501Z","caller":"traceutil/trace.go:171","msg":"trace[1539332843] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"350.013013ms","start":"2026-02-18T22:56:24.925451Z","end":"2026-02-18T22:56:25.275464Z","steps":["trace[1539332843] 'process raft request' (duration: 349.950801ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:25.275633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:24.92544Z","time spent":"350.128766ms","remote":"127.0.0.1:58332","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:56:25.275723Z","caller":"traceutil/trace.go:171","msg":"trace[1745840809] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"350.26495ms","start":"2026-02-18T22:56:24.925441Z","end":"2026-02-18T22:56:25.275706Z","steps":["trace[1745840809] 'process raft request' (duration: 349.803977ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:25.275821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:24.925419Z","time spent":"350.339142ms","remote":"127.0.0.1:58332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:56:25.946899Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432438185379966225,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T22:56:26.447466Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432438185379966225,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T22:56:26.537088Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.261830328s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-18T22:56:28.05063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.001015962s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-18T22:56:28.050731Z","caller":"traceutil/trace.go:171","msg":"trace[840439142] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.001162906s","start":"2026-02-18T22:56:26.04955Z","end":"2026-02-18T22:56:28.050713Z","steps":["trace[840439142] 'agreement among raft nodes before linearized reading' (duration: 2.001039672s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:28.050782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:26.049534Z","time spent":"2.001238447s","remote":"127.0.0.1:58094","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T22:56:28.144613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.607282776s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T22:56:28.144842Z","caller":"traceutil/trace.go:171","msg":"trace[958327365] linearizableReadLoop","detail":"{readStateIndex:632; appliedIndex:629; }","duration":"2.699014663s","start":"2026-02-18T22:56:25.445802Z","end":"2026-02-18T22:56:28.144817Z","steps":["trace[958327365] 'read index received' (duration: 1.091397599s)","trace[958327365] 'applied index is now lower than readState.Index' (duration: 1.607616094s)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:56:28.145052Z","caller":"traceutil/trace.go:171","msg":"trace[890352243] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"3.217968594s","start":"2026-02-18T22:56:24.927072Z","end":"2026-02-18T22:56:28.14504Z","steps":["trace[890352243] 'process raft request' (duration: 1.610198005s)","trace[890352243] 'compare' (duration: 1.606602787s)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:56:28.145121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:24.927063Z","time spent":"3.218024337s","remote":"127.0.0.1:58332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:56:28.14524Z","caller":"traceutil/trace.go:171","msg":"trace[1979448238] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"3.217516783s","start":"2026-02-18T22:56:24.927715Z","end":"2026-02-18T22:56:28.145231Z","steps":["trace[1979448238] 'process raft request' (duration: 3.216962847s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:28.145293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:24.927708Z","time spent":"3.217560764s","remote":"127.0.0.1:58332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:56:28.145397Z","caller":"traceutil/trace.go:171","msg":"trace[2031417229] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"3.217086281s","start":"2026-02-18T22:56:24.928303Z","end":"2026-02-18T22:56:28.145389Z","steps":["trace[2031417229] 'process raft request' (duration: 3.216479184s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:28.145447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:24.928296Z","time spent":"3.217128262s","remote":"127.0.0.1:58332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:56:28.145627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.699836326s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.153\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-18T22:56:28.145663Z","caller":"traceutil/trace.go:171","msg":"trace[72726335] range","detail":"{range_begin:/registry/masterleases/199.204.45.153; range_end:; response_count:1; response_revision:614; }","duration":"2.699898378s","start":"2026-02-18T22:56:25.445756Z","end":"2026-02-18T22:56:28.145655Z","steps":["trace[72726335] 'agreement among raft nodes before linearized reading' (duration: 2.699787925s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:28.14569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:25.445739Z","time spent":"2.69994478s","remote":"127.0.0.1:58130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.153\" "} {"level":"warn","ts":"2026-02-18T22:56:28.467987Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.724900045s","expected-duration":"1s"} {"level":"info","ts":"2026-02-18T22:56:28.499942Z","caller":"traceutil/trace.go:171","msg":"trace[869983404] linearizableReadLoop","detail":"{readStateIndex:633; appliedIndex:632; }","duration":"355.051344ms","start":"2026-02-18T22:56:28.144868Z","end":"2026-02-18T22:56:28.499919Z","steps":["trace[869983404] 'read index received' (duration: 323.363117ms)","trace[869983404] 'applied index is now lower than readState.Index' (duration: 31.687097ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:56:28.50014Z","caller":"traceutil/trace.go:171","msg":"trace[381564596] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"1.757204369s","start":"2026-02-18T22:56:26.742892Z","end":"2026-02-18T22:56:28.500096Z","steps":["trace[381564596] 'process raft request' (duration: 1.725317797s)","trace[381564596] 'compare' (duration: 31.586744ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:56:28.500232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.359856ms","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:28.500262Z","caller":"traceutil/trace.go:171","msg":"trace[491373579] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:615; }","duration":"449.415447ms","start":"2026-02-18T22:56:28.050836Z","end":"2026-02-18T22:56:28.500251Z","steps":["trace[491373579] 'agreement among raft nodes before linearized reading' (duration: 449.372636ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:28.500301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:28.050816Z","time spent":"449.477469ms","remote":"127.0.0.1:58108","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:56:28.50036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:26.742848Z","time spent":"1.757379994s","remote":"127.0.0.1:58332","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:56:28.500621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.21964526s","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:56:28.500653Z","caller":"traceutil/trace.go:171","msg":"trace[432170682] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:615; }","duration":"1.219690382s","start":"2026-02-18T22:56:27.280953Z","end":"2026-02-18T22:56:28.500644Z","steps":["trace[432170682] 'agreement among raft nodes before linearized reading' (duration: 1.219587129s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:28.500678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:27.280949Z","time spent":"1.219723713s","remote":"127.0.0.1:58332","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:56:28.500185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.219223359s","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:56:28.500765Z","caller":"traceutil/trace.go:171","msg":"trace[1889980262] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:615; }","duration":"1.219858576s","start":"2026-02-18T22:56:27.280892Z","end":"2026-02-18T22:56:28.500751Z","steps":["trace[1889980262] 'agreement among raft nodes before linearized reading' (duration: 1.219118816s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:28.500813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:27.280874Z","time spent":"1.219925018s","remote":"127.0.0.1:58332","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:56:28.503336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:28.151365Z","time spent":"351.968588ms","remote":"127.0.0.1:58130","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:56:28.650002Z","caller":"traceutil/trace.go:171","msg":"trace[1016685420] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"131.468531ms","start":"2026-02-18T22:56:28.518516Z","end":"2026-02-18T22:56:28.649985Z","steps":["trace[1016685420] 'process raft request' (duration: 131.44207ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:56:28.650247Z","caller":"traceutil/trace.go:171","msg":"trace[474905668] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"143.754691ms","start":"2026-02-18T22:56:28.506486Z","end":"2026-02-18T22:56:28.650241Z","steps":["trace[474905668] 'process raft request' (duration: 115.804307ms)","trace[474905668] 'compare' (duration: 27.591054ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:56:28.65031Z","caller":"traceutil/trace.go:171","msg":"trace[686494440] linearizableReadLoop","detail":"{readStateIndex:636; appliedIndex:635; }","duration":"133.93696ms","start":"2026-02-18T22:56:28.516366Z","end":"2026-02-18T22:56:28.650303Z","steps":["trace[686494440] 'read index received' (duration: 105.931404ms)","trace[686494440] 'applied index is now lower than readState.Index' (duration: 28.004676ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:56:28.650428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.046392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.1895794175fdd097\" ","response":"range_response_count:1 size:774"} {"level":"info","ts":"2026-02-18T22:56:28.650448Z","caller":"traceutil/trace.go:171","msg":"trace[890108527] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.1895794175fdd097; range_end:; response_count:1; response_revision:618; }","duration":"134.098394ms","start":"2026-02-18T22:56:28.516343Z","end":"2026-02-18T22:56:28.650441Z","steps":["trace[890108527] 'agreement among raft nodes before linearized reading' (duration: 134.025332ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:28.650539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.701232ms","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:28.650551Z","caller":"traceutil/trace.go:171","msg":"trace[1242502281] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:618; }","duration":"129.727272ms","start":"2026-02-18T22:56:28.520821Z","end":"2026-02-18T22:56:28.650548Z","steps":["trace[1242502281] 'agreement among raft nodes before linearized reading' (duration: 129.704662ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:28.650669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.515376ms","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:56:28.650711Z","caller":"traceutil/trace.go:171","msg":"trace[1704980307] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:618; }","duration":"129.540778ms","start":"2026-02-18T22:56:28.521137Z","end":"2026-02-18T22:56:28.650678Z","steps":["trace[1704980307] 'agreement among raft nodes before linearized reading' (duration: 129.511667ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:33.080023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.453337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-02-18T22:56:33.080017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.028312801s","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:33.080108Z","caller":"traceutil/trace.go:171","msg":"trace[595233273] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:641; }","duration":"415.55558ms","start":"2026-02-18T22:56:32.664519Z","end":"2026-02-18T22:56:33.080075Z","steps":["trace[595233273] 'range keys from in-memory index tree' (duration: 415.340314ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:56:33.080123Z","caller":"traceutil/trace.go:171","msg":"trace[254606650] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:641; }","duration":"1.028423384s","start":"2026-02-18T22:56:32.051678Z","end":"2026-02-18T22:56:33.080102Z","steps":["trace[254606650] 'range keys from in-memory index tree' (duration: 1.0282575s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:33.080149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:32.664505Z","time spent":"415.636361ms","remote":"127.0.0.1:58332","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:56:33.080168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:32.05163Z","time spent":"1.028528007s","remote":"127.0.0.1:58094","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:56:33.080346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.83787ms","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:56:33.080376Z","caller":"traceutil/trace.go:171","msg":"trace[463697968] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:641; }","duration":"414.874931ms","start":"2026-02-18T22:56:32.66549Z","end":"2026-02-18T22:56:33.080365Z","steps":["trace[463697968] 'range keys from in-memory index tree' (duration: 414.767318ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:33.080401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:32.665484Z","time spent":"414.911102ms","remote":"127.0.0.1:58332","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:56:33.080451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"905.094729ms","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:56:33.080483Z","caller":"traceutil/trace.go:171","msg":"trace[677834898] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:641; }","duration":"905.133729ms","start":"2026-02-18T22:56:32.17534Z","end":"2026-02-18T22:56:33.080473Z","steps":["trace[677834898] 'range keys from in-memory index tree' (duration: 904.980235ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:33.080516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:32.175337Z","time spent":"905.17178ms","remote":"127.0.0.1:58332","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:56:33.080632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.801668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-gwb8j\" ","response":"range_response_count:1 size:17670"} {"level":"info","ts":"2026-02-18T22:56:33.080665Z","caller":"traceutil/trace.go:171","msg":"trace[127512915] range","detail":"{range_begin:/registry/pods/kube-system/cilium-gwb8j; range_end:; response_count:1; response_revision:641; }","duration":"293.834788ms","start":"2026-02-18T22:56:32.786821Z","end":"2026-02-18T22:56:33.080655Z","steps":["trace[127512915] 'range keys from in-memory index tree' (duration: 293.700195ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:33.080705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"905.394867ms","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:56:33.08074Z","caller":"traceutil/trace.go:171","msg":"trace[410507904] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:641; }","duration":"905.448488ms","start":"2026-02-18T22:56:32.17528Z","end":"2026-02-18T22:56:33.080728Z","steps":["trace[410507904] 'range keys from in-memory index tree' (duration: 905.235342ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:33.080772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:32.175267Z","time spent":"905.496419ms","remote":"127.0.0.1:58332","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:56:33.080939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"905.52863ms","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:56:33.080966Z","caller":"traceutil/trace.go:171","msg":"trace[1493860065] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:641; }","duration":"905.560702ms","start":"2026-02-18T22:56:32.175396Z","end":"2026-02-18T22:56:33.080957Z","steps":["trace[1493860065] 'range keys from in-memory index tree' (duration: 905.462939ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:33.080992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:32.175392Z","time spent":"905.593912ms","remote":"127.0.0.1:58332","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:56:33.081178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"701.209002ms","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:56:33.081211Z","caller":"traceutil/trace.go:171","msg":"trace[621368342] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:641; }","duration":"701.288694ms","start":"2026-02-18T22:56:32.379913Z","end":"2026-02-18T22:56:33.081201Z","steps":["trace[621368342] 'count revisions from in-memory index tree' (duration: 701.087588ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:33.081242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:32.379895Z","time spent":"701.336405ms","remote":"127.0.0.1:58202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":12,"response size":30,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"info","ts":"2026-02-18T22:56:33.314478Z","caller":"traceutil/trace.go:171","msg":"trace[885700579] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"215.566381ms","start":"2026-02-18T22:56:33.098897Z","end":"2026-02-18T22:56:33.314463Z","steps":["trace[885700579] 'process raft request' (duration: 215.52997ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:33.314553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.024059ms","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:33.314574Z","caller":"traceutil/trace.go:171","msg":"trace[767945836] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:647; }","duration":"218.057799ms","start":"2026-02-18T22:56:33.096512Z","end":"2026-02-18T22:56:33.31457Z","steps":["trace[767945836] 'agreement among raft nodes before linearized reading' (duration: 218.000198ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:56:33.314675Z","caller":"traceutil/trace.go:171","msg":"trace[233879419] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"214.527842ms","start":"2026-02-18T22:56:33.100142Z","end":"2026-02-18T22:56:33.31467Z","steps":["trace[233879419] 'process raft request' (duration: 214.301966ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:56:33.314841Z","caller":"traceutil/trace.go:171","msg":"trace[1330474790] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"219.040607ms","start":"2026-02-18T22:56:33.095794Z","end":"2026-02-18T22:56:33.314835Z","steps":["trace[1330474790] 'process raft request' (duration: 218.453061ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:56:33.314485Z","caller":"traceutil/trace.go:171","msg":"trace[1421743932] linearizableReadLoop","detail":"{readStateIndex:664; appliedIndex:661; }","duration":"217.959247ms","start":"2026-02-18T22:56:33.096516Z","end":"2026-02-18T22:56:33.314476Z","steps":["trace[1421743932] 'read index received' (duration: 82.701731ms)","trace[1421743932] 'applied index is now lower than readState.Index' (duration: 135.256956ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:56:33.314895Z","caller":"traceutil/trace.go:171","msg":"trace[1286245628] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"219.017526ms","start":"2026-02-18T22:56:33.09587Z","end":"2026-02-18T22:56:33.314888Z","steps":["trace[1286245628] 'process raft request' (duration: 218.464221ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:56:33.31508Z","caller":"traceutil/trace.go:171","msg":"trace[1883475067] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"216.599648ms","start":"2026-02-18T22:56:33.098475Z","end":"2026-02-18T22:56:33.315075Z","steps":["trace[1883475067] 'process raft request' (duration: 215.92016ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:56:35.227357Z","caller":"traceutil/trace.go:171","msg":"trace[1462570497] linearizableReadLoop","detail":"{readStateIndex:672; appliedIndex:671; }","duration":"178.078463ms","start":"2026-02-18T22:56:35.049263Z","end":"2026-02-18T22:56:35.227342Z","steps":["trace[1462570497] 'read index received' (duration: 177.96599ms)","trace[1462570497] 'applied index is now lower than readState.Index' (duration: 112.093µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:56:35.227456Z","caller":"traceutil/trace.go:171","msg":"trace[1330020192] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"410.174291ms","start":"2026-02-18T22:56:34.817276Z","end":"2026-02-18T22:56:35.227451Z","steps":["trace[1330020192] 'process raft request' (duration: 409.963225ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:35.227521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:34.817261Z","time spent":"410.207671ms","remote":"127.0.0.1:58244","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18065,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:56:35.227678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.411702ms","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:35.227698Z","caller":"traceutil/trace.go:171","msg":"trace[361699531] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:652; }","duration":"178.453352ms","start":"2026-02-18T22:56:35.049238Z","end":"2026-02-18T22:56:35.227692Z","steps":["trace[361699531] 'agreement among raft nodes before linearized reading' (duration: 178.422641ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:56:44.198247Z","caller":"traceutil/trace.go:171","msg":"trace[1624808405] linearizableReadLoop","detail":"{readStateIndex:747; appliedIndex:746; }","duration":"146.876437ms","start":"2026-02-18T22:56:44.05135Z","end":"2026-02-18T22:56:44.198227Z","steps":["trace[1624808405] 'read index received' (duration: 146.720593ms)","trace[1624808405] 'applied index is now lower than readState.Index' (duration: 155.214µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:56:44.198346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.975961ms","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:44.19837Z","caller":"traceutil/trace.go:171","msg":"trace[1742381724] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:723; }","duration":"147.066334ms","start":"2026-02-18T22:56:44.051297Z","end":"2026-02-18T22:56:44.198363Z","steps":["trace[1742381724] 'agreement among raft nodes before linearized reading' (duration: 147.007682ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:56:44.198502Z","caller":"traceutil/trace.go:171","msg":"trace[1566939861] transaction","detail":"{read_only:false; response_revision:723; number_of_response:1; }","duration":"299.055943ms","start":"2026-02-18T22:56:43.899425Z","end":"2026-02-18T22:56:44.198481Z","steps":["trace[1566939861] 'process raft request' (duration: 298.695863ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:56:44.524974Z","caller":"traceutil/trace.go:171","msg":"trace[494569374] transaction","detail":"{read_only:false; response_revision:724; number_of_response:1; }","duration":"317.739541ms","start":"2026-02-18T22:56:44.207217Z","end":"2026-02-18T22:56:44.524957Z","steps":["trace[494569374] 'process raft request' (duration: 299.731151ms)","trace[494569374] 'compare' (duration: 17.128485ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:56:44.525071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:44.207197Z","time spent":"317.819902ms","remote":"127.0.0.1:58236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":783,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:56:44.541515Z","caller":"traceutil/trace.go:171","msg":"trace[1580157640] linearizableReadLoop","detail":"{readStateIndex:749; appliedIndex:747; }","duration":"331.326656ms","start":"2026-02-18T22:56:44.210169Z","end":"2026-02-18T22:56:44.541496Z","steps":["trace[1580157640] 'read index received' (duration: 296.707867ms)","trace[1580157640] 'applied index is now lower than readState.Index' (duration: 34.617809ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:56:44.541778Z","caller":"traceutil/trace.go:171","msg":"trace[2105215570] transaction","detail":"{read_only:false; response_revision:725; number_of_response:1; }","duration":"332.962432ms","start":"2026-02-18T22:56:44.208806Z","end":"2026-02-18T22:56:44.541769Z","steps":["trace[2105215570] 'process raft request' (duration: 332.59238ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:44.541863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:44.20693Z","time spent":"334.872116ms","remote":"127.0.0.1:58348","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1292,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:56:44.542103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.935245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-77cccfdc44-4vk4d\" ","response":"range_response_count:1 size:4733"} {"level":"info","ts":"2026-02-18T22:56:44.542132Z","caller":"traceutil/trace.go:171","msg":"trace[345606018] range","detail":"{range_begin:/registry/pods/kube-system/coredns-77cccfdc44-4vk4d; range_end:; response_count:1; response_revision:725; }","duration":"331.982785ms","start":"2026-02-18T22:56:44.21014Z","end":"2026-02-18T22:56:44.542123Z","steps":["trace[345606018] 'agreement among raft nodes before linearized reading' (duration: 331.879572ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:44.542152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:44.210131Z","time spent":"332.015496ms","remote":"127.0.0.1:58244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":4756,"request content":"key:\"/registry/pods/kube-system/coredns-77cccfdc44-4vk4d\" "} {"level":"warn","ts":"2026-02-18T22:56:44.542266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.145789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:56:44.542283Z","caller":"traceutil/trace.go:171","msg":"trace[931620654] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:725; }","duration":"315.182871ms","start":"2026-02-18T22:56:44.227094Z","end":"2026-02-18T22:56:44.542277Z","steps":["trace[931620654] 'agreement among raft nodes before linearized reading' (duration: 315.15218ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:44.5423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:44.227085Z","time spent":"315.209791ms","remote":"127.0.0.1:58452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"info","ts":"2026-02-18T22:56:45.062644Z","caller":"traceutil/trace.go:171","msg":"trace[264832590] linearizableReadLoop","detail":"{readStateIndex:750; appliedIndex:749; }","duration":"213.0483ms","start":"2026-02-18T22:56:44.849575Z","end":"2026-02-18T22:56:45.062623Z","steps":["trace[264832590] 'read index received' (duration: 212.899136ms)","trace[264832590] 'applied index is now lower than readState.Index' (duration: 148.444µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:56:45.062804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.704449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T22:56:45.062828Z","caller":"traceutil/trace.go:171","msg":"trace[704978465] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:726; }","duration":"113.782812ms","start":"2026-02-18T22:56:44.949038Z","end":"2026-02-18T22:56:45.062821Z","steps":["trace[704978465] 'agreement among raft nodes before linearized reading' (duration: 113.705389ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:45.062842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.252607ms","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:56:45.062893Z","caller":"traceutil/trace.go:171","msg":"trace[1190900250] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:726; }","duration":"213.35634ms","start":"2026-02-18T22:56:44.849525Z","end":"2026-02-18T22:56:45.062882Z","steps":["trace[1190900250] 'agreement among raft nodes before linearized reading' (duration: 213.217256ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:56:45.063082Z","caller":"traceutil/trace.go:171","msg":"trace[28826846] transaction","detail":"{read_only:false; response_revision:726; number_of_response:1; }","duration":"506.073847ms","start":"2026-02-18T22:56:44.557001Z","end":"2026-02-18T22:56:45.063074Z","steps":["trace[28826846] 'process raft request' (duration: 505.527641ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:45.063142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:44.556982Z","time spent":"506.121098ms","remote":"127.0.0.1:58244","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4833,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:56:47.933102Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432438185379966527,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-18T22:56:48.120973Z","caller":"traceutil/trace.go:171","msg":"trace[1909513831] linearizableReadLoop","detail":"{readStateIndex:781; appliedIndex:780; }","duration":"688.9055ms","start":"2026-02-18T22:56:47.43205Z","end":"2026-02-18T22:56:48.120956Z","steps":["trace[1909513831] 'read index received' (duration: 688.789697ms)","trace[1909513831] 'applied index is now lower than readState.Index' (duration: 115.093µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:56:48.121061Z","caller":"traceutil/trace.go:171","msg":"trace[961793188] transaction","detail":"{read_only:false; response_revision:755; number_of_response:1; }","duration":"712.637577ms","start":"2026-02-18T22:56:47.408418Z","end":"2026-02-18T22:56:48.121055Z","steps":["trace[961793188] 'process raft request' (duration: 712.417311ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:48.121135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:47.4084Z","time spent":"712.679769ms","remote":"127.0.0.1:58332","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:56:48.121332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"685.604618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-18T22:56:48.121392Z","caller":"traceutil/trace.go:171","msg":"trace[1166598035] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:755; }","duration":"685.757933ms","start":"2026-02-18T22:56:47.435616Z","end":"2026-02-18T22:56:48.121374Z","steps":["trace[1166598035] 'agreement among raft nodes before linearized reading' (duration: 685.605769ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:48.121423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:47.435606Z","time spent":"685.811274ms","remote":"127.0.0.1:58332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-18T22:56:48.121496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"689.443915ms","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:56:48.121522Z","caller":"traceutil/trace.go:171","msg":"trace[369003475] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:755; }","duration":"689.495546ms","start":"2026-02-18T22:56:47.432018Z","end":"2026-02-18T22:56:48.121513Z","steps":["trace[369003475] 'agreement among raft nodes before linearized reading' (duration: 689.416904ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:48.121539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:47.432004Z","time spent":"689.531417ms","remote":"127.0.0.1:58332","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:56:48.121599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"684.93419ms","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:56:48.121627Z","caller":"traceutil/trace.go:171","msg":"trace[895231432] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:755; }","duration":"684.97266ms","start":"2026-02-18T22:56:47.436647Z","end":"2026-02-18T22:56:48.12162Z","steps":["trace[895231432] 'agreement among raft nodes before linearized reading' (duration: 684.901278ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:48.121646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:47.436641Z","time spent":"685.000912ms","remote":"127.0.0.1:58332","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:56:49.553113Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432438185379966531,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T22:56:49.619505Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.493478011s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-18T22:56:49.820048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:48.853173Z","time spent":"966.869574ms","remote":"127.0.0.1:39290","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-18T22:56:49.823607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.628563ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T22:56:49.824135Z","caller":"traceutil/trace.go:171","msg":"trace[1836341508] linearizableReadLoop","detail":"{readStateIndex:785; appliedIndex:781; }","duration":"771.350726ms","start":"2026-02-18T22:56:49.052773Z","end":"2026-02-18T22:56:49.824124Z","steps":["trace[1836341508] 'read index received' (duration: 567.159254ms)","trace[1836341508] 'applied index is now lower than readState.Index' (duration: 204.190852ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:56:49.824802Z","caller":"traceutil/trace.go:171","msg":"trace[1020245299] transaction","detail":"{read_only:false; response_revision:759; number_of_response:1; }","duration":"1.145209312s","start":"2026-02-18T22:56:48.67957Z","end":"2026-02-18T22:56:49.824779Z","steps":["trace[1020245299] 'process raft request' (duration: 1.144498249s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:49.824922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:48.679551Z","time spent":"1.145299915s","remote":"127.0.0.1:58332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:56:49.825036Z","caller":"traceutil/trace.go:171","msg":"trace[1306648485] transaction","detail":"{read_only:false; response_revision:756; number_of_response:1; }","duration":"1.699068209s","start":"2026-02-18T22:56:48.125959Z","end":"2026-02-18T22:56:49.825027Z","steps":["trace[1306648485] 'process raft request' (duration: 1.493962577s)","trace[1306648485] 'compare' (duration: 202.722174ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:56:49.825068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:48.125948Z","time spent":"1.69910092s","remote":"127.0.0.1:58332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:56:49.825113Z","caller":"traceutil/trace.go:171","msg":"trace[1107047710] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"1.698493454s","start":"2026-02-18T22:56:48.126615Z","end":"2026-02-18T22:56:49.825108Z","steps":["trace[1107047710] 'process raft request' (duration: 1.697385067s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:49.825148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:48.126607Z","time spent":"1.698517574s","remote":"127.0.0.1:58332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:56:49.825205Z","caller":"traceutil/trace.go:171","msg":"trace[1635583310] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"1.697115706s","start":"2026-02-18T22:56:48.128084Z","end":"2026-02-18T22:56:49.825199Z","steps":["trace[1635583310] 'process raft request' (duration: 1.695962097s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:49.825233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:48.128075Z","time spent":"1.697140986s","remote":"127.0.0.1:58332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:56:49.825302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"758.341992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:56:49.825323Z","caller":"traceutil/trace.go:171","msg":"trace[1990054347] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:759; }","duration":"758.384123ms","start":"2026-02-18T22:56:49.066933Z","end":"2026-02-18T22:56:49.825317Z","steps":["trace[1990054347] 'agreement among raft nodes before linearized reading' (duration: 758.341642ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:49.825341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:49.066923Z","time spent":"758.413154ms","remote":"127.0.0.1:58458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true "} {"level":"warn","ts":"2026-02-18T22:56:49.825438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"772.664509ms","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:49.825454Z","caller":"traceutil/trace.go:171","msg":"trace[1024484524] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:759; }","duration":"772.707691ms","start":"2026-02-18T22:56:49.052741Z","end":"2026-02-18T22:56:49.825449Z","steps":["trace[1024484524] 'agreement among raft nodes before linearized reading' (duration: 772.67913ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:49.825468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:49.052727Z","time spent":"772.737741ms","remote":"127.0.0.1:58094","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:56:49.825706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.230122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-18T22:56:49.825731Z","caller":"traceutil/trace.go:171","msg":"trace[1688342596] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:759; }","duration":"531.288205ms","start":"2026-02-18T22:56:49.294435Z","end":"2026-02-18T22:56:49.825724Z","steps":["trace[1688342596] 'agreement among raft nodes before linearized reading' (duration: 531.183661ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:49.825754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:49.294418Z","time spent":"531.329215ms","remote":"127.0.0.1:58332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-18T22:56:50.104702Z","caller":"traceutil/trace.go:171","msg":"trace[1558010442] transaction","detail":"{read_only:false; response_revision:760; number_of_response:1; }","duration":"272.82574ms","start":"2026-02-18T22:56:49.831857Z","end":"2026-02-18T22:56:50.104682Z","steps":["trace[1558010442] 'process raft request' (duration: 272.726387ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:52.903988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"709.634783ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T22:56:52.904058Z","caller":"traceutil/trace.go:171","msg":"trace[312067532] linearizableReadLoop","detail":"{readStateIndex:793; appliedIndex:792; }","duration":"795.407891ms","start":"2026-02-18T22:56:52.108639Z","end":"2026-02-18T22:56:52.904046Z","steps":["trace[312067532] 'read index received' (duration: 85.597222ms)","trace[312067532] 'applied index is now lower than readState.Index' (duration: 709.808289ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:56:52.904084Z","caller":"traceutil/trace.go:171","msg":"trace[212229821] transaction","detail":"{read_only:false; response_revision:766; number_of_response:1; }","duration":"922.294471ms","start":"2026-02-18T22:56:51.981769Z","end":"2026-02-18T22:56:52.904064Z","steps":["trace[212229821] 'process raft request' (duration: 212.525504ms)","trace[212229821] 'compare' (duration: 709.55278ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:56:52.904175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"795.546765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-18T22:56:52.904198Z","caller":"traceutil/trace.go:171","msg":"trace[1763530418] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:766; }","duration":"795.596916ms","start":"2026-02-18T22:56:52.108593Z","end":"2026-02-18T22:56:52.90419Z","steps":["trace[1763530418] 'agreement among raft nodes before linearized reading' (duration: 795.512154ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:52.904198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:51.981746Z","time spent":"922.384404ms","remote":"127.0.0.1:58332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:56:52.90422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:52.108557Z","time spent":"795.657239ms","remote":"127.0.0.1:58332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-18T22:56:52.904321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"771.517391ms","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:56:52.904338Z","caller":"traceutil/trace.go:171","msg":"trace[1004157839] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:766; }","duration":"771.551973ms","start":"2026-02-18T22:56:52.13278Z","end":"2026-02-18T22:56:52.904332Z","steps":["trace[1004157839] 'agreement among raft nodes before linearized reading' (duration: 771.508301ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:52.904359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:52.132769Z","time spent":"771.584584ms","remote":"127.0.0.1:58332","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:56:53.354051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.774663ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T22:56:53.354203Z","caller":"traceutil/trace.go:171","msg":"trace[519169088] linearizableReadLoop","detail":"{readStateIndex:795; appliedIndex:793; }","duration":"302.052785ms","start":"2026-02-18T22:56:53.052135Z","end":"2026-02-18T22:56:53.354187Z","steps":["trace[519169088] 'read index received' (duration: 31.082226ms)","trace[519169088] 'applied index is now lower than readState.Index' (duration: 270.969649ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:56:53.354281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.151848ms","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:53.354305Z","caller":"traceutil/trace.go:171","msg":"trace[1507673357] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:768; }","duration":"302.21247ms","start":"2026-02-18T22:56:53.052085Z","end":"2026-02-18T22:56:53.354297Z","steps":["trace[1507673357] 'agreement among raft nodes before linearized reading' (duration: 302.152278ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:53.354333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:53.052061Z","time spent":"302.265992ms","remote":"127.0.0.1:58094","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:56:53.354624Z","caller":"traceutil/trace.go:171","msg":"trace[646853880] transaction","detail":"{read_only:false; response_revision:767; number_of_response:1; }","duration":"443.695566ms","start":"2026-02-18T22:56:52.910912Z","end":"2026-02-18T22:56:53.354608Z","steps":["trace[646853880] 'process raft request' (duration: 172.297592ms)","trace[646853880] 'compare' (duration: 270.406972ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:56:53.354719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:52.910896Z","time spent":"443.756167ms","remote":"127.0.0.1:58332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:56:53.354849Z","caller":"traceutil/trace.go:171","msg":"trace[518309177] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"443.653145ms","start":"2026-02-18T22:56:52.911185Z","end":"2026-02-18T22:56:53.354838Z","steps":["trace[518309177] 'process raft request' (duration: 442.956782ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:53.354905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:52.911174Z","time spent":"443.703096ms","remote":"127.0.0.1:58332","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:56:53.675278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.772271ms","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:53.675421Z","caller":"traceutil/trace.go:171","msg":"trace[1933561451] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:768; }","duration":"109.937026ms","start":"2026-02-18T22:56:53.565461Z","end":"2026-02-18T22:56:53.675398Z","steps":["trace[1933561451] 'range keys from in-memory index tree' (duration: 109.75292ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:54.152122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.866015ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T22:56:54.152194Z","caller":"traceutil/trace.go:171","msg":"trace[2034996810] transaction","detail":"{read_only:false; response_revision:770; number_of_response:1; }","duration":"272.740898ms","start":"2026-02-18T22:56:53.879441Z","end":"2026-02-18T22:56:54.152182Z","steps":["trace[2034996810] 'process raft request' (duration: 36.76071ms)","trace[2034996810] 'compare' (duration: 235.798502ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:56:54.25006Z","caller":"traceutil/trace.go:171","msg":"trace[639028755] transaction","detail":"{read_only:false; response_revision:771; number_of_response:1; }","duration":"370.009061ms","start":"2026-02-18T22:56:53.880036Z","end":"2026-02-18T22:56:54.250045Z","steps":["trace[639028755] 'process raft request' (duration: 369.934239ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:54.250151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:53.880031Z","time spent":"370.072054ms","remote":"127.0.0.1:58332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:56:56.912188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.219788445s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T22:56:56.91236Z","caller":"traceutil/trace.go:171","msg":"trace[1585652856] transaction","detail":"{read_only:false; response_revision:773; number_of_response:1; }","duration":"1.545517102s","start":"2026-02-18T22:56:55.36683Z","end":"2026-02-18T22:56:56.912347Z","steps":["trace[1585652856] 'process raft request' (duration: 325.51064ms)","trace[1585652856] 'compare' (duration: 1.219182776s)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:56:56.912404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:55.366814Z","time spent":"1.545570304s","remote":"127.0.0.1:58332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:56:56.912512Z","caller":"traceutil/trace.go:171","msg":"trace[750945439] linearizableReadLoop","detail":"{readStateIndex:800; appliedIndex:799; }","duration":"1.464854891s","start":"2026-02-18T22:56:55.447652Z","end":"2026-02-18T22:56:56.912506Z","steps":["trace[750945439] 'read index received' (duration: 244.713724ms)","trace[750945439] 'applied index is now lower than readState.Index' (duration: 1.220140467s)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:56:56.91265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.464995706s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.153\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-18T22:56:56.912668Z","caller":"traceutil/trace.go:171","msg":"trace[252512237] range","detail":"{range_begin:/registry/masterleases/199.204.45.153; range_end:; response_count:1; response_revision:773; }","duration":"1.465037357s","start":"2026-02-18T22:56:55.447625Z","end":"2026-02-18T22:56:56.912662Z","steps":["trace[252512237] 'agreement among raft nodes before linearized reading' (duration: 1.464947024s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:56.912695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:55.447611Z","time spent":"1.465079189s","remote":"127.0.0.1:58130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.153\" "} {"level":"warn","ts":"2026-02-18T22:56:56.912829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.044292461s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-18T22:56:56.912851Z","caller":"traceutil/trace.go:171","msg":"trace[1905535675] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:773; }","duration":"1.044337471s","start":"2026-02-18T22:56:55.868506Z","end":"2026-02-18T22:56:56.912843Z","steps":["trace[1905535675] 'agreement among raft nodes before linearized reading' (duration: 1.04428766s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:56.912877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:55.868458Z","time spent":"1.044414064s","remote":"127.0.0.1:58332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-18T22:56:56.913156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"657.672217ms","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:56:56.913196Z","caller":"traceutil/trace.go:171","msg":"trace[763643962] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:773; }","duration":"657.736669ms","start":"2026-02-18T22:56:56.255437Z","end":"2026-02-18T22:56:56.913174Z","steps":["trace[763643962] 'agreement among raft nodes before linearized reading' (duration: 657.636246ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:56.913224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:56.255425Z","time spent":"657.794151ms","remote":"127.0.0.1:58332","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:56:56.913376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"757.557057ms","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:56:56.913409Z","caller":"traceutil/trace.go:171","msg":"trace[212302937] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:773; }","duration":"757.611938ms","start":"2026-02-18T22:56:56.155778Z","end":"2026-02-18T22:56:56.91339Z","steps":["trace[212302937] 'agreement among raft nodes before linearized reading' (duration: 757.541736ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:56.913427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:56.155759Z","time spent":"757.66419ms","remote":"127.0.0.1:58332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-18T22:56:57.04585Z","caller":"traceutil/trace.go:171","msg":"trace[355787319] transaction","detail":"{read_only:false; response_revision:776; number_of_response:1; }","duration":"125.511985ms","start":"2026-02-18T22:56:56.920319Z","end":"2026-02-18T22:56:57.045831Z","steps":["trace[355787319] 'process raft request' (duration: 125.475904ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:56:57.04601Z","caller":"traceutil/trace.go:171","msg":"trace[1818517367] transaction","detail":"{read_only:false; response_revision:775; number_of_response:1; }","duration":"126.59514ms","start":"2026-02-18T22:56:56.919409Z","end":"2026-02-18T22:56:57.046004Z","steps":["trace[1818517367] 'process raft request' (duration: 126.357693ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:56:57.045857Z","caller":"traceutil/trace.go:171","msg":"trace[422400591] transaction","detail":"{read_only:false; response_revision:774; number_of_response:1; }","duration":"126.465776ms","start":"2026-02-18T22:56:56.919367Z","end":"2026-02-18T22:56:57.045832Z","steps":["trace[422400591] 'process raft request' (duration: 114.596889ms)","trace[422400591] 'compare' (duration: 11.709592ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:57:05.987209Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432438185379966656,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-18T22:57:06.068264Z","caller":"traceutil/trace.go:171","msg":"trace[651017039] linearizableReadLoop","detail":"{readStateIndex:834; appliedIndex:833; }","duration":"581.825462ms","start":"2026-02-18T22:57:05.486415Z","end":"2026-02-18T22:57:06.068241Z","steps":["trace[651017039] 'read index received' (duration: 581.639136ms)","trace[651017039] 'applied index is now lower than readState.Index' (duration: 185.226µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:57:06.06837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:57:05.448575Z","time spent":"619.79104ms","remote":"127.0.0.1:58130","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:57:06.068466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"582.030979ms","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:57:06.068504Z","caller":"traceutil/trace.go:171","msg":"trace[764632466] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:804; }","duration":"582.105571ms","start":"2026-02-18T22:57:05.486382Z","end":"2026-02-18T22:57:06.068487Z","steps":["trace[764632466] 'agreement among raft nodes before linearized reading' (duration: 581.944956ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:57:06.068538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:57:05.486372Z","time spent":"582.153893ms","remote":"127.0.0.1:58332","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:57:06.491472Z","caller":"traceutil/trace.go:171","msg":"trace[330700878] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"421.712089ms","start":"2026-02-18T22:57:06.069737Z","end":"2026-02-18T22:57:06.491449Z","steps":["trace[330700878] 'process raft request' (duration: 421.546053ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:57:06.491904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:57:06.069718Z","time spent":"421.828183ms","remote":"127.0.0.1:58130","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:57:06.511426Z","caller":"traceutil/trace.go:171","msg":"trace[660676907] transaction","detail":"{read_only:false; response_revision:806; number_of_response:1; }","duration":"437.818663ms","start":"2026-02-18T22:57:06.073582Z","end":"2026-02-18T22:57:06.5114Z","steps":["trace[660676907] 'process raft request' (duration: 437.276686ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:57:06.511597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:57:06.073569Z","time spent":"437.958638ms","remote":"127.0.0.1:58332","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:57:07.74365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"554.315015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:57:07.743714Z","caller":"traceutil/trace.go:171","msg":"trace[459451887] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:807; }","duration":"554.435229ms","start":"2026-02-18T22:57:07.189265Z","end":"2026-02-18T22:57:07.7437Z","steps":["trace[459451887] 'count revisions from in-memory index tree' (duration: 554.246583ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:57:07.743749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:57:07.189253Z","time spent":"554.487571ms","remote":"127.0.0.1:58578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-02-18T22:57:07.74411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"596.972277ms","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:57:07.74413Z","caller":"traceutil/trace.go:171","msg":"trace[1550456448] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:807; }","duration":"597.029049ms","start":"2026-02-18T22:57:07.147095Z","end":"2026-02-18T22:57:07.744124Z","steps":["trace[1550456448] 'range keys from in-memory index tree' (duration: 596.857523ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:57:07.744146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:57:07.147078Z","time spent":"597.06467ms","remote":"127.0.0.1:58332","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:57:07.744331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"582.744312ms","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:57:07.744398Z","caller":"traceutil/trace.go:171","msg":"trace[319030435] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:807; }","duration":"582.840596ms","start":"2026-02-18T22:57:07.161539Z","end":"2026-02-18T22:57:07.744379Z","steps":["trace[319030435] 'range keys from in-memory index tree' (duration: 582.610948ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:57:07.744412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.471302ms","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:57:07.744434Z","caller":"traceutil/trace.go:171","msg":"trace[1407267675] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:807; }","duration":"570.528974ms","start":"2026-02-18T22:57:07.173898Z","end":"2026-02-18T22:57:07.744427Z","steps":["trace[1407267675] 'range keys from in-memory index tree' (duration: 570.40161ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:57:07.744435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:57:07.161525Z","time spent":"582.901947ms","remote":"127.0.0.1:58332","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:57:07.744452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:57:07.173887Z","time spent":"570.560996ms","remote":"127.0.0.1:58332","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:57:08.186847Z","caller":"traceutil/trace.go:171","msg":"trace[863781885] transaction","detail":"{read_only:false; response_revision:809; number_of_response:1; }","duration":"434.252437ms","start":"2026-02-18T22:57:07.752567Z","end":"2026-02-18T22:57:08.186819Z","steps":["trace[863781885] 'process raft request' (duration: 434.02007ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:57:08.187046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:57:07.752549Z","time spent":"434.369342ms","remote":"127.0.0.1:58332","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:57:08.18713Z","caller":"traceutil/trace.go:171","msg":"trace[709927208] transaction","detail":"{read_only:false; response_revision:810; number_of_response:1; }","duration":"434.478835ms","start":"2026-02-18T22:57:07.752627Z","end":"2026-02-18T22:57:08.187106Z","steps":["trace[709927208] 'process raft request' (duration: 434.151134ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:57:08.18726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:57:07.752623Z","time spent":"434.564357ms","remote":"127.0.0.1:58332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:57:20.548843Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432438185379966861,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-18T22:57:20.638327Z","caller":"traceutil/trace.go:171","msg":"trace[1524421786] transaction","detail":"{read_only:false; response_revision:892; number_of_response:1; }","duration":"724.614671ms","start":"2026-02-18T22:57:19.913695Z","end":"2026-02-18T22:57:20.63831Z","steps":["trace[1524421786] 'process raft request' (duration: 724.130276ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:57:20.638453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:57:19.913677Z","time spent":"724.710395ms","remote":"127.0.0.1:58332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:57:20.64078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"591.978554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:57:20.640902Z","caller":"traceutil/trace.go:171","msg":"trace[1833388110] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:892; }","duration":"592.064847ms","start":"2026-02-18T22:57:20.048747Z","end":"2026-02-18T22:57:20.640812Z","steps":["trace[1833388110] 'agreement among raft nodes before linearized reading' (duration: 591.863251ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:57:20.640995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:57:20.048737Z","time spent":"592.248334ms","remote":"127.0.0.1:58094","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:57:20.642746Z","caller":"traceutil/trace.go:171","msg":"trace[240004777] linearizableReadLoop","detail":"{readStateIndex:925; appliedIndex:924; }","duration":"589.221314ms","start":"2026-02-18T22:57:20.048772Z","end":"2026-02-18T22:57:20.637993Z","steps":["trace[240004777] 'read index received' (duration: 589.030418ms)","trace[240004777] 'applied index is now lower than readState.Index' (duration: 189.996µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:57:20.643239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.334594ms","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:57:20.643275Z","caller":"traceutil/trace.go:171","msg":"trace[360923862] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:893; }","duration":"394.406168ms","start":"2026-02-18T22:57:20.248856Z","end":"2026-02-18T22:57:20.643263Z","steps":["trace[360923862] 'agreement among raft nodes before linearized reading' (duration: 394.248942ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:57:20.6433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:57:20.248844Z","time spent":"394.449989ms","remote":"127.0.0.1:58332","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:57:20.643636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.619458ms","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:57:20.643662Z","caller":"traceutil/trace.go:171","msg":"trace[1367423265] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:893; }","duration":"380.645949ms","start":"2026-02-18T22:57:20.26301Z","end":"2026-02-18T22:57:20.643656Z","steps":["trace[1367423265] 'agreement among raft nodes before linearized reading' (duration: 380.593037ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:57:20.643679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:57:20.26299Z","time spent":"380.68559ms","remote":"127.0.0.1:58332","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:57:24.760552Z","caller":"traceutil/trace.go:171","msg":"trace[1895588589] transaction","detail":"{read_only:false; response_revision:904; number_of_response:1; }","duration":"103.139455ms","start":"2026-02-18T22:57:24.657397Z","end":"2026-02-18T22:57:24.760536Z","steps":["trace[1895588589] 'process raft request' (duration: 102.904197ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:57:25.660888Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432438185379966894,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T22:57:25.940114Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.175015056s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-18T22:57:26.535809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"592.825261ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T22:57:26.537447Z","caller":"traceutil/trace.go:171","msg":"trace[1206839830] linearizableReadLoop","detail":"{readStateIndex:941; appliedIndex:938; }","duration":"1.376977715s","start":"2026-02-18T22:57:25.160456Z","end":"2026-02-18T22:57:26.537434Z","steps":["trace[1206839830] 'read index received' (duration: 782.49645ms)","trace[1206839830] 'applied index is now lower than readState.Index' (duration: 594.480575ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:57:26.537586Z","caller":"traceutil/trace.go:171","msg":"trace[1276941072] transaction","detail":"{read_only:false; response_revision:905; number_of_response:1; }","duration":"1.77251239s","start":"2026-02-18T22:57:24.765041Z","end":"2026-02-18T22:57:26.537553Z","steps":["trace[1276941072] 'process raft request' (duration: 1.17788787s)","trace[1276941072] 'compare' (duration: 592.521301ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:57:26.537632Z","caller":"traceutil/trace.go:171","msg":"trace[1100908715] transaction","detail":"{read_only:false; response_revision:907; number_of_response:1; }","duration":"1.771335892s","start":"2026-02-18T22:57:24.766274Z","end":"2026-02-18T22:57:26.53761Z","steps":["trace[1100908715] 'process raft request' (duration: 1.771133555s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:57:26.537758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:57:24.766268Z","time spent":"1.771422514s","remote":"127.0.0.1:58332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:57:26.537716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:57:24.765029Z","time spent":"1.772604743s","remote":"127.0.0.1:58332","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:57:26.537983Z","caller":"traceutil/trace.go:171","msg":"trace[1398177682] transaction","detail":"{read_only:false; response_revision:906; number_of_response:1; }","duration":"1.772642804s","start":"2026-02-18T22:57:24.765333Z","end":"2026-02-18T22:57:26.537976Z","steps":["trace[1398177682] 'process raft request' (duration: 1.772021313s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:57:26.538037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:57:24.765328Z","time spent":"1.772677425s","remote":"127.0.0.1:58332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:57:26.538166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.377714638s","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:57:26.538232Z","caller":"traceutil/trace.go:171","msg":"trace[1922615411] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:907; }","duration":"1.377820672s","start":"2026-02-18T22:57:25.160403Z","end":"2026-02-18T22:57:26.538224Z","steps":["trace[1922615411] 'agreement among raft nodes before linearized reading' (duration: 1.377596374s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:57:26.538297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:57:25.160382Z","time spent":"1.377885984s","remote":"127.0.0.1:58332","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:57:26.538332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.088741891s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.153\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-18T22:57:26.538363Z","caller":"traceutil/trace.go:171","msg":"trace[551168217] range","detail":"{range_begin:/registry/masterleases/199.204.45.153; range_end:; response_count:1; response_revision:907; }","duration":"1.088811213s","start":"2026-02-18T22:57:25.449544Z","end":"2026-02-18T22:57:26.538355Z","steps":["trace[551168217] 'agreement among raft nodes before linearized reading' (duration: 1.08870873s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:57:26.538384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:57:25.449522Z","time spent":"1.088855825s","remote":"127.0.0.1:58130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.153\" "} {"level":"warn","ts":"2026-02-18T22:57:26.538485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.589499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T22:57:26.538509Z","caller":"traceutil/trace.go:171","msg":"trace[374376497] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:907; }","duration":"194.652741ms","start":"2026-02-18T22:57:26.34385Z","end":"2026-02-18T22:57:26.538503Z","steps":["trace[374376497] 'agreement among raft nodes before linearized reading' (duration: 194.613939ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:57:26.538601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.194293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:57:26.538622Z","caller":"traceutil/trace.go:171","msg":"trace[1653603606] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:907; }","duration":"486.246564ms","start":"2026-02-18T22:57:26.052371Z","end":"2026-02-18T22:57:26.538617Z","steps":["trace[1653603606] 'agreement among raft nodes before linearized reading' (duration: 486.216383ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:57:26.538637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:57:26.052354Z","time spent":"486.280465ms","remote":"127.0.0.1:58094","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:57:26.539811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"668.710056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:57:26.539835Z","caller":"traceutil/trace.go:171","msg":"trace[2047839600] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:907; }","duration":"668.784158ms","start":"2026-02-18T22:57:25.871045Z","end":"2026-02-18T22:57:26.53983Z","steps":["trace[2047839600] 'agreement among raft nodes before linearized reading' (duration: 668.742567ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:57:26.539866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:57:25.871026Z","time spent":"668.83487ms","remote":"127.0.0.1:58484","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"warn","ts":"2026-02-18T22:57:27.043304Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432438185379966899,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-18T22:57:27.559305Z","caller":"traceutil/trace.go:171","msg":"trace[1079629194] linearizableReadLoop","detail":"{readStateIndex:943; appliedIndex:941; }","duration":"1.016260735s","start":"2026-02-18T22:57:26.543029Z","end":"2026-02-18T22:57:27.55929Z","steps":["trace[1079629194] 'read index received' (duration: 956.443945ms)","trace[1079629194] 'applied index is now lower than readState.Index' (duration: 59.81621ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:57:27.559382Z","caller":"traceutil/trace.go:171","msg":"trace[520657800] transaction","detail":"{read_only:false; response_revision:908; number_of_response:1; }","duration":"1.014319333s","start":"2026-02-18T22:57:26.545057Z","end":"2026-02-18T22:57:27.559377Z","steps":["trace[520657800] 'process raft request' (duration: 1.014173169s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:57:27.559445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:57:26.545046Z","time spent":"1.014351254s","remote":"127.0.0.1:58332","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:57:27.559461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:57:26.540579Z","time spent":"1.018876901s","remote":"127.0.0.1:58130","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:57:27.559562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.016532124s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:57:27.559581Z","caller":"traceutil/trace.go:171","msg":"trace[1443386839] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:908; }","duration":"1.016566446s","start":"2026-02-18T22:57:26.543009Z","end":"2026-02-18T22:57:27.559576Z","steps":["trace[1443386839] 'agreement among raft nodes before linearized reading' (duration: 1.016536595s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:57:27.559594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:57:26.542996Z","time spent":"1.016595836s","remote":"127.0.0.1:58108","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:57:27.559707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"792.909319ms","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:57:27.55972Z","caller":"traceutil/trace.go:171","msg":"trace[1386312318] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:908; }","duration":"792.960521ms","start":"2026-02-18T22:57:26.766755Z","end":"2026-02-18T22:57:27.559715Z","steps":["trace[1386312318] 'agreement among raft nodes before linearized reading' (duration: 792.898589ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:57:27.559733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:57:26.766734Z","time spent":"792.994912ms","remote":"127.0.0.1:58332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-18T22:57:30.731692Z","caller":"traceutil/trace.go:171","msg":"trace[595928940] transaction","detail":"{read_only:false; response_revision:916; number_of_response:1; }","duration":"178.198691ms","start":"2026-02-18T22:57:30.553468Z","end":"2026-02-18T22:57:30.731666Z","steps":["trace[595928940] 'process raft request' (duration: 178.098309ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:57:30.752151Z","caller":"traceutil/trace.go:171","msg":"trace[165553644] linearizableReadLoop","detail":"{readStateIndex:952; appliedIndex:951; }","duration":"197.340899ms","start":"2026-02-18T22:57:30.554788Z","end":"2026-02-18T22:57:30.752129Z","steps":["trace[165553644] 'read index received' (duration: 176.910018ms)","trace[165553644] 'applied index is now lower than readState.Index' (duration: 20.429941ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:57:30.752323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.513634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-18T22:57:30.752353Z","caller":"traceutil/trace.go:171","msg":"trace[1286620466] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:917; }","duration":"197.565935ms","start":"2026-02-18T22:57:30.554778Z","end":"2026-02-18T22:57:30.752344Z","steps":["trace[1286620466] 'agreement among raft nodes before linearized reading' (duration: 197.422641ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:57:30.752542Z","caller":"traceutil/trace.go:171","msg":"trace[1076862422] transaction","detail":"{read_only:false; response_revision:917; number_of_response:1; }","duration":"198.200121ms","start":"2026-02-18T22:57:30.554337Z","end":"2026-02-18T22:57:30.752537Z","steps":["trace[1076862422] 'process raft request' (duration: 197.707049ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:57:30.924495Z","caller":"traceutil/trace.go:171","msg":"trace[1640749875] transaction","detail":"{read_only:false; response_revision:919; number_of_response:1; }","duration":"147.755939ms","start":"2026-02-18T22:57:30.776721Z","end":"2026-02-18T22:57:30.924477Z","steps":["trace[1640749875] 'process raft request' (duration: 66.32573ms)","trace[1640749875] 'compare' (duration: 81.168582ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:57:31.691591Z","caller":"traceutil/trace.go:171","msg":"trace[622467848] transaction","detail":"{read_only:false; response_revision:920; number_of_response:1; }","duration":"117.348157ms","start":"2026-02-18T22:57:31.57423Z","end":"2026-02-18T22:57:31.691578Z","steps":["trace[622467848] 'process raft request' (duration: 116.965928ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:57:32.270427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.082834ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T22:57:32.270481Z","caller":"traceutil/trace.go:171","msg":"trace[2138656670] linearizableReadLoop","detail":"{readStateIndex:957; appliedIndex:956; }","duration":"291.653301ms","start":"2026-02-18T22:57:31.978819Z","end":"2026-02-18T22:57:32.270472Z","steps":["trace[2138656670] 'read index received' (duration: 26.261µs)","trace[2138656670] 'applied index is now lower than readState.Index' (duration: 291.62643ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:57:32.27059Z","caller":"traceutil/trace.go:171","msg":"trace[560299182] transaction","detail":"{read_only:false; response_revision:921; number_of_response:1; }","duration":"576.02625ms","start":"2026-02-18T22:57:31.694557Z","end":"2026-02-18T22:57:32.270584Z","steps":["trace[560299182] 'process raft request' (duration: 80.739521ms)","trace[560299182] 'compare' (duration: 494.954971ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:57:32.270618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:57:31.694546Z","time spent":"576.05737ms","remote":"127.0.0.1:58332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:57:32.270749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.923828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:57:32.270763Z","caller":"traceutil/trace.go:171","msg":"trace[2096846322] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:921; }","duration":"291.943368ms","start":"2026-02-18T22:57:31.978815Z","end":"2026-02-18T22:57:32.270759Z","steps":["trace[2096846322] 'agreement among raft nodes before linearized reading' (duration: 291.912037ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:57:32.270845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.763276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T22:57:32.270858Z","caller":"traceutil/trace.go:171","msg":"trace[439877410] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:921; }","duration":"222.780437ms","start":"2026-02-18T22:57:32.048073Z","end":"2026-02-18T22:57:32.270854Z","steps":["trace[439877410] 'agreement among raft nodes before linearized reading' (duration: 222.759416ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:57:35.261008Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432438185379966959,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-18T22:57:35.695681Z","caller":"traceutil/trace.go:171","msg":"trace[976169343] linearizableReadLoop","detail":"{readStateIndex:964; appliedIndex:963; }","duration":"935.426066ms","start":"2026-02-18T22:57:34.76024Z","end":"2026-02-18T22:57:35.695666Z","steps":["trace[976169343] 'read index received' (duration: 935.299903ms)","trace[976169343] 'applied index is now lower than readState.Index' (duration: 125.613µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:57:35.695915Z","caller":"traceutil/trace.go:171","msg":"trace[729323923] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"947.332404ms","start":"2026-02-18T22:57:34.748577Z","end":"2026-02-18T22:57:35.69591Z","steps":["trace[729323923] 'process raft request' (duration: 947.001236ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:57:35.695975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:57:34.748561Z","time spent":"947.368245ms","remote":"127.0.0.1:58332","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:57:35.696137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"935.893698ms","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:57:35.696152Z","caller":"traceutil/trace.go:171","msg":"trace[1237378129] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:928; }","duration":"935.918878ms","start":"2026-02-18T22:57:34.760228Z","end":"2026-02-18T22:57:35.696147Z","steps":["trace[1237378129] 'agreement among raft nodes before linearized reading' (duration: 935.860157ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:57:35.696165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:57:34.760223Z","time spent":"935.939269ms","remote":"127.0.0.1:58332","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:57:35.696248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"907.970379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-18T22:57:35.696278Z","caller":"traceutil/trace.go:171","msg":"trace[1167925204] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:928; }","duration":"908.01214ms","start":"2026-02-18T22:57:34.788262Z","end":"2026-02-18T22:57:35.696275Z","steps":["trace[1167925204] 'agreement among raft nodes before linearized reading' (duration: 907.958819ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:57:35.69629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:57:34.788255Z","time spent":"908.032531ms","remote":"127.0.0.1:58332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-18T22:57:35.696477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.89477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.153\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-18T22:57:35.696493Z","caller":"traceutil/trace.go:171","msg":"trace[1575081678] range","detail":"{range_begin:/registry/masterleases/199.204.45.153; range_end:; response_count:1; response_revision:928; }","duration":"246.928992ms","start":"2026-02-18T22:57:35.449559Z","end":"2026-02-18T22:57:35.696488Z","steps":["trace[1575081678] 'agreement among raft nodes before linearized reading' (duration: 246.889471ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:57:35.696569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"648.070512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:57:35.696581Z","caller":"traceutil/trace.go:171","msg":"trace[77516923] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:928; }","duration":"648.103294ms","start":"2026-02-18T22:57:35.048474Z","end":"2026-02-18T22:57:35.696577Z","steps":["trace[77516923] 'agreement among raft nodes before linearized reading' (duration: 648.084533ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:57:35.696592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:57:35.048462Z","time spent":"648.127594ms","remote":"127.0.0.1:58094","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:57:38.07193Z","caller":"traceutil/trace.go:171","msg":"trace[340566923] linearizableReadLoop","detail":"{readStateIndex:971; appliedIndex:970; }","duration":"312.915493ms","start":"2026-02-18T22:57:37.759003Z","end":"2026-02-18T22:57:38.071919Z","steps":["trace[340566923] 'read index received' (duration: 312.832811ms)","trace[340566923] 'applied index is now lower than readState.Index' (duration: 82.032µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:57:38.072002Z","caller":"traceutil/trace.go:171","msg":"trace[184107830] transaction","detail":"{read_only:false; response_revision:934; number_of_response:1; }","duration":"369.989161ms","start":"2026-02-18T22:57:37.702007Z","end":"2026-02-18T22:57:38.071996Z","steps":["trace[184107830] 'process raft request' (duration: 369.828527ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:57:38.072058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:57:37.701993Z","time spent":"370.021382ms","remote":"127.0.0.1:58332","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:57:38.072154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.149839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-18T22:57:38.072168Z","caller":"traceutil/trace.go:171","msg":"trace[473756479] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:934; }","duration":"313.19155ms","start":"2026-02-18T22:57:37.758972Z","end":"2026-02-18T22:57:38.072164Z","steps":["trace[473756479] 'agreement among raft nodes before linearized reading' (duration: 313.136409ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:57:38.072194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:57:37.758959Z","time spent":"313.232181ms","remote":"127.0.0.1:58332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-18T22:57:38.072326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.028997ms","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:57:38.072384Z","caller":"traceutil/trace.go:171","msg":"trace[722015329] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:934; }","duration":"313.102168ms","start":"2026-02-18T22:57:37.75927Z","end":"2026-02-18T22:57:38.072372Z","steps":["trace[722015329] 'agreement among raft nodes before linearized reading' (duration: 312.965685ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:57:38.072414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:57:37.759265Z","time spent":"313.140949ms","remote":"127.0.0.1:58332","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:57:38.072436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.2348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-18T22:57:38.07246Z","caller":"traceutil/trace.go:171","msg":"trace[668860935] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:934; }","duration":"257.292181ms","start":"2026-02-18T22:57:37.815163Z","end":"2026-02-18T22:57:38.072456Z","steps":["trace[668860935] 'agreement among raft nodes before linearized reading' (duration: 257.24096ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:57:38.720762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.466267ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T22:57:38.720883Z","caller":"traceutil/trace.go:171","msg":"trace[1963087109] linearizableReadLoop","detail":"{readStateIndex:974; appliedIndex:972; }","duration":"372.72192ms","start":"2026-02-18T22:57:38.34815Z","end":"2026-02-18T22:57:38.720872Z","steps":["trace[1963087109] 'read index received' (duration: 53.12073ms)","trace[1963087109] 'applied index is now lower than readState.Index' (duration: 319.60054ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:57:38.720986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.841083ms","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:57:38.721009Z","caller":"traceutil/trace.go:171","msg":"trace[1250059600] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:937; }","duration":"372.898335ms","start":"2026-02-18T22:57:38.348104Z","end":"2026-02-18T22:57:38.721003Z","steps":["trace[1250059600] 'agreement among raft nodes before linearized reading' (duration: 372.796842ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:57:38.72103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:57:38.348084Z","time spent":"372.942325ms","remote":"127.0.0.1:58332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-18T22:57:38.721212Z","caller":"traceutil/trace.go:171","msg":"trace[436483298] transaction","detail":"{read_only:false; response_revision:936; number_of_response:1; }","duration":"642.677839ms","start":"2026-02-18T22:57:38.078526Z","end":"2026-02-18T22:57:38.721204Z","steps":["trace[436483298] 'process raft request' (duration: 322.711949ms)","trace[436483298] 'compare' (duration: 319.162769ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:57:38.721254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:57:38.078506Z","time spent":"642.727299ms","remote":"127.0.0.1:58332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:57:38.721334Z","caller":"traceutil/trace.go:171","msg":"trace[138092061] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"640.74103ms","start":"2026-02-18T22:57:38.080588Z","end":"2026-02-18T22:57:38.721329Z","steps":["trace[138092061] 'process raft request' (duration: 640.243717ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:57:38.721365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:57:38.080579Z","time spent":"640.76826ms","remote":"127.0.0.1:58332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:57:42.529541Z","caller":"traceutil/trace.go:171","msg":"trace[1286215451] linearizableReadLoop","detail":"{readStateIndex:982; appliedIndex:981; }","duration":"363.011466ms","start":"2026-02-18T22:57:42.166503Z","end":"2026-02-18T22:57:42.529515Z","steps":["trace[1286215451] 'read index received' (duration: 362.871123ms)","trace[1286215451] 'applied index is now lower than readState.Index' (duration: 139.233µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:57:42.529742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.229192ms","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:57:42.529774Z","caller":"traceutil/trace.go:171","msg":"trace[1484927511] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:945; }","duration":"363.315424ms","start":"2026-02-18T22:57:42.166449Z","end":"2026-02-18T22:57:42.529765Z","steps":["trace[1484927511] 'agreement among raft nodes before linearized reading' (duration: 363.16289ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:57:42.529806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:57:42.166429Z","time spent":"363.370495ms","remote":"127.0.0.1:58332","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:57:42.529848Z","caller":"traceutil/trace.go:171","msg":"trace[276175038] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"446.699612ms","start":"2026-02-18T22:57:42.083118Z","end":"2026-02-18T22:57:42.529818Z","steps":["trace[276175038] 'process raft request' (duration: 446.254571ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:57:42.530007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:57:42.083106Z","time spent":"446.824945ms","remote":"127.0.0.1:58332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:57:42.959403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.520641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-18T22:57:42.9595Z","caller":"traceutil/trace.go:171","msg":"trace[1906438358] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:946; }","duration":"228.653924ms","start":"2026-02-18T22:57:42.730805Z","end":"2026-02-18T22:57:42.959459Z","steps":["trace[1906438358] 'range keys from in-memory index tree' (duration: 228.394527ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:57:42.960063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.337205ms","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:57:42.960104Z","caller":"traceutil/trace.go:171","msg":"trace[307191025] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:946; }","duration":"202.400406ms","start":"2026-02-18T22:57:42.757693Z","end":"2026-02-18T22:57:42.960093Z","steps":["trace[307191025] 'range keys from in-memory index tree' (duration: 202.253633ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:57:42.960558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.33265ms","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:57:42.960599Z","caller":"traceutil/trace.go:171","msg":"trace[1688362811] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:946; }","duration":"227.388941ms","start":"2026-02-18T22:57:42.733201Z","end":"2026-02-18T22:57:42.96059Z","steps":["trace[1688362811] 'range keys from in-memory index tree' (duration: 226.732525ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:57:43.362141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.425454ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-18T22:57:43.376077Z","caller":"traceutil/trace.go:171","msg":"trace[725264781] transaction","detail":"{read_only:false; response_revision:947; number_of_response:1; }","duration":"409.708966ms","start":"2026-02-18T22:57:42.966356Z","end":"2026-02-18T22:57:43.376065Z","steps":["trace[725264781] 'process raft request' (duration: 409.635784ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:57:43.376161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:57:42.966336Z","time spent":"409.784058ms","remote":"127.0.0.1:58332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:57:43.93701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.566477ms","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:57:43.937093Z","caller":"traceutil/trace.go:171","msg":"trace[1101272810] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:947; }","duration":"370.66081ms","start":"2026-02-18T22:57:43.566422Z","end":"2026-02-18T22:57:43.937083Z","steps":["trace[1101272810] 'range keys from in-memory index tree' (duration: 370.554066ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:57:43.973347Z","caller":"traceutil/trace.go:171","msg":"trace[585924517] transaction","detail":"{read_only:false; response_revision:949; number_of_response:1; }","duration":"1.004702991s","start":"2026-02-18T22:57:42.968623Z","end":"2026-02-18T22:57:43.973326Z","steps":["trace[585924517] 'process raft request' (duration: 1.00465899s)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:57:43.973484Z","caller":"traceutil/trace.go:171","msg":"trace[2141464250] transaction","detail":"{read_only:false; response_revision:948; number_of_response:1; }","duration":"1.006590149s","start":"2026-02-18T22:57:42.966884Z","end":"2026-02-18T22:57:43.973474Z","steps":["trace[2141464250] 'process raft request' (duration: 1.00626338s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:57:43.973559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:57:42.966864Z","time spent":"1.00665044s","remote":"127.0.0.1:58332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:57:43.973662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:57:42.968612Z","time spent":"1.004857125s","remote":"127.0.0.1:58332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:57:43.985863Z","caller":"traceutil/trace.go:171","msg":"trace[813358257] transaction","detail":"{read_only:false; response_revision:950; number_of_response:1; }","duration":"121.490191ms","start":"2026-02-18T22:57:43.864364Z","end":"2026-02-18T22:57:43.985854Z","steps":["trace[813358257] 'process raft request' (duration: 121.42311ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:57:44.170778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.681896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:57:44.170839Z","caller":"traceutil/trace.go:171","msg":"trace[760186147] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:950; }","duration":"121.773837ms","start":"2026-02-18T22:57:44.049051Z","end":"2026-02-18T22:57:44.170825Z","steps":["trace[760186147] 'range keys from in-memory index tree' (duration: 121.617935ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:57:44.663323Z","caller":"traceutil/trace.go:171","msg":"trace[399680755] transaction","detail":"{read_only:false; response_revision:951; number_of_response:1; }","duration":"114.859465ms","start":"2026-02-18T22:57:44.548441Z","end":"2026-02-18T22:57:44.663301Z","steps":["trace[399680755] 'process raft request' (duration: 114.340362ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:57:45.75456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"702.419892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:57:45.754617Z","caller":"traceutil/trace.go:171","msg":"trace[342403616] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:952; }","duration":"702.511165ms","start":"2026-02-18T22:57:45.052092Z","end":"2026-02-18T22:57:45.754603Z","steps":["trace[342403616] 'range keys from in-memory index tree' (duration: 702.363041ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:57:45.754643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:57:45.052035Z","time spent":"702.602938ms","remote":"127.0.0.1:58094","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:57:45.754944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.209213ms","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:57:45.754964Z","caller":"traceutil/trace.go:171","msg":"trace[1817057200] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:952; }","duration":"353.262974ms","start":"2026-02-18T22:57:45.401695Z","end":"2026-02-18T22:57:45.754958Z","steps":["trace[1817057200] 'range keys from in-memory index tree' (duration: 353.09954ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:57:45.754983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:57:45.401596Z","time spent":"353.381687ms","remote":"127.0.0.1:58332","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:57:45.755147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.737998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.153\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-18T22:57:45.755165Z","caller":"traceutil/trace.go:171","msg":"trace[1382967421] range","detail":"{range_begin:/registry/masterleases/199.204.45.153; range_end:; response_count:1; response_revision:952; }","duration":"304.797941ms","start":"2026-02-18T22:57:45.450362Z","end":"2026-02-18T22:57:45.75516Z","steps":["trace[1382967421] 'range keys from in-memory index tree' (duration: 304.610655ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:57:45.755181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:57:45.45034Z","time spent":"304.837521ms","remote":"127.0.0.1:58130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.153\" "} {"level":"warn","ts":"2026-02-18T22:57:51.094227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.573067ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-02-18T22:58:01.149924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.854831ms","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:58:01.150007Z","caller":"traceutil/trace.go:171","msg":"trace[736661602] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1060; }","duration":"432.996895ms","start":"2026-02-18T22:58:00.716994Z","end":"2026-02-18T22:58:01.14999Z","steps":["trace[736661602] 'range keys from in-memory index tree' (duration: 432.684757ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:58:01.150657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:58:00.716975Z","time spent":"433.119189ms","remote":"127.0.0.1:58332","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:58:01.151706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.098952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-18T22:58:01.151824Z","caller":"traceutil/trace.go:171","msg":"trace[1093023238] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1060; }","duration":"291.202465ms","start":"2026-02-18T22:58:00.860571Z","end":"2026-02-18T22:58:01.151773Z","steps":["trace[1093023238] 'range keys from in-memory index tree' (duration: 290.958219ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:58:01.437943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.536259ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-18T22:58:01.438031Z","caller":"traceutil/trace.go:171","msg":"trace[423009675] linearizableReadLoop","detail":"{readStateIndex:1103; appliedIndex:1102; }","duration":"336.972793ms","start":"2026-02-18T22:58:01.101042Z","end":"2026-02-18T22:58:01.438014Z","steps":["trace[423009675] 'read index received' (duration: 34.305251ms)","trace[423009675] 'applied index is now lower than readState.Index' (duration: 302.666252ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:58:01.438169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.115616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-02-18T22:58:01.438198Z","caller":"traceutil/trace.go:171","msg":"trace[611205972] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1060; }","duration":"337.188818ms","start":"2026-02-18T22:58:01.100998Z","end":"2026-02-18T22:58:01.438187Z","steps":["trace[611205972] 'agreement among raft nodes before linearized reading' (duration: 337.059725ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:58:01.438231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:58:01.100984Z","time spent":"337.23707ms","remote":"127.0.0.1:58332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":548,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-02-18T22:58:01.445755Z","caller":"traceutil/trace.go:171","msg":"trace[1582715438] transaction","detail":"{read_only:false; response_revision:1061; number_of_response:1; }","duration":"288.480306ms","start":"2026-02-18T22:58:01.157264Z","end":"2026-02-18T22:58:01.445744Z","steps":["trace[1582715438] 'process raft request' (duration: 288.375544ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:58:02.034573Z","caller":"traceutil/trace.go:171","msg":"trace[846112343] linearizableReadLoop","detail":"{readStateIndex:1107; appliedIndex:1104; }","duration":"184.74494ms","start":"2026-02-18T22:58:01.849808Z","end":"2026-02-18T22:58:02.034553Z","steps":["trace[846112343] 'read index received' (duration: 183.871329ms)","trace[846112343] 'applied index is now lower than readState.Index' (duration: 872.821µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:58:02.034909Z","caller":"traceutil/trace.go:171","msg":"trace[1228748110] transaction","detail":"{read_only:false; response_revision:1062; number_of_response:1; }","duration":"876.152867ms","start":"2026-02-18T22:58:01.158699Z","end":"2026-02-18T22:58:02.034852Z","steps":["trace[1228748110] 'process raft request' (duration: 875.49336ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:58:02.035105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:58:01.158673Z","time spent":"876.308851ms","remote":"127.0.0.1:58332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:58:02.035321Z","caller":"traceutil/trace.go:171","msg":"trace[849328207] transaction","detail":"{read_only:false; response_revision:1063; number_of_response:1; }","duration":"695.741705ms","start":"2026-02-18T22:58:01.339566Z","end":"2026-02-18T22:58:02.035308Z","steps":["trace[849328207] 'process raft request' (duration: 694.909414ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:58:02.035532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:58:01.339545Z","time spent":"695.842197ms","remote":"127.0.0.1:58332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:58:02.035687Z","caller":"traceutil/trace.go:171","msg":"trace[869123529] transaction","detail":"{read_only:false; response_revision:1064; number_of_response:1; }","duration":"593.695902ms","start":"2026-02-18T22:58:01.441978Z","end":"2026-02-18T22:58:02.035674Z","steps":["trace[869123529] 'process raft request' (duration: 592.537383ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:58:02.035832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:58:01.441966Z","time spent":"593.806295ms","remote":"127.0.0.1:58332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:58:02.036099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.285788ms","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:02.036172Z","caller":"traceutil/trace.go:171","msg":"trace[1897009247] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1064; }","duration":"186.414541ms","start":"2026-02-18T22:58:01.849747Z","end":"2026-02-18T22:58:02.036161Z","steps":["trace[1897009247] 'agreement among raft nodes before linearized reading' (duration: 186.230777ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:58:02.036481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.576764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:510"} {"level":"info","ts":"2026-02-18T22:58:02.036727Z","caller":"traceutil/trace.go:171","msg":"trace[1153226079] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1064; }","duration":"160.82893ms","start":"2026-02-18T22:58:01.875861Z","end":"2026-02-18T22:58:02.036689Z","steps":["trace[1153226079] 'agreement among raft nodes before linearized reading' (duration: 160.515132ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:58:02.765011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.75007ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T22:58:02.765298Z","caller":"traceutil/trace.go:171","msg":"trace[645762175] linearizableReadLoop","detail":"{readStateIndex:1109; appliedIndex:1107; }","duration":"716.425465ms","start":"2026-02-18T22:58:02.048855Z","end":"2026-02-18T22:58:02.76528Z","steps":["trace[645762175] 'read index received' (duration: 328.338947ms)","trace[645762175] 'applied index is now lower than readState.Index' (duration: 388.085668ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:58:02.765557Z","caller":"traceutil/trace.go:171","msg":"trace[1336539966] transaction","detail":"{read_only:false; response_revision:1065; number_of_response:1; }","duration":"722.341643ms","start":"2026-02-18T22:58:02.0432Z","end":"2026-02-18T22:58:02.765542Z","steps":["trace[1336539966] 'process raft request' (duration: 333.982638ms)","trace[1336539966] 'compare' (duration: 387.270147ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:58:02.765691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:58:02.043183Z","time spent":"722.447616ms","remote":"127.0.0.1:58332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:58:02.765844Z","caller":"traceutil/trace.go:171","msg":"trace[1098056552] transaction","detail":"{read_only:false; response_revision:1066; number_of_response:1; }","duration":"721.559564ms","start":"2026-02-18T22:58:02.044264Z","end":"2026-02-18T22:58:02.765824Z","steps":["trace[1098056552] 'process raft request' (duration: 720.958469ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:58:02.765953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:58:02.044255Z","time spent":"721.637476ms","remote":"127.0.0.1:58332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":495,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:58:02.766161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"707.658975ms","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:02.76625Z","caller":"traceutil/trace.go:171","msg":"trace[1311145972] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1066; }","duration":"707.763707ms","start":"2026-02-18T22:58:02.058473Z","end":"2026-02-18T22:58:02.766236Z","steps":["trace[1311145972] 'agreement among raft nodes before linearized reading' (duration: 707.562872ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:58:02.766323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:58:02.058466Z","time spent":"707.845619ms","remote":"127.0.0.1:58332","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:58:02.766765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"696.453032ms","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:58:02.767378Z","caller":"traceutil/trace.go:171","msg":"trace[1013188250] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1066; }","duration":"697.083598ms","start":"2026-02-18T22:58:02.07028Z","end":"2026-02-18T22:58:02.767364Z","steps":["trace[1013188250] 'agreement among raft nodes before linearized reading' (duration: 696.3706ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:58:02.767519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:58:02.070269Z","time spent":"697.221661ms","remote":"127.0.0.1:58332","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:58:02.765657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"716.787764ms","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:02.770348Z","caller":"traceutil/trace.go:171","msg":"trace[840606346] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1066; }","duration":"721.500221ms","start":"2026-02-18T22:58:02.048824Z","end":"2026-02-18T22:58:02.770324Z","steps":["trace[840606346] 'agreement among raft nodes before linearized reading' (duration: 716.737892ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:58:02.768453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.591217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-02-18T22:58:02.771291Z","caller":"traceutil/trace.go:171","msg":"trace[811613446] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1066; }","duration":"496.465149ms","start":"2026-02-18T22:58:02.274816Z","end":"2026-02-18T22:58:02.771282Z","steps":["trace[811613446] 'agreement among raft nodes before linearized reading' (duration: 491.511394ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:58:02.771374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:58:02.274802Z","time spent":"496.560522ms","remote":"127.0.0.1:58332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":554,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-02-18T22:58:02.771406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:58:02.048813Z","time spent":"722.525618ms","remote":"127.0.0.1:58094","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-18T23:01:36.371706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.865088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/default/\" range_end:\"/registry/cronjobs/default0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T23:01:36.372114Z","caller":"traceutil/trace.go:171","msg":"trace[1122155310] range","detail":"{range_begin:/registry/cronjobs/default/; range_end:/registry/cronjobs/default0; response_count:0; response_revision:1980; }","duration":"179.288438ms","start":"2026-02-18T23:01:36.192772Z","end":"2026-02-18T23:01:36.372061Z","steps":["trace[1122155310] 'range keys from in-memory index tree' (duration: 178.798376ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T23:01:36.372629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.628759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-node-lease/kube-root-ca.crt\" ","response":"range_response_count:1 size:1754"} {"level":"info","ts":"2026-02-18T23:01:36.372838Z","caller":"traceutil/trace.go:171","msg":"trace[104290335] range","detail":"{range_begin:/registry/configmaps/kube-node-lease/kube-root-ca.crt; range_end:; response_count:1; response_revision:1980; }","duration":"212.878326ms","start":"2026-02-18T23:01:36.159945Z","end":"2026-02-18T23:01:36.372824Z","steps":["trace[104290335] 'range keys from in-memory index tree' (duration: 212.523076ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T23:01:36.371972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.327967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-node-lease/\" range_end:\"/registry/daemonsets/kube-node-lease0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T23:01:36.373381Z","caller":"traceutil/trace.go:171","msg":"trace[695564386] range","detail":"{range_begin:/registry/daemonsets/kube-node-lease/; range_end:/registry/daemonsets/kube-node-lease0; response_count:0; response_revision:1980; }","duration":"158.774003ms","start":"2026-02-18T23:01:36.214589Z","end":"2026-02-18T23:01:36.373363Z","steps":["trace[695564386] 'range keys from in-memory index tree' (duration: 157.288786ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T23:01:36.372776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.062379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/default/kube-root-ca.crt\" ","response":"range_response_count:1 size:1738"} {"level":"info","ts":"2026-02-18T23:01:36.374165Z","caller":"traceutil/trace.go:171","msg":"trace[389676452] range","detail":"{range_begin:/registry/configmaps/default/kube-root-ca.crt; range_end:; response_count:1; response_revision:1980; }","duration":"203.486785ms","start":"2026-02-18T23:01:36.170658Z","end":"2026-02-18T23:01:36.374145Z","steps":["trace[389676452] 'range keys from in-memory index tree' (duration: 201.993368ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T23:01:37.258467Z","caller":"traceutil/trace.go:171","msg":"trace[1412612475] transaction","detail":"{read_only:false; response_revision:1982; number_of_response:1; }","duration":"125.945484ms","start":"2026-02-18T23:01:37.132502Z","end":"2026-02-18T23:01:37.258448Z","steps":["trace[1412612475] 'process raft request' (duration: 125.677937ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T23:01:37.631283Z","caller":"traceutil/trace.go:171","msg":"trace[1435895221] transaction","detail":"{read_only:false; response_revision:1983; number_of_response:1; }","duration":"141.014066ms","start":"2026-02-18T23:01:37.490248Z","end":"2026-02-18T23:01:37.631262Z","steps":["trace[1435895221] 'process raft request' (duration: 140.730059ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T23:01:38.374981Z","caller":"traceutil/trace.go:171","msg":"trace[1791053870] linearizableReadLoop","detail":"{readStateIndex:2076; appliedIndex:2074; }","duration":"182.649682ms","start":"2026-02-18T23:01:38.192319Z","end":"2026-02-18T23:01:38.374968Z","steps":["trace[1791053870] 'read index received' (duration: 3.800724ms)","trace[1791053870] 'applied index is now lower than readState.Index' (duration: 178.848468ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T23:01:38.375115Z","caller":"traceutil/trace.go:171","msg":"trace[451639771] transaction","detail":"{read_only:false; response_revision:1987; number_of_response:1; }","duration":"218.226658ms","start":"2026-02-18T23:01:38.156882Z","end":"2026-02-18T23:01:38.375109Z","steps":["trace[451639771] 'process raft request' (duration: 159.147482ms)","trace[451639771] 'compare' (duration: 58.761988ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T23:01:38.375217Z","caller":"traceutil/trace.go:171","msg":"trace[35227137] transaction","detail":"{read_only:false; response_revision:1988; number_of_response:1; }","duration":"214.875685ms","start":"2026-02-18T23:01:38.160327Z","end":"2026-02-18T23:01:38.375203Z","steps":["trace[35227137] 'process raft request' (duration: 214.616069ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T23:01:38.375336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.0077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-02-18T23:01:38.375358Z","caller":"traceutil/trace.go:171","msg":"trace[347664185] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1988; }","duration":"183.058571ms","start":"2026-02-18T23:01:38.192293Z","end":"2026-02-18T23:01:38.375352Z","steps":["trace[347664185] 'agreement among raft nodes before linearized reading' (duration: 182.986099ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T23:01:38.375431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.022647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/default/kubernetes\" ","response":"range_response_count:1 size:704"} {"level":"info","ts":"2026-02-18T23:01:38.375447Z","caller":"traceutil/trace.go:171","msg":"trace[130378465] range","detail":"{range_begin:/registry/services/specs/default/kubernetes; range_end:; response_count:1; response_revision:1988; }","duration":"109.060568ms","start":"2026-02-18T23:01:38.266383Z","end":"2026-02-18T23:01:38.375443Z","steps":["trace[130378465] 'agreement among raft nodes before linearized reading' (duration: 109.032628ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T23:01:38.671733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.611114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-node-lease/\" range_end:\"/registry/rolebindings/kube-node-lease0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T23:01:38.671938Z","caller":"traceutil/trace.go:171","msg":"trace[1601146987] range","detail":"{range_begin:/registry/rolebindings/kube-node-lease/; range_end:/registry/rolebindings/kube-node-lease0; response_count:0; response_revision:1990; }","duration":"187.839359ms","start":"2026-02-18T23:01:38.484079Z","end":"2026-02-18T23:01:38.671918Z","steps":["trace[1601146987] 'range keys from in-memory index tree' (duration: 187.574983ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T23:01:38.672231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.988851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-public/kube-root-ca.crt\" ","response":"range_response_count:1 size:1746"} {"level":"info","ts":"2026-02-18T23:01:38.672348Z","caller":"traceutil/trace.go:171","msg":"trace[1602634666] range","detail":"{range_begin:/registry/configmaps/kube-public/kube-root-ca.crt; range_end:; response_count:1; response_revision:1990; }","duration":"112.129964ms","start":"2026-02-18T23:01:38.560206Z","end":"2026-02-18T23:01:38.672336Z","steps":["trace[1602634666] 'range keys from in-memory index tree' (duration: 111.931429ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T23:01:39.386984Z","caller":"traceutil/trace.go:171","msg":"trace[1430189457] transaction","detail":"{read_only:false; response_revision:1991; number_of_response:1; }","duration":"122.056687ms","start":"2026-02-18T23:01:39.264909Z","end":"2026-02-18T23:01:39.386966Z","steps":["trace[1430189457] 'process raft request' (duration: 121.939054ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T23:01:40.209163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"548.72571ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T23:01:40.209254Z","caller":"traceutil/trace.go:171","msg":"trace[2094906647] linearizableReadLoop","detail":"{readStateIndex:2080; appliedIndex:2079; }","duration":"729.372563ms","start":"2026-02-18T23:01:39.479865Z","end":"2026-02-18T23:01:40.209238Z","steps":["trace[2094906647] 'read index received' (duration: 180.55356ms)","trace[2094906647] 'applied index is now lower than readState.Index' (duration: 548.817813ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T23:01:40.209548Z","caller":"traceutil/trace.go:171","msg":"trace[391324390] transaction","detail":"{read_only:false; response_revision:1992; number_of_response:1; }","duration":"744.975927ms","start":"2026-02-18T23:01:39.464556Z","end":"2026-02-18T23:01:40.209532Z","steps":["trace[391324390] 'process raft request' (duration: 195.811716ms)","trace[391324390] 'compare' (duration: 548.424913ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T23:01:40.209609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T23:01:39.464545Z","time spent":"745.027789ms","remote":"127.0.0.1:58332","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-18T23:01:40.209796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"729.938746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/kube-public/\" range_end:\"/registry/persistentvolumeclaims/kube-public0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T23:01:40.209826Z","caller":"traceutil/trace.go:171","msg":"trace[1295689613] range","detail":"{range_begin:/registry/persistentvolumeclaims/kube-public/; range_end:/registry/persistentvolumeclaims/kube-public0; response_count:0; response_revision:1992; }","duration":"729.997598ms","start":"2026-02-18T23:01:39.479819Z","end":"2026-02-18T23:01:40.209817Z","steps":["trace[1295689613] 'agreement among raft nodes before linearized reading' (duration: 729.945077ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T23:01:40.209857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T23:01:39.479799Z","time spent":"730.050409ms","remote":"127.0.0.1:58234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumeclaims/kube-public/\" range_end:\"/registry/persistentvolumeclaims/kube-public0\" limit:500 "} {"level":"warn","ts":"2026-02-18T23:01:40.209859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"650.902639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-public/\" range_end:\"/registry/rolebindings/kube-public0\" limit:500 ","response":"range_response_count:2 size:1230"} {"level":"info","ts":"2026-02-18T23:01:40.209905Z","caller":"traceutil/trace.go:171","msg":"trace[203864730] range","detail":"{range_begin:/registry/rolebindings/kube-public/; range_end:/registry/rolebindings/kube-public0; response_count:2; response_revision:1992; }","duration":"650.980371ms","start":"2026-02-18T23:01:39.558912Z","end":"2026-02-18T23:01:40.209892Z","steps":["trace[203864730] 'agreement among raft nodes before linearized reading' (duration: 650.889139ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T23:01:40.20994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T23:01:39.558884Z","time spent":"651.046352ms","remote":"127.0.0.1:58408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":77,"response count":2,"response size":1253,"request content":"key:\"/registry/rolebindings/kube-public/\" range_end:\"/registry/rolebindings/kube-public0\" limit:500 "} {"level":"warn","ts":"2026-02-18T23:01:40.210275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.607347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-02-18T23:01:40.210308Z","caller":"traceutil/trace.go:171","msg":"trace[1475601747] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1992; }","duration":"270.676329ms","start":"2026-02-18T23:01:39.939622Z","end":"2026-02-18T23:01:40.210298Z","steps":["trace[1475601747] 'agreement among raft nodes before linearized reading' (duration: 270.579076ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T23:01:40.210404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.983407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T23:01:40.210427Z","caller":"traceutil/trace.go:171","msg":"trace[631068544] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1992; }","duration":"159.042639ms","start":"2026-02-18T23:01:40.051377Z","end":"2026-02-18T23:01:40.21042Z","steps":["trace[631068544] 'agreement among raft nodes before linearized reading' (duration: 159.009048ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T23:01:46.640959Z","caller":"traceutil/trace.go:171","msg":"trace[1791508091] transaction","detail":"{read_only:false; response_revision:2022; number_of_response:1; }","duration":"144.443101ms","start":"2026-02-18T23:01:46.496488Z","end":"2026-02-18T23:01:46.640931Z","steps":["trace[1791508091] 'process raft request' (duration: 100.432216ms)","trace[1791508091] 'compare' (duration: 43.860631ms)"],"step_count":2}