{"level":"warn","ts":"2026-06-23T18:44:55.149254Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-23T18:44:55.149462Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.142: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.19.213.142:2380","--initial-cluster=instance=https://199.19.213.142:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.142:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.142: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-06-23T18:44:55.149574Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-23T18:44:55.149611Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.142:2380"]} {"level":"info","ts":"2026-06-23T18:44:55.149658Z","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-06-23T18:44:55.150633Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.142:2379"]} {"level":"info","ts":"2026-06-23T18:44:55.150772Z","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.19.213.142:2380"],"listen-peer-urls":["https://199.19.213.142:2380"],"advertise-client-urls":["https://199.19.213.142:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.142:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.142: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-06-23T18:44:55.155651Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.283533ms"} {"level":"info","ts":"2026-06-23T18:44:55.163347Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"54fca804e66e40f4","cluster-id":"374317481b3c77b9"} {"level":"info","ts":"2026-06-23T18:44:55.163472Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"54fca804e66e40f4 switched to configuration voters=()"} {"level":"info","ts":"2026-06-23T18:44:55.1635Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"54fca804e66e40f4 became follower at term 0"} {"level":"info","ts":"2026-06-23T18:44:55.163521Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 54fca804e66e40f4 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-23T18:44:55.163531Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"54fca804e66e40f4 became follower at term 1"} {"level":"info","ts":"2026-06-23T18:44:55.163583Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"54fca804e66e40f4 switched to configuration voters=(6123954332316352756)"} {"level":"warn","ts":"2026-06-23T18:44:55.1701Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-23T18:44:55.171567Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-23T18:44:55.172357Z","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-06-23T18:44:55.174515Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"54fca804e66e40f4","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-23T18:44:55.174701Z","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-06-23T18:44:55.174807Z","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-06-23T18:44:55.174799Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"54fca804e66e40f4","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-23T18:44:55.174904Z","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-06-23T18:44:55.17678Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"54fca804e66e40f4 switched to configuration voters=(6123954332316352756)"} {"level":"info","ts":"2026-06-23T18:44:55.177093Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"374317481b3c77b9","local-member-id":"54fca804e66e40f4","added-peer-id":"54fca804e66e40f4","added-peer-peer-urls":["https://199.19.213.142:2380"]} {"level":"info","ts":"2026-06-23T18:44:55.179777Z","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-06-23T18:44:55.17978Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.142:2380"} {"level":"info","ts":"2026-06-23T18:44:55.179943Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.142:2380"} {"level":"info","ts":"2026-06-23T18:44:55.181022Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"54fca804e66e40f4","initial-advertise-peer-urls":["https://199.19.213.142:2380"],"listen-peer-urls":["https://199.19.213.142:2380"],"advertise-client-urls":["https://199.19.213.142:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.142:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-23T18:44:55.181104Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-23T18:44:55.36436Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"54fca804e66e40f4 is starting a new election at term 1"} {"level":"info","ts":"2026-06-23T18:44:55.364413Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"54fca804e66e40f4 became pre-candidate at term 1"} {"level":"info","ts":"2026-06-23T18:44:55.364431Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"54fca804e66e40f4 received MsgPreVoteResp from 54fca804e66e40f4 at term 1"} {"level":"info","ts":"2026-06-23T18:44:55.364491Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"54fca804e66e40f4 became candidate at term 2"} {"level":"info","ts":"2026-06-23T18:44:55.364504Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"54fca804e66e40f4 received MsgVoteResp from 54fca804e66e40f4 at term 2"} {"level":"info","ts":"2026-06-23T18:44:55.364516Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"54fca804e66e40f4 became leader at term 2"} {"level":"info","ts":"2026-06-23T18:44:55.36455Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 54fca804e66e40f4 elected leader 54fca804e66e40f4 at term 2"} {"level":"info","ts":"2026-06-23T18:44:55.36625Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"54fca804e66e40f4","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.142:2379]}","request-path":"/0/members/54fca804e66e40f4/attributes","cluster-id":"374317481b3c77b9","publish-timeout":"7s"} {"level":"info","ts":"2026-06-23T18:44:55.366281Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-23T18:44:55.366394Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-23T18:44:55.366792Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-23T18:44:55.366858Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-23T18:44:55.366555Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-23T18:44:55.367789Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"374317481b3c77b9","local-member-id":"54fca804e66e40f4","cluster-version":"3.5"} {"level":"info","ts":"2026-06-23T18:44:55.368092Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-23T18:44:55.36827Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-23T18:44:55.369181Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-23T18:44:55.371062Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.142:2379"} {"level":"info","ts":"2026-06-23T18:45:21.698704Z","caller":"traceutil/trace.go:171","msg":"trace[1955104894] transaction","detail":"{read_only:false; response_revision:423; number_of_response:1; }","duration":"129.297708ms","start":"2026-06-23T18:45:21.569338Z","end":"2026-06-23T18:45:21.698635Z","steps":["trace[1955104894] 'process raft request' (duration: 129.030151ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:45:33.372131Z","caller":"traceutil/trace.go:171","msg":"trace[266516520] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"670.996785ms","start":"2026-06-23T18:45:32.701109Z","end":"2026-06-23T18:45:33.372105Z","steps":["trace[266516520] 'process raft request' (duration: 670.79322ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:45:33.372635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:45:32.701088Z","time spent":"671.127589ms","remote":"127.0.0.1:40566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-23T18:45:33.383253Z","caller":"traceutil/trace.go:171","msg":"trace[1712660533] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"677.511211ms","start":"2026-06-23T18:45:32.705698Z","end":"2026-06-23T18:45:33.383209Z","steps":["trace[1712660533] 'process raft request' (duration: 677.339247ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:45:33.383383Z","caller":"traceutil/trace.go:171","msg":"trace[207267778] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"671.108328ms","start":"2026-06-23T18:45:32.712255Z","end":"2026-06-23T18:45:33.383363Z","steps":["trace[207267778] 'process raft request' (duration: 670.885463ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:45:33.383397Z","caller":"traceutil/trace.go:171","msg":"trace[944137723] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"668.060966ms","start":"2026-06-23T18:45:32.715312Z","end":"2026-06-23T18:45:33.383373Z","steps":["trace[944137723] 'process raft request' (duration: 667.871601ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:45:33.383457Z","caller":"traceutil/trace.go:171","msg":"trace[4860126] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"663.964868ms","start":"2026-06-23T18:45:32.719462Z","end":"2026-06-23T18:45:33.383427Z","steps":["trace[4860126] 'process raft request' (duration: 663.760973ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:45:33.383491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:45:32.715286Z","time spent":"668.144628ms","remote":"127.0.0.1:40566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-23T18:45:33.383488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:45:32.71223Z","time spent":"671.200381ms","remote":"127.0.0.1:40566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-23T18:45:33.383516Z","caller":"traceutil/trace.go:171","msg":"trace[856547643] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"663.978058ms","start":"2026-06-23T18:45:32.719522Z","end":"2026-06-23T18:45:33.3835Z","steps":["trace[856547643] 'process raft request' (duration: 663.733332ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:45:33.383587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:45:32.719444Z","time spent":"664.07199ms","remote":"127.0.0.1:40566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-23T18:45:33.383608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:45:32.719516Z","time spent":"664.062089ms","remote":"127.0.0.1:40566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-23T18:45:33.383606Z","caller":"traceutil/trace.go:171","msg":"trace[220771189] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"663.427365ms","start":"2026-06-23T18:45:32.720157Z","end":"2026-06-23T18:45:33.383584Z","steps":["trace[220771189] 'process raft request' (duration: 663.24995ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:45:33.383621Z","caller":"traceutil/trace.go:171","msg":"trace[1002186509] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"662.263417ms","start":"2026-06-23T18:45:32.721347Z","end":"2026-06-23T18:45:33.383611Z","steps":["trace[1002186509] 'process raft request' (duration: 662.137994ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:45:33.383667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:45:32.721318Z","time spent":"662.326528ms","remote":"127.0.0.1:40566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-23T18:45:33.383676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:45:32.720149Z","time spent":"663.494306ms","remote":"127.0.0.1:40566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-23T18:45:33.383832Z","caller":"traceutil/trace.go:171","msg":"trace[1568172901] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"631.915622ms","start":"2026-06-23T18:45:32.751897Z","end":"2026-06-23T18:45:33.383813Z","steps":["trace[1568172901] 'process raft request' (duration: 631.758748ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:45:33.38386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:45:32.705682Z","time spent":"678.124046ms","remote":"127.0.0.1:40566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-23T18:45:33.383881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:45:32.751867Z","time spent":"631.992204ms","remote":"127.0.0.1:40566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-23T18:45:33.383776Z","caller":"traceutil/trace.go:171","msg":"trace[1265842645] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"656.071599ms","start":"2026-06-23T18:45:32.727689Z","end":"2026-06-23T18:45:33.383761Z","steps":["trace[1265842645] 'process raft request' (duration: 655.862444ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:45:33.383988Z","caller":"traceutil/trace.go:171","msg":"trace[673639133] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"616.541235ms","start":"2026-06-23T18:45:32.767434Z","end":"2026-06-23T18:45:33.383975Z","steps":["trace[673639133] 'process raft request' (duration: 616.31489ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:45:33.384039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:45:32.767399Z","time spent":"616.616417ms","remote":"127.0.0.1:40566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-23T18:45:33.384082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:45:32.727651Z","time spent":"656.310045ms","remote":"127.0.0.1:40566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-23T18:45:33.3862Z","caller":"traceutil/trace.go:171","msg":"trace[1886995111] linearizableReadLoop","detail":"{readStateIndex:509; appliedIndex:497; }","duration":"269.24599ms","start":"2026-06-23T18:45:33.116928Z","end":"2026-06-23T18:45:33.386174Z","steps":["trace[1886995111] 'read index received' (duration: 255.124373ms)","trace[1886995111] 'applied index is now lower than readState.Index' (duration: 14.120417ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T18:45:33.386324Z","caller":"traceutil/trace.go:171","msg":"trace[1579609886] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"530.810668ms","start":"2026-06-23T18:45:32.855502Z","end":"2026-06-23T18:45:33.386313Z","steps":["trace[1579609886] 'process raft request' (duration: 528.512623ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:45:33.386471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:45:32.85547Z","time spent":"530.916339ms","remote":"127.0.0.1:40566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-23T18:45:33.387494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.563761ms","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-06-23T18:45:33.387541Z","caller":"traceutil/trace.go:171","msg":"trace[1445714642] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:492; }","duration":"270.655504ms","start":"2026-06-23T18:45:33.116875Z","end":"2026-06-23T18:45:33.38753Z","steps":["trace[1445714642] 'agreement among raft nodes before linearized reading' (duration: 270.521531ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:45:33.393332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.686723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-06-23T18:45:33.393379Z","caller":"traceutil/trace.go:171","msg":"trace[297632686] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:494; }","duration":"115.775915ms","start":"2026-06-23T18:45:33.277592Z","end":"2026-06-23T18:45:33.393368Z","steps":["trace[297632686] 'agreement among raft nodes before linearized reading' (duration: 115.633632ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:45:33.393711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.387175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-23T18:45:33.393743Z","caller":"traceutil/trace.go:171","msg":"trace[1349714253] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:494; }","duration":"102.462507ms","start":"2026-06-23T18:45:33.291273Z","end":"2026-06-23T18:45:33.393735Z","steps":["trace[1349714253] 'agreement among raft nodes before linearized reading' (duration: 102.415076ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:45:39.791886Z","caller":"traceutil/trace.go:171","msg":"trace[160391038] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"235.108195ms","start":"2026-06-23T18:45:39.55672Z","end":"2026-06-23T18:45:39.791828Z","steps":["trace[160391038] 'process raft request' (duration: 221.102241ms)","trace[160391038] 'compare' (duration: 13.80992ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T18:45:39.850349Z","caller":"traceutil/trace.go:171","msg":"trace[33434657] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"109.881554ms","start":"2026-06-23T18:45:39.740396Z","end":"2026-06-23T18:45:39.850277Z","steps":["trace[33434657] 'process raft request' (duration: 109.056905ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:45:40.097352Z","caller":"traceutil/trace.go:171","msg":"trace[1280399037] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"222.146625ms","start":"2026-06-23T18:45:39.875169Z","end":"2026-06-23T18:45:40.097316Z","steps":["trace[1280399037] 'process raft request' (duration: 127.871334ms)","trace[1280399037] 'compare' (duration: 94.124758ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T18:45:40.728694Z","caller":"traceutil/trace.go:171","msg":"trace[1273656516] linearizableReadLoop","detail":"{readStateIndex:619; appliedIndex:618; }","duration":"435.917981ms","start":"2026-06-23T18:45:40.292749Z","end":"2026-06-23T18:45:40.728667Z","steps":["trace[1273656516] 'read index received' (duration: 406.804175ms)","trace[1273656516] 'applied index is now lower than readState.Index' (duration: 29.112956ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T18:45:40.728808Z","caller":"traceutil/trace.go:171","msg":"trace[160957137] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"810.001335ms","start":"2026-06-23T18:45:39.918767Z","end":"2026-06-23T18:45:40.728769Z","steps":["trace[160957137] 'process raft request' (duration: 780.738516ms)","trace[160957137] 'compare' (duration: 28.89648ms)"],"step_count":2} {"level":"warn","ts":"2026-06-23T18:45:40.728952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.190238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-23T18:45:40.729285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:45:39.918731Z","time spent":"810.454756ms","remote":"127.0.0.1:40762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-23T18:45:40.729309Z","caller":"traceutil/trace.go:171","msg":"trace[2140934841] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:595; }","duration":"436.602627ms","start":"2026-06-23T18:45:40.292675Z","end":"2026-06-23T18:45:40.729277Z","steps":["trace[2140934841] 'agreement among raft nodes before linearized reading' (duration: 436.185347ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:45:40.729387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:45:40.292644Z","time spent":"436.728551ms","remote":"127.0.0.1:40532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-23T18:45:42.37665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.306530943s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-23T18:45:42.376898Z","caller":"traceutil/trace.go:171","msg":"trace[347122045] linearizableReadLoop","detail":"{readStateIndex:625; appliedIndex:621; }","duration":"942.867918ms","start":"2026-06-23T18:45:41.434019Z","end":"2026-06-23T18:45:42.376887Z","steps":["trace[347122045] 'read index received' (duration: 209.562635ms)","trace[347122045] 'applied index is now lower than readState.Index' (duration: 733.304573ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T18:45:42.376961Z","caller":"traceutil/trace.go:171","msg":"trace[1479965822] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"1.52710518s","start":"2026-06-23T18:45:40.849849Z","end":"2026-06-23T18:45:42.376954Z","steps":["trace[1479965822] 'process raft request' (duration: 220.106196ms)","trace[1479965822] 'compare' (duration: 1.306336339s)"],"step_count":2} {"level":"warn","ts":"2026-06-23T18:45:42.376997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:45:40.849826Z","time spent":"1.527151522s","remote":"127.0.0.1:40580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":775,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-23T18:45:42.377226Z","caller":"traceutil/trace.go:171","msg":"trace[173597239] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"1.527286605s","start":"2026-06-23T18:45:40.849932Z","end":"2026-06-23T18:45:42.377219Z","steps":["trace[173597239] 'process raft request' (duration: 1.526794223s)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:45:42.37738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:45:40.84991Z","time spent":"1.527331536s","remote":"127.0.0.1:40708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3209,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-23T18:45:42.377465Z","caller":"traceutil/trace.go:171","msg":"trace[552129801] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"1.526494726s","start":"2026-06-23T18:45:40.850964Z","end":"2026-06-23T18:45:42.377458Z","steps":["trace[552129801] 'process raft request' (duration: 1.525863641s)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:45:42.377494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:45:40.850932Z","time spent":"1.526548067s","remote":"127.0.0.1:40580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":905,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-23T18:45:42.377699Z","caller":"traceutil/trace.go:171","msg":"trace[962351883] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"1.128522031s","start":"2026-06-23T18:45:41.249134Z","end":"2026-06-23T18:45:42.377656Z","steps":["trace[962351883] 'process raft request' (duration: 1.127720762s)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:45:42.377844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:45:41.249112Z","time spent":"1.128659555s","remote":"127.0.0.1:40788","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-23T18:45:42.378042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"944.008965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-23T18:45:42.378148Z","caller":"traceutil/trace.go:171","msg":"trace[1807546506] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:601; }","duration":"944.122817ms","start":"2026-06-23T18:45:41.434001Z","end":"2026-06-23T18:45:42.378124Z","steps":["trace[1807546506] 'agreement among raft nodes before linearized reading' (duration: 943.862431ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:45:42.378206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:45:41.433937Z","time spent":"944.255051ms","remote":"127.0.0.1:40788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-23T18:45:42.378266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"867.352193ms","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-06-23T18:45:42.378325Z","caller":"traceutil/trace.go:171","msg":"trace[1957679881] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:601; }","duration":"867.448926ms","start":"2026-06-23T18:45:41.510862Z","end":"2026-06-23T18:45:42.378311Z","steps":["trace[1957679881] 'agreement among raft nodes before linearized reading' (duration: 867.236431ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:45:42.378445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.994427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-06-23T18:45:42.378498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:45:41.510832Z","time spent":"867.639311ms","remote":"127.0.0.1:40788","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-06-23T18:45:42.378589Z","caller":"traceutil/trace.go:171","msg":"trace[102919711] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:601; }","duration":"504.14901ms","start":"2026-06-23T18:45:41.874398Z","end":"2026-06-23T18:45:42.378547Z","steps":["trace[102919711] 'agreement among raft nodes before linearized reading' (duration: 503.866874ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:45:42.378673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:45:41.874379Z","time spent":"504.273434ms","remote":"127.0.0.1:40788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-23T18:45:42.378735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"582.927792ms","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-06-23T18:45:42.378777Z","caller":"traceutil/trace.go:171","msg":"trace[435468890] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:601; }","duration":"582.999934ms","start":"2026-06-23T18:45:41.795769Z","end":"2026-06-23T18:45:42.378769Z","steps":["trace[435468890] 'agreement among raft nodes before linearized reading' (duration: 582.870751ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:45:42.378801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:45:41.79575Z","time spent":"583.046414ms","remote":"127.0.0.1:40788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-23T18:45:44.032718Z","caller":"traceutil/trace.go:171","msg":"trace[1181426219] linearizableReadLoop","detail":"{readStateIndex:647; appliedIndex:646; }","duration":"149.240554ms","start":"2026-06-23T18:45:43.883455Z","end":"2026-06-23T18:45:44.032696Z","steps":["trace[1181426219] 'read index received' (duration: 149.154712ms)","trace[1181426219] 'applied index is now lower than readState.Index' (duration: 84.882µs)"],"step_count":2} {"level":"warn","ts":"2026-06-23T18:45:44.032848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.375207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-23T18:45:44.032874Z","caller":"traceutil/trace.go:171","msg":"trace[675171718] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:623; }","duration":"149.455659ms","start":"2026-06-23T18:45:43.88341Z","end":"2026-06-23T18:45:44.032865Z","steps":["trace[675171718] 'agreement among raft nodes before linearized reading' (duration: 149.375717ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:45:44.033082Z","caller":"traceutil/trace.go:171","msg":"trace[2035119886] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"155.240697ms","start":"2026-06-23T18:45:43.877834Z","end":"2026-06-23T18:45:44.033075Z","steps":["trace[2035119886] 'process raft request' (duration: 154.653593ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:45:44.074476Z","caller":"traceutil/trace.go:171","msg":"trace[1806651467] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"185.167013ms","start":"2026-06-23T18:45:43.889291Z","end":"2026-06-23T18:45:44.074458Z","steps":["trace[1806651467] 'process raft request' (duration: 185.0736ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:45:44.19251Z","caller":"traceutil/trace.go:171","msg":"trace[1150497163] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"106.941955ms","start":"2026-06-23T18:45:44.08555Z","end":"2026-06-23T18:45:44.192492Z","steps":["trace[1150497163] 'process raft request' (duration: 106.7457ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:45:54.087177Z","caller":"traceutil/trace.go:171","msg":"trace[663648673] transaction","detail":"{read_only:false; response_revision:731; number_of_response:1; }","duration":"179.782644ms","start":"2026-06-23T18:45:53.907317Z","end":"2026-06-23T18:45:54.087099Z","steps":["trace[663648673] 'process raft request' (duration: 137.407762ms)","trace[663648673] 'compare' (duration: 42.002253ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T18:45:54.132047Z","caller":"traceutil/trace.go:171","msg":"trace[1796989144] transaction","detail":"{read_only:false; response_revision:732; number_of_response:1; }","duration":"224.42175ms","start":"2026-06-23T18:45:53.9076Z","end":"2026-06-23T18:45:54.132022Z","steps":["trace[1796989144] 'process raft request' (duration: 224.274266ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:45:54.132157Z","caller":"traceutil/trace.go:171","msg":"trace[1343990160] transaction","detail":"{read_only:false; response_revision:733; number_of_response:1; }","duration":"220.493455ms","start":"2026-06-23T18:45:53.911637Z","end":"2026-06-23T18:45:54.13213Z","steps":["trace[1343990160] 'process raft request' (duration: 220.340232ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:45:54.356322Z","caller":"traceutil/trace.go:171","msg":"trace[762281371] transaction","detail":"{read_only:false; response_revision:736; number_of_response:1; }","duration":"214.80422ms","start":"2026-06-23T18:45:54.141498Z","end":"2026-06-23T18:45:54.356302Z","steps":["trace[762281371] 'process raft request' (duration: 214.689398ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:45:54.356334Z","caller":"traceutil/trace.go:171","msg":"trace[1160438357] transaction","detail":"{read_only:false; response_revision:735; number_of_response:1; }","duration":"217.547955ms","start":"2026-06-23T18:45:54.138755Z","end":"2026-06-23T18:45:54.356303Z","steps":["trace[1160438357] 'process raft request' (duration: 217.30243ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:45:54.401043Z","caller":"traceutil/trace.go:171","msg":"trace[48023409] linearizableReadLoop","detail":"{readStateIndex:764; appliedIndex:762; }","duration":"108.931552ms","start":"2026-06-23T18:45:54.292093Z","end":"2026-06-23T18:45:54.401025Z","steps":["trace[48023409] 'read index received' (duration: 64.090691ms)","trace[48023409] 'applied index is now lower than readState.Index' (duration: 44.839751ms)"],"step_count":2} {"level":"warn","ts":"2026-06-23T18:45:54.401143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.041754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-23T18:45:54.401165Z","caller":"traceutil/trace.go:171","msg":"trace[1732377967] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:736; }","duration":"109.120036ms","start":"2026-06-23T18:45:54.292039Z","end":"2026-06-23T18:45:54.401159Z","steps":["trace[1732377967] 'agreement among raft nodes before linearized reading' (duration: 109.059454ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:45:55.985557Z","caller":"traceutil/trace.go:171","msg":"trace[2094173533] transaction","detail":"{read_only:false; response_revision:742; number_of_response:1; }","duration":"158.969577ms","start":"2026-06-23T18:45:55.826562Z","end":"2026-06-23T18:45:55.985532Z","steps":["trace[2094173533] 'process raft request' (duration: 158.791573ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:45:59.119415Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4680540691283640695,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-23T18:45:59.472844Z","caller":"traceutil/trace.go:171","msg":"trace[1775619969] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"855.524842ms","start":"2026-06-23T18:45:58.617296Z","end":"2026-06-23T18:45:59.472821Z","steps":["trace[1775619969] 'process raft request' (duration: 855.372308ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:45:59.473143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:45:58.617276Z","time spent":"855.634024ms","remote":"127.0.0.1:40788","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-23T18:45:59.691904Z","caller":"traceutil/trace.go:171","msg":"trace[310332896] transaction","detail":"{read_only:false; response_revision:759; number_of_response:1; }","duration":"1.071249672s","start":"2026-06-23T18:45:58.620625Z","end":"2026-06-23T18:45:59.691874Z","steps":["trace[310332896] 'process raft request' (duration: 1.070940525s)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:45:59.6919Z","caller":"traceutil/trace.go:171","msg":"trace[1772169422] linearizableReadLoop","detail":"{readStateIndex:788; appliedIndex:785; }","duration":"1.073294391s","start":"2026-06-23T18:45:58.618575Z","end":"2026-06-23T18:45:59.691869Z","steps":["trace[1772169422] 'read index received' (duration: 854.163159ms)","trace[1772169422] 'applied index is now lower than readState.Index' (duration: 219.130072ms)"],"step_count":2} {"level":"warn","ts":"2026-06-23T18:45:59.692055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:45:58.620609Z","time spent":"1.071368616s","remote":"127.0.0.1:40788","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-23T18:45:59.692374Z","caller":"traceutil/trace.go:171","msg":"trace[1552261792] transaction","detail":"{read_only:false; response_revision:760; number_of_response:1; }","duration":"780.882748ms","start":"2026-06-23T18:45:58.91148Z","end":"2026-06-23T18:45:59.692363Z","steps":["trace[1552261792] 'process raft request' (duration: 780.319305ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:45:59.692522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:45:58.911454Z","time spent":"780.95791ms","remote":"127.0.0.1:40762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-23T18:45:59.692601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"953.07061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-23T18:45:59.692647Z","caller":"traceutil/trace.go:171","msg":"trace[598675152] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:760; }","duration":"953.158173ms","start":"2026-06-23T18:45:58.739476Z","end":"2026-06-23T18:45:59.692634Z","steps":["trace[598675152] 'agreement among raft nodes before linearized reading' (duration: 953.06883ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:45:59.69271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.107769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-23T18:45:59.692723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:45:58.739455Z","time spent":"953.254075ms","remote":"127.0.0.1:40566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":31,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"info","ts":"2026-06-23T18:45:59.69275Z","caller":"traceutil/trace.go:171","msg":"trace[1008179497] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:760; }","duration":"401.239142ms","start":"2026-06-23T18:45:59.291502Z","end":"2026-06-23T18:45:59.692741Z","steps":["trace[1008179497] 'agreement among raft nodes before linearized reading' (duration: 401.121199ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:45:59.692784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:45:59.291482Z","time spent":"401.296983ms","remote":"127.0.0.1:40532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-23T18:45:59.692823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.074241544s","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-06-23T18:45:59.692864Z","caller":"traceutil/trace.go:171","msg":"trace[1879584453] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:760; }","duration":"1.074320696s","start":"2026-06-23T18:45:58.618535Z","end":"2026-06-23T18:45:59.692855Z","steps":["trace[1879584453] 'agreement among raft nodes before linearized reading' (duration: 1.074171962s)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:45:59.692895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:45:58.618514Z","time spent":"1.074376058s","remote":"127.0.0.1:40788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-23T18:45:59.692984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.074419279s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-06-23T18:45:59.692977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.697302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-23T18:45:59.693013Z","caller":"traceutil/trace.go:171","msg":"trace[201429113] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:760; }","duration":"1.074470439s","start":"2026-06-23T18:45:58.618533Z","end":"2026-06-23T18:45:59.693004Z","steps":["trace[201429113] 'agreement among raft nodes before linearized reading' (duration: 1.074361777s)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:45:59.693029Z","caller":"traceutil/trace.go:171","msg":"trace[1110698328] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:760; }","duration":"558.757004ms","start":"2026-06-23T18:45:59.134258Z","end":"2026-06-23T18:45:59.693015Z","steps":["trace[1110698328] 'agreement among raft nodes before linearized reading' (duration: 558.665331ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:45:59.693092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:45:59.13421Z","time spent":"558.857016ms","remote":"127.0.0.1:40776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":29,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"warn","ts":"2026-06-23T18:45:59.693041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:45:58.618518Z","time spent":"1.074513301s","remote":"127.0.0.1:40788","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-06-23T18:45:59.69327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"783.315416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.142\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-06-23T18:45:59.693333Z","caller":"traceutil/trace.go:171","msg":"trace[442506973] range","detail":"{range_begin:/registry/masterleases/199.19.213.142; range_end:; response_count:1; response_revision:760; }","duration":"783.418899ms","start":"2026-06-23T18:45:58.909899Z","end":"2026-06-23T18:45:59.693318Z","steps":["trace[442506973] 'agreement among raft nodes before linearized reading' (duration: 783.290846ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:45:59.693382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:45:58.909877Z","time spent":"783.492271ms","remote":"127.0.0.1:40542","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.19.213.142\" "} {"level":"info","ts":"2026-06-23T18:46:10.153042Z","caller":"traceutil/trace.go:171","msg":"trace[883784173] transaction","detail":"{read_only:false; response_revision:883; number_of_response:1; }","duration":"100.617013ms","start":"2026-06-23T18:46:10.052361Z","end":"2026-06-23T18:46:10.152978Z","steps":["trace[883784173] 'process raft request' (duration: 68.392303ms)","trace[883784173] 'compare' (duration: 31.854941ms)"],"step_count":2} {"level":"warn","ts":"2026-06-23T18:46:20.055542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.334953ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-23T18:46:20.055647Z","caller":"traceutil/trace.go:171","msg":"trace[1596559456] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"197.179119ms","start":"2026-06-23T18:46:19.858449Z","end":"2026-06-23T18:46:20.055628Z","steps":["trace[1596559456] 'process raft request' (duration: 76.669961ms)","trace[1596559456] 'compare' (duration: 120.21036ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T18:46:20.252311Z","caller":"traceutil/trace.go:171","msg":"trace[593921450] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"136.626813ms","start":"2026-06-23T18:46:20.115628Z","end":"2026-06-23T18:46:20.252255Z","steps":["trace[593921450] 'process raft request' (duration: 136.325055ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:46:34.053976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.531599ms","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-06-23T18:46:34.05425Z","caller":"traceutil/trace.go:171","msg":"trace[150116191] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:971; }","duration":"128.820306ms","start":"2026-06-23T18:46:33.925406Z","end":"2026-06-23T18:46:34.054226Z","steps":["trace[150116191] 'range keys from in-memory index tree' (duration: 128.510208ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:46:34.054487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.795236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-23T18:46:34.054735Z","caller":"traceutil/trace.go:171","msg":"trace[1013069521] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:971; }","duration":"142.079423ms","start":"2026-06-23T18:46:33.912642Z","end":"2026-06-23T18:46:34.054721Z","steps":["trace[1013069521] 'range keys from in-memory index tree' (duration: 141.268473ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:46:34.852478Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4680540691283641170,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-23T18:46:35.722364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"754.602518ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-06-23T18:46:35.72289Z","caller":"traceutil/trace.go:171","msg":"trace[1434699125] linearizableReadLoop","detail":"{readStateIndex:1014; appliedIndex:1012; }","duration":"1.370498695s","start":"2026-06-23T18:46:34.352373Z","end":"2026-06-23T18:46:35.722871Z","steps":["trace[1434699125] 'read index received' (duration: 615.394405ms)","trace[1434699125] 'applied index is now lower than readState.Index' (duration: 755.10343ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T18:46:35.722972Z","caller":"traceutil/trace.go:171","msg":"trace[2138263361] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"1.40002039s","start":"2026-06-23T18:46:34.322902Z","end":"2026-06-23T18:46:35.722923Z","steps":["trace[2138263361] 'process raft request' (duration: 1.399576069s)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:46:35.723094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.37070778s","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-06-23T18:46:35.723181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:46:34.322884Z","time spent":"1.400204324s","remote":"127.0.0.1:40788","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-23T18:46:35.723243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.540527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-23T18:46:35.723301Z","caller":"traceutil/trace.go:171","msg":"trace[1687379879] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:975; }","duration":"535.6395ms","start":"2026-06-23T18:46:35.187648Z","end":"2026-06-23T18:46:35.723287Z","steps":["trace[1687379879] 'agreement among raft nodes before linearized reading' (duration: 535.547708ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:46:35.723209Z","caller":"traceutil/trace.go:171","msg":"trace[989018521] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:975; }","duration":"1.370850094s","start":"2026-06-23T18:46:34.352347Z","end":"2026-06-23T18:46:35.723197Z","steps":["trace[989018521] 'agreement among raft nodes before linearized reading' (duration: 1.370620358s)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:46:35.72334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:46:35.187628Z","time spent":"535.702901ms","remote":"127.0.0.1:40602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":5,"response size":29,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"warn","ts":"2026-06-23T18:46:35.723421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:46:34.352337Z","time spent":"1.371045968s","remote":"127.0.0.1:40788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-23T18:46:35.723558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.145028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-23T18:46:35.723611Z","caller":"traceutil/trace.go:171","msg":"trace[1283187230] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:975; }","duration":"432.24092ms","start":"2026-06-23T18:46:35.291356Z","end":"2026-06-23T18:46:35.723597Z","steps":["trace[1283187230] 'agreement among raft nodes before linearized reading' (duration: 432.166728ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:46:35.72365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:46:35.29133Z","time spent":"432.310832ms","remote":"127.0.0.1:40532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-23T18:46:38.307625Z","caller":"traceutil/trace.go:171","msg":"trace[1985295588] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"102.459967ms","start":"2026-06-23T18:46:38.205135Z","end":"2026-06-23T18:46:38.307595Z","steps":["trace[1985295588] 'process raft request' (duration: 102.282043ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:46:59.817917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"715.582816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-23T18:46:59.818001Z","caller":"traceutil/trace.go:171","msg":"trace[164672027] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:1051; }","duration":"715.73232ms","start":"2026-06-23T18:46:59.10225Z","end":"2026-06-23T18:46:59.817982Z","steps":["trace[164672027] 'count revisions from in-memory index tree' (duration: 715.485095ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:46:59.818017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"904.210871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.142\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-06-23T18:46:59.818043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:46:59.102226Z","time spent":"715.806262ms","remote":"127.0.0.1:40708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":11,"response size":29,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"info","ts":"2026-06-23T18:46:59.818101Z","caller":"traceutil/trace.go:171","msg":"trace[457420559] range","detail":"{range_begin:/registry/masterleases/199.19.213.142; range_end:; response_count:1; response_revision:1051; }","duration":"904.340433ms","start":"2026-06-23T18:46:58.913736Z","end":"2026-06-23T18:46:59.818077Z","steps":["trace[457420559] 'range keys from in-memory index tree' (duration: 904.011966ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:46:59.818143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:46:58.913717Z","time spent":"904.417216ms","remote":"127.0.0.1:40542","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.19.213.142\" "} {"level":"warn","ts":"2026-06-23T18:46:59.818181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.115310911s","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-06-23T18:46:59.81824Z","caller":"traceutil/trace.go:171","msg":"trace[2004398937] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1051; }","duration":"1.115419514s","start":"2026-06-23T18:46:58.702802Z","end":"2026-06-23T18:46:59.818221Z","steps":["trace[2004398937] 'count revisions from in-memory index tree' (duration: 1.115104126s)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:46:59.818281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:46:58.702774Z","time spent":"1.115495036s","remote":"127.0.0.1:40756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":27,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-06-23T18:46:59.818337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.198065327s","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-06-23T18:46:59.818474Z","caller":"traceutil/trace.go:171","msg":"trace[401818605] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1051; }","duration":"1.198259942s","start":"2026-06-23T18:46:58.620189Z","end":"2026-06-23T18:46:59.818449Z","steps":["trace[401818605] 'range keys from in-memory index tree' (duration: 1.197833082s)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:46:59.818533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:46:58.620168Z","time spent":"1.198352584s","remote":"127.0.0.1:40788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-23T18:47:00.0129Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4680540691283641383,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-23T18:47:00.441609Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.134445479s","expected-duration":"1s"} {"level":"info","ts":"2026-06-23T18:47:00.442006Z","caller":"traceutil/trace.go:171","msg":"trace[2146275046] transaction","detail":"{read_only:false; response_revision:1052; number_of_response:1; }","duration":"1.134942311s","start":"2026-06-23T18:46:59.307041Z","end":"2026-06-23T18:47:00.441983Z","steps":["trace[2146275046] 'process raft request' (duration: 1.134767487s)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:47:00.442191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:46:59.307014Z","time spent":"1.135044173s","remote":"127.0.0.1:40788","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-23T18:47:00.790539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:46:59.819172Z","time spent":"971.339934ms","remote":"127.0.0.1:40542","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-06-23T18:47:00.790709Z","caller":"traceutil/trace.go:171","msg":"trace[1383437907] transaction","detail":"{read_only:false; response_revision:1053; number_of_response:1; }","duration":"967.905631ms","start":"2026-06-23T18:46:59.822778Z","end":"2026-06-23T18:47:00.790684Z","steps":["trace[1383437907] 'process raft request' (duration: 967.487731ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:47:00.790854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:46:59.822743Z","time spent":"968.037064ms","remote":"127.0.0.1:40788","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-23T18:47:00.790758Z","caller":"traceutil/trace.go:171","msg":"trace[1940016261] transaction","detail":"{read_only:false; response_revision:1054; number_of_response:1; }","duration":"821.70995ms","start":"2026-06-23T18:46:59.969019Z","end":"2026-06-23T18:47:00.790729Z","steps":["trace[1940016261] 'process raft request' (duration: 821.442824ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:47:00.790973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.278209701s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-23T18:47:00.791034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:46:59.968993Z","time spent":"821.919465ms","remote":"127.0.0.1:40788","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-23T18:47:00.791149Z","caller":"traceutil/trace.go:171","msg":"trace[1810806997] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:1054; }","duration":"1.278404905s","start":"2026-06-23T18:46:59.51267Z","end":"2026-06-23T18:47:00.791075Z","steps":["trace[1810806997] 'agreement among raft nodes before linearized reading' (duration: 1.278144159s)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:47:00.791279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:46:59.512624Z","time spent":"1.27860527s","remote":"127.0.0.1:40720","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":3,"response size":29,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true "} {"level":"info","ts":"2026-06-23T18:47:00.79054Z","caller":"traceutil/trace.go:171","msg":"trace[1229028454] linearizableReadLoop","detail":"{readStateIndex:1098; appliedIndex:1094; }","duration":"1.277772661s","start":"2026-06-23T18:46:59.512743Z","end":"2026-06-23T18:47:00.790516Z","steps":["trace[1229028454] 'read index received' (duration: 929.045255ms)","trace[1229028454] 'applied index is now lower than readState.Index' (duration: 348.726306ms)"],"step_count":2} {"level":"warn","ts":"2026-06-23T18:47:00.791466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.132275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-23T18:47:00.791528Z","caller":"traceutil/trace.go:171","msg":"trace[412812393] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1054; }","duration":"501.227448ms","start":"2026-06-23T18:47:00.290285Z","end":"2026-06-23T18:47:00.791512Z","steps":["trace[412812393] 'agreement among raft nodes before linearized reading' (duration: 501.124085ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:47:00.79153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"724.937399ms","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-06-23T18:47:00.791581Z","caller":"traceutil/trace.go:171","msg":"trace[1973675460] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1054; }","duration":"725.041222ms","start":"2026-06-23T18:47:00.066525Z","end":"2026-06-23T18:47:00.791567Z","steps":["trace[1973675460] 'agreement among raft nodes before linearized reading' (duration: 724.872008ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:47:00.791632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:47:00.066507Z","time spent":"725.113223ms","remote":"127.0.0.1:40788","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-06-23T18:47:00.791739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.766076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-06-23T18:47:00.791791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.141802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-06-23T18:47:00.791797Z","caller":"traceutil/trace.go:171","msg":"trace[1111071280] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1054; }","duration":"278.851277ms","start":"2026-06-23T18:47:00.512931Z","end":"2026-06-23T18:47:00.791782Z","steps":["trace[1111071280] 'agreement among raft nodes before linearized reading' (duration: 278.706364ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:47:00.791826Z","caller":"traceutil/trace.go:171","msg":"trace[556679979] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1054; }","duration":"508.173353ms","start":"2026-06-23T18:47:00.283641Z","end":"2026-06-23T18:47:00.791814Z","steps":["trace[556679979] 'agreement among raft nodes before linearized reading' (duration: 508.081611ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:47:00.791868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:47:00.283622Z","time spent":"508.236775ms","remote":"127.0.0.1:40788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-23T18:47:00.79202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"666.862933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-06-23T18:47:00.792069Z","caller":"traceutil/trace.go:171","msg":"trace[815913520] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1054; }","duration":"666.947795ms","start":"2026-06-23T18:47:00.125109Z","end":"2026-06-23T18:47:00.792056Z","steps":["trace[815913520] 'agreement among raft nodes before linearized reading' (duration: 666.834282ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:47:00.792107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:47:00.125086Z","time spent":"667.010657ms","remote":"127.0.0.1:40788","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-06-23T18:47:00.79245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:47:00.290264Z","time spent":"502.169311ms","remote":"127.0.0.1:40532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-23T18:47:02.942161Z","caller":"traceutil/trace.go:171","msg":"trace[1715637449] transaction","detail":"{read_only:false; response_revision:1060; number_of_response:1; }","duration":"145.485585ms","start":"2026-06-23T18:47:02.796646Z","end":"2026-06-23T18:47:02.942132Z","steps":["trace[1715637449] 'process raft request' (duration: 145.2815ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:47:03.074226Z","caller":"traceutil/trace.go:171","msg":"trace[1359198563] linearizableReadLoop","detail":"{readStateIndex:1105; appliedIndex:1104; }","duration":"232.333268ms","start":"2026-06-23T18:47:02.84187Z","end":"2026-06-23T18:47:03.074204Z","steps":["trace[1359198563] 'read index received' (duration: 100.323086ms)","trace[1359198563] 'applied index is now lower than readState.Index' (duration: 132.009342ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T18:47:03.074305Z","caller":"traceutil/trace.go:171","msg":"trace[1647342351] transaction","detail":"{read_only:false; response_revision:1061; number_of_response:1; }","duration":"275.207871ms","start":"2026-06-23T18:47:02.799079Z","end":"2026-06-23T18:47:03.074287Z","steps":["trace[1647342351] 'process raft request' (duration: 274.990896ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:47:03.074426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.537563ms","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-06-23T18:47:03.074508Z","caller":"traceutil/trace.go:171","msg":"trace[1856104305] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1061; }","duration":"232.643055ms","start":"2026-06-23T18:47:02.841842Z","end":"2026-06-23T18:47:03.074485Z","steps":["trace[1856104305] 'agreement among raft nodes before linearized reading' (duration: 232.475301ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:47:03.074612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.292246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-06-23T18:47:03.074678Z","caller":"traceutil/trace.go:171","msg":"trace[1818644525] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1061; }","duration":"232.394049ms","start":"2026-06-23T18:47:02.842267Z","end":"2026-06-23T18:47:03.074662Z","steps":["trace[1818644525] 'agreement among raft nodes before linearized reading' (duration: 232.170664ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:47:03.075123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.921972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-06-23T18:47:03.075152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.992964ms","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-06-23T18:47:03.075177Z","caller":"traceutil/trace.go:171","msg":"trace[1545707001] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1061; }","duration":"233.015904ms","start":"2026-06-23T18:47:02.842145Z","end":"2026-06-23T18:47:03.075161Z","steps":["trace[1545707001] 'agreement among raft nodes before linearized reading' (duration: 232.307347ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:47:03.075223Z","caller":"traceutil/trace.go:171","msg":"trace[1452154236] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1061; }","duration":"233.131017ms","start":"2026-06-23T18:47:02.842074Z","end":"2026-06-23T18:47:03.075206Z","steps":["trace[1452154236] 'agreement among raft nodes before linearized reading' (duration: 232.330758ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:47:03.287064Z","caller":"traceutil/trace.go:171","msg":"trace[298206936] transaction","detail":"{read_only:false; response_revision:1063; number_of_response:1; }","duration":"207.433384ms","start":"2026-06-23T18:47:03.079598Z","end":"2026-06-23T18:47:03.287031Z","steps":["trace[298206936] 'process raft request' (duration: 137.232837ms)","trace[298206936] 'compare' (duration: 70.033393ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T18:47:03.287118Z","caller":"traceutil/trace.go:171","msg":"trace[1209449801] transaction","detail":"{read_only:false; response_revision:1064; number_of_response:1; }","duration":"206.48221ms","start":"2026-06-23T18:47:03.080619Z","end":"2026-06-23T18:47:03.287101Z","steps":["trace[1209449801] 'process raft request' (duration: 206.354057ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:47:03.287131Z","caller":"traceutil/trace.go:171","msg":"trace[1314770869] transaction","detail":"{read_only:false; response_revision:1065; number_of_response:1; }","duration":"205.931438ms","start":"2026-06-23T18:47:03.081187Z","end":"2026-06-23T18:47:03.287118Z","steps":["trace[1314770869] 'process raft request' (duration: 205.870886ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:47:04.255375Z","caller":"traceutil/trace.go:171","msg":"trace[1944938520] transaction","detail":"{read_only:false; response_revision:1066; number_of_response:1; }","duration":"122.767802ms","start":"2026-06-23T18:47:04.132582Z","end":"2026-06-23T18:47:04.25535Z","steps":["trace[1944938520] 'process raft request' (duration: 122.642359ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:47:04.886892Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4680540691283641416,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-23T18:47:05.521103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.435564ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-06-23T18:47:05.521219Z","caller":"traceutil/trace.go:171","msg":"trace[45832429] linearizableReadLoop","detail":"{readStateIndex:1111; appliedIndex:1110; }","duration":"1.13533644s","start":"2026-06-23T18:47:04.385863Z","end":"2026-06-23T18:47:05.5212Z","steps":["trace[45832429] 'read index received' (duration: 932.835524ms)","trace[45832429] 'applied index is now lower than readState.Index' (duration: 202.498496ms)"],"step_count":2} {"level":"warn","ts":"2026-06-23T18:47:05.521346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.135452123s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-23T18:47:05.521398Z","caller":"traceutil/trace.go:171","msg":"trace[950662631] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:1066; }","duration":"1.135562485s","start":"2026-06-23T18:47:04.385819Z","end":"2026-06-23T18:47:05.521382Z","steps":["trace[950662631] 'agreement among raft nodes before linearized reading' (duration: 1.135460423s)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:47:05.521456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:47:04.385798Z","time spent":"1.135633388s","remote":"127.0.0.1:51546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "} {"level":"warn","ts":"2026-06-23T18:47:05.526873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.875518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-06-23T18:47:05.526888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.159625ms","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-06-23T18:47:05.526907Z","caller":"traceutil/trace.go:171","msg":"trace[1163678007] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1067; }","duration":"235.194116ms","start":"2026-06-23T18:47:05.291708Z","end":"2026-06-23T18:47:05.526902Z","steps":["trace[1163678007] 'agreement among raft nodes before linearized reading' (duration: 235.135265ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:47:05.526868Z","caller":"traceutil/trace.go:171","msg":"trace[1364310465] transaction","detail":"{read_only:false; response_revision:1067; number_of_response:1; }","duration":"579.899617ms","start":"2026-06-23T18:47:04.946934Z","end":"2026-06-23T18:47:05.526834Z","steps":["trace[1364310465] 'process raft request' (duration: 579.60536ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:47:05.52691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.387218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-23T18:47:05.526913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.431687ms","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-06-23T18:47:05.526973Z","caller":"traceutil/trace.go:171","msg":"trace[259378408] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1067; }","duration":"237.492651ms","start":"2026-06-23T18:47:05.289465Z","end":"2026-06-23T18:47:05.526958Z","steps":["trace[259378408] 'agreement among raft nodes before linearized reading' (duration: 237.365508ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:47:05.527053Z","caller":"traceutil/trace.go:171","msg":"trace[378978847] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1067; }","duration":"448.566541ms","start":"2026-06-23T18:47:05.078413Z","end":"2026-06-23T18:47:05.526979Z","steps":["trace[378978847] 'agreement among raft nodes before linearized reading' (duration: 448.331125ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:47:05.527085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.923999ms","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-06-23T18:47:05.527115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.444729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-06-23T18:47:05.52712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:47:05.078394Z","time spent":"448.712215ms","remote":"127.0.0.1:40788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-23T18:47:05.527134Z","caller":"traceutil/trace.go:171","msg":"trace[628171883] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1067; }","duration":"234.993221ms","start":"2026-06-23T18:47:05.292111Z","end":"2026-06-23T18:47:05.527104Z","steps":["trace[628171883] 'agreement among raft nodes before linearized reading' (duration: 234.813996ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:47:05.527154Z","caller":"traceutil/trace.go:171","msg":"trace[630453545] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1067; }","duration":"394.48534ms","start":"2026-06-23T18:47:05.132663Z","end":"2026-06-23T18:47:05.527149Z","steps":["trace[630453545] 'agreement among raft nodes before linearized reading' (duration: 394.413668ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:47:05.526908Z","caller":"traceutil/trace.go:171","msg":"trace[758438134] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1067; }","duration":"234.9419ms","start":"2026-06-23T18:47:05.291957Z","end":"2026-06-23T18:47:05.526899Z","steps":["trace[758438134] 'agreement among raft nodes before linearized reading' (duration: 234.807947ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:47:05.527184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:47:05.132617Z","time spent":"394.562602ms","remote":"127.0.0.1:40788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-23T18:47:05.527455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:47:04.946905Z","time spent":"580.04611ms","remote":"127.0.0.1:40788","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-23T18:47:10.943045Z","caller":"traceutil/trace.go:171","msg":"trace[881025716] transaction","detail":"{read_only:false; response_revision:1086; number_of_response:1; }","duration":"101.710529ms","start":"2026-06-23T18:47:10.841312Z","end":"2026-06-23T18:47:10.943022Z","steps":["trace[881025716] 'process raft request' (duration: 101.541905ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:47:11.748661Z","caller":"traceutil/trace.go:171","msg":"trace[1432440122] transaction","detail":"{read_only:false; response_revision:1090; number_of_response:1; }","duration":"100.723175ms","start":"2026-06-23T18:47:11.647914Z","end":"2026-06-23T18:47:11.748637Z","steps":["trace[1432440122] 'process raft request' (duration: 100.51263ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:47:11.758026Z","caller":"traceutil/trace.go:171","msg":"trace[751666984] transaction","detail":"{read_only:false; response_revision:1091; number_of_response:1; }","duration":"109.043353ms","start":"2026-06-23T18:47:11.648961Z","end":"2026-06-23T18:47:11.758005Z","steps":["trace[751666984] 'process raft request' (duration: 108.835468ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:47:11.758278Z","caller":"traceutil/trace.go:171","msg":"trace[1011056204] transaction","detail":"{read_only:false; response_revision:1092; number_of_response:1; }","duration":"108.831318ms","start":"2026-06-23T18:47:11.649422Z","end":"2026-06-23T18:47:11.758254Z","steps":["trace[1011056204] 'process raft request' (duration: 108.50889ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:47:15.735323Z","caller":"traceutil/trace.go:171","msg":"trace[549458848] linearizableReadLoop","detail":"{readStateIndex:1146; appliedIndex:1145; }","duration":"101.664695ms","start":"2026-06-23T18:47:15.633618Z","end":"2026-06-23T18:47:15.735282Z","steps":["trace[549458848] 'read index received' (duration: 101.46234ms)","trace[549458848] 'applied index is now lower than readState.Index' (duration: 201.435µs)"],"step_count":2} {"level":"info","ts":"2026-06-23T18:47:15.735382Z","caller":"traceutil/trace.go:171","msg":"trace[22623298] transaction","detail":"{read_only:false; response_revision:1100; number_of_response:1; }","duration":"115.333442ms","start":"2026-06-23T18:47:15.620027Z","end":"2026-06-23T18:47:15.735361Z","steps":["trace[22623298] 'process raft request' (duration: 115.109036ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:47:15.7356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.964132ms","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-06-23T18:47:15.735651Z","caller":"traceutil/trace.go:171","msg":"trace[1946187433] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1100; }","duration":"102.078394ms","start":"2026-06-23T18:47:15.633558Z","end":"2026-06-23T18:47:15.735636Z","steps":["trace[1946187433] 'agreement among raft nodes before linearized reading' (duration: 101.91358ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:47:15.886083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.506937ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-23T18:47:15.8862Z","caller":"traceutil/trace.go:171","msg":"trace[1132513732] transaction","detail":"{read_only:false; response_revision:1101; number_of_response:1; }","duration":"146.867028ms","start":"2026-06-23T18:47:15.739306Z","end":"2026-06-23T18:47:15.886173Z","steps":["trace[1132513732] 'process raft request' (duration: 41.189497ms)","trace[1132513732] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/kube-system/kube-controller-manager; req_size:488; } (duration: 105.291472ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T18:47:15.926721Z","caller":"traceutil/trace.go:171","msg":"trace[872774909] linearizableReadLoop","detail":"{readStateIndex:1148; appliedIndex:1146; }","duration":"163.239699ms","start":"2026-06-23T18:47:15.763457Z","end":"2026-06-23T18:47:15.926697Z","steps":["trace[872774909] 'read index received' (duration: 17.040208ms)","trace[872774909] 'applied index is now lower than readState.Index' (duration: 146.198241ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T18:47:15.926756Z","caller":"traceutil/trace.go:171","msg":"trace[1326537290] transaction","detail":"{read_only:false; response_revision:1102; number_of_response:1; }","duration":"186.798474ms","start":"2026-06-23T18:47:15.739935Z","end":"2026-06-23T18:47:15.926733Z","steps":["trace[1326537290] 'process raft request' (duration: 186.63882ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:47:15.926957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.486865ms","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-06-23T18:47:15.927161Z","caller":"traceutil/trace.go:171","msg":"trace[1897021818] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1102; }","duration":"163.729031ms","start":"2026-06-23T18:47:15.763417Z","end":"2026-06-23T18:47:15.927146Z","steps":["trace[1897021818] 'agreement among raft nodes before linearized reading' (duration: 163.401583ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:47:16.144966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.270809ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-06-23T18:47:16.145194Z","caller":"traceutil/trace.go:171","msg":"trace[1885788424] linearizableReadLoop","detail":"{readStateIndex:1149; appliedIndex:1148; }","duration":"218.37454ms","start":"2026-06-23T18:47:15.926789Z","end":"2026-06-23T18:47:16.145164Z","steps":["trace[1885788424] 'read index received' (duration: 67.883076ms)","trace[1885788424] 'applied index is now lower than readState.Index' (duration: 150.488014ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T18:47:16.145223Z","caller":"traceutil/trace.go:171","msg":"trace[76469200] transaction","detail":"{read_only:false; response_revision:1103; number_of_response:1; }","duration":"214.556088ms","start":"2026-06-23T18:47:15.930649Z","end":"2026-06-23T18:47:16.145205Z","steps":["trace[76469200] 'process raft request' (duration: 214.425265ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:47:16.145471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.950557ms","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-06-23T18:47:16.145502Z","caller":"traceutil/trace.go:171","msg":"trace[1710207281] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1103; }","duration":"377.019429ms","start":"2026-06-23T18:47:15.768475Z","end":"2026-06-23T18:47:16.145495Z","steps":["trace[1710207281] 'agreement among raft nodes before linearized reading' (duration: 376.887606ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:47:16.145522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:47:15.768462Z","time spent":"377.05519ms","remote":"127.0.0.1:40788","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-06-23T18:47:16.145681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.88793ms","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-06-23T18:47:16.145789Z","caller":"traceutil/trace.go:171","msg":"trace[700719861] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1103; }","duration":"378.042494ms","start":"2026-06-23T18:47:15.767722Z","end":"2026-06-23T18:47:16.145764Z","steps":["trace[700719861] 'agreement among raft nodes before linearized reading' (duration: 377.718436ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:47:16.145846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:47:15.767707Z","time spent":"378.127635ms","remote":"127.0.0.1:40788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-23T18:47:16.34596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.357816ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-23T18:47:16.346182Z","caller":"traceutil/trace.go:171","msg":"trace[48198809] transaction","detail":"{read_only:false; response_revision:1104; number_of_response:1; }","duration":"193.689659ms","start":"2026-06-23T18:47:16.152465Z","end":"2026-06-23T18:47:16.346155Z","steps":["trace[48198809] 'process raft request' (duration: 29.052066ms)","trace[48198809] 'compare' (duration: 164.162651ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T18:47:16.346462Z","caller":"traceutil/trace.go:171","msg":"trace[687673074] transaction","detail":"{read_only:false; response_revision:1105; number_of_response:1; }","duration":"193.341651ms","start":"2026-06-23T18:47:16.153103Z","end":"2026-06-23T18:47:16.346445Z","steps":["trace[687673074] 'process raft request' (duration: 192.972392ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:47:25.074453Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4680540691283641666,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-23T18:47:25.575323Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4680540691283641666,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-23T18:47:26.076343Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4680540691283641666,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-23T18:47:26.167769Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.730192014s","expected-duration":"1s"} {"level":"info","ts":"2026-06-23T18:47:26.168208Z","caller":"traceutil/trace.go:171","msg":"trace[239394823] transaction","detail":"{read_only:false; response_revision:1181; number_of_response:1; }","duration":"1.730675455s","start":"2026-06-23T18:47:24.437501Z","end":"2026-06-23T18:47:26.168176Z","steps":["trace[239394823] 'process raft request' (duration: 1.730534552s)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:47:26.168357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:47:24.437479Z","time spent":"1.730801119s","remote":"127.0.0.1:40788","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-23T18:47:26.174963Z","caller":"traceutil/trace.go:171","msg":"trace[1699446522] linearizableReadLoop","detail":"{readStateIndex:1235; appliedIndex:1231; }","duration":"1.601346829s","start":"2026-06-23T18:47:24.573598Z","end":"2026-06-23T18:47:26.174945Z","steps":["trace[1699446522] 'read index received' (duration: 1.594394082s)","trace[1699446522] 'applied index is now lower than readState.Index' (duration: 6.952177ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T18:47:26.175232Z","caller":"traceutil/trace.go:171","msg":"trace[598307953] transaction","detail":"{read_only:false; response_revision:1182; number_of_response:1; }","duration":"1.737681994s","start":"2026-06-23T18:47:24.437539Z","end":"2026-06-23T18:47:26.175221Z","steps":["trace[598307953] 'process raft request' (duration: 1.735711927s)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:47:26.175312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:47:24.437522Z","time spent":"1.737734785s","remote":"127.0.0.1:40788","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-23T18:47:26.175435Z","caller":"traceutil/trace.go:171","msg":"trace[905550184] transaction","detail":"{read_only:false; response_revision:1183; number_of_response:1; }","duration":"1.323685819s","start":"2026-06-23T18:47:24.851741Z","end":"2026-06-23T18:47:26.175427Z","steps":["trace[905550184] 'process raft request' (duration: 1.323150076s)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:47:26.175472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:47:24.851721Z","time spent":"1.32373472s","remote":"127.0.0.1:40788","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-23T18:47:26.17554Z","caller":"traceutil/trace.go:171","msg":"trace[692418756] transaction","detail":"{read_only:false; response_revision:1184; number_of_response:1; }","duration":"398.660368ms","start":"2026-06-23T18:47:25.776874Z","end":"2026-06-23T18:47:26.175534Z","steps":["trace[692418756] 'process raft request' (duration: 398.044793ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:47:26.175583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:47:25.776855Z","time spent":"398.700548ms","remote":"127.0.0.1:40788","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-23T18:47:26.175656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.602057495s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-23T18:47:26.175677Z","caller":"traceutil/trace.go:171","msg":"trace[1977079434] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:1184; }","duration":"1.602108007s","start":"2026-06-23T18:47:24.573564Z","end":"2026-06-23T18:47:26.175672Z","steps":["trace[1977079434] 'agreement among raft nodes before linearized reading' (duration: 1.602059006s)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:47:26.175693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:47:24.573548Z","time spent":"1.602140317s","remote":"127.0.0.1:41038","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":29,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-06-23T18:47:26.175952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.270142397s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-23T18:47:26.175988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.929197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-23T18:47:26.176016Z","caller":"traceutil/trace.go:171","msg":"trace[1951244270] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1184; }","duration":"252.990439ms","start":"2026-06-23T18:47:25.92302Z","end":"2026-06-23T18:47:26.176011Z","steps":["trace[1951244270] 'agreement among raft nodes before linearized reading' (duration: 252.905627ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:47:26.175972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.511926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-23T18:47:26.176028Z","caller":"traceutil/trace.go:171","msg":"trace[1997888198] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:1184; }","duration":"1.2702651s","start":"2026-06-23T18:47:24.905744Z","end":"2026-06-23T18:47:26.176009Z","steps":["trace[1997888198] 'agreement among raft nodes before linearized reading' (duration: 1.270126587s)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:47:26.176078Z","caller":"traceutil/trace.go:171","msg":"trace[1489513633] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:1184; }","duration":"553.661969ms","start":"2026-06-23T18:47:25.622387Z","end":"2026-06-23T18:47:26.176049Z","steps":["trace[1489513633] 'agreement among raft nodes before linearized reading' (duration: 553.542056ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:47:26.17608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.46609ms","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-06-23T18:47:26.1761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:47:24.905727Z","time spent":"1.270361242s","remote":"127.0.0.1:57184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"warn","ts":"2026-06-23T18:47:26.176147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:47:25.622356Z","time spent":"553.770652ms","remote":"127.0.0.1:51500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true "} {"level":"info","ts":"2026-06-23T18:47:26.176148Z","caller":"traceutil/trace.go:171","msg":"trace[772294228] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1184; }","duration":"210.550083ms","start":"2026-06-23T18:47:25.965582Z","end":"2026-06-23T18:47:26.176132Z","steps":["trace[772294228] 'agreement among raft nodes before linearized reading' (duration: 210.345678ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:47:42.362573Z","caller":"traceutil/trace.go:171","msg":"trace[535006610] transaction","detail":"{read_only:false; response_revision:1242; number_of_response:1; }","duration":"672.385462ms","start":"2026-06-23T18:47:41.690165Z","end":"2026-06-23T18:47:42.36255Z","steps":["trace[535006610] 'process raft request' (duration: 672.259049ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:47:42.36273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:47:41.690149Z","time spent":"672.494475ms","remote":"127.0.0.1:40788","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-23T18:47:42.45641Z","caller":"traceutil/trace.go:171","msg":"trace[1191101285] linearizableReadLoop","detail":"{readStateIndex:1297; appliedIndex:1295; }","duration":"214.440345ms","start":"2026-06-23T18:47:42.241943Z","end":"2026-06-23T18:47:42.456384Z","steps":["trace[1191101285] 'read index received' (duration: 120.474175ms)","trace[1191101285] 'applied index is now lower than readState.Index' (duration: 93.9652ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T18:47:42.456554Z","caller":"traceutil/trace.go:171","msg":"trace[799679666] transaction","detail":"{read_only:false; response_revision:1243; number_of_response:1; }","duration":"224.302812ms","start":"2026-06-23T18:47:42.232213Z","end":"2026-06-23T18:47:42.456515Z","steps":["trace[799679666] 'process raft request' (duration: 176.728902ms)","trace[799679666] 'compare' (duration: 47.332944ms)"],"step_count":2} {"level":"warn","ts":"2026-06-23T18:47:42.456646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.677611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-23T18:47:42.456703Z","caller":"traceutil/trace.go:171","msg":"trace[1455594107] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1243; }","duration":"214.773924ms","start":"2026-06-23T18:47:42.241908Z","end":"2026-06-23T18:47:42.456682Z","steps":["trace[1455594107] 'agreement among raft nodes before linearized reading' (duration: 214.61473ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:47:42.457287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.740896ms","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-06-23T18:47:42.45733Z","caller":"traceutil/trace.go:171","msg":"trace[286318790] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1243; }","duration":"202.784617ms","start":"2026-06-23T18:47:42.254535Z","end":"2026-06-23T18:47:42.45732Z","steps":["trace[286318790] 'agreement among raft nodes before linearized reading' (duration: 202.689505ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:47:42.457371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.86365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-06-23T18:47:42.457435Z","caller":"traceutil/trace.go:171","msg":"trace[977158715] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1243; }","duration":"200.954012ms","start":"2026-06-23T18:47:42.256464Z","end":"2026-06-23T18:47:42.457418Z","steps":["trace[977158715] 'agreement among raft nodes before linearized reading' (duration: 200.751337ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:47:42.457532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.552296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-06-23T18:47:42.457596Z","caller":"traceutil/trace.go:171","msg":"trace[353293272] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1243; }","duration":"211.665219ms","start":"2026-06-23T18:47:42.245913Z","end":"2026-06-23T18:47:42.457578Z","steps":["trace[353293272] 'agreement among raft nodes before linearized reading' (duration: 211.426263ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:47:42.458014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.183351ms","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-06-23T18:47:42.45806Z","caller":"traceutil/trace.go:171","msg":"trace[566554979] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1243; }","duration":"213.610325ms","start":"2026-06-23T18:47:42.244439Z","end":"2026-06-23T18:47:42.458049Z","steps":["trace[566554979] 'agreement among raft nodes before linearized reading' (duration: 212.701244ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:47:44.602438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.913228ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-06-23T18:47:44.603394Z","caller":"traceutil/trace.go:171","msg":"trace[1300757865] transaction","detail":"{read_only:false; response_revision:1249; number_of_response:1; }","duration":"141.378066ms","start":"2026-06-23T18:47:44.461986Z","end":"2026-06-23T18:47:44.603364Z","steps":["trace[1300757865] 'process raft request' (duration: 140.522815ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:47:44.603452Z","caller":"traceutil/trace.go:171","msg":"trace[442111743] linearizableReadLoop","detail":"{readStateIndex:1304; appliedIndex:1302; }","duration":"123.197631ms","start":"2026-06-23T18:47:44.480237Z","end":"2026-06-23T18:47:44.603434Z","steps":["trace[442111743] 'read index received' (duration: 90.026246ms)","trace[442111743] 'applied index is now lower than readState.Index' (duration: 33.170065ms)"],"step_count":2} {"level":"warn","ts":"2026-06-23T18:47:44.666641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.145747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-06-23T18:47:44.666712Z","caller":"traceutil/trace.go:171","msg":"trace[689063618] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1249; }","duration":"125.24738ms","start":"2026-06-23T18:47:44.541447Z","end":"2026-06-23T18:47:44.666695Z","steps":["trace[689063618] 'agreement among raft nodes before linearized reading' (duration: 62.19106ms)","trace[689063618] 'range keys from in-memory index tree' (duration: 62.842526ms)"],"step_count":2} {"level":"warn","ts":"2026-06-23T18:47:44.666787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.365452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-23T18:47:44.666862Z","caller":"traceutil/trace.go:171","msg":"trace[1061441660] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1249; }","duration":"125.460404ms","start":"2026-06-23T18:47:44.541379Z","end":"2026-06-23T18:47:44.66684Z","steps":["trace[1061441660] 'agreement among raft nodes before linearized reading' (duration: 62.302872ms)","trace[1061441660] 'range keys from in-memory index tree' (duration: 62.992369ms)"],"step_count":2} {"level":"warn","ts":"2026-06-23T18:47:44.666921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.16197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-23T18:47:44.666949Z","caller":"traceutil/trace.go:171","msg":"trace[2004981518] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:1249; }","duration":"113.211892ms","start":"2026-06-23T18:47:44.553725Z","end":"2026-06-23T18:47:44.666937Z","steps":["trace[2004981518] 'agreement among raft nodes before linearized reading' (duration: 49.944136ms)","trace[2004981518] 'count revisions from in-memory index tree' (duration: 63.229495ms)"],"step_count":2} {"level":"warn","ts":"2026-06-23T18:47:44.666957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.606898ms","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-06-23T18:47:44.667004Z","caller":"traceutil/trace.go:171","msg":"trace[289429497] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1249; }","duration":"125.67982ms","start":"2026-06-23T18:47:44.541299Z","end":"2026-06-23T18:47:44.666979Z","steps":["trace[289429497] 'agreement among raft nodes before linearized reading' (duration: 62.2164ms)","trace[289429497] 'range keys from in-memory index tree' (duration: 63.371388ms)"],"step_count":2} {"level":"warn","ts":"2026-06-23T18:47:44.667038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.599948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-06-23T18:47:44.667069Z","caller":"traceutil/trace.go:171","msg":"trace[1765061384] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1249; }","duration":"125.64851ms","start":"2026-06-23T18:47:44.541411Z","end":"2026-06-23T18:47:44.66706Z","steps":["trace[1765061384] 'agreement among raft nodes before linearized reading' (duration: 62.279932ms)","trace[1765061384] 'range keys from in-memory index tree' (duration: 63.273266ms)"],"step_count":2} {"level":"warn","ts":"2026-06-23T18:47:44.667184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.924807ms","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-06-23T18:47:44.667325Z","caller":"traceutil/trace.go:171","msg":"trace[1476424988] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1249; }","duration":"187.090741ms","start":"2026-06-23T18:47:44.4802Z","end":"2026-06-23T18:47:44.66729Z","steps":["trace[1476424988] 'agreement among raft nodes before linearized reading' (duration: 123.303813ms)","trace[1476424988] 'range keys from in-memory index tree' (duration: 63.544432ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T18:47:44.937796Z","caller":"traceutil/trace.go:171","msg":"trace[266633153] transaction","detail":"{read_only:false; response_revision:1252; number_of_response:1; }","duration":"267.316262ms","start":"2026-06-23T18:47:44.670449Z","end":"2026-06-23T18:47:44.937765Z","steps":["trace[266633153] 'process raft request' (duration: 167.86371ms)","trace[266633153] 'compare' (duration: 99.201936ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T18:47:44.937896Z","caller":"traceutil/trace.go:171","msg":"trace[1531531796] transaction","detail":"{read_only:false; response_revision:1254; number_of_response:1; }","duration":"266.780569ms","start":"2026-06-23T18:47:44.671109Z","end":"2026-06-23T18:47:44.93789Z","steps":["trace[1531531796] 'process raft request' (duration: 266.751998ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:47:44.938145Z","caller":"traceutil/trace.go:171","msg":"trace[240419375] transaction","detail":"{read_only:false; response_revision:1253; number_of_response:1; }","duration":"267.374663ms","start":"2026-06-23T18:47:44.670739Z","end":"2026-06-23T18:47:44.938114Z","steps":["trace[240419375] 'process raft request' (duration: 266.998474ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:47:48.037689Z","caller":"traceutil/trace.go:171","msg":"trace[195181748] transaction","detail":"{read_only:false; response_revision:1272; number_of_response:1; }","duration":"200.047321ms","start":"2026-06-23T18:47:47.837617Z","end":"2026-06-23T18:47:48.037664Z","steps":["trace[195181748] 'process raft request' (duration: 199.895117ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:47:49.078353Z","caller":"traceutil/trace.go:171","msg":"trace[647040291] linearizableReadLoop","detail":"{readStateIndex:1333; appliedIndex:1332; }","duration":"124.096382ms","start":"2026-06-23T18:47:48.954238Z","end":"2026-06-23T18:47:49.078335Z","steps":["trace[647040291] 'read index received' (duration: 114.811289ms)","trace[647040291] 'applied index is now lower than readState.Index' (duration: 9.284123ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T18:47:49.078441Z","caller":"traceutil/trace.go:171","msg":"trace[300746260] transaction","detail":"{read_only:false; response_revision:1277; number_of_response:1; }","duration":"144.808707ms","start":"2026-06-23T18:47:48.933596Z","end":"2026-06-23T18:47:49.078405Z","steps":["trace[300746260] 'process raft request' (duration: 135.555346ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:47:49.078618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.240456ms","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-06-23T18:47:49.07888Z","caller":"traceutil/trace.go:171","msg":"trace[578975699] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1277; }","duration":"124.535322ms","start":"2026-06-23T18:47:48.954333Z","end":"2026-06-23T18:47:49.078868Z","steps":["trace[578975699] 'agreement among raft nodes before linearized reading' (duration: 124.111242ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:47:49.078668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.40532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-06-23T18:47:49.07872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.118742ms","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-06-23T18:47:49.07898Z","caller":"traceutil/trace.go:171","msg":"trace[369843666] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1277; }","duration":"124.751347ms","start":"2026-06-23T18:47:48.954195Z","end":"2026-06-23T18:47:49.078946Z","steps":["trace[369843666] 'agreement among raft nodes before linearized reading' (duration: 124.223825ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:47:49.079084Z","caller":"traceutil/trace.go:171","msg":"trace[1933416823] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1277; }","duration":"124.508532ms","start":"2026-06-23T18:47:48.954539Z","end":"2026-06-23T18:47:49.079048Z","steps":["trace[1933416823] 'agreement among raft nodes before linearized reading' (duration: 124.00594ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:47:55.317925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.522265ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"warn","ts":"2026-06-23T18:47:55.318491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"703.98739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-23T18:47:55.318599Z","caller":"traceutil/trace.go:171","msg":"trace[798364165] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:1314; }","duration":"704.120263ms","start":"2026-06-23T18:47:54.614464Z","end":"2026-06-23T18:47:55.318585Z","steps":["trace[798364165] 'agreement among raft nodes before linearized reading' (duration: 703.952449ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:47:55.318718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:47:54.614416Z","time spent":"704.286427ms","remote":"127.0.0.1:40888","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":29,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"info","ts":"2026-06-23T18:47:55.318906Z","caller":"traceutil/trace.go:171","msg":"trace[240288657] linearizableReadLoop","detail":"{readStateIndex:1371; appliedIndex:1370; }","duration":"703.819945ms","start":"2026-06-23T18:47:54.614472Z","end":"2026-06-23T18:47:55.318292Z","steps":["trace[240288657] 'read index received' (duration: 443.868619ms)","trace[240288657] 'applied index is now lower than readState.Index' (duration: 259.892084ms)"],"step_count":2} {"level":"warn","ts":"2026-06-23T18:47:55.325145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.80091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-23T18:47:55.325276Z","caller":"traceutil/trace.go:171","msg":"trace[51188596] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1315; }","duration":"466.954443ms","start":"2026-06-23T18:47:54.858304Z","end":"2026-06-23T18:47:55.325258Z","steps":["trace[51188596] 'agreement among raft nodes before linearized reading' (duration: 466.646825ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:47:55.325405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:47:54.858288Z","time spent":"467.096097ms","remote":"127.0.0.1:40788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-23T18:47:55.325495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.417303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-06-23T18:47:55.325533Z","caller":"traceutil/trace.go:171","msg":"trace[753843964] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1315; }","duration":"211.496525ms","start":"2026-06-23T18:47:55.114026Z","end":"2026-06-23T18:47:55.325523Z","steps":["trace[753843964] 'agreement among raft nodes before linearized reading' (duration: 211.394493ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:47:55.325149Z","caller":"traceutil/trace.go:171","msg":"trace[967351915] transaction","detail":"{read_only:false; response_revision:1315; number_of_response:1; }","duration":"589.474527ms","start":"2026-06-23T18:47:54.735604Z","end":"2026-06-23T18:47:55.325078Z","steps":["trace[967351915] 'process raft request' (duration: 589.033727ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:47:55.325639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.971398ms","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-06-23T18:47:55.32566Z","caller":"traceutil/trace.go:171","msg":"trace[1078349324] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1315; }","duration":"458.014719ms","start":"2026-06-23T18:47:54.867639Z","end":"2026-06-23T18:47:55.325653Z","steps":["trace[1078349324] 'agreement among raft nodes before linearized reading' (duration: 457.952608ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:47:55.325686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:47:54.867631Z","time spent":"458.04936ms","remote":"127.0.0.1:40788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-23T18:47:55.325761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:47:54.735573Z","time spent":"590.060511ms","remote":"127.0.0.1:40788","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-23T18:47:55.325797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.752356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-23T18:47:55.325829Z","caller":"traceutil/trace.go:171","msg":"trace[2040842598] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1315; }","duration":"207.806276ms","start":"2026-06-23T18:47:55.118015Z","end":"2026-06-23T18:47:55.325822Z","steps":["trace[2040842598] 'agreement among raft nodes before linearized reading' (duration: 207.381596ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:47:55.326104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.591984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-06-23T18:47:55.326154Z","caller":"traceutil/trace.go:171","msg":"trace[1487970567] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1315; }","duration":"210.681016ms","start":"2026-06-23T18:47:55.115459Z","end":"2026-06-23T18:47:55.32614Z","steps":["trace[1487970567] 'agreement among raft nodes before linearized reading' (duration: 209.944758ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:47:55.856494Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4680540691283641960,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-23T18:47:56.013467Z","caller":"traceutil/trace.go:171","msg":"trace[1275200891] transaction","detail":"{read_only:false; response_revision:1316; number_of_response:1; }","duration":"682.721799ms","start":"2026-06-23T18:47:55.330717Z","end":"2026-06-23T18:47:56.013438Z","steps":["trace[1275200891] 'process raft request' (duration: 682.551435ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:47:56.013607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:47:55.330701Z","time spent":"682.839633ms","remote":"127.0.0.1:40788","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-23T18:47:56.016769Z","caller":"traceutil/trace.go:171","msg":"trace[1474625978] transaction","detail":"{read_only:false; response_revision:1317; number_of_response:1; }","duration":"685.572348ms","start":"2026-06-23T18:47:55.331158Z","end":"2026-06-23T18:47:56.016731Z","steps":["trace[1474625978] 'process raft request' (duration: 685.353293ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:47:56.016903Z","caller":"traceutil/trace.go:171","msg":"trace[1017406575] linearizableReadLoop","detail":"{readStateIndex:1377; appliedIndex:1372; }","duration":"660.551559ms","start":"2026-06-23T18:47:55.35633Z","end":"2026-06-23T18:47:56.016882Z","steps":["trace[1017406575] 'read index received' (duration: 656.917742ms)","trace[1017406575] 'applied index is now lower than readState.Index' (duration: 3.632627ms)"],"step_count":2} {"level":"warn","ts":"2026-06-23T18:47:56.01694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:47:55.331146Z","time spent":"685.698881ms","remote":"127.0.0.1:40788","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-23T18:47:56.016973Z","caller":"traceutil/trace.go:171","msg":"trace[836254605] transaction","detail":"{read_only:false; response_revision:1318; number_of_response:1; }","duration":"685.528447ms","start":"2026-06-23T18:47:55.331439Z","end":"2026-06-23T18:47:56.016968Z","steps":["trace[836254605] 'process raft request' (duration: 685.164018ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:47:56.017122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"660.776744ms","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-06-23T18:47:56.017182Z","caller":"traceutil/trace.go:171","msg":"trace[47572051] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1320; }","duration":"660.883587ms","start":"2026-06-23T18:47:55.356283Z","end":"2026-06-23T18:47:56.017167Z","steps":["trace[47572051] 'agreement among raft nodes before linearized reading' (duration: 660.695632ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:47:56.017232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:47:55.356262Z","time spent":"660.958579ms","remote":"127.0.0.1:40788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":496,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-06-23T18:47:56.016944Z","caller":"traceutil/trace.go:171","msg":"trace[1924777731] transaction","detail":"{read_only:false; response_revision:1320; number_of_response:1; }","duration":"684.754829ms","start":"2026-06-23T18:47:55.332178Z","end":"2026-06-23T18:47:56.016933Z","steps":["trace[1924777731] 'process raft request' (duration: 684.643297ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:47:56.017563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:47:55.332171Z","time spent":"685.328643ms","remote":"127.0.0.1:40788","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-23T18:47:56.017869Z","caller":"traceutil/trace.go:171","msg":"trace[72608362] transaction","detail":"{read_only:false; response_revision:1319; number_of_response:1; }","duration":"685.946757ms","start":"2026-06-23T18:47:55.331448Z","end":"2026-06-23T18:47:56.017395Z","steps":["trace[72608362] 'process raft request' (duration: 685.317042ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:47:56.019239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:47:55.331431Z","time spent":"686.025369ms","remote":"127.0.0.1:40788","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-23T18:47:56.019616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:47:55.331427Z","time spent":"687.444393ms","remote":"127.0.0.1:40788","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-23T18:47:57.507903Z","caller":"traceutil/trace.go:171","msg":"trace[2144907291] transaction","detail":"{read_only:false; response_revision:1335; number_of_response:1; }","duration":"283.402907ms","start":"2026-06-23T18:47:57.224464Z","end":"2026-06-23T18:47:57.507867Z","steps":["trace[2144907291] 'process raft request' (duration: 283.179101ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:47:57.512468Z","caller":"traceutil/trace.go:171","msg":"trace[1832589753] linearizableReadLoop","detail":"{readStateIndex:1393; appliedIndex:1391; }","duration":"219.841504ms","start":"2026-06-23T18:47:57.292597Z","end":"2026-06-23T18:47:57.512439Z","steps":["trace[1832589753] 'read index received' (duration: 215.004798ms)","trace[1832589753] 'applied index is now lower than readState.Index' (duration: 4.835456ms)"],"step_count":2} {"level":"warn","ts":"2026-06-23T18:47:57.512779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.149112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-23T18:47:57.512875Z","caller":"traceutil/trace.go:171","msg":"trace[1285635119] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1337; }","duration":"220.299186ms","start":"2026-06-23T18:47:57.292553Z","end":"2026-06-23T18:47:57.512852Z","steps":["trace[1285635119] 'agreement among raft nodes before linearized reading' (duration: 220.108701ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:47:57.512949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.721814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-06-23T18:47:57.512996Z","caller":"traceutil/trace.go:171","msg":"trace[432220721] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1337; }","duration":"189.804305ms","start":"2026-06-23T18:47:57.323176Z","end":"2026-06-23T18:47:57.51298Z","steps":["trace[432220721] 'agreement among raft nodes before linearized reading' (duration: 189.652211ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:47:57.513196Z","caller":"traceutil/trace.go:171","msg":"trace[1593540798] transaction","detail":"{read_only:false; response_revision:1336; number_of_response:1; }","duration":"287.167357ms","start":"2026-06-23T18:47:57.226016Z","end":"2026-06-23T18:47:57.513183Z","steps":["trace[1593540798] 'process raft request' (duration: 286.186733ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:47:57.513268Z","caller":"traceutil/trace.go:171","msg":"trace[2075910755] transaction","detail":"{read_only:false; response_revision:1337; number_of_response:1; }","duration":"180.436721ms","start":"2026-06-23T18:47:57.332798Z","end":"2026-06-23T18:47:57.513235Z","steps":["trace[2075910755] 'process raft request' (duration: 179.584561ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:48:42.501326Z","caller":"traceutil/trace.go:171","msg":"trace[184677338] transaction","detail":"{read_only:false; response_revision:1548; number_of_response:1; }","duration":"145.333187ms","start":"2026-06-23T18:48:42.355278Z","end":"2026-06-23T18:48:42.500611Z","steps":["trace[184677338] 'process raft request' (duration: 140.708786ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:48:59.31431Z","caller":"traceutil/trace.go:171","msg":"trace[526726984] transaction","detail":"{read_only:false; response_revision:1627; number_of_response:1; }","duration":"207.230382ms","start":"2026-06-23T18:48:59.107045Z","end":"2026-06-23T18:48:59.314275Z","steps":["trace[526726984] 'process raft request' (duration: 207.017927ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:49:02.570761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.239464ms","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-06-23T18:49:02.570835Z","caller":"traceutil/trace.go:171","msg":"trace[1754711206] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1638; }","duration":"140.354567ms","start":"2026-06-23T18:49:02.430463Z","end":"2026-06-23T18:49:02.570817Z","steps":["trace[1754711206] 'range keys from in-memory index tree' (duration: 140.088501ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:49:02.685449Z","caller":"traceutil/trace.go:171","msg":"trace[291300524] transaction","detail":"{read_only:false; response_revision:1639; number_of_response:1; }","duration":"109.598369ms","start":"2026-06-23T18:49:02.575822Z","end":"2026-06-23T18:49:02.68542Z","steps":["trace[291300524] 'process raft request' (duration: 109.445426ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:49:02.796728Z","caller":"traceutil/trace.go:171","msg":"trace[660831226] transaction","detail":"{read_only:false; response_revision:1642; number_of_response:1; }","duration":"106.628498ms","start":"2026-06-23T18:49:02.690078Z","end":"2026-06-23T18:49:02.796706Z","steps":["trace[660831226] 'process raft request' (duration: 106.541316ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:49:02.796792Z","caller":"traceutil/trace.go:171","msg":"trace[424626258] transaction","detail":"{read_only:false; response_revision:1643; number_of_response:1; }","duration":"106.568247ms","start":"2026-06-23T18:49:02.690183Z","end":"2026-06-23T18:49:02.796751Z","steps":["trace[424626258] 'process raft request' (duration: 106.470064ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:49:02.796823Z","caller":"traceutil/trace.go:171","msg":"trace[49650270] transaction","detail":"{read_only:false; response_revision:1641; number_of_response:1; }","duration":"106.959347ms","start":"2026-06-23T18:49:02.689836Z","end":"2026-06-23T18:49:02.796795Z","steps":["trace[49650270] 'process raft request' (duration: 105.520672ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:49:43.245114Z","caller":"traceutil/trace.go:171","msg":"trace[2030710958] linearizableReadLoop","detail":"{readStateIndex:1905; appliedIndex:1903; }","duration":"128.344887ms","start":"2026-06-23T18:49:43.11674Z","end":"2026-06-23T18:49:43.245085Z","steps":["trace[2030710958] 'read index received' (duration: 70.581428ms)","trace[2030710958] 'applied index is now lower than readState.Index' (duration: 57.761749ms)"],"step_count":2} {"level":"warn","ts":"2026-06-23T18:49:43.245413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.655284ms","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-06-23T18:49:43.245464Z","caller":"traceutil/trace.go:171","msg":"trace[250332881] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1827; }","duration":"128.771487ms","start":"2026-06-23T18:49:43.116678Z","end":"2026-06-23T18:49:43.24545Z","steps":["trace[250332881] 'agreement among raft nodes before linearized reading' (duration: 128.542042ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:49:43.245952Z","caller":"traceutil/trace.go:171","msg":"trace[971879386] transaction","detail":"{read_only:false; response_revision:1827; number_of_response:1; }","duration":"131.263537ms","start":"2026-06-23T18:49:43.114009Z","end":"2026-06-23T18:49:43.245272Z","steps":["trace[971879386] 'process raft request' (duration: 109.7972ms)","trace[971879386] 'compare' (duration: 21.118188ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T18:50:57.977774Z","caller":"traceutil/trace.go:171","msg":"trace[977113498] transaction","detail":"{read_only:false; response_revision:2166; number_of_response:1; }","duration":"240.658622ms","start":"2026-06-23T18:50:57.73709Z","end":"2026-06-23T18:50:57.977748Z","steps":["trace[977113498] 'process raft request' (duration: 240.486118ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:50:58.027925Z","caller":"traceutil/trace.go:171","msg":"trace[1713525477] linearizableReadLoop","detail":"{readStateIndex:2260; appliedIndex:2258; }","duration":"242.886136ms","start":"2026-06-23T18:50:57.78501Z","end":"2026-06-23T18:50:58.027896Z","steps":["trace[1713525477] 'read index received' (duration: 192.534336ms)","trace[1713525477] 'applied index is now lower than readState.Index' (duration: 50.35056ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T18:50:58.028042Z","caller":"traceutil/trace.go:171","msg":"trace[1497808904] transaction","detail":"{read_only:false; response_revision:2167; number_of_response:1; }","duration":"290.901319ms","start":"2026-06-23T18:50:57.737095Z","end":"2026-06-23T18:50:58.027997Z","steps":["trace[1497808904] 'process raft request' (duration: 290.598342ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:50:58.028226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.172333ms","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-06-23T18:50:58.028303Z","caller":"traceutil/trace.go:171","msg":"trace[1076837366] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2167; }","duration":"243.304726ms","start":"2026-06-23T18:50:57.784979Z","end":"2026-06-23T18:50:58.028283Z","steps":["trace[1076837366] 'agreement among raft nodes before linearized reading' (duration: 243.04058ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:50:58.028368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.03147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-06-23T18:50:58.028475Z","caller":"traceutil/trace.go:171","msg":"trace[627944498] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2167; }","duration":"243.192353ms","start":"2026-06-23T18:50:57.785262Z","end":"2026-06-23T18:50:58.028454Z","steps":["trace[627944498] 'agreement among raft nodes before linearized reading' (duration: 242.809834ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:51:26.953925Z","caller":"traceutil/trace.go:171","msg":"trace[1895529240] transaction","detail":"{read_only:false; response_revision:2301; number_of_response:1; }","duration":"128.377235ms","start":"2026-06-23T18:51:26.825508Z","end":"2026-06-23T18:51:26.953885Z","steps":["trace[1895529240] 'process raft request' (duration: 80.504544ms)","trace[1895529240] 'compare' (duration: 47.710497ms)"],"step_count":2} {"level":"warn","ts":"2026-06-23T18:51:58.54539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.368158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-06-23T18:51:58.545453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.552177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-06-23T18:51:58.545512Z","caller":"traceutil/trace.go:171","msg":"trace[1050133356] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2469; }","duration":"301.578294ms","start":"2026-06-23T18:51:58.243908Z","end":"2026-06-23T18:51:58.545486Z","steps":["trace[1050133356] 'range keys from in-memory index tree' (duration: 301.079122ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:51:58.545552Z","caller":"traceutil/trace.go:171","msg":"trace[611529059] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2469; }","duration":"171.6547ms","start":"2026-06-23T18:51:58.373862Z","end":"2026-06-23T18:51:58.545517Z","steps":["trace[611529059] 'range keys from in-memory index tree' (duration: 171.374073ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:51:58.54542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.680697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-23T18:51:58.545818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:51:58.243889Z","time spent":"301.907452ms","remote":"127.0.0.1:40788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-06-23T18:51:58.545883Z","caller":"traceutil/trace.go:171","msg":"trace[1073753471] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2469; }","duration":"256.007825ms","start":"2026-06-23T18:51:58.28967Z","end":"2026-06-23T18:51:58.545678Z","steps":["trace[1073753471] 'range keys from in-memory index tree' (duration: 255.564604ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:51:58.666168Z","caller":"traceutil/trace.go:171","msg":"trace[671087524] transaction","detail":"{read_only:false; response_revision:2470; number_of_response:1; }","duration":"116.152822ms","start":"2026-06-23T18:51:58.549985Z","end":"2026-06-23T18:51:58.666138Z","steps":["trace[671087524] 'process raft request' (duration: 115.986968ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:51:58.674888Z","caller":"traceutil/trace.go:171","msg":"trace[1767720785] transaction","detail":"{read_only:false; response_revision:2471; number_of_response:1; }","duration":"124.555336ms","start":"2026-06-23T18:51:58.55031Z","end":"2026-06-23T18:51:58.674866Z","steps":["trace[1767720785] 'process raft request' (duration: 124.419762ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:51:58.675226Z","caller":"traceutil/trace.go:171","msg":"trace[1753228273] transaction","detail":"{read_only:false; response_revision:2472; number_of_response:1; }","duration":"124.560896ms","start":"2026-06-23T18:51:58.55052Z","end":"2026-06-23T18:51:58.67508Z","steps":["trace[1753228273] 'process raft request' (duration: 124.30912ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:51:58.675361Z","caller":"traceutil/trace.go:171","msg":"trace[1484658928] transaction","detail":"{read_only:false; response_revision:2473; number_of_response:1; }","duration":"124.664968ms","start":"2026-06-23T18:51:58.550666Z","end":"2026-06-23T18:51:58.675331Z","steps":["trace[1484658928] 'process raft request' (duration: 124.363381ms)"],"step_count":1}