{"level":"warn","ts":"2026-02-25T02:13:02.785804Z","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-25T02:13:02.785897Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.149: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.149:2380","--initial-cluster=instance=https://199.204.45.149:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.149:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.149: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-25T02:13:02.786196Z","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-25T02:13:02.786216Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.149:2380"]} {"level":"info","ts":"2026-02-25T02:13:02.786236Z","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-25T02:13:02.787227Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.149:2379"]} {"level":"info","ts":"2026-02-25T02:13:02.787418Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.149:2380"],"listen-peer-urls":["https://199.204.45.149:2380"],"advertise-client-urls":["https://199.204.45.149:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.149:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.149: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-25T02:13:02.791224Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.502736ms"} {"level":"info","ts":"2026-02-25T02:13:02.800412Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b1b675f124322401","cluster-id":"913dfbf24a38e0aa"} {"level":"info","ts":"2026-02-25T02:13:02.800544Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1b675f124322401 switched to configuration voters=()"} {"level":"info","ts":"2026-02-25T02:13:02.800584Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1b675f124322401 became follower at term 0"} {"level":"info","ts":"2026-02-25T02:13:02.800622Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b1b675f124322401 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-25T02:13:02.800653Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1b675f124322401 became follower at term 1"} {"level":"info","ts":"2026-02-25T02:13:02.800733Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1b675f124322401 switched to configuration voters=(12805552269029417985)"} {"level":"warn","ts":"2026-02-25T02:13:02.809636Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-25T02:13:02.811956Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-25T02:13:02.813252Z","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-25T02:13:02.815664Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b1b675f124322401","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-25T02:13:02.816337Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b1b675f124322401","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-25T02:13:02.816379Z","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-25T02:13:02.816495Z","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-25T02:13:02.816521Z","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-25T02:13:02.820609Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1b675f124322401 switched to configuration voters=(12805552269029417985)"} {"level":"info","ts":"2026-02-25T02:13:02.82093Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"913dfbf24a38e0aa","local-member-id":"b1b675f124322401","added-peer-id":"b1b675f124322401","added-peer-peer-urls":["https://199.204.45.149:2380"]} {"level":"info","ts":"2026-02-25T02:13:02.822005Z","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-25T02:13:02.822309Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.149:2380"} {"level":"info","ts":"2026-02-25T02:13:02.82241Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.149:2380"} {"level":"info","ts":"2026-02-25T02:13:02.822827Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b1b675f124322401","initial-advertise-peer-urls":["https://199.204.45.149:2380"],"listen-peer-urls":["https://199.204.45.149:2380"],"advertise-client-urls":["https://199.204.45.149:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.149:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-25T02:13:02.822878Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-25T02:13:03.301763Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1b675f124322401 is starting a new election at term 1"} {"level":"info","ts":"2026-02-25T02:13:03.301841Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1b675f124322401 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-25T02:13:03.30191Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1b675f124322401 received MsgPreVoteResp from b1b675f124322401 at term 1"} {"level":"info","ts":"2026-02-25T02:13:03.301953Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1b675f124322401 became candidate at term 2"} {"level":"info","ts":"2026-02-25T02:13:03.301998Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1b675f124322401 received MsgVoteResp from b1b675f124322401 at term 2"} {"level":"info","ts":"2026-02-25T02:13:03.302022Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1b675f124322401 became leader at term 2"} {"level":"info","ts":"2026-02-25T02:13:03.302032Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b1b675f124322401 elected leader b1b675f124322401 at term 2"} {"level":"info","ts":"2026-02-25T02:13:03.400252Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b1b675f124322401","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.149:2379]}","request-path":"/0/members/b1b675f124322401/attributes","cluster-id":"913dfbf24a38e0aa","publish-timeout":"7s"} {"level":"info","ts":"2026-02-25T02:13:03.400573Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T02:13:03.400787Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T02:13:03.400847Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T02:13:03.401105Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-25T02:13:03.401193Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-25T02:13:03.404161Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-25T02:13:03.405474Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.149:2379"} {"level":"info","ts":"2026-02-25T02:13:03.48542Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"913dfbf24a38e0aa","local-member-id":"b1b675f124322401","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T02:13:03.48565Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T02:13:03.485711Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-02-25T02:13:26.92156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:13:26.506287Z","time spent":"415.269059ms","remote":"127.0.0.1:54262","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-25T02:13:35.615366Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2594527013674603620,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T02:13:35.883Z","caller":"traceutil/trace.go:171","msg":"trace[220753961] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"860.314952ms","start":"2026-02-25T02:13:35.022648Z","end":"2026-02-25T02:13:35.882963Z","steps":["trace[220753961] 'process raft request' (duration: 860.057354ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:13:35.886623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:13:35.022615Z","time spent":"862.649069ms","remote":"127.0.0.1:54418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4258,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:13:35.889847Z","caller":"traceutil/trace.go:171","msg":"trace[1345754984] linearizableReadLoop","detail":"{readStateIndex:534; appliedIndex:532; }","duration":"775.205509ms","start":"2026-02-25T02:13:35.114561Z","end":"2026-02-25T02:13:35.889766Z","steps":["trace[1345754984] 'read index received' (duration: 768.226968ms)","trace[1345754984] 'applied index is now lower than readState.Index' (duration: 6.976441ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:13:35.890187Z","caller":"traceutil/trace.go:171","msg":"trace[1058150814] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"332.196313ms","start":"2026-02-25T02:13:35.557862Z","end":"2026-02-25T02:13:35.890058Z","steps":["trace[1058150814] 'process raft request' (duration: 331.677936ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:13:35.89041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:13:35.557836Z","time spent":"332.453172ms","remote":"127.0.0.1:54508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:13:35.890512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"697.100587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-25T02:13:35.890692Z","caller":"traceutil/trace.go:171","msg":"trace[205825031] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:516; }","duration":"697.335074ms","start":"2026-02-25T02:13:35.193273Z","end":"2026-02-25T02:13:35.890608Z","steps":["trace[205825031] 'agreement among raft nodes before linearized reading' (duration: 696.92243ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:13:35.890711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"608.57798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-02-25T02:13:35.890762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"776.178171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-02-25T02:13:35.890779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:13:35.193247Z","time spent":"697.51818ms","remote":"127.0.0.1:54508","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-25T02:13:35.890801Z","caller":"traceutil/trace.go:171","msg":"trace[549700909] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:516; }","duration":"608.721255ms","start":"2026-02-25T02:13:35.282062Z","end":"2026-02-25T02:13:35.890783Z","steps":["trace[549700909] 'agreement among raft nodes before linearized reading' (duration: 608.499528ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:13:35.890834Z","caller":"traceutil/trace.go:171","msg":"trace[228506168] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:516; }","duration":"776.298785ms","start":"2026-02-25T02:13:35.114503Z","end":"2026-02-25T02:13:35.890802Z","steps":["trace[228506168] 'agreement among raft nodes before linearized reading' (duration: 775.517049ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:13:35.890848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:13:35.282033Z","time spent":"608.802588ms","remote":"127.0.0.1:54508","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-25T02:13:35.890871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:13:35.114479Z","time spent":"776.378727ms","remote":"127.0.0.1:54508","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-25T02:13:35.89197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"698.600336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-25T02:13:35.89205Z","caller":"traceutil/trace.go:171","msg":"trace[843115722] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:516; }","duration":"698.72388ms","start":"2026-02-25T02:13:35.193309Z","end":"2026-02-25T02:13:35.892033Z","steps":["trace[843115722] 'agreement among raft nodes before linearized reading' (duration: 697.344555ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:13:35.892109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:13:35.193282Z","time spent":"698.819183ms","remote":"127.0.0.1:54508","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-25T02:13:42.558451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.535055ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T02:13:42.558942Z","caller":"traceutil/trace.go:171","msg":"trace[455620354] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"603.398828ms","start":"2026-02-25T02:13:41.9555Z","end":"2026-02-25T02:13:42.558899Z","steps":["trace[455620354] 'process raft request' (duration: 603.089308ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:13:42.559039Z","caller":"traceutil/trace.go:171","msg":"trace[886410070] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"603.45181ms","start":"2026-02-25T02:13:41.9555Z","end":"2026-02-25T02:13:42.558952Z","steps":["trace[886410070] 'process raft request' (duration: 603.15504ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:13:42.559137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:13:41.955478Z","time spent":"603.607695ms","remote":"127.0.0.1:54508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:13:42.559119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:13:41.955469Z","time spent":"603.561764ms","remote":"127.0.0.1:54508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:13:42.559156Z","caller":"traceutil/trace.go:171","msg":"trace[1066929794] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"605.362803ms","start":"2026-02-25T02:13:41.953712Z","end":"2026-02-25T02:13:42.559075Z","steps":["trace[1066929794] 'process raft request' (duration: 470.929039ms)","trace[1066929794] 'compare' (duration: 133.226434ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:13:42.559443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:13:41.953684Z","time spent":"605.633402ms","remote":"127.0.0.1:54508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:13:42.877447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.736648ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-25T02:13:42.87754Z","caller":"traceutil/trace.go:171","msg":"trace[1970373419] linearizableReadLoop","detail":"{readStateIndex:557; appliedIndex:556; }","duration":"274.234246ms","start":"2026-02-25T02:13:42.603291Z","end":"2026-02-25T02:13:42.877526Z","steps":["trace[1970373419] 'read index received' (duration: 30.821µs)","trace[1970373419] 'applied index is now lower than readState.Index' (duration: 274.201905ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:13:42.877625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.324749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-hsr4m\" ","response":"range_response_count:1 size:16656"} {"level":"info","ts":"2026-02-25T02:13:42.877645Z","caller":"traceutil/trace.go:171","msg":"trace[1290304887] range","detail":"{range_begin:/registry/pods/kube-system/cilium-hsr4m; range_end:; response_count:1; response_revision:537; }","duration":"274.390942ms","start":"2026-02-25T02:13:42.603247Z","end":"2026-02-25T02:13:42.877638Z","steps":["trace[1290304887] 'agreement among raft nodes before linearized reading' (duration: 274.315259ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:13:43.347596Z","caller":"traceutil/trace.go:171","msg":"trace[564192296] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"454.298289ms","start":"2026-02-25T02:13:42.893251Z","end":"2026-02-25T02:13:43.347549Z","steps":["trace[564192296] 'process raft request' (duration: 454.01167ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:13:43.347801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:13:42.893224Z","time spent":"454.469285ms","remote":"127.0.0.1:54430","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:13:44.766728Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2594527013674603681,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T02:13:44.878495Z","caller":"traceutil/trace.go:171","msg":"trace[628476059] linearizableReadLoop","detail":"{readStateIndex:559; appliedIndex:558; }","duration":"612.331822ms","start":"2026-02-25T02:13:44.266135Z","end":"2026-02-25T02:13:44.878467Z","steps":["trace[628476059] 'read index received' (duration: 611.917269ms)","trace[628476059] 'applied index is now lower than readState.Index' (duration: 413.253µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:13:44.87861Z","caller":"traceutil/trace.go:171","msg":"trace[89128983] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"929.396864ms","start":"2026-02-25T02:13:43.949163Z","end":"2026-02-25T02:13:44.87856Z","steps":["trace[89128983] 'process raft request' (duration: 929.013332ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:13:44.878697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.302813ms","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-25T02:13:44.878735Z","caller":"traceutil/trace.go:171","msg":"trace[592351978] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:539; }","duration":"315.393766ms","start":"2026-02-25T02:13:44.56333Z","end":"2026-02-25T02:13:44.878724Z","steps":["trace[592351978] 'agreement among raft nodes before linearized reading' (duration: 315.302173ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:13:44.878731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"612.5655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T02:13:44.87885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.762695ms","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-25T02:13:44.878886Z","caller":"traceutil/trace.go:171","msg":"trace[1392098496] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:539; }","duration":"314.833298ms","start":"2026-02-25T02:13:44.564044Z","end":"2026-02-25T02:13:44.878877Z","steps":["trace[1392098496] 'agreement among raft nodes before linearized reading' (duration: 314.782366ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:13:44.878885Z","caller":"traceutil/trace.go:171","msg":"trace[602870629] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:539; }","duration":"612.774588ms","start":"2026-02-25T02:13:44.266069Z","end":"2026-02-25T02:13:44.878843Z","steps":["trace[602870629] 'agreement among raft nodes before linearized reading' (duration: 612.53079ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:13:44.878913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:13:44.564022Z","time spent":"314.885739ms","remote":"127.0.0.1:54508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T02:13:44.878803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:13:44.563303Z","time spent":"315.491029ms","remote":"127.0.0.1:54508","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-25T02:13:44.878958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:13:44.266038Z","time spent":"612.909472ms","remote":"127.0.0.1:54252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T02:13:44.879019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:13:43.949142Z","time spent":"929.591491ms","remote":"127.0.0.1:54508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:13:44.879598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.274092ms","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-25T02:13:44.879748Z","caller":"traceutil/trace.go:171","msg":"trace[757218186] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:539; }","duration":"315.545191ms","start":"2026-02-25T02:13:44.564175Z","end":"2026-02-25T02:13:44.87972Z","steps":["trace[757218186] 'agreement among raft nodes before linearized reading' (duration: 314.62089ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:13:44.879885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:13:44.564141Z","time spent":"315.691266ms","remote":"127.0.0.1:54508","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-25T02:13:45.76688Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2594527013674603685,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T02:13:45.98732Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.10344206s","expected-duration":"1s"} {"level":"info","ts":"2026-02-25T02:13:45.987755Z","caller":"traceutil/trace.go:171","msg":"trace[1402394575] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"1.103974358s","start":"2026-02-25T02:13:44.883757Z","end":"2026-02-25T02:13:45.987731Z","steps":["trace[1402394575] 'process raft request' (duration: 1.103848114s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:13:45.987922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:13:44.88374Z","time spent":"1.104102692s","remote":"127.0.0.1:54508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:13:45.989798Z","caller":"traceutil/trace.go:171","msg":"trace[1792414359] linearizableReadLoop","detail":"{readStateIndex:562; appliedIndex:560; }","duration":"723.466278ms","start":"2026-02-25T02:13:45.266312Z","end":"2026-02-25T02:13:45.989778Z","steps":["trace[1792414359] 'read index received' (duration: 721.530944ms)","trace[1792414359] 'applied index is now lower than readState.Index' (duration: 1.934444ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:13:45.989889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"723.568492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:13:45.9899Z","caller":"traceutil/trace.go:171","msg":"trace[1958215057] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"1.105959433s","start":"2026-02-25T02:13:44.883902Z","end":"2026-02-25T02:13:45.989861Z","steps":["trace[1958215057] 'process raft request' (duration: 1.105748506s)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:13:45.989963Z","caller":"traceutil/trace.go:171","msg":"trace[1968311946] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:542; }","duration":"723.698116ms","start":"2026-02-25T02:13:45.266257Z","end":"2026-02-25T02:13:45.989955Z","steps":["trace[1968311946] 'agreement among raft nodes before linearized reading' (duration: 723.594232ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:13:45.99004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:13:45.266235Z","time spent":"723.796999ms","remote":"127.0.0.1:54252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T02:13:45.989902Z","caller":"traceutil/trace.go:171","msg":"trace[465661602] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"1.105940283s","start":"2026-02-25T02:13:44.88394Z","end":"2026-02-25T02:13:45.98988Z","steps":["trace[465661602] 'process raft request' (duration: 1.105786948s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:13:45.990306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:13:44.883602Z","time spent":"1.106642406s","remote":"127.0.0.1:54508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:13:45.990127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:13:44.883867Z","time spent":"1.106124899s","remote":"127.0.0.1:54508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:13:48.145715Z","caller":"traceutil/trace.go:171","msg":"trace[1793907883] linearizableReadLoop","detail":"{readStateIndex:592; appliedIndex:591; }","duration":"197.176479ms","start":"2026-02-25T02:13:47.948496Z","end":"2026-02-25T02:13:48.145673Z","steps":["trace[1793907883] 'read index received' (duration: 196.983482ms)","trace[1793907883] 'applied index is now lower than readState.Index' (duration: 191.907µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:13:48.145825Z","caller":"traceutil/trace.go:171","msg":"trace[1451955879] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"396.050763ms","start":"2026-02-25T02:13:47.749723Z","end":"2026-02-25T02:13:48.145774Z","steps":["trace[1451955879] 'process raft request' (duration: 395.760863ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:13:48.145916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.405237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:13:48.145963Z","caller":"traceutil/trace.go:171","msg":"trace[1196913656] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:0; response_revision:571; }","duration":"197.51492ms","start":"2026-02-25T02:13:47.948433Z","end":"2026-02-25T02:13:48.145948Z","steps":["trace[1196913656] 'agreement among raft nodes before linearized reading' (duration: 197.399376ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:13:48.145996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:13:47.749694Z","time spent":"396.213788ms","remote":"127.0.0.1:54280","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4234,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T02:13:48.146025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.675991ms","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-25T02:13:48.146083Z","caller":"traceutil/trace.go:171","msg":"trace[471669730] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:571; }","duration":"153.785244ms","start":"2026-02-25T02:13:47.992283Z","end":"2026-02-25T02:13:48.146069Z","steps":["trace[471669730] 'agreement among raft nodes before linearized reading' (duration: 153.6675ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:13:48.146192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.712085ms","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-25T02:13:48.146246Z","caller":"traceutil/trace.go:171","msg":"trace[591364797] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:571; }","duration":"151.788848ms","start":"2026-02-25T02:13:47.994446Z","end":"2026-02-25T02:13:48.146235Z","steps":["trace[591364797] 'agreement among raft nodes before linearized reading' (duration: 151.681795ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:13:48.147059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.871744ms","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-25T02:13:48.147695Z","caller":"traceutil/trace.go:171","msg":"trace[1044063637] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:571; }","duration":"152.976607ms","start":"2026-02-25T02:13:47.994133Z","end":"2026-02-25T02:13:48.14711Z","steps":["trace[1044063637] 'agreement among raft nodes before linearized reading' (duration: 152.013965ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:13:58.641856Z","caller":"traceutil/trace.go:171","msg":"trace[779601594] transaction","detail":"{read_only:false; response_revision:711; number_of_response:1; }","duration":"428.108273ms","start":"2026-02-25T02:13:58.213714Z","end":"2026-02-25T02:13:58.641822Z","steps":["trace[779601594] 'process raft request' (duration: 413.20478ms)","trace[779601594] 'compare' (duration: 14.404256ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:13:58.641932Z","caller":"traceutil/trace.go:171","msg":"trace[1977786005] linearizableReadLoop","detail":"{readStateIndex:736; appliedIndex:734; }","duration":"375.312068ms","start":"2026-02-25T02:13:58.266581Z","end":"2026-02-25T02:13:58.641893Z","steps":["trace[1977786005] 'read index received' (duration: 360.331233ms)","trace[1977786005] 'applied index is now lower than readState.Index' (duration: 14.979385ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:13:58.642032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:13:58.213694Z","time spent":"428.250588ms","remote":"127.0.0.1:54508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:13:58.64212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.501434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:13:58.642092Z","caller":"traceutil/trace.go:171","msg":"trace[480391265] transaction","detail":"{read_only:false; response_revision:712; number_of_response:1; }","duration":"428.31488ms","start":"2026-02-25T02:13:58.213752Z","end":"2026-02-25T02:13:58.642067Z","steps":["trace[480391265] 'process raft request' (duration: 428.00602ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:13:58.642309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:13:58.213725Z","time spent":"428.506886ms","remote":"127.0.0.1:54508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:13:58.642168Z","caller":"traceutil/trace.go:171","msg":"trace[1174370080] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:712; }","duration":"375.614318ms","start":"2026-02-25T02:13:58.266533Z","end":"2026-02-25T02:13:58.642147Z","steps":["trace[1174370080] 'agreement among raft nodes before linearized reading' (duration: 375.510354ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:13:58.642498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:13:58.266515Z","time spent":"375.9718ms","remote":"127.0.0.1:54252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T02:14:04.766798Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2594527013674604035,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T02:14:04.836535Z","caller":"traceutil/trace.go:171","msg":"trace[1029494555] linearizableReadLoop","detail":"{readStateIndex:758; appliedIndex:757; }","duration":"570.680251ms","start":"2026-02-25T02:14:04.265819Z","end":"2026-02-25T02:14:04.836499Z","steps":["trace[1029494555] 'read index received' (duration: 570.141874ms)","trace[1029494555] 'applied index is now lower than readState.Index' (duration: 536.177µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:14:04.836626Z","caller":"traceutil/trace.go:171","msg":"trace[22599213] transaction","detail":"{read_only:false; response_revision:733; number_of_response:1; }","duration":"596.400494ms","start":"2026-02-25T02:14:04.240172Z","end":"2026-02-25T02:14:04.836573Z","steps":["trace[22599213] 'process raft request' (duration: 595.838356ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:14:04.836743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.908148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:14:04.836834Z","caller":"traceutil/trace.go:171","msg":"trace[238020765] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:733; }","duration":"571.048102ms","start":"2026-02-25T02:14:04.265765Z","end":"2026-02-25T02:14:04.836813Z","steps":["trace[238020765] 'agreement among raft nodes before linearized reading' (duration: 570.885227ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:14:04.836872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:14:04.265752Z","time spent":"571.114625ms","remote":"127.0.0.1:54252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T02:14:04.836884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:14:04.240142Z","time spent":"596.58923ms","remote":"127.0.0.1:54508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:14:04.836927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.197832ms","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-25T02:14:04.837004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.299713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-25T02:14:04.836981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.141357ms","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-25T02:14:04.837044Z","caller":"traceutil/trace.go:171","msg":"trace[552021454] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:733; }","duration":"159.397329ms","start":"2026-02-25T02:14:04.677619Z","end":"2026-02-25T02:14:04.837016Z","steps":["trace[552021454] 'agreement among raft nodes before linearized reading' (duration: 159.10215ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:14:04.837049Z","caller":"traceutil/trace.go:171","msg":"trace[1898751606] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:733; }","duration":"182.450328ms","start":"2026-02-25T02:14:04.654585Z","end":"2026-02-25T02:14:04.837036Z","steps":["trace[1898751606] 'agreement among raft nodes before linearized reading' (duration: 182.132828ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:14:04.837081Z","caller":"traceutil/trace.go:171","msg":"trace[553134277] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:733; }","duration":"163.318313ms","start":"2026-02-25T02:14:04.673742Z","end":"2026-02-25T02:14:04.83706Z","steps":["trace[553134277] 'agreement among raft nodes before linearized reading' (duration: 163.016223ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:14:05.560667Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2594527013674604039,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T02:14:05.837845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.754047ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T02:14:05.837956Z","caller":"traceutil/trace.go:171","msg":"trace[1359161804] transaction","detail":"{read_only:false; response_revision:734; number_of_response:1; }","duration":"996.008996ms","start":"2026-02-25T02:14:04.841926Z","end":"2026-02-25T02:14:05.837935Z","steps":["trace[1359161804] 'process raft request' (duration: 782.073033ms)","trace[1359161804] 'compare' (duration: 213.544951ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:14:05.838025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:14:04.841907Z","time spent":"996.089459ms","remote":"127.0.0.1:54508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:14:05.843537Z","caller":"traceutil/trace.go:171","msg":"trace[1798147419] linearizableReadLoop","detail":"{readStateIndex:760; appliedIndex:758; }","duration":"783.952423ms","start":"2026-02-25T02:14:05.059555Z","end":"2026-02-25T02:14:05.843508Z","steps":["trace[1798147419] 'read index received' (duration: 564.395492ms)","trace[1798147419] 'applied index is now lower than readState.Index' (duration: 219.555461ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:14:05.84358Z","caller":"traceutil/trace.go:171","msg":"trace[1885861166] transaction","detail":"{read_only:false; response_revision:735; number_of_response:1; }","duration":"1.000946053s","start":"2026-02-25T02:14:04.842606Z","end":"2026-02-25T02:14:05.843552Z","steps":["trace[1885861166] 'process raft request' (duration: 1.000756667s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:14:05.843755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"577.701923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:14:05.843803Z","caller":"traceutil/trace.go:171","msg":"trace[428665150] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:735; }","duration":"577.815426ms","start":"2026-02-25T02:14:05.265976Z","end":"2026-02-25T02:14:05.843791Z","steps":["trace[428665150] 'agreement among raft nodes before linearized reading' (duration: 577.715073ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:14:05.843798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"784.229452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-02-25T02:14:05.843841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:14:05.265955Z","time spent":"577.878209ms","remote":"127.0.0.1:54252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T02:14:05.843896Z","caller":"traceutil/trace.go:171","msg":"trace[629125351] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:735; }","duration":"784.351805ms","start":"2026-02-25T02:14:05.059505Z","end":"2026-02-25T02:14:05.843857Z","steps":["trace[629125351] 'agreement among raft nodes before linearized reading' (duration: 784.138399ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:14:05.84377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:14:04.842582Z","time spent":"1.001109168s","remote":"127.0.0.1:54508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:14:05.843958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:14:05.059483Z","time spent":"784.459259ms","remote":"127.0.0.1:54508","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-25T02:14:05.843927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.646387ms","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-25T02:14:05.844037Z","caller":"traceutil/trace.go:171","msg":"trace[937961600] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:735; }","duration":"196.816562ms","start":"2026-02-25T02:14:05.647199Z","end":"2026-02-25T02:14:05.844016Z","steps":["trace[937961600] 'agreement among raft nodes before linearized reading' (duration: 196.523203ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:14:06.513482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.67395ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T02:14:06.513691Z","caller":"traceutil/trace.go:171","msg":"trace[68292990] linearizableReadLoop","detail":"{readStateIndex:762; appliedIndex:760; }","duration":"501.222794ms","start":"2026-02-25T02:14:06.012446Z","end":"2026-02-25T02:14:06.513669Z","steps":["trace[68292990] 'read index received' (duration: 303.313797ms)","trace[68292990] 'applied index is now lower than readState.Index' (duration: 197.907857ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:14:06.513771Z","caller":"traceutil/trace.go:171","msg":"trace[2063572849] transaction","detail":"{read_only:false; response_revision:736; number_of_response:1; }","duration":"664.604989ms","start":"2026-02-25T02:14:05.84914Z","end":"2026-02-25T02:14:06.513745Z","steps":["trace[2063572849] 'process raft request' (duration: 466.530768ms)","trace[2063572849] 'compare' (duration: 197.40087ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:14:06.5138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.353009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T02:14:06.513988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:14:05.84912Z","time spent":"664.806465ms","remote":"127.0.0.1:54508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:14:06.514064Z","caller":"traceutil/trace.go:171","msg":"trace[167796201] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:737; }","duration":"501.660499ms","start":"2026-02-25T02:14:06.012378Z","end":"2026-02-25T02:14:06.514038Z","steps":["trace[167796201] 'agreement among raft nodes before linearized reading' (duration: 501.38372ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:14:06.514149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:14:06.012355Z","time spent":"501.747461ms","remote":"127.0.0.1:54490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":29,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"info","ts":"2026-02-25T02:14:06.513786Z","caller":"traceutil/trace.go:171","msg":"trace[729787597] transaction","detail":"{read_only:false; response_revision:737; number_of_response:1; }","duration":"663.783974ms","start":"2026-02-25T02:14:05.849966Z","end":"2026-02-25T02:14:06.51375Z","steps":["trace[729787597] 'process raft request' (duration: 663.642849ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:14:06.51432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:14:05.849956Z","time spent":"664.250768ms","remote":"127.0.0.1:54508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:14:08.1197Z","caller":"traceutil/trace.go:171","msg":"trace[1108068954] linearizableReadLoop","detail":"{readStateIndex:767; appliedIndex:766; }","duration":"270.526042ms","start":"2026-02-25T02:14:07.849151Z","end":"2026-02-25T02:14:08.119677Z","steps":["trace[1108068954] 'read index received' (duration: 270.421839ms)","trace[1108068954] 'applied index is now lower than readState.Index' (duration: 103.173µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:14:08.119819Z","caller":"traceutil/trace.go:171","msg":"trace[1531912034] transaction","detail":"{read_only:false; response_revision:741; number_of_response:1; }","duration":"273.377372ms","start":"2026-02-25T02:14:07.846406Z","end":"2026-02-25T02:14:08.119783Z","steps":["trace[1531912034] 'process raft request' (duration: 273.137675ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:14:08.1199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.746669ms","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-25T02:14:08.119949Z","caller":"traceutil/trace.go:171","msg":"trace[1729323537] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:741; }","duration":"270.854632ms","start":"2026-02-25T02:14:07.849084Z","end":"2026-02-25T02:14:08.119938Z","steps":["trace[1729323537] 'agreement among raft nodes before linearized reading' (duration: 270.677657ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:14:08.336418Z","caller":"traceutil/trace.go:171","msg":"trace[1420167226] transaction","detail":"{read_only:false; response_revision:742; number_of_response:1; }","duration":"212.388694ms","start":"2026-02-25T02:14:08.124007Z","end":"2026-02-25T02:14:08.336395Z","steps":["trace[1420167226] 'process raft request' (duration: 145.278843ms)","trace[1420167226] 'compare' (duration: 66.709749ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:14:08.649404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.504013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-02-25T02:14:08.649424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.37597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-25T02:14:08.649534Z","caller":"traceutil/trace.go:171","msg":"trace[714943669] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:742; }","duration":"129.561315ms","start":"2026-02-25T02:14:08.519945Z","end":"2026-02-25T02:14:08.649506Z","steps":["trace[714943669] 'range keys from in-memory index tree' (duration: 129.133642ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:14:08.649515Z","caller":"traceutil/trace.go:171","msg":"trace[787191488] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:742; }","duration":"122.670938ms","start":"2026-02-25T02:14:08.526817Z","end":"2026-02-25T02:14:08.649487Z","steps":["trace[787191488] 'range keys from in-memory index tree' (duration: 122.148531ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:14:32.614732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.538042ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-25T02:14:32.614995Z","caller":"traceutil/trace.go:171","msg":"trace[1467375238] linearizableReadLoop","detail":"{readStateIndex:845; appliedIndex:844; }","duration":"361.741946ms","start":"2026-02-25T02:14:32.253193Z","end":"2026-02-25T02:14:32.614934Z","steps":["trace[1467375238] 'read index received' (duration: 57.922µs)","trace[1467375238] 'applied index is now lower than readState.Index' (duration: 361.682024ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:14:32.615154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.299683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:14:32.615201Z","caller":"traceutil/trace.go:171","msg":"trace[1535177324] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:813; }","duration":"349.415146ms","start":"2026-02-25T02:14:32.265776Z","end":"2026-02-25T02:14:32.615191Z","steps":["trace[1535177324] 'agreement among raft nodes before linearized reading' (duration: 349.315823ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:14:32.615257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:14:32.265757Z","time spent":"349.491979ms","remote":"127.0.0.1:54252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T02:14:32.61526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.077396ms","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-25T02:14:32.615343Z","caller":"traceutil/trace.go:171","msg":"trace[806995164] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:813; }","duration":"176.168229ms","start":"2026-02-25T02:14:32.439134Z","end":"2026-02-25T02:14:32.615302Z","steps":["trace[806995164] 'agreement among raft nodes before linearized reading' (duration: 175.986003ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:14:32.615543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.359636ms","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-25T02:14:32.615583Z","caller":"traceutil/trace.go:171","msg":"trace[858691141] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:813; }","duration":"362.443138ms","start":"2026-02-25T02:14:32.25313Z","end":"2026-02-25T02:14:32.615573Z","steps":["trace[858691141] 'agreement among raft nodes before linearized reading' (duration: 361.776737ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:14:32.615621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:14:32.253106Z","time spent":"362.506301ms","remote":"127.0.0.1:54508","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-25T02:14:33.207682Z","caller":"traceutil/trace.go:171","msg":"trace[578906255] transaction","detail":"{read_only:false; response_revision:814; number_of_response:1; }","duration":"576.087592ms","start":"2026-02-25T02:14:32.631549Z","end":"2026-02-25T02:14:33.207636Z","steps":["trace[578906255] 'process raft request' (duration: 575.815893ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:14:33.208008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:14:32.631538Z","time spent":"576.285508ms","remote":"127.0.0.1:54508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:14:33.44827Z","caller":"traceutil/trace.go:171","msg":"trace[750500197] linearizableReadLoop","detail":"{readStateIndex:847; appliedIndex:845; }","duration":"687.739571ms","start":"2026-02-25T02:14:32.760498Z","end":"2026-02-25T02:14:33.448237Z","steps":["trace[750500197] 'read index received' (duration: 446.950899ms)","trace[750500197] 'applied index is now lower than readState.Index' (duration: 240.787422ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:14:33.448521Z","caller":"traceutil/trace.go:171","msg":"trace[1807714044] transaction","detail":"{read_only:false; response_revision:815; number_of_response:1; }","duration":"816.090609ms","start":"2026-02-25T02:14:32.632363Z","end":"2026-02-25T02:14:33.448454Z","steps":["trace[1807714044] 'process raft request' (duration: 815.630335ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:14:33.448916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.823441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:14:33.448962Z","caller":"traceutil/trace.go:171","msg":"trace[1056496769] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:815; }","duration":"182.899823ms","start":"2026-02-25T02:14:33.266049Z","end":"2026-02-25T02:14:33.448949Z","steps":["trace[1056496769] 'agreement among raft nodes before linearized reading' (duration: 182.841051ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:14:33.449098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"682.098303ms","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-25T02:14:33.449175Z","caller":"traceutil/trace.go:171","msg":"trace[1279873323] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:815; }","duration":"682.241978ms","start":"2026-02-25T02:14:32.766916Z","end":"2026-02-25T02:14:33.449158Z","steps":["trace[1279873323] 'agreement among raft nodes before linearized reading' (duration: 681.966119ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:14:33.449188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.673782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-02-25T02:14:33.449225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:14:32.766905Z","time spent":"682.309649ms","remote":"127.0.0.1:54508","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-25T02:14:33.44926Z","caller":"traceutil/trace.go:171","msg":"trace[1369917892] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:815; }","duration":"341.854458ms","start":"2026-02-25T02:14:33.107386Z","end":"2026-02-25T02:14:33.449241Z","steps":["trace[1369917892] 'agreement among raft nodes before linearized reading' (duration: 341.512677ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:14:33.448599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"688.075731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-02-25T02:14:33.449296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:14:33.107315Z","time spent":"341.973692ms","remote":"127.0.0.1:54508","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-25T02:14:33.449837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:14:32.632358Z","time spent":"816.375988ms","remote":"127.0.0.1:54508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:14:33.449328Z","caller":"traceutil/trace.go:171","msg":"trace[276590637] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:815; }","duration":"688.873967ms","start":"2026-02-25T02:14:32.760426Z","end":"2026-02-25T02:14:33.4493Z","steps":["trace[276590637] 'agreement among raft nodes before linearized reading' (duration: 687.972048ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:14:33.450366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:14:32.760398Z","time spent":"689.91373ms","remote":"127.0.0.1:54508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-25T02:14:35.807706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.472568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-25T02:14:35.807776Z","caller":"traceutil/trace.go:171","msg":"trace[1909525453] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:819; }","duration":"342.606312ms","start":"2026-02-25T02:14:35.465153Z","end":"2026-02-25T02:14:35.807759Z","steps":["trace[1909525453] 'range keys from in-memory index tree' (duration: 342.259822ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:14:35.807786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.747725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-02-25T02:14:35.807818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:14:35.465123Z","time spent":"342.677735ms","remote":"127.0.0.1:54508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-25T02:14:35.807842Z","caller":"traceutil/trace.go:171","msg":"trace[1792863753] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:819; }","duration":"335.836628ms","start":"2026-02-25T02:14:35.471992Z","end":"2026-02-25T02:14:35.807828Z","steps":["trace[1792863753] 'range keys from in-memory index tree' (duration: 335.559319ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:14:35.807877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:14:35.471979Z","time spent":"335.89226ms","remote":"127.0.0.1:54508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-25T02:14:35.80804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.61631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-02-25T02:14:35.808052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.078463ms","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-25T02:14:35.808084Z","caller":"traceutil/trace.go:171","msg":"trace[1553277419] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:819; }","duration":"353.162106ms","start":"2026-02-25T02:14:35.454914Z","end":"2026-02-25T02:14:35.808076Z","steps":["trace[1553277419] 'range keys from in-memory index tree' (duration: 352.863176ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:14:35.808083Z","caller":"traceutil/trace.go:171","msg":"trace[1984029128] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:819; }","duration":"334.689162ms","start":"2026-02-25T02:14:35.473382Z","end":"2026-02-25T02:14:35.808071Z","steps":["trace[1984029128] 'range keys from in-memory index tree' (duration: 334.355551ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:14:35.808106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:14:35.454885Z","time spent":"353.215957ms","remote":"127.0.0.1:54508","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-25T02:14:35.808124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:14:35.473374Z","time spent":"334.739653ms","remote":"127.0.0.1:54508","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-25T02:14:36.142614Z","caller":"traceutil/trace.go:171","msg":"trace[1158821637] transaction","detail":"{read_only:false; response_revision:822; number_of_response:1; }","duration":"331.236643ms","start":"2026-02-25T02:14:35.811343Z","end":"2026-02-25T02:14:36.142579Z","steps":["trace[1158821637] 'process raft request' (duration: 331.050607ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:14:36.142885Z","caller":"traceutil/trace.go:171","msg":"trace[1923718535] transaction","detail":"{read_only:false; response_revision:821; number_of_response:1; }","duration":"331.714007ms","start":"2026-02-25T02:14:35.811139Z","end":"2026-02-25T02:14:36.142852Z","steps":["trace[1923718535] 'process raft request' (duration: 331.044696ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:14:36.142931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:14:35.811337Z","time spent":"331.364796ms","remote":"127.0.0.1:54508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:14:36.143005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:14:35.811127Z","time spent":"331.81777ms","remote":"127.0.0.1:54508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:14:36.142707Z","caller":"traceutil/trace.go:171","msg":"trace[1162970219] transaction","detail":"{read_only:false; response_revision:823; number_of_response:1; }","duration":"331.271613ms","start":"2026-02-25T02:14:35.811383Z","end":"2026-02-25T02:14:36.142655Z","steps":["trace[1162970219] 'process raft request' (duration: 331.075667ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:14:36.143426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:14:35.811379Z","time spent":"331.791799ms","remote":"127.0.0.1:54508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:14:36.406588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.764666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:14:36.406662Z","caller":"traceutil/trace.go:171","msg":"trace[1503238028] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:823; }","duration":"125.882941ms","start":"2026-02-25T02:14:36.28076Z","end":"2026-02-25T02:14:36.406643Z","steps":["trace[1503238028] 'range keys from in-memory index tree' (duration: 125.656713ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:14:54.735438Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2594527013674604537,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T02:14:54.983202Z","caller":"traceutil/trace.go:171","msg":"trace[1150363889] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"987.822118ms","start":"2026-02-25T02:14:53.995361Z","end":"2026-02-25T02:14:54.983184Z","steps":["trace[1150363889] 'process raft request' (duration: 987.435666ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:14:54.983511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"749.009829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-02-25T02:14:54.983553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:14:53.995321Z","time spent":"988.106277ms","remote":"127.0.0.1:54508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:14:54.983591Z","caller":"traceutil/trace.go:171","msg":"trace[1126515151] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:931; }","duration":"749.156233ms","start":"2026-02-25T02:14:54.234412Z","end":"2026-02-25T02:14:54.983568Z","steps":["trace[1126515151] 'agreement among raft nodes before linearized reading' (duration: 748.879405ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:14:54.984643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:14:54.234388Z","time spent":"750.236188ms","remote":"127.0.0.1:54508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-25T02:14:54.983111Z","caller":"traceutil/trace.go:171","msg":"trace[1928415182] linearizableReadLoop","detail":"{readStateIndex:969; appliedIndex:968; }","duration":"748.597876ms","start":"2026-02-25T02:14:54.234477Z","end":"2026-02-25T02:14:54.983075Z","steps":["trace[1928415182] 'read index received' (duration: 748.354628ms)","trace[1928415182] 'applied index is now lower than readState.Index' (duration: 241.818µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:14:54.98498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.873613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:14:54.985053Z","caller":"traceutil/trace.go:171","msg":"trace[1870457449] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:931; }","duration":"524.991227ms","start":"2026-02-25T02:14:54.46004Z","end":"2026-02-25T02:14:54.985031Z","steps":["trace[1870457449] 'agreement among raft nodes before linearized reading' (duration: 524.868053ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:14:54.985104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:14:54.460017Z","time spent":"525.07426ms","remote":"127.0.0.1:60936","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-02-25T02:14:54.985105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"750.325481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-25T02:14:54.985361Z","caller":"traceutil/trace.go:171","msg":"trace[1368935834] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:931; }","duration":"750.62433ms","start":"2026-02-25T02:14:54.23471Z","end":"2026-02-25T02:14:54.985335Z","steps":["trace[1368935834] 'agreement among raft nodes before linearized reading' (duration: 750.179206ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:14:54.985418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:14:54.234699Z","time spent":"750.708663ms","remote":"127.0.0.1:54508","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-25T02:14:54.985419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"718.843365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:14:54.985525Z","caller":"traceutil/trace.go:171","msg":"trace[293134095] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:931; }","duration":"718.982419ms","start":"2026-02-25T02:14:54.266513Z","end":"2026-02-25T02:14:54.985496Z","steps":["trace[293134095] 'agreement among raft nodes before linearized reading' (duration: 718.814654ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:14:54.985583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:14:54.266501Z","time spent":"719.072532ms","remote":"127.0.0.1:54252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T02:14:54.985619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"741.842202ms","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-25T02:14:54.985657Z","caller":"traceutil/trace.go:171","msg":"trace[1567845466] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:931; }","duration":"741.898514ms","start":"2026-02-25T02:14:54.243748Z","end":"2026-02-25T02:14:54.985647Z","steps":["trace[1567845466] 'agreement among raft nodes before linearized reading' (duration: 741.789581ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:14:54.985688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:14:54.243738Z","time spent":"741.941395ms","remote":"127.0.0.1:54508","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-25T02:14:55.174734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.882627ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T02:14:55.175291Z","caller":"traceutil/trace.go:171","msg":"trace[2126118761] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"183.884413ms","start":"2026-02-25T02:14:54.991349Z","end":"2026-02-25T02:14:55.175233Z","steps":["trace[2126118761] 'process raft request' (duration: 70.85134ms)","trace[2126118761] 'compare' (duration: 110.672589ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:14:55.17577Z","caller":"traceutil/trace.go:171","msg":"trace[1778869844] transaction","detail":"{read_only:false; response_revision:933; number_of_response:1; }","duration":"183.732479ms","start":"2026-02-25T02:14:54.992016Z","end":"2026-02-25T02:14:55.175748Z","steps":["trace[1778869844] 'process raft request' (duration: 183.115249ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:14:55.176138Z","caller":"traceutil/trace.go:171","msg":"trace[1449962737] transaction","detail":"{read_only:false; response_revision:934; number_of_response:1; }","duration":"184.011897ms","start":"2026-02-25T02:14:54.992086Z","end":"2026-02-25T02:14:55.176098Z","steps":["trace[1449962737] 'process raft request' (duration: 183.600874ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:15.430682Z","caller":"traceutil/trace.go:171","msg":"trace[1498959500] transaction","detail":"{read_only:false; response_revision:1007; number_of_response:1; }","duration":"152.320385ms","start":"2026-02-25T02:15:15.278335Z","end":"2026-02-25T02:15:15.430655Z","steps":["trace[1498959500] 'process raft request' (duration: 152.268233ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:15.430811Z","caller":"traceutil/trace.go:171","msg":"trace[834956845] transaction","detail":"{read_only:false; response_revision:1006; number_of_response:1; }","duration":"153.810262ms","start":"2026-02-25T02:15:15.276951Z","end":"2026-02-25T02:15:15.430761Z","steps":["trace[834956845] 'process raft request' (duration: 133.159359ms)","trace[834956845] 'compare' (duration: 20.357714ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:15:16.860505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:16.510923Z","time spent":"349.579211ms","remote":"127.0.0.1:54262","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-25T02:15:16.988966Z","caller":"traceutil/trace.go:171","msg":"trace[2021138584] transaction","detail":"{read_only:false; response_revision:1010; number_of_response:1; }","duration":"350.778909ms","start":"2026-02-25T02:15:16.638155Z","end":"2026-02-25T02:15:16.988934Z","steps":["trace[2021138584] 'process raft request' (duration: 280.432496ms)","trace[2021138584] 'compare' (duration: 70.147357ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:15:16.98921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:16.638118Z","time spent":"350.932214ms","remote":"127.0.0.1:54312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":790,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T02:15:17.014997Z","caller":"traceutil/trace.go:171","msg":"trace[1181269626] transaction","detail":"{read_only:false; response_revision:1011; number_of_response:1; }","duration":"153.270946ms","start":"2026-02-25T02:15:16.8617Z","end":"2026-02-25T02:15:17.014971Z","steps":["trace[1181269626] 'process raft request' (duration: 153.129541ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:17.170077Z","caller":"traceutil/trace.go:171","msg":"trace[383298744] linearizableReadLoop","detail":"{readStateIndex:1055; appliedIndex:1054; }","duration":"153.135341ms","start":"2026-02-25T02:15:17.016915Z","end":"2026-02-25T02:15:17.170051Z","steps":["trace[383298744] 'read index received' (duration: 83.860851ms)","trace[383298744] 'applied index is now lower than readState.Index' (duration: 69.2736ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:15:17.170131Z","caller":"traceutil/trace.go:171","msg":"trace[2136620819] transaction","detail":"{read_only:false; response_revision:1012; number_of_response:1; }","duration":"176.573812ms","start":"2026-02-25T02:15:16.993532Z","end":"2026-02-25T02:15:17.170106Z","steps":["trace[2136620819] 'process raft request' (duration: 107.308932ms)","trace[2136620819] 'compare' (duration: 69.072134ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:15:17.170283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.356458ms","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-25T02:15:17.17032Z","caller":"traceutil/trace.go:171","msg":"trace[1361610556] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1012; }","duration":"153.43666ms","start":"2026-02-25T02:15:17.016872Z","end":"2026-02-25T02:15:17.170309Z","steps":["trace[1361610556] 'agreement among raft nodes before linearized reading' (duration: 153.321687ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:18.016846Z","caller":"traceutil/trace.go:171","msg":"trace[1073471726] transaction","detail":"{read_only:false; response_revision:1019; number_of_response:1; }","duration":"156.425255ms","start":"2026-02-25T02:15:17.860378Z","end":"2026-02-25T02:15:18.016803Z","steps":["trace[1073471726] 'process raft request' (duration: 156.212588ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:23.586425Z","caller":"traceutil/trace.go:171","msg":"trace[2099350853] linearizableReadLoop","detail":"{readStateIndex:1111; appliedIndex:1110; }","duration":"216.39357ms","start":"2026-02-25T02:15:23.370008Z","end":"2026-02-25T02:15:23.586402Z","steps":["trace[2099350853] 'read index received' (duration: 216.227745ms)","trace[2099350853] 'applied index is now lower than readState.Index' (duration: 164.745µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:15:23.586548Z","caller":"traceutil/trace.go:171","msg":"trace[2140337611] transaction","detail":"{read_only:false; response_revision:1067; number_of_response:1; }","duration":"309.280167ms","start":"2026-02-25T02:15:23.277259Z","end":"2026-02-25T02:15:23.586539Z","steps":["trace[2140337611] 'process raft request' (duration: 308.968657ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:23.586649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:23.277244Z","time spent":"309.330789ms","remote":"127.0.0.1:54508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:15:23.587103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.085213ms","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-25T02:15:23.587178Z","caller":"traceutil/trace.go:171","msg":"trace[1889354112] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1067; }","duration":"217.188276ms","start":"2026-02-25T02:15:23.369968Z","end":"2026-02-25T02:15:23.587157Z","steps":["trace[1889354112] 'agreement among raft nodes before linearized reading' (duration: 217.047022ms)"],"step_count":1}