{"level":"warn","ts":"2026-02-25T06:05:42.957347Z","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-25T06:05:42.957445Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.115: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.115:2380","--initial-cluster=instance=https://199.204.45.115:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.115:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.115: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-25T06:05:42.957514Z","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-25T06:05:42.957522Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.115:2380"]} {"level":"info","ts":"2026-02-25T06:05:42.957539Z","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-25T06:05:42.958206Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.115:2379"]} {"level":"info","ts":"2026-02-25T06:05:42.958276Z","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.115:2380"],"listen-peer-urls":["https://199.204.45.115:2380"],"advertise-client-urls":["https://199.204.45.115:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.115:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.115: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-25T06:05:42.963438Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.970412ms"} {"level":"info","ts":"2026-02-25T06:05:42.9706Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"e3aaa5c123e150e0","cluster-id":"61ec519698823541"} {"level":"info","ts":"2026-02-25T06:05:42.970678Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 switched to configuration voters=()"} {"level":"info","ts":"2026-02-25T06:05:42.970701Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became follower at term 0"} {"level":"info","ts":"2026-02-25T06:05:42.970714Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft e3aaa5c123e150e0 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-25T06:05:42.970729Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became follower at term 1"} {"level":"info","ts":"2026-02-25T06:05:42.970767Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 switched to configuration voters=(16405106841599693024)"} {"level":"warn","ts":"2026-02-25T06:05:42.976256Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-25T06:05:42.977696Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-25T06:05:42.978774Z","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-25T06:05:42.981076Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"e3aaa5c123e150e0","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-25T06:05:42.981223Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"e3aaa5c123e150e0","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-25T06:05:42.981409Z","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-25T06:05:42.981473Z","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-25T06:05:42.981488Z","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-25T06:05:42.98334Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 switched to configuration voters=(16405106841599693024)"} {"level":"info","ts":"2026-02-25T06:05:42.983458Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"61ec519698823541","local-member-id":"e3aaa5c123e150e0","added-peer-id":"e3aaa5c123e150e0","added-peer-peer-urls":["https://199.204.45.115:2380"]} {"level":"info","ts":"2026-02-25T06:05:42.985281Z","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-25T06:05:42.985486Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.115:2380"} {"level":"info","ts":"2026-02-25T06:05:42.985568Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.115:2380"} {"level":"info","ts":"2026-02-25T06:05:42.985704Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"e3aaa5c123e150e0","initial-advertise-peer-urls":["https://199.204.45.115:2380"],"listen-peer-urls":["https://199.204.45.115:2380"],"advertise-client-urls":["https://199.204.45.115:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.115:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-25T06:05:42.985817Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-25T06:05:43.771781Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 is starting a new election at term 1"} {"level":"info","ts":"2026-02-25T06:05:43.771844Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-25T06:05:43.771883Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 received MsgPreVoteResp from e3aaa5c123e150e0 at term 1"} {"level":"info","ts":"2026-02-25T06:05:43.771903Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became candidate at term 2"} {"level":"info","ts":"2026-02-25T06:05:43.77192Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 received MsgVoteResp from e3aaa5c123e150e0 at term 2"} {"level":"info","ts":"2026-02-25T06:05:43.771941Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became leader at term 2"} {"level":"info","ts":"2026-02-25T06:05:43.771966Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: e3aaa5c123e150e0 elected leader e3aaa5c123e150e0 at term 2"} {"level":"info","ts":"2026-02-25T06:05:43.773358Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T06:05:43.774548Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"e3aaa5c123e150e0","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.115:2379]}","request-path":"/0/members/e3aaa5c123e150e0/attributes","cluster-id":"61ec519698823541","publish-timeout":"7s"} {"level":"info","ts":"2026-02-25T06:05:43.774565Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T06:05:43.77469Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T06:05:43.775422Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"61ec519698823541","local-member-id":"e3aaa5c123e150e0","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T06:05:43.775606Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T06:05:43.775419Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-25T06:05:43.775707Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-25T06:05:43.775668Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T06:05:43.779492Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.115:2379"} {"level":"info","ts":"2026-02-25T06:05:43.780489Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-25T06:06:19.078406Z","caller":"traceutil/trace.go:171","msg":"trace[1359270958] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"602.797358ms","start":"2026-02-25T06:06:18.475584Z","end":"2026-02-25T06:06:19.078381Z","steps":["trace[1359270958] 'process raft request' (duration: 602.641505ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:06:19.078994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:06:18.475551Z","time spent":"602.939159ms","remote":"127.0.0.1:49804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T06:06:20.76171Z","caller":"traceutil/trace.go:171","msg":"trace[759255971] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"122.956309ms","start":"2026-02-25T06:06:20.638729Z","end":"2026-02-25T06:06:20.761685Z","steps":["trace[759255971] 'process raft request' (duration: 122.770855ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:06:39.839746Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827830074723712967,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T06:06:40.222628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.334446334s","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-25T06:06:40.222694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.633159655s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T06:06:40.222747Z","caller":"traceutil/trace.go:171","msg":"trace[860675135] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:672; }","duration":"1.633246877s","start":"2026-02-25T06:06:38.589481Z","end":"2026-02-25T06:06:40.222728Z","steps":["trace[860675135] 'range keys from in-memory index tree' (duration: 1.633078623s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:06:40.222781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:06:38.589459Z","time spent":"1.633315238s","remote":"127.0.0.1:49646","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-25T06:06:40.222788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.276652273s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-25T06:06:40.222864Z","caller":"traceutil/trace.go:171","msg":"trace[1402350865] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:672; }","duration":"2.276775146s","start":"2026-02-25T06:06:37.946069Z","end":"2026-02-25T06:06:40.222845Z","steps":["trace[1402350865] 'range keys from in-memory index tree' (duration: 2.276436928s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:06:40.222902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:06:37.946049Z","time spent":"2.276844227s","remote":"127.0.0.1:49886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-25T06:06:40.22271Z","caller":"traceutil/trace.go:171","msg":"trace[1640763448] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:672; }","duration":"1.334586828s","start":"2026-02-25T06:06:38.888112Z","end":"2026-02-25T06:06:40.222699Z","steps":["trace[1640763448] 'range keys from in-memory index tree' (duration: 1.334279941s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:06:40.22316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:06:38.888092Z","time spent":"1.335057788s","remote":"127.0.0.1:49886","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-25T06:06:40.340824Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827830074723712967,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T06:06:40.762706Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.60646347s","expected-duration":"1s"} {"level":"info","ts":"2026-02-25T06:06:40.76306Z","caller":"traceutil/trace.go:171","msg":"trace[613385102] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"1.606954681s","start":"2026-02-25T06:06:39.156081Z","end":"2026-02-25T06:06:40.763035Z","steps":["trace[613385102] 'process raft request' (duration: 1.606834828s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:06:40.763175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:06:39.156033Z","time spent":"1.607073214s","remote":"127.0.0.1:49886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T06:06:40.792249Z","caller":"traceutil/trace.go:171","msg":"trace[39558773] linearizableReadLoop","detail":"{readStateIndex:700; appliedIndex:697; }","duration":"1.452881663s","start":"2026-02-25T06:06:39.339351Z","end":"2026-02-25T06:06:40.792232Z","steps":["trace[39558773] 'read index received' (duration: 1.423550662s)","trace[39558773] 'applied index is now lower than readState.Index' (duration: 29.329511ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:06:40.792343Z","caller":"traceutil/trace.go:171","msg":"trace[24243043] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"564.508282ms","start":"2026-02-25T06:06:40.227814Z","end":"2026-02-25T06:06:40.792322Z","steps":["trace[24243043] 'process raft request' (duration: 564.361239ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:06:40.792482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:06:40.227806Z","time spent":"564.604274ms","remote":"127.0.0.1:49886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:06:40.792487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.453128519s","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-25T06:06:40.792543Z","caller":"traceutil/trace.go:171","msg":"trace[724774273] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:675; }","duration":"1.453220361s","start":"2026-02-25T06:06:39.339308Z","end":"2026-02-25T06:06:40.792528Z","steps":["trace[724774273] 'agreement among raft nodes before linearized reading' (duration: 1.453052907s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:06:40.792519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"568.126531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T06:06:40.792599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:06:39.339263Z","time spent":"1.453321663s","remote":"127.0.0.1:49886","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-25T06:06:40.792568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.424170845s","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-25T06:06:40.792616Z","caller":"traceutil/trace.go:171","msg":"trace[1594621882] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:675; }","duration":"568.261313ms","start":"2026-02-25T06:06:40.224333Z","end":"2026-02-25T06:06:40.792594Z","steps":["trace[1594621882] 'agreement among raft nodes before linearized reading' (duration: 568.080119ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:06:40.79264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"915.848753ms","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-25T06:06:40.792669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:06:40.224321Z","time spent":"568.339586ms","remote":"127.0.0.1:49642","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-25T06:06:40.792663Z","caller":"traceutil/trace.go:171","msg":"trace[1017451939] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:675; }","duration":"1.424308348s","start":"2026-02-25T06:06:39.368334Z","end":"2026-02-25T06:06:40.792642Z","steps":["trace[1017451939] 'agreement among raft nodes before linearized reading' (duration: 1.424055373s)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:06:40.79272Z","caller":"traceutil/trace.go:171","msg":"trace[590013851] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:675; }","duration":"915.972435ms","start":"2026-02-25T06:06:39.876725Z","end":"2026-02-25T06:06:40.792698Z","steps":["trace[590013851] 'agreement among raft nodes before linearized reading' (duration: 915.716ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:06:40.792725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:06:39.368318Z","time spent":"1.42439816s","remote":"127.0.0.1:49886","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-25T06:06:40.792766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:06:39.876708Z","time spent":"916.049427ms","remote":"127.0.0.1:49886","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-25T06:06:40.792935Z","caller":"traceutil/trace.go:171","msg":"trace[1624030417] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"566.016004ms","start":"2026-02-25T06:06:40.226901Z","end":"2026-02-25T06:06:40.792917Z","steps":["trace[1624030417] 'process raft request' (duration: 565.183096ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:06:40.793367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:06:40.22689Z","time spent":"566.404152ms","remote":"127.0.0.1:49886","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-25T06:06:41.101989Z","caller":"traceutil/trace.go:171","msg":"trace[1766786770] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"304.385425ms","start":"2026-02-25T06:06:40.797571Z","end":"2026-02-25T06:06:41.101956Z","steps":["trace[1766786770] 'process raft request' (duration: 261.18067ms)","trace[1766786770] 'compare' (duration: 42.897427ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:06:41.102215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:06:40.797549Z","time spent":"304.587769ms","remote":"127.0.0.1:49886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:06:41.628021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.409706ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T06:06:41.628192Z","caller":"traceutil/trace.go:171","msg":"trace[1797247183] linearizableReadLoop","detail":"{readStateIndex:703; appliedIndex:700; }","duration":"827.198705ms","start":"2026-02-25T06:06:40.80098Z","end":"2026-02-25T06:06:41.628179Z","steps":["trace[1797247183] 'read index received' (duration: 257.753906ms)","trace[1797247183] 'applied index is now lower than readState.Index' (duration: 569.444079ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:06:41.628283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"827.299897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T06:06:41.628304Z","caller":"traceutil/trace.go:171","msg":"trace[794780634] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:678; }","duration":"827.342387ms","start":"2026-02-25T06:06:40.800956Z","end":"2026-02-25T06:06:41.628298Z","steps":["trace[794780634] 'agreement among raft nodes before linearized reading' (duration: 827.263016ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:06:41.628325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:06:40.800945Z","time spent":"827.375438ms","remote":"127.0.0.1:49646","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-25T06:06:41.628468Z","caller":"traceutil/trace.go:171","msg":"trace[721283855] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"830.747072ms","start":"2026-02-25T06:06:40.797714Z","end":"2026-02-25T06:06:41.628461Z","steps":["trace[721283855] 'process raft request' (duration: 417.718902ms)","trace[721283855] 'compare' (duration: 412.259463ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:06:41.628508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:06:40.797695Z","time spent":"830.789702ms","remote":"127.0.0.1:49886","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-25T06:06:41.628557Z","caller":"traceutil/trace.go:171","msg":"trace[1962748297] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"829.772561ms","start":"2026-02-25T06:06:40.798752Z","end":"2026-02-25T06:06:41.628525Z","steps":["trace[1962748297] 'process raft request' (duration: 829.347162ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:06:41.628651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:06:40.798731Z","time spent":"829.868803ms","remote":"127.0.0.1:49886","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-25T06:06:51.831739Z","caller":"traceutil/trace.go:171","msg":"trace[56762056] linearizableReadLoop","detail":"{readStateIndex:738; appliedIndex:737; }","duration":"152.874893ms","start":"2026-02-25T06:06:51.678844Z","end":"2026-02-25T06:06:51.831719Z","steps":["trace[56762056] 'read index received' (duration: 152.73061ms)","trace[56762056] 'applied index is now lower than readState.Index' (duration: 143.343µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:06:51.831798Z","caller":"traceutil/trace.go:171","msg":"trace[921786469] transaction","detail":"{read_only:false; response_revision:711; number_of_response:1; }","duration":"155.658003ms","start":"2026-02-25T06:06:51.676106Z","end":"2026-02-25T06:06:51.831764Z","steps":["trace[921786469] 'process raft request' (duration: 155.467739ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:06:51.831916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.061097ms","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-25T06:06:51.831949Z","caller":"traceutil/trace.go:171","msg":"trace[1047109124] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:711; }","duration":"153.145489ms","start":"2026-02-25T06:06:51.678794Z","end":"2026-02-25T06:06:51.831939Z","steps":["trace[1047109124] 'agreement among raft nodes before linearized reading' (duration: 153.010686ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:07:05.440157Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827830074723713163,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T06:07:05.940381Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827830074723713163,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T06:07:06.441291Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827830074723713163,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T06:07:06.544027Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.641983046s","expected-duration":"1s"} {"level":"info","ts":"2026-02-25T06:07:06.544599Z","caller":"traceutil/trace.go:171","msg":"trace[501673694] transaction","detail":"{read_only:false; response_revision:746; number_of_response:1; }","duration":"1.64266602s","start":"2026-02-25T06:07:04.901909Z","end":"2026-02-25T06:07:06.544575Z","steps":["trace[501673694] 'process raft request' (duration: 1.642527947s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:07:06.5449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:07:04.901885Z","time spent":"1.642789143s","remote":"127.0.0.1:49886","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-25T06:07:06.751235Z","caller":"traceutil/trace.go:171","msg":"trace[849445474] linearizableReadLoop","detail":"{readStateIndex:777; appliedIndex:775; }","duration":"1.811992054s","start":"2026-02-25T06:07:04.939217Z","end":"2026-02-25T06:07:06.751209Z","steps":["trace[849445474] 'read index received' (duration: 1.605082661s)","trace[849445474] 'applied index is now lower than readState.Index' (duration: 206.907773ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:07:06.751351Z","caller":"traceutil/trace.go:171","msg":"trace[836046887] transaction","detail":"{read_only:false; response_revision:747; number_of_response:1; }","duration":"346.973453ms","start":"2026-02-25T06:07:06.404346Z","end":"2026-02-25T06:07:06.75132Z","steps":["trace[836046887] 'process raft request' (duration: 296.08501ms)","trace[836046887] 'compare' (duration: 50.485253ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:07:06.751642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:07:06.404318Z","time spent":"347.244128ms","remote":"127.0.0.1:49886","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-25T06:07:06.751807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.553137636s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-02-25T06:07:06.751645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.812413893s","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-25T06:07:06.752837Z","caller":"traceutil/trace.go:171","msg":"trace[234378029] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:747; }","duration":"1.813654181s","start":"2026-02-25T06:07:04.939163Z","end":"2026-02-25T06:07:06.752817Z","steps":["trace[234378029] 'agreement among raft nodes before linearized reading' (duration: 1.812340692s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:07:06.752905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:07:04.939145Z","time spent":"1.813739682s","remote":"127.0.0.1:49886","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-25T06:07:06.752196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.162088021s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T06:07:06.75316Z","caller":"traceutil/trace.go:171","msg":"trace[1323311117] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:747; }","duration":"1.163096593s","start":"2026-02-25T06:07:05.59005Z","end":"2026-02-25T06:07:06.753147Z","steps":["trace[1323311117] 'agreement among raft nodes before linearized reading' (duration: 1.16205168s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:07:06.753202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:07:05.590029Z","time spent":"1.163161364s","remote":"127.0.0.1:49646","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-25T06:07:06.752261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"742.452645ms","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-25T06:07:06.753469Z","caller":"traceutil/trace.go:171","msg":"trace[203012220] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:747; }","duration":"743.715512ms","start":"2026-02-25T06:07:06.009733Z","end":"2026-02-25T06:07:06.753449Z","steps":["trace[203012220] 'agreement among raft nodes before linearized reading' (duration: 742.410594ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:07:06.75352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:07:06.009678Z","time spent":"743.826934ms","remote":"127.0.0.1:49886","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-25T06:07:06.752272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"820.795083ms","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-25T06:07:06.752753Z","caller":"traceutil/trace.go:171","msg":"trace[875777475] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:747; }","duration":"1.554120677s","start":"2026-02-25T06:07:05.198608Z","end":"2026-02-25T06:07:06.752729Z","steps":["trace[875777475] 'agreement among raft nodes before linearized reading' (duration: 1.553043983s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:07:06.753653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:07:05.19859Z","time spent":"1.555046457s","remote":"127.0.0.1:49886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-25T06:07:06.753654Z","caller":"traceutil/trace.go:171","msg":"trace[1761723755] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:747; }","duration":"822.209904ms","start":"2026-02-25T06:07:05.931406Z","end":"2026-02-25T06:07:06.753616Z","steps":["trace[1761723755] 'agreement among raft nodes before linearized reading' (duration: 820.64631ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:07:06.753815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:07:05.931382Z","time spent":"822.394837ms","remote":"127.0.0.1:49886","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-25T06:07:06.918179Z","caller":"traceutil/trace.go:171","msg":"trace[669796947] linearizableReadLoop","detail":"{readStateIndex:783; appliedIndex:778; }","duration":"109.688319ms","start":"2026-02-25T06:07:06.808464Z","end":"2026-02-25T06:07:06.918153Z","steps":["trace[669796947] 'read index received' (duration: 24.798017ms)","trace[669796947] 'applied index is now lower than readState.Index' (duration: 84.888292ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:07:06.918252Z","caller":"traceutil/trace.go:171","msg":"trace[1543794775] transaction","detail":"{read_only:false; response_revision:752; number_of_response:1; }","duration":"116.009358ms","start":"2026-02-25T06:07:06.802204Z","end":"2026-02-25T06:07:06.918213Z","steps":["trace[1543794775] 'process raft request' (duration: 115.806794ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:07:06.918365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.837192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T06:07:06.918517Z","caller":"traceutil/trace.go:171","msg":"trace[1796571964] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:752; }","duration":"109.991105ms","start":"2026-02-25T06:07:06.808459Z","end":"2026-02-25T06:07:06.91845Z","steps":["trace[1796571964] 'agreement among raft nodes before linearized reading' (duration: 109.784651ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:07:22.537295Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.030441777s","expected-duration":"1s"} {"level":"info","ts":"2026-02-25T06:07:22.537646Z","caller":"traceutil/trace.go:171","msg":"trace[1855298260] transaction","detail":"{read_only:false; response_revision:842; number_of_response:1; }","duration":"1.030834455s","start":"2026-02-25T06:07:21.506778Z","end":"2026-02-25T06:07:22.537613Z","steps":["trace[1855298260] 'process raft request' (duration: 1.030685492s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:07:22.537835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:07:21.506759Z","time spent":"1.030967169s","remote":"127.0.0.1:49886","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-25T06:07:23.089173Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827830074723713379,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T06:07:23.337932Z","caller":"traceutil/trace.go:171","msg":"trace[1181235620] linearizableReadLoop","detail":"{readStateIndex:878; appliedIndex:877; }","duration":"748.774514ms","start":"2026-02-25T06:07:22.589126Z","end":"2026-02-25T06:07:23.337901Z","steps":["trace[1181235620] 'read index received' (duration: 706.579244ms)","trace[1181235620] 'applied index is now lower than readState.Index' (duration: 42.19362ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:07:23.337994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"748.848686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T06:07:23.338046Z","caller":"traceutil/trace.go:171","msg":"trace[723363505] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:842; }","duration":"748.913067ms","start":"2026-02-25T06:07:22.58912Z","end":"2026-02-25T06:07:23.338034Z","steps":["trace[723363505] 'agreement among raft nodes before linearized reading' (duration: 748.810975ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:07:23.338074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:07:22.589066Z","time spent":"749.003249ms","remote":"127.0.0.1:49646","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-25T06:07:23.33816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"748.727443ms","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-25T06:07:23.338203Z","caller":"traceutil/trace.go:171","msg":"trace[728786437] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:842; }","duration":"748.843616ms","start":"2026-02-25T06:07:22.589348Z","end":"2026-02-25T06:07:23.338192Z","steps":["trace[728786437] 'agreement among raft nodes before linearized reading' (duration: 748.739453ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:07:23.338245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:07:22.589324Z","time spent":"748.910647ms","remote":"127.0.0.1:50022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-02-25T06:07:23.338352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.145063ms","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-25T06:07:23.338372Z","caller":"traceutil/trace.go:171","msg":"trace[254736406] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:842; }","duration":"384.182864ms","start":"2026-02-25T06:07:22.954184Z","end":"2026-02-25T06:07:23.338367Z","steps":["trace[254736406] 'agreement among raft nodes before linearized reading' (duration: 384.094792ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:07:23.338385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:07:22.954175Z","time spent":"384.206955ms","remote":"127.0.0.1:49886","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-25T06:07:23.338484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.656374ms","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-25T06:07:23.338457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.103708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T06:07:23.338542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"648.849171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-25T06:07:23.338539Z","caller":"traceutil/trace.go:171","msg":"trace[823707124] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:842; }","duration":"129.246642ms","start":"2026-02-25T06:07:23.209271Z","end":"2026-02-25T06:07:23.338518Z","steps":["trace[823707124] 'agreement among raft nodes before linearized reading' (duration: 129.077048ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:07:23.338562Z","caller":"traceutil/trace.go:171","msg":"trace[764675810] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:842; }","duration":"648.930452ms","start":"2026-02-25T06:07:22.689627Z","end":"2026-02-25T06:07:23.338558Z","steps":["trace[764675810] 'agreement among raft nodes before linearized reading' (duration: 648.887852ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:07:23.338537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.720673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-25T06:07:23.338578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:07:22.689607Z","time spent":"648.968264ms","remote":"127.0.0.1:49886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T06:07:23.338574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.767656ms","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-25T06:07:23.338611Z","caller":"traceutil/trace.go:171","msg":"trace[1685289523] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:842; }","duration":"407.837615ms","start":"2026-02-25T06:07:22.930758Z","end":"2026-02-25T06:07:23.338596Z","steps":["trace[1685289523] 'agreement among raft nodes before linearized reading' (duration: 407.638121ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:07:23.338637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"662.282437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T06:07:23.338634Z","caller":"traceutil/trace.go:171","msg":"trace[1985471467] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:842; }","duration":"384.882259ms","start":"2026-02-25T06:07:22.953735Z","end":"2026-02-25T06:07:23.338617Z","steps":["trace[1985471467] 'agreement among raft nodes before linearized reading' (duration: 384.696485ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:07:23.338653Z","caller":"traceutil/trace.go:171","msg":"trace[889914681] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:842; }","duration":"662.330468ms","start":"2026-02-25T06:07:22.676317Z","end":"2026-02-25T06:07:23.338647Z","steps":["trace[889914681] 'agreement among raft nodes before linearized reading' (duration: 662.302978ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:07:23.338648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:07:22.930717Z","time spent":"407.923457ms","remote":"127.0.0.1:49886","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-25T06:07:23.338663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:07:22.953713Z","time spent":"384.945921ms","remote":"127.0.0.1:49886","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-25T06:07:23.338668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:07:22.676265Z","time spent":"662.39894ms","remote":"127.0.0.1:49810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":4,"response size":30,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true "} {"level":"info","ts":"2026-02-25T06:07:23.3385Z","caller":"traceutil/trace.go:171","msg":"trace[1082980599] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:842; }","duration":"379.690304ms","start":"2026-02-25T06:07:22.958805Z","end":"2026-02-25T06:07:23.338496Z","steps":["trace[1082980599] 'agreement among raft nodes before linearized reading' (duration: 379.644873ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:07:23.338763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:07:22.958786Z","time spent":"379.971531ms","remote":"127.0.0.1:49886","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-25T06:07:35.556179Z","caller":"traceutil/trace.go:171","msg":"trace[957162026] transaction","detail":"{read_only:false; response_revision:877; number_of_response:1; }","duration":"100.556439ms","start":"2026-02-25T06:07:35.455595Z","end":"2026-02-25T06:07:35.556152Z","steps":["trace[957162026] 'process raft request' (duration: 26.788562ms)","trace[957162026] 'compare' (duration: 73.491111ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:07:35.711608Z","caller":"traceutil/trace.go:171","msg":"trace[403302235] linearizableReadLoop","detail":"{readStateIndex:918; appliedIndex:915; }","duration":"147.574324ms","start":"2026-02-25T06:07:35.564009Z","end":"2026-02-25T06:07:35.711584Z","steps":["trace[403302235] 'read index received' (duration: 42.783613ms)","trace[403302235] 'applied index is now lower than readState.Index' (duration: 104.789691ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:07:35.711632Z","caller":"traceutil/trace.go:171","msg":"trace[2119796153] transaction","detail":"{read_only:false; response_revision:879; number_of_response:1; }","duration":"149.498077ms","start":"2026-02-25T06:07:35.562111Z","end":"2026-02-25T06:07:35.711609Z","steps":["trace[2119796153] 'process raft request' (duration: 127.917881ms)","trace[2119796153] 'compare' (duration: 21.372551ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:07:35.711939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.895262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T06:07:35.712014Z","caller":"traceutil/trace.go:171","msg":"trace[1119344019] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:880; }","duration":"148.003614ms","start":"2026-02-25T06:07:35.563992Z","end":"2026-02-25T06:07:35.711995Z","steps":["trace[1119344019] 'agreement among raft nodes before linearized reading' (duration: 147.826069ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:07:35.711795Z","caller":"traceutil/trace.go:171","msg":"trace[141029095] transaction","detail":"{read_only:false; response_revision:880; number_of_response:1; }","duration":"149.276142ms","start":"2026-02-25T06:07:35.5625Z","end":"2026-02-25T06:07:35.711776Z","steps":["trace[141029095] 'process raft request' (duration: 149.040317ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:07:37.175729Z","caller":"traceutil/trace.go:171","msg":"trace[1500773931] linearizableReadLoop","detail":"{readStateIndex:919; appliedIndex:918; }","duration":"436.050817ms","start":"2026-02-25T06:07:36.739654Z","end":"2026-02-25T06:07:37.175705Z","steps":["trace[1500773931] 'read index received' (duration: 435.819762ms)","trace[1500773931] 'applied index is now lower than readState.Index' (duration: 229.765µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:07:37.175866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.193311ms","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-25T06:07:37.175899Z","caller":"traceutil/trace.go:171","msg":"trace[283326223] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:880; }","duration":"436.315493ms","start":"2026-02-25T06:07:36.739574Z","end":"2026-02-25T06:07:37.175889Z","steps":["trace[283326223] 'agreement among raft nodes before linearized reading' (duration: 436.226751ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:07:37.175936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:07:36.739552Z","time spent":"436.374004ms","remote":"127.0.0.1:49934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":30,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"warn","ts":"2026-02-25T06:07:37.176067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:07:36.71141Z","time spent":"464.649998ms","remote":"127.0.0.1:49682","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-25T06:07:37.782473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.86625ms","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-25T06:07:37.7826Z","caller":"traceutil/trace.go:171","msg":"trace[260488401] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:881; }","duration":"152.034854ms","start":"2026-02-25T06:07:37.630548Z","end":"2026-02-25T06:07:37.782583Z","steps":["trace[260488401] 'range keys from in-memory index tree' (duration: 151.85259ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:07:37.782974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.702885ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T06:07:37.783066Z","caller":"traceutil/trace.go:171","msg":"trace[47736571] linearizableReadLoop","detail":"{readStateIndex:921; appliedIndex:920; }","duration":"517.712859ms","start":"2026-02-25T06:07:37.265338Z","end":"2026-02-25T06:07:37.783051Z","steps":["trace[47736571] 'read index received' (duration: 161.744878ms)","trace[47736571] 'applied index is now lower than readState.Index' (duration: 355.966421ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:07:37.783189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.838891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T06:07:37.783173Z","caller":"traceutil/trace.go:171","msg":"trace[1225150868] transaction","detail":"{read_only:false; response_revision:882; number_of_response:1; }","duration":"522.523705ms","start":"2026-02-25T06:07:37.260578Z","end":"2026-02-25T06:07:37.783102Z","steps":["trace[1225150868] 'process raft request' (duration: 166.589785ms)","trace[1225150868] 'compare' (duration: 355.291076ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:07:37.783223Z","caller":"traceutil/trace.go:171","msg":"trace[604173053] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:882; }","duration":"517.899732ms","start":"2026-02-25T06:07:37.265313Z","end":"2026-02-25T06:07:37.783213Z","steps":["trace[604173053] 'agreement among raft nodes before linearized reading' (duration: 517.830621ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:07:37.783265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:07:37.265295Z","time spent":"517.959694ms","remote":"127.0.0.1:49690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":11,"response size":30,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"warn","ts":"2026-02-25T06:07:37.783332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:07:37.260552Z","time spent":"522.681779ms","remote":"127.0.0.1:49886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:07:37.783435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.329534ms","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-25T06:07:37.783489Z","caller":"traceutil/trace.go:171","msg":"trace[2059283172] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:882; }","duration":"334.418425ms","start":"2026-02-25T06:07:37.449056Z","end":"2026-02-25T06:07:37.783475Z","steps":["trace[2059283172] 'agreement among raft nodes before linearized reading' (duration: 334.262392ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:07:37.783517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:07:37.44904Z","time spent":"334.473277ms","remote":"127.0.0.1:49886","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-25T06:07:37.783615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.130366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-25T06:07:37.783629Z","caller":"traceutil/trace.go:171","msg":"trace[1088000290] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:882; }","duration":"172.187667ms","start":"2026-02-25T06:07:37.611437Z","end":"2026-02-25T06:07:37.783625Z","steps":["trace[1088000290] 'agreement among raft nodes before linearized reading' (duration: 172.144256ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:07:37.783629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.973958ms","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-25T06:07:37.783672Z","caller":"traceutil/trace.go:171","msg":"trace[737186799] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:882; }","duration":"223.06465ms","start":"2026-02-25T06:07:37.560597Z","end":"2026-02-25T06:07:37.783662Z","steps":["trace[737186799] 'agreement among raft nodes before linearized reading' (duration: 222.931507ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:07:37.922465Z","caller":"traceutil/trace.go:171","msg":"trace[788188610] transaction","detail":"{read_only:false; response_revision:885; number_of_response:1; }","duration":"132.808589ms","start":"2026-02-25T06:07:37.789636Z","end":"2026-02-25T06:07:37.922444Z","steps":["trace[788188610] 'process raft request' (duration: 132.736598ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:07:37.922514Z","caller":"traceutil/trace.go:171","msg":"trace[1291025223] transaction","detail":"{read_only:false; response_revision:884; number_of_response:1; }","duration":"132.850451ms","start":"2026-02-25T06:07:37.789628Z","end":"2026-02-25T06:07:37.922479Z","steps":["trace[1291025223] 'process raft request' (duration: 132.37628ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:07:37.922695Z","caller":"traceutil/trace.go:171","msg":"trace[1469837417] transaction","detail":"{read_only:false; response_revision:887; number_of_response:1; }","duration":"132.758018ms","start":"2026-02-25T06:07:37.789914Z","end":"2026-02-25T06:07:37.922672Z","steps":["trace[1469837417] 'process raft request' (duration: 132.674867ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:07:37.922718Z","caller":"traceutil/trace.go:171","msg":"trace[1378539292] transaction","detail":"{read_only:false; response_revision:886; number_of_response:1; }","duration":"133.037495ms","start":"2026-02-25T06:07:37.789667Z","end":"2026-02-25T06:07:37.922705Z","steps":["trace[1378539292] 'process raft request' (duration: 132.747069ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:07:40.249722Z","caller":"traceutil/trace.go:171","msg":"trace[416259601] transaction","detail":"{read_only:false; response_revision:889; number_of_response:1; }","duration":"318.966195ms","start":"2026-02-25T06:07:39.930718Z","end":"2026-02-25T06:07:40.249684Z","steps":["trace[416259601] 'process raft request' (duration: 297.012722ms)","trace[416259601] 'compare' (duration: 21.665217ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:07:40.249959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:07:39.930704Z","time spent":"319.118908ms","remote":"127.0.0.1:49886","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-25T06:07:40.25813Z","caller":"traceutil/trace.go:171","msg":"trace[1128440952] transaction","detail":"{read_only:false; response_revision:890; number_of_response:1; }","duration":"327.334929ms","start":"2026-02-25T06:07:39.930745Z","end":"2026-02-25T06:07:40.25808Z","steps":["trace[1128440952] 'process raft request' (duration: 327.077984ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:07:40.25833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:07:39.930731Z","time spent":"327.489423ms","remote":"127.0.0.1:49886","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-25T06:07:40.258942Z","caller":"traceutil/trace.go:171","msg":"trace[1255205687] transaction","detail":"{read_only:false; response_revision:891; number_of_response:1; }","duration":"328.110256ms","start":"2026-02-25T06:07:39.930806Z","end":"2026-02-25T06:07:40.258916Z","steps":["trace[1255205687] 'process raft request' (duration: 327.202606ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:07:40.25894Z","caller":"traceutil/trace.go:171","msg":"trace[1246060107] transaction","detail":"{read_only:false; response_revision:892; number_of_response:1; }","duration":"328.058505ms","start":"2026-02-25T06:07:39.930852Z","end":"2026-02-25T06:07:40.25891Z","steps":["trace[1246060107] 'process raft request' (duration: 327.972863ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:07:40.259077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:07:39.930799Z","time spent":"328.203538ms","remote":"127.0.0.1:49886","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-25T06:07:40.25915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:07:39.930846Z","time spent":"328.228449ms","remote":"127.0.0.1:49886","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-25T06:07:40.496952Z","caller":"traceutil/trace.go:171","msg":"trace[657695329] transaction","detail":"{read_only:false; response_revision:893; number_of_response:1; }","duration":"174.162692ms","start":"2026-02-25T06:07:40.322738Z","end":"2026-02-25T06:07:40.496901Z","steps":["trace[657695329] 'process raft request' (duration: 141.121343ms)","trace[657695329] 'compare' (duration: 32.802074ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:07:42.212872Z","caller":"traceutil/trace.go:171","msg":"trace[1257293804] transaction","detail":"{read_only:false; response_revision:894; number_of_response:1; }","duration":"317.226557ms","start":"2026-02-25T06:07:41.8956Z","end":"2026-02-25T06:07:42.212827Z","steps":["trace[1257293804] 'process raft request' (duration: 316.887079ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:07:42.21316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:07:41.895584Z","time spent":"317.414621ms","remote":"127.0.0.1:49886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:07:42.618482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.958675ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-25T06:07:42.61857Z","caller":"traceutil/trace.go:171","msg":"trace[985289834] linearizableReadLoop","detail":"{readStateIndex:934; appliedIndex:933; }","duration":"363.446786ms","start":"2026-02-25T06:07:42.25511Z","end":"2026-02-25T06:07:42.618557Z","steps":["trace[985289834] 'read index received' (duration: 34.389909ms)","trace[985289834] 'applied index is now lower than readState.Index' (duration: 329.055567ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:07:42.618746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.638121ms","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-25T06:07:42.618771Z","caller":"traceutil/trace.go:171","msg":"trace[224121843] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:894; }","duration":"363.707661ms","start":"2026-02-25T06:07:42.255056Z","end":"2026-02-25T06:07:42.618764Z","steps":["trace[224121843] 'agreement among raft nodes before linearized reading' (duration: 363.569038ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:07:42.618796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:07:42.255029Z","time spent":"363.761653ms","remote":"127.0.0.1:49886","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-25T06:07:42.618787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.005591ms","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-25T06:07:42.618836Z","caller":"traceutil/trace.go:171","msg":"trace[1380977757] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:894; }","duration":"356.084864ms","start":"2026-02-25T06:07:42.262738Z","end":"2026-02-25T06:07:42.618822Z","steps":["trace[1380977757] 'agreement among raft nodes before linearized reading' (duration: 355.94064ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:07:42.618863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:07:42.262703Z","time spent":"356.149615ms","remote":"127.0.0.1:49886","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-25T06:07:42.618916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.490882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-02-25T06:07:42.618967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.231117ms","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-25T06:07:42.618984Z","caller":"traceutil/trace.go:171","msg":"trace[1059432641] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:894; }","duration":"356.260237ms","start":"2026-02-25T06:07:42.262719Z","end":"2026-02-25T06:07:42.618979Z","steps":["trace[1059432641] 'agreement among raft nodes before linearized reading' (duration: 356.213406ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:07:42.618997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:07:42.262709Z","time spent":"356.284547ms","remote":"127.0.0.1:49886","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-25T06:07:42.618994Z","caller":"traceutil/trace.go:171","msg":"trace[207018147] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:894; }","duration":"356.605645ms","start":"2026-02-25T06:07:42.262367Z","end":"2026-02-25T06:07:42.618973Z","steps":["trace[207018147] 'agreement among raft nodes before linearized reading' (duration: 356.296818ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:07:42.619039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:07:42.262348Z","time spent":"356.682576ms","remote":"127.0.0.1:49886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-25T06:07:42.797413Z","caller":"traceutil/trace.go:171","msg":"trace[1818684113] transaction","detail":"{read_only:false; response_revision:896; number_of_response:1; }","duration":"175.049301ms","start":"2026-02-25T06:07:42.622347Z","end":"2026-02-25T06:07:42.797396Z","steps":["trace[1818684113] 'process raft request' (duration: 174.863127ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:07:42.797651Z","caller":"traceutil/trace.go:171","msg":"trace[2045215110] transaction","detail":"{read_only:false; response_revision:897; number_of_response:1; }","duration":"175.174034ms","start":"2026-02-25T06:07:42.622457Z","end":"2026-02-25T06:07:42.797631Z","steps":["trace[2045215110] 'process raft request' (duration: 174.903559ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:07:42.797833Z","caller":"traceutil/trace.go:171","msg":"trace[977387511] transaction","detail":"{read_only:false; response_revision:898; number_of_response:1; }","duration":"175.239156ms","start":"2026-02-25T06:07:42.622583Z","end":"2026-02-25T06:07:42.797822Z","steps":["trace[977387511] 'process raft request' (duration: 174.98847ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:07:43.462504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.901193ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-25T06:07:43.462742Z","caller":"traceutil/trace.go:171","msg":"trace[1453595841] transaction","detail":"{read_only:false; response_revision:900; number_of_response:1; }","duration":"395.755948ms","start":"2026-02-25T06:07:43.066932Z","end":"2026-02-25T06:07:43.462688Z","steps":["trace[1453595841] 'process raft request' (duration: 395.685577ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:07:43.462831Z","caller":"traceutil/trace.go:171","msg":"trace[1153677397] linearizableReadLoop","detail":"{readStateIndex:939; appliedIndex:938; }","duration":"480.395865ms","start":"2026-02-25T06:07:42.982383Z","end":"2026-02-25T06:07:43.462779Z","steps":["trace[1153677397] 'read index received' (duration: 40.771µs)","trace[1153677397] 'applied index is now lower than readState.Index' (duration: 480.351184ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:07:43.462863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:07:43.066909Z","time spent":"395.902652ms","remote":"127.0.0.1:49886","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-25T06:07:43.463111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.685181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4742"} {"level":"info","ts":"2026-02-25T06:07:43.463163Z","caller":"traceutil/trace.go:171","msg":"trace[1804042555] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:900; }","duration":"480.808574ms","start":"2026-02-25T06:07:42.982341Z","end":"2026-02-25T06:07:43.46315Z","steps":["trace[1804042555] 'agreement among raft nodes before linearized reading' (duration: 480.568599ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:07:43.463206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:07:42.982322Z","time spent":"480.874245ms","remote":"127.0.0.1:49796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4765,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-02-25T06:07:43.463231Z","caller":"traceutil/trace.go:171","msg":"trace[811309314] transaction","detail":"{read_only:false; response_revision:899; number_of_response:1; }","duration":"596.29295ms","start":"2026-02-25T06:07:42.86691Z","end":"2026-02-25T06:07:43.463203Z","steps":["trace[811309314] 'process raft request' (duration: 67.59168ms)","trace[811309314] 'compare' (duration: 527.723029ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:07:43.46345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:07:42.866704Z","time spent":"596.682ms","remote":"127.0.0.1:49712","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":750,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T06:07:44.102643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.537679ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-25T06:07:44.102957Z","caller":"traceutil/trace.go:171","msg":"trace[1180156294] linearizableReadLoop","detail":"{readStateIndex:941; appliedIndex:940; }","duration":"575.33315ms","start":"2026-02-25T06:07:43.527601Z","end":"2026-02-25T06:07:44.102934Z","steps":["trace[1180156294] 'read index received' (duration: 352.420842ms)","trace[1180156294] 'applied index is now lower than readState.Index' (duration: 222.906848ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:07:44.103091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"575.482524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T06:07:44.103144Z","caller":"traceutil/trace.go:171","msg":"trace[1747515620] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:901; }","duration":"575.543795ms","start":"2026-02-25T06:07:43.527586Z","end":"2026-02-25T06:07:44.10313Z","steps":["trace[1747515620] 'agreement among raft nodes before linearized reading' (duration: 575.423352ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:07:44.103192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:07:43.527531Z","time spent":"575.648767ms","remote":"127.0.0.1:49964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":0,"response size":28,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" "} {"level":"info","ts":"2026-02-25T06:07:44.103488Z","caller":"traceutil/trace.go:171","msg":"trace[957180339] transaction","detail":"{read_only:false; response_revision:901; number_of_response:1; }","duration":"636.018897ms","start":"2026-02-25T06:07:43.467454Z","end":"2026-02-25T06:07:44.103473Z","steps":["trace[957180339] 'process raft request' (duration: 412.584709ms)","trace[957180339] 'compare' (duration: 222.427186ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:07:44.103558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:07:43.467436Z","time spent":"636.091249ms","remote":"127.0.0.1:49712","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":750,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T06:07:44.543825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.408003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T06:07:44.543865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.548472ms","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-25T06:07:44.54391Z","caller":"traceutil/trace.go:171","msg":"trace[62413981] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:902; }","duration":"192.534615ms","start":"2026-02-25T06:07:44.351351Z","end":"2026-02-25T06:07:44.543885Z","steps":["trace[62413981] 'count revisions from in-memory index tree' (duration: 192.335421ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:07:44.543961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.367246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T06:07:44.543986Z","caller":"traceutil/trace.go:171","msg":"trace[1435361638] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:902; }","duration":"326.681394ms","start":"2026-02-25T06:07:44.217243Z","end":"2026-02-25T06:07:44.543924Z","steps":["trace[1435361638] 'range keys from in-memory index tree' (duration: 326.337297ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:07:44.544025Z","caller":"traceutil/trace.go:171","msg":"trace[1410509959] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:902; }","duration":"145.475979ms","start":"2026-02-25T06:07:44.398535Z","end":"2026-02-25T06:07:44.544011Z","steps":["trace[1410509959] 'count revisions from in-memory index tree' (duration: 145.296405ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:07:44.544035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:07:44.217223Z","time spent":"326.801317ms","remote":"127.0.0.1:49886","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-25T06:07:44.83309Z","caller":"traceutil/trace.go:171","msg":"trace[2076592570] linearizableReadLoop","detail":"{readStateIndex:943; appliedIndex:942; }","duration":"244.128424ms","start":"2026-02-25T06:07:44.588938Z","end":"2026-02-25T06:07:44.833066Z","steps":["trace[2076592570] 'read index received' (duration: 243.93239ms)","trace[2076592570] 'applied index is now lower than readState.Index' (duration: 194.974µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:07:44.833196Z","caller":"traceutil/trace.go:171","msg":"trace[416864421] transaction","detail":"{read_only:false; response_revision:903; number_of_response:1; }","duration":"286.242144ms","start":"2026-02-25T06:07:44.546921Z","end":"2026-02-25T06:07:44.833163Z","steps":["trace[416864421] 'process raft request' (duration: 286.002548ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:07:44.833261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.305619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T06:07:44.833299Z","caller":"traceutil/trace.go:171","msg":"trace[1378928276] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:903; }","duration":"244.383431ms","start":"2026-02-25T06:07:44.588904Z","end":"2026-02-25T06:07:44.833287Z","steps":["trace[1378928276] 'agreement among raft nodes before linearized reading' (duration: 244.313089ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:07:44.833529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.085147ms","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-25T06:07:44.833594Z","caller":"traceutil/trace.go:171","msg":"trace[316654731] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:903; }","duration":"153.175148ms","start":"2026-02-25T06:07:44.680404Z","end":"2026-02-25T06:07:44.833579Z","steps":["trace[316654731] 'agreement among raft nodes before linearized reading' (duration: 153.013675ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:07:55.240727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.690462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T06:07:55.240827Z","caller":"traceutil/trace.go:171","msg":"trace[40495777] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:967; }","duration":"110.858745ms","start":"2026-02-25T06:07:55.129947Z","end":"2026-02-25T06:07:55.240806Z","steps":["trace[40495777] 'count revisions from in-memory index tree' (duration: 110.59706ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:07:58.567878Z","caller":"traceutil/trace.go:171","msg":"trace[1499410097] transaction","detail":"{read_only:false; response_revision:982; number_of_response:1; }","duration":"181.680796ms","start":"2026-02-25T06:07:58.386176Z","end":"2026-02-25T06:07:58.567857Z","steps":["trace[1499410097] 'process raft request' (duration: 181.543103ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:09:42.241656Z","caller":"traceutil/trace.go:171","msg":"trace[1087489071] transaction","detail":"{read_only:false; response_revision:1426; number_of_response:1; }","duration":"185.433454ms","start":"2026-02-25T06:09:42.056183Z","end":"2026-02-25T06:09:42.241616Z","steps":["trace[1087489071] 'process raft request' (duration: 153.1358ms)","trace[1087489071] 'compare' (duration: 31.933695ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:10:17.812231Z","caller":"traceutil/trace.go:171","msg":"trace[892047335] transaction","detail":"{read_only:false; response_revision:1568; number_of_response:1; }","duration":"100.487558ms","start":"2026-02-25T06:10:17.71172Z","end":"2026-02-25T06:10:17.812208Z","steps":["trace[892047335] 'process raft request' (duration: 100.356025ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:10:17.81392Z","caller":"traceutil/trace.go:171","msg":"trace[323832333] transaction","detail":"{read_only:false; response_revision:1569; number_of_response:1; }","duration":"101.853158ms","start":"2026-02-25T06:10:17.712039Z","end":"2026-02-25T06:10:17.813892Z","steps":["trace[323832333] 'process raft request' (duration: 101.688955ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:10:29.734955Z","caller":"traceutil/trace.go:171","msg":"trace[1476123260] transaction","detail":"{read_only:false; response_revision:1616; number_of_response:1; }","duration":"111.084574ms","start":"2026-02-25T06:10:29.623846Z","end":"2026-02-25T06:10:29.734931Z","steps":["trace[1476123260] 'process raft request' (duration: 110.92727ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:11:02.986591Z","caller":"traceutil/trace.go:171","msg":"trace[550175280] transaction","detail":"{read_only:false; response_revision:1784; number_of_response:1; }","duration":"101.97055ms","start":"2026-02-25T06:11:02.884599Z","end":"2026-02-25T06:11:02.98657Z","steps":["trace[550175280] 'process raft request' (duration: 101.839247ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:04.803242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.754096ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T06:11:04.80337Z","caller":"traceutil/trace.go:171","msg":"trace[75421598] linearizableReadLoop","detail":"{readStateIndex:1868; appliedIndex:1867; }","duration":"541.732054ms","start":"2026-02-25T06:11:04.261622Z","end":"2026-02-25T06:11:04.803354Z","steps":["trace[75421598] 'read index received' (duration: 267.726823ms)","trace[75421598] 'applied index is now lower than readState.Index' (duration: 274.004121ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:11:04.803474Z","caller":"traceutil/trace.go:171","msg":"trace[825592096] transaction","detail":"{read_only:false; response_revision:1787; number_of_response:1; }","duration":"554.715753ms","start":"2026-02-25T06:11:04.248726Z","end":"2026-02-25T06:11:04.803441Z","steps":["trace[825592096] 'process raft request' (duration: 280.681451ms)","trace[825592096] 'compare' (duration: 273.653294ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:11:04.803636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:04.248711Z","time spent":"554.846256ms","remote":"127.0.0.1:49886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":511,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:11:04.803626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.289372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T06:11:04.803636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.002021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-02-25T06:11:04.803702Z","caller":"traceutil/trace.go:171","msg":"trace[1013229054] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1787; }","duration":"214.428155ms","start":"2026-02-25T06:11:04.589253Z","end":"2026-02-25T06:11:04.803681Z","steps":["trace[1013229054] 'agreement among raft nodes before linearized reading' (duration: 214.294862ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:04.803702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.488921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-02-25T06:11:04.803736Z","caller":"traceutil/trace.go:171","msg":"trace[1426292917] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1787; }","duration":"542.117413ms","start":"2026-02-25T06:11:04.261607Z","end":"2026-02-25T06:11:04.803724Z","steps":["trace[1426292917] 'agreement among raft nodes before linearized reading' (duration: 541.867678ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:04.803768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.008694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-02-25T06:11:04.803806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:04.2616Z","time spent":"542.194285ms","remote":"127.0.0.1:49886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-02-25T06:11:04.803756Z","caller":"traceutil/trace.go:171","msg":"trace[1458941949] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1787; }","duration":"392.575504ms","start":"2026-02-25T06:11:04.411166Z","end":"2026-02-25T06:11:04.803742Z","steps":["trace[1458941949] 'agreement among raft nodes before linearized reading' (duration: 392.425ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:11:04.803846Z","caller":"traceutil/trace.go:171","msg":"trace[1336489116] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1787; }","duration":"279.120426ms","start":"2026-02-25T06:11:04.524706Z","end":"2026-02-25T06:11:04.803827Z","steps":["trace[1336489116] 'agreement among raft nodes before linearized reading' (duration: 278.871391ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:04.803881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:04.41115Z","time spent":"392.723276ms","remote":"127.0.0.1:49886","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-25T06:11:04.80394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.905253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-02-25T06:11:04.803829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.676298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-02-25T06:11:04.804045Z","caller":"traceutil/trace.go:171","msg":"trace[1987673679] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1787; }","duration":"517.904744ms","start":"2026-02-25T06:11:04.286125Z","end":"2026-02-25T06:11:04.80403Z","steps":["trace[1987673679] 'agreement among raft nodes before linearized reading' (duration: 517.395203ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:04.804081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:04.286115Z","time spent":"517.957835ms","remote":"127.0.0.1:49886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-25T06:11:04.804065Z","caller":"traceutil/trace.go:171","msg":"trace[1178982307] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1787; }","duration":"227.091837ms","start":"2026-02-25T06:11:04.576918Z","end":"2026-02-25T06:11:04.804009Z","steps":["trace[1178982307] 'agreement among raft nodes before linearized reading' (duration: 226.622147ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:11:05.017851Z","caller":"traceutil/trace.go:171","msg":"trace[1427170950] transaction","detail":"{read_only:false; response_revision:1789; number_of_response:1; }","duration":"209.400793ms","start":"2026-02-25T06:11:04.808421Z","end":"2026-02-25T06:11:05.017821Z","steps":["trace[1427170950] 'process raft request' (duration: 209.22717ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:11:05.018294Z","caller":"traceutil/trace.go:171","msg":"trace[1720519772] transaction","detail":"{read_only:false; response_revision:1790; number_of_response:1; }","duration":"209.786742ms","start":"2026-02-25T06:11:04.808471Z","end":"2026-02-25T06:11:05.018258Z","steps":["trace[1720519772] 'process raft request' (duration: 209.313822ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:11:05.018508Z","caller":"traceutil/trace.go:171","msg":"trace[49685766] transaction","detail":"{read_only:false; response_revision:1791; number_of_response:1; }","duration":"209.036114ms","start":"2026-02-25T06:11:04.809455Z","end":"2026-02-25T06:11:05.018491Z","steps":["trace[49685766] 'process raft request' (duration: 208.638825ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:11:05.018764Z","caller":"traceutil/trace.go:171","msg":"trace[466925822] transaction","detail":"{read_only:false; response_revision:1792; number_of_response:1; }","duration":"208.647396ms","start":"2026-02-25T06:11:04.810075Z","end":"2026-02-25T06:11:05.018723Z","steps":["trace[466925822] 'process raft request' (duration: 208.35307ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:11:05.018813Z","caller":"traceutil/trace.go:171","msg":"trace[957770724] transaction","detail":"{read_only:false; response_revision:1793; number_of_response:1; }","duration":"208.671287ms","start":"2026-02-25T06:11:04.810129Z","end":"2026-02-25T06:11:05.0188Z","steps":["trace[957770724] 'process raft request' (duration: 208.527204ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:11:35.256418Z","caller":"traceutil/trace.go:171","msg":"trace[794231113] transaction","detail":"{read_only:false; response_revision:1923; number_of_response:1; }","duration":"100.731167ms","start":"2026-02-25T06:11:35.155662Z","end":"2026-02-25T06:11:35.256393Z","steps":["trace[794231113] 'process raft request' (duration: 100.575374ms)"],"step_count":1}