{"level":"warn","ts":"2026-02-20T21:38:26.871431Z","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-20T21:38:26.871536Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.235: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.235:2380","--initial-cluster=instance=https://199.204.45.235:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.235:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.235: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-20T21:38:26.871618Z","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-20T21:38:26.871632Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.235:2380"]} {"level":"info","ts":"2026-02-20T21:38:26.871657Z","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-20T21:38:26.872475Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.235:2379"]} {"level":"info","ts":"2026-02-20T21:38:26.872554Z","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.235:2380"],"listen-peer-urls":["https://199.204.45.235:2380"],"advertise-client-urls":["https://199.204.45.235:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.235:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.235: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-20T21:38:26.876456Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.715646ms"} {"level":"info","ts":"2026-02-20T21:38:26.883967Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"aab6040abcf11","cluster-id":"2bae7d3d8857c059"} {"level":"info","ts":"2026-02-20T21:38:26.884056Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 switched to configuration voters=()"} {"level":"info","ts":"2026-02-20T21:38:26.884086Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became follower at term 0"} {"level":"info","ts":"2026-02-20T21:38:26.884104Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft aab6040abcf11 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-20T21:38:26.884121Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became follower at term 1"} {"level":"info","ts":"2026-02-20T21:38:26.884164Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 switched to configuration voters=(3003179657318161)"} {"level":"warn","ts":"2026-02-20T21:38:26.890698Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-20T21:38:26.892336Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-20T21:38:26.893715Z","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-20T21:38:26.897045Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"aab6040abcf11","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-20T21:38:26.897815Z","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-20T21:38:26.897901Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"aab6040abcf11","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-20T21:38:26.898756Z","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-20T21:38:26.898832Z","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-20T21:38:26.900136Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 switched to configuration voters=(3003179657318161)"} {"level":"info","ts":"2026-02-20T21:38:26.900772Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"2bae7d3d8857c059","local-member-id":"aab6040abcf11","added-peer-id":"aab6040abcf11","added-peer-peer-urls":["https://199.204.45.235:2380"]} {"level":"info","ts":"2026-02-20T21:38:26.904298Z","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-20T21:38:26.90445Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.235:2380"} {"level":"info","ts":"2026-02-20T21:38:26.904551Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.235:2380"} {"level":"info","ts":"2026-02-20T21:38:26.904866Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"aab6040abcf11","initial-advertise-peer-urls":["https://199.204.45.235:2380"],"listen-peer-urls":["https://199.204.45.235:2380"],"advertise-client-urls":["https://199.204.45.235:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.235:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-20T21:38:26.90494Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-20T21:38:27.785439Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 is starting a new election at term 1"} {"level":"info","ts":"2026-02-20T21:38:27.785505Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-20T21:38:27.785541Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 received MsgPreVoteResp from aab6040abcf11 at term 1"} {"level":"info","ts":"2026-02-20T21:38:27.785587Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became candidate at term 2"} {"level":"info","ts":"2026-02-20T21:38:27.785599Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 received MsgVoteResp from aab6040abcf11 at term 2"} {"level":"info","ts":"2026-02-20T21:38:27.78561Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became leader at term 2"} {"level":"info","ts":"2026-02-20T21:38:27.785624Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: aab6040abcf11 elected leader aab6040abcf11 at term 2"} {"level":"info","ts":"2026-02-20T21:38:27.78664Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"aab6040abcf11","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.235:2379]}","request-path":"/0/members/aab6040abcf11/attributes","cluster-id":"2bae7d3d8857c059","publish-timeout":"7s"} {"level":"info","ts":"2026-02-20T21:38:27.786654Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-20T21:38:27.78665Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-20T21:38:27.786805Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-20T21:38:27.787162Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-20T21:38:27.787186Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-20T21:38:27.787647Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"2bae7d3d8857c059","local-member-id":"aab6040abcf11","cluster-version":"3.5"} {"level":"info","ts":"2026-02-20T21:38:27.787755Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-20T21:38:27.787806Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-20T21:38:27.790328Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-20T21:38:27.79234Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.235:2379"} {"level":"info","ts":"2026-02-20T21:38:34.63859Z","caller":"traceutil/trace.go:171","msg":"trace[1831881519] transaction","detail":"{read_only:false; response_revision:329; number_of_response:1; }","duration":"158.942597ms","start":"2026-02-20T21:38:34.47962Z","end":"2026-02-20T21:38:34.638563Z","steps":["trace[1831881519] 'process raft request' (duration: 158.804414ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T21:38:42.792801Z","caller":"traceutil/trace.go:171","msg":"trace[221112651] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"156.441962ms","start":"2026-02-20T21:38:42.636338Z","end":"2026-02-20T21:38:42.79278Z","steps":["trace[221112651] 'process raft request' (duration: 156.317149ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T21:38:42.986976Z","caller":"traceutil/trace.go:171","msg":"trace[284069649] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"290.326506ms","start":"2026-02-20T21:38:42.696624Z","end":"2026-02-20T21:38:42.98695Z","steps":["trace[284069649] 'process raft request' (duration: 288.971011ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T21:38:52.0299Z","caller":"traceutil/trace.go:171","msg":"trace[1507093042] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"176.586167ms","start":"2026-02-20T21:38:51.85329Z","end":"2026-02-20T21:38:52.029876Z","steps":["trace[1507093042] 'process raft request' (duration: 176.461864ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:38:52.522007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.711069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T21:38:52.52211Z","caller":"traceutil/trace.go:171","msg":"trace[1216532098] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:465; }","duration":"402.870682ms","start":"2026-02-20T21:38:52.119219Z","end":"2026-02-20T21:38:52.52209Z","steps":["trace[1216532098] 'range keys from in-memory index tree' (duration: 402.629536ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:38:52.522154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:38:52.119199Z","time spent":"402.945725ms","remote":"127.0.0.1:52002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-20T21:38:57.261878Z","caller":"traceutil/trace.go:171","msg":"trace[1746856292] linearizableReadLoop","detail":"{readStateIndex:527; appliedIndex:526; }","duration":"210.600706ms","start":"2026-02-20T21:38:57.051252Z","end":"2026-02-20T21:38:57.261853Z","steps":["trace[1746856292] 'read index received' (duration: 210.394731ms)","trace[1746856292] 'applied index is now lower than readState.Index' (duration: 205.045µs)"],"step_count":2} {"level":"info","ts":"2026-02-20T21:38:57.262011Z","caller":"traceutil/trace.go:171","msg":"trace[962436771] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"573.029863ms","start":"2026-02-20T21:38:56.688969Z","end":"2026-02-20T21:38:57.261999Z","steps":["trace[962436771] 'process raft request' (duration: 572.690754ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:38:57.262219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.860885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T21:38:57.2623Z","caller":"traceutil/trace.go:171","msg":"trace[1706574296] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:509; }","duration":"143.986008ms","start":"2026-02-20T21:38:57.118294Z","end":"2026-02-20T21:38:57.26228Z","steps":["trace[1706574296] 'agreement among raft nodes before linearized reading' (duration: 143.866285ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:38:57.262569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:38:56.688951Z","time spent":"573.096154ms","remote":"127.0.0.1:52190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4213,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T21:38:57.262585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.304814ms","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-20T21:38:57.262671Z","caller":"traceutil/trace.go:171","msg":"trace[371393186] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:509; }","duration":"211.434457ms","start":"2026-02-20T21:38:57.051203Z","end":"2026-02-20T21:38:57.262638Z","steps":["trace[371393186] 'agreement among raft nodes before linearized reading' (duration: 211.211182ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T21:38:57.54683Z","caller":"traceutil/trace.go:171","msg":"trace[1295961160] linearizableReadLoop","detail":"{readStateIndex:528; appliedIndex:527; }","duration":"221.070108ms","start":"2026-02-20T21:38:57.325713Z","end":"2026-02-20T21:38:57.546783Z","steps":["trace[1295961160] 'read index received' (duration: 220.637987ms)","trace[1295961160] 'applied index is now lower than readState.Index' (duration: 430.211µs)"],"step_count":2} {"level":"info","ts":"2026-02-20T21:38:57.546909Z","caller":"traceutil/trace.go:171","msg":"trace[761034657] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"279.466482ms","start":"2026-02-20T21:38:57.267409Z","end":"2026-02-20T21:38:57.546876Z","steps":["trace[761034657] 'process raft request' (duration: 279.125573ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:38:57.547058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.291555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-20T21:38:57.547158Z","caller":"traceutil/trace.go:171","msg":"trace[1662757909] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:510; }","duration":"221.48071ms","start":"2026-02-20T21:38:57.325661Z","end":"2026-02-20T21:38:57.547142Z","steps":["trace[1662757909] 'agreement among raft nodes before linearized reading' (duration: 221.279775ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:39:00.428133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"728.107019ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-20T21:39:00.42826Z","caller":"traceutil/trace.go:171","msg":"trace[1489741124] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"829.672879ms","start":"2026-02-20T21:38:59.598567Z","end":"2026-02-20T21:39:00.42824Z","steps":["trace[1489741124] 'process raft request' (duration: 101.19076ms)","trace[1489741124] 'compare' (duration: 727.655908ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T21:39:00.428342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:38:59.598556Z","time spent":"829.747622ms","remote":"127.0.0.1:52292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T21:39:00.619243Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920879101096216682,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T21:39:00.732899Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.033211891s","expected-duration":"1s"} {"level":"info","ts":"2026-02-20T21:39:00.734162Z","caller":"traceutil/trace.go:171","msg":"trace[1417126680] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"1.135439798s","start":"2026-02-20T21:38:59.598684Z","end":"2026-02-20T21:39:00.734123Z","steps":["trace[1417126680] 'process raft request' (duration: 1.134431052s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:39:00.734369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:38:59.598658Z","time spent":"1.135627223s","remote":"127.0.0.1:52292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T21:39:00.735168Z","caller":"traceutil/trace.go:171","msg":"trace[918331029] linearizableReadLoop","detail":"{readStateIndex:535; appliedIndex:533; }","duration":"616.695242ms","start":"2026-02-20T21:39:00.118444Z","end":"2026-02-20T21:39:00.735139Z","steps":["trace[918331029] 'read index received' (duration: 614.68805ms)","trace[918331029] 'applied index is now lower than readState.Index' (duration: 2.006072ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T21:39:00.73547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"617.00671ms","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-20T21:39:00.735721Z","caller":"traceutil/trace.go:171","msg":"trace[1174752937] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:517; }","duration":"617.311708ms","start":"2026-02-20T21:39:00.11839Z","end":"2026-02-20T21:39:00.735702Z","steps":["trace[1174752937] 'agreement among raft nodes before linearized reading' (duration: 616.876007ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:39:00.735843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:39:00.118371Z","time spent":"617.451322ms","remote":"127.0.0.1:52292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-20T21:39:00.735573Z","caller":"traceutil/trace.go:171","msg":"trace[1684659983] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"738.439519ms","start":"2026-02-20T21:38:59.997109Z","end":"2026-02-20T21:39:00.735548Z","steps":["trace[1684659983] 'process raft request' (duration: 737.927345ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:39:00.736293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:38:59.997068Z","time spent":"739.143167ms","remote":"127.0.0.1:52292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T21:39:04.959602Z","caller":"traceutil/trace.go:171","msg":"trace[1320834135] linearizableReadLoop","detail":"{readStateIndex:549; appliedIndex:548; }","duration":"198.19006ms","start":"2026-02-20T21:39:04.761386Z","end":"2026-02-20T21:39:04.959576Z","steps":["trace[1320834135] 'read index received' (duration: 197.953644ms)","trace[1320834135] 'applied index is now lower than readState.Index' (duration: 234.816µs)"],"step_count":2} {"level":"info","ts":"2026-02-20T21:39:04.959796Z","caller":"traceutil/trace.go:171","msg":"trace[1667107169] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"352.215231ms","start":"2026-02-20T21:39:04.60754Z","end":"2026-02-20T21:39:04.959755Z","steps":["trace[1667107169] 'process raft request' (duration: 351.889572ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:39:04.959912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.200008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-20T21:39:04.959946Z","caller":"traceutil/trace.go:171","msg":"trace[383387402] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:530; }","duration":"194.27852ms","start":"2026-02-20T21:39:04.765658Z","end":"2026-02-20T21:39:04.959936Z","steps":["trace[383387402] 'agreement among raft nodes before linearized reading' (duration: 194.096665ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:39:04.959996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:39:04.607511Z","time spent":"352.394516ms","remote":"127.0.0.1:52196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T21:39:04.960043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.614473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-20T21:39:04.960169Z","caller":"traceutil/trace.go:171","msg":"trace[2057718750] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:530; }","duration":"198.825148ms","start":"2026-02-20T21:39:04.761316Z","end":"2026-02-20T21:39:04.960141Z","steps":["trace[2057718750] 'agreement among raft nodes before linearized reading' (duration: 198.350956ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:39:05.216723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.211194ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-20T21:39:05.216935Z","caller":"traceutil/trace.go:171","msg":"trace[1714605554] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"252.15692ms","start":"2026-02-20T21:39:04.964747Z","end":"2026-02-20T21:39:05.216903Z","steps":["trace[1714605554] 'process raft request' (duration: 61.65613ms)","trace[1714605554] 'compare' (duration: 190.068189ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T21:39:06.620129Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920879101096216727,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T21:39:07.436366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"975.884096ms","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-20T21:39:07.43649Z","caller":"traceutil/trace.go:171","msg":"trace[863010734] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:532; }","duration":"976.022069ms","start":"2026-02-20T21:39:06.460453Z","end":"2026-02-20T21:39:07.436475Z","steps":["trace[863010734] 'range keys from in-memory index tree' (duration: 975.861934ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:39:07.436645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.824136539s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-20T21:39:07.436715Z","caller":"traceutil/trace.go:171","msg":"trace[1990543656] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:532; }","duration":"1.824247772s","start":"2026-02-20T21:39:05.612443Z","end":"2026-02-20T21:39:07.43669Z","steps":["trace[1990543656] 'range keys from in-memory index tree' (duration: 1.823937374s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:39:07.436752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:39:05.612411Z","time spent":"1.824333064s","remote":"127.0.0.1:52292","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":"warn","ts":"2026-02-20T21:39:07.525419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"875.747461ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:26"} {"level":"info","ts":"2026-02-20T21:39:07.525558Z","caller":"traceutil/trace.go:171","msg":"trace[940838968] linearizableReadLoop","detail":"{readStateIndex:552; appliedIndex:551; }","duration":"1.405848924s","start":"2026-02-20T21:39:06.119664Z","end":"2026-02-20T21:39:07.525513Z","steps":["trace[940838968] 'read index received' (duration: 530.063962ms)","trace[940838968] 'applied index is now lower than readState.Index' (duration: 875.783612ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T21:39:07.52567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.406008218s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T21:39:07.525706Z","caller":"traceutil/trace.go:171","msg":"trace[780197664] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:532; }","duration":"1.40608964s","start":"2026-02-20T21:39:06.119607Z","end":"2026-02-20T21:39:07.525696Z","steps":["trace[780197664] 'agreement among raft nodes before linearized reading' (duration: 1.406020148s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:39:07.525737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:39:06.119586Z","time spent":"1.406144652s","remote":"127.0.0.1:52002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-20T21:39:07.567048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"969.060037ms","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-20T21:39:07.56711Z","caller":"traceutil/trace.go:171","msg":"trace[922485245] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:533; }","duration":"969.16361ms","start":"2026-02-20T21:39:06.597933Z","end":"2026-02-20T21:39:07.567096Z","steps":["trace[922485245] 'agreement among raft nodes before linearized reading' (duration: 969.007586ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:39:07.567157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:39:06.597908Z","time spent":"969.240151ms","remote":"127.0.0.1:52292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-20T21:39:07.56717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"577.764536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-20T21:39:07.567262Z","caller":"traceutil/trace.go:171","msg":"trace[999036642] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:533; }","duration":"577.90843ms","start":"2026-02-20T21:39:06.989336Z","end":"2026-02-20T21:39:07.567244Z","steps":["trace[999036642] 'agreement among raft nodes before linearized reading' (duration: 577.654323ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:39:07.567323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:39:06.989315Z","time spent":"577.989841ms","remote":"127.0.0.1:52292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-20T21:39:07.567737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.273651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-20T21:39:07.56785Z","caller":"traceutil/trace.go:171","msg":"trace[884129631] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:533; }","duration":"347.400385ms","start":"2026-02-20T21:39:07.220397Z","end":"2026-02-20T21:39:07.567797Z","steps":["trace[884129631] 'agreement among raft nodes before linearized reading' (duration: 347.174688ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:39:07.567895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:39:07.220374Z","time spent":"347.511048ms","remote":"127.0.0.1:52292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-20T21:39:07.568167Z","caller":"traceutil/trace.go:171","msg":"trace[609959290] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"127.392243ms","start":"2026-02-20T21:39:07.439523Z","end":"2026-02-20T21:39:07.566915Z","steps":["trace[609959290] 'process raft request' (duration: 127.174708ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T21:39:09.877275Z","caller":"traceutil/trace.go:171","msg":"trace[1746573104] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"234.287764ms","start":"2026-02-20T21:39:09.642958Z","end":"2026-02-20T21:39:09.877246Z","steps":["trace[1746573104] 'process raft request' (duration: 234.102729ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T21:39:19.334354Z","caller":"traceutil/trace.go:171","msg":"trace[2006249056] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"101.944791ms","start":"2026-02-20T21:39:19.232388Z","end":"2026-02-20T21:39:19.334333Z","steps":["trace[2006249056] 'process raft request' (duration: 101.778577ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T21:39:19.528974Z","caller":"traceutil/trace.go:171","msg":"trace[72047179] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"295.058922ms","start":"2026-02-20T21:39:19.233879Z","end":"2026-02-20T21:39:19.528938Z","steps":["trace[72047179] 'process raft request' (duration: 202.10122ms)","trace[72047179] 'compare' (duration: 92.794978ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T21:39:20.078737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.900146ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-20T21:39:20.07891Z","caller":"traceutil/trace.go:171","msg":"trace[1390713734] linearizableReadLoop","detail":"{readStateIndex:683; appliedIndex:681; }","duration":"682.285039ms","start":"2026-02-20T21:39:19.396612Z","end":"2026-02-20T21:39:20.078897Z","steps":["trace[1390713734] 'read index received' (duration: 39.448394ms)","trace[1390713734] 'applied index is now lower than readState.Index' (duration: 642.835795ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T21:39:20.07898Z","caller":"traceutil/trace.go:171","msg":"trace[1205937778] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"737.560218ms","start":"2026-02-20T21:39:19.341411Z","end":"2026-02-20T21:39:20.078971Z","steps":["trace[1205937778] 'process raft request' (duration: 376.344594ms)","trace[1205937778] 'compare' (duration: 360.750063ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T21:39:20.079023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:39:19.341393Z","time spent":"737.60522ms","remote":"127.0.0.1:52190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4440,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T21:39:20.079157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"682.525864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T21:39:20.07922Z","caller":"traceutil/trace.go:171","msg":"trace[786941693] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:660; }","duration":"682.622697ms","start":"2026-02-20T21:39:19.396583Z","end":"2026-02-20T21:39:20.079205Z","steps":["trace[786941693] 'agreement among raft nodes before linearized reading' (duration: 682.472733ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:39:20.079255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:39:19.396565Z","time spent":"682.685168ms","remote":"127.0.0.1:52002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-20T21:39:20.079279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.676053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-20T21:39:20.079308Z","caller":"traceutil/trace.go:171","msg":"trace[1262951045] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:660; }","duration":"405.714225ms","start":"2026-02-20T21:39:19.673586Z","end":"2026-02-20T21:39:20.0793Z","steps":["trace[1262951045] 'agreement among raft nodes before linearized reading' (duration: 405.655313ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:39:20.079328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.966126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-20T21:39:20.079332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:39:19.673578Z","time spent":"405.749275ms","remote":"127.0.0.1:52292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-20T21:39:20.079324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.511654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-20T21:39:20.079356Z","caller":"traceutil/trace.go:171","msg":"trace[88452454] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:660; }","duration":"452.030647ms","start":"2026-02-20T21:39:19.627317Z","end":"2026-02-20T21:39:20.079348Z","steps":["trace[88452454] 'agreement among raft nodes before linearized reading' (duration: 451.925474ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:39:20.079378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:39:19.627298Z","time spent":"452.075768ms","remote":"127.0.0.1:52292","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-20T21:39:20.079383Z","caller":"traceutil/trace.go:171","msg":"trace[293094098] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:660; }","duration":"400.616066ms","start":"2026-02-20T21:39:19.678752Z","end":"2026-02-20T21:39:20.079368Z","steps":["trace[293094098] 'agreement among raft nodes before linearized reading' (duration: 400.437822ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T21:39:20.079402Z","caller":"traceutil/trace.go:171","msg":"trace[789933377] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"675.088991ms","start":"2026-02-20T21:39:19.404303Z","end":"2026-02-20T21:39:20.079392Z","steps":["trace[789933377] 'process raft request' (duration: 674.527648ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:39:20.079417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:39:19.678735Z","time spent":"400.674777ms","remote":"127.0.0.1:52292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-20T21:39:20.07922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"591.807944ms","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-20T21:39:20.079446Z","caller":"traceutil/trace.go:171","msg":"trace[216191617] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:660; }","duration":"592.07926ms","start":"2026-02-20T21:39:19.48736Z","end":"2026-02-20T21:39:20.079439Z","steps":["trace[216191617] 'agreement among raft nodes before linearized reading' (duration: 591.733432ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:39:20.079464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:39:19.404285Z","time spent":"675.141113ms","remote":"127.0.0.1:52094","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":780,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-20T21:39:20.07947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:39:19.487339Z","time spent":"592.125091ms","remote":"127.0.0.1:52292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-20T21:39:20.079579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.827648ms","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-20T21:39:20.079659Z","caller":"traceutil/trace.go:171","msg":"trace[1053495932] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:660; }","duration":"399.955571ms","start":"2026-02-20T21:39:19.679673Z","end":"2026-02-20T21:39:20.079628Z","steps":["trace[1053495932] 'agreement among raft nodes before linearized reading' (duration: 399.736906ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:39:20.079843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:39:19.679653Z","time spent":"400.170086ms","remote":"127.0.0.1:52292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-20T21:39:20.69661Z","caller":"traceutil/trace.go:171","msg":"trace[1464399991] transaction","detail":"{read_only:false; response_revision:672; number_of_response:1; }","duration":"121.324809ms","start":"2026-02-20T21:39:20.575223Z","end":"2026-02-20T21:39:20.696548Z","steps":["trace[1464399991] 'process raft request' (duration: 121.179045ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T21:39:20.994389Z","caller":"traceutil/trace.go:171","msg":"trace[1404598916] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"134.537245ms","start":"2026-02-20T21:39:20.859821Z","end":"2026-02-20T21:39:20.994358Z","steps":["trace[1404598916] 'process raft request' (duration: 120.685484ms)","trace[1404598916] 'compare' (duration: 13.739438ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T21:39:21.139295Z","caller":"traceutil/trace.go:171","msg":"trace[838350797] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"274.843084ms","start":"2026-02-20T21:39:20.864436Z","end":"2026-02-20T21:39:21.139279Z","steps":["trace[838350797] 'process raft request' (duration: 274.798993ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T21:39:21.139354Z","caller":"traceutil/trace.go:171","msg":"trace[1176328954] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"275.477808ms","start":"2026-02-20T21:39:20.863866Z","end":"2026-02-20T21:39:21.139343Z","steps":["trace[1176328954] 'process raft request' (duration: 275.330155ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T21:39:21.139293Z","caller":"traceutil/trace.go:171","msg":"trace[1209924540] transaction","detail":"{read_only:false; response_revision:680; number_of_response:1; }","duration":"279.149864ms","start":"2026-02-20T21:39:20.860118Z","end":"2026-02-20T21:39:21.139268Z","steps":["trace[1209924540] 'process raft request' (duration: 276.479452ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T21:39:21.200606Z","caller":"traceutil/trace.go:171","msg":"trace[656305127] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"100.003835ms","start":"2026-02-20T21:39:21.100571Z","end":"2026-02-20T21:39:21.200575Z","steps":["trace[656305127] 'process raft request' (duration: 99.697648ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T21:39:21.325828Z","caller":"traceutil/trace.go:171","msg":"trace[2124428658] linearizableReadLoop","detail":"{readStateIndex:712; appliedIndex:708; }","duration":"125.437525ms","start":"2026-02-20T21:39:21.200378Z","end":"2026-02-20T21:39:21.325815Z","steps":["trace[2124428658] 'read index received' (duration: 125.272181ms)","trace[2124428658] 'applied index is now lower than readState.Index' (duration: 164.614µs)"],"step_count":2} {"level":"info","ts":"2026-02-20T21:39:21.325879Z","caller":"traceutil/trace.go:171","msg":"trace[2080471235] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"179.972118ms","start":"2026-02-20T21:39:21.145883Z","end":"2026-02-20T21:39:21.325855Z","steps":["trace[2080471235] 'process raft request' (duration: 179.868095ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:39:21.325925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.557365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T21:39:21.32595Z","caller":"traceutil/trace.go:171","msg":"trace[1145146378] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:687; }","duration":"124.617207ms","start":"2026-02-20T21:39:21.201325Z","end":"2026-02-20T21:39:21.325942Z","steps":["trace[1145146378] 'agreement among raft nodes before linearized reading' (duration: 124.567915ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T21:39:21.326202Z","caller":"traceutil/trace.go:171","msg":"trace[835587466] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"180.490759ms","start":"2026-02-20T21:39:21.145703Z","end":"2026-02-20T21:39:21.326193Z","steps":["trace[835587466] 'process raft request' (duration: 180.011888ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:39:21.326215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.246373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-77cccfdc44-6sc8z\" ","response":"range_response_count:1 size:5026"} {"level":"info","ts":"2026-02-20T21:39:21.326237Z","caller":"traceutil/trace.go:171","msg":"trace[831162584] range","detail":"{range_begin:/registry/pods/kube-system/coredns-77cccfdc44-6sc8z; range_end:; response_count:1; response_revision:687; }","duration":"183.326015ms","start":"2026-02-20T21:39:21.142904Z","end":"2026-02-20T21:39:21.32623Z","steps":["trace[831162584] 'agreement among raft nodes before linearized reading' (duration: 183.218143ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T21:39:21.326319Z","caller":"traceutil/trace.go:171","msg":"trace[1639355135] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"179.704381ms","start":"2026-02-20T21:39:21.14661Z","end":"2026-02-20T21:39:21.326315Z","steps":["trace[1639355135] 'process raft request' (duration: 179.173369ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T21:39:21.326256Z","caller":"traceutil/trace.go:171","msg":"trace[1720406602] transaction","detail":"{read_only:false; response_revision:684; number_of_response:1; }","duration":"181.284748ms","start":"2026-02-20T21:39:21.144925Z","end":"2026-02-20T21:39:21.32621Z","steps":["trace[1720406602] 'process raft request' (duration: 180.721115ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T21:39:21.551278Z","caller":"traceutil/trace.go:171","msg":"trace[2134955731] transaction","detail":"{read_only:false; response_revision:689; number_of_response:1; }","duration":"217.093336ms","start":"2026-02-20T21:39:21.334164Z","end":"2026-02-20T21:39:21.551257Z","steps":["trace[2134955731] 'process raft request' (duration: 216.953943ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T21:39:21.551372Z","caller":"traceutil/trace.go:171","msg":"trace[1839753279] linearizableReadLoop","detail":"{readStateIndex:714; appliedIndex:714; }","duration":"166.473544ms","start":"2026-02-20T21:39:21.384867Z","end":"2026-02-20T21:39:21.55134Z","steps":["trace[1839753279] 'read index received' (duration: 166.418043ms)","trace[1839753279] 'applied index is now lower than readState.Index' (duration: 53.061µs)"],"step_count":2} {"level":"warn","ts":"2026-02-20T21:39:21.551581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.686929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-qfq7b\" ","response":"range_response_count:1 size:4809"} {"level":"info","ts":"2026-02-20T21:39:21.551659Z","caller":"traceutil/trace.go:171","msg":"trace[624878573] range","detail":"{range_begin:/registry/pods/kube-system/coredns-5dd5756b68-qfq7b; range_end:; response_count:1; response_revision:689; }","duration":"166.799412ms","start":"2026-02-20T21:39:21.384845Z","end":"2026-02-20T21:39:21.551644Z","steps":["trace[624878573] 'agreement among raft nodes before linearized reading' (duration: 166.586437ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T21:39:21.611291Z","caller":"traceutil/trace.go:171","msg":"trace[1568630737] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"223.26955ms","start":"2026-02-20T21:39:21.388005Z","end":"2026-02-20T21:39:21.611275Z","steps":["trace[1568630737] 'process raft request' (duration: 223.225479ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T21:39:21.611326Z","caller":"traceutil/trace.go:171","msg":"trace[1921322876] transaction","detail":"{read_only:false; response_revision:690; number_of_response:1; }","duration":"226.26919ms","start":"2026-02-20T21:39:21.385034Z","end":"2026-02-20T21:39:21.611303Z","steps":["trace[1921322876] 'process raft request' (duration: 226.109866ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T21:39:21.783112Z","caller":"traceutil/trace.go:171","msg":"trace[1041687495] transaction","detail":"{read_only:false; response_revision:692; number_of_response:1; }","duration":"230.069557ms","start":"2026-02-20T21:39:21.553024Z","end":"2026-02-20T21:39:21.783094Z","steps":["trace[1041687495] 'process raft request' (duration: 228.308676ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T21:39:21.783213Z","caller":"traceutil/trace.go:171","msg":"trace[891113161] transaction","detail":"{read_only:false; response_revision:695; number_of_response:1; }","duration":"224.163461ms","start":"2026-02-20T21:39:21.559029Z","end":"2026-02-20T21:39:21.783193Z","steps":["trace[891113161] 'process raft request' (duration: 224.097839ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T21:39:21.783517Z","caller":"traceutil/trace.go:171","msg":"trace[1077387072] transaction","detail":"{read_only:false; response_revision:694; number_of_response:1; }","duration":"225.421809ms","start":"2026-02-20T21:39:21.558084Z","end":"2026-02-20T21:39:21.783505Z","steps":["trace[1077387072] 'process raft request' (duration: 224.963289ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T21:39:21.783629Z","caller":"traceutil/trace.go:171","msg":"trace[527201185] transaction","detail":"{read_only:false; response_revision:693; number_of_response:1; }","duration":"226.72747ms","start":"2026-02-20T21:39:21.556891Z","end":"2026-02-20T21:39:21.783619Z","steps":["trace[527201185] 'process raft request' (duration: 226.062064ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T21:39:21.839074Z","caller":"traceutil/trace.go:171","msg":"trace[1956563317] transaction","detail":"{read_only:false; response_revision:696; number_of_response:1; }","duration":"219.686717ms","start":"2026-02-20T21:39:21.619354Z","end":"2026-02-20T21:39:21.839041Z","steps":["trace[1956563317] 'process raft request' (duration: 219.504313ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T21:39:21.900707Z","caller":"traceutil/trace.go:171","msg":"trace[13640138] transaction","detail":"{read_only:false; response_revision:698; number_of_response:1; }","duration":"111.401489ms","start":"2026-02-20T21:39:21.789284Z","end":"2026-02-20T21:39:21.900685Z","steps":["trace[13640138] 'process raft request' (duration: 111.157714ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T21:39:21.900718Z","caller":"traceutil/trace.go:171","msg":"trace[1759192580] transaction","detail":"{read_only:false; response_revision:697; number_of_response:1; }","duration":"111.732208ms","start":"2026-02-20T21:39:21.788966Z","end":"2026-02-20T21:39:21.900698Z","steps":["trace[1759192580] 'process raft request' (duration: 110.254943ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T21:39:21.900944Z","caller":"traceutil/trace.go:171","msg":"trace[1417735337] transaction","detail":"{read_only:false; response_revision:699; number_of_response:1; }","duration":"110.620712ms","start":"2026-02-20T21:39:21.790306Z","end":"2026-02-20T21:39:21.900927Z","steps":["trace[1417735337] 'process raft request' (duration: 110.198202ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T21:39:21.901206Z","caller":"traceutil/trace.go:171","msg":"trace[754404308] transaction","detail":"{read_only:false; response_revision:700; number_of_response:1; }","duration":"107.723515ms","start":"2026-02-20T21:39:21.79288Z","end":"2026-02-20T21:39:21.900603Z","steps":["trace[754404308] 'process raft request' (duration: 107.668884ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T21:39:22.267565Z","caller":"traceutil/trace.go:171","msg":"trace[275523941] transaction","detail":"{read_only:false; response_revision:704; number_of_response:1; }","duration":"150.653979ms","start":"2026-02-20T21:39:22.116884Z","end":"2026-02-20T21:39:22.267538Z","steps":["trace[275523941] 'process raft request' (duration: 150.525596ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T21:39:22.342296Z","caller":"traceutil/trace.go:171","msg":"trace[1094012327] transaction","detail":"{read_only:false; response_revision:705; number_of_response:1; }","duration":"225.061862ms","start":"2026-02-20T21:39:22.117192Z","end":"2026-02-20T21:39:22.342254Z","steps":["trace[1094012327] 'process raft request' (duration: 203.422791ms)","trace[1094012327] 'compare' (duration: 21.289542ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T21:39:22.342373Z","caller":"traceutil/trace.go:171","msg":"trace[2145864415] transaction","detail":"{read_only:false; response_revision:706; number_of_response:1; }","duration":"225.058402ms","start":"2026-02-20T21:39:22.117295Z","end":"2026-02-20T21:39:22.342353Z","steps":["trace[2145864415] 'process raft request' (duration: 224.847227ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T21:39:22.342429Z","caller":"traceutil/trace.go:171","msg":"trace[1446930679] transaction","detail":"{read_only:false; response_revision:707; number_of_response:1; }","duration":"224.653493ms","start":"2026-02-20T21:39:22.117751Z","end":"2026-02-20T21:39:22.342405Z","steps":["trace[1446930679] 'process raft request' (duration: 224.473398ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T21:39:22.342498Z","caller":"traceutil/trace.go:171","msg":"trace[1581223031] transaction","detail":"{read_only:false; response_revision:708; number_of_response:1; }","duration":"223.841183ms","start":"2026-02-20T21:39:22.118647Z","end":"2026-02-20T21:39:22.342488Z","steps":["trace[1581223031] 'process raft request' (duration: 223.667639ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T21:39:22.342414Z","caller":"traceutil/trace.go:171","msg":"trace[1242607404] linearizableReadLoop","detail":"{readStateIndex:732; appliedIndex:728; }","duration":"223.824953ms","start":"2026-02-20T21:39:22.118563Z","end":"2026-02-20T21:39:22.342387Z","steps":["trace[1242607404] 'read index received' (duration: 148.889548ms)","trace[1242607404] 'applied index is now lower than readState.Index' (duration: 74.933805ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T21:39:22.342749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.155791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T21:39:22.342833Z","caller":"traceutil/trace.go:171","msg":"trace[1461916125] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:708; }","duration":"224.255363ms","start":"2026-02-20T21:39:22.11856Z","end":"2026-02-20T21:39:22.342815Z","steps":["trace[1461916125] 'agreement among raft nodes before linearized reading' (duration: 224.101499ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:39:26.620391Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920879101096217063,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T21:39:27.949573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.010507329s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:26"} {"level":"info","ts":"2026-02-20T21:39:27.949683Z","caller":"traceutil/trace.go:171","msg":"trace[1509161326] linearizableReadLoop","detail":"{readStateIndex:742; appliedIndex:741; }","duration":"1.830246349s","start":"2026-02-20T21:39:26.119419Z","end":"2026-02-20T21:39:27.949665Z","steps":["trace[1509161326] 'read index received' (duration: 819.536046ms)","trace[1509161326] 'applied index is now lower than readState.Index' (duration: 1.010708462s)"],"step_count":2} {"level":"warn","ts":"2026-02-20T21:39:27.949844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.830400462s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T21:39:27.949872Z","caller":"traceutil/trace.go:171","msg":"trace[425563913] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:716; }","duration":"1.830477194s","start":"2026-02-20T21:39:26.119385Z","end":"2026-02-20T21:39:27.949862Z","steps":["trace[425563913] 'agreement among raft nodes before linearized reading' (duration: 1.830399542s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:39:27.949908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:39:26.119369Z","time spent":"1.830530685s","remote":"127.0.0.1:52002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-20T21:39:27.950036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.671171504s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-20T21:39:27.950091Z","caller":"traceutil/trace.go:171","msg":"trace[2070090077] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:716; }","duration":"1.671235187s","start":"2026-02-20T21:39:26.278842Z","end":"2026-02-20T21:39:27.950077Z","steps":["trace[2070090077] 'agreement among raft nodes before linearized reading' (duration: 1.671074083s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:39:27.950165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:39:26.278801Z","time spent":"1.671311448s","remote":"127.0.0.1:52292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-20T21:39:27.950141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.593767944s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-20T21:39:27.950208Z","caller":"traceutil/trace.go:171","msg":"trace[1318198220] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:716; }","duration":"1.593896026s","start":"2026-02-20T21:39:26.356293Z","end":"2026-02-20T21:39:27.950189Z","steps":["trace[1318198220] 'agreement among raft nodes before linearized reading' (duration: 1.593685742s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:39:27.950246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:39:26.35625Z","time spent":"1.593988359s","remote":"127.0.0.1:52292","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":"warn","ts":"2026-02-20T21:39:27.950233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.593873736s","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-20T21:39:27.950261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.594018739s","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-20T21:39:27.950303Z","caller":"traceutil/trace.go:171","msg":"trace[132750678] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:716; }","duration":"1.594001879s","start":"2026-02-20T21:39:26.356283Z","end":"2026-02-20T21:39:27.950285Z","steps":["trace[132750678] 'agreement among raft nodes before linearized reading' (duration: 1.593754493s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:39:27.950292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.592503954s","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-20T21:39:27.950344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:39:26.356247Z","time spent":"1.594088981s","remote":"127.0.0.1:52292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-20T21:39:27.9504Z","caller":"traceutil/trace.go:171","msg":"trace[536045274] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:716; }","duration":"1.592647177s","start":"2026-02-20T21:39:26.357727Z","end":"2026-02-20T21:39:27.950374Z","steps":["trace[536045274] 'agreement among raft nodes before linearized reading' (duration: 1.592266978s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:39:27.950449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:39:26.357716Z","time spent":"1.592723468s","remote":"127.0.0.1:52292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-20T21:39:27.950332Z","caller":"traceutil/trace.go:171","msg":"trace[263468117] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:716; }","duration":"1.594120091s","start":"2026-02-20T21:39:26.356194Z","end":"2026-02-20T21:39:27.950314Z","steps":["trace[263468117] 'agreement among raft nodes before linearized reading' (duration: 1.593873336s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:39:27.950518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:39:26.356181Z","time spent":"1.594326426s","remote":"127.0.0.1:52292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-20T21:39:30.230317Z","caller":"traceutil/trace.go:171","msg":"trace[521659630] linearizableReadLoop","detail":"{readStateIndex:748; appliedIndex:747; }","duration":"242.210677ms","start":"2026-02-20T21:39:29.988076Z","end":"2026-02-20T21:39:30.230287Z","steps":["trace[521659630] 'read index received' (duration: 242.029693ms)","trace[521659630] 'applied index is now lower than readState.Index' (duration: 179.544µs)"],"step_count":2} {"level":"info","ts":"2026-02-20T21:39:30.230516Z","caller":"traceutil/trace.go:171","msg":"trace[681444694] transaction","detail":"{read_only:false; response_revision:722; number_of_response:1; }","duration":"244.847299ms","start":"2026-02-20T21:39:29.985627Z","end":"2026-02-20T21:39:30.230474Z","steps":["trace[681444694] 'process raft request' (duration: 244.45904ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:39:30.231293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.878251ms","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-20T21:39:30.231314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.200291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-20T21:39:30.231343Z","caller":"traceutil/trace.go:171","msg":"trace[1771565761] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:722; }","duration":"241.998303ms","start":"2026-02-20T21:39:29.989333Z","end":"2026-02-20T21:39:30.231331Z","steps":["trace[1771565761] 'agreement among raft nodes before linearized reading' (duration: 241.808409ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:39:30.231312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.392892ms","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-20T21:39:30.231411Z","caller":"traceutil/trace.go:171","msg":"trace[1643177481] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:722; }","duration":"242.520796ms","start":"2026-02-20T21:39:29.988869Z","end":"2026-02-20T21:39:30.231389Z","steps":["trace[1643177481] 'agreement among raft nodes before linearized reading' (duration: 242.198128ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T21:39:30.231351Z","caller":"traceutil/trace.go:171","msg":"trace[1041764685] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:722; }","duration":"243.302844ms","start":"2026-02-20T21:39:29.988036Z","end":"2026-02-20T21:39:30.231339Z","steps":["trace[1041764685] 'agreement among raft nodes before linearized reading' (duration: 242.415052ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:39:30.231548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.483284ms","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-20T21:39:30.231575Z","caller":"traceutil/trace.go:171","msg":"trace[701385592] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:722; }","duration":"242.548966ms","start":"2026-02-20T21:39:29.989017Z","end":"2026-02-20T21:39:30.231566Z","steps":["trace[701385592] 'agreement among raft nodes before linearized reading' (duration: 242.472924ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T21:39:30.375871Z","caller":"traceutil/trace.go:171","msg":"trace[1789748617] transaction","detail":"{read_only:false; response_revision:724; number_of_response:1; }","duration":"139.166994ms","start":"2026-02-20T21:39:30.236679Z","end":"2026-02-20T21:39:30.375846Z","steps":["trace[1789748617] 'process raft request' (duration: 139.059862ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T21:39:30.37615Z","caller":"traceutil/trace.go:171","msg":"trace[4260712] transaction","detail":"{read_only:false; response_revision:723; number_of_response:1; }","duration":"139.590534ms","start":"2026-02-20T21:39:30.236513Z","end":"2026-02-20T21:39:30.376103Z","steps":["trace[4260712] 'process raft request' (duration: 137.387913ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T21:39:30.376218Z","caller":"traceutil/trace.go:171","msg":"trace[926686035] transaction","detail":"{read_only:false; response_revision:725; number_of_response:1; }","duration":"139.245916ms","start":"2026-02-20T21:39:30.236942Z","end":"2026-02-20T21:39:30.376188Z","steps":["trace[926686035] 'process raft request' (duration: 138.870637ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T21:39:30.376515Z","caller":"traceutil/trace.go:171","msg":"trace[1912718713] transaction","detail":"{read_only:false; response_revision:726; number_of_response:1; }","duration":"138.687693ms","start":"2026-02-20T21:39:30.237791Z","end":"2026-02-20T21:39:30.376479Z","steps":["trace[1912718713] 'process raft request' (duration: 138.213382ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:39:51.073689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.503623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-20T21:39:51.073833Z","caller":"traceutil/trace.go:171","msg":"trace[1638120877] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:789; }","duration":"100.701817ms","start":"2026-02-20T21:39:50.973092Z","end":"2026-02-20T21:39:51.073794Z","steps":["trace[1638120877] 'count revisions from in-memory index tree' (duration: 100.417731ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T21:39:52.424539Z","caller":"traceutil/trace.go:171","msg":"trace[1211910679] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"399.081191ms","start":"2026-02-20T21:39:52.025393Z","end":"2026-02-20T21:39:52.424475Z","steps":["trace[1211910679] 'process raft request' (duration: 398.672361ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:39:52.424831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:39:52.025365Z","time spent":"399.357688ms","remote":"127.0.0.1:52292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T21:39:54.836065Z","caller":"traceutil/trace.go:171","msg":"trace[354076911] linearizableReadLoop","detail":"{readStateIndex:828; appliedIndex:827; }","duration":"327.00055ms","start":"2026-02-20T21:39:54.509029Z","end":"2026-02-20T21:39:54.83603Z","steps":["trace[354076911] 'read index received' (duration: 326.753644ms)","trace[354076911] 'applied index is now lower than readState.Index' (duration: 245.266µs)"],"step_count":2} {"level":"info","ts":"2026-02-20T21:39:54.836191Z","caller":"traceutil/trace.go:171","msg":"trace[813344048] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"382.110691ms","start":"2026-02-20T21:39:54.454031Z","end":"2026-02-20T21:39:54.836142Z","steps":["trace[813344048] 'process raft request' (duration: 381.784734ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:39:54.8364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.348407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-20T21:39:54.836411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:39:54.453996Z","time spent":"382.278254ms","remote":"127.0.0.1:52292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T21:39:54.836457Z","caller":"traceutil/trace.go:171","msg":"trace[1742056253] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:796; }","duration":"327.492491ms","start":"2026-02-20T21:39:54.508947Z","end":"2026-02-20T21:39:54.83644Z","steps":["trace[1742056253] 'agreement among raft nodes before linearized reading' (duration: 327.251155ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:39:54.8365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:39:54.508915Z","time spent":"327.574942ms","remote":"127.0.0.1:52292","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-20T21:39:54.885731Z","caller":"traceutil/trace.go:171","msg":"trace[287726904] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"226.010934ms","start":"2026-02-20T21:39:54.659697Z","end":"2026-02-20T21:39:54.885708Z","steps":["trace[287726904] 'process raft request' (duration: 198.371873ms)","trace[287726904] 'compare' (duration: 27.412376ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T21:39:54.885854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.977504ms","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-20T21:39:54.885938Z","caller":"traceutil/trace.go:171","msg":"trace[569361368] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:797; }","duration":"345.099877ms","start":"2026-02-20T21:39:54.540819Z","end":"2026-02-20T21:39:54.885919Z","steps":["trace[569361368] 'agreement among raft nodes before linearized reading' (duration: 344.84496ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:39:54.885981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:39:54.540805Z","time spent":"345.167268ms","remote":"127.0.0.1:52292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-20T21:39:54.886185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.681895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-20T21:39:54.886225Z","caller":"traceutil/trace.go:171","msg":"trace[521386676] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:797; }","duration":"316.783828ms","start":"2026-02-20T21:39:54.56943Z","end":"2026-02-20T21:39:54.886214Z","steps":["trace[521386676] 'agreement among raft nodes before linearized reading' (duration: 316.651195ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:39:54.886248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:39:54.569406Z","time spent":"316.836359ms","remote":"127.0.0.1:52292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-20T21:39:54.88631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.834089ms","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-20T21:39:54.886376Z","caller":"traceutil/trace.go:171","msg":"trace[1229791003] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:797; }","duration":"316.926761ms","start":"2026-02-20T21:39:54.569432Z","end":"2026-02-20T21:39:54.886359Z","steps":["trace[1229791003] 'agreement among raft nodes before linearized reading' (duration: 316.728557ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:39:54.88641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:39:54.569414Z","time spent":"316.988242ms","remote":"127.0.0.1:52292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-20T21:40:05.240552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.826643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T21:40:05.240631Z","caller":"traceutil/trace.go:171","msg":"trace[1672775694] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:879; }","duration":"159.959846ms","start":"2026-02-20T21:40:05.080654Z","end":"2026-02-20T21:40:05.240614Z","steps":["trace[1672775694] 'count revisions from in-memory index tree' (duration: 159.74034ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T21:40:05.433301Z","caller":"traceutil/trace.go:171","msg":"trace[1924110972] transaction","detail":"{read_only:false; response_revision:880; number_of_response:1; }","duration":"232.507378ms","start":"2026-02-20T21:40:05.20076Z","end":"2026-02-20T21:40:05.433267Z","steps":["trace[1924110972] 'process raft request' (duration: 232.203131ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T21:40:21.427179Z","caller":"traceutil/trace.go:171","msg":"trace[12881610] transaction","detail":"{read_only:false; response_revision:921; number_of_response:1; }","duration":"205.544621ms","start":"2026-02-20T21:40:21.22161Z","end":"2026-02-20T21:40:21.427154Z","steps":["trace[12881610] 'process raft request' (duration: 205.410748ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:40:21.633345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.469269ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-20T21:40:21.633676Z","caller":"traceutil/trace.go:171","msg":"trace[1961803015] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"409.066086ms","start":"2026-02-20T21:40:21.224586Z","end":"2026-02-20T21:40:21.633652Z","steps":["trace[1961803015] 'process raft request' (duration: 409.013665ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T21:40:21.63364Z","caller":"traceutil/trace.go:171","msg":"trace[343790060] transaction","detail":"{read_only:false; response_revision:922; number_of_response:1; }","duration":"411.614466ms","start":"2026-02-20T21:40:21.221997Z","end":"2026-02-20T21:40:21.633612Z","steps":["trace[343790060] 'process raft request' (duration: 258.781838ms)","trace[343790060] 'compare' (duration: 152.340646ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T21:40:21.633798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:40:21.224576Z","time spent":"409.151858ms","remote":"127.0.0.1:52292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T21:40:21.633939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:40:21.221982Z","time spent":"411.79271ms","remote":"127.0.0.1:52292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T21:40:21.634144Z","caller":"traceutil/trace.go:171","msg":"trace[1223802175] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"411.896583ms","start":"2026-02-20T21:40:21.222229Z","end":"2026-02-20T21:40:21.634126Z","steps":["trace[1223802175] 'process raft request' (duration: 411.219468ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:40:21.634263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:40:21.222219Z","time spent":"411.983995ms","remote":"127.0.0.1:52292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T21:40:25.590499Z","caller":"traceutil/trace.go:171","msg":"trace[61547611] transaction","detail":"{read_only:false; response_revision:952; number_of_response:1; }","duration":"141.122951ms","start":"2026-02-20T21:40:25.449352Z","end":"2026-02-20T21:40:25.590475Z","steps":["trace[61547611] 'process raft request' (duration: 140.953337ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:40:25.901585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.35703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-20T21:40:25.901666Z","caller":"traceutil/trace.go:171","msg":"trace[791906442] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:952; }","duration":"250.477343ms","start":"2026-02-20T21:40:25.651165Z","end":"2026-02-20T21:40:25.901642Z","steps":["trace[791906442] 'range keys from in-memory index tree' (duration: 250.134624ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:40:25.901755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.575564ms","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-20T21:40:25.901781Z","caller":"traceutil/trace.go:171","msg":"trace[628144623] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:952; }","duration":"250.637456ms","start":"2026-02-20T21:40:25.651136Z","end":"2026-02-20T21:40:25.901774Z","steps":["trace[628144623] 'range keys from in-memory index tree' (duration: 250.161685ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:40:25.901851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.170093ms","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-20T21:40:25.901863Z","caller":"traceutil/trace.go:171","msg":"trace[2004762195] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:952; }","duration":"252.222984ms","start":"2026-02-20T21:40:25.649636Z","end":"2026-02-20T21:40:25.901859Z","steps":["trace[2004762195] 'range keys from in-memory index tree' (duration: 251.703832ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T21:40:26.032349Z","caller":"traceutil/trace.go:171","msg":"trace[524534322] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"114.878972ms","start":"2026-02-20T21:40:25.917453Z","end":"2026-02-20T21:40:26.032332Z","steps":["trace[524534322] 'process raft request' (duration: 114.852992ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T21:40:26.032636Z","caller":"traceutil/trace.go:171","msg":"trace[1041907199] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"124.256903ms","start":"2026-02-20T21:40:25.908374Z","end":"2026-02-20T21:40:26.032631Z","steps":["trace[1041907199] 'process raft request' (duration: 123.810393ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T21:40:26.0327Z","caller":"traceutil/trace.go:171","msg":"trace[933160276] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"122.84695ms","start":"2026-02-20T21:40:25.909849Z","end":"2026-02-20T21:40:26.032696Z","steps":["trace[933160276] 'process raft request' (duration: 122.4366ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:40:26.680366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"635.265246ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:26"} {"level":"info","ts":"2026-02-20T21:40:26.680472Z","caller":"traceutil/trace.go:171","msg":"trace[726943641] linearizableReadLoop","detail":"{readStateIndex:997; appliedIndex:996; }","duration":"560.991582ms","start":"2026-02-20T21:40:26.119462Z","end":"2026-02-20T21:40:26.680453Z","steps":["trace[726943641] 'read index received' (duration: 40.121µs)","trace[726943641] 'applied index is now lower than readState.Index' (duration: 560.949791ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T21:40:26.680495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.7328ms","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-20T21:40:26.680587Z","caller":"traceutil/trace.go:171","msg":"trace[1876287005] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:956; }","duration":"220.834913ms","start":"2026-02-20T21:40:26.459737Z","end":"2026-02-20T21:40:26.680572Z","steps":["trace[1876287005] 'range keys from in-memory index tree' (duration: 220.71844ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:40:26.680743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.814233ms","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-20T21:40:26.680763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"561.29798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T21:40:26.680807Z","caller":"traceutil/trace.go:171","msg":"trace[811404436] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:956; }","duration":"517.887705ms","start":"2026-02-20T21:40:26.162902Z","end":"2026-02-20T21:40:26.68079Z","steps":["trace[811404436] 'agreement among raft nodes before linearized reading' (duration: 517.784172ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T21:40:26.680828Z","caller":"traceutil/trace.go:171","msg":"trace[57972573] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:956; }","duration":"561.387912ms","start":"2026-02-20T21:40:26.11941Z","end":"2026-02-20T21:40:26.680798Z","steps":["trace[57972573] 'agreement among raft nodes before linearized reading' (duration: 561.30087ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:40:26.680837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:40:26.162883Z","time spent":"517.947007ms","remote":"127.0.0.1:52504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":26,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-02-20T21:40:26.680866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:40:26.119395Z","time spent":"561.462403ms","remote":"127.0.0.1:52002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-20T21:40:27.817428Z","caller":"traceutil/trace.go:171","msg":"trace[1914809192] linearizableReadLoop","detail":"{readStateIndex:998; appliedIndex:997; }","duration":"223.954266ms","start":"2026-02-20T21:40:27.59345Z","end":"2026-02-20T21:40:27.817404Z","steps":["trace[1914809192] 'read index received' (duration: 223.767022ms)","trace[1914809192] 'applied index is now lower than readState.Index' (duration: 186.044µs)"],"step_count":2} {"level":"warn","ts":"2026-02-20T21:40:27.817842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.360546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-20T21:40:27.817825Z","caller":"traceutil/trace.go:171","msg":"trace[773835559] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"530.84524ms","start":"2026-02-20T21:40:27.286952Z","end":"2026-02-20T21:40:27.817797Z","steps":["trace[773835559] 'process raft request' (duration: 530.316718ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:40:27.817925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.953626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-20T21:40:27.817986Z","caller":"traceutil/trace.go:171","msg":"trace[1159601893] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:957; }","duration":"224.037548ms","start":"2026-02-20T21:40:27.593931Z","end":"2026-02-20T21:40:27.817969Z","steps":["trace[1159601893] 'agreement among raft nodes before linearized reading' (duration: 223.826623ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:40:27.817985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:40:27.286925Z","time spent":"530.980114ms","remote":"127.0.0.1:52292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T21:40:27.817897Z","caller":"traceutil/trace.go:171","msg":"trace[1371583705] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:957; }","duration":"224.468998ms","start":"2026-02-20T21:40:27.593415Z","end":"2026-02-20T21:40:27.817884Z","steps":["trace[1371583705] 'agreement among raft nodes before linearized reading' (duration: 224.292164ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T21:40:28.026145Z","caller":"traceutil/trace.go:171","msg":"trace[797876558] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"203.791481ms","start":"2026-02-20T21:40:27.82227Z","end":"2026-02-20T21:40:28.026061Z","steps":["trace[797876558] 'process raft request' (duration: 193.051798ms)","trace[797876558] 'compare' (duration: 10.028706ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T21:40:28.220276Z","caller":"traceutil/trace.go:171","msg":"trace[135936453] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"161.228926ms","start":"2026-02-20T21:40:28.059013Z","end":"2026-02-20T21:40:28.220242Z","steps":["trace[135936453] 'process raft request' (duration: 69.818248ms)","trace[135936453] 'compare' (duration: 91.293396ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T21:40:28.302531Z","caller":"traceutil/trace.go:171","msg":"trace[268012513] linearizableReadLoop","detail":"{readStateIndex:1002; appliedIndex:1000; }","duration":"184.038215ms","start":"2026-02-20T21:40:28.118475Z","end":"2026-02-20T21:40:28.302513Z","steps":["trace[268012513] 'read index received' (duration: 10.372296ms)","trace[268012513] 'applied index is now lower than readState.Index' (duration: 173.663569ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T21:40:28.302612Z","caller":"traceutil/trace.go:171","msg":"trace[840594694] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"243.056787ms","start":"2026-02-20T21:40:28.05952Z","end":"2026-02-20T21:40:28.302577Z","steps":["trace[840594694] 'process raft request' (duration: 242.900444ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:40:28.302704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.293632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-20T21:40:28.302647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.165748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T21:40:28.302724Z","caller":"traceutil/trace.go:171","msg":"trace[147250638] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:961; }","duration":"151.333073ms","start":"2026-02-20T21:40:28.151383Z","end":"2026-02-20T21:40:28.302716Z","steps":["trace[147250638] 'agreement among raft nodes before linearized reading' (duration: 151.284331ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T21:40:28.302774Z","caller":"traceutil/trace.go:171","msg":"trace[137194863] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:961; }","duration":"184.314571ms","start":"2026-02-20T21:40:28.118433Z","end":"2026-02-20T21:40:28.302748Z","steps":["trace[137194863] 'agreement among raft nodes before linearized reading' (duration: 184.180708ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T21:40:30.393652Z","caller":"traceutil/trace.go:171","msg":"trace[115423184] linearizableReadLoop","detail":"{readStateIndex:1004; appliedIndex:1003; }","duration":"360.556571ms","start":"2026-02-20T21:40:30.033069Z","end":"2026-02-20T21:40:30.393626Z","steps":["trace[115423184] 'read index received' (duration: 360.343266ms)","trace[115423184] 'applied index is now lower than readState.Index' (duration: 212.445µs)"],"step_count":2} {"level":"info","ts":"2026-02-20T21:40:30.393777Z","caller":"traceutil/trace.go:171","msg":"trace[2126586629] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"568.181622ms","start":"2026-02-20T21:40:29.825582Z","end":"2026-02-20T21:40:30.393764Z","steps":["trace[2126586629] 'process raft request' (duration: 567.895226ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:40:30.393936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:40:29.825565Z","time spent":"568.239244ms","remote":"127.0.0.1:52292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T21:40:30.39466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.828347ms","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-20T21:40:30.394753Z","caller":"traceutil/trace.go:171","msg":"trace[412807810] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:963; }","duration":"335.971551ms","start":"2026-02-20T21:40:30.058756Z","end":"2026-02-20T21:40:30.394728Z","steps":["trace[412807810] 'agreement among raft nodes before linearized reading' (duration: 335.244804ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:40:30.394791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:40:30.058734Z","time spent":"336.049603ms","remote":"127.0.0.1:52292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-20T21:40:30.395036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.899677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T21:40:30.395057Z","caller":"traceutil/trace.go:171","msg":"trace[1348549967] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:963; }","duration":"276.938737ms","start":"2026-02-20T21:40:30.118111Z","end":"2026-02-20T21:40:30.39505Z","steps":["trace[1348549967] 'agreement among raft nodes before linearized reading' (duration: 276.900636ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:40:30.395065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.834433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-20T21:40:30.395345Z","caller":"traceutil/trace.go:171","msg":"trace[2130731077] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:963; }","duration":"170.954265ms","start":"2026-02-20T21:40:30.224149Z","end":"2026-02-20T21:40:30.395103Z","steps":["trace[2130731077] 'agreement among raft nodes before linearized reading' (duration: 170.640228ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:40:30.395098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.002484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-20T21:40:30.395426Z","caller":"traceutil/trace.go:171","msg":"trace[2107595904] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:963; }","duration":"362.363033ms","start":"2026-02-20T21:40:30.03303Z","end":"2026-02-20T21:40:30.395393Z","steps":["trace[2107595904] 'agreement among raft nodes before linearized reading' (duration: 361.946554ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:40:30.395475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:40:30.033018Z","time spent":"362.445555ms","remote":"127.0.0.1:52292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-20T21:40:31.155899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.207249ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-20T21:40:31.156032Z","caller":"traceutil/trace.go:171","msg":"trace[291992501] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"756.300272ms","start":"2026-02-20T21:40:30.399712Z","end":"2026-02-20T21:40:31.156012Z","steps":["trace[291992501] 'process raft request' (duration: 582.873379ms)","trace[291992501] 'compare' (duration: 172.919352ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T21:40:31.156091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:40:30.399684Z","time spent":"756.379615ms","remote":"127.0.0.1:52292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T21:40:31.466339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.369955ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-20T21:40:31.466583Z","caller":"traceutil/trace.go:171","msg":"trace[827675842] linearizableReadLoop","detail":"{readStateIndex:1008; appliedIndex:1004; }","duration":"672.641539ms","start":"2026-02-20T21:40:30.793895Z","end":"2026-02-20T21:40:31.466537Z","steps":["trace[827675842] 'read index received' (duration: 188.970291ms)","trace[827675842] 'applied index is now lower than readState.Index' (duration: 483.670338ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T21:40:31.466677Z","caller":"traceutil/trace.go:171","msg":"trace[849933780] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"1.065681637s","start":"2026-02-20T21:40:30.400984Z","end":"2026-02-20T21:40:31.466665Z","steps":["trace[849933780] 'process raft request' (duration: 907.883931ms)","trace[849933780] 'compare' (duration: 157.057737ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T21:40:31.466735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:40:30.400973Z","time spent":"1.065730017s","remote":"127.0.0.1:52292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T21:40:31.46672Z","caller":"traceutil/trace.go:171","msg":"trace[1223273486] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"1.064247062s","start":"2026-02-20T21:40:30.402432Z","end":"2026-02-20T21:40:31.466679Z","steps":["trace[1223273486] 'process raft request' (duration: 1.064065528s)"],"step_count":1} {"level":"info","ts":"2026-02-20T21:40:31.466904Z","caller":"traceutil/trace.go:171","msg":"trace[66780142] transaction","detail":"{read_only:false; response_revision:966; number_of_response:1; }","duration":"1.06583133s","start":"2026-02-20T21:40:30.401038Z","end":"2026-02-20T21:40:31.46687Z","steps":["trace[66780142] 'process raft request' (duration: 1.06540307s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:40:31.466993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.844011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T21:40:31.467064Z","caller":"traceutil/trace.go:171","msg":"trace[1453129858] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:967; }","duration":"347.947343ms","start":"2026-02-20T21:40:31.119093Z","end":"2026-02-20T21:40:31.46704Z","steps":["trace[1453129858] 'agreement among raft nodes before linearized reading' (duration: 347.81637ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:40:31.467067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:40:30.401033Z","time spent":"1.065941193s","remote":"127.0.0.1:52292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T21:40:31.467062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"673.14301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.235\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-02-20T21:40:31.46713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:40:31.119072Z","time spent":"348.024405ms","remote":"127.0.0.1:52002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-20T21:40:31.467178Z","caller":"traceutil/trace.go:171","msg":"trace[775314682] range","detail":"{range_begin:/registry/masterleases/199.204.45.235; range_end:; response_count:1; response_revision:967; }","duration":"673.262163ms","start":"2026-02-20T21:40:30.793889Z","end":"2026-02-20T21:40:31.467151Z","steps":["trace[775314682] 'agreement among raft nodes before linearized reading' (duration: 673.011217ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:40:31.46722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:40:30.793867Z","time spent":"673.344304ms","remote":"127.0.0.1:52038","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.235\" "} {"level":"warn","ts":"2026-02-20T21:40:31.46786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:40:30.402409Z","time spent":"1.064383676s","remote":"127.0.0.1:52292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T21:40:31.692099Z","caller":"traceutil/trace.go:171","msg":"trace[958934065] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"154.436475ms","start":"2026-02-20T21:40:31.537647Z","end":"2026-02-20T21:40:31.692084Z","steps":["trace[958934065] 'process raft request' (duration: 70.629147ms)","trace[958934065] 'compare' (duration: 83.696485ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T21:40:32.442193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"577.906041ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-20T21:40:32.442572Z","caller":"traceutil/trace.go:171","msg":"trace[366891324] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"661.118926ms","start":"2026-02-20T21:40:31.781417Z","end":"2026-02-20T21:40:32.442536Z","steps":["trace[366891324] 'process raft request' (duration: 82.798665ms)","trace[366891324] 'compare' (duration: 577.753058ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T21:40:32.442662Z","caller":"traceutil/trace.go:171","msg":"trace[1391174520] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"657.337517ms","start":"2026-02-20T21:40:31.785298Z","end":"2026-02-20T21:40:32.442635Z","steps":["trace[1391174520] 'process raft request' (duration: 657.183114ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:40:32.442709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:40:31.781395Z","time spent":"661.251708ms","remote":"127.0.0.1:52292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-20T21:40:32.44279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:40:31.785271Z","time spent":"657.464319ms","remote":"127.0.0.1:52094","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":790,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-20T21:40:32.713153Z","caller":"traceutil/trace.go:171","msg":"trace[2106691975] transaction","detail":"{read_only:false; response_revision:972; number_of_response:1; }","duration":"264.619665ms","start":"2026-02-20T21:40:32.448499Z","end":"2026-02-20T21:40:32.713118Z","steps":["trace[2106691975] 'process raft request' (duration: 195.508541ms)","trace[2106691975] 'compare' (duration: 68.96246ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T21:40:32.970369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.629396ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-20T21:40:32.970533Z","caller":"traceutil/trace.go:171","msg":"trace[912339002] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"521.950364ms","start":"2026-02-20T21:40:32.448563Z","end":"2026-02-20T21:40:32.970513Z","steps":["trace[912339002] 'process raft request' (duration: 376.093492ms)","trace[912339002] 'compare' (duration: 145.504513ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T21:40:32.970595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:40:32.44854Z","time spent":"522.027167ms","remote":"127.0.0.1:52292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T21:40:32.970932Z","caller":"traceutil/trace.go:171","msg":"trace[1548521954] linearizableReadLoop","detail":"{readStateIndex:1015; appliedIndex:1013; }","duration":"521.480293ms","start":"2026-02-20T21:40:32.449439Z","end":"2026-02-20T21:40:32.970919Z","steps":["trace[1548521954] 'read index received' (duration: 194.583548ms)","trace[1548521954] 'applied index is now lower than readState.Index' (duration: 326.894965ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T21:40:32.971081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.637207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-02-20T21:40:32.97111Z","caller":"traceutil/trace.go:171","msg":"trace[878994994] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:974; }","duration":"521.696429ms","start":"2026-02-20T21:40:32.449403Z","end":"2026-02-20T21:40:32.971099Z","steps":["trace[878994994] 'agreement among raft nodes before linearized reading' (duration: 521.566936ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T21:40:32.971075Z","caller":"traceutil/trace.go:171","msg":"trace[178351562] transaction","detail":"{read_only:false; response_revision:974; number_of_response:1; }","duration":"520.763846ms","start":"2026-02-20T21:40:32.450275Z","end":"2026-02-20T21:40:32.971038Z","steps":["trace[178351562] 'process raft request' (duration: 520.188812ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:40:32.971142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:40:32.44939Z","time spent":"521.74505ms","remote":"127.0.0.1:52292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":495,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-20T21:40:32.971248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:40:32.450257Z","time spent":"520.90679ms","remote":"127.0.0.1:52094","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":772,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-20T21:40:33.144048Z","caller":"traceutil/trace.go:171","msg":"trace[1225789628] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"166.246715ms","start":"2026-02-20T21:40:32.977766Z","end":"2026-02-20T21:40:33.144013Z","steps":["trace[1225789628] 'process raft request' (duration: 165.825355ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T21:40:33.494932Z","caller":"traceutil/trace.go:171","msg":"trace[7297999] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"332.245145ms","start":"2026-02-20T21:40:33.162664Z","end":"2026-02-20T21:40:33.49491Z","steps":["trace[7297999] 'process raft request' (duration: 308.95462ms)","trace[7297999] 'compare' (duration: 23.178312ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T21:40:33.495082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:40:33.162645Z","time spent":"332.354667ms","remote":"127.0.0.1:52292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T21:40:38.054895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"540.570017ms","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-20T21:40:38.055076Z","caller":"traceutil/trace.go:171","msg":"trace[292022308] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1002; }","duration":"540.798094ms","start":"2026-02-20T21:40:37.514254Z","end":"2026-02-20T21:40:38.055052Z","steps":["trace[292022308] 'range keys from in-memory index tree' (duration: 540.371231ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:40:38.055176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:40:37.514232Z","time spent":"540.912676ms","remote":"127.0.0.1:52292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-20T21:40:38.055318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"582.188195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T21:40:38.055852Z","caller":"traceutil/trace.go:171","msg":"trace[395106449] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1002; }","duration":"582.765138ms","start":"2026-02-20T21:40:37.473059Z","end":"2026-02-20T21:40:38.055825Z","steps":["trace[395106449] 'count revisions from in-memory index tree' (duration: 582.099603ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:40:38.05546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"540.002024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-02-20T21:40:38.055919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:40:37.473037Z","time spent":"582.864681ms","remote":"127.0.0.1:52256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":26,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-02-20T21:40:38.055463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.660107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-20T21:40:38.05599Z","caller":"traceutil/trace.go:171","msg":"trace[627310439] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1002; }","duration":"540.503875ms","start":"2026-02-20T21:40:37.515407Z","end":"2026-02-20T21:40:38.055911Z","steps":["trace[627310439] 'range keys from in-memory index tree' (duration: 539.903311ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T21:40:38.056084Z","caller":"traceutil/trace.go:171","msg":"trace[1717526228] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1002; }","duration":"545.330413ms","start":"2026-02-20T21:40:37.510731Z","end":"2026-02-20T21:40:38.056061Z","steps":["trace[1717526228] 'range keys from in-memory index tree' (duration: 544.452851ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:40:38.056154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:40:37.510709Z","time spent":"545.427665ms","remote":"127.0.0.1:52292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-20T21:40:38.055628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"540.853594ms","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-20T21:40:38.056318Z","caller":"traceutil/trace.go:171","msg":"trace[2101433622] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1002; }","duration":"541.52195ms","start":"2026-02-20T21:40:37.514753Z","end":"2026-02-20T21:40:38.056275Z","steps":["trace[2101433622] 'range keys from in-memory index tree' (duration: 540.246279ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:40:38.056397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:40:37.514691Z","time spent":"541.666773ms","remote":"127.0.0.1:52292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-20T21:40:38.056614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:40:37.515392Z","time spent":"541.210073ms","remote":"127.0.0.1:52292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-20T21:40:38.373045Z","caller":"traceutil/trace.go:171","msg":"trace[130351679] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"312.893516ms","start":"2026-02-20T21:40:38.060118Z","end":"2026-02-20T21:40:38.373012Z","steps":["trace[130351679] 'process raft request' (duration: 312.683911ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:40:38.373237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:40:38.060099Z","time spent":"313.039729ms","remote":"127.0.0.1:52292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T21:40:38.524724Z","caller":"traceutil/trace.go:171","msg":"trace[528569688] transaction","detail":"{read_only:false; response_revision:1004; number_of_response:1; }","duration":"463.137122ms","start":"2026-02-20T21:40:38.061557Z","end":"2026-02-20T21:40:38.524694Z","steps":["trace[528569688] 'process raft request' (duration: 427.355025ms)","trace[528569688] 'compare' (duration: 35.601643ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T21:40:38.525037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:40:38.061486Z","time spent":"463.318207ms","remote":"127.0.0.1:52292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T21:40:38.524998Z","caller":"traceutil/trace.go:171","msg":"trace[1812886876] transaction","detail":"{read_only:false; response_revision:1005; number_of_response:1; }","duration":"463.203274ms","start":"2026-02-20T21:40:38.061769Z","end":"2026-02-20T21:40:38.524972Z","steps":["trace[1812886876] 'process raft request' (duration: 462.878606ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T21:40:38.525148Z","caller":"traceutil/trace.go:171","msg":"trace[339531398] transaction","detail":"{read_only:false; response_revision:1006; number_of_response:1; }","duration":"463.325166ms","start":"2026-02-20T21:40:38.061803Z","end":"2026-02-20T21:40:38.525128Z","steps":["trace[339531398] 'process raft request' (duration: 463.08022ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T21:40:38.525166Z","caller":"traceutil/trace.go:171","msg":"trace[2073401867] linearizableReadLoop","detail":"{readStateIndex:1050; appliedIndex:1045; }","duration":"405.407015ms","start":"2026-02-20T21:40:38.119732Z","end":"2026-02-20T21:40:38.525139Z","steps":["trace[2073401867] 'read index received' (duration: 253.090857ms)","trace[2073401867] 'applied index is now lower than readState.Index' (duration: 152.315057ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T21:40:38.525204Z","caller":"traceutil/trace.go:171","msg":"trace[1101491033] transaction","detail":"{read_only:false; response_revision:1007; number_of_response:1; }","duration":"463.01368ms","start":"2026-02-20T21:40:38.06217Z","end":"2026-02-20T21:40:38.525183Z","steps":["trace[1101491033] 'process raft request' (duration: 462.895067ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:40:38.525258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:40:38.061779Z","time spent":"463.402149ms","remote":"127.0.0.1:52292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T21:40:38.525289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:40:38.062155Z","time spent":"463.093361ms","remote":"127.0.0.1:52292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T21:40:38.525307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.571338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T21:40:38.525424Z","caller":"traceutil/trace.go:171","msg":"trace[79331184] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1007; }","duration":"405.761773ms","start":"2026-02-20T21:40:38.119651Z","end":"2026-02-20T21:40:38.525413Z","steps":["trace[79331184] 'agreement among raft nodes before linearized reading' (duration: 405.588889ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:40:38.525476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:40:38.119615Z","time spent":"405.834715ms","remote":"127.0.0.1:52002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-20T21:40:38.525806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:40:38.061744Z","time spent":"463.352768ms","remote":"127.0.0.1:52292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T21:40:41.130759Z","caller":"traceutil/trace.go:171","msg":"trace[508166980] linearizableReadLoop","detail":"{readStateIndex:1071; appliedIndex:1070; }","duration":"183.262616ms","start":"2026-02-20T21:40:40.947472Z","end":"2026-02-20T21:40:41.130735Z","steps":["trace[508166980] 'read index received' (duration: 183.209965ms)","trace[508166980] 'applied index is now lower than readState.Index' (duration: 51.301µs)"],"step_count":2} {"level":"warn","ts":"2026-02-20T21:40:41.130828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:40:40.795242Z","time spent":"335.579933ms","remote":"127.0.0.1:52038","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-20T21:40:41.131031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.890322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-20T21:40:41.131074Z","caller":"traceutil/trace.go:171","msg":"trace[1651264458] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1027; }","duration":"133.974863ms","start":"2026-02-20T21:40:40.997089Z","end":"2026-02-20T21:40:41.131063Z","steps":["trace[1651264458] 'agreement among raft nodes before linearized reading' (duration: 133.812679ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:40:41.131195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.670627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-provisioner-795cfbf7fb-vj6gs\" ","response":"range_response_count:1 size:12337"} {"level":"info","ts":"2026-02-20T21:40:41.13164Z","caller":"traceutil/trace.go:171","msg":"trace[2144444293] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-provisioner-795cfbf7fb-vj6gs; range_end:; response_count:1; response_revision:1027; }","duration":"184.142718ms","start":"2026-02-20T21:40:40.947418Z","end":"2026-02-20T21:40:41.131561Z","steps":["trace[2144444293] 'agreement among raft nodes before linearized reading' (duration: 183.43332ms)"],"step_count":1}