{"level":"warn","ts":"2026-04-21T18:15:48.459099Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-21T18:15:48.459285Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.108: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.108:2380","--initial-cluster=instance=https://199.19.213.108:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.108:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.108: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-04-21T18:15:48.459402Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-21T18:15:48.459419Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.108:2380"]} {"level":"info","ts":"2026-04-21T18:15:48.459451Z","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-04-21T18:15:48.46035Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.108:2379"]} {"level":"info","ts":"2026-04-21T18:15:48.460482Z","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.108:2380"],"listen-peer-urls":["https://199.19.213.108:2380"],"advertise-client-urls":["https://199.19.213.108:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.108:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.108: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-04-21T18:15:48.466904Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.054751ms"} {"level":"info","ts":"2026-04-21T18:15:48.475612Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"e9047e95cdba2056","cluster-id":"51f5e218724a48a2"} {"level":"info","ts":"2026-04-21T18:15:48.475702Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e9047e95cdba2056 switched to configuration voters=()"} {"level":"info","ts":"2026-04-21T18:15:48.475731Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e9047e95cdba2056 became follower at term 0"} {"level":"info","ts":"2026-04-21T18:15:48.475741Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft e9047e95cdba2056 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-21T18:15:48.475751Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e9047e95cdba2056 became follower at term 1"} {"level":"info","ts":"2026-04-21T18:15:48.475798Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e9047e95cdba2056 switched to configuration voters=(16790684492610805846)"} {"level":"warn","ts":"2026-04-21T18:15:48.48331Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-21T18:15:48.486416Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-21T18:15:48.489575Z","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-04-21T18:15:48.493846Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"e9047e95cdba2056","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-21T18:15:48.495016Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"e9047e95cdba2056","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-21T18:15:48.49513Z","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-04-21T18:15:48.496318Z","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-04-21T18:15:48.496334Z","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-04-21T18:15:48.497071Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e9047e95cdba2056 switched to configuration voters=(16790684492610805846)"} {"level":"info","ts":"2026-04-21T18:15:48.497357Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"51f5e218724a48a2","local-member-id":"e9047e95cdba2056","added-peer-id":"e9047e95cdba2056","added-peer-peer-urls":["https://199.19.213.108:2380"]} {"level":"info","ts":"2026-04-21T18:15:48.500796Z","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-04-21T18:15:48.500873Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.108:2380"} {"level":"info","ts":"2026-04-21T18:15:48.50093Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.108:2380"} {"level":"info","ts":"2026-04-21T18:15:48.501419Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"e9047e95cdba2056","initial-advertise-peer-urls":["https://199.19.213.108:2380"],"listen-peer-urls":["https://199.19.213.108:2380"],"advertise-client-urls":["https://199.19.213.108:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.108:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-21T18:15:48.501509Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-21T18:15:48.876814Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e9047e95cdba2056 is starting a new election at term 1"} {"level":"info","ts":"2026-04-21T18:15:48.87691Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e9047e95cdba2056 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-21T18:15:48.876969Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e9047e95cdba2056 received MsgPreVoteResp from e9047e95cdba2056 at term 1"} {"level":"info","ts":"2026-04-21T18:15:48.877Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e9047e95cdba2056 became candidate at term 2"} {"level":"info","ts":"2026-04-21T18:15:48.877021Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e9047e95cdba2056 received MsgVoteResp from e9047e95cdba2056 at term 2"} {"level":"info","ts":"2026-04-21T18:15:48.877119Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e9047e95cdba2056 became leader at term 2"} {"level":"info","ts":"2026-04-21T18:15:48.877145Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: e9047e95cdba2056 elected leader e9047e95cdba2056 at term 2"} {"level":"info","ts":"2026-04-21T18:15:48.878585Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"e9047e95cdba2056","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.108:2379]}","request-path":"/0/members/e9047e95cdba2056/attributes","cluster-id":"51f5e218724a48a2","publish-timeout":"7s"} {"level":"info","ts":"2026-04-21T18:15:48.878641Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-21T18:15:48.879044Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-21T18:15:48.879275Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-21T18:15:48.879491Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-21T18:15:48.879521Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-21T18:15:48.883389Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.108:2379"} {"level":"info","ts":"2026-04-21T18:15:48.883716Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-21T18:15:48.888422Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"51f5e218724a48a2","local-member-id":"e9047e95cdba2056","cluster-version":"3.5"} {"level":"info","ts":"2026-04-21T18:15:48.888573Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-21T18:15:48.888621Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-21T18:15:54.079965Z","caller":"traceutil/trace.go:171","msg":"trace[35747427] transaction","detail":"{read_only:false; response_revision:245; number_of_response:1; }","duration":"104.002527ms","start":"2026-04-21T18:15:53.975926Z","end":"2026-04-21T18:15:54.079929Z","steps":["trace[35747427] 'process raft request' (duration: 103.795292ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:15:54.138514Z","caller":"traceutil/trace.go:171","msg":"trace[152219206] transaction","detail":"{read_only:false; number_of_response:0; response_revision:245; }","duration":"162.16262ms","start":"2026-04-21T18:15:53.976315Z","end":"2026-04-21T18:15:54.138478Z","steps":["trace[152219206] 'process raft request' (duration: 154.905075ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:15:54.140204Z","caller":"traceutil/trace.go:171","msg":"trace[29698231] transaction","detail":"{read_only:false; response_revision:247; number_of_response:1; }","duration":"112.821806ms","start":"2026-04-21T18:15:54.026094Z","end":"2026-04-21T18:15:54.138916Z","steps":["trace[29698231] 'process raft request' (duration: 112.384394ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:15:54.140765Z","caller":"traceutil/trace.go:171","msg":"trace[709742688] transaction","detail":"{read_only:false; response_revision:246; number_of_response:1; }","duration":"163.502689ms","start":"2026-04-21T18:15:53.977016Z","end":"2026-04-21T18:15:54.140519Z","steps":["trace[709742688] 'process raft request' (duration: 161.348558ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:15:54.648949Z","caller":"traceutil/trace.go:171","msg":"trace[431961469] linearizableReadLoop","detail":"{readStateIndex:255; appliedIndex:254; }","duration":"499.564388ms","start":"2026-04-21T18:15:54.149335Z","end":"2026-04-21T18:15:54.648899Z","steps":["trace[431961469] 'read index received' (duration: 494.854935ms)","trace[431961469] 'applied index is now lower than readState.Index' (duration: 4.705583ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:15:54.649045Z","caller":"traceutil/trace.go:171","msg":"trace[1492021180] transaction","detail":"{read_only:false; response_revision:248; number_of_response:1; }","duration":"499.735174ms","start":"2026-04-21T18:15:54.149254Z","end":"2026-04-21T18:15:54.648989Z","steps":["trace[1492021180] 'process raft request' (duration: 494.917628ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:15:54.649295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"499.868578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/resourcequota-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-04-21T18:15:54.649406Z","caller":"traceutil/trace.go:171","msg":"trace[1331584639] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/resourcequota-controller; range_end:; response_count:1; response_revision:248; }","duration":"500.093174ms","start":"2026-04-21T18:15:54.149293Z","end":"2026-04-21T18:15:54.649386Z","steps":["trace[1331584639] 'agreement among raft nodes before linearized reading' (duration: 499.787596ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:15:54.649461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:15:54.149272Z","time spent":"500.174816ms","remote":"127.0.0.1:57810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":237,"request content":"key:\"/registry/serviceaccounts/kube-system/resourcequota-controller\" "} {"level":"warn","ts":"2026-04-21T18:15:54.650128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:15:54.149236Z","time spent":"499.932259ms","remote":"127.0.0.1:57704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":638,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-21T18:15:55.211406Z","caller":"traceutil/trace.go:171","msg":"trace[429022997] transaction","detail":"{read_only:false; response_revision:249; number_of_response:1; }","duration":"1.061614873s","start":"2026-04-21T18:15:54.149741Z","end":"2026-04-21T18:15:55.211356Z","steps":["trace[429022997] 'process raft request' (duration: 975.162461ms)","trace[429022997] 'compare' (duration: 85.451784ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T18:15:55.211709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:15:54.149713Z","time spent":"1.061813408s","remote":"127.0.0.1:58074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2651,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-21T18:15:55.211716Z","caller":"traceutil/trace.go:171","msg":"trace[1567549714] linearizableReadLoop","detail":"{readStateIndex:262; appliedIndex:255; }","duration":"562.63942ms","start":"2026-04-21T18:15:54.649047Z","end":"2026-04-21T18:15:55.211686Z","steps":["trace[1567549714] 'read index received' (duration: 475.955422ms)","trace[1567549714] 'applied index is now lower than readState.Index' (duration: 86.682918ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:15:55.211946Z","caller":"traceutil/trace.go:171","msg":"trace[1918321163] transaction","detail":"{read_only:false; response_revision:250; number_of_response:1; }","duration":"1.054562524s","start":"2026-04-21T18:15:54.157369Z","end":"2026-04-21T18:15:55.211932Z","steps":["trace[1918321163] 'process raft request' (duration: 1.053580766s)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:15:55.212026Z","caller":"traceutil/trace.go:171","msg":"trace[2110575730] transaction","detail":"{read_only:false; number_of_response:0; response_revision:253; }","duration":"1.014577584s","start":"2026-04-21T18:15:54.197437Z","end":"2026-04-21T18:15:55.212015Z","steps":["trace[2110575730] 'process raft request' (duration: 1.014196813s)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:15:55.212082Z","caller":"traceutil/trace.go:171","msg":"trace[185135465] transaction","detail":"{read_only:false; response_revision:251; number_of_response:1; }","duration":"1.01587558s","start":"2026-04-21T18:15:54.196196Z","end":"2026-04-21T18:15:55.212071Z","steps":["trace[185135465] 'process raft request' (duration: 1.015349495s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:15:55.212092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:15:54.197419Z","time spent":"1.014644915s","remote":"127.0.0.1:57792","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-21T18:15:55.212142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:15:54.196159Z","time spent":"1.015950573s","remote":"127.0.0.1:57792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3965,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-21T18:15:55.212361Z","caller":"traceutil/trace.go:171","msg":"trace[2124550350] transaction","detail":"{read_only:false; response_revision:252; number_of_response:1; }","duration":"1.015939372s","start":"2026-04-21T18:15:54.19641Z","end":"2026-04-21T18:15:55.21235Z","steps":["trace[2124550350] 'process raft request' (duration: 1.01518324s)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:15:55.212396Z","caller":"traceutil/trace.go:171","msg":"trace[1919012053] transaction","detail":"{read_only:false; response_revision:254; number_of_response:1; }","duration":"1.014943285s","start":"2026-04-21T18:15:54.197446Z","end":"2026-04-21T18:15:55.212389Z","steps":["trace[1919012053] 'process raft request' (duration: 1.014208104s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:15:55.212424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:15:54.196367Z","time spent":"1.016030895s","remote":"127.0.0.1:57792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5882,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-21T18:15:55.212443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:15:54.197432Z","time spent":"1.014989796s","remote":"127.0.0.1:57792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2905,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-21T18:15:55.212562Z","caller":"traceutil/trace.go:171","msg":"trace[728496157] transaction","detail":"{read_only:false; response_revision:253; number_of_response:1; }","duration":"1.016032374s","start":"2026-04-21T18:15:54.196516Z","end":"2026-04-21T18:15:55.212548Z","steps":["trace[728496157] 'process raft request' (duration: 1.015095918s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:15:55.21263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:15:54.196493Z","time spent":"1.016103447s","remote":"127.0.0.1:57792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5498,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-21T18:15:55.212368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"720.927721ms","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-04-21T18:15:55.213259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"822.53457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T18:15:55.213669Z","caller":"traceutil/trace.go:171","msg":"trace[1596765554] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:254; }","duration":"822.863559ms","start":"2026-04-21T18:15:54.390573Z","end":"2026-04-21T18:15:55.213436Z","steps":["trace[1596765554] 'agreement among raft nodes before linearized reading' (duration: 822.327834ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:15:55.213008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:15:54.157346Z","time spent":"1.054629515s","remote":"127.0.0.1:57780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4183,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T18:15:55.213369Z","caller":"traceutil/trace.go:171","msg":"trace[2014646123] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:254; }","duration":"721.767364ms","start":"2026-04-21T18:15:54.491368Z","end":"2026-04-21T18:15:55.213135Z","steps":["trace[2014646123] 'agreement among raft nodes before linearized reading' (duration: 720.959212ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:15:55.213834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:15:54.491339Z","time spent":"722.483385ms","remote":"127.0.0.1:57868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-21T18:15:55.213942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:15:54.390511Z","time spent":"823.413485ms","remote":"127.0.0.1:57868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-21T18:15:55.229795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.09885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/resourcequota-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-04-21T18:15:55.229861Z","caller":"traceutil/trace.go:171","msg":"trace[267141033] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/resourcequota-controller; range_end:; response_count:1; response_revision:255; }","duration":"576.217054ms","start":"2026-04-21T18:15:54.653636Z","end":"2026-04-21T18:15:55.229853Z","steps":["trace[267141033] 'agreement among raft nodes before linearized reading' (duration: 576.07381ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:15:55.229871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.895327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-21T18:15:55.229899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:15:54.653616Z","time spent":"576.277765ms","remote":"127.0.0.1:57810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":237,"request content":"key:\"/registry/serviceaccounts/kube-system/resourcequota-controller\" "} {"level":"info","ts":"2026-04-21T18:15:55.229907Z","caller":"traceutil/trace.go:171","msg":"trace[1117664571] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:255; }","duration":"461.072801ms","start":"2026-04-21T18:15:54.768823Z","end":"2026-04-21T18:15:55.229895Z","steps":["trace[1117664571] 'agreement among raft nodes before linearized reading' (duration: 460.875365ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:15:55.229931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:15:54.768795Z","time spent":"461.128863ms","remote":"127.0.0.1:57868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-21T18:15:55.229688Z","caller":"traceutil/trace.go:171","msg":"trace[1491366042] transaction","detail":"{read_only:false; response_revision:255; number_of_response:1; }","duration":"575.523754ms","start":"2026-04-21T18:15:54.654142Z","end":"2026-04-21T18:15:55.229666Z","steps":["trace[1491366042] 'process raft request' (duration: 575.419151ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:15:55.230182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:15:54.654119Z","time spent":"576.019668ms","remote":"127.0.0.1:57704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":636,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-21T18:15:56.076478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.649139ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-21T18:15:56.076769Z","caller":"traceutil/trace.go:171","msg":"trace[400912125] transaction","detail":"{read_only:false; response_revision:256; number_of_response:1; }","duration":"859.544365ms","start":"2026-04-21T18:15:55.217132Z","end":"2026-04-21T18:15:56.076676Z","steps":["trace[400912125] 'process raft request' (duration: 443.455184ms)","trace[400912125] 'compare' (duration: 415.478014ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:15:56.076879Z","caller":"traceutil/trace.go:171","msg":"trace[611062629] transaction","detail":"{read_only:false; response_revision:259; number_of_response:1; }","duration":"844.272384ms","start":"2026-04-21T18:15:55.232572Z","end":"2026-04-21T18:15:56.076845Z","steps":["trace[611062629] 'process raft request' (duration: 844.236113ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:15:56.076958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:15:55.232562Z","time spent":"844.368136ms","remote":"127.0.0.1:57868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-21T18:15:56.077012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:15:55.217101Z","time spent":"859.816523ms","remote":"127.0.0.1:57810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":170,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-21T18:15:56.07714Z","caller":"traceutil/trace.go:171","msg":"trace[14919951] transaction","detail":"{read_only:false; response_revision:257; number_of_response:1; }","duration":"852.083085ms","start":"2026-04-21T18:15:55.225029Z","end":"2026-04-21T18:15:56.077112Z","steps":["trace[14919951] 'process raft request' (duration: 851.57344ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:15:56.077276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:15:55.225Z","time spent":"852.212189ms","remote":"127.0.0.1:57868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-21T18:15:56.077312Z","caller":"traceutil/trace.go:171","msg":"trace[1669456085] linearizableReadLoop","detail":"{readStateIndex:266; appliedIndex:263; }","duration":"847.569427ms","start":"2026-04-21T18:15:55.229689Z","end":"2026-04-21T18:15:56.077258Z","steps":["trace[1669456085] 'read index received' (duration: 430.928151ms)","trace[1669456085] 'applied index is now lower than readState.Index' (duration: 416.634746ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T18:15:56.077386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"851.545009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T18:15:56.077431Z","caller":"traceutil/trace.go:171","msg":"trace[895811205] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:259; }","duration":"851.628362ms","start":"2026-04-21T18:15:55.225789Z","end":"2026-04-21T18:15:56.077417Z","steps":["trace[895811205] 'agreement among raft nodes before linearized reading' (duration: 851.485308ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:15:56.077463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:15:55.225769Z","time spent":"851.688113ms","remote":"127.0.0.1:57638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-21T18:15:56.077426Z","caller":"traceutil/trace.go:171","msg":"trace[1147333048] transaction","detail":"{read_only:false; response_revision:258; number_of_response:1; }","duration":"851.662403ms","start":"2026-04-21T18:15:55.22572Z","end":"2026-04-21T18:15:56.077383Z","steps":["trace[1147333048] 'process raft request' (duration: 850.951523ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:15:56.077525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"854.853463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:3466"} {"level":"warn","ts":"2026-04-21T18:15:56.077572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"834.167299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-21T18:15:56.077572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"860.917964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:3466"} {"level":"info","ts":"2026-04-21T18:15:56.077578Z","caller":"traceutil/trace.go:171","msg":"trace[1064006233] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:259; }","duration":"854.980106ms","start":"2026-04-21T18:15:55.222582Z","end":"2026-04-21T18:15:56.077563Z","steps":["trace[1064006233] 'agreement among raft nodes before linearized reading' (duration: 854.853833ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:15:56.077614Z","caller":"traceutil/trace.go:171","msg":"trace[1587713296] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:0; response_revision:259; }","duration":"834.238691ms","start":"2026-04-21T18:15:55.243363Z","end":"2026-04-21T18:15:56.077601Z","steps":["trace[1587713296] 'agreement among raft nodes before linearized reading' (duration: 834.164179ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:15:56.077624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:15:55.225709Z","time spent":"851.811737ms","remote":"127.0.0.1:57868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T18:15:56.077641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:15:55.222563Z","time spent":"855.065209ms","remote":"127.0.0.1:57792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":3489,"request content":"key:\"/registry/pods/kube-system/kube-vip-instance\" "} {"level":"info","ts":"2026-04-21T18:15:56.077645Z","caller":"traceutil/trace.go:171","msg":"trace[1399454649] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:259; }","duration":"861.021337ms","start":"2026-04-21T18:15:55.216588Z","end":"2026-04-21T18:15:56.077609Z","steps":["trace[1399454649] 'agreement among raft nodes before linearized reading' (duration: 860.874743ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:15:56.077649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"591.989729ms","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-04-21T18:15:56.077675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:15:55.24335Z","time spent":"834.314293ms","remote":"127.0.0.1:57810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/kube-system/job-controller\" "} {"level":"info","ts":"2026-04-21T18:15:56.077693Z","caller":"traceutil/trace.go:171","msg":"trace[2050233922] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:259; }","duration":"592.086081ms","start":"2026-04-21T18:15:55.485597Z","end":"2026-04-21T18:15:56.077683Z","steps":["trace[2050233922] 'agreement among raft nodes before linearized reading' (duration: 591.980118ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:15:56.077696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:15:55.216568Z","time spent":"861.115929ms","remote":"127.0.0.1:57792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":3489,"request content":"key:\"/registry/pods/kube-system/kube-vip-instance\" "} {"level":"warn","ts":"2026-04-21T18:15:56.077716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:15:55.485547Z","time spent":"592.164173ms","remote":"127.0.0.1:57868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-21T18:15:57.426067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.327564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-04-21T18:15:57.426133Z","caller":"traceutil/trace.go:171","msg":"trace[1390036228] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:291; }","duration":"242.446607ms","start":"2026-04-21T18:15:57.183673Z","end":"2026-04-21T18:15:57.426119Z","steps":["trace[1390036228] 'range keys from in-memory index tree' (duration: 242.229091ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:15:57.707962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.449857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-04-21T18:15:57.708069Z","caller":"traceutil/trace.go:171","msg":"trace[518516243] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/generic-garbage-collector; range_end:; response_count:1; response_revision:293; }","duration":"180.619661ms","start":"2026-04-21T18:15:57.527427Z","end":"2026-04-21T18:15:57.708047Z","steps":["trace[518516243] 'range keys from in-memory index tree' (duration: 180.308773ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:16:08.968076Z","caller":"traceutil/trace.go:171","msg":"trace[1459082822] transaction","detail":"{read_only:false; response_revision:398; number_of_response:1; }","duration":"124.554561ms","start":"2026-04-21T18:16:08.84349Z","end":"2026-04-21T18:16:08.968045Z","steps":["trace[1459082822] 'process raft request' (duration: 81.944056ms)","trace[1459082822] 'compare' (duration: 42.289946ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:16:08.968133Z","caller":"traceutil/trace.go:171","msg":"trace[839874524] transaction","detail":"{read_only:false; number_of_response:1; response_revision:400; }","duration":"114.193572ms","start":"2026-04-21T18:16:08.853908Z","end":"2026-04-21T18:16:08.968101Z","steps":["trace[839874524] 'process raft request' (duration: 114.136571ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:16:08.968453Z","caller":"traceutil/trace.go:171","msg":"trace[509567152] transaction","detail":"{read_only:false; response_revision:399; number_of_response:1; }","duration":"122.863903ms","start":"2026-04-21T18:16:08.845579Z","end":"2026-04-21T18:16:08.968443Z","steps":["trace[509567152] 'process raft request' (duration: 122.37074ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:16:09.30952Z","caller":"traceutil/trace.go:171","msg":"trace[1902321917] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"182.57043ms","start":"2026-04-21T18:16:09.12692Z","end":"2026-04-21T18:16:09.30949Z","steps":["trace[1902321917] 'process raft request' (duration: 175.807342ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:16:09.324767Z","caller":"traceutil/trace.go:171","msg":"trace[1973616598] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"104.695638ms","start":"2026-04-21T18:16:09.219968Z","end":"2026-04-21T18:16:09.324663Z","steps":["trace[1973616598] 'process raft request' (duration: 104.502253ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:16:21.472696Z","caller":"traceutil/trace.go:171","msg":"trace[306900210] linearizableReadLoop","detail":"{readStateIndex:456; appliedIndex:456; }","duration":"245.067598ms","start":"2026-04-21T18:16:21.227589Z","end":"2026-04-21T18:16:21.472656Z","steps":["trace[306900210] 'read index received' (duration: 245.055478ms)","trace[306900210] 'applied index is now lower than readState.Index' (duration: 9.74µs)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:16:21.472704Z","caller":"traceutil/trace.go:171","msg":"trace[1846066659] transaction","detail":"{read_only:false; response_revision:442; number_of_response:1; }","duration":"354.224609ms","start":"2026-04-21T18:16:21.118444Z","end":"2026-04-21T18:16:21.472669Z","steps":["trace[1846066659] 'process raft request' (duration: 354.012504ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:16:21.472906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.302514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T18:16:21.47297Z","caller":"traceutil/trace.go:171","msg":"trace[1656255571] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:442; }","duration":"245.434968ms","start":"2026-04-21T18:16:21.22752Z","end":"2026-04-21T18:16:21.472955Z","steps":["trace[1656255571] 'agreement among raft nodes before linearized reading' (duration: 245.315545ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:16:21.473028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:16:21.118415Z","time spent":"354.489027ms","remote":"127.0.0.1:57868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T18:16:22.511369Z","caller":"traceutil/trace.go:171","msg":"trace[853258446] linearizableReadLoop","detail":"{readStateIndex:460; appliedIndex:459; }","duration":"148.714431ms","start":"2026-04-21T18:16:22.362618Z","end":"2026-04-21T18:16:22.511332Z","steps":["trace[853258446] 'read index received' (duration: 148.36288ms)","trace[853258446] 'applied index is now lower than readState.Index' (duration: 350.661µs)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:16:22.511416Z","caller":"traceutil/trace.go:171","msg":"trace[1006168794] transaction","detail":"{read_only:false; response_revision:445; number_of_response:1; }","duration":"150.171191ms","start":"2026-04-21T18:16:22.36122Z","end":"2026-04-21T18:16:22.511391Z","steps":["trace[1006168794] 'process raft request' (duration: 149.776951ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:16:22.511525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.884526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/cilium-operator\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T18:16:22.511597Z","caller":"traceutil/trace.go:171","msg":"trace[2098844913] range","detail":"{range_begin:/registry/clusterrolebindings/cilium-operator; range_end:; response_count:0; response_revision:445; }","duration":"148.979838ms","start":"2026-04-21T18:16:22.362601Z","end":"2026-04-21T18:16:22.511581Z","steps":["trace[2098844913] 'agreement among raft nodes before linearized reading' (duration: 148.868065ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:16:22.511622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.803261ms","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-04-21T18:16:22.511659Z","caller":"traceutil/trace.go:171","msg":"trace[893955543] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:445; }","duration":"113.889495ms","start":"2026-04-21T18:16:22.39776Z","end":"2026-04-21T18:16:22.51165Z","steps":["trace[893955543] 'agreement among raft nodes before linearized reading' (duration: 113.829913ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:16:22.856498Z","caller":"traceutil/trace.go:171","msg":"trace[69799838] transaction","detail":"{read_only:false; response_revision:450; number_of_response:1; }","duration":"207.699538ms","start":"2026-04-21T18:16:22.648768Z","end":"2026-04-21T18:16:22.856468Z","steps":["trace[69799838] 'process raft request' (duration: 207.651737ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:16:22.856705Z","caller":"traceutil/trace.go:171","msg":"trace[2080699321] transaction","detail":"{read_only:false; response_revision:449; number_of_response:1; }","duration":"207.949836ms","start":"2026-04-21T18:16:22.648576Z","end":"2026-04-21T18:16:22.856526Z","steps":["trace[2080699321] 'process raft request' (duration: 204.982143ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:16:24.757223Z","caller":"traceutil/trace.go:171","msg":"trace[649162325] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"234.806773ms","start":"2026-04-21T18:16:24.522394Z","end":"2026-04-21T18:16:24.757201Z","steps":["trace[649162325] 'process raft request' (duration: 234.662709ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:16:25.288068Z","caller":"traceutil/trace.go:171","msg":"trace[1515306092] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"764.044523ms","start":"2026-04-21T18:16:24.52398Z","end":"2026-04-21T18:16:25.288024Z","steps":["trace[1515306092] 'process raft request' (duration: 763.731445ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:16:25.288104Z","caller":"traceutil/trace.go:171","msg":"trace[294851165] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"761.944005ms","start":"2026-04-21T18:16:24.526127Z","end":"2026-04-21T18:16:25.288071Z","steps":["trace[294851165] 'process raft request' (duration: 761.800692ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:16:25.28828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:16:24.523963Z","time spent":"764.202698ms","remote":"127.0.0.1:57868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T18:16:25.288351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:16:24.526118Z","time spent":"762.099309ms","remote":"127.0.0.1:57868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T18:16:25.288527Z","caller":"traceutil/trace.go:171","msg":"trace[345799643] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"722.966672ms","start":"2026-04-21T18:16:24.565427Z","end":"2026-04-21T18:16:25.288394Z","steps":["trace[345799643] 'process raft request' (duration: 722.54232ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:16:25.288834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:16:24.565399Z","time spent":"723.279351ms","remote":"127.0.0.1:57868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T18:16:25.288984Z","caller":"traceutil/trace.go:171","msg":"trace[1823178418] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"648.809653ms","start":"2026-04-21T18:16:24.640142Z","end":"2026-04-21T18:16:25.288951Z","steps":["trace[1823178418] 'process raft request' (duration: 648.479793ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:16:25.289008Z","caller":"traceutil/trace.go:171","msg":"trace[705184527] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"639.843704ms","start":"2026-04-21T18:16:24.64915Z","end":"2026-04-21T18:16:25.288993Z","steps":["trace[705184527] 'process raft request' (duration: 639.552406ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:16:25.289103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:16:24.649126Z","time spent":"639.931945ms","remote":"127.0.0.1:57686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-21T18:16:25.289121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:16:24.640135Z","time spent":"648.918135ms","remote":"127.0.0.1:57686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-21T18:16:25.289177Z","caller":"traceutil/trace.go:171","msg":"trace[1174350128] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"651.980851ms","start":"2026-04-21T18:16:24.637143Z","end":"2026-04-21T18:16:25.289124Z","steps":["trace[1174350128] 'process raft request' (duration: 651.414995ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:16:25.289217Z","caller":"traceutil/trace.go:171","msg":"trace[2131258118] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"639.879004ms","start":"2026-04-21T18:16:24.649327Z","end":"2026-04-21T18:16:25.289206Z","steps":["trace[2131258118] 'process raft request' (duration: 639.480463ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:16:25.289078Z","caller":"traceutil/trace.go:171","msg":"trace[300097655] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"647.934028ms","start":"2026-04-21T18:16:24.641113Z","end":"2026-04-21T18:16:25.289047Z","steps":["trace[300097655] 'process raft request' (duration: 647.551218ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:16:25.289266Z","caller":"traceutil/trace.go:171","msg":"trace[1168318134] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"636.225963ms","start":"2026-04-21T18:16:24.653031Z","end":"2026-04-21T18:16:25.289257Z","steps":["trace[1168318134] 'process raft request' (duration: 635.824492ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:16:25.289275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:16:24.649323Z","time spent":"639.928925ms","remote":"127.0.0.1:57686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-21T18:16:25.289307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:16:24.653024Z","time spent":"636.266134ms","remote":"127.0.0.1:57686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-21T18:16:25.289379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:16:24.637114Z","time spent":"652.123794ms","remote":"127.0.0.1:57686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-21T18:16:25.289483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:16:24.6411Z","time spent":"648.205265ms","remote":"127.0.0.1:57686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-21T18:16:25.289638Z","caller":"traceutil/trace.go:171","msg":"trace[778692961] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"636.537771ms","start":"2026-04-21T18:16:24.653071Z","end":"2026-04-21T18:16:25.289609Z","steps":["trace[778692961] 'process raft request' (duration: 635.826842ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:16:25.289761Z","caller":"traceutil/trace.go:171","msg":"trace[1643563971] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"627.142861ms","start":"2026-04-21T18:16:24.662602Z","end":"2026-04-21T18:16:25.289745Z","steps":["trace[1643563971] 'process raft request' (duration: 626.345368ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:16:25.289821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:16:24.662593Z","time spent":"627.200482ms","remote":"127.0.0.1:57686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-21T18:16:25.289814Z","caller":"traceutil/trace.go:171","msg":"trace[1089512282] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"627.14956ms","start":"2026-04-21T18:16:24.662647Z","end":"2026-04-21T18:16:25.289797Z","steps":["trace[1089512282] 'process raft request' (duration: 627.004936ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:16:25.289782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:16:24.653067Z","time spent":"636.647214ms","remote":"127.0.0.1:57686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-21T18:16:25.28985Z","caller":"traceutil/trace.go:171","msg":"trace[526567866] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"594.444332ms","start":"2026-04-21T18:16:24.695392Z","end":"2026-04-21T18:16:25.289836Z","steps":["trace[526567866] 'process raft request' (duration: 594.402921ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:16:25.289896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:16:24.695362Z","time spent":"594.511585ms","remote":"127.0.0.1:57686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-21T18:16:25.289893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:16:24.662643Z","time spent":"627.217532ms","remote":"127.0.0.1:57686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-21T18:16:25.289931Z","caller":"traceutil/trace.go:171","msg":"trace[69392336] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"623.098528ms","start":"2026-04-21T18:16:24.666824Z","end":"2026-04-21T18:16:25.289922Z","steps":["trace[69392336] 'process raft request' (duration: 622.927544ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:16:25.289971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:16:24.666815Z","time spent":"623.136109ms","remote":"127.0.0.1:57686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-21T18:16:25.296018Z","caller":"traceutil/trace.go:171","msg":"trace[743946141] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"447.353077ms","start":"2026-04-21T18:16:24.848636Z","end":"2026-04-21T18:16:25.295989Z","steps":["trace[743946141] 'process raft request' (duration: 447.065198ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:16:25.296139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:16:24.848609Z","time spent":"447.473519ms","remote":"127.0.0.1:57686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-21T18:16:29.492625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.176227ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-21T18:16:29.492875Z","caller":"traceutil/trace.go:171","msg":"trace[840865750] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"223.667953ms","start":"2026-04-21T18:16:29.269156Z","end":"2026-04-21T18:16:29.492824Z","steps":["trace[840865750] 'compare' (duration: 214.960951ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:16:29.493005Z","caller":"traceutil/trace.go:171","msg":"trace[1042670813] linearizableReadLoop","detail":"{readStateIndex:596; appliedIndex:595; }","duration":"174.801876ms","start":"2026-04-21T18:16:29.317999Z","end":"2026-04-21T18:16:29.492801Z","steps":["trace[1042670813] 'read index received' (duration: 72.122µs)","trace[1042670813] 'applied index is now lower than readState.Index' (duration: 174.727764ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T18:16:29.49302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.999051ms","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-04-21T18:16:29.493101Z","caller":"traceutil/trace.go:171","msg":"trace[974307340] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:573; }","duration":"175.156196ms","start":"2026-04-21T18:16:29.317927Z","end":"2026-04-21T18:16:29.493083Z","steps":["trace[974307340] 'agreement among raft nodes before linearized reading' (duration: 174.968971ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:16:29.493304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.638161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-21T18:16:29.49336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.847593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T18:16:29.493365Z","caller":"traceutil/trace.go:171","msg":"trace[1973702405] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:573; }","duration":"174.725333ms","start":"2026-04-21T18:16:29.318622Z","end":"2026-04-21T18:16:29.493348Z","steps":["trace[1973702405] 'agreement among raft nodes before linearized reading' (duration: 174.59562ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:16:29.493393Z","caller":"traceutil/trace.go:171","msg":"trace[67942104] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:573; }","duration":"122.883714ms","start":"2026-04-21T18:16:29.370499Z","end":"2026-04-21T18:16:29.493383Z","steps":["trace[67942104] 'agreement among raft nodes before linearized reading' (duration: 122.829113ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:16:29.493523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.186358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-21T18:16:29.493546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.067995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T18:16:29.49359Z","caller":"traceutil/trace.go:171","msg":"trace[879177313] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:573; }","duration":"156.312872ms","start":"2026-04-21T18:16:29.337252Z","end":"2026-04-21T18:16:29.493565Z","steps":["trace[879177313] 'agreement among raft nodes before linearized reading' (duration: 156.131977ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:16:29.493662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.312048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T18:16:29.49366Z","caller":"traceutil/trace.go:171","msg":"trace[1247438405] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:573; }","duration":"156.23164ms","start":"2026-04-21T18:16:29.337405Z","end":"2026-04-21T18:16:29.493637Z","steps":["trace[1247438405] 'agreement among raft nodes before linearized reading' (duration: 155.967582ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:16:29.493742Z","caller":"traceutil/trace.go:171","msg":"trace[1657806415] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:573; }","duration":"122.3932ms","start":"2026-04-21T18:16:29.371319Z","end":"2026-04-21T18:16:29.493712Z","steps":["trace[1657806415] 'agreement among raft nodes before linearized reading' (duration: 122.216915ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:16:29.49372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.123219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T18:16:29.493825Z","caller":"traceutil/trace.go:171","msg":"trace[349337022] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:573; }","duration":"123.283594ms","start":"2026-04-21T18:16:29.370513Z","end":"2026-04-21T18:16:29.493796Z","steps":["trace[349337022] 'agreement among raft nodes before linearized reading' (duration: 123.044248ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:16:29.494025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.792218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T18:16:29.494196Z","caller":"traceutil/trace.go:171","msg":"trace[2026927189] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:573; }","duration":"156.023734ms","start":"2026-04-21T18:16:29.338148Z","end":"2026-04-21T18:16:29.494172Z","steps":["trace[2026927189] 'agreement among raft nodes before linearized reading' (duration: 155.689475ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:16:30.354316Z","caller":"traceutil/trace.go:171","msg":"trace[67072789] linearizableReadLoop","detail":"{readStateIndex:600; appliedIndex:599; }","duration":"126.910314ms","start":"2026-04-21T18:16:30.227375Z","end":"2026-04-21T18:16:30.354285Z","steps":["trace[67072789] 'read index received' (duration: 126.706809ms)","trace[67072789] 'applied index is now lower than readState.Index' (duration: 202.065µs)"],"step_count":2} {"level":"warn","ts":"2026-04-21T18:16:30.354685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.246954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T18:16:30.354918Z","caller":"traceutil/trace.go:171","msg":"trace[1858494113] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"209.15979ms","start":"2026-04-21T18:16:30.14573Z","end":"2026-04-21T18:16:30.35489Z","steps":["trace[1858494113] 'process raft request' (duration: 208.395709ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:16:30.35493Z","caller":"traceutil/trace.go:171","msg":"trace[814750110] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:577; }","duration":"127.583374ms","start":"2026-04-21T18:16:30.227268Z","end":"2026-04-21T18:16:30.354852Z","steps":["trace[814750110] 'agreement among raft nodes before linearized reading' (duration: 127.148812ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:16:33.245876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.665041ms","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-04-21T18:16:33.246022Z","caller":"traceutil/trace.go:171","msg":"trace[647206913] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:595; }","duration":"413.840335ms","start":"2026-04-21T18:16:32.832148Z","end":"2026-04-21T18:16:33.245989Z","steps":["trace[647206913] 'range keys from in-memory index tree' (duration: 413.413114ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:16:33.246085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:16:32.83207Z","time spent":"414.00154ms","remote":"127.0.0.1:57868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-21T18:16:33.310989Z","caller":"traceutil/trace.go:171","msg":"trace[1336568778] linearizableReadLoop","detail":"{readStateIndex:620; appliedIndex:619; }","duration":"289.607316ms","start":"2026-04-21T18:16:33.021354Z","end":"2026-04-21T18:16:33.310962Z","steps":["trace[1336568778] 'read index received' (duration: 288.385531ms)","trace[1336568778] 'applied index is now lower than readState.Index' (duration: 1.220785ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:16:33.311067Z","caller":"traceutil/trace.go:171","msg":"trace[1304424861] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"354.732224ms","start":"2026-04-21T18:16:32.95629Z","end":"2026-04-21T18:16:33.311022Z","steps":["trace[1304424861] 'process raft request' (duration: 353.664294ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:16:33.311203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.81593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-pjt2r\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-21T18:16:33.311234Z","caller":"traceutil/trace.go:171","msg":"trace[747686638] range","detail":"{range_begin:/registry/pods/kube-system/cilium-pjt2r; range_end:; response_count:1; response_revision:596; }","duration":"289.917693ms","start":"2026-04-21T18:16:33.021307Z","end":"2026-04-21T18:16:33.311225Z","steps":["trace[747686638] 'agreement among raft nodes before linearized reading' (duration: 289.76438ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:16:33.311326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:16:32.956254Z","time spent":"354.93029ms","remote":"127.0.0.1:57714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036313,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T18:16:33.340477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.697856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T18:16:33.340587Z","caller":"traceutil/trace.go:171","msg":"trace[1748584417] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:597; }","duration":"110.87943ms","start":"2026-04-21T18:16:33.229682Z","end":"2026-04-21T18:16:33.340561Z","steps":["trace[1748584417] 'agreement among raft nodes before linearized reading' (duration: 110.688785ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:16:33.997668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.03913ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-21T18:16:33.997907Z","caller":"traceutil/trace.go:171","msg":"trace[952519627] linearizableReadLoop","detail":"{readStateIndex:623; appliedIndex:621; }","duration":"441.657648ms","start":"2026-04-21T18:16:33.556227Z","end":"2026-04-21T18:16:33.997884Z","steps":["trace[952519627] 'read index received' (duration: 319.31308ms)","trace[952519627] 'applied index is now lower than readState.Index' (duration: 122.343088ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:16:33.997931Z","caller":"traceutil/trace.go:171","msg":"trace[1947282127] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"657.776071ms","start":"2026-04-21T18:16:33.340115Z","end":"2026-04-21T18:16:33.997891Z","steps":["trace[1947282127] 'process raft request' (duration: 535.405882ms)","trace[1947282127] 'compare' (duration: 121.537846ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:16:33.998013Z","caller":"traceutil/trace.go:171","msg":"trace[1047504532] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"620.061544ms","start":"2026-04-21T18:16:33.377938Z","end":"2026-04-21T18:16:33.997999Z","steps":["trace[1047504532] 'process raft request' (duration: 619.861489ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:16:33.998071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:16:33.377904Z","time spent":"620.142856ms","remote":"127.0.0.1:57844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-21T18:16:33.998127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:16:33.340076Z","time spent":"657.940326ms","remote":"127.0.0.1:57792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T18:16:33.998217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.964976ms","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-04-21T18:16:33.998313Z","caller":"traceutil/trace.go:171","msg":"trace[464657049] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:599; }","duration":"402.12292ms","start":"2026-04-21T18:16:33.596168Z","end":"2026-04-21T18:16:33.998291Z","steps":["trace[464657049] 'agreement among raft nodes before linearized reading' (duration: 401.855822ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:16:33.998355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:16:33.596139Z","time spent":"402.208092ms","remote":"127.0.0.1:57868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-21T18:16:33.998408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.203623ms","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-04-21T18:16:33.998448Z","caller":"traceutil/trace.go:171","msg":"trace[1060767413] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:599; }","duration":"442.280386ms","start":"2026-04-21T18:16:33.556157Z","end":"2026-04-21T18:16:33.998438Z","steps":["trace[1060767413] 'agreement among raft nodes before linearized reading' (duration: 442.123302ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:16:33.998474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:16:33.556104Z","time spent":"442.364257ms","remote":"127.0.0.1:57868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-21T18:16:34.508588Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2330223241843310642,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-21T18:16:35.009275Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2330223241843310642,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-21T18:16:35.230584Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.22652055s","expected-duration":"1s"} {"level":"info","ts":"2026-04-21T18:16:35.285356Z","caller":"traceutil/trace.go:171","msg":"trace[1060003657] linearizableReadLoop","detail":"{readStateIndex:625; appliedIndex:623; }","duration":"1.277332791s","start":"2026-04-21T18:16:34.007995Z","end":"2026-04-21T18:16:35.285327Z","steps":["trace[1060003657] 'read index received' (duration: 1.222869609s)","trace[1060003657] 'applied index is now lower than readState.Index' (duration: 54.462222ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:16:35.285446Z","caller":"traceutil/trace.go:171","msg":"trace[1313151318] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"1.281537147s","start":"2026-04-21T18:16:34.003875Z","end":"2026-04-21T18:16:35.285412Z","steps":["trace[1313151318] 'process raft request' (duration: 1.227164817s)","trace[1313151318] 'compare' (duration: 53.763334ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:16:35.285511Z","caller":"traceutil/trace.go:171","msg":"trace[810733677] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"1.28161023s","start":"2026-04-21T18:16:34.00389Z","end":"2026-04-21T18:16:35.285501Z","steps":["trace[810733677] 'process raft request' (duration: 1.281329792s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:16:35.2856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:16:34.003862Z","time spent":"1.281650381s","remote":"127.0.0.1:57868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T18:16:35.285635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:16:34.003866Z","time spent":"1.281677733s","remote":"127.0.0.1:57868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T18:16:35.285796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.059584203s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T18:16:35.285834Z","caller":"traceutil/trace.go:171","msg":"trace[991378072] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:601; }","duration":"1.059678416s","start":"2026-04-21T18:16:34.226146Z","end":"2026-04-21T18:16:35.285825Z","steps":["trace[991378072] 'agreement among raft nodes before linearized reading' (duration: 1.059619275s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:16:35.285857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:16:34.226116Z","time spent":"1.059736657s","remote":"127.0.0.1:57638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-21T18:16:35.285993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.2780056s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-04-21T18:16:35.286025Z","caller":"traceutil/trace.go:171","msg":"trace[584884710] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:601; }","duration":"1.278072083s","start":"2026-04-21T18:16:34.007944Z","end":"2026-04-21T18:16:35.286016Z","steps":["trace[584884710] 'agreement among raft nodes before linearized reading' (duration: 1.278014241s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:16:35.286045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:16:34.007933Z","time spent":"1.278108803s","remote":"127.0.0.1:57810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":216,"request content":"key:\"/registry/serviceaccounts/kube-system/job-controller\" "} {"level":"warn","ts":"2026-04-21T18:16:35.286239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"873.6897ms","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-04-21T18:16:35.286279Z","caller":"traceutil/trace.go:171","msg":"trace[879851065] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:601; }","duration":"873.774121ms","start":"2026-04-21T18:16:34.41249Z","end":"2026-04-21T18:16:35.286264Z","steps":["trace[879851065] 'agreement among raft nodes before linearized reading' (duration: 873.645428ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:16:35.286304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:16:34.412465Z","time spent":"873.833583ms","remote":"127.0.0.1:57868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-21T18:16:36.460348Z","caller":"traceutil/trace.go:171","msg":"trace[2024629027] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"152.955088ms","start":"2026-04-21T18:16:36.307362Z","end":"2026-04-21T18:16:36.460317Z","steps":["trace[2024629027] 'process raft request' (duration: 152.781303ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:16:37.164782Z","caller":"traceutil/trace.go:171","msg":"trace[288275529] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"122.453192ms","start":"2026-04-21T18:16:37.042302Z","end":"2026-04-21T18:16:37.164755Z","steps":["trace[288275529] 'process raft request' (duration: 51.777979ms)","trace[288275529] 'compare' (duration: 70.323213ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:16:37.217872Z","caller":"traceutil/trace.go:171","msg":"trace[1045466966] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"162.881335ms","start":"2026-04-21T18:16:37.054957Z","end":"2026-04-21T18:16:37.217839Z","steps":["trace[1045466966] 'process raft request' (duration: 162.645958ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:16:45.367532Z","caller":"traceutil/trace.go:171","msg":"trace[1688702306] transaction","detail":"{read_only:false; response_revision:715; number_of_response:1; }","duration":"100.607884ms","start":"2026-04-21T18:16:45.266878Z","end":"2026-04-21T18:16:45.367486Z","steps":["trace[1688702306] 'process raft request' (duration: 100.362498ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:16:45.546841Z","caller":"traceutil/trace.go:171","msg":"trace[1472999960] transaction","detail":"{read_only:false; response_revision:717; number_of_response:1; }","duration":"158.503323ms","start":"2026-04-21T18:16:45.388307Z","end":"2026-04-21T18:16:45.54681Z","steps":["trace[1472999960] 'process raft request' (duration: 117.854834ms)","trace[1472999960] 'compare' (duration: 40.248988ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:16:45.621021Z","caller":"traceutil/trace.go:171","msg":"trace[1339338195] linearizableReadLoop","detail":"{readStateIndex:745; appliedIndex:743; }","duration":"204.289805ms","start":"2026-04-21T18:16:45.416707Z","end":"2026-04-21T18:16:45.620997Z","steps":["trace[1339338195] 'read index received' (duration: 89.468186ms)","trace[1339338195] 'applied index is now lower than readState.Index' (duration: 114.820619ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:16:45.621191Z","caller":"traceutil/trace.go:171","msg":"trace[2121441736] transaction","detail":"{read_only:false; response_revision:718; number_of_response:1; }","duration":"232.488738ms","start":"2026-04-21T18:16:45.38865Z","end":"2026-04-21T18:16:45.621139Z","steps":["trace[2121441736] 'process raft request' (duration: 232.22208ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:16:45.621374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.448015ms","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-04-21T18:16:45.621376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.656736ms","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-04-21T18:16:45.621413Z","caller":"traceutil/trace.go:171","msg":"trace[848673167] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:718; }","duration":"188.560638ms","start":"2026-04-21T18:16:45.432843Z","end":"2026-04-21T18:16:45.621404Z","steps":["trace[848673167] 'agreement among raft nodes before linearized reading' (duration: 188.419054ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:16:45.621432Z","caller":"traceutil/trace.go:171","msg":"trace[1198050426] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:718; }","duration":"204.756258ms","start":"2026-04-21T18:16:45.416663Z","end":"2026-04-21T18:16:45.621419Z","steps":["trace[1198050426] 'agreement among raft nodes before linearized reading' (duration: 204.522272ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:16:45.621534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.401772ms","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-04-21T18:16:45.621611Z","caller":"traceutil/trace.go:171","msg":"trace[437656970] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:718; }","duration":"184.497635ms","start":"2026-04-21T18:16:45.43709Z","end":"2026-04-21T18:16:45.621588Z","steps":["trace[437656970] 'agreement among raft nodes before linearized reading' (duration: 184.238677ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:16:45.621623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.677791ms","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-04-21T18:16:45.621703Z","caller":"traceutil/trace.go:171","msg":"trace[85670671] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:718; }","duration":"188.740472ms","start":"2026-04-21T18:16:45.432904Z","end":"2026-04-21T18:16:45.621644Z","steps":["trace[85670671] 'agreement among raft nodes before linearized reading' (duration: 188.659471ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:16:51.836188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.551053ms","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-04-21T18:16:51.836274Z","caller":"traceutil/trace.go:171","msg":"trace[1824717375] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:760; }","duration":"172.707118ms","start":"2026-04-21T18:16:51.663546Z","end":"2026-04-21T18:16:51.836253Z","steps":["trace[1824717375] 'range keys from in-memory index tree' (duration: 172.393898ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:16:52.530759Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2330223241843310950,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-21T18:16:53.031626Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2330223241843310950,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-21T18:16:53.532655Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2330223241843310950,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-21T18:16:53.940168Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.75464979s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-21T18:16:54.197754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.199525ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-21T18:16:54.198132Z","caller":"traceutil/trace.go:171","msg":"trace[931314633] transaction","detail":"{read_only:false; response_revision:765; number_of_response:1; }","duration":"2.355928272s","start":"2026-04-21T18:16:51.842185Z","end":"2026-04-21T18:16:54.198114Z","steps":["trace[931314633] 'process raft request' (duration: 2.355873931s)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:16:54.198111Z","caller":"traceutil/trace.go:171","msg":"trace[1185665456] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"2.356298373s","start":"2026-04-21T18:16:51.841763Z","end":"2026-04-21T18:16:54.198061Z","steps":["trace[1185665456] 'process raft request' (duration: 2.098696437s)","trace[1185665456] 'compare' (duration: 256.968439ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T18:16:54.198276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:16:51.842179Z","time spent":"2.355992774s","remote":"127.0.0.1:57868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T18:16:54.198329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:16:51.841728Z","time spent":"2.356460278s","remote":"127.0.0.1:57868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T18:16:54.198422Z","caller":"traceutil/trace.go:171","msg":"trace[840100562] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"2.356636323s","start":"2026-04-21T18:16:51.841763Z","end":"2026-04-21T18:16:54.198399Z","steps":["trace[840100562] 'process raft request' (duration: 2.356145729s)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:16:54.19845Z","caller":"traceutil/trace.go:171","msg":"trace[886522699] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"2.356319143s","start":"2026-04-21T18:16:51.842119Z","end":"2026-04-21T18:16:54.198439Z","steps":["trace[886522699] 'process raft request' (duration: 2.355907472s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:16:54.198566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:16:51.842107Z","time spent":"2.356400647s","remote":"127.0.0.1:57868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T18:16:54.198577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:16:51.84173Z","time spent":"2.356757766s","remote":"127.0.0.1:57868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T18:16:54.227177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999882531s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-21T18:16:54.227352Z","caller":"traceutil/trace.go:171","msg":"trace[861759400] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000099308s","start":"2026-04-21T18:16:52.227194Z","end":"2026-04-21T18:16:54.227289Z","steps":["trace[861759400] 'agreement among raft nodes before linearized reading' (duration: 1.999925293s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:16:54.227468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:16:52.227158Z","time spent":"2.000240582s","remote":"127.0.0.1:57638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/21 18:16:54 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"info","ts":"2026-04-21T18:16:54.46716Z","caller":"traceutil/trace.go:171","msg":"trace[1083532960] transaction","detail":"{read_only:false; response_revision:766; number_of_response:1; }","duration":"2.312323641s","start":"2026-04-21T18:16:52.154812Z","end":"2026-04-21T18:16:54.467135Z","steps":["trace[1083532960] 'process raft request' (duration: 2.312009682s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:16:54.46751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:16:52.154781Z","time spent":"2.3126419s","remote":"127.0.0.1:57844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T18:16:54.467538Z","caller":"traceutil/trace.go:171","msg":"trace[326194949] linearizableReadLoop","detail":"{readStateIndex:795; appliedIndex:789; }","duration":"2.437361245s","start":"2026-04-21T18:16:52.030133Z","end":"2026-04-21T18:16:54.467494Z","steps":["trace[326194949] 'read index received' (duration: 1.910345796s)","trace[326194949] 'applied index is now lower than readState.Index' (duration: 527.013009ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:16:54.467668Z","caller":"traceutil/trace.go:171","msg":"trace[2020477217] transaction","detail":"{read_only:false; response_revision:767; number_of_response:1; }","duration":"2.210778131s","start":"2026-04-21T18:16:52.256847Z","end":"2026-04-21T18:16:54.467626Z","steps":["trace[2020477217] 'process raft request' (duration: 2.210230586s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:16:54.46781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.216812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-21T18:16:54.467827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.437667243s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.108\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-21T18:16:54.467871Z","caller":"traceutil/trace.go:171","msg":"trace[280588329] range","detail":"{range_begin:/registry/masterleases/199.19.213.108; range_end:; response_count:1; response_revision:767; }","duration":"2.437781146s","start":"2026-04-21T18:16:52.030076Z","end":"2026-04-21T18:16:54.467857Z","steps":["trace[280588329] 'agreement among raft nodes before linearized reading' (duration: 2.4375662s)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:16:54.467866Z","caller":"traceutil/trace.go:171","msg":"trace[1372088072] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:767; }","duration":"240.319526ms","start":"2026-04-21T18:16:54.227533Z","end":"2026-04-21T18:16:54.467852Z","steps":["trace[1372088072] 'agreement among raft nodes before linearized reading' (duration: 240.217583ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:16:54.467912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:16:52.030053Z","time spent":"2.437850277s","remote":"127.0.0.1:57670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.108\" "} {"level":"warn","ts":"2026-04-21T18:16:54.467901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999301837s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T18:16:54.468033Z","caller":"traceutil/trace.go:171","msg":"trace[1905253722] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:767; }","duration":"1.999505891s","start":"2026-04-21T18:16:52.468499Z","end":"2026-04-21T18:16:54.468005Z","steps":["trace[1905253722] 'agreement among raft nodes before linearized reading' (duration: 1.999247214s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:16:54.4681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:16:52.468478Z","time spent":"1.999610325s","remote":"127.0.0.1:57914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"warn","ts":"2026-04-21T18:16:54.468307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"583.210601ms","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-04-21T18:16:54.46835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:16:52.256799Z","time spent":"2.210978166s","remote":"127.0.0.1:57868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T18:16:54.468479Z","caller":"traceutil/trace.go:171","msg":"trace[974641671] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:767; }","duration":"583.429416ms","start":"2026-04-21T18:16:53.885015Z","end":"2026-04-21T18:16:54.468445Z","steps":["trace[974641671] 'agreement among raft nodes before linearized reading' (duration: 583.131588ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:16:54.469742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:16:53.88498Z","time spent":"584.408254ms","remote":"127.0.0.1:57868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-21T18:16:54.583928Z","caller":"traceutil/trace.go:171","msg":"trace[1686789570] linearizableReadLoop","detail":"{readStateIndex:796; appliedIndex:795; }","duration":"108.627918ms","start":"2026-04-21T18:16:54.475256Z","end":"2026-04-21T18:16:54.583883Z","steps":["trace[1686789570] 'read index received' (duration: 107.150757ms)","trace[1686789570] 'applied index is now lower than readState.Index' (duration: 1.473071ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T18:16:54.584446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.927626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T18:16:54.584545Z","caller":"traceutil/trace.go:171","msg":"trace[1473753573] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"108.581576ms","start":"2026-04-21T18:16:54.475934Z","end":"2026-04-21T18:16:54.584516Z","steps":["trace[1473753573] 'process raft request' (duration: 108.139674ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:16:54.584603Z","caller":"traceutil/trace.go:171","msg":"trace[1796785808] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:767; }","duration":"109.345087ms","start":"2026-04-21T18:16:54.475214Z","end":"2026-04-21T18:16:54.584559Z","steps":["trace[1796785808] 'agreement among raft nodes before linearized reading' (duration: 108.768681ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:17:03.546348Z","caller":"traceutil/trace.go:171","msg":"trace[49657027] transaction","detail":"{read_only:false; response_revision:874; number_of_response:1; }","duration":"137.049148ms","start":"2026-04-21T18:17:03.409265Z","end":"2026-04-21T18:17:03.546315Z","steps":["trace[49657027] 'process raft request' (duration: 131.695679ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:17:03.54661Z","caller":"traceutil/trace.go:171","msg":"trace[1000775114] transaction","detail":"{read_only:false; response_revision:879; number_of_response:1; }","duration":"131.468892ms","start":"2026-04-21T18:17:03.415115Z","end":"2026-04-21T18:17:03.546584Z","steps":["trace[1000775114] 'process raft request' (duration: 131.391949ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:17:03.546888Z","caller":"traceutil/trace.go:171","msg":"trace[334869936] transaction","detail":"{read_only:false; response_revision:875; number_of_response:1; }","duration":"137.52371ms","start":"2026-04-21T18:17:03.409342Z","end":"2026-04-21T18:17:03.546866Z","steps":["trace[334869936] 'process raft request' (duration: 136.83696ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:17:03.546999Z","caller":"traceutil/trace.go:171","msg":"trace[797548949] transaction","detail":"{read_only:false; response_revision:878; number_of_response:1; }","duration":"134.27707ms","start":"2026-04-21T18:17:03.412702Z","end":"2026-04-21T18:17:03.546979Z","steps":["trace[797548949] 'process raft request' (duration: 133.720855ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:17:03.546987Z","caller":"traceutil/trace.go:171","msg":"trace[975201540] transaction","detail":"{read_only:false; response_revision:877; number_of_response:1; }","duration":"136.699138ms","start":"2026-04-21T18:17:03.41026Z","end":"2026-04-21T18:17:03.546959Z","steps":["trace[975201540] 'process raft request' (duration: 136.106761ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:17:03.547455Z","caller":"traceutil/trace.go:171","msg":"trace[2103560598] transaction","detail":"{read_only:false; response_revision:876; number_of_response:1; }","duration":"136.958764ms","start":"2026-04-21T18:17:03.40975Z","end":"2026-04-21T18:17:03.546709Z","steps":["trace[2103560598] 'process raft request' (duration: 136.531222ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:17:30.727287Z","caller":"traceutil/trace.go:171","msg":"trace[1906223988] linearizableReadLoop","detail":"{readStateIndex:1020; appliedIndex:1019; }","duration":"197.324128ms","start":"2026-04-21T18:17:30.52991Z","end":"2026-04-21T18:17:30.727234Z","steps":["trace[1906223988] 'read index received' (duration: 197.071221ms)","trace[1906223988] 'applied index is now lower than readState.Index' (duration: 251.396µs)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:17:30.727406Z","caller":"traceutil/trace.go:171","msg":"trace[1683590943] transaction","detail":"{read_only:false; response_revision:982; number_of_response:1; }","duration":"221.156111ms","start":"2026-04-21T18:17:30.506199Z","end":"2026-04-21T18:17:30.727356Z","steps":["trace[1683590943] 'process raft request' (duration: 220.837272ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:17:30.727544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.586384ms","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-04-21T18:17:30.727588Z","caller":"traceutil/trace.go:171","msg":"trace[127444582] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:982; }","duration":"197.708359ms","start":"2026-04-21T18:17:30.529867Z","end":"2026-04-21T18:17:30.727576Z","steps":["trace[127444582] 'agreement among raft nodes before linearized reading' (duration: 197.595226ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:17:30.727744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.11672ms","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-04-21T18:17:30.727793Z","caller":"traceutil/trace.go:171","msg":"trace[395268379] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:982; }","duration":"174.215454ms","start":"2026-04-21T18:17:30.553564Z","end":"2026-04-21T18:17:30.727779Z","steps":["trace[395268379] 'agreement among raft nodes before linearized reading' (duration: 174.05385ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:17:30.727992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.180711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-21T18:17:30.728034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.632953ms","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-04-21T18:17:30.72804Z","caller":"traceutil/trace.go:171","msg":"trace[32511936] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:982; }","duration":"161.240202ms","start":"2026-04-21T18:17:30.566785Z","end":"2026-04-21T18:17:30.728025Z","steps":["trace[32511936] 'agreement among raft nodes before linearized reading' (duration: 161.067348ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:17:30.728065Z","caller":"traceutil/trace.go:171","msg":"trace[295507042] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:982; }","duration":"161.712846ms","start":"2026-04-21T18:17:30.566344Z","end":"2026-04-21T18:17:30.728057Z","steps":["trace[295507042] 'agreement among raft nodes before linearized reading' (duration: 161.573321ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:17:31.252822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.245937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-21T18:17:31.252898Z","caller":"traceutil/trace.go:171","msg":"trace[1520139111] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:985; }","duration":"243.377601ms","start":"2026-04-21T18:17:31.009498Z","end":"2026-04-21T18:17:31.252876Z","steps":["trace[1520139111] 'count revisions from in-memory index tree' (duration: 243.131784ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:17:31.252935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.922235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T18:17:31.253034Z","caller":"traceutil/trace.go:171","msg":"trace[763975252] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:985; }","duration":"394.0841ms","start":"2026-04-21T18:17:30.858931Z","end":"2026-04-21T18:17:31.253015Z","steps":["trace[763975252] 'count revisions from in-memory index tree' (duration: 393.833603ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:17:31.25308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:17:30.8589Z","time spent":"394.168762ms","remote":"127.0.0.1:57974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"warn","ts":"2026-04-21T18:17:31.253046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.912011ms","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-04-21T18:17:31.253157Z","caller":"traceutil/trace.go:171","msg":"trace[375518082] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:985; }","duration":"452.065286ms","start":"2026-04-21T18:17:30.801058Z","end":"2026-04-21T18:17:31.253123Z","steps":["trace[375518082] 'range keys from in-memory index tree' (duration: 451.696725ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:17:31.253207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:17:30.801035Z","time spent":"452.159119ms","remote":"127.0.0.1:57868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-21T18:17:32.528454Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.042892167s","expected-duration":"1s"} {"level":"info","ts":"2026-04-21T18:17:32.528991Z","caller":"traceutil/trace.go:171","msg":"trace[267642990] transaction","detail":"{read_only:false; response_revision:986; number_of_response:1; }","duration":"1.271734862s","start":"2026-04-21T18:17:31.257223Z","end":"2026-04-21T18:17:32.528958Z","steps":["trace[267642990] 'process raft request' (duration: 1.271546097s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:17:32.529227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:17:31.257206Z","time spent":"1.271902426s","remote":"127.0.0.1:57868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T18:17:32.672922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.837755ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-21T18:17:32.673029Z","caller":"traceutil/trace.go:171","msg":"trace[827584509] linearizableReadLoop","detail":"{readStateIndex:1025; appliedIndex:1023; }","duration":"641.972866ms","start":"2026-04-21T18:17:32.03104Z","end":"2026-04-21T18:17:32.673013Z","steps":["trace[827584509] 'read index received' (duration: 497.82326ms)","trace[827584509] 'applied index is now lower than readState.Index' (duration: 144.148696ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T18:17:32.673188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"642.163261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.108\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-21T18:17:32.673161Z","caller":"traceutil/trace.go:171","msg":"trace[726382088] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"1.059404417s","start":"2026-04-21T18:17:31.613705Z","end":"2026-04-21T18:17:32.67311Z","steps":["trace[726382088] 'process raft request' (duration: 951.275175ms)","trace[726382088] 'compare' (duration: 107.67796ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:17:32.673215Z","caller":"traceutil/trace.go:171","msg":"trace[773741400] range","detail":"{range_begin:/registry/masterleases/199.19.213.108; range_end:; response_count:1; response_revision:987; }","duration":"642.239183ms","start":"2026-04-21T18:17:32.030969Z","end":"2026-04-21T18:17:32.673208Z","steps":["trace[773741400] 'agreement among raft nodes before linearized reading' (duration: 642.11946ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:17:32.673373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:17:31.613682Z","time spent":"1.059574602s","remote":"127.0.0.1:57868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T18:17:32.673429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:17:32.030944Z","time spent":"642.430019ms","remote":"127.0.0.1:57670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.108\" "} {"level":"warn","ts":"2026-04-21T18:17:32.673476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.886339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T18:17:32.673541Z","caller":"traceutil/trace.go:171","msg":"trace[1744780876] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:987; }","duration":"298.008503ms","start":"2026-04-21T18:17:32.375516Z","end":"2026-04-21T18:17:32.673524Z","steps":["trace[1744780876] 'agreement among raft nodes before linearized reading' (duration: 297.90771ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:17:32.673911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.967614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T18:17:32.673999Z","caller":"traceutil/trace.go:171","msg":"trace[964178925] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:987; }","duration":"447.094556ms","start":"2026-04-21T18:17:32.226888Z","end":"2026-04-21T18:17:32.673983Z","steps":["trace[964178925] 'agreement among raft nodes before linearized reading' (duration: 446.394837ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:17:32.674042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:17:32.226862Z","time spent":"447.174879ms","remote":"127.0.0.1:57638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-21T18:17:59.147836Z","caller":"traceutil/trace.go:171","msg":"trace[498520727] transaction","detail":"{read_only:false; response_revision:1073; number_of_response:1; }","duration":"464.203553ms","start":"2026-04-21T18:17:58.683529Z","end":"2026-04-21T18:17:59.147733Z","steps":["trace[498520727] 'process raft request' (duration: 463.966996ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:17:59.148046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:17:58.683502Z","time spent":"464.44803ms","remote":"127.0.0.1:57868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T18:17:59.675782Z","caller":"traceutil/trace.go:171","msg":"trace[791077113] transaction","detail":"{read_only:false; response_revision:1074; number_of_response:1; }","duration":"899.880462ms","start":"2026-04-21T18:17:58.775873Z","end":"2026-04-21T18:17:59.675753Z","steps":["trace[791077113] 'process raft request' (duration: 898.187426ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:17:59.676155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:17:58.775843Z","time spent":"900.214611ms","remote":"127.0.0.1:57868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T18:17:59.675869Z","caller":"traceutil/trace.go:171","msg":"trace[1181566234] linearizableReadLoop","detail":"{readStateIndex:1118; appliedIndex:1117; }","duration":"859.417914ms","start":"2026-04-21T18:17:58.815806Z","end":"2026-04-21T18:17:59.675224Z","steps":["trace[1181566234] 'read index received' (duration: 331.920288ms)","trace[1181566234] 'applied index is now lower than readState.Index' (duration: 527.495826ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T18:17:59.677367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"722.763917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-21T18:17:59.677397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"791.882163ms","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-04-21T18:17:59.677426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.839523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T18:17:59.677443Z","caller":"traceutil/trace.go:171","msg":"trace[322805681] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1074; }","duration":"722.910982ms","start":"2026-04-21T18:17:58.954516Z","end":"2026-04-21T18:17:59.677427Z","steps":["trace[322805681] 'agreement among raft nodes before linearized reading' (duration: 722.690996ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:17:59.677462Z","caller":"traceutil/trace.go:171","msg":"trace[1254485594] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1074; }","duration":"792.007286ms","start":"2026-04-21T18:17:58.885437Z","end":"2026-04-21T18:17:59.677444Z","steps":["trace[1254485594] 'agreement among raft nodes before linearized reading' (duration: 791.77152ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:17:59.677498Z","caller":"traceutil/trace.go:171","msg":"trace[776698638] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1074; }","duration":"449.958656ms","start":"2026-04-21T18:17:59.227522Z","end":"2026-04-21T18:17:59.677481Z","steps":["trace[776698638] 'agreement among raft nodes before linearized reading' (duration: 449.841833ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:17:59.677503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:17:58.954485Z","time spent":"723.009593ms","remote":"127.0.0.1:57868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-21T18:17:59.677544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:17:59.227494Z","time spent":"450.041628ms","remote":"127.0.0.1:57638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-21T18:17:59.677579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:17:58.885407Z","time spent":"792.095438ms","remote":"127.0.0.1:57868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-21T18:17:59.67755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"741.969413ms","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-04-21T18:17:59.677642Z","caller":"traceutil/trace.go:171","msg":"trace[1413838845] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1074; }","duration":"742.078926ms","start":"2026-04-21T18:17:58.935542Z","end":"2026-04-21T18:17:59.677621Z","steps":["trace[1413838845] 'agreement among raft nodes before linearized reading' (duration: 741.841398ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:17:59.677692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:17:58.935474Z","time spent":"742.207989ms","remote":"127.0.0.1:57868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-21T18:17:59.677651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"730.73436ms","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-04-21T18:17:59.67825Z","caller":"traceutil/trace.go:171","msg":"trace[1440888155] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1074; }","duration":"731.405088ms","start":"2026-04-21T18:17:58.946767Z","end":"2026-04-21T18:17:59.678172Z","steps":["trace[1440888155] 'agreement among raft nodes before linearized reading' (duration: 730.453701ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:17:59.678349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:17:58.946754Z","time spent":"731.580383ms","remote":"127.0.0.1:57868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-21T18:17:59.677791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"861.995717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-21T18:17:59.678654Z","caller":"traceutil/trace.go:171","msg":"trace[1039007728] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1074; }","duration":"862.868662ms","start":"2026-04-21T18:17:58.815721Z","end":"2026-04-21T18:17:59.67859Z","steps":["trace[1039007728] 'agreement among raft nodes before linearized reading' (duration: 860.364831ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:17:59.679583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:17:58.815679Z","time spent":"863.821908ms","remote":"127.0.0.1:58022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-04-21T18:18:00.344499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.141816ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-21T18:18:00.344756Z","caller":"traceutil/trace.go:171","msg":"trace[1700501891] transaction","detail":"{read_only:false; response_revision:1075; number_of_response:1; }","duration":"662.272511ms","start":"2026-04-21T18:17:59.682352Z","end":"2026-04-21T18:18:00.344625Z","steps":["trace[1700501891] 'process raft request' (duration: 255.803776ms)","trace[1700501891] 'compare' (duration: 405.814847ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T18:18:00.34503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:17:59.682326Z","time spent":"662.512609ms","remote":"127.0.0.1:57868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T18:18:00.370967Z","caller":"traceutil/trace.go:171","msg":"trace[1398251668] transaction","detail":"{read_only:false; response_revision:1077; number_of_response:1; }","duration":"688.247435ms","start":"2026-04-21T18:17:59.68268Z","end":"2026-04-21T18:18:00.370928Z","steps":["trace[1398251668] 'process raft request' (duration: 688.150362ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:18:00.371359Z","caller":"traceutil/trace.go:171","msg":"trace[451068914] linearizableReadLoop","detail":"{readStateIndex:1122; appliedIndex:1119; }","duration":"210.280289ms","start":"2026-04-21T18:18:00.161051Z","end":"2026-04-21T18:18:00.371331Z","steps":["trace[451068914] 'read index received' (duration: 209.521008ms)","trace[451068914] 'applied index is now lower than readState.Index' (duration: 757.731µs)"],"step_count":2} {"level":"warn","ts":"2026-04-21T18:18:00.371395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:17:59.682662Z","time spent":"688.503803ms","remote":"127.0.0.1:57868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T18:18:00.371532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.461484ms","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-04-21T18:18:00.371693Z","caller":"traceutil/trace.go:171","msg":"trace[1368235977] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:1078; }","duration":"210.67697ms","start":"2026-04-21T18:18:00.160999Z","end":"2026-04-21T18:18:00.371676Z","steps":["trace[1368235977] 'agreement among raft nodes before linearized reading' (duration: 210.444213ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:18:00.371721Z","caller":"traceutil/trace.go:171","msg":"trace[113202150] transaction","detail":"{read_only:false; response_revision:1078; number_of_response:1; }","duration":"688.093801ms","start":"2026-04-21T18:17:59.683505Z","end":"2026-04-21T18:18:00.371599Z","steps":["trace[113202150] 'process raft request' (duration: 687.369962ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:18:00.371619Z","caller":"traceutil/trace.go:171","msg":"trace[821793481] transaction","detail":"{read_only:false; response_revision:1076; number_of_response:1; }","duration":"688.869324ms","start":"2026-04-21T18:17:59.682554Z","end":"2026-04-21T18:18:00.371423Z","steps":["trace[821793481] 'process raft request' (duration: 688.01575ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:18:00.372167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:17:59.683497Z","time spent":"688.455242ms","remote":"127.0.0.1:57868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T18:18:00.372257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:17:59.68253Z","time spent":"689.643835ms","remote":"127.0.0.1:57868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T18:18:01.424907Z","caller":"traceutil/trace.go:171","msg":"trace[1727106812] linearizableReadLoop","detail":"{readStateIndex:1123; appliedIndex:1122; }","duration":"198.563182ms","start":"2026-04-21T18:18:01.226318Z","end":"2026-04-21T18:18:01.424881Z","steps":["trace[1727106812] 'read index received' (duration: 198.296934ms)","trace[1727106812] 'applied index is now lower than readState.Index' (duration: 259.608µs)"],"step_count":2} {"level":"warn","ts":"2026-04-21T18:18:01.425048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.702127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T18:18:01.425098Z","caller":"traceutil/trace.go:171","msg":"trace[1762612615] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1079; }","duration":"198.810289ms","start":"2026-04-21T18:18:01.226274Z","end":"2026-04-21T18:18:01.425084Z","steps":["trace[1762612615] 'agreement among raft nodes before linearized reading' (duration: 198.713216ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:18:01.424961Z","caller":"traceutil/trace.go:171","msg":"trace[1762793304] transaction","detail":"{read_only:false; response_revision:1079; number_of_response:1; }","duration":"266.927557ms","start":"2026-04-21T18:18:01.158016Z","end":"2026-04-21T18:18:01.424944Z","steps":["trace[1762793304] 'process raft request' (duration: 266.67748ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:18:02.318471Z","caller":"traceutil/trace.go:171","msg":"trace[1525856699] linearizableReadLoop","detail":"{readStateIndex:1124; appliedIndex:1123; }","duration":"286.125742ms","start":"2026-04-21T18:18:02.032306Z","end":"2026-04-21T18:18:02.318432Z","steps":["trace[1525856699] 'read index received' (duration: 192.73638ms)","trace[1525856699] 'applied index is now lower than readState.Index' (duration: 93.387862ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:18:02.318564Z","caller":"traceutil/trace.go:171","msg":"trace[234490714] transaction","detail":"{read_only:false; response_revision:1080; number_of_response:1; }","duration":"634.846288ms","start":"2026-04-21T18:18:01.683668Z","end":"2026-04-21T18:18:02.318515Z","steps":["trace[234490714] 'process raft request' (duration: 541.326221ms)","trace[234490714] 'compare' (duration: 92.516258ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T18:18:02.318785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:18:01.683643Z","time spent":"635.010743ms","remote":"127.0.0.1:57868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T18:18:02.319232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.467522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.108\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-21T18:18:02.319302Z","caller":"traceutil/trace.go:171","msg":"trace[462847841] range","detail":"{range_begin:/registry/masterleases/199.19.213.108; range_end:; response_count:1; response_revision:1080; }","duration":"287.045858ms","start":"2026-04-21T18:18:02.032241Z","end":"2026-04-21T18:18:02.319287Z","steps":["trace[462847841] 'agreement among raft nodes before linearized reading' (duration: 286.38466ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:18:03.598567Z","caller":"traceutil/trace.go:171","msg":"trace[158941442] transaction","detail":"{read_only:false; response_revision:1086; number_of_response:1; }","duration":"164.057351ms","start":"2026-04-21T18:18:03.43448Z","end":"2026-04-21T18:18:03.598538Z","steps":["trace[158941442] 'process raft request' (duration: 163.876497ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:18:04.499413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.161086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T18:18:04.499536Z","caller":"traceutil/trace.go:171","msg":"trace[390564693] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1086; }","duration":"271.362762ms","start":"2026-04-21T18:18:04.228149Z","end":"2026-04-21T18:18:04.499511Z","steps":["trace[390564693] 'range keys from in-memory index tree' (duration: 271.047162ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:18:04.50367Z","caller":"traceutil/trace.go:171","msg":"trace[1404428317] linearizableReadLoop","detail":"{readStateIndex:1132; appliedIndex:1131; }","duration":"120.60976ms","start":"2026-04-21T18:18:04.383035Z","end":"2026-04-21T18:18:04.503645Z","steps":["trace[1404428317] 'read index received' (duration: 120.392914ms)","trace[1404428317] 'applied index is now lower than readState.Index' (duration: 215.886µs)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:18:04.503733Z","caller":"traceutil/trace.go:171","msg":"trace[411999662] transaction","detail":"{read_only:false; response_revision:1087; number_of_response:1; }","duration":"178.995637ms","start":"2026-04-21T18:18:04.32471Z","end":"2026-04-21T18:18:04.503706Z","steps":["trace[411999662] 'process raft request' (duration: 178.774251ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:18:04.503946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.890367ms","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-04-21T18:18:04.503985Z","caller":"traceutil/trace.go:171","msg":"trace[2051632502] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1087; }","duration":"120.985441ms","start":"2026-04-21T18:18:04.382987Z","end":"2026-04-21T18:18:04.503972Z","steps":["trace[2051632502] 'agreement among raft nodes before linearized reading' (duration: 120.798976ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:18:04.504004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.215717ms","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-04-21T18:18:04.504053Z","caller":"traceutil/trace.go:171","msg":"trace[2083392220] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1087; }","duration":"102.31246ms","start":"2026-04-21T18:18:04.401727Z","end":"2026-04-21T18:18:04.50404Z","steps":["trace[2083392220] 'agreement among raft nodes before linearized reading' (duration: 102.115085ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:18:05.317073Z","caller":"traceutil/trace.go:171","msg":"trace[793045861] transaction","detail":"{read_only:false; response_revision:1089; number_of_response:1; }","duration":"806.786398ms","start":"2026-04-21T18:18:04.510259Z","end":"2026-04-21T18:18:05.317045Z","steps":["trace[793045861] 'process raft request' (duration: 806.487401ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:18:05.317242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:18:04.510252Z","time spent":"806.905031ms","remote":"127.0.0.1:57868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T18:18:05.317368Z","caller":"traceutil/trace.go:171","msg":"trace[539108946] transaction","detail":"{read_only:false; response_revision:1090; number_of_response:1; }","duration":"806.955604ms","start":"2026-04-21T18:18:04.510391Z","end":"2026-04-21T18:18:05.317347Z","steps":["trace[539108946] 'process raft request' (duration: 806.545592ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:18:05.317475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:18:04.510387Z","time spent":"807.035116ms","remote":"127.0.0.1:57868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T18:18:05.317662Z","caller":"traceutil/trace.go:171","msg":"trace[1672980946] linearizableReadLoop","detail":"{readStateIndex:1136; appliedIndex:1133; }","duration":"400.674943ms","start":"2026-04-21T18:18:04.916918Z","end":"2026-04-21T18:18:05.317593Z","steps":["trace[1672980946] 'read index received' (duration: 399.726658ms)","trace[1672980946] 'applied index is now lower than readState.Index' (duration: 947.115µs)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:18:05.317673Z","caller":"traceutil/trace.go:171","msg":"trace[818840434] transaction","detail":"{read_only:false; response_revision:1091; number_of_response:1; }","duration":"807.027006ms","start":"2026-04-21T18:18:04.510625Z","end":"2026-04-21T18:18:05.317652Z","steps":["trace[818840434] 'process raft request' (duration: 806.645675ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:18:05.317772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.857598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-21T18:18:05.31777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:18:04.510588Z","time spent":"807.130679ms","remote":"127.0.0.1:57868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T18:18:05.317809Z","caller":"traceutil/trace.go:171","msg":"trace[378411454] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:1091; }","duration":"400.898669ms","start":"2026-04-21T18:18:04.916896Z","end":"2026-04-21T18:18:05.317794Z","steps":["trace[378411454] 'agreement among raft nodes before linearized reading' (duration: 400.828768ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:18:05.317842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:18:04.916824Z","time spent":"401.009473ms","remote":"127.0.0.1:34622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true "} {"level":"info","ts":"2026-04-21T18:18:17.789248Z","caller":"traceutil/trace.go:171","msg":"trace[789338566] linearizableReadLoop","detail":"{readStateIndex:1230; appliedIndex:1229; }","duration":"384.470966ms","start":"2026-04-21T18:18:17.404753Z","end":"2026-04-21T18:18:17.789224Z","steps":["trace[789338566] 'read index received' (duration: 384.404403ms)","trace[789338566] 'applied index is now lower than readState.Index' (duration: 65.403µs)"],"step_count":2} {"level":"warn","ts":"2026-04-21T18:18:17.78952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.749162ms","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-04-21T18:18:17.789492Z","caller":"traceutil/trace.go:171","msg":"trace[848277967] transaction","detail":"{read_only:false; response_revision:1181; number_of_response:1; }","duration":"394.944364ms","start":"2026-04-21T18:18:17.394512Z","end":"2026-04-21T18:18:17.789456Z","steps":["trace[848277967] 'process raft request' (duration: 394.566303ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:18:17.789558Z","caller":"traceutil/trace.go:171","msg":"trace[1531482299] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1181; }","duration":"384.806195ms","start":"2026-04-21T18:18:17.404742Z","end":"2026-04-21T18:18:17.789548Z","steps":["trace[1531482299] 'agreement among raft nodes before linearized reading' (duration: 384.602979ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:18:17.789588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:18:17.404693Z","time spent":"384.888857ms","remote":"127.0.0.1:57868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-21T18:18:17.789755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:18:17.394489Z","time spent":"395.096408ms","remote":"127.0.0.1:57868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T18:18:17.795366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.746531ms","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-04-21T18:18:17.795416Z","caller":"traceutil/trace.go:171","msg":"trace[275735599] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1181; }","duration":"385.823442ms","start":"2026-04-21T18:18:17.40958Z","end":"2026-04-21T18:18:17.795404Z","steps":["trace[275735599] 'agreement among raft nodes before linearized reading' (duration: 385.654108ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:18:17.795443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:18:17.409571Z","time spent":"385.867344ms","remote":"127.0.0.1:57868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-21T18:18:17.795551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.372628ms","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-04-21T18:18:17.79564Z","caller":"traceutil/trace.go:171","msg":"trace[1166657657] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1182; }","duration":"128.515642ms","start":"2026-04-21T18:18:17.667103Z","end":"2026-04-21T18:18:17.795618Z","steps":["trace[1166657657] 'agreement among raft nodes before linearized reading' (duration: 128.177562ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:18:17.79593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.574148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T18:18:17.795982Z","caller":"traceutil/trace.go:171","msg":"trace[1335755275] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1182; }","duration":"114.665019ms","start":"2026-04-21T18:18:17.681303Z","end":"2026-04-21T18:18:17.795968Z","steps":["trace[1335755275] 'agreement among raft nodes before linearized reading' (duration: 113.93811ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:18:18.726464Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2330223241843311905,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-21T18:18:19.227557Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2330223241843311905,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-21T18:18:19.641576Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.740755151s","expected-duration":"1s"} {"level":"info","ts":"2026-04-21T18:18:19.654655Z","caller":"traceutil/trace.go:171","msg":"trace[1262354800] transaction","detail":"{read_only:false; response_revision:1185; number_of_response:1; }","duration":"1.753971816s","start":"2026-04-21T18:18:17.900644Z","end":"2026-04-21T18:18:19.654616Z","steps":["trace[1262354800] 'process raft request' (duration: 1.741393339s)","trace[1262354800] 'compare' (duration: 11.745654ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T18:18:19.654671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"710.686085ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-21T18:18:19.654862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:18:17.900611Z","time spent":"1.75414265s","remote":"127.0.0.1:57868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T18:18:19.65492Z","caller":"traceutil/trace.go:171","msg":"trace[936440180] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1184; }","duration":"711.658652ms","start":"2026-04-21T18:18:18.943231Z","end":"2026-04-21T18:18:19.65489Z","steps":["trace[936440180] 'range keys from in-memory index tree' (duration: 710.654235ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:18:19.664857Z","caller":"traceutil/trace.go:171","msg":"trace[984131385] linearizableReadLoop","detail":"{readStateIndex:1236; appliedIndex:1234; }","duration":"1.438876893s","start":"2026-04-21T18:18:18.225934Z","end":"2026-04-21T18:18:19.66481Z","steps":["trace[984131385] 'read index received' (duration: 1.416117005s)","trace[984131385] 'applied index is now lower than readState.Index' (duration: 22.758957ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:18:19.664956Z","caller":"traceutil/trace.go:171","msg":"trace[1096184014] transaction","detail":"{read_only:false; response_revision:1186; number_of_response:1; }","duration":"1.061579926s","start":"2026-04-21T18:18:18.603358Z","end":"2026-04-21T18:18:19.664938Z","steps":["trace[1096184014] 'process raft request' (duration: 1.061290997s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:18:19.665035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.439061868s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T18:18:19.665071Z","caller":"traceutil/trace.go:171","msg":"trace[645839273] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1186; }","duration":"1.439169581s","start":"2026-04-21T18:18:18.225894Z","end":"2026-04-21T18:18:19.665063Z","steps":["trace[645839273] 'agreement among raft nodes before linearized reading' (duration: 1.439068728s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:18:19.665099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:18:18.225874Z","time spent":"1.439220272s","remote":"127.0.0.1:57638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-21T18:18:19.665099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:18:18.603329Z","time spent":"1.061689988s","remote":"127.0.0.1:57868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T18:18:19.665241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"920.25691ms","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-04-21T18:18:19.665325Z","caller":"traceutil/trace.go:171","msg":"trace[1877647584] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1186; }","duration":"920.399054ms","start":"2026-04-21T18:18:18.744905Z","end":"2026-04-21T18:18:19.665304Z","steps":["trace[1877647584] 'agreement among raft nodes before linearized reading' (duration: 920.133847ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:18:19.665365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:18:18.744884Z","time spent":"920.473906ms","remote":"127.0.0.1:57868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-21T18:18:27.887077Z","caller":"traceutil/trace.go:171","msg":"trace[410976428] transaction","detail":"{read_only:false; response_revision:1213; number_of_response:1; }","duration":"115.365119ms","start":"2026-04-21T18:18:27.771676Z","end":"2026-04-21T18:18:27.887041Z","steps":["trace[410976428] 'process raft request' (duration: 32.213328ms)","trace[410976428] 'compare' (duration: 82.7393ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T18:18:28.126794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.175928ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-21T18:18:28.126938Z","caller":"traceutil/trace.go:171","msg":"trace[241596631] linearizableReadLoop","detail":"{readStateIndex:1267; appliedIndex:1265; }","duration":"229.924107ms","start":"2026-04-21T18:18:27.896994Z","end":"2026-04-21T18:18:28.126918Z","steps":["trace[241596631] 'read index received' (duration: 33.755801ms)","trace[241596631] 'applied index is now lower than readState.Index' (duration: 196.166696ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:18:28.127059Z","caller":"traceutil/trace.go:171","msg":"trace[319073665] transaction","detail":"{read_only:false; response_revision:1215; number_of_response:1; }","duration":"235.053008ms","start":"2026-04-21T18:18:27.891992Z","end":"2026-04-21T18:18:28.127045Z","steps":["trace[319073665] 'process raft request' (duration: 133.508569ms)","trace[319073665] 'compare' (duration: 101.027834ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T18:18:28.127339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.416104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-21T18:18:28.127371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.343028ms","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-04-21T18:18:28.127466Z","caller":"traceutil/trace.go:171","msg":"trace[2020685964] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1215; }","duration":"230.501482ms","start":"2026-04-21T18:18:27.896943Z","end":"2026-04-21T18:18:28.127444Z","steps":["trace[2020685964] 'agreement among raft nodes before linearized reading' (duration: 230.181183ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:18:28.127458Z","caller":"traceutil/trace.go:171","msg":"trace[308762466] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1215; }","duration":"220.594879ms","start":"2026-04-21T18:18:27.906835Z","end":"2026-04-21T18:18:28.12743Z","steps":["trace[308762466] 'agreement among raft nodes before linearized reading' (duration: 220.2603ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:18:34.133651Z","caller":"traceutil/trace.go:171","msg":"trace[817460900] linearizableReadLoop","detail":"{readStateIndex:1286; appliedIndex:1285; }","duration":"151.264848ms","start":"2026-04-21T18:18:33.982342Z","end":"2026-04-21T18:18:34.133607Z","steps":["trace[817460900] 'read index received' (duration: 150.96005ms)","trace[817460900] 'applied index is now lower than readState.Index' (duration: 303.518µs)"],"step_count":2} {"level":"warn","ts":"2026-04-21T18:18:34.134736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.593487ms","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-04-21T18:18:34.134878Z","caller":"traceutil/trace.go:171","msg":"trace[602796347] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1233; }","duration":"152.558214ms","start":"2026-04-21T18:18:33.982289Z","end":"2026-04-21T18:18:34.134847Z","steps":["trace[602796347] 'agreement among raft nodes before linearized reading' (duration: 151.470765ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:18:34.135467Z","caller":"traceutil/trace.go:171","msg":"trace[2029871775] transaction","detail":"{read_only:false; response_revision:1233; number_of_response:1; }","duration":"210.399438ms","start":"2026-04-21T18:18:33.923313Z","end":"2026-04-21T18:18:34.133713Z","steps":["trace[2029871775] 'process raft request' (duration: 210.059019ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:18:37.728415Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2330223241843312097,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-21T18:18:38.418258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.937439ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-21T18:18:38.41851Z","caller":"traceutil/trace.go:171","msg":"trace[1786627745] linearizableReadLoop","detail":"{readStateIndex:1318; appliedIndex:1317; }","duration":"1.191146547s","start":"2026-04-21T18:18:37.227345Z","end":"2026-04-21T18:18:38.418491Z","steps":["trace[1786627745] 'read index received' (duration: 650.95122ms)","trace[1786627745] 'applied index is now lower than readState.Index' (duration: 540.194227ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T18:18:38.418663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.19130731s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T18:18:38.418753Z","caller":"traceutil/trace.go:171","msg":"trace[889237734] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1264; }","duration":"1.191453934s","start":"2026-04-21T18:18:37.227282Z","end":"2026-04-21T18:18:38.418736Z","steps":["trace[889237734] 'agreement among raft nodes before linearized reading' (duration: 1.19130363s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:18:38.418791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:18:37.227259Z","time spent":"1.191526305s","remote":"127.0.0.1:57638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-21T18:18:38.419072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.529608ms","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-04-21T18:18:38.419111Z","caller":"traceutil/trace.go:171","msg":"trace[324280298] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1265; }","duration":"265.586559ms","start":"2026-04-21T18:18:38.153515Z","end":"2026-04-21T18:18:38.419101Z","steps":["trace[324280298] 'agreement among raft nodes before linearized reading' (duration: 265.399854ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:18:38.419077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.746337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-21T18:18:38.419146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.025925ms","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-04-21T18:18:38.419177Z","caller":"traceutil/trace.go:171","msg":"trace[574407051] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1265; }","duration":"236.100836ms","start":"2026-04-21T18:18:38.183069Z","end":"2026-04-21T18:18:38.419169Z","steps":["trace[574407051] 'agreement among raft nodes before linearized reading' (duration: 235.981923ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:18:38.419193Z","caller":"traceutil/trace.go:171","msg":"trace[523321871] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1265; }","duration":"268.910991ms","start":"2026-04-21T18:18:38.150256Z","end":"2026-04-21T18:18:38.419167Z","steps":["trace[523321871] 'agreement among raft nodes before linearized reading' (duration: 268.579542ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:18:38.419077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.160473ms","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-04-21T18:18:38.419241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.788365ms","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-04-21T18:18:38.419331Z","caller":"traceutil/trace.go:171","msg":"trace[1174015060] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1265; }","duration":"234.456511ms","start":"2026-04-21T18:18:38.184856Z","end":"2026-04-21T18:18:38.419313Z","steps":["trace[1174015060] 'agreement among raft nodes before linearized reading' (duration: 233.993328ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:18:38.419382Z","caller":"traceutil/trace.go:171","msg":"trace[1425167625] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1265; }","duration":"236.998512ms","start":"2026-04-21T18:18:38.18236Z","end":"2026-04-21T18:18:38.419358Z","steps":["trace[1425167625] 'agreement among raft nodes before linearized reading' (duration: 236.537478ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:18:38.419301Z","caller":"traceutil/trace.go:171","msg":"trace[849895692] transaction","detail":"{read_only:false; response_revision:1265; number_of_response:1; }","duration":"596.254561ms","start":"2026-04-21T18:18:37.823024Z","end":"2026-04-21T18:18:38.419278Z","steps":["trace[849895692] 'process raft request' (duration: 595.359527ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:18:38.419627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:18:37.823005Z","time spent":"596.519249ms","remote":"127.0.0.1:57868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T18:18:38.640637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.175129ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-21T18:18:38.641147Z","caller":"traceutil/trace.go:171","msg":"trace[1901446026] transaction","detail":"{read_only:false; response_revision:1266; number_of_response:1; }","duration":"216.760583ms","start":"2026-04-21T18:18:38.424344Z","end":"2026-04-21T18:18:38.641104Z","steps":["trace[1901446026] 'process raft request' (duration: 98.998438ms)","trace[1901446026] 'compare' (duration: 116.887961ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:18:38.641352Z","caller":"traceutil/trace.go:171","msg":"trace[311641429] transaction","detail":"{read_only:false; response_revision:1267; number_of_response:1; }","duration":"215.217911ms","start":"2026-04-21T18:18:38.425999Z","end":"2026-04-21T18:18:38.641217Z","steps":["trace[311641429] 'process raft request' (duration: 215.006335ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:18:38.641465Z","caller":"traceutil/trace.go:171","msg":"trace[1299838487] transaction","detail":"{read_only:false; response_revision:1268; number_of_response:1; }","duration":"213.568625ms","start":"2026-04-21T18:18:38.42787Z","end":"2026-04-21T18:18:38.641439Z","steps":["trace[1299838487] 'process raft request' (duration: 213.280367ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:18:38.641879Z","caller":"traceutil/trace.go:171","msg":"trace[1085572872] linearizableReadLoop","detail":"{readStateIndex:1322; appliedIndex:1319; }","duration":"212.020943ms","start":"2026-04-21T18:18:38.429838Z","end":"2026-04-21T18:18:38.641859Z","steps":["trace[1085572872] 'read index received' (duration: 93.523768ms)","trace[1085572872] 'applied index is now lower than readState.Index' (duration: 118.496445ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T18:18:38.642021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.153766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T18:18:38.642054Z","caller":"traceutil/trace.go:171","msg":"trace[233749465] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1270; }","duration":"212.238729ms","start":"2026-04-21T18:18:38.429806Z","end":"2026-04-21T18:18:38.642045Z","steps":["trace[233749465] 'agreement among raft nodes before linearized reading' (duration: 212.137087ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:18:38.642059Z","caller":"traceutil/trace.go:171","msg":"trace[667091233] transaction","detail":"{read_only:false; response_revision:1269; number_of_response:1; }","duration":"211.290652ms","start":"2026-04-21T18:18:38.430757Z","end":"2026-04-21T18:18:38.642048Z","steps":["trace[667091233] 'process raft request' (duration: 210.619453ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:18:38.642194Z","caller":"traceutil/trace.go:171","msg":"trace[2135595845] transaction","detail":"{read_only:false; response_revision:1270; number_of_response:1; }","duration":"209.34378ms","start":"2026-04-21T18:18:38.432826Z","end":"2026-04-21T18:18:38.64217Z","steps":["trace[2135595845] 'process raft request' (duration: 208.909008ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:18:40.380359Z","caller":"traceutil/trace.go:171","msg":"trace[122282546] transaction","detail":"{read_only:false; response_revision:1272; number_of_response:1; }","duration":"101.778015ms","start":"2026-04-21T18:18:40.278548Z","end":"2026-04-21T18:18:40.380326Z","steps":["trace[122282546] 'process raft request' (duration: 101.575319ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:18:40.938092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.031642ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-21T18:18:40.938228Z","caller":"traceutil/trace.go:171","msg":"trace[1983444353] linearizableReadLoop","detail":"{readStateIndex:1327; appliedIndex:1326; }","duration":"313.15651ms","start":"2026-04-21T18:18:40.62505Z","end":"2026-04-21T18:18:40.938207Z","steps":["trace[1983444353] 'read index received' (duration: 90.813µs)","trace[1983444353] 'applied index is now lower than readState.Index' (duration: 313.063557ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T18:18:40.938502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.420577ms","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-04-21T18:18:40.938598Z","caller":"traceutil/trace.go:171","msg":"trace[2095845477] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:1273; }","duration":"313.599022ms","start":"2026-04-21T18:18:40.624978Z","end":"2026-04-21T18:18:40.938577Z","steps":["trace[2095845477] 'agreement among raft nodes before linearized reading' (duration: 313.428997ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:18:40.938644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.951224ms","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-04-21T18:18:40.938691Z","caller":"traceutil/trace.go:171","msg":"trace[1563732516] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1273; }","duration":"292.041238ms","start":"2026-04-21T18:18:40.646638Z","end":"2026-04-21T18:18:40.938679Z","steps":["trace[1563732516] 'agreement among raft nodes before linearized reading' (duration: 291.839722ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:18:40.938696Z","caller":"traceutil/trace.go:171","msg":"trace[725909389] transaction","detail":"{read_only:false; response_revision:1273; number_of_response:1; }","duration":"513.387787ms","start":"2026-04-21T18:18:40.425293Z","end":"2026-04-21T18:18:40.938681Z","steps":["trace[725909389] 'process raft request' (duration: 192.934406ms)","trace[725909389] 'compare' (duration: 318.654951ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T18:18:40.938791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:18:40.425264Z","time spent":"513.459679ms","remote":"127.0.0.1:57868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T18:18:40.938979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.206466ms","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-04-21T18:18:40.939087Z","caller":"traceutil/trace.go:171","msg":"trace[1160424503] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1273; }","duration":"291.343159ms","start":"2026-04-21T18:18:40.647734Z","end":"2026-04-21T18:18:40.939077Z","steps":["trace[1160424503] 'agreement among raft nodes before linearized reading' (duration: 291.160594ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:18:40.939209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.523444ms","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-04-21T18:18:40.939244Z","caller":"traceutil/trace.go:171","msg":"trace[1731285925] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1273; }","duration":"291.557694ms","start":"2026-04-21T18:18:40.647679Z","end":"2026-04-21T18:18:40.939237Z","steps":["trace[1731285925] 'agreement among raft nodes before linearized reading' (duration: 291.476592ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:18:40.938649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:18:40.624933Z","time spent":"313.705405ms","remote":"127.0.0.1:57968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":30,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"warn","ts":"2026-04-21T18:18:40.939431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.543403ms","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-04-21T18:18:40.939489Z","caller":"traceutil/trace.go:171","msg":"trace[2089887315] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1273; }","duration":"292.600164ms","start":"2026-04-21T18:18:40.646864Z","end":"2026-04-21T18:18:40.939464Z","steps":["trace[2089887315] 'agreement among raft nodes before linearized reading' (duration: 292.44763ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:18:40.939809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.297465ms","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-04-21T18:18:40.939888Z","caller":"traceutil/trace.go:171","msg":"trace[428671978] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1273; }","duration":"292.436489ms","start":"2026-04-21T18:18:40.647434Z","end":"2026-04-21T18:18:40.93987Z","steps":["trace[428671978] 'agreement among raft nodes before linearized reading' (duration: 292.160301ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:18:41.121421Z","caller":"traceutil/trace.go:171","msg":"trace[635706103] transaction","detail":"{read_only:false; response_revision:1276; number_of_response:1; }","duration":"176.747262ms","start":"2026-04-21T18:18:40.944649Z","end":"2026-04-21T18:18:41.121396Z","steps":["trace[635706103] 'process raft request' (duration: 101.693163ms)","trace[635706103] 'compare' (duration: 74.478282ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:18:41.121458Z","caller":"traceutil/trace.go:171","msg":"trace[686216175] transaction","detail":"{read_only:false; response_revision:1278; number_of_response:1; }","duration":"171.341272ms","start":"2026-04-21T18:18:40.950088Z","end":"2026-04-21T18:18:41.121429Z","steps":["trace[686216175] 'process raft request' (duration: 170.985963ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:18:41.121459Z","caller":"traceutil/trace.go:171","msg":"trace[545325434] transaction","detail":"{read_only:false; response_revision:1277; number_of_response:1; }","duration":"171.423504ms","start":"2026-04-21T18:18:40.949995Z","end":"2026-04-21T18:18:41.121419Z","steps":["trace[545325434] 'process raft request' (duration: 171.049583ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:18:41.293844Z","caller":"traceutil/trace.go:171","msg":"trace[1740814527] transaction","detail":"{read_only:false; response_revision:1281; number_of_response:1; }","duration":"108.059497ms","start":"2026-04-21T18:18:41.185751Z","end":"2026-04-21T18:18:41.29381Z","steps":["trace[1740814527] 'process raft request' (duration: 107.848452ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:18:41.294076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.79021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:472"} {"level":"info","ts":"2026-04-21T18:18:41.294125Z","caller":"traceutil/trace.go:171","msg":"trace[2025798175] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1281; }","duration":"107.899254ms","start":"2026-04-21T18:18:41.186217Z","end":"2026-04-21T18:18:41.294116Z","steps":["trace[2025798175] 'agreement among raft nodes before linearized reading' (duration: 107.683538ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:18:41.293849Z","caller":"traceutil/trace.go:171","msg":"trace[706953087] linearizableReadLoop","detail":"{readStateIndex:1335; appliedIndex:1335; }","duration":"107.555584ms","start":"2026-04-21T18:18:41.186264Z","end":"2026-04-21T18:18:41.29382Z","steps":["trace[706953087] 'read index received' (duration: 107.545163ms)","trace[706953087] 'applied index is now lower than readState.Index' (duration: 8.631µs)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:18:41.31723Z","caller":"traceutil/trace.go:171","msg":"trace[406401941] transaction","detail":"{read_only:false; response_revision:1282; number_of_response:1; }","duration":"129.044676ms","start":"2026-04-21T18:18:41.188132Z","end":"2026-04-21T18:18:41.317176Z","steps":["trace[406401941] 'process raft request' (duration: 128.884442ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:18:42.548145Z","caller":"traceutil/trace.go:171","msg":"trace[929239323] transaction","detail":"{read_only:false; response_revision:1285; number_of_response:1; }","duration":"229.531615ms","start":"2026-04-21T18:18:42.318585Z","end":"2026-04-21T18:18:42.548117Z","steps":["trace[929239323] 'process raft request' (duration: 145.794138ms)","trace[929239323] 'compare' (duration: 83.567693ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:18:43.067548Z","caller":"traceutil/trace.go:171","msg":"trace[936562465] transaction","detail":"{read_only:false; response_revision:1286; number_of_response:1; }","duration":"121.532119ms","start":"2026-04-21T18:18:42.945995Z","end":"2026-04-21T18:18:43.067527Z","steps":["trace[936562465] 'process raft request' (duration: 121.068687ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:18:54.898Z","caller":"traceutil/trace.go:171","msg":"trace[1713412515] transaction","detail":"{read_only:false; response_revision:1363; number_of_response:1; }","duration":"379.351345ms","start":"2026-04-21T18:18:54.518626Z","end":"2026-04-21T18:18:54.897977Z","steps":["trace[1713412515] 'process raft request' (duration: 379.207461ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:18:54.898305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:18:54.518603Z","time spent":"379.53826ms","remote":"127.0.0.1:57868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T18:18:55.624545Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2330223241843312306,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-21T18:18:56.112302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.035587868s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T18:18:56.112368Z","caller":"traceutil/trace.go:171","msg":"trace[2013322096] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1363; }","duration":"1.035704502s","start":"2026-04-21T18:18:55.076651Z","end":"2026-04-21T18:18:56.112355Z","steps":["trace[2013322096] 'count revisions from in-memory index tree' (duration: 1.035483226s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:18:56.112394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:18:55.076633Z","time spent":"1.035755802s","remote":"127.0.0.1:57980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true "} {"level":"warn","ts":"2026-04-21T18:18:56.125704Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2330223241843312306,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-21T18:18:56.273023Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.162432274s","expected-duration":"1s"} {"level":"info","ts":"2026-04-21T18:18:56.273408Z","caller":"traceutil/trace.go:171","msg":"trace[1992996657] transaction","detail":"{read_only:false; response_revision:1364; number_of_response:1; }","duration":"1.163014959s","start":"2026-04-21T18:18:55.110362Z","end":"2026-04-21T18:18:56.273377Z","steps":["trace[1992996657] 'process raft request' (duration: 1.162878136s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:18:56.273541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:18:55.11032Z","time spent":"1.163138863s","remote":"127.0.0.1:57868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T18:18:56.273903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.150078493s","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-04-21T18:18:56.273966Z","caller":"traceutil/trace.go:171","msg":"trace[590608902] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1364; }","duration":"1.150164846s","start":"2026-04-21T18:18:55.123785Z","end":"2026-04-21T18:18:56.27395Z","steps":["trace[590608902] 'agreement among raft nodes before linearized reading' (duration: 1.150019701s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:18:56.274033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:18:55.123766Z","time spent":"1.150252298s","remote":"127.0.0.1:57868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-21T18:18:56.273767Z","caller":"traceutil/trace.go:171","msg":"trace[1414597288] linearizableReadLoop","detail":"{readStateIndex:1421; appliedIndex:1421; }","duration":"1.149881317s","start":"2026-04-21T18:18:55.123819Z","end":"2026-04-21T18:18:56.2737Z","steps":["trace[1414597288] 'read index received' (duration: 1.149872527s)","trace[1414597288] 'applied index is now lower than readState.Index' (duration: 6.86µs)"],"step_count":2} {"level":"warn","ts":"2026-04-21T18:18:56.274508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.141029834s","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-04-21T18:18:56.27454Z","caller":"traceutil/trace.go:171","msg":"trace[1762081685] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1364; }","duration":"1.141064884s","start":"2026-04-21T18:18:55.133465Z","end":"2026-04-21T18:18:56.27453Z","steps":["trace[1762081685] 'agreement among raft nodes before linearized reading' (duration: 1.140967902s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:18:56.27457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:18:55.133443Z","time spent":"1.141120156s","remote":"127.0.0.1:57868","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-04-21T18:18:56.274958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"711.904909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-04-21T18:18:56.275004Z","caller":"traceutil/trace.go:171","msg":"trace[1788246059] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1364; }","duration":"712.009341ms","start":"2026-04-21T18:18:55.562981Z","end":"2026-04-21T18:18:56.274991Z","steps":["trace[1788246059] 'agreement among raft nodes before linearized reading' (duration: 711.887438ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:18:56.275055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:18:55.562957Z","time spent":"712.068372ms","remote":"127.0.0.1:57868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":558,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-21T18:18:56.27526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.079948501s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-21T18:18:56.275271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.04979397s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T18:18:56.275282Z","caller":"traceutil/trace.go:171","msg":"trace[1569315719] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1364; }","duration":"1.079972261s","start":"2026-04-21T18:18:55.195303Z","end":"2026-04-21T18:18:56.275275Z","steps":["trace[1569315719] 'agreement among raft nodes before linearized reading' (duration: 1.079906499s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:18:56.275299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:18:55.195221Z","time spent":"1.080074143s","remote":"127.0.0.1:57868","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-04-21T18:18:56.275302Z","caller":"traceutil/trace.go:171","msg":"trace[141797947] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1364; }","duration":"1.049863072s","start":"2026-04-21T18:18:55.225428Z","end":"2026-04-21T18:18:56.275291Z","steps":["trace[141797947] 'agreement among raft nodes before linearized reading' (duration: 1.04979925s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:18:56.275328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:18:55.225409Z","time spent":"1.049913323s","remote":"127.0.0.1:57638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-21T18:18:56.275592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.08027246s","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-04-21T18:18:56.275616Z","caller":"traceutil/trace.go:171","msg":"trace[172290179] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1364; }","duration":"1.080341282s","start":"2026-04-21T18:18:55.195269Z","end":"2026-04-21T18:18:56.275611Z","steps":["trace[172290179] 'agreement among raft nodes before linearized reading' (duration: 1.0802685s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:18:56.275632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:18:55.195245Z","time spent":"1.080382862s","remote":"127.0.0.1:57868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-21T18:18:56.275683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.088608649s","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-04-21T18:18:56.275714Z","caller":"traceutil/trace.go:171","msg":"trace[937453477] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1364; }","duration":"1.08863841s","start":"2026-04-21T18:18:55.187066Z","end":"2026-04-21T18:18:56.275704Z","steps":["trace[937453477] 'agreement among raft nodes before linearized reading' (duration: 1.088550097s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:18:56.275759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:18:55.186997Z","time spent":"1.088747673s","remote":"127.0.0.1:57868","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-04-21T18:20:51.847154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.506798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-04-21T18:20:51.847278Z","caller":"traceutil/trace.go:171","msg":"trace[1092930699] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1898; }","duration":"111.700913ms","start":"2026-04-21T18:20:51.735549Z","end":"2026-04-21T18:20:51.84725Z","steps":["trace[1092930699] 'range keys from in-memory index tree' (duration: 111.256451ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:20:55.190676Z","caller":"traceutil/trace.go:171","msg":"trace[2113746815] transaction","detail":"{read_only:false; response_revision:1913; number_of_response:1; }","duration":"168.10789ms","start":"2026-04-21T18:20:55.022537Z","end":"2026-04-21T18:20:55.190645Z","steps":["trace[2113746815] 'process raft request' (duration: 167.252916ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:20:55.191506Z","caller":"traceutil/trace.go:171","msg":"trace[1382226409] linearizableReadLoop","detail":"{readStateIndex:1995; appliedIndex:1993; }","duration":"119.801141ms","start":"2026-04-21T18:20:55.071681Z","end":"2026-04-21T18:20:55.191482Z","steps":["trace[1382226409] 'read index received' (duration: 118.271108ms)","trace[1382226409] 'applied index is now lower than readState.Index' (duration: 1.529243ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:20:55.191798Z","caller":"traceutil/trace.go:171","msg":"trace[1016844811] transaction","detail":"{read_only:false; response_revision:1914; number_of_response:1; }","duration":"166.137776ms","start":"2026-04-21T18:20:55.025653Z","end":"2026-04-21T18:20:55.191791Z","steps":["trace[1016844811] 'process raft request' (duration: 165.724424ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:20:55.191979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.286385ms","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-04-21T18:20:55.191996Z","caller":"traceutil/trace.go:171","msg":"trace[1767582421] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1914; }","duration":"120.365858ms","start":"2026-04-21T18:20:55.071625Z","end":"2026-04-21T18:20:55.191991Z","steps":["trace[1767582421] 'agreement among raft nodes before linearized reading' (duration: 120.280765ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:20:55.192116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.467617ms","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-04-21T18:20:55.192128Z","caller":"traceutil/trace.go:171","msg":"trace[1156426345] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1914; }","duration":"100.542509ms","start":"2026-04-21T18:20:55.091582Z","end":"2026-04-21T18:20:55.192124Z","steps":["trace[1156426345] 'agreement among raft nodes before linearized reading' (duration: 100.504028ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:21:05.529984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.407202ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-21T18:21:05.530101Z","caller":"traceutil/trace.go:171","msg":"trace[809719941] linearizableReadLoop","detail":"{readStateIndex:2043; appliedIndex:2041; }","duration":"253.425602ms","start":"2026-04-21T18:21:05.276656Z","end":"2026-04-21T18:21:05.530082Z","steps":["trace[809719941] 'read index received' (duration: 73.017715ms)","trace[809719941] 'applied index is now lower than readState.Index' (duration: 180.406107ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:21:05.530219Z","caller":"traceutil/trace.go:171","msg":"trace[1120983061] transaction","detail":"{read_only:false; response_revision:1960; number_of_response:1; }","duration":"254.215033ms","start":"2026-04-21T18:21:05.275953Z","end":"2026-04-21T18:21:05.530168Z","steps":["trace[1120983061] 'process raft request' (duration: 114.505902ms)","trace[1120983061] 'compare' (duration: 139.263969ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T18:21:05.530287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.627247ms","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-04-21T18:21:05.530319Z","caller":"traceutil/trace.go:171","msg":"trace[1988041804] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1960; }","duration":"253.681378ms","start":"2026-04-21T18:21:05.276627Z","end":"2026-04-21T18:21:05.530309Z","steps":["trace[1988041804] 'agreement among raft nodes before linearized reading' (duration: 253.552664ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:21:05.530492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.291441ms","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-04-21T18:21:05.53052Z","caller":"traceutil/trace.go:171","msg":"trace[2007063737] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1960; }","duration":"165.355693ms","start":"2026-04-21T18:21:05.365155Z","end":"2026-04-21T18:21:05.530511Z","steps":["trace[2007063737] 'agreement among raft nodes before linearized reading' (duration: 165.311612ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:21:05.531032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.343642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-21T18:21:05.531123Z","caller":"traceutil/trace.go:171","msg":"trace[2060840079] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1960; }","duration":"203.553188ms","start":"2026-04-21T18:21:05.327549Z","end":"2026-04-21T18:21:05.531102Z","steps":["trace[2060840079] 'agreement among raft nodes before linearized reading' (duration: 202.90448ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:21:05.693114Z","caller":"traceutil/trace.go:171","msg":"trace[498706949] transaction","detail":"{read_only:false; response_revision:1962; number_of_response:1; }","duration":"157.649407ms","start":"2026-04-21T18:21:05.535417Z","end":"2026-04-21T18:21:05.693066Z","steps":["trace[498706949] 'process raft request' (duration: 102.075893ms)","trace[498706949] 'compare' (duration: 55.404829ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:22:15.33074Z","caller":"traceutil/trace.go:171","msg":"trace[914607014] linearizableReadLoop","detail":"{readStateIndex:2401; appliedIndex:2400; }","duration":"103.747895ms","start":"2026-04-21T18:22:15.226937Z","end":"2026-04-21T18:22:15.330685Z","steps":["trace[914607014] 'read index received' (duration: 103.554489ms)","trace[914607014] 'applied index is now lower than readState.Index' (duration: 192.416µs)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:22:15.330767Z","caller":"traceutil/trace.go:171","msg":"trace[1994146614] transaction","detail":"{read_only:false; response_revision:2303; number_of_response:1; }","duration":"267.43922ms","start":"2026-04-21T18:22:15.063307Z","end":"2026-04-21T18:22:15.330746Z","steps":["trace[1994146614] 'process raft request' (duration: 267.237234ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:22:15.330864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.92236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T18:22:15.330901Z","caller":"traceutil/trace.go:171","msg":"trace[1225916766] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2303; }","duration":"104.009553ms","start":"2026-04-21T18:22:15.226882Z","end":"2026-04-21T18:22:15.330892Z","steps":["trace[1225916766] 'agreement among raft nodes before linearized reading' (duration: 103.93152ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:22:22.140962Z","caller":"traceutil/trace.go:171","msg":"trace[274421007] transaction","detail":"{read_only:false; response_revision:2339; number_of_response:1; }","duration":"149.688983ms","start":"2026-04-21T18:22:21.991243Z","end":"2026-04-21T18:22:22.140932Z","steps":["trace[274421007] 'process raft request' (duration: 149.383344ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:22:22.42998Z","caller":"traceutil/trace.go:171","msg":"trace[453045541] linearizableReadLoop","detail":"{readStateIndex:2441; appliedIndex:2438; }","duration":"242.515942ms","start":"2026-04-21T18:22:22.18744Z","end":"2026-04-21T18:22:22.429956Z","steps":["trace[453045541] 'read index received' (duration: 219.056976ms)","trace[453045541] 'applied index is now lower than readState.Index' (duration: 23.458146ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:22:22.430056Z","caller":"traceutil/trace.go:171","msg":"trace[1727368880] transaction","detail":"{read_only:false; response_revision:2340; number_of_response:1; }","duration":"284.479137ms","start":"2026-04-21T18:22:22.145522Z","end":"2026-04-21T18:22:22.430001Z","steps":["trace[1727368880] 'process raft request' (duration: 284.276121ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:22:22.430113Z","caller":"traceutil/trace.go:171","msg":"trace[1245861685] transaction","detail":"{read_only:false; response_revision:2341; number_of_response:1; }","duration":"284.480428ms","start":"2026-04-21T18:22:22.145622Z","end":"2026-04-21T18:22:22.430102Z","steps":["trace[1245861685] 'process raft request' (duration: 284.296003ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:22:22.430306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.667893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-04-21T18:22:22.430396Z","caller":"traceutil/trace.go:171","msg":"trace[1314447644] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2341; }","duration":"203.791387ms","start":"2026-04-21T18:22:22.226586Z","end":"2026-04-21T18:22:22.430377Z","steps":["trace[1314447644] 'agreement among raft nodes before linearized reading' (duration: 203.608273ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:22:22.430531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.736065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T18:22:22.430598Z","caller":"traceutil/trace.go:171","msg":"trace[1788684875] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2341; }","duration":"203.809857ms","start":"2026-04-21T18:22:22.226761Z","end":"2026-04-21T18:22:22.430571Z","steps":["trace[1788684875] 'agreement among raft nodes before linearized reading' (duration: 203.713764ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:22:22.430789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.340875ms","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-04-21T18:22:22.430833Z","caller":"traceutil/trace.go:171","msg":"trace[1787815036] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2341; }","duration":"243.522361ms","start":"2026-04-21T18:22:22.187301Z","end":"2026-04-21T18:22:22.430823Z","steps":["trace[1787815036] 'agreement among raft nodes before linearized reading' (duration: 242.834592ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:22:39.031356Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2330223241843315174,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-21T18:22:39.361485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"830.606209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-21T18:22:39.361618Z","caller":"traceutil/trace.go:171","msg":"trace[993355158] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"830.815535ms","start":"2026-04-21T18:22:38.530779Z","end":"2026-04-21T18:22:39.361595Z","steps":["trace[993355158] 'agreement among raft nodes before linearized reading' (duration: 830.64806ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:22:39.361679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:22:38.530753Z","time spent":"830.915949ms","remote":"127.0.0.1:57638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/21 18:22:39 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"info","ts":"2026-04-21T18:22:39.472293Z","caller":"traceutil/trace.go:171","msg":"trace[33514998] linearizableReadLoop","detail":"{readStateIndex:2519; appliedIndex:2517; }","duration":"942.021081ms","start":"2026-04-21T18:22:38.530239Z","end":"2026-04-21T18:22:39.47226Z","steps":["trace[33514998] 'read index received' (duration: 938.325637ms)","trace[33514998] 'applied index is now lower than readState.Index' (duration: 3.694124ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:22:39.472407Z","caller":"traceutil/trace.go:171","msg":"trace[1461966750] transaction","detail":"{read_only:false; response_revision:2416; number_of_response:1; }","duration":"931.748591ms","start":"2026-04-21T18:22:38.540602Z","end":"2026-04-21T18:22:39.472351Z","steps":["trace[1461966750] 'process raft request' (duration: 931.381211ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:22:39.472561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"942.287748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-04-21T18:22:39.472616Z","caller":"traceutil/trace.go:171","msg":"trace[409072413] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2416; }","duration":"942.372641ms","start":"2026-04-21T18:22:38.53023Z","end":"2026-04-21T18:22:39.472603Z","steps":["trace[409072413] 'agreement among raft nodes before linearized reading' (duration: 942.144794ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:22:39.472644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:22:38.530149Z","time spent":"942.488992ms","remote":"127.0.0.1:57868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":549,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-21T18:22:39.47275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:22:38.540586Z","time spent":"932.01771ms","remote":"127.0.0.1:57868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T18:22:39.472858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"932.202614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-21T18:22:39.472922Z","caller":"traceutil/trace.go:171","msg":"trace[1768943422] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2416; }","duration":"932.321758ms","start":"2026-04-21T18:22:38.540586Z","end":"2026-04-21T18:22:39.472908Z","steps":["trace[1768943422] 'agreement among raft nodes before linearized reading' (duration: 932.101822ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:22:39.472938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.111252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-21T18:22:39.472995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:22:38.540551Z","time spent":"932.434991ms","remote":"127.0.0.1:57868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-21T18:22:39.473048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"859.111471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:pvc-protection-controller\" ","response":"range_response_count:1 size:775"} {"level":"warn","ts":"2026-04-21T18:22:39.473099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"903.519908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:ttl-controller\" ","response":"range_response_count:1 size:663"} {"level":"info","ts":"2026-04-21T18:22:39.473127Z","caller":"traceutil/trace.go:171","msg":"trace[370967295] range","detail":"{range_begin:/registry/clusterroles/system:controller:ttl-controller; range_end:; response_count:1; response_revision:2416; }","duration":"903.596361ms","start":"2026-04-21T18:22:38.569523Z","end":"2026-04-21T18:22:39.473119Z","steps":["trace[370967295] 'agreement among raft nodes before linearized reading' (duration: 903.540539ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:22:39.473147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:22:38.569497Z","time spent":"903.645442ms","remote":"127.0.0.1:57948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":1,"response size":686,"request content":"key:\"/registry/clusterroles/system:controller:ttl-controller\" "} {"level":"warn","ts":"2026-04-21T18:22:39.472828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.477779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T18:22:39.473249Z","caller":"traceutil/trace.go:171","msg":"trace[616719579] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:2416; }","duration":"270.968404ms","start":"2026-04-21T18:22:39.202244Z","end":"2026-04-21T18:22:39.473213Z","steps":["trace[616719579] 'agreement among raft nodes before linearized reading' (duration: 270.462581ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:22:39.473346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"932.791872ms","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-04-21T18:22:39.473375Z","caller":"traceutil/trace.go:171","msg":"trace[298694289] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2416; }","duration":"932.863863ms","start":"2026-04-21T18:22:38.540504Z","end":"2026-04-21T18:22:39.473368Z","steps":["trace[298694289] 'agreement among raft nodes before linearized reading' (duration: 932.794701ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:22:39.473395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:22:38.540484Z","time spent":"932.906314ms","remote":"127.0.0.1:57868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-21T18:22:39.473397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"932.946305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"warn","ts":"2026-04-21T18:22:39.473372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"932.884184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-04-21T18:22:39.473437Z","caller":"traceutil/trace.go:171","msg":"trace[1899789423] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2416; }","duration":"933.040507ms","start":"2026-04-21T18:22:38.540385Z","end":"2026-04-21T18:22:39.473426Z","steps":["trace[1899789423] 'agreement among raft nodes before linearized reading' (duration: 932.922304ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:22:39.473482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:22:38.540363Z","time spent":"933.11091ms","remote":"127.0.0.1:57868","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-04-21T18:22:39.473484Z","caller":"traceutil/trace.go:171","msg":"trace[489563389] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2416; }","duration":"933.009878ms","start":"2026-04-21T18:22:38.54046Z","end":"2026-04-21T18:22:39.47347Z","steps":["trace[489563389] 'agreement among raft nodes before linearized reading' (duration: 932.75231ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:22:39.473595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"942.982047ms","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-04-21T18:22:39.473621Z","caller":"traceutil/trace.go:171","msg":"trace[938543645] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2416; }","duration":"943.024238ms","start":"2026-04-21T18:22:38.53059Z","end":"2026-04-21T18:22:39.473615Z","steps":["trace[938543645] 'agreement among raft nodes before linearized reading' (duration: 942.956676ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:22:39.47364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:22:38.530583Z","time spent":"943.052338ms","remote":"127.0.0.1:57868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-21T18:22:39.473586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:22:38.540457Z","time spent":"933.116191ms","remote":"127.0.0.1:57868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-04-21T18:22:39.473828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"933.272225ms","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-04-21T18:22:39.473867Z","caller":"traceutil/trace.go:171","msg":"trace[1779362378] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2416; }","duration":"933.322785ms","start":"2026-04-21T18:22:38.540532Z","end":"2026-04-21T18:22:39.473855Z","steps":["trace[1779362378] 'agreement among raft nodes before linearized reading' (duration: 933.165271ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:22:39.473911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:22:38.540528Z","time spent":"933.373767ms","remote":"127.0.0.1:57868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-21T18:22:39.473133Z","caller":"traceutil/trace.go:171","msg":"trace[544070204] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:pvc-protection-controller; range_end:; response_count:1; response_revision:2416; }","duration":"859.250213ms","start":"2026-04-21T18:22:38.613867Z","end":"2026-04-21T18:22:39.473117Z","steps":["trace[544070204] 'agreement among raft nodes before linearized reading' (duration: 859.014627ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:22:39.474193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"943.584463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-21T18:22:39.474248Z","caller":"traceutil/trace.go:171","msg":"trace[414666780] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2416; }","duration":"943.681777ms","start":"2026-04-21T18:22:38.530551Z","end":"2026-04-21T18:22:39.474232Z","steps":["trace[414666780] 'agreement among raft nodes before linearized reading' (duration: 942.066932ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:22:39.474298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:22:38.530543Z","time spent":"943.742798ms","remote":"127.0.0.1:57868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-21T18:22:39.474451Z","caller":"traceutil/trace.go:171","msg":"trace[1932088047] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2416; }","duration":"112.653665ms","start":"2026-04-21T18:22:39.361772Z","end":"2026-04-21T18:22:39.474425Z","steps":["trace[1932088047] 'agreement among raft nodes before linearized reading' (duration: 111.142043ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:22:39.474647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:22:38.613835Z","time spent":"860.587312ms","remote":"127.0.0.1:57964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":798,"request content":"key:\"/registry/clusterrolebindings/system:controller:pvc-protection-controller\" "}