{"level":"warn","ts":"2026-02-25T10:07:03.52278Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-25T10:07:03.5229Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.201: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.201:2380","--initial-cluster=instance=https://199.19.213.201:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.201:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.201:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-02-25T10:07:03.522977Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-25T10:07:03.522985Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.201:2380"]} {"level":"info","ts":"2026-02-25T10:07:03.523005Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-25T10:07:03.523683Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.201:2379"]} {"level":"info","ts":"2026-02-25T10:07:03.523771Z","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":16,"max-cpu-available":16,"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.201:2380"],"listen-peer-urls":["https://199.19.213.201:2380"],"advertise-client-urls":["https://199.19.213.201:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.201:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.201:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-02-25T10:07:03.528139Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.145066ms"} {"level":"info","ts":"2026-02-25T10:07:03.533717Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"61bc451777564e43","cluster-id":"b1814cb801e33ddc"} {"level":"info","ts":"2026-02-25T10:07:03.533805Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"61bc451777564e43 switched to configuration voters=()"} {"level":"info","ts":"2026-02-25T10:07:03.533834Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"61bc451777564e43 became follower at term 0"} {"level":"info","ts":"2026-02-25T10:07:03.533851Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 61bc451777564e43 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-25T10:07:03.533865Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"61bc451777564e43 became follower at term 1"} {"level":"info","ts":"2026-02-25T10:07:03.533912Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"61bc451777564e43 switched to configuration voters=(7042579884389322307)"} {"level":"warn","ts":"2026-02-25T10:07:03.54609Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-25T10:07:03.547594Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-25T10:07:03.548887Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-02-25T10:07:03.55135Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"61bc451777564e43","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-25T10:07:03.552101Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"61bc451777564e43","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-25T10:07:03.552284Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-25T10:07:03.552401Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-25T10:07:03.552478Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-25T10:07:03.55425Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"61bc451777564e43 switched to configuration voters=(7042579884389322307)"} {"level":"info","ts":"2026-02-25T10:07:03.554455Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"b1814cb801e33ddc","local-member-id":"61bc451777564e43","added-peer-id":"61bc451777564e43","added-peer-peer-urls":["https://199.19.213.201:2380"]} {"level":"info","ts":"2026-02-25T10:07:03.557041Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-25T10:07:03.557167Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.201:2380"} {"level":"info","ts":"2026-02-25T10:07:03.557248Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.201:2380"} {"level":"info","ts":"2026-02-25T10:07:03.557368Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"61bc451777564e43","initial-advertise-peer-urls":["https://199.19.213.201:2380"],"listen-peer-urls":["https://199.19.213.201:2380"],"advertise-client-urls":["https://199.19.213.201:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.201:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-25T10:07:03.557424Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-25T10:07:03.634959Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"61bc451777564e43 is starting a new election at term 1"} {"level":"info","ts":"2026-02-25T10:07:03.635039Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"61bc451777564e43 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-25T10:07:03.635082Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"61bc451777564e43 received MsgPreVoteResp from 61bc451777564e43 at term 1"} {"level":"info","ts":"2026-02-25T10:07:03.635102Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"61bc451777564e43 became candidate at term 2"} {"level":"info","ts":"2026-02-25T10:07:03.635113Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"61bc451777564e43 received MsgVoteResp from 61bc451777564e43 at term 2"} {"level":"info","ts":"2026-02-25T10:07:03.635128Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"61bc451777564e43 became leader at term 2"} {"level":"info","ts":"2026-02-25T10:07:03.635143Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 61bc451777564e43 elected leader 61bc451777564e43 at term 2"} {"level":"info","ts":"2026-02-25T10:07:03.644516Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"61bc451777564e43","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.201:2379]}","request-path":"/0/members/61bc451777564e43/attributes","cluster-id":"b1814cb801e33ddc","publish-timeout":"7s"} {"level":"info","ts":"2026-02-25T10:07:03.644533Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T10:07:03.644596Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T10:07:03.644686Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T10:07:03.64541Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"b1814cb801e33ddc","local-member-id":"61bc451777564e43","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T10:07:03.64551Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T10:07:03.645517Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-25T10:07:03.645575Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-25T10:07:03.645554Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T10:07:03.648049Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.201:2379"} {"level":"info","ts":"2026-02-25T10:07:03.648402Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-02-25T10:07:12.057656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.833705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-25T10:07:12.057752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.014316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-25T10:07:12.057794Z","caller":"traceutil/trace.go:171","msg":"trace[1028455342] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:330; }","duration":"116.013308ms","start":"2026-02-25T10:07:11.941755Z","end":"2026-02-25T10:07:12.057768Z","steps":["trace[1028455342] 'range keys from in-memory index tree' (duration: 115.749973ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:12.05781Z","caller":"traceutil/trace.go:171","msg":"trace[2049593126] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:330; }","duration":"466.093117ms","start":"2026-02-25T10:07:11.591699Z","end":"2026-02-25T10:07:12.057792Z","steps":["trace[2049593126] 'range keys from in-memory index tree' (duration: 465.923393ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:12.057712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"590.96501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/namespace-controller\" ","response":"range_response_count:1 size:205"} {"level":"warn","ts":"2026-02-25T10:07:12.05788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:11.591678Z","time spent":"466.189009ms","remote":"127.0.0.1:54734","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":"info","ts":"2026-02-25T10:07:12.057954Z","caller":"traceutil/trace.go:171","msg":"trace[937420614] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/namespace-controller; range_end:; response_count:1; response_revision:330; }","duration":"591.263335ms","start":"2026-02-25T10:07:11.466669Z","end":"2026-02-25T10:07:12.057932Z","steps":["trace[937420614] 'range keys from in-memory index tree' (duration: 590.827267ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:12.058094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:11.466637Z","time spent":"591.388128ms","remote":"127.0.0.1:54684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":228,"request content":"key:\"/registry/serviceaccounts/kube-system/namespace-controller\" "} {"level":"warn","ts":"2026-02-25T10:07:12.570112Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5639523319011307762,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T10:07:12.635678Z","caller":"traceutil/trace.go:171","msg":"trace[1995095736] transaction","detail":"{read_only:false; response_revision:331; number_of_response:1; }","duration":"573.667001ms","start":"2026-02-25T10:07:12.061986Z","end":"2026-02-25T10:07:12.635653Z","steps":["trace[1995095736] 'process raft request' (duration: 573.478837ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:12.636518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:12.061963Z","time spent":"573.795404ms","remote":"127.0.0.1:54734","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-02-25T10:07:13.927885Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.216177116s","expected-duration":"1s"} {"level":"info","ts":"2026-02-25T10:07:13.98824Z","caller":"traceutil/trace.go:171","msg":"trace[677830592] linearizableReadLoop","detail":"{readStateIndex:342; appliedIndex:340; }","duration":"1.918984218s","start":"2026-02-25T10:07:12.069218Z","end":"2026-02-25T10:07:13.988202Z","steps":["trace[677830592] 'read index received' (duration: 566.197057ms)","trace[677830592] 'applied index is now lower than readState.Index' (duration: 1.352785511s)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:07:13.98842Z","caller":"traceutil/trace.go:171","msg":"trace[342866862] transaction","detail":"{read_only:false; response_revision:332; number_of_response:1; }","duration":"1.926193497s","start":"2026-02-25T10:07:12.062157Z","end":"2026-02-25T10:07:13.98835Z","steps":["trace[342866862] 'process raft request' (duration: 1.865898161s)","trace[342866862] 'compare' (duration: 59.96934ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:07:13.988559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"872.295876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-02-25T10:07:13.988555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.919326776s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-02-25T10:07:13.98864Z","caller":"traceutil/trace.go:171","msg":"trace[235110428] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:332; }","duration":"872.424869ms","start":"2026-02-25T10:07:13.116196Z","end":"2026-02-25T10:07:13.988621Z","steps":["trace[235110428] 'agreement among raft nodes before linearized reading' (duration: 872.293896ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:13.98866Z","caller":"traceutil/trace.go:171","msg":"trace[1530818370] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:332; }","duration":"1.919497719s","start":"2026-02-25T10:07:12.069136Z","end":"2026-02-25T10:07:13.988634Z","steps":["trace[1530818370] 'agreement among raft nodes before linearized reading' (duration: 1.919237504s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:13.988521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.758789936s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-02-25T10:07:13.988711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:12.069116Z","time spent":"1.91958481s","remote":"127.0.0.1:54684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":232,"request content":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" "} {"level":"warn","ts":"2026-02-25T10:07:13.988732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:12.062097Z","time spent":"1.926509484s","remote":"127.0.0.1:54734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T10:07:13.988752Z","caller":"traceutil/trace.go:171","msg":"trace[447368763] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:332; }","duration":"1.759091982s","start":"2026-02-25T10:07:12.229645Z","end":"2026-02-25T10:07:13.988737Z","steps":["trace[447368763] 'agreement among raft nodes before linearized reading' (duration: 1.758750795s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:13.9887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:13.116167Z","time spent":"872.5216ms","remote":"127.0.0.1:54734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T10:07:13.988802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:12.229625Z","time spent":"1.759162453s","remote":"127.0.0.1:54734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T10:07:14.26579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.238683ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T10:07:14.265945Z","caller":"traceutil/trace.go:171","msg":"trace[159334904] transaction","detail":"{read_only:false; response_revision:333; number_of_response:1; }","duration":"273.373612ms","start":"2026-02-25T10:07:13.992551Z","end":"2026-02-25T10:07:14.265925Z","steps":["trace[159334904] 'process raft request' (duration: 171.767681ms)","trace[159334904] 'compare' (duration: 101.108961ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:07:14.530341Z","caller":"traceutil/trace.go:171","msg":"trace[1149728452] transaction","detail":"{read_only:false; response_revision:334; number_of_response:1; }","duration":"536.910142ms","start":"2026-02-25T10:07:13.993395Z","end":"2026-02-25T10:07:14.530305Z","steps":["trace[1149728452] 'process raft request' (duration: 531.845017ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:14.530454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.915601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/pod-garbage-collector\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T10:07:14.530497Z","caller":"traceutil/trace.go:171","msg":"trace[1364181883] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/pod-garbage-collector; range_end:; response_count:0; response_revision:334; }","duration":"534.993912ms","start":"2026-02-25T10:07:13.995495Z","end":"2026-02-25T10:07:14.530489Z","steps":["trace[1364181883] 'agreement among raft nodes before linearized reading' (duration: 534.89975ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:14.530522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:13.995488Z","time spent":"535.026974ms","remote":"127.0.0.1:54684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/kube-system/pod-garbage-collector\" "} {"level":"warn","ts":"2026-02-25T10:07:14.530527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:13.993364Z","time spent":"537.058016ms","remote":"127.0.0.1:54734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T10:07:14.530322Z","caller":"traceutil/trace.go:171","msg":"trace[1018687843] linearizableReadLoop","detail":"{readStateIndex:344; appliedIndex:342; }","duration":"534.779538ms","start":"2026-02-25T10:07:13.995512Z","end":"2026-02-25T10:07:14.530291Z","steps":["trace[1018687843] 'read index received' (duration: 168.80257ms)","trace[1018687843] 'applied index is now lower than readState.Index' (duration: 365.976058ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:07:19.373043Z","caller":"traceutil/trace.go:171","msg":"trace[383589546] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"334.23166ms","start":"2026-02-25T10:07:19.038789Z","end":"2026-02-25T10:07:19.373021Z","steps":["trace[383589546] 'process raft request' (duration: 334.071837ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:19.373222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:19.038765Z","time spent":"334.361434ms","remote":"127.0.0.1:54672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4583,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T10:07:19.666399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.441949ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T10:07:19.666475Z","caller":"traceutil/trace.go:171","msg":"trace[2070497948] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"537.334181ms","start":"2026-02-25T10:07:19.129127Z","end":"2026-02-25T10:07:19.666461Z","steps":["trace[2070497948] 'process raft request' (duration: 285.754509ms)","trace[2070497948] 'compare' (duration: 251.293776ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:07:19.666507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:19.129096Z","time spent":"537.395941ms","remote":"127.0.0.1:54734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T10:07:20.496535Z","caller":"traceutil/trace.go:171","msg":"trace[273788496] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"132.683224ms","start":"2026-02-25T10:07:20.363829Z","end":"2026-02-25T10:07:20.496512Z","steps":["trace[273788496] 'process raft request' (duration: 132.530439ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:20.668868Z","caller":"traceutil/trace.go:171","msg":"trace[1948874433] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"103.708234ms","start":"2026-02-25T10:07:20.565128Z","end":"2026-02-25T10:07:20.668836Z","steps":["trace[1948874433] 'process raft request' (duration: 12.067269ms)","trace[1948874433] 'compare' (duration: 91.449721ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:07:26.794727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.845647ms","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-02-25T10:07:26.794856Z","caller":"traceutil/trace.go:171","msg":"trace[1877884516] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:455; }","duration":"104.041741ms","start":"2026-02-25T10:07:26.690793Z","end":"2026-02-25T10:07:26.794834Z","steps":["trace[1877884516] 'range keys from in-memory index tree' (duration: 103.666094ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:31.263239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.044868074s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-25T10:07:31.263318Z","caller":"traceutil/trace.go:171","msg":"trace[1354868539] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:463; }","duration":"1.044997227s","start":"2026-02-25T10:07:30.218303Z","end":"2026-02-25T10:07:31.263301Z","steps":["trace[1354868539] 'range keys from in-memory index tree' (duration: 1.044675671s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:31.263352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:30.218283Z","time spent":"1.045062088s","remote":"127.0.0.1:54734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T10:07:31.263234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.423733ms","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-02-25T10:07:31.263529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"649.866217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-25T10:07:31.263546Z","caller":"traceutil/trace.go:171","msg":"trace[1981346625] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:463; }","duration":"376.79563ms","start":"2026-02-25T10:07:30.886732Z","end":"2026-02-25T10:07:31.263528Z","steps":["trace[1981346625] 'range keys from in-memory index tree' (duration: 376.28523ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:31.26359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:30.886703Z","time spent":"376.876712ms","remote":"127.0.0.1:54734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-25T10:07:31.26359Z","caller":"traceutil/trace.go:171","msg":"trace[1449973053] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:463; }","duration":"649.9708ms","start":"2026-02-25T10:07:30.613603Z","end":"2026-02-25T10:07:31.263574Z","steps":["trace[1449973053] 'range keys from in-memory index tree' (duration: 649.772886ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:31.263574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.284899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-02-25T10:07:31.263623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:30.613583Z","time spent":"650.03177ms","remote":"127.0.0.1:54734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-25T10:07:31.26365Z","caller":"traceutil/trace.go:171","msg":"trace[631549872] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:463; }","duration":"419.359041ms","start":"2026-02-25T10:07:30.844268Z","end":"2026-02-25T10:07:31.263627Z","steps":["trace[631549872] 'range keys from in-memory index tree' (duration: 419.173477ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:31.2637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:30.844216Z","time spent":"419.471783ms","remote":"127.0.0.1:54734","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-02-25T10:07:31.263768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.278126097s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T10:07:31.26383Z","caller":"traceutil/trace.go:171","msg":"trace[1509757170] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:463; }","duration":"1.278243059s","start":"2026-02-25T10:07:29.985569Z","end":"2026-02-25T10:07:31.263812Z","steps":["trace[1509757170] 'range keys from in-memory index tree' (duration: 1.277990474s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:31.263901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:29.985538Z","time spent":"1.278351072s","remote":"127.0.0.1:54466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T10:07:36.042647Z","caller":"traceutil/trace.go:171","msg":"trace[1895672600] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"200.573055ms","start":"2026-02-25T10:07:35.842004Z","end":"2026-02-25T10:07:36.042577Z","steps":["trace[1895672600] 'process raft request' (duration: 200.119926ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:39.037556Z","caller":"traceutil/trace.go:171","msg":"trace[1444361338] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"809.405337ms","start":"2026-02-25T10:07:38.228113Z","end":"2026-02-25T10:07:39.037519Z","steps":["trace[1444361338] 'process raft request' (duration: 809.099071ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:39.03774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:38.228101Z","time spent":"809.550841ms","remote":"127.0.0.1:54668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4217,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T10:07:42.251954Z","caller":"traceutil/trace.go:171","msg":"trace[437331027] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"825.381311ms","start":"2026-02-25T10:07:41.426541Z","end":"2026-02-25T10:07:42.251923Z","steps":["trace[437331027] 'process raft request' (duration: 824.940071ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:42.252179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:41.426514Z","time spent":"825.563465ms","remote":"127.0.0.1:54734","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-02-25T10:07:42.266927Z","caller":"traceutil/trace.go:171","msg":"trace[1862279750] linearizableReadLoop","detail":"{readStateIndex:543; appliedIndex:540; }","duration":"332.519088ms","start":"2026-02-25T10:07:41.934381Z","end":"2026-02-25T10:07:42.266901Z","steps":["trace[1862279750] 'read index received' (duration: 317.120456ms)","trace[1862279750] 'applied index is now lower than readState.Index' (duration: 15.397282ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:07:42.267116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.705652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T10:07:42.267229Z","caller":"traceutil/trace.go:171","msg":"trace[1902332564] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"840.620058ms","start":"2026-02-25T10:07:41.426589Z","end":"2026-02-25T10:07:42.267209Z","steps":["trace[1902332564] 'process raft request' (duration: 840.134309ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:42.267213Z","caller":"traceutil/trace.go:171","msg":"trace[678051861] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:0; response_revision:525; }","duration":"332.843755ms","start":"2026-02-25T10:07:41.934355Z","end":"2026-02-25T10:07:42.267199Z","steps":["trace[678051861] 'agreement among raft nodes before linearized reading' (duration: 332.661502ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:42.267297Z","caller":"traceutil/trace.go:171","msg":"trace[1721526224] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"840.425434ms","start":"2026-02-25T10:07:41.426831Z","end":"2026-02-25T10:07:42.267256Z","steps":["trace[1721526224] 'process raft request' (duration: 840.018236ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:42.267304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:41.934342Z","time spent":"332.949667ms","remote":"127.0.0.1:54576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" "} {"level":"warn","ts":"2026-02-25T10:07:42.267357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:41.426567Z","time spent":"840.716831ms","remote":"127.0.0.1:54734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T10:07:42.267522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:41.426802Z","time spent":"840.575808ms","remote":"127.0.0.1:54734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T10:07:42.976553Z","caller":"traceutil/trace.go:171","msg":"trace[1093491988] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"257.630705ms","start":"2026-02-25T10:07:42.718899Z","end":"2026-02-25T10:07:42.97653Z","steps":["trace[1093491988] 'process raft request' (duration: 158.792883ms)","trace[1093491988] 'compare' (duration: 98.321522ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:07:43.178003Z","caller":"traceutil/trace.go:171","msg":"trace[2081698214] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"131.590786ms","start":"2026-02-25T10:07:43.046393Z","end":"2026-02-25T10:07:43.177983Z","steps":["trace[2081698214] 'process raft request' (duration: 130.538774ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:43.348233Z","caller":"traceutil/trace.go:171","msg":"trace[58009540] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"118.795799ms","start":"2026-02-25T10:07:43.229383Z","end":"2026-02-25T10:07:43.348179Z","steps":["trace[58009540] 'process raft request' (duration: 89.928107ms)","trace[58009540] 'compare' (duration: 28.682208ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:07:47.592693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:47.263824Z","time spent":"328.86192ms","remote":"127.0.0.1:37794","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-25T10:07:47.980046Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5639523319011308230,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T10:07:48.091254Z","caller":"traceutil/trace.go:171","msg":"trace[125209260] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"911.041838ms","start":"2026-02-25T10:07:47.180184Z","end":"2026-02-25T10:07:48.091226Z","steps":["trace[125209260] 'process raft request' (duration: 910.866925ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:48.091408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:47.180155Z","time spent":"911.17972ms","remote":"127.0.0.1:54672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T10:07:48.289962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.866168ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T10:07:48.290079Z","caller":"traceutil/trace.go:171","msg":"trace[2000743987] linearizableReadLoop","detail":"{readStateIndex:581; appliedIndex:580; }","duration":"811.021192ms","start":"2026-02-25T10:07:47.47904Z","end":"2026-02-25T10:07:48.290061Z","steps":["trace[2000743987] 'read index received' (duration: 612.260015ms)","trace[2000743987] 'applied index is now lower than readState.Index' (duration: 198.759997ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:07:48.290186Z","caller":"traceutil/trace.go:171","msg":"trace[530450062] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"1.039871466s","start":"2026-02-25T10:07:47.250301Z","end":"2026-02-25T10:07:48.290173Z","steps":["trace[530450062] 'process raft request' (duration: 934.714472ms)","trace[530450062] 'compare' (duration: 104.743376ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:07:48.290249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:47.250267Z","time spent":"1.039947787s","remote":"127.0.0.1:54494","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T10:07:48.290413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"540.513868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T10:07:48.290485Z","caller":"traceutil/trace.go:171","msg":"trace[125819497] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:561; }","duration":"540.613779ms","start":"2026-02-25T10:07:47.749853Z","end":"2026-02-25T10:07:48.290467Z","steps":["trace[125819497] 'agreement among raft nodes before linearized reading' (duration: 540.467526ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:48.290523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:47.74984Z","time spent":"540.67586ms","remote":"127.0.0.1:54466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T10:07:48.290639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.005463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T10:07:48.290685Z","caller":"traceutil/trace.go:171","msg":"trace[1098718053] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:561; }","duration":"304.096384ms","start":"2026-02-25T10:07:47.986571Z","end":"2026-02-25T10:07:48.290668Z","steps":["trace[1098718053] 'agreement among raft nodes before linearized reading' (duration: 304.036123ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:48.290723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:47.98654Z","time spent":"304.174026ms","remote":"127.0.0.1:54482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T10:07:48.290769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"811.707286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-25T10:07:48.290839Z","caller":"traceutil/trace.go:171","msg":"trace[2133900406] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:561; }","duration":"811.815648ms","start":"2026-02-25T10:07:47.478997Z","end":"2026-02-25T10:07:48.290813Z","steps":["trace[2133900406] 'agreement among raft nodes before linearized reading' (duration: 811.622284ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:48.290874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:47.478977Z","time spent":"811.890691ms","remote":"127.0.0.1:54734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T10:07:49.745564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.314362454s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T10:07:49.745658Z","caller":"traceutil/trace.go:171","msg":"trace[508361674] linearizableReadLoop","detail":"{readStateIndex:582; appliedIndex:581; }","duration":"1.45173372s","start":"2026-02-25T10:07:48.293909Z","end":"2026-02-25T10:07:49.745643Z","steps":["trace[508361674] 'read index received' (duration: 137.262404ms)","trace[508361674] 'applied index is now lower than readState.Index' (duration: 1.314470236s)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:07:49.74576Z","caller":"traceutil/trace.go:171","msg":"trace[1835686089] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"1.452124328s","start":"2026-02-25T10:07:48.293599Z","end":"2026-02-25T10:07:49.745723Z","steps":["trace[1835686089] 'process raft request' (duration: 137.533269ms)","trace[1835686089] 'compare' (duration: 1.31420735s)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:07:49.7458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.451883413s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-25T10:07:49.745833Z","caller":"traceutil/trace.go:171","msg":"trace[538220143] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:562; }","duration":"1.451931795s","start":"2026-02-25T10:07:48.29389Z","end":"2026-02-25T10:07:49.745822Z","steps":["trace[538220143] 'agreement among raft nodes before linearized reading' (duration: 1.451813672s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:49.745865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:48.29358Z","time spent":"1.45222882s","remote":"127.0.0.1:54734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T10:07:49.745871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:48.293882Z","time spent":"1.451980955s","remote":"127.0.0.1:54494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" "} {"level":"warn","ts":"2026-02-25T10:07:50.246332Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5639523319011308243,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T10:07:50.614846Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.183639901s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-25T10:07:50.894292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.967741ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T10:07:50.915498Z","caller":"traceutil/trace.go:171","msg":"trace[1801163417] linearizableReadLoop","detail":"{readStateIndex:585; appliedIndex:582; }","duration":"1.169739185s","start":"2026-02-25T10:07:49.74568Z","end":"2026-02-25T10:07:50.91542Z","steps":["trace[1801163417] 'read index received' (duration: 869.496401ms)","trace[1801163417] 'applied index is now lower than readState.Index' (duration: 300.05556ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:07:50.894608Z","caller":"traceutil/trace.go:171","msg":"trace[1761883183] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"2.600008887s","start":"2026-02-25T10:07:48.294545Z","end":"2026-02-25T10:07:50.894554Z","steps":["trace[1761883183] 'process raft request' (duration: 2.320646618s)","trace[1761883183] 'compare' (duration: 278.803647ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:07:50.916762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:48.29453Z","time spent":"2.622094098s","remote":"127.0.0.1:54734","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-02-25T10:07:50.915197Z","caller":"traceutil/trace.go:171","msg":"trace[892211299] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"2.618890701s","start":"2026-02-25T10:07:48.296246Z","end":"2026-02-25T10:07:50.915137Z","steps":["trace[892211299] 'process raft request' (duration: 2.598255481s)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:50.915565Z","caller":"traceutil/trace.go:171","msg":"trace[936773519] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"2.620647067s","start":"2026-02-25T10:07:48.294892Z","end":"2026-02-25T10:07:50.915539Z","steps":["trace[936773519] 'process raft request' (duration: 2.599546438s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:50.91735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:48.294881Z","time spent":"2.622369583s","remote":"127.0.0.1:54734","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-02-25T10:07:50.91747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:48.296224Z","time spent":"2.621110958s","remote":"127.0.0.1:54734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T10:07:50.918798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.931973579s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T10:07:50.918896Z","caller":"traceutil/trace.go:171","msg":"trace[935528482] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:565; }","duration":"1.932067481s","start":"2026-02-25T10:07:48.986799Z","end":"2026-02-25T10:07:50.918867Z","steps":["trace[935528482] 'agreement among raft nodes before linearized reading' (duration: 1.928557928s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:50.918951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:48.986743Z","time spent":"1.932197293s","remote":"127.0.0.1:54466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T10:07:50.918981Z","caller":"traceutil/trace.go:171","msg":"trace[1300354674] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"455.618965ms","start":"2026-02-25T10:07:50.463319Z","end":"2026-02-25T10:07:50.918938Z","steps":["trace[1300354674] 'process raft request' (duration: 451.453109ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:50.919047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.17184989s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-02-25T10:07:50.919135Z","caller":"traceutil/trace.go:171","msg":"trace[26316021] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:566; }","duration":"1.171971032s","start":"2026-02-25T10:07:49.747139Z","end":"2026-02-25T10:07:50.91911Z","steps":["trace[26316021] 'agreement among raft nodes before linearized reading' (duration: 1.171798208s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:50.919155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:50.46329Z","time spent":"455.775209ms","remote":"127.0.0.1:54734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T10:07:50.919203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:49.747125Z","time spent":"1.172062554s","remote":"127.0.0.1:54740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"info","ts":"2026-02-25T10:07:51.670426Z","caller":"traceutil/trace.go:171","msg":"trace[765006166] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"154.667607ms","start":"2026-02-25T10:07:51.515729Z","end":"2026-02-25T10:07:51.670397Z","steps":["trace[765006166] 'process raft request' (duration: 96.752479ms)","trace[765006166] 'compare' (duration: 57.689564ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:07:51.68953Z","caller":"traceutil/trace.go:171","msg":"trace[1727726014] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"173.304265ms","start":"2026-02-25T10:07:51.516193Z","end":"2026-02-25T10:07:51.689497Z","steps":["trace[1727726014] 'process raft request' (duration: 173.136992ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:51.68961Z","caller":"traceutil/trace.go:171","msg":"trace[543535195] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"172.842396ms","start":"2026-02-25T10:07:51.516733Z","end":"2026-02-25T10:07:51.689575Z","steps":["trace[543535195] 'process raft request' (duration: 172.683382ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:51.689815Z","caller":"traceutil/trace.go:171","msg":"trace[794483911] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"166.003924ms","start":"2026-02-25T10:07:51.523792Z","end":"2026-02-25T10:07:51.689796Z","steps":["trace[794483911] 'process raft request' (duration: 165.837701ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:51.689806Z","caller":"traceutil/trace.go:171","msg":"trace[1487286838] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"168.474866ms","start":"2026-02-25T10:07:51.52127Z","end":"2026-02-25T10:07:51.689745Z","steps":["trace[1487286838] 'process raft request' (duration: 168.194249ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:51.689886Z","caller":"traceutil/trace.go:171","msg":"trace[1705446001] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"164.173176ms","start":"2026-02-25T10:07:51.525695Z","end":"2026-02-25T10:07:51.689869Z","steps":["trace[1705446001] 'process raft request' (duration: 164.081644ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:51.929788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.568201ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-25T10:07:51.930058Z","caller":"traceutil/trace.go:171","msg":"trace[1126172707] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"198.883949ms","start":"2026-02-25T10:07:51.73115Z","end":"2026-02-25T10:07:51.930034Z","steps":["trace[1126172707] 'process raft request' (duration: 94.000681ms)","trace[1126172707] 'compare' (duration: 104.436299ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:07:51.93017Z","caller":"traceutil/trace.go:171","msg":"trace[321867384] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"198.596963ms","start":"2026-02-25T10:07:51.731546Z","end":"2026-02-25T10:07:51.930143Z","steps":["trace[321867384] 'process raft request' (duration: 198.44914ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:51.930195Z","caller":"traceutil/trace.go:171","msg":"trace[962780047] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"198.295896ms","start":"2026-02-25T10:07:51.731879Z","end":"2026-02-25T10:07:51.930175Z","steps":["trace[962780047] 'process raft request' (duration: 198.190044ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:51.93024Z","caller":"traceutil/trace.go:171","msg":"trace[1111991969] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"196.671783ms","start":"2026-02-25T10:07:51.733559Z","end":"2026-02-25T10:07:51.93023Z","steps":["trace[1111991969] 'process raft request' (duration: 196.53346ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:51.930291Z","caller":"traceutil/trace.go:171","msg":"trace[156086028] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"198.694135ms","start":"2026-02-25T10:07:51.731592Z","end":"2026-02-25T10:07:51.930286Z","steps":["trace[156086028] 'process raft request' (duration: 198.44824ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:51.930347Z","caller":"traceutil/trace.go:171","msg":"trace[1406136526] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"196.625342ms","start":"2026-02-25T10:07:51.733716Z","end":"2026-02-25T10:07:51.930341Z","steps":["trace[1406136526] 'process raft request' (duration: 196.454709ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:51.930337Z","caller":"traceutil/trace.go:171","msg":"trace[106069461] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"196.652623ms","start":"2026-02-25T10:07:51.733671Z","end":"2026-02-25T10:07:51.930324Z","steps":["trace[106069461] 'process raft request' (duration: 196.475359ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:51.930379Z","caller":"traceutil/trace.go:171","msg":"trace[1819318202] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"199.011642ms","start":"2026-02-25T10:07:51.731312Z","end":"2026-02-25T10:07:51.930324Z","steps":["trace[1819318202] 'process raft request' (duration: 198.652494ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:51.930119Z","caller":"traceutil/trace.go:171","msg":"trace[1982577139] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"198.748586ms","start":"2026-02-25T10:07:51.731309Z","end":"2026-02-25T10:07:51.930057Z","steps":["trace[1982577139] 'process raft request' (duration: 198.595343ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:51.931509Z","caller":"traceutil/trace.go:171","msg":"trace[1544860317] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"196.885968ms","start":"2026-02-25T10:07:51.734604Z","end":"2026-02-25T10:07:51.93149Z","steps":["trace[1544860317] 'process raft request' (duration: 195.686142ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:51.934164Z","caller":"traceutil/trace.go:171","msg":"trace[445762013] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"199.273507ms","start":"2026-02-25T10:07:51.734862Z","end":"2026-02-25T10:07:51.934136Z","steps":["trace[445762013] 'process raft request' (duration: 196.183933ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:51.934473Z","caller":"traceutil/trace.go:171","msg":"trace[1920276912] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"198.905689ms","start":"2026-02-25T10:07:51.73554Z","end":"2026-02-25T10:07:51.934445Z","steps":["trace[1920276912] 'process raft request' (duration: 198.526312ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:51.93515Z","caller":"traceutil/trace.go:171","msg":"trace[275948787] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"199.565723ms","start":"2026-02-25T10:07:51.735548Z","end":"2026-02-25T10:07:51.935114Z","steps":["trace[275948787] 'process raft request' (duration: 198.819358ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:51.93753Z","caller":"traceutil/trace.go:171","msg":"trace[835985318] linearizableReadLoop","detail":"{readStateIndex:630; appliedIndex:615; }","duration":"188.229907ms","start":"2026-02-25T10:07:51.749215Z","end":"2026-02-25T10:07:51.937445Z","steps":["trace[835985318] 'read index received' (duration: 8.13991ms)","trace[835985318] 'applied index is now lower than readState.Index' (duration: 180.088607ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:07:51.937689Z","caller":"traceutil/trace.go:171","msg":"trace[980759265] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"200.350979ms","start":"2026-02-25T10:07:51.737317Z","end":"2026-02-25T10:07:51.937668Z","steps":["trace[980759265] 'process raft request' (duration: 198.296426ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:51.937809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.561914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-25T10:07:51.937864Z","caller":"traceutil/trace.go:171","msg":"trace[2088967047] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:610; }","duration":"188.662786ms","start":"2026-02-25T10:07:51.749182Z","end":"2026-02-25T10:07:51.937845Z","steps":["trace[2088967047] 'agreement among raft nodes before linearized reading' (duration: 188.450902ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:52.06723Z","caller":"traceutil/trace.go:171","msg":"trace[172716206] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"128.126333ms","start":"2026-02-25T10:07:51.939069Z","end":"2026-02-25T10:07:52.067196Z","steps":["trace[172716206] 'process raft request' (duration: 113.503478ms)","trace[172716206] 'compare' (duration: 14.156225ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:07:52.067443Z","caller":"traceutil/trace.go:171","msg":"trace[351758689] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"127.247894ms","start":"2026-02-25T10:07:51.94017Z","end":"2026-02-25T10:07:52.067418Z","steps":["trace[351758689] 'process raft request' (duration: 126.923588ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:52.067582Z","caller":"traceutil/trace.go:171","msg":"trace[199469251] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"127.362827ms","start":"2026-02-25T10:07:51.94019Z","end":"2026-02-25T10:07:52.067552Z","steps":["trace[199469251] 'process raft request' (duration: 127.064621ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:52.067724Z","caller":"traceutil/trace.go:171","msg":"trace[1662635156] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"126.747074ms","start":"2026-02-25T10:07:51.94096Z","end":"2026-02-25T10:07:52.067707Z","steps":["trace[1662635156] 'process raft request' (duration: 126.466418ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:52.067906Z","caller":"traceutil/trace.go:171","msg":"trace[1894404473] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"124.220603ms","start":"2026-02-25T10:07:51.943672Z","end":"2026-02-25T10:07:52.067892Z","steps":["trace[1894404473] 'process raft request' (duration: 123.870675ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:52.068095Z","caller":"traceutil/trace.go:171","msg":"trace[1155596512] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"123.843425ms","start":"2026-02-25T10:07:51.944239Z","end":"2026-02-25T10:07:52.068082Z","steps":["trace[1155596512] 'process raft request' (duration: 123.58986ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:52.068121Z","caller":"traceutil/trace.go:171","msg":"trace[1604049249] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"123.428025ms","start":"2026-02-25T10:07:51.94468Z","end":"2026-02-25T10:07:52.068108Z","steps":["trace[1604049249] 'process raft request' (duration: 123.254722ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:52.396974Z","caller":"traceutil/trace.go:171","msg":"trace[364254129] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"323.97929ms","start":"2026-02-25T10:07:52.072958Z","end":"2026-02-25T10:07:52.396937Z","steps":["trace[364254129] 'process raft request' (duration: 323.812657ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:52.397037Z","caller":"traceutil/trace.go:171","msg":"trace[1598958198] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"324.331437ms","start":"2026-02-25T10:07:52.072675Z","end":"2026-02-25T10:07:52.397007Z","steps":["trace[1598958198] 'process raft request' (duration: 264.250975ms)","trace[1598958198] 'compare' (duration: 59.650394ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:07:52.397141Z","caller":"traceutil/trace.go:171","msg":"trace[2126916039] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"193.287433ms","start":"2026-02-25T10:07:52.203828Z","end":"2026-02-25T10:07:52.397115Z","steps":["trace[2126916039] 'process raft request' (duration: 193.232312ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:52.397175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:52.072647Z","time spent":"324.460401ms","remote":"127.0.0.1:54796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1174,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T10:07:52.397232Z","caller":"traceutil/trace.go:171","msg":"trace[1635478221] linearizableReadLoop","detail":"{readStateIndex:642; appliedIndex:638; }","duration":"314.024012ms","start":"2026-02-25T10:07:52.083195Z","end":"2026-02-25T10:07:52.397219Z","steps":["trace[1635478221] 'read index received' (duration: 67.555759ms)","trace[1635478221] 'applied index is now lower than readState.Index' (duration: 246.466993ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:07:52.39716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:52.072938Z","time spent":"324.139354ms","remote":"127.0.0.1:54796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1134,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T10:07:52.397339Z","caller":"traceutil/trace.go:171","msg":"trace[408742528] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"310.770134ms","start":"2026-02-25T10:07:52.086521Z","end":"2026-02-25T10:07:52.397291Z","steps":["trace[408742528] 'process raft request' (duration: 310.454728ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:52.39746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.259316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/admin\" ","response":"range_response_count:1 size:3944"} {"level":"info","ts":"2026-02-25T10:07:52.397495Z","caller":"traceutil/trace.go:171","msg":"trace[578922313] range","detail":"{range_begin:/registry/clusterroles/admin; range_end:; response_count:1; response_revision:624; }","duration":"314.343288ms","start":"2026-02-25T10:07:52.083142Z","end":"2026-02-25T10:07:52.397486Z","steps":["trace[578922313] 'agreement among raft nodes before linearized reading' (duration: 314.165525ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:52.397498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.728204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/edit\" ","response":"range_response_count:1 size:3871"} {"level":"warn","ts":"2026-02-25T10:07:52.397509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:52.086509Z","time spent":"310.907087ms","remote":"127.0.0.1:54512","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":59672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T10:07:52.397527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:52.083121Z","time spent":"314.399829ms","remote":"127.0.0.1:54808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":30,"response count":1,"response size":3967,"request content":"key:\"/registry/clusterroles/admin\" "} {"level":"info","ts":"2026-02-25T10:07:52.39756Z","caller":"traceutil/trace.go:171","msg":"trace[504167980] range","detail":"{range_begin:/registry/clusterroles/edit; range_end:; response_count:1; response_revision:624; }","duration":"312.823146ms","start":"2026-02-25T10:07:52.084716Z","end":"2026-02-25T10:07:52.39754Z","steps":["trace[504167980] 'agreement among raft nodes before linearized reading' (duration: 312.655003ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:52.397595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:52.084695Z","time spent":"312.892657ms","remote":"127.0.0.1:54808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":3894,"request content":"key:\"/registry/clusterroles/edit\" "} {"level":"warn","ts":"2026-02-25T10:07:52.397592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.378637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-r2pl4\" ","response":"range_response_count:1 size:16818"} {"level":"info","ts":"2026-02-25T10:07:52.397665Z","caller":"traceutil/trace.go:171","msg":"trace[1811695330] range","detail":"{range_begin:/registry/pods/kube-system/cilium-r2pl4; range_end:; response_count:1; response_revision:624; }","duration":"195.4935ms","start":"2026-02-25T10:07:52.202151Z","end":"2026-02-25T10:07:52.397645Z","steps":["trace[1811695330] 'agreement among raft nodes before linearized reading' (duration: 195.252895ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:52.39722Z","caller":"traceutil/trace.go:171","msg":"trace[42197099] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"314.003242ms","start":"2026-02-25T10:07:52.083107Z","end":"2026-02-25T10:07:52.39711Z","steps":["trace[42197099] 'process raft request' (duration: 313.727646ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:52.398087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:52.08309Z","time spent":"314.92552ms","remote":"127.0.0.1:54512","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9941,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T10:07:52.603332Z","caller":"traceutil/trace.go:171","msg":"trace[1676032474] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"191.250951ms","start":"2026-02-25T10:07:52.412048Z","end":"2026-02-25T10:07:52.603299Z","steps":["trace[1676032474] 'process raft request' (duration: 146.285003ms)","trace[1676032474] 'compare' (duration: 44.58093ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:07:52.603587Z","caller":"traceutil/trace.go:171","msg":"trace[61817069] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"187.002342ms","start":"2026-02-25T10:07:52.416536Z","end":"2026-02-25T10:07:52.603539Z","steps":["trace[61817069] 'process raft request' (duration: 186.652095ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:52.603782Z","caller":"traceutil/trace.go:171","msg":"trace[1693958665] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"165.859801ms","start":"2026-02-25T10:07:52.437896Z","end":"2026-02-25T10:07:52.603756Z","steps":["trace[1693958665] 'process raft request' (duration: 165.779579ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:52.603906Z","caller":"traceutil/trace.go:171","msg":"trace[1780218103] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"181.950537ms","start":"2026-02-25T10:07:52.421928Z","end":"2026-02-25T10:07:52.603879Z","steps":["trace[1780218103] 'process raft request' (duration: 181.507928ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:52.60378Z","caller":"traceutil/trace.go:171","msg":"trace[746434802] linearizableReadLoop","detail":"{readStateIndex:648; appliedIndex:645; }","duration":"165.990174ms","start":"2026-02-25T10:07:52.437765Z","end":"2026-02-25T10:07:52.603755Z","steps":["trace[746434802] 'read index received' (duration: 120.75561ms)","trace[746434802] 'applied index is now lower than readState.Index' (duration: 45.232814ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:07:52.604118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.338521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:122928"} {"level":"info","ts":"2026-02-25T10:07:52.604167Z","caller":"traceutil/trace.go:171","msg":"trace[1466769736] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:629; }","duration":"166.443433ms","start":"2026-02-25T10:07:52.437714Z","end":"2026-02-25T10:07:52.604157Z","steps":["trace[1466769736] 'agreement among raft nodes before linearized reading' (duration: 166.153647ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:52.788508Z","caller":"traceutil/trace.go:171","msg":"trace[60754220] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"179.78876ms","start":"2026-02-25T10:07:52.608688Z","end":"2026-02-25T10:07:52.788477Z","steps":["trace[60754220] 'process raft request' (duration: 178.659076ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:52.788689Z","caller":"traceutil/trace.go:171","msg":"trace[2125227128] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"179.835452ms","start":"2026-02-25T10:07:52.608803Z","end":"2026-02-25T10:07:52.788639Z","steps":["trace[2125227128] 'process raft request' (duration: 179.612347ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:52.808217Z","caller":"traceutil/trace.go:171","msg":"trace[1026680859] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"163.034772ms","start":"2026-02-25T10:07:52.645155Z","end":"2026-02-25T10:07:52.80819Z","steps":["trace[1026680859] 'process raft request' (duration: 162.597373ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:52.808384Z","caller":"traceutil/trace.go:171","msg":"trace[2047654545] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"156.976255ms","start":"2026-02-25T10:07:52.65139Z","end":"2026-02-25T10:07:52.808366Z","steps":["trace[2047654545] 'process raft request' (duration: 156.677279ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:53.077669Z","caller":"traceutil/trace.go:171","msg":"trace[515900522] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"138.353136ms","start":"2026-02-25T10:07:52.939272Z","end":"2026-02-25T10:07:53.077625Z","steps":["trace[515900522] 'process raft request' (duration: 129.641134ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:53.077715Z","caller":"traceutil/trace.go:171","msg":"trace[97282057] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"136.09854ms","start":"2026-02-25T10:07:52.941585Z","end":"2026-02-25T10:07:53.077684Z","steps":["trace[97282057] 'process raft request' (duration: 136.031179ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:53.07788Z","caller":"traceutil/trace.go:171","msg":"trace[1847287044] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"138.078861ms","start":"2026-02-25T10:07:52.939771Z","end":"2026-02-25T10:07:53.07785Z","steps":["trace[1847287044] 'process raft request' (duration: 137.705003ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:53.078116Z","caller":"traceutil/trace.go:171","msg":"trace[1470265538] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"136.337955ms","start":"2026-02-25T10:07:52.94177Z","end":"2026-02-25T10:07:53.078108Z","steps":["trace[1470265538] 'process raft request' (duration: 135.877275ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:53.078148Z","caller":"traceutil/trace.go:171","msg":"trace[1885953715] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"136.851505ms","start":"2026-02-25T10:07:52.941283Z","end":"2026-02-25T10:07:53.078135Z","steps":["trace[1885953715] 'process raft request' (duration: 136.289554ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:53.078137Z","caller":"traceutil/trace.go:171","msg":"trace[2043819898] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"137.06933ms","start":"2026-02-25T10:07:52.941046Z","end":"2026-02-25T10:07:53.078115Z","steps":["trace[2043819898] 'process raft request' (duration: 136.475088ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:53.418965Z","caller":"traceutil/trace.go:171","msg":"trace[1977843187] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"335.563762ms","start":"2026-02-25T10:07:53.083344Z","end":"2026-02-25T10:07:53.418908Z","steps":["trace[1977843187] 'process raft request' (duration: 335.189924ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:53.419128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.133213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/cert-manager/\" range_end:\"/registry/limitranges/cert-manager0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T10:07:53.419166Z","caller":"traceutil/trace.go:171","msg":"trace[1698174016] range","detail":"{range_begin:/registry/limitranges/cert-manager/; range_end:/registry/limitranges/cert-manager0; response_count:0; response_revision:653; }","duration":"335.214054ms","start":"2026-02-25T10:07:53.083942Z","end":"2026-02-25T10:07:53.419156Z","steps":["trace[1698174016] 'agreement among raft nodes before linearized reading' (duration: 335.126272ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:53.419192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:53.08393Z","time spent":"335.254976ms","remote":"127.0.0.1:54628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/cert-manager/\" range_end:\"/registry/limitranges/cert-manager0\" "} {"level":"warn","ts":"2026-02-25T10:07:53.419242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:53.083319Z","time spent":"335.782256ms","remote":"127.0.0.1:54896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4603,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T10:07:53.419234Z","caller":"traceutil/trace.go:171","msg":"trace[209778457] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"335.622893ms","start":"2026-02-25T10:07:53.083582Z","end":"2026-02-25T10:07:53.419205Z","steps":["trace[209778457] 'process raft request' (duration: 335.109053ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:53.419334Z","caller":"traceutil/trace.go:171","msg":"trace[473366698] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"334.94833ms","start":"2026-02-25T10:07:53.084377Z","end":"2026-02-25T10:07:53.419326Z","steps":["trace[473366698] 'process raft request' (duration: 334.554161ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:53.419372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:53.083545Z","time spent":"335.756536ms","remote":"127.0.0.1:54934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1732,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T10:07:53.4194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:53.084357Z","time spent":"334.9963ms","remote":"127.0.0.1:54896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4315,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T10:07:53.419477Z","caller":"traceutil/trace.go:171","msg":"trace[508065268] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"335.049191ms","start":"2026-02-25T10:07:53.084412Z","end":"2026-02-25T10:07:53.419462Z","steps":["trace[508065268] 'process raft request' (duration: 334.552431ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:53.419507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:53.084394Z","time spent":"335.095322ms","remote":"127.0.0.1:54896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3479,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T10:07:53.419014Z","caller":"traceutil/trace.go:171","msg":"trace[682704409] linearizableReadLoop","detail":"{readStateIndex:672; appliedIndex:670; }","duration":"335.004361ms","start":"2026-02-25T10:07:53.083989Z","end":"2026-02-25T10:07:53.418993Z","steps":["trace[682704409] 'read index received' (duration: 90.403238ms)","trace[682704409] 'applied index is now lower than readState.Index' (duration: 244.600473ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:07:53.461105Z","caller":"traceutil/trace.go:171","msg":"trace[881256864] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"282.542185ms","start":"2026-02-25T10:07:53.178536Z","end":"2026-02-25T10:07:53.461078Z","steps":["trace[881256864] 'process raft request' (duration: 282.405252ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:53.583417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.962353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/cert-manager/cert-manager-startupapicheck\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T10:07:53.583482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.040256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/cert-manager/cert-manager\" ","response":"range_response_count:1 size:4330"} {"level":"info","ts":"2026-02-25T10:07:53.583545Z","caller":"traceutil/trace.go:171","msg":"trace[1365367980] range","detail":"{range_begin:/registry/deployments/cert-manager/cert-manager; range_end:; response_count:1; response_revision:660; }","duration":"156.125648ms","start":"2026-02-25T10:07:53.427401Z","end":"2026-02-25T10:07:53.583527Z","steps":["trace[1365367980] 'agreement among raft nodes before linearized reading' (duration: 155.876232ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:53.583503Z","caller":"traceutil/trace.go:171","msg":"trace[249338904] range","detail":"{range_begin:/registry/serviceaccounts/cert-manager/cert-manager-startupapicheck; range_end:; response_count:0; response_revision:660; }","duration":"154.090805ms","start":"2026-02-25T10:07:53.429391Z","end":"2026-02-25T10:07:53.583482Z","steps":["trace[249338904] 'agreement among raft nodes before linearized reading' (duration: 153.933501ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:53.58328Z","caller":"traceutil/trace.go:171","msg":"trace[1107990897] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"117.90984ms","start":"2026-02-25T10:07:53.465319Z","end":"2026-02-25T10:07:53.583229Z","steps":["trace[1107990897] 'process raft request' (duration: 33.919098ms)","trace[1107990897] 'compare' (duration: 83.537242ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:07:53.584416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.901903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/cert-manager/cert-manager-webhook\" ","response":"range_response_count:1 size:4618"} {"level":"info","ts":"2026-02-25T10:07:53.584591Z","caller":"traceutil/trace.go:171","msg":"trace[2121382361] range","detail":"{range_begin:/registry/deployments/cert-manager/cert-manager-webhook; range_end:; response_count:1; response_revision:665; }","duration":"157.099328ms","start":"2026-02-25T10:07:53.427468Z","end":"2026-02-25T10:07:53.584567Z","steps":["trace[2121382361] 'agreement among raft nodes before linearized reading' (duration: 155.972965ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:53.726119Z","caller":"traceutil/trace.go:171","msg":"trace[420395819] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"133.578566ms","start":"2026-02-25T10:07:53.592514Z","end":"2026-02-25T10:07:53.726093Z","steps":["trace[420395819] 'process raft request' (duration: 133.365362ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:53.726472Z","caller":"traceutil/trace.go:171","msg":"trace[941369645] transaction","detail":"{read_only:false; response_revision:670; number_of_response:1; }","duration":"126.846357ms","start":"2026-02-25T10:07:53.599616Z","end":"2026-02-25T10:07:53.726462Z","steps":["trace[941369645] 'process raft request' (duration: 126.744655ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:53.726531Z","caller":"traceutil/trace.go:171","msg":"trace[1500594402] transaction","detail":"{read_only:false; response_revision:668; number_of_response:1; }","duration":"129.898601ms","start":"2026-02-25T10:07:53.596607Z","end":"2026-02-25T10:07:53.726506Z","steps":["trace[1500594402] 'process raft request' (duration: 129.43862ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:53.726598Z","caller":"traceutil/trace.go:171","msg":"trace[446753304] transaction","detail":"{read_only:false; response_revision:669; number_of_response:1; }","duration":"127.858578ms","start":"2026-02-25T10:07:53.598666Z","end":"2026-02-25T10:07:53.726525Z","steps":["trace[446753304] 'process raft request' (duration: 127.639864ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:53.726935Z","caller":"traceutil/trace.go:171","msg":"trace[448534446] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"126.284404ms","start":"2026-02-25T10:07:53.600634Z","end":"2026-02-25T10:07:53.726918Z","steps":["trace[448534446] 'process raft request' (duration: 126.229653ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:53.726943Z","caller":"traceutil/trace.go:171","msg":"trace[509903349] transaction","detail":"{read_only:false; response_revision:671; number_of_response:1; }","duration":"126.97182ms","start":"2026-02-25T10:07:53.599955Z","end":"2026-02-25T10:07:53.726927Z","steps":["trace[509903349] 'process raft request' (duration: 126.461199ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:53.726945Z","caller":"traceutil/trace.go:171","msg":"trace[451400651] transaction","detail":"{read_only:false; response_revision:672; number_of_response:1; }","duration":"126.669713ms","start":"2026-02-25T10:07:53.600259Z","end":"2026-02-25T10:07:53.726928Z","steps":["trace[451400651] 'process raft request' (duration: 126.54542ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:53.958018Z","caller":"traceutil/trace.go:171","msg":"trace[335386079] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"268.852649ms","start":"2026-02-25T10:07:53.689142Z","end":"2026-02-25T10:07:53.957994Z","steps":["trace[335386079] 'process raft request' (duration: 268.334799ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:54.175187Z","caller":"traceutil/trace.go:171","msg":"trace[942413819] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"443.063574ms","start":"2026-02-25T10:07:53.732072Z","end":"2026-02-25T10:07:54.175136Z","steps":["trace[942413819] 'process raft request' (duration: 411.899254ms)","trace[942413819] 'compare' (duration: 30.880084ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:07:54.175226Z","caller":"traceutil/trace.go:171","msg":"trace[1951704912] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"425.967648ms","start":"2026-02-25T10:07:53.74923Z","end":"2026-02-25T10:07:54.175197Z","steps":["trace[1951704912] 'process raft request' (duration: 425.868466ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:54.175225Z","caller":"traceutil/trace.go:171","msg":"trace[725736724] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"441.911611ms","start":"2026-02-25T10:07:53.733292Z","end":"2026-02-25T10:07:54.175204Z","steps":["trace[725736724] 'process raft request' (duration: 441.738997ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:54.175402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:53.749188Z","time spent":"426.136231ms","remote":"127.0.0.1:54556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":771,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T10:07:54.17541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:53.732049Z","time spent":"443.270168ms","remote":"127.0.0.1:54556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":881,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T10:07:54.175531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:53.733284Z","time spent":"442.164936ms","remote":"127.0.0.1:54672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3093,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T10:07:54.175521Z","caller":"traceutil/trace.go:171","msg":"trace[1193733720] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"424.13608ms","start":"2026-02-25T10:07:53.751355Z","end":"2026-02-25T10:07:54.175491Z","steps":["trace[1193733720] 'process raft request' (duration: 423.777462ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:54.175578Z","caller":"traceutil/trace.go:171","msg":"trace[1627513286] linearizableReadLoop","detail":"{readStateIndex:699; appliedIndex:694; }","duration":"424.061907ms","start":"2026-02-25T10:07:53.751445Z","end":"2026-02-25T10:07:54.175507Z","steps":["trace[1627513286] 'read index received' (duration: 205.903305ms)","trace[1627513286] 'applied index is now lower than readState.Index' (duration: 218.154312ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:07:54.175664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:53.751306Z","time spent":"424.278893ms","remote":"127.0.0.1:54896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3828,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T10:07:54.17569Z","caller":"traceutil/trace.go:171","msg":"trace[1727502142] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"199.957431ms","start":"2026-02-25T10:07:53.975722Z","end":"2026-02-25T10:07:54.17568Z","steps":["trace[1727502142] 'process raft request' (duration: 199.659235ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:54.175838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.878964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T10:07:54.175866Z","caller":"traceutil/trace.go:171","msg":"trace[1410171787] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:679; }","duration":"181.976867ms","start":"2026-02-25T10:07:53.993882Z","end":"2026-02-25T10:07:54.175859Z","steps":["trace[1410171787] 'agreement among raft nodes before linearized reading' (duration: 181.925336ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:54.175866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.407104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/cert-manager/cert-manager-webhook\" ","response":"range_response_count:1 size:4928"} {"level":"warn","ts":"2026-02-25T10:07:54.175881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.386054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/cert-manager/cert-manager\" ","response":"range_response_count:1 size:4641"} {"level":"info","ts":"2026-02-25T10:07:54.175919Z","caller":"traceutil/trace.go:171","msg":"trace[1398027393] range","detail":"{range_begin:/registry/deployments/cert-manager/cert-manager-webhook; range_end:; response_count:1; response_revision:679; }","duration":"424.491697ms","start":"2026-02-25T10:07:53.751411Z","end":"2026-02-25T10:07:54.175903Z","steps":["trace[1398027393] 'agreement among raft nodes before linearized reading' (duration: 424.267102ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:54.175924Z","caller":"traceutil/trace.go:171","msg":"trace[520293827] range","detail":"{range_begin:/registry/deployments/cert-manager/cert-manager; range_end:; response_count:1; response_revision:679; }","duration":"424.64373ms","start":"2026-02-25T10:07:53.751267Z","end":"2026-02-25T10:07:54.175911Z","steps":["trace[520293827] 'agreement among raft nodes before linearized reading' (duration: 424.463577ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:54.175969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:53.751258Z","time spent":"424.701231ms","remote":"127.0.0.1:54896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":4664,"request content":"key:\"/registry/deployments/cert-manager/cert-manager\" "} {"level":"warn","ts":"2026-02-25T10:07:54.175963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:53.751403Z","time spent":"424.550718ms","remote":"127.0.0.1:54896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":1,"response size":4951,"request content":"key:\"/registry/deployments/cert-manager/cert-manager-webhook\" "} {"level":"warn","ts":"2026-02-25T10:07:54.175997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.685983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-25T10:07:54.176086Z","caller":"traceutil/trace.go:171","msg":"trace[181574677] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:679; }","duration":"102.824555ms","start":"2026-02-25T10:07:54.07324Z","end":"2026-02-25T10:07:54.176065Z","steps":["trace[181574677] 'agreement among raft nodes before linearized reading' (duration: 102.524089ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:54.476717Z","caller":"traceutil/trace.go:171","msg":"trace[177458874] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"170.262631ms","start":"2026-02-25T10:07:54.306436Z","end":"2026-02-25T10:07:54.476699Z","steps":["trace[177458874] 'process raft request' (duration: 170.052227ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:54.985779Z","caller":"traceutil/trace.go:171","msg":"trace[1495570751] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"351.547984ms","start":"2026-02-25T10:07:54.634201Z","end":"2026-02-25T10:07:54.985749Z","steps":["trace[1495570751] 'process raft request' (duration: 322.361355ms)","trace[1495570751] 'compare' (duration: 28.853462ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:07:54.985958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:54.634181Z","time spent":"351.675267ms","remote":"127.0.0.1:54576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":211602,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T10:07:54.986048Z","caller":"traceutil/trace.go:171","msg":"trace[1049153262] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"129.582903ms","start":"2026-02-25T10:07:54.856396Z","end":"2026-02-25T10:07:54.985979Z","steps":["trace[1049153262] 'process raft request' (duration: 129.43282ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:55.630454Z","caller":"traceutil/trace.go:171","msg":"trace[473980104] transaction","detail":"{read_only:false; response_revision:695; number_of_response:1; }","duration":"351.216867ms","start":"2026-02-25T10:07:55.2792Z","end":"2026-02-25T10:07:55.630417Z","steps":["trace[473980104] 'process raft request' (duration: 268.709406ms)","trace[473980104] 'compare' (duration: 82.304447ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:07:55.630603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:55.27918Z","time spent":"351.360601ms","remote":"127.0.0.1:54712","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2944,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T10:07:55.843784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.195921ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-25T10:07:55.844059Z","caller":"traceutil/trace.go:171","msg":"trace[1562749554] transaction","detail":"{read_only:false; response_revision:700; number_of_response:1; }","duration":"163.158184ms","start":"2026-02-25T10:07:55.680882Z","end":"2026-02-25T10:07:55.84404Z","steps":["trace[1562749554] 'process raft request' (duration: 163.100183ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:55.844301Z","caller":"traceutil/trace.go:171","msg":"trace[863282148] transaction","detail":"{read_only:false; response_revision:699; number_of_response:1; }","duration":"166.134467ms","start":"2026-02-25T10:07:55.678152Z","end":"2026-02-25T10:07:55.844287Z","steps":["trace[863282148] 'process raft request' (duration: 165.752158ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:55.844314Z","caller":"traceutil/trace.go:171","msg":"trace[1468541251] transaction","detail":"{read_only:false; response_revision:698; number_of_response:1; }","duration":"166.816891ms","start":"2026-02-25T10:07:55.677463Z","end":"2026-02-25T10:07:55.84428Z","steps":["trace[1468541251] 'process raft request' (duration: 53.049327ms)","trace[1468541251] 'compare' (duration: 113.078479ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:07:56.94902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"746.68984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-25T10:07:56.949106Z","caller":"traceutil/trace.go:171","msg":"trace[1125046667] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:702; }","duration":"746.828903ms","start":"2026-02-25T10:07:56.20226Z","end":"2026-02-25T10:07:56.949089Z","steps":["trace[1125046667] 'range keys from in-memory index tree' (duration: 746.522516ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:56.949149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:56.202238Z","time spent":"746.900084ms","remote":"127.0.0.1:54734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T10:07:56.949418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.478731ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T10:07:56.949565Z","caller":"traceutil/trace.go:171","msg":"trace[1861158926] transaction","detail":"{read_only:false; response_revision:703; number_of_response:1; }","duration":"454.662836ms","start":"2026-02-25T10:07:56.494876Z","end":"2026-02-25T10:07:56.949539Z","steps":["trace[1861158926] 'process raft request' (duration: 140.966601ms)","trace[1861158926] 'compare' (duration: 313.356718ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:07:56.949546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"731.928931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-r2pl4\" ","response":"range_response_count:1 size:17171"} {"level":"info","ts":"2026-02-25T10:07:56.949662Z","caller":"traceutil/trace.go:171","msg":"trace[1531602069] range","detail":"{range_begin:/registry/pods/kube-system/cilium-r2pl4; range_end:; response_count:1; response_revision:702; }","duration":"732.112225ms","start":"2026-02-25T10:07:56.217523Z","end":"2026-02-25T10:07:56.949635Z","steps":["trace[1531602069] 'range keys from in-memory index tree' (duration: 731.664985ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:56.949674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:56.494854Z","time spent":"454.773849ms","remote":"127.0.0.1:54734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T10:07:56.949731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:56.217501Z","time spent":"732.219167ms","remote":"127.0.0.1:54672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":17194,"request content":"key:\"/registry/pods/kube-system/cilium-r2pl4\" "} {"level":"info","ts":"2026-02-25T10:07:57.072246Z","caller":"traceutil/trace.go:171","msg":"trace[391417946] transaction","detail":"{read_only:false; response_revision:705; number_of_response:1; }","duration":"108.496404ms","start":"2026-02-25T10:07:56.96371Z","end":"2026-02-25T10:07:57.072206Z","steps":["trace[391417946] 'process raft request' (duration: 105.877309ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:57.282213Z","caller":"traceutil/trace.go:171","msg":"trace[973301613] transaction","detail":"{read_only:false; response_revision:707; number_of_response:1; }","duration":"107.985063ms","start":"2026-02-25T10:07:57.174199Z","end":"2026-02-25T10:07:57.282184Z","steps":["trace[973301613] 'process raft request' (duration: 86.798681ms)","trace[973301613] 'compare' (duration: 20.923587ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:07:57.282443Z","caller":"traceutil/trace.go:171","msg":"trace[1248157961] linearizableReadLoop","detail":"{readStateIndex:728; appliedIndex:727; }","duration":"106.551273ms","start":"2026-02-25T10:07:57.175865Z","end":"2026-02-25T10:07:57.282416Z","steps":["trace[1248157961] 'read index received' (duration: 85.144216ms)","trace[1248157961] 'applied index is now lower than readState.Index' (duration: 21.404767ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:07:57.282927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.034903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.201\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-25T10:07:57.282982Z","caller":"traceutil/trace.go:171","msg":"trace[333872758] range","detail":"{range_begin:/registry/masterleases/199.19.213.201; range_end:; response_count:1; response_revision:708; }","duration":"107.138395ms","start":"2026-02-25T10:07:57.175829Z","end":"2026-02-25T10:07:57.282967Z","steps":["trace[333872758] 'agreement among raft nodes before linearized reading' (duration: 106.930171ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:57.471051Z","caller":"traceutil/trace.go:171","msg":"trace[1993797104] transaction","detail":"{read_only:false; response_revision:710; number_of_response:1; }","duration":"183.711842ms","start":"2026-02-25T10:07:57.287322Z","end":"2026-02-25T10:07:57.471034Z","steps":["trace[1993797104] 'process raft request' (duration: 183.664811ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:57.471164Z","caller":"traceutil/trace.go:171","msg":"trace[1244984993] transaction","detail":"{read_only:false; response_revision:709; number_of_response:1; }","duration":"183.820775ms","start":"2026-02-25T10:07:57.287299Z","end":"2026-02-25T10:07:57.47112Z","steps":["trace[1244984993] 'process raft request' (duration: 144.256319ms)","trace[1244984993] 'compare' (duration: 39.320531ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:07:57.535203Z","caller":"traceutil/trace.go:171","msg":"trace[824307948] transaction","detail":"{read_only:false; response_revision:711; number_of_response:1; }","duration":"238.025576ms","start":"2026-02-25T10:07:57.297142Z","end":"2026-02-25T10:07:57.535168Z","steps":["trace[824307948] 'process raft request' (duration: 237.817222ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:58.243995Z","caller":"traceutil/trace.go:171","msg":"trace[1233303352] transaction","detail":"{read_only:false; response_revision:714; number_of_response:1; }","duration":"112.585369ms","start":"2026-02-25T10:07:58.131388Z","end":"2026-02-25T10:07:58.243973Z","steps":["trace[1233303352] 'process raft request' (duration: 112.490147ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:58.53319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.552942ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-25T10:07:58.533571Z","caller":"traceutil/trace.go:171","msg":"trace[1601734137] transaction","detail":"{read_only:false; response_revision:716; number_of_response:1; }","duration":"136.001708ms","start":"2026-02-25T10:07:58.397549Z","end":"2026-02-25T10:07:58.53355Z","steps":["trace[1601734137] 'process raft request' (duration: 30.014436ms)","trace[1601734137] 'compare' (duration: 105.428039ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:07:58.716999Z","caller":"traceutil/trace.go:171","msg":"trace[1630492303] linearizableReadLoop","detail":"{readStateIndex:740; appliedIndex:739; }","duration":"103.752674ms","start":"2026-02-25T10:07:58.613222Z","end":"2026-02-25T10:07:58.716975Z","steps":["trace[1630492303] 'read index received' (duration: 73.175346ms)","trace[1630492303] 'applied index is now lower than readState.Index' (duration: 30.576238ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:07:58.717107Z","caller":"traceutil/trace.go:171","msg":"trace[1136056081] transaction","detail":"{read_only:false; response_revision:718; number_of_response:1; }","duration":"105.463551ms","start":"2026-02-25T10:07:58.611606Z","end":"2026-02-25T10:07:58.717069Z","steps":["trace[1136056081] 'process raft request' (duration: 74.857682ms)","trace[1136056081] 'compare' (duration: 30.405775ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:07:58.717202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.948588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T10:07:58.717264Z","caller":"traceutil/trace.go:171","msg":"trace[771192035] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:0; response_revision:718; }","duration":"104.046731ms","start":"2026-02-25T10:07:58.6132Z","end":"2026-02-25T10:07:58.717246Z","steps":["trace[771192035] 'agreement among raft nodes before linearized reading' (duration: 103.928489ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:58.894201Z","caller":"traceutil/trace.go:171","msg":"trace[99808954] linearizableReadLoop","detail":"{readStateIndex:741; appliedIndex:740; }","duration":"177.082864ms","start":"2026-02-25T10:07:58.71709Z","end":"2026-02-25T10:07:58.894173Z","steps":["trace[99808954] 'read index received' (duration: 105.047482ms)","trace[99808954] 'applied index is now lower than readState.Index' (duration: 72.033992ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:07:58.894264Z","caller":"traceutil/trace.go:171","msg":"trace[1781935346] transaction","detail":"{read_only:false; response_revision:719; number_of_response:1; }","duration":"201.937484ms","start":"2026-02-25T10:07:58.692262Z","end":"2026-02-25T10:07:58.894199Z","steps":["trace[1781935346] 'process raft request' (duration: 130.020434ms)","trace[1781935346] 'compare' (duration: 71.636304ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:07:58.894405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.808529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T10:07:58.894414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.202806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T10:07:58.894458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.723806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T10:07:58.89441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.91564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T10:07:58.894491Z","caller":"traceutil/trace.go:171","msg":"trace[701547305] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:0; response_revision:719; }","duration":"280.274447ms","start":"2026-02-25T10:07:58.614196Z","end":"2026-02-25T10:07:58.89447Z","steps":["trace[701547305] 'agreement among raft nodes before linearized reading' (duration: 280.175315ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:58.894526Z","caller":"traceutil/trace.go:171","msg":"trace[1758378733] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:0; response_revision:719; }","duration":"280.803678ms","start":"2026-02-25T10:07:58.613704Z","end":"2026-02-25T10:07:58.894508Z","steps":["trace[1758378733] 'agreement among raft nodes before linearized reading' (duration: 280.694506ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:58.894538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.131055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T10:07:58.894538Z","caller":"traceutil/trace.go:171","msg":"trace[1246280156] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:0; response_revision:719; }","duration":"280.074933ms","start":"2026-02-25T10:07:58.614446Z","end":"2026-02-25T10:07:58.894521Z","steps":["trace[1246280156] 'agreement among raft nodes before linearized reading' (duration: 279.823068ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:58.89458Z","caller":"traceutil/trace.go:171","msg":"trace[633780055] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:0; response_revision:719; }","duration":"281.173346ms","start":"2026-02-25T10:07:58.613395Z","end":"2026-02-25T10:07:58.894568Z","steps":["trace[633780055] 'agreement among raft nodes before linearized reading' (duration: 281.111245ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:58.894477Z","caller":"traceutil/trace.go:171","msg":"trace[1710360389] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:0; response_revision:719; }","duration":"280.921881ms","start":"2026-02-25T10:07:58.613534Z","end":"2026-02-25T10:07:58.894456Z","steps":["trace[1710360389] 'agreement among raft nodes before linearized reading' (duration: 280.796348ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:58.894651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.447371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T10:07:58.894766Z","caller":"traceutil/trace.go:171","msg":"trace[1410277808] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:0; response_revision:719; }","duration":"280.574844ms","start":"2026-02-25T10:07:58.614164Z","end":"2026-02-25T10:07:58.894738Z","steps":["trace[1410277808] 'agreement among raft nodes before linearized reading' (duration: 280.38586ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:58.894769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.766624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T10:07:58.894741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.129044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T10:07:58.894978Z","caller":"traceutil/trace.go:171","msg":"trace[1105218289] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:719; }","duration":"280.319919ms","start":"2026-02-25T10:07:58.614588Z","end":"2026-02-25T10:07:58.894908Z","steps":["trace[1105218289] 'agreement among raft nodes before linearized reading' (duration: 280.065043ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:58.894862Z","caller":"traceutil/trace.go:171","msg":"trace[1493562596] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:719; }","duration":"253.901988ms","start":"2026-02-25T10:07:58.640934Z","end":"2026-02-25T10:07:58.894836Z","steps":["trace[1493562596] 'agreement among raft nodes before linearized reading' (duration: 253.777284ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:58.895422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.140484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T10:07:58.895542Z","caller":"traceutil/trace.go:171","msg":"trace[1326181464] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:719; }","duration":"279.649975ms","start":"2026-02-25T10:07:58.615873Z","end":"2026-02-25T10:07:58.895523Z","steps":["trace[1326181464] 'agreement among raft nodes before linearized reading' (duration: 278.747385ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:59.182279Z","caller":"traceutil/trace.go:171","msg":"trace[158325355] transaction","detail":"{read_only:false; response_revision:723; number_of_response:1; }","duration":"126.134452ms","start":"2026-02-25T10:07:59.056121Z","end":"2026-02-25T10:07:59.182255Z","steps":["trace[158325355] 'process raft request' (duration: 125.956148ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:59.36696Z","caller":"traceutil/trace.go:171","msg":"trace[1674138226] transaction","detail":"{read_only:false; response_revision:725; number_of_response:1; }","duration":"178.835151ms","start":"2026-02-25T10:07:59.188099Z","end":"2026-02-25T10:07:59.366934Z","steps":["trace[1674138226] 'process raft request' (duration: 102.442147ms)","trace[1674138226] 'compare' (duration: 76.277522ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:07:59.375842Z","caller":"traceutil/trace.go:171","msg":"trace[785725237] linearizableReadLoop","detail":"{readStateIndex:748; appliedIndex:746; }","duration":"127.908269ms","start":"2026-02-25T10:07:59.247917Z","end":"2026-02-25T10:07:59.375826Z","steps":["trace[785725237] 'read index received' (duration: 42.63657ms)","trace[785725237] 'applied index is now lower than readState.Index' (duration: 85.270819ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:07:59.375951Z","caller":"traceutil/trace.go:171","msg":"trace[2048601698] transaction","detail":"{read_only:false; response_revision:726; number_of_response:1; }","duration":"158.802343ms","start":"2026-02-25T10:07:59.217079Z","end":"2026-02-25T10:07:59.375882Z","steps":["trace[2048601698] 'process raft request' (duration: 158.631649ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:59.376196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.255516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-r2pl4\" ","response":"range_response_count:1 size:17577"} {"level":"info","ts":"2026-02-25T10:07:59.376256Z","caller":"traceutil/trace.go:171","msg":"trace[369724227] range","detail":"{range_begin:/registry/pods/kube-system/cilium-r2pl4; range_end:; response_count:1; response_revision:727; }","duration":"128.431619ms","start":"2026-02-25T10:07:59.24781Z","end":"2026-02-25T10:07:59.376242Z","steps":["trace[369724227] 'agreement among raft nodes before linearized reading' (duration: 128.204994ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:59.375975Z","caller":"traceutil/trace.go:171","msg":"trace[510708380] transaction","detail":"{read_only:false; response_revision:727; number_of_response:1; }","duration":"127.182583ms","start":"2026-02-25T10:07:59.248756Z","end":"2026-02-25T10:07:59.375938Z","steps":["trace[510708380] 'process raft request' (duration: 127.0281ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:59.511997Z","caller":"traceutil/trace.go:171","msg":"trace[1275682342] transaction","detail":"{read_only:false; response_revision:729; number_of_response:1; }","duration":"131.608416ms","start":"2026-02-25T10:07:59.380346Z","end":"2026-02-25T10:07:59.511954Z","steps":["trace[1275682342] 'process raft request' (duration: 130.705668ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:59.512157Z","caller":"traceutil/trace.go:171","msg":"trace[1667080802] transaction","detail":"{read_only:false; response_revision:731; number_of_response:1; }","duration":"122.529067ms","start":"2026-02-25T10:07:59.389598Z","end":"2026-02-25T10:07:59.512127Z","steps":["trace[1667080802] 'process raft request' (duration: 122.316692ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:59.512164Z","caller":"traceutil/trace.go:171","msg":"trace[1053221498] transaction","detail":"{read_only:false; response_revision:730; number_of_response:1; }","duration":"131.562925ms","start":"2026-02-25T10:07:59.380553Z","end":"2026-02-25T10:07:59.512116Z","steps":["trace[1053221498] 'process raft request' (duration: 131.244419ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:59.623895Z","caller":"traceutil/trace.go:171","msg":"trace[119788299] linearizableReadLoop","detail":"{readStateIndex:754; appliedIndex:750; }","duration":"149.150931ms","start":"2026-02-25T10:07:59.474727Z","end":"2026-02-25T10:07:59.623878Z","steps":["trace[119788299] 'read index received' (duration: 36.354518ms)","trace[119788299] 'applied index is now lower than readState.Index' (duration: 112.795573ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:07:59.623975Z","caller":"traceutil/trace.go:171","msg":"trace[524946651] transaction","detail":"{read_only:false; response_revision:732; number_of_response:1; }","duration":"209.124704ms","start":"2026-02-25T10:07:59.414841Z","end":"2026-02-25T10:07:59.623966Z","steps":["trace[524946651] 'process raft request' (duration: 202.896585ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:59.624116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.373006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-25T10:07:59.62416Z","caller":"traceutil/trace.go:171","msg":"trace[1102682537] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:732; }","duration":"149.463848ms","start":"2026-02-25T10:07:59.474686Z","end":"2026-02-25T10:07:59.62415Z","steps":["trace[1102682537] 'agreement among raft nodes before linearized reading' (duration: 149.308155ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:59.736419Z","caller":"traceutil/trace.go:171","msg":"trace[946445634] linearizableReadLoop","detail":"{readStateIndex:756; appliedIndex:754; }","duration":"112.452166ms","start":"2026-02-25T10:07:59.623946Z","end":"2026-02-25T10:07:59.736398Z","steps":["trace[946445634] 'read index received' (duration: 110.26789ms)","trace[946445634] 'applied index is now lower than readState.Index' (duration: 2.183256ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:07:59.736564Z","caller":"traceutil/trace.go:171","msg":"trace[643964882] transaction","detail":"{read_only:false; response_revision:734; number_of_response:1; }","duration":"119.242179ms","start":"2026-02-25T10:07:59.617287Z","end":"2026-02-25T10:07:59.736529Z","steps":["trace[643964882] 'process raft request' (duration: 119.037215ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:59.736675Z","caller":"traceutil/trace.go:171","msg":"trace[1603603073] transaction","detail":"{read_only:false; response_revision:733; number_of_response:1; }","duration":"218.648702ms","start":"2026-02-25T10:07:59.517987Z","end":"2026-02-25T10:07:59.736635Z","steps":["trace[1603603073] 'process raft request' (duration: 216.212401ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:59.736698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.563972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-7fcd8d6ffd-h5b4h\" ","response":"range_response_count:1 size:4639"} {"level":"info","ts":"2026-02-25T10:07:59.736767Z","caller":"traceutil/trace.go:171","msg":"trace[1116456773] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-7fcd8d6ffd-h5b4h; range_end:; response_count:1; response_revision:734; }","duration":"220.705045ms","start":"2026-02-25T10:07:59.516045Z","end":"2026-02-25T10:07:59.73675Z","steps":["trace[1116456773] 'agreement among raft nodes before linearized reading' (duration: 220.474821ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:59.76348Z","caller":"traceutil/trace.go:171","msg":"trace[906664046] transaction","detail":"{read_only:false; response_revision:735; number_of_response:1; }","duration":"133.997806ms","start":"2026-02-25T10:07:59.629455Z","end":"2026-02-25T10:07:59.763453Z","steps":["trace[906664046] 'process raft request' (duration: 133.819542ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:59.763647Z","caller":"traceutil/trace.go:171","msg":"trace[63328506] transaction","detail":"{read_only:false; response_revision:736; number_of_response:1; }","duration":"134.076007ms","start":"2026-02-25T10:07:59.629547Z","end":"2026-02-25T10:07:59.763623Z","steps":["trace[63328506] 'process raft request' (duration: 133.838552ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:59.890232Z","caller":"traceutil/trace.go:171","msg":"trace[1527759734] transaction","detail":"{read_only:false; response_revision:738; number_of_response:1; }","duration":"107.134385ms","start":"2026-02-25T10:07:59.783062Z","end":"2026-02-25T10:07:59.890196Z","steps":["trace[1527759734] 'process raft request' (duration: 68.244973ms)","trace[1527759734] 'compare' (duration: 38.708178ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:07:59.890595Z","caller":"traceutil/trace.go:171","msg":"trace[718000681] transaction","detail":"{read_only:false; response_revision:739; number_of_response:1; }","duration":"106.610035ms","start":"2026-02-25T10:07:59.783968Z","end":"2026-02-25T10:07:59.890578Z","steps":["trace[718000681] 'process raft request' (duration: 106.182476ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:08:02.366049Z","caller":"traceutil/trace.go:171","msg":"trace[440650694] transaction","detail":"{read_only:false; response_revision:781; number_of_response:1; }","duration":"107.32814ms","start":"2026-02-25T10:08:02.258693Z","end":"2026-02-25T10:08:02.366021Z","steps":["trace[440650694] 'process raft request' (duration: 107.161427ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:08:15.737043Z","caller":"traceutil/trace.go:171","msg":"trace[911480269] linearizableReadLoop","detail":"{readStateIndex:987; appliedIndex:986; }","duration":"241.98749ms","start":"2026-02-25T10:08:15.495024Z","end":"2026-02-25T10:08:15.737011Z","steps":["trace[911480269] 'read index received' (duration: 241.726754ms)","trace[911480269] 'applied index is now lower than readState.Index' (duration: 259.596µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:08:15.737173Z","caller":"traceutil/trace.go:171","msg":"trace[1031213982] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"357.295565ms","start":"2026-02-25T10:08:15.379828Z","end":"2026-02-25T10:08:15.737124Z","steps":["trace[1031213982] 'process raft request' (duration: 356.961938ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:08:15.737419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:08:15.379794Z","time spent":"357.493259ms","remote":"127.0.0.1:54672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5271,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T10:08:15.737433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.386518ms","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-02-25T10:08:15.737632Z","caller":"traceutil/trace.go:171","msg":"trace[348849723] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:959; }","duration":"242.660893ms","start":"2026-02-25T10:08:15.494954Z","end":"2026-02-25T10:08:15.737615Z","steps":["trace[348849723] 'agreement among raft nodes before linearized reading' (duration: 242.286195ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:08:16.347246Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5639523319011308946,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T10:08:16.8482Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5639523319011308946,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T10:08:16.92195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.10284255s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T10:08:16.922181Z","caller":"traceutil/trace.go:171","msg":"trace[1252827430] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"1.179195984s","start":"2026-02-25T10:08:15.742888Z","end":"2026-02-25T10:08:16.922084Z","steps":["trace[1252827430] 'process raft request' (duration: 76.089258ms)","trace[1252827430] 'compare' (duration: 1.102695937s)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:08:16.922285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:08:15.742853Z","time spent":"1.179385467s","remote":"127.0.0.1:54734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T10:08:16.923052Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.103978233s","expected-duration":"1s"} {"level":"info","ts":"2026-02-25T10:08:16.923515Z","caller":"traceutil/trace.go:171","msg":"trace[551456836] linearizableReadLoop","detail":"{readStateIndex:990; appliedIndex:988; }","duration":"1.077312467s","start":"2026-02-25T10:08:15.846171Z","end":"2026-02-25T10:08:16.923483Z","steps":["trace[551456836] 'read index received' (duration: 1.077019031s)","trace[551456836] 'applied index is now lower than readState.Index' (duration: 292.206µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:08:16.923625Z","caller":"traceutil/trace.go:171","msg":"trace[1374231323] transaction","detail":"{read_only:false; response_revision:962; number_of_response:1; }","duration":"1.179749865s","start":"2026-02-25T10:08:15.743866Z","end":"2026-02-25T10:08:16.923616Z","steps":["trace[1374231323] 'process raft request' (duration: 1.179534841s)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:08:16.923641Z","caller":"traceutil/trace.go:171","msg":"trace[780778655] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"1.180261625s","start":"2026-02-25T10:08:15.743349Z","end":"2026-02-25T10:08:16.92361Z","steps":["trace[780778655] 'process raft request' (duration: 1.179926768s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:08:16.923703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:08:15.743853Z","time spent":"1.179800596s","remote":"127.0.0.1:54740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1613,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T10:08:16.923764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:08:15.743316Z","time spent":"1.180363007s","remote":"127.0.0.1:54652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1069,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T10:08:16.923909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.077740036s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-25T10:08:16.924285Z","caller":"traceutil/trace.go:171","msg":"trace[2054661948] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:962; }","duration":"1.078113793s","start":"2026-02-25T10:08:15.846105Z","end":"2026-02-25T10:08:16.924219Z","steps":["trace[2054661948] 'agreement among raft nodes before linearized reading' (duration: 1.077677254s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:08:16.924375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:08:15.846073Z","time spent":"1.078270288s","remote":"127.0.0.1:54734","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-02-25T10:08:16.923875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.072351893s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T10:08:16.924525Z","caller":"traceutil/trace.go:171","msg":"trace[1969936092] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:962; }","duration":"1.073066039s","start":"2026-02-25T10:08:15.851434Z","end":"2026-02-25T10:08:16.9245Z","steps":["trace[1969936092] 'agreement among raft nodes before linearized reading' (duration: 1.072330933s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:08:16.924654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:08:15.851407Z","time spent":"1.073182782s","remote":"127.0.0.1:54934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":1,"response size":30,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"info","ts":"2026-02-25T10:08:42.310109Z","caller":"traceutil/trace.go:171","msg":"trace[1389156151] linearizableReadLoop","detail":"{readStateIndex:1165; appliedIndex:1164; }","duration":"145.932314ms","start":"2026-02-25T10:08:42.164139Z","end":"2026-02-25T10:08:42.310072Z","steps":["trace[1389156151] 'read index received' (duration: 145.654358ms)","trace[1389156151] 'applied index is now lower than readState.Index' (duration: 276.216µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:08:42.310251Z","caller":"traceutil/trace.go:171","msg":"trace[178472798] transaction","detail":"{read_only:false; response_revision:1128; number_of_response:1; }","duration":"211.747152ms","start":"2026-02-25T10:08:42.098439Z","end":"2026-02-25T10:08:42.310186Z","steps":["trace[178472798] 'process raft request' (duration: 211.395705ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:08:42.310373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.203099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T10:08:42.310486Z","caller":"traceutil/trace.go:171","msg":"trace[1015146999] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1128; }","duration":"146.394995ms","start":"2026-02-25T10:08:42.164069Z","end":"2026-02-25T10:08:42.310464Z","steps":["trace[1015146999] 'agreement among raft nodes before linearized reading' (duration: 146.17466ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:08:48.718981Z","caller":"traceutil/trace.go:171","msg":"trace[595452559] transaction","detail":"{read_only:false; response_revision:1255; number_of_response:1; }","duration":"274.19575ms","start":"2026-02-25T10:08:48.444763Z","end":"2026-02-25T10:08:48.718959Z","steps":["trace[595452559] 'process raft request' (duration: 274.073757ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:09:19.93236Z","caller":"traceutil/trace.go:171","msg":"trace[1867705697] linearizableReadLoop","detail":"{readStateIndex:1653; appliedIndex:1652; }","duration":"246.456367ms","start":"2026-02-25T10:09:19.685875Z","end":"2026-02-25T10:09:19.932332Z","steps":["trace[1867705697] 'read index received' (duration: 246.221032ms)","trace[1867705697] 'applied index is now lower than readState.Index' (duration: 233.955µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:09:19.932521Z","caller":"traceutil/trace.go:171","msg":"trace[876208239] transaction","detail":"{read_only:false; response_revision:1587; number_of_response:1; }","duration":"246.942448ms","start":"2026-02-25T10:09:19.685533Z","end":"2026-02-25T10:09:19.932475Z","steps":["trace[876208239] 'process raft request' (duration: 246.634661ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:09:19.932685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.788684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-25T10:09:19.932773Z","caller":"traceutil/trace.go:171","msg":"trace[1121129143] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1587; }","duration":"246.916118ms","start":"2026-02-25T10:09:19.68584Z","end":"2026-02-25T10:09:19.932756Z","steps":["trace[1121129143] 'agreement among raft nodes before linearized reading' (duration: 246.697692ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:09:21.098116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"877.103177ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T10:09:21.098295Z","caller":"traceutil/trace.go:171","msg":"trace[2084043755] linearizableReadLoop","detail":"{readStateIndex:1654; appliedIndex:1653; }","duration":"586.545639ms","start":"2026-02-25T10:09:20.51173Z","end":"2026-02-25T10:09:21.098275Z","steps":["trace[2084043755] 'read index received' (duration: 50.202µs)","trace[2084043755] 'applied index is now lower than readState.Index' (duration: 586.493747ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:09:21.098405Z","caller":"traceutil/trace.go:171","msg":"trace[2138806328] transaction","detail":"{read_only:false; response_revision:1588; number_of_response:1; }","duration":"1.161007765s","start":"2026-02-25T10:09:19.937383Z","end":"2026-02-25T10:09:21.098391Z","steps":["trace[2138806328] 'process raft request' (duration: 283.484598ms)","trace[2138806328] 'compare' (duration: 876.862852ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:09:21.098489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:09:19.937361Z","time spent":"1.161084167s","remote":"127.0.0.1:54734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T10:09:21.098629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"586.895106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:3875"} {"level":"warn","ts":"2026-02-25T10:09:21.098496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.130939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T10:09:21.098684Z","caller":"traceutil/trace.go:171","msg":"trace[1701150217] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1588; }","duration":"586.996878ms","start":"2026-02-25T10:09:20.51167Z","end":"2026-02-25T10:09:21.098667Z","steps":["trace[1701150217] 'agreement among raft nodes before linearized reading' (duration: 586.856275ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:09:21.098735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:09:20.511643Z","time spent":"587.084079ms","remote":"127.0.0.1:55422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":3898,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-02-25T10:09:21.098721Z","caller":"traceutil/trace.go:171","msg":"trace[554297124] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1588; }","duration":"110.386784ms","start":"2026-02-25T10:09:20.988305Z","end":"2026-02-25T10:09:21.098691Z","steps":["trace[554297124] 'agreement among raft nodes before linearized reading' (duration: 110.131339ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:09:21.741847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.963725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-25T10:09:21.741972Z","caller":"traceutil/trace.go:171","msg":"trace[585912585] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1588; }","duration":"374.106718ms","start":"2026-02-25T10:09:21.367845Z","end":"2026-02-25T10:09:21.741951Z","steps":["trace[585912585] 'range keys from in-memory index tree' (duration: 373.732771ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:09:21.742074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:09:21.367838Z","time spent":"374.22453ms","remote":"127.0.0.1:54734","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-02-25T10:09:21.742222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.429367ms","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-02-25T10:09:21.742317Z","caller":"traceutil/trace.go:171","msg":"trace[279928672] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1588; }","duration":"375.549649ms","start":"2026-02-25T10:09:21.366747Z","end":"2026-02-25T10:09:21.742297Z","steps":["trace[279928672] 'range keys from in-memory index tree' (duration: 375.249843ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:09:21.742375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:09:21.366728Z","time spent":"375.632181ms","remote":"127.0.0.1:54734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T10:09:21.742466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.811484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-25T10:09:21.742513Z","caller":"traceutil/trace.go:171","msg":"trace[1400493524] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1588; }","duration":"374.876256ms","start":"2026-02-25T10:09:21.367628Z","end":"2026-02-25T10:09:21.742504Z","steps":["trace[1400493524] 'range keys from in-memory index tree' (duration: 374.69774ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:09:21.74255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:09:21.36762Z","time spent":"374.923357ms","remote":"127.0.0.1:54734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-25T10:09:24.00784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.91193ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T10:09:24.00821Z","caller":"traceutil/trace.go:171","msg":"trace[908302113] transaction","detail":"{read_only:false; response_revision:1596; number_of_response:1; }","duration":"222.562194ms","start":"2026-02-25T10:09:23.785621Z","end":"2026-02-25T10:09:24.008183Z","steps":["trace[908302113] 'process raft request' (duration: 121.227825ms)","trace[908302113] 'compare' (duration: 100.727237ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:09:24.008324Z","caller":"traceutil/trace.go:171","msg":"trace[477030246] transaction","detail":"{read_only:false; response_revision:1597; number_of_response:1; }","duration":"222.039323ms","start":"2026-02-25T10:09:23.786256Z","end":"2026-02-25T10:09:24.008296Z","steps":["trace[477030246] 'process raft request' (duration: 221.685725ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:17:04.139636Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2155} {"level":"info","ts":"2026-02-25T10:17:04.245058Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2155,"took":"104.306325ms","hash":2709890160,"current-db-size-bytes":14540800,"current-db-size":"14 MB","current-db-size-in-use-bytes":4366336,"current-db-size-in-use":"4.4 MB"} {"level":"info","ts":"2026-02-25T10:17:04.245133Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2709890160,"revision":2155,"compact-revision":-1} {"level":"info","ts":"2026-02-25T10:22:04.145859Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3085} {"level":"info","ts":"2026-02-25T10:22:04.165251Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3085,"took":"18.645156ms","hash":2599420813,"current-db-size-bytes":14540800,"current-db-size":"14 MB","current-db-size-in-use-bytes":4734976,"current-db-size-in-use":"4.7 MB"} {"level":"info","ts":"2026-02-25T10:22:04.165318Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2599420813,"revision":3085,"compact-revision":2155}