{"level":"warn","ts":"2026-03-13T17:12:09.405023Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-13T17:12:09.405111Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.78:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.78:2380","--initial-cluster=instance=https://199.204.45.78:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.78:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.78: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-03-13T17:12:09.405179Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-13T17:12:09.405191Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.78:2380"]} {"level":"info","ts":"2026-03-13T17:12:09.405207Z","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-03-13T17:12:09.405838Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.78:2379"]} {"level":"info","ts":"2026-03-13T17:12:09.405914Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.78:2380"],"listen-peer-urls":["https://199.204.45.78:2380"],"advertise-client-urls":["https://199.204.45.78:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.78:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.78: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-03-13T17:12:09.410449Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.329316ms"} {"level":"info","ts":"2026-03-13T17:12:09.415731Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"6eb8766b1ec86e92","cluster-id":"9bbfe9e5d45d3718"} {"level":"info","ts":"2026-03-13T17:12:09.415824Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 switched to configuration voters=()"} {"level":"info","ts":"2026-03-13T17:12:09.415855Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 became follower at term 0"} {"level":"info","ts":"2026-03-13T17:12:09.415878Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 6eb8766b1ec86e92 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-13T17:12:09.415892Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 became follower at term 1"} {"level":"info","ts":"2026-03-13T17:12:09.415942Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 switched to configuration voters=(7978256942336863890)"} {"level":"warn","ts":"2026-03-13T17:12:09.423226Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-13T17:12:09.424502Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-13T17:12:09.425459Z","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-03-13T17:12:09.42777Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"6eb8766b1ec86e92","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-13T17:12:09.428133Z","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-03-13T17:12:09.428292Z","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-03-13T17:12:09.428307Z","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-03-13T17:12:09.42847Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"6eb8766b1ec86e92","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-13T17:12:09.429172Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 switched to configuration voters=(7978256942336863890)"} {"level":"info","ts":"2026-03-13T17:12:09.429378Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9bbfe9e5d45d3718","local-member-id":"6eb8766b1ec86e92","added-peer-id":"6eb8766b1ec86e92","added-peer-peer-urls":["https://199.204.45.78:2380"]} {"level":"info","ts":"2026-03-13T17:12:09.435516Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.78:2380"} {"level":"info","ts":"2026-03-13T17:12:09.435553Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.78:2380"} {"level":"info","ts":"2026-03-13T17:12:09.436658Z","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-03-13T17:12:09.437285Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"6eb8766b1ec86e92","initial-advertise-peer-urls":["https://199.204.45.78:2380"],"listen-peer-urls":["https://199.204.45.78:2380"],"advertise-client-urls":["https://199.204.45.78:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.78:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-13T17:12:09.437351Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-13T17:12:10.217032Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 is starting a new election at term 1"} {"level":"info","ts":"2026-03-13T17:12:10.217106Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-13T17:12:10.217197Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 received MsgPreVoteResp from 6eb8766b1ec86e92 at term 1"} {"level":"info","ts":"2026-03-13T17:12:10.21722Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 became candidate at term 2"} {"level":"info","ts":"2026-03-13T17:12:10.217243Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 received MsgVoteResp from 6eb8766b1ec86e92 at term 2"} {"level":"info","ts":"2026-03-13T17:12:10.217261Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 became leader at term 2"} {"level":"info","ts":"2026-03-13T17:12:10.217284Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 6eb8766b1ec86e92 elected leader 6eb8766b1ec86e92 at term 2"} {"level":"info","ts":"2026-03-13T17:12:10.218519Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-13T17:12:10.219652Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"6eb8766b1ec86e92","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.78:2379]}","request-path":"/0/members/6eb8766b1ec86e92/attributes","cluster-id":"9bbfe9e5d45d3718","publish-timeout":"7s"} {"level":"info","ts":"2026-03-13T17:12:10.219701Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-13T17:12:10.219667Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-13T17:12:10.219902Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-13T17:12:10.219924Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-13T17:12:10.220325Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9bbfe9e5d45d3718","local-member-id":"6eb8766b1ec86e92","cluster-version":"3.5"} {"level":"info","ts":"2026-03-13T17:12:10.220515Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-13T17:12:10.220558Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-13T17:12:10.221401Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.78:2379"} {"level":"info","ts":"2026-03-13T17:12:10.223456Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-13T17:12:13.966501Z","caller":"traceutil/trace.go:171","msg":"trace[2113288836] linearizableReadLoop","detail":"{readStateIndex:243; appliedIndex:242; }","duration":"347.389025ms","start":"2026-03-13T17:12:13.619086Z","end":"2026-03-13T17:12:13.966475Z","steps":["trace[2113288836] 'read index received' (duration: 347.142668ms)","trace[2113288836] 'applied index is now lower than readState.Index' (duration: 245.137µs)"],"step_count":2} {"level":"warn","ts":"2026-03-13T17:12:13.966679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.57057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T17:12:13.966743Z","caller":"traceutil/trace.go:171","msg":"trace[40129075] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:237; }","duration":"347.682693ms","start":"2026-03-13T17:12:13.619048Z","end":"2026-03-13T17:12:13.966731Z","steps":["trace[40129075] 'agreement among raft nodes before linearized reading' (duration: 347.540829ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:12:13.966776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:12:13.618992Z","time spent":"347.777155ms","remote":"127.0.0.1:40110","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-03-13T17:12:13.966782Z","caller":"traceutil/trace.go:171","msg":"trace[18126374] transaction","detail":"{read_only:false; response_revision:237; number_of_response:1; }","duration":"426.072486ms","start":"2026-03-13T17:12:13.540664Z","end":"2026-03-13T17:12:13.966737Z","steps":["trace[18126374] 'process raft request' (duration: 425.660565ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:12:13.967343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:12:13.540616Z","time spent":"426.252942ms","remote":"127.0.0.1:40274","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3959,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-13T17:12:14.909238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.665307ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-13T17:12:14.909345Z","caller":"traceutil/trace.go:171","msg":"trace[892868104] transaction","detail":"{read_only:false; response_revision:238; number_of_response:1; }","duration":"936.960292ms","start":"2026-03-13T17:12:13.972367Z","end":"2026-03-13T17:12:14.909328Z","steps":["trace[892868104] 'process raft request' (duration: 377.980876ms)","trace[892868104] 'compare' (duration: 558.518593ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T17:12:14.90945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:12:13.972332Z","time spent":"937.052664ms","remote":"127.0.0.1:40200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1068,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-13T17:12:14.96814Z","caller":"traceutil/trace.go:171","msg":"trace[918093717] transaction","detail":"{read_only:false; response_revision:239; number_of_response:1; }","duration":"512.450886ms","start":"2026-03-13T17:12:14.455666Z","end":"2026-03-13T17:12:14.968117Z","steps":["trace[918093717] 'process raft request' (duration: 512.347163ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:12:14.968272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:12:14.455645Z","time spent":"512.565219ms","remote":"127.0.0.1:40274","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5498,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-13T17:12:42.303118Z","caller":"traceutil/trace.go:171","msg":"trace[155040394] linearizableReadLoop","detail":"{readStateIndex:553; appliedIndex:552; }","duration":"451.26364ms","start":"2026-03-13T17:12:41.851827Z","end":"2026-03-13T17:12:42.303091Z","steps":["trace[155040394] 'read index received' (duration: 451.054635ms)","trace[155040394] 'applied index is now lower than readState.Index' (duration: 208.135µs)"],"step_count":2} {"level":"info","ts":"2026-03-13T17:12:42.303186Z","caller":"traceutil/trace.go:171","msg":"trace[305986213] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"598.042356ms","start":"2026-03-13T17:12:41.705113Z","end":"2026-03-13T17:12:42.303155Z","steps":["trace[305986213] 'process raft request' (duration: 597.842581ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:12:42.303313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.465195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-13T17:12:42.303319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:12:41.705081Z","time spent":"598.171259ms","remote":"127.0.0.1:40172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12244,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-13T17:12:42.303358Z","caller":"traceutil/trace.go:171","msg":"trace[2028320523] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:533; }","duration":"451.585258ms","start":"2026-03-13T17:12:41.851761Z","end":"2026-03-13T17:12:42.303346Z","steps":["trace[2028320523] 'agreement among raft nodes before linearized reading' (duration: 451.457515ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:12:42.303397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:12:41.851721Z","time spent":"451.66741ms","remote":"127.0.0.1:40120","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-03-13T17:12:43.067287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.430842ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-13T17:12:43.067554Z","caller":"traceutil/trace.go:171","msg":"trace[401719342] linearizableReadLoop","detail":"{readStateIndex:556; appliedIndex:553; }","duration":"764.340859ms","start":"2026-03-13T17:12:42.303197Z","end":"2026-03-13T17:12:43.067538Z","steps":["trace[401719342] 'read index received' (duration: 477.612849ms)","trace[401719342] 'applied index is now lower than readState.Index' (duration: 286.7269ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T17:12:43.067628Z","caller":"traceutil/trace.go:171","msg":"trace[1925287237] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"1.164342166s","start":"2026-03-13T17:12:41.903275Z","end":"2026-03-13T17:12:43.067617Z","steps":["trace[1925287237] 'process raft request' (duration: 877.517014ms)","trace[1925287237] 'compare' (duration: 286.246297ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T17:12:43.067677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:12:41.903244Z","time spent":"1.164408097s","remote":"127.0.0.1:40172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3104,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-13T17:12:43.067749Z","caller":"traceutil/trace.go:171","msg":"trace[1396355386] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"963.413745ms","start":"2026-03-13T17:12:42.104311Z","end":"2026-03-13T17:12:43.067725Z","steps":["trace[1396355386] 'process raft request' (duration: 963.190119ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:12:43.067848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:12:42.104278Z","time spent":"963.517306ms","remote":"127.0.0.1:40172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8270,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-13T17:12:43.067967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.184742276s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-03-13T17:12:43.067975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.196215974s","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-03-13T17:12:43.068003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"986.917243ms","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-03-13T17:12:43.068024Z","caller":"traceutil/trace.go:171","msg":"trace[2131310912] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:536; }","duration":"1.184850659s","start":"2026-03-13T17:12:41.883161Z","end":"2026-03-13T17:12:43.068012Z","steps":["trace[2131310912] 'agreement among raft nodes before linearized reading' (duration: 1.184611243s)"],"step_count":1} {"level":"info","ts":"2026-03-13T17:12:43.068034Z","caller":"traceutil/trace.go:171","msg":"trace[976091459] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:536; }","duration":"1.196280366s","start":"2026-03-13T17:12:41.871738Z","end":"2026-03-13T17:12:43.068019Z","steps":["trace[976091459] 'agreement among raft nodes before linearized reading' (duration: 1.19605405s)"],"step_count":1} {"level":"info","ts":"2026-03-13T17:12:43.068042Z","caller":"traceutil/trace.go:171","msg":"trace[1607673782] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:536; }","duration":"986.991835ms","start":"2026-03-13T17:12:42.081039Z","end":"2026-03-13T17:12:43.068031Z","steps":["trace[1607673782] 'agreement among raft nodes before linearized reading' (duration: 986.892093ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:12:43.068056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:12:41.883141Z","time spent":"1.184908181s","remote":"127.0.0.1:40352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-13T17:12:43.068071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:12:42.081021Z","time spent":"987.046026ms","remote":"127.0.0.1:40352","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-03-13T17:12:43.068072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:12:41.871635Z","time spent":"1.196429449s","remote":"127.0.0.1:40352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-13T17:12:43.068297Z","caller":"traceutil/trace.go:171","msg":"trace[847207099] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"1.078033514s","start":"2026-03-13T17:12:41.98972Z","end":"2026-03-13T17:12:43.067754Z","steps":["trace[847207099] 'process raft request' (duration: 1.077693756s)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:12:43.068519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:12:41.989695Z","time spent":"1.07865209s","remote":"127.0.0.1:40352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T17:12:43.074691Z","caller":"traceutil/trace.go:171","msg":"trace[543462319] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"771.15689ms","start":"2026-03-13T17:12:42.30352Z","end":"2026-03-13T17:12:43.074677Z","steps":["trace[543462319] 'process raft request' (duration: 770.981665ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T17:12:43.074714Z","caller":"traceutil/trace.go:171","msg":"trace[98923577] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"761.387365ms","start":"2026-03-13T17:12:42.313311Z","end":"2026-03-13T17:12:43.074698Z","steps":["trace[98923577] 'process raft request' (duration: 761.299293ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:12:43.074761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:12:42.303497Z","time spent":"771.228312ms","remote":"127.0.0.1:40172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4234,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-13T17:12:43.074786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.650271ms","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-03-13T17:12:43.074802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:12:42.313301Z","time spent":"761.453447ms","remote":"127.0.0.1:40172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13205,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T17:12:43.074831Z","caller":"traceutil/trace.go:171","msg":"trace[374231571] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:539; }","duration":"372.752703ms","start":"2026-03-13T17:12:42.702069Z","end":"2026-03-13T17:12:43.074822Z","steps":["trace[374231571] 'agreement among raft nodes before linearized reading' (duration: 372.670571ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:12:43.074851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:12:42.702047Z","time spent":"372.799995ms","remote":"127.0.0.1:40172","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-03-13T17:12:43.074853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.02645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-13T17:12:43.074844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.296234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T17:12:43.074875Z","caller":"traceutil/trace.go:171","msg":"trace[2042362220] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:539; }","duration":"224.081471ms","start":"2026-03-13T17:12:42.850787Z","end":"2026-03-13T17:12:43.074868Z","steps":["trace[2042362220] 'agreement among raft nodes before linearized reading' (duration: 224.04432ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T17:12:43.074907Z","caller":"traceutil/trace.go:171","msg":"trace[533594651] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"571.407898ms","start":"2026-03-13T17:12:42.503483Z","end":"2026-03-13T17:12:43.074891Z","steps":["trace[533594651] 'process raft request' (duration: 571.188952ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T17:12:43.074899Z","caller":"traceutil/trace.go:171","msg":"trace[725458199] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:539; }","duration":"172.395347ms","start":"2026-03-13T17:12:42.902487Z","end":"2026-03-13T17:12:43.074883Z","steps":["trace[725458199] 'agreement among raft nodes before linearized reading' (duration: 172.302894ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:12:43.074962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:12:42.503452Z","time spent":"571.48734ms","remote":"127.0.0.1:40172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3353,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-13T17:12:43.075075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.56897ms","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-03-13T17:12:43.075122Z","caller":"traceutil/trace.go:171","msg":"trace[1848823884] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:539; }","duration":"168.621452ms","start":"2026-03-13T17:12:42.906476Z","end":"2026-03-13T17:12:43.075097Z","steps":["trace[1848823884] 'agreement among raft nodes before linearized reading' (duration: 168.285364ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T17:12:49.222998Z","caller":"traceutil/trace.go:171","msg":"trace[1707667415] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"121.439451ms","start":"2026-03-13T17:12:49.101532Z","end":"2026-03-13T17:12:49.222971Z","steps":["trace[1707667415] 'process raft request' (duration: 104.837745ms)","trace[1707667415] 'compare' (duration: 16.37461ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T17:12:49.223284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.772294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-03-13T17:12:49.223348Z","caller":"traceutil/trace.go:171","msg":"trace[1194093307] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:579; }","duration":"120.858376ms","start":"2026-03-13T17:12:49.102475Z","end":"2026-03-13T17:12:49.223334Z","steps":["trace[1194093307] 'agreement among raft nodes before linearized reading' (duration: 120.660361ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:13:09.163511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.607088ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-13T17:13:09.163619Z","caller":"traceutil/trace.go:171","msg":"trace[1870912052] linearizableReadLoop","detail":"{readStateIndex:720; appliedIndex:719; }","duration":"434.90347ms","start":"2026-03-13T17:13:08.728697Z","end":"2026-03-13T17:13:09.1636Z","steps":["trace[1870912052] 'read index received' (duration: 52.501µs)","trace[1870912052] 'applied index is now lower than readState.Index' (duration: 434.849249ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T17:13:09.163826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.574277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T17:13:09.163866Z","caller":"traceutil/trace.go:171","msg":"trace[1127797545] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:693; }","duration":"311.410458ms","start":"2026-03-13T17:13:08.852444Z","end":"2026-03-13T17:13:09.163855Z","steps":["trace[1127797545] 'agreement among raft nodes before linearized reading' (duration: 311.327866ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:13:09.163873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.154546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-v4q6m\" ","response":"range_response_count:1 size:4740"} {"level":"warn","ts":"2026-03-13T17:13:09.163918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:13:08.852419Z","time spent":"311.480759ms","remote":"127.0.0.1:40110","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-03-13T17:13:09.163944Z","caller":"traceutil/trace.go:171","msg":"trace[1326687234] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-v4q6m; range_end:; response_count:1; response_revision:693; }","duration":"435.28633ms","start":"2026-03-13T17:13:08.728639Z","end":"2026-03-13T17:13:09.163925Z","steps":["trace[1326687234] 'agreement among raft nodes before linearized reading' (duration: 435.048194ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:13:09.163976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:13:08.728619Z","time spent":"435.350911ms","remote":"127.0.0.1:40274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":4763,"request content":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-v4q6m\" "} {"level":"info","ts":"2026-03-13T17:13:17.497065Z","caller":"traceutil/trace.go:171","msg":"trace[1684690440] linearizableReadLoop","detail":"{readStateIndex:772; appliedIndex:771; }","duration":"214.84367ms","start":"2026-03-13T17:13:17.282199Z","end":"2026-03-13T17:13:17.497043Z","steps":["trace[1684690440] 'read index received' (duration: 214.748058ms)","trace[1684690440] 'applied index is now lower than readState.Index' (duration: 93.972µs)"],"step_count":2} {"level":"warn","ts":"2026-03-13T17:13:17.497251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.035845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-13T17:13:17.497308Z","caller":"traceutil/trace.go:171","msg":"trace[828675468] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:743; }","duration":"215.126847ms","start":"2026-03-13T17:13:17.282167Z","end":"2026-03-13T17:13:17.497294Z","steps":["trace[828675468] 'agreement among raft nodes before linearized reading' (duration: 214.971794ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T17:13:17.588987Z","caller":"traceutil/trace.go:171","msg":"trace[1109924922] transaction","detail":"{read_only:false; response_revision:744; number_of_response:1; }","duration":"130.918978ms","start":"2026-03-13T17:13:17.45805Z","end":"2026-03-13T17:13:17.588969Z","steps":["trace[1109924922] 'process raft request' (duration: 129.271067ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:13:17.589431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.43637ms","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-03-13T17:13:17.589535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.339897ms","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-03-13T17:13:17.58957Z","caller":"traceutil/trace.go:171","msg":"trace[1234421109] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:745; }","duration":"232.450391ms","start":"2026-03-13T17:13:17.357111Z","end":"2026-03-13T17:13:17.589562Z","steps":["trace[1234421109] 'agreement among raft nodes before linearized reading' (duration: 232.257776ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T17:13:17.589549Z","caller":"traceutil/trace.go:171","msg":"trace[1142825181] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:745; }","duration":"230.537953ms","start":"2026-03-13T17:13:17.358952Z","end":"2026-03-13T17:13:17.58949Z","steps":["trace[1142825181] 'agreement among raft nodes before linearized reading' (duration: 230.342878ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T17:13:17.589169Z","caller":"traceutil/trace.go:171","msg":"trace[366039975] transaction","detail":"{read_only:false; response_revision:745; number_of_response:1; }","duration":"104.742253ms","start":"2026-03-13T17:13:17.48442Z","end":"2026-03-13T17:13:17.589162Z","steps":["trace[366039975] 'process raft request' (duration: 104.507167ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:13:17.5894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.652936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-13T17:13:17.589816Z","caller":"traceutil/trace.go:171","msg":"trace[1780267445] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:745; }","duration":"237.086017ms","start":"2026-03-13T17:13:17.352684Z","end":"2026-03-13T17:13:17.58977Z","steps":["trace[1780267445] 'agreement among raft nodes before linearized reading' (duration: 236.508292ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T17:13:39.962754Z","caller":"traceutil/trace.go:171","msg":"trace[795724779] transaction","detail":"{read_only:false; response_revision:815; number_of_response:1; }","duration":"169.643234ms","start":"2026-03-13T17:13:39.793064Z","end":"2026-03-13T17:13:39.962707Z","steps":["trace[795724779] 'process raft request' (duration: 165.800701ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T17:13:40.113775Z","caller":"traceutil/trace.go:171","msg":"trace[805115773] transaction","detail":"{read_only:false; response_revision:816; number_of_response:1; }","duration":"319.881997ms","start":"2026-03-13T17:13:39.793851Z","end":"2026-03-13T17:13:40.113733Z","steps":["trace[805115773] 'process raft request' (duration: 290.809411ms)","trace[805115773] 'compare' (duration: 28.81188ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T17:13:40.11388Z","caller":"traceutil/trace.go:171","msg":"trace[1787112506] transaction","detail":"{read_only:false; response_revision:817; number_of_response:1; }","duration":"319.001596ms","start":"2026-03-13T17:13:39.794857Z","end":"2026-03-13T17:13:40.113859Z","steps":["trace[1787112506] 'process raft request' (duration: 318.831562ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:13:40.114004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.642531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T17:13:40.113883Z","caller":"traceutil/trace.go:171","msg":"trace[1481284278] linearizableReadLoop","detail":"{readStateIndex:852; appliedIndex:849; }","duration":"261.496568ms","start":"2026-03-13T17:13:39.852351Z","end":"2026-03-13T17:13:40.113848Z","steps":["trace[1481284278] 'read index received' (duration: 106.5245ms)","trace[1481284278] 'applied index is now lower than readState.Index' (duration: 154.970718ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T17:13:40.114028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:13:39.793829Z","time spent":"320.071102ms","remote":"127.0.0.1:40352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T17:13:40.114036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:13:39.794845Z","time spent":"319.101598ms","remote":"127.0.0.1:40352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T17:13:40.114046Z","caller":"traceutil/trace.go:171","msg":"trace[27314277] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:817; }","duration":"261.751054ms","start":"2026-03-13T17:13:39.852285Z","end":"2026-03-13T17:13:40.114036Z","steps":["trace[27314277] 'agreement among raft nodes before linearized reading' (duration: 261.648631ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T17:13:44.333703Z","caller":"traceutil/trace.go:171","msg":"trace[676082815] linearizableReadLoop","detail":"{readStateIndex:860; appliedIndex:859; }","duration":"491.609003ms","start":"2026-03-13T17:13:43.84207Z","end":"2026-03-13T17:13:44.333679Z","steps":["trace[676082815] 'read index received' (duration: 491.376447ms)","trace[676082815] 'applied index is now lower than readState.Index' (duration: 231.586µs)"],"step_count":2} {"level":"info","ts":"2026-03-13T17:13:44.333782Z","caller":"traceutil/trace.go:171","msg":"trace[526326358] transaction","detail":"{read_only:false; response_revision:824; number_of_response:1; }","duration":"500.300124ms","start":"2026-03-13T17:13:43.833464Z","end":"2026-03-13T17:13:44.333764Z","steps":["trace[526326358] 'process raft request' (duration: 500.074709ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:13:44.333881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:13:43.833439Z","time spent":"500.385576ms","remote":"127.0.0.1:40352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T17:13:44.333943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.853609ms","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-03-13T17:13:44.334012Z","caller":"traceutil/trace.go:171","msg":"trace[1523775998] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:824; }","duration":"491.947061ms","start":"2026-03-13T17:13:43.84202Z","end":"2026-03-13T17:13:44.333967Z","steps":["trace[1523775998] 'agreement among raft nodes before linearized reading' (duration: 491.788577ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:13:44.334066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:13:43.842003Z","time spent":"492.036954ms","remote":"127.0.0.1:40352","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-03-13T17:13:44.334111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.491291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-13T17:13:44.334197Z","caller":"traceutil/trace.go:171","msg":"trace[340421617] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:824; }","duration":"359.607343ms","start":"2026-03-13T17:13:43.97457Z","end":"2026-03-13T17:13:44.334177Z","steps":["trace[340421617] 'agreement among raft nodes before linearized reading' (duration: 359.406708ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:13:44.334085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.501021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-03-13T17:13:44.334215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.485777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T17:13:44.334267Z","caller":"traceutil/trace.go:171","msg":"trace[375962870] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:824; }","duration":"206.727336ms","start":"2026-03-13T17:13:44.12753Z","end":"2026-03-13T17:13:44.334257Z","steps":["trace[375962870] 'agreement among raft nodes before linearized reading' (duration: 206.388908ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:13:44.33425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:13:43.974536Z","time spent":"359.704396ms","remote":"127.0.0.1:40352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-13T17:13:44.334337Z","caller":"traceutil/trace.go:171","msg":"trace[59791524] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:824; }","duration":"481.579329ms","start":"2026-03-13T17:13:43.852704Z","end":"2026-03-13T17:13:44.334283Z","steps":["trace[59791524] 'agreement among raft nodes before linearized reading' (duration: 481.179559ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:13:44.334411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:13:43.852696Z","time spent":"481.703953ms","remote":"127.0.0.1:40110","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-03-13T17:13:44.335229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.771366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-03-13T17:13:44.335289Z","caller":"traceutil/trace.go:171","msg":"trace[1135633287] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:824; }","duration":"208.869329ms","start":"2026-03-13T17:13:44.126405Z","end":"2026-03-13T17:13:44.335274Z","steps":["trace[1135633287] 'agreement among raft nodes before linearized reading' (duration: 207.502136ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T17:13:46.538294Z","caller":"traceutil/trace.go:171","msg":"trace[1686610634] linearizableReadLoop","detail":"{readStateIndex:866; appliedIndex:865; }","duration":"149.29995ms","start":"2026-03-13T17:13:46.388967Z","end":"2026-03-13T17:13:46.538267Z","steps":["trace[1686610634] 'read index received' (duration: 148.922381ms)","trace[1686610634] 'applied index is now lower than readState.Index' (duration: 376.619µs)"],"step_count":2} {"level":"warn","ts":"2026-03-13T17:13:46.538533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.558827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-03-13T17:13:46.538581Z","caller":"traceutil/trace.go:171","msg":"trace[601339103] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:830; }","duration":"149.657369ms","start":"2026-03-13T17:13:46.388914Z","end":"2026-03-13T17:13:46.538571Z","steps":["trace[601339103] 'agreement among raft nodes before linearized reading' (duration: 149.449614ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T17:13:46.538333Z","caller":"traceutil/trace.go:171","msg":"trace[573292751] transaction","detail":"{read_only:false; response_revision:830; number_of_response:1; }","duration":"195.455962ms","start":"2026-03-13T17:13:46.342853Z","end":"2026-03-13T17:13:46.538309Z","steps":["trace[573292751] 'process raft request' (duration: 195.143074ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:13:46.538815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.866789ms","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-03-13T17:13:46.538809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.798308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-13T17:13:46.539053Z","caller":"traceutil/trace.go:171","msg":"trace[1083777871] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:830; }","duration":"149.056465ms","start":"2026-03-13T17:13:46.389979Z","end":"2026-03-13T17:13:46.539036Z","steps":["trace[1083777871] 'agreement among raft nodes before linearized reading' (duration: 148.598743ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T17:13:46.538912Z","caller":"traceutil/trace.go:171","msg":"trace[1714077991] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:830; }","duration":"149.015003ms","start":"2026-03-13T17:13:46.38987Z","end":"2026-03-13T17:13:46.538885Z","steps":["trace[1714077991] 'agreement among raft nodes before linearized reading' (duration: 148.731906ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:13:46.539112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.28671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-03-13T17:13:46.539162Z","caller":"traceutil/trace.go:171","msg":"trace[1688928700] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:830; }","duration":"149.357521ms","start":"2026-03-13T17:13:46.389791Z","end":"2026-03-13T17:13:46.539149Z","steps":["trace[1688928700] 'agreement among raft nodes before linearized reading' (duration: 148.743346ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:13:53.585488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:13:53.195995Z","time spent":"389.4892ms","remote":"127.0.0.1:40144","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-03-13T17:13:54.094347Z","caller":"traceutil/trace.go:171","msg":"trace[898626795] linearizableReadLoop","detail":"{readStateIndex:942; appliedIndex:941; }","duration":"242.208929ms","start":"2026-03-13T17:13:53.85212Z","end":"2026-03-13T17:13:54.094329Z","steps":["trace[898626795] 'read index received' (duration: 233.947238ms)","trace[898626795] 'applied index is now lower than readState.Index' (duration: 8.260681ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T17:13:54.094397Z","caller":"traceutil/trace.go:171","msg":"trace[1709338484] transaction","detail":"{read_only:false; response_revision:903; number_of_response:1; }","duration":"507.801116ms","start":"2026-03-13T17:13:53.586572Z","end":"2026-03-13T17:13:54.094373Z","steps":["trace[1709338484] 'process raft request' (duration: 499.552836ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:13:54.094446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.318702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T17:13:54.094471Z","caller":"traceutil/trace.go:171","msg":"trace[909958767] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:903; }","duration":"242.354582ms","start":"2026-03-13T17:13:53.852109Z","end":"2026-03-13T17:13:54.094463Z","steps":["trace[909958767] 'agreement among raft nodes before linearized reading' (duration: 242.285911ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:13:54.094506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:13:53.586546Z","time spent":"507.901629ms","remote":"127.0.0.1:40144","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T17:14:06.819417Z","caller":"traceutil/trace.go:171","msg":"trace[1397142867] transaction","detail":"{read_only:false; response_revision:938; number_of_response:1; }","duration":"188.38967ms","start":"2026-03-13T17:14:06.630993Z","end":"2026-03-13T17:14:06.819383Z","steps":["trace[1397142867] 'process raft request' (duration: 188.228537ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T17:14:06.822257Z","caller":"traceutil/trace.go:171","msg":"trace[190477518] linearizableReadLoop","detail":"{readStateIndex:980; appliedIndex:978; }","duration":"174.679608ms","start":"2026-03-13T17:14:06.647554Z","end":"2026-03-13T17:14:06.822234Z","steps":["trace[190477518] 'read index received' (duration: 171.682305ms)","trace[190477518] 'applied index is now lower than readState.Index' (duration: 2.996253ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T17:14:06.822538Z","caller":"traceutil/trace.go:171","msg":"trace[1243553720] transaction","detail":"{read_only:false; response_revision:939; number_of_response:1; }","duration":"189.373175ms","start":"2026-03-13T17:14:06.633153Z","end":"2026-03-13T17:14:06.822526Z","steps":["trace[1243553720] 'process raft request' (duration: 188.977415ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:14:06.822798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.223661ms","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-03-13T17:14:06.822839Z","caller":"traceutil/trace.go:171","msg":"trace[632173061] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:939; }","duration":"175.304692ms","start":"2026-03-13T17:14:06.647522Z","end":"2026-03-13T17:14:06.822827Z","steps":["trace[632173061] 'agreement among raft nodes before linearized reading' (duration: 175.171479ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:14:06.823033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.756939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-13T17:14:06.823068Z","caller":"traceutil/trace.go:171","msg":"trace[1166178650] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:939; }","duration":"174.829591ms","start":"2026-03-13T17:14:06.648231Z","end":"2026-03-13T17:14:06.823061Z","steps":["trace[1166178650] 'agreement among raft nodes before linearized reading' (duration: 174.741659ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:14:06.823289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.946921ms","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-03-13T17:14:06.823324Z","caller":"traceutil/trace.go:171","msg":"trace[1153324960] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:939; }","duration":"148.992693ms","start":"2026-03-13T17:14:06.674324Z","end":"2026-03-13T17:14:06.823316Z","steps":["trace[1153324960] 'agreement among raft nodes before linearized reading' (duration: 148.905431ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:14:12.018905Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.157253437s","expected-duration":"1s"} {"level":"info","ts":"2026-03-13T17:14:12.023156Z","caller":"traceutil/trace.go:171","msg":"trace[441992147] linearizableReadLoop","detail":"{readStateIndex:1015; appliedIndex:1014; }","duration":"173.14956ms","start":"2026-03-13T17:14:11.849986Z","end":"2026-03-13T17:14:12.023135Z","steps":["trace[441992147] 'read index received' (duration: 169.052ms)","trace[441992147] 'applied index is now lower than readState.Index' (duration: 4.09645ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T17:14:12.023202Z","caller":"traceutil/trace.go:171","msg":"trace[1684705582] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"1.161597243s","start":"2026-03-13T17:14:10.861573Z","end":"2026-03-13T17:14:12.023171Z","steps":["trace[1684705582] 'process raft request' (duration: 1.157548464s)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:14:12.023249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.246022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T17:14:12.023295Z","caller":"traceutil/trace.go:171","msg":"trace[462328480] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:973; }","duration":"173.311093ms","start":"2026-03-13T17:14:11.84996Z","end":"2026-03-13T17:14:12.023271Z","steps":["trace[462328480] 'agreement among raft nodes before linearized reading' (duration: 173.246582ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:14:12.024987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:14:10.861554Z","time spent":"1.161716755s","remote":"127.0.0.1:40352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T17:14:13.32702Z","caller":"traceutil/trace.go:171","msg":"trace[1451756258] linearizableReadLoop","detail":"{readStateIndex:1016; appliedIndex:1015; }","duration":"483.606578ms","start":"2026-03-13T17:14:12.84339Z","end":"2026-03-13T17:14:13.326996Z","steps":["trace[1451756258] 'read index received' (duration: 482.800179ms)","trace[1451756258] 'applied index is now lower than readState.Index' (duration: 805.449µs)"],"step_count":2} {"level":"info","ts":"2026-03-13T17:14:13.327047Z","caller":"traceutil/trace.go:171","msg":"trace[2021159533] transaction","detail":"{read_only:false; response_revision:974; number_of_response:1; }","duration":"483.840124ms","start":"2026-03-13T17:14:12.843178Z","end":"2026-03-13T17:14:13.327018Z","steps":["trace[2021159533] 'process raft request' (duration: 483.042884ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:14:13.327228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:14:12.843157Z","time spent":"483.965107ms","remote":"127.0.0.1:40352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T17:14:13.327316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.163784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-13T17:14:13.327319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.139091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-13T17:14:13.327364Z","caller":"traceutil/trace.go:171","msg":"trace[1633866070] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:974; }","duration":"431.260916ms","start":"2026-03-13T17:14:12.896091Z","end":"2026-03-13T17:14:13.327352Z","steps":["trace[1633866070] 'agreement among raft nodes before linearized reading' (duration: 431.127072ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:14:13.327357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.940956ms","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-03-13T17:14:13.327405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:14:12.896079Z","time spent":"431.317117ms","remote":"127.0.0.1:52268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":2,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"info","ts":"2026-03-13T17:14:13.327442Z","caller":"traceutil/trace.go:171","msg":"trace[1877833447] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:974; }","duration":"484.068169ms","start":"2026-03-13T17:14:12.843355Z","end":"2026-03-13T17:14:13.327423Z","steps":["trace[1877833447] 'agreement among raft nodes before linearized reading' (duration: 483.742542ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:14:13.327496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:14:12.843346Z","time spent":"484.137801ms","remote":"127.0.0.1:40352","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-03-13T17:14:13.327546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.328176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-03-13T17:14:13.327556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.902857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.78\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-03-13T17:14:13.327639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.4928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T17:14:13.327675Z","caller":"traceutil/trace.go:171","msg":"trace[1499325535] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:974; }","duration":"475.534082ms","start":"2026-03-13T17:14:12.85213Z","end":"2026-03-13T17:14:13.327664Z","steps":["trace[1499325535] 'agreement among raft nodes before linearized reading' (duration: 475.483501ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:14:13.327704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:14:12.852124Z","time spent":"475.575233ms","remote":"127.0.0.1:40110","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-03-13T17:14:13.327643Z","caller":"traceutil/trace.go:171","msg":"trace[536408631] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:974; }","duration":"468.44819ms","start":"2026-03-13T17:14:12.859155Z","end":"2026-03-13T17:14:13.327603Z","steps":["trace[536408631] 'agreement among raft nodes before linearized reading' (duration: 468.04556ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:14:13.327787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:14:12.859148Z","time spent":"468.626274ms","remote":"127.0.0.1:40352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-03-13T17:14:13.327636Z","caller":"traceutil/trace.go:171","msg":"trace[1092205964] range","detail":"{range_begin:/registry/masterleases/199.204.45.78; range_end:; response_count:1; response_revision:974; }","duration":"131.997559ms","start":"2026-03-13T17:14:13.195624Z","end":"2026-03-13T17:14:13.327621Z","steps":["trace[1092205964] 'agreement among raft nodes before linearized reading' (duration: 131.796624ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:14:13.327869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.454604ms","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-03-13T17:14:13.327953Z","caller":"traceutil/trace.go:171","msg":"trace[406648737] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:974; }","duration":"476.547117ms","start":"2026-03-13T17:14:12.851392Z","end":"2026-03-13T17:14:13.32794Z","steps":["trace[406648737] 'agreement among raft nodes before linearized reading' (duration: 475.749608ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:14:13.328008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:14:12.851384Z","time spent":"476.616778ms","remote":"127.0.0.1:40352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-13T17:14:13.327379Z","caller":"traceutil/trace.go:171","msg":"trace[1566477394] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:974; }","duration":"148.253125ms","start":"2026-03-13T17:14:13.17911Z","end":"2026-03-13T17:14:13.327363Z","steps":["trace[1566477394] 'agreement among raft nodes before linearized reading' (duration: 148.05518ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T17:14:13.471157Z","caller":"traceutil/trace.go:171","msg":"trace[1421196424] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"139.451231ms","start":"2026-03-13T17:14:13.331686Z","end":"2026-03-13T17:14:13.471138Z","steps":["trace[1421196424] 'process raft request' (duration: 139.284626ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T17:14:13.471487Z","caller":"traceutil/trace.go:171","msg":"trace[1431876304] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"139.938083ms","start":"2026-03-13T17:14:13.331536Z","end":"2026-03-13T17:14:13.471474Z","steps":["trace[1431876304] 'process raft request' (duration: 139.567074ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T17:14:13.471498Z","caller":"traceutil/trace.go:171","msg":"trace[709321052] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"137.74644ms","start":"2026-03-13T17:14:13.333722Z","end":"2026-03-13T17:14:13.471468Z","steps":["trace[709321052] 'process raft request' (duration: 137.609526ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:14:14.827907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"624.075692ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T17:14:14.828046Z","caller":"traceutil/trace.go:171","msg":"trace[815407659] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:979; }","duration":"624.223906ms","start":"2026-03-13T17:14:14.203802Z","end":"2026-03-13T17:14:14.828026Z","steps":["trace[815407659] 'range keys from in-memory index tree' (duration: 624.062502ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T17:14:15.388119Z","caller":"traceutil/trace.go:171","msg":"trace[838697177] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"366.917372ms","start":"2026-03-13T17:14:15.021155Z","end":"2026-03-13T17:14:15.388072Z","steps":["trace[838697177] 'process raft request' (duration: 366.716587ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:14:15.388311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:14:15.021135Z","time spent":"367.072345ms","remote":"127.0.0.1:40352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T17:14:15.669001Z","caller":"traceutil/trace.go:171","msg":"trace[113226019] transaction","detail":"{read_only:false; response_revision:984; number_of_response:1; }","duration":"135.754881ms","start":"2026-03-13T17:14:15.533217Z","end":"2026-03-13T17:14:15.668972Z","steps":["trace[113226019] 'process raft request' (duration: 100.177787ms)","trace[113226019] 'compare' (duration: 35.41853ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T17:14:16.213496Z","caller":"traceutil/trace.go:171","msg":"trace[662260776] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"144.849502ms","start":"2026-03-13T17:14:16.068623Z","end":"2026-03-13T17:14:16.213472Z","steps":["trace[662260776] 'process raft request' (duration: 144.701598ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T17:14:17.679409Z","caller":"traceutil/trace.go:171","msg":"trace[371821537] transaction","detail":"{read_only:false; response_revision:986; number_of_response:1; }","duration":"266.403997ms","start":"2026-03-13T17:14:17.412983Z","end":"2026-03-13T17:14:17.679387Z","steps":["trace[371821537] 'process raft request' (duration: 266.243743ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:14:23.697218Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7967603211809050865,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-13T17:14:24.197997Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7967603211809050865,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-13T17:14:24.464007Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.270642554s","expected-duration":"1s"} {"level":"info","ts":"2026-03-13T17:14:24.464481Z","caller":"traceutil/trace.go:171","msg":"trace[1083004667] linearizableReadLoop","detail":"{readStateIndex:1088; appliedIndex:1087; }","duration":"1.267707683s","start":"2026-03-13T17:14:23.19675Z","end":"2026-03-13T17:14:24.464457Z","steps":["trace[1083004667] 'read index received' (duration: 1.267452296s)","trace[1083004667] 'applied index is now lower than readState.Index' (duration: 254.347µs)"],"step_count":2} {"level":"info","ts":"2026-03-13T17:14:24.464527Z","caller":"traceutil/trace.go:171","msg":"trace[388727138] transaction","detail":"{read_only:false; response_revision:1043; number_of_response:1; }","duration":"1.271245479s","start":"2026-03-13T17:14:23.193252Z","end":"2026-03-13T17:14:24.464497Z","steps":["trace[388727138] 'process raft request' (duration: 1.271028373s)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:14:24.464685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.267917528s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.78\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-03-13T17:14:24.464725Z","caller":"traceutil/trace.go:171","msg":"trace[16230042] range","detail":"{range_begin:/registry/masterleases/199.204.45.78; range_end:; response_count:1; response_revision:1043; }","duration":"1.2679991s","start":"2026-03-13T17:14:23.196715Z","end":"2026-03-13T17:14:24.464714Z","steps":["trace[16230042] 'agreement among raft nodes before linearized reading' (duration: 1.267864317s)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:14:24.464762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:14:23.196702Z","time spent":"1.268052201s","remote":"127.0.0.1:40144","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.78\" "} {"level":"warn","ts":"2026-03-13T17:14:24.464824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:14:23.193219Z","time spent":"1.271429353s","remote":"127.0.0.1:40352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":457,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T17:14:24.46489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"756.029132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-13T17:14:24.465002Z","caller":"traceutil/trace.go:171","msg":"trace[302140278] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1043; }","duration":"756.198016ms","start":"2026-03-13T17:14:23.708783Z","end":"2026-03-13T17:14:24.464981Z","steps":["trace[302140278] 'agreement among raft nodes before linearized reading' (duration: 755.847208ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:14:24.465052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:14:23.708771Z","time spent":"756.270168ms","remote":"127.0.0.1:40352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-13T17:14:24.465137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"750.784894ms","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-03-13T17:14:24.465186Z","caller":"traceutil/trace.go:171","msg":"trace[296272437] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1043; }","duration":"750.852356ms","start":"2026-03-13T17:14:23.714321Z","end":"2026-03-13T17:14:24.465173Z","steps":["trace[296272437] 'agreement among raft nodes before linearized reading' (duration: 750.739063ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:14:24.465221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:14:23.714312Z","time spent":"750.901877ms","remote":"127.0.0.1:40352","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-03-13T17:14:24.465217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"620.161948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-03-13T17:14:24.465302Z","caller":"traceutil/trace.go:171","msg":"trace[1283575395] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1043; }","duration":"620.290282ms","start":"2026-03-13T17:14:23.844987Z","end":"2026-03-13T17:14:24.465277Z","steps":["trace[1283575395] 'agreement among raft nodes before linearized reading' (duration: 620.046926ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:14:24.465343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.95308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-13T17:14:24.465349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:14:23.84497Z","time spent":"620.370223ms","remote":"127.0.0.1:40352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-03-13T17:14:24.465353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"750.970339ms","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-03-13T17:14:24.465387Z","caller":"traceutil/trace.go:171","msg":"trace[307956701] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1043; }","duration":"751.03061ms","start":"2026-03-13T17:14:23.714347Z","end":"2026-03-13T17:14:24.465377Z","steps":["trace[307956701] 'agreement among raft nodes before linearized reading' (duration: 750.926657ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T17:14:24.465383Z","caller":"traceutil/trace.go:171","msg":"trace[1460129034] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:1043; }","duration":"311.014132ms","start":"2026-03-13T17:14:24.154354Z","end":"2026-03-13T17:14:24.465368Z","steps":["trace[1460129034] 'agreement among raft nodes before linearized reading' (duration: 310.93909ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:14:24.465388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"752.117167ms","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-03-13T17:14:24.465411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.332157ms","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-03-13T17:14:24.465417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:14:24.154318Z","time spent":"311.091374ms","remote":"127.0.0.1:40454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":53,"response size":30,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"info","ts":"2026-03-13T17:14:24.465447Z","caller":"traceutil/trace.go:171","msg":"trace[1834948355] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1043; }","duration":"752.180798ms","start":"2026-03-13T17:14:23.713252Z","end":"2026-03-13T17:14:24.465432Z","steps":["trace[1834948355] 'agreement among raft nodes before linearized reading' (duration: 751.982404ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:14:24.465485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:14:23.713197Z","time spent":"752.28132ms","remote":"127.0.0.1:40352","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-03-13T17:14:24.465408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:14:23.714333Z","time spent":"751.071292ms","remote":"127.0.0.1:40352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-13T17:14:24.465451Z","caller":"traceutil/trace.go:171","msg":"trace[1565618392] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1043; }","duration":"220.43555ms","start":"2026-03-13T17:14:24.245004Z","end":"2026-03-13T17:14:24.46544Z","steps":["trace[1565618392] 'agreement among raft nodes before linearized reading' (duration: 220.246515ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T17:15:32.018389Z","caller":"traceutil/trace.go:171","msg":"trace[1028310314] transaction","detail":"{read_only:false; response_revision:1324; number_of_response:1; }","duration":"151.497106ms","start":"2026-03-13T17:15:31.86687Z","end":"2026-03-13T17:15:32.018367Z","steps":["trace[1028310314] 'process raft request' (duration: 151.229849ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:15:37.351668Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7967603211809051612,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-13T17:15:37.626928Z","caller":"traceutil/trace.go:171","msg":"trace[1549834879] transaction","detail":"{read_only:false; response_revision:1341; number_of_response:1; }","duration":"778.901445ms","start":"2026-03-13T17:15:36.848003Z","end":"2026-03-13T17:15:37.626904Z","steps":["trace[1549834879] 'process raft request' (duration: 778.747492ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:15:37.627081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:15:36.847984Z","time spent":"779.017279ms","remote":"127.0.0.1:40352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T17:15:37.629559Z","caller":"traceutil/trace.go:171","msg":"trace[1723567075] transaction","detail":"{read_only:false; response_revision:1342; number_of_response:1; }","duration":"781.382995ms","start":"2026-03-13T17:15:36.848163Z","end":"2026-03-13T17:15:37.629546Z","steps":["trace[1723567075] 'process raft request' (duration: 781.031457ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:15:37.629666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:15:36.848136Z","time spent":"781.456296ms","remote":"127.0.0.1:40352","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-03-13T17:15:37.629865Z","caller":"traceutil/trace.go:171","msg":"trace[205562519] linearizableReadLoop","detail":"{readStateIndex:1404; appliedIndex:1400; }","duration":"778.549667ms","start":"2026-03-13T17:15:36.851268Z","end":"2026-03-13T17:15:37.629818Z","steps":["trace[205562519] 'read index received' (duration: 775.594737ms)","trace[205562519] 'applied index is now lower than readState.Index' (duration: 2.95369ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T17:15:37.629996Z","caller":"traceutil/trace.go:171","msg":"trace[774501018] transaction","detail":"{read_only:false; response_revision:1343; number_of_response:1; }","duration":"779.784246ms","start":"2026-03-13T17:15:36.850199Z","end":"2026-03-13T17:15:37.629983Z","steps":["trace[774501018] 'process raft request' (duration: 779.08381ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:15:37.630057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:15:36.850185Z","time spent":"779.844188ms","remote":"127.0.0.1:40352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T17:15:37.630247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"778.951657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-03-13T17:15:37.630414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.248404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-03-13T17:15:37.63045Z","caller":"traceutil/trace.go:171","msg":"trace[203593163] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1344; }","duration":"605.303506ms","start":"2026-03-13T17:15:37.025134Z","end":"2026-03-13T17:15:37.630437Z","steps":["trace[203593163] 'agreement among raft nodes before linearized reading' (duration: 605.206194ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:15:37.630638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"778.472656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-13T17:15:37.63076Z","caller":"traceutil/trace.go:171","msg":"trace[1385813781] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1344; }","duration":"778.606379ms","start":"2026-03-13T17:15:36.852142Z","end":"2026-03-13T17:15:37.630748Z","steps":["trace[1385813781] 'agreement among raft nodes before linearized reading' (duration: 778.402364ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T17:15:37.630507Z","caller":"traceutil/trace.go:171","msg":"trace[2071957128] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1344; }","duration":"779.167772ms","start":"2026-03-13T17:15:36.85125Z","end":"2026-03-13T17:15:37.630417Z","steps":["trace[2071957128] 'agreement among raft nodes before linearized reading' (duration: 778.741542ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:15:37.630831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:15:36.852137Z","time spent":"778.6808ms","remote":"127.0.0.1:40352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-13T17:15:37.630872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:15:36.851242Z","time spent":"779.620723ms","remote":"127.0.0.1:40352","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-03-13T17:15:37.63032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"777.974604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T17:15:37.631076Z","caller":"traceutil/trace.go:171","msg":"trace[786602751] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1344; }","duration":"778.739522ms","start":"2026-03-13T17:15:36.852327Z","end":"2026-03-13T17:15:37.631066Z","steps":["trace[786602751] 'agreement among raft nodes before linearized reading' (duration: 777.965064ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:15:37.631221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:15:36.852322Z","time spent":"778.777013ms","remote":"127.0.0.1:40110","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-03-13T17:15:37.630525Z","caller":"traceutil/trace.go:171","msg":"trace[965807185] transaction","detail":"{read_only:false; response_revision:1344; number_of_response:1; }","duration":"381.688939ms","start":"2026-03-13T17:15:37.248795Z","end":"2026-03-13T17:15:37.630484Z","steps":["trace[965807185] 'process raft request' (duration: 380.974352ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:15:37.631362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:15:37.248772Z","time spent":"382.535999ms","remote":"127.0.0.1:40352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T17:15:37.630307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"778.887296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-03-13T17:15:37.63147Z","caller":"traceutil/trace.go:171","msg":"trace[180207280] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1344; }","duration":"780.069043ms","start":"2026-03-13T17:15:36.851387Z","end":"2026-03-13T17:15:37.631456Z","steps":["trace[180207280] 'agreement among raft nodes before linearized reading' (duration: 778.808313ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:15:37.631564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:15:36.851383Z","time spent":"780.168776ms","remote":"127.0.0.1:40352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-03-13T17:15:37.630689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:15:37.025121Z","time spent":"605.556732ms","remote":"127.0.0.1:40352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":549,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-03-13T17:15:44.955669Z","caller":"traceutil/trace.go:171","msg":"trace[1137508134] transaction","detail":"{read_only:false; response_revision:1373; number_of_response:1; }","duration":"329.153772ms","start":"2026-03-13T17:15:44.626483Z","end":"2026-03-13T17:15:44.955637Z","steps":["trace[1137508134] 'process raft request' (duration: 328.967858ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:15:44.955867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:15:44.626471Z","time spent":"329.304855ms","remote":"127.0.0.1:40352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":494,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T17:15:44.958065Z","caller":"traceutil/trace.go:171","msg":"trace[566007061] transaction","detail":"{read_only:false; response_revision:1374; number_of_response:1; }","duration":"330.75497ms","start":"2026-03-13T17:15:44.6271Z","end":"2026-03-13T17:15:44.957855Z","steps":["trace[566007061] 'process raft request' (duration: 330.607506ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:15:44.958354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:15:44.627092Z","time spent":"331.056148ms","remote":"127.0.0.1:40352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T17:15:46.145653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.025538ms","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-03-13T17:15:46.145743Z","caller":"traceutil/trace.go:171","msg":"trace[458672414] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1374; }","duration":"476.152361ms","start":"2026-03-13T17:15:45.66957Z","end":"2026-03-13T17:15:46.145723Z","steps":["trace[458672414] 'range keys from in-memory index tree' (duration: 475.839154ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:15:46.145789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:15:45.669562Z","time spent":"476.217042ms","remote":"127.0.0.1:40352","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-03-13T17:15:46.145818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.184839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-03-13T17:15:46.14589Z","caller":"traceutil/trace.go:171","msg":"trace[36366541] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1374; }","duration":"411.285311ms","start":"2026-03-13T17:15:45.734582Z","end":"2026-03-13T17:15:46.145867Z","steps":["trace[36366541] 'range keys from in-memory index tree' (duration: 411.021915ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:15:46.145936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:15:45.73457Z","time spent":"411.354632ms","remote":"127.0.0.1:40352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":558,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-03-13T17:15:46.145962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.615093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-13T17:15:46.146035Z","caller":"traceutil/trace.go:171","msg":"trace[628435446] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1374; }","duration":"479.703064ms","start":"2026-03-13T17:15:45.666306Z","end":"2026-03-13T17:15:46.146009Z","steps":["trace[628435446] 'range keys from in-memory index tree' (duration: 479.49504ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:15:46.146082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.922804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-03-13T17:15:46.146114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:15:45.666295Z","time spent":"479.804998ms","remote":"127.0.0.1:40352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-13T17:15:46.146152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.974604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-03-13T17:15:46.146192Z","caller":"traceutil/trace.go:171","msg":"trace[999269063] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1374; }","duration":"478.022715ms","start":"2026-03-13T17:15:45.668153Z","end":"2026-03-13T17:15:46.146175Z","steps":["trace[999269063] 'range keys from in-memory index tree' (duration: 477.888772ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:15:46.146221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:15:45.668144Z","time spent":"478.071056ms","remote":"127.0.0.1:40352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-03-13T17:15:46.145648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.159181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T17:15:46.146269Z","caller":"traceutil/trace.go:171","msg":"trace[1087412561] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1374; }","duration":"294.829507ms","start":"2026-03-13T17:15:45.851422Z","end":"2026-03-13T17:15:46.146252Z","steps":["trace[1087412561] 'range keys from in-memory index tree' (duration: 294.08833ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T17:15:46.146107Z","caller":"traceutil/trace.go:171","msg":"trace[1249082287] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1374; }","duration":"480.979235ms","start":"2026-03-13T17:15:45.665119Z","end":"2026-03-13T17:15:46.146098Z","steps":["trace[1249082287] 'range keys from in-memory index tree' (duration: 480.837012ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:15:46.14633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:15:45.665102Z","time spent":"481.220301ms","remote":"127.0.0.1:40352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-13T17:15:46.146544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.028553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-13T17:15:46.146613Z","caller":"traceutil/trace.go:171","msg":"trace[1555928302] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1374; }","duration":"488.133625ms","start":"2026-03-13T17:15:45.658462Z","end":"2026-03-13T17:15:46.146595Z","steps":["trace[1555928302] 'range keys from in-memory index tree' (duration: 487.888819ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:15:46.146656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:15:45.658442Z","time spent":"488.202327ms","remote":"127.0.0.1:40352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-13T17:15:46.146728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.391648ms","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-03-13T17:15:46.146778Z","caller":"traceutil/trace.go:171","msg":"trace[1577534799] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1374; }","duration":"482.4654ms","start":"2026-03-13T17:15:45.664297Z","end":"2026-03-13T17:15:46.146762Z","steps":["trace[1577534799] 'range keys from in-memory index tree' (duration: 481.036126ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:15:46.146819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:15:45.664285Z","time spent":"482.520201ms","remote":"127.0.0.1:40352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-13T17:17:25.344174Z","caller":"traceutil/trace.go:171","msg":"trace[887617416] transaction","detail":"{read_only:false; response_revision:1809; number_of_response:1; }","duration":"185.979836ms","start":"2026-03-13T17:17:25.158174Z","end":"2026-03-13T17:17:25.344154Z","steps":["trace[887617416] 'process raft request' (duration: 177.939947ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T17:17:32.848914Z","caller":"traceutil/trace.go:171","msg":"trace[186519850] transaction","detail":"{read_only:false; response_revision:1846; number_of_response:1; }","duration":"153.695258ms","start":"2026-03-13T17:17:32.695182Z","end":"2026-03-13T17:17:32.848878Z","steps":["trace[186519850] 'process raft request' (duration: 153.646687ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T17:17:32.849309Z","caller":"traceutil/trace.go:171","msg":"trace[343785212] transaction","detail":"{read_only:false; response_revision:1843; number_of_response:1; }","duration":"154.717722ms","start":"2026-03-13T17:17:32.694555Z","end":"2026-03-13T17:17:32.849272Z","steps":["trace[343785212] 'process raft request' (duration: 140.636172ms)","trace[343785212] 'compare' (duration: 13.041426ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T17:17:32.849606Z","caller":"traceutil/trace.go:171","msg":"trace[561055830] transaction","detail":"{read_only:false; response_revision:1845; number_of_response:1; }","duration":"154.817965ms","start":"2026-03-13T17:17:32.694766Z","end":"2026-03-13T17:17:32.849584Z","steps":["trace[561055830] 'process raft request' (duration: 154.020276ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T17:17:32.849788Z","caller":"traceutil/trace.go:171","msg":"trace[1940362066] transaction","detail":"{read_only:false; response_revision:1844; number_of_response:1; }","duration":"155.168643ms","start":"2026-03-13T17:17:32.694594Z","end":"2026-03-13T17:17:32.849763Z","steps":["trace[1940362066] 'process raft request' (duration: 154.072137ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T17:18:03.23646Z","caller":"traceutil/trace.go:171","msg":"trace[645360719] linearizableReadLoop","detail":"{readStateIndex:2062; appliedIndex:2061; }","duration":"382.393699ms","start":"2026-03-13T17:18:02.85403Z","end":"2026-03-13T17:18:03.236424Z","steps":["trace[645360719] 'read index received' (duration: 381.914778ms)","trace[645360719] 'applied index is now lower than readState.Index' (duration: 477.521µs)"],"step_count":2} {"level":"info","ts":"2026-03-13T17:18:03.23655Z","caller":"traceutil/trace.go:171","msg":"trace[1566959499] transaction","detail":"{read_only:false; response_revision:1971; number_of_response:1; }","duration":"447.363962ms","start":"2026-03-13T17:18:02.789134Z","end":"2026-03-13T17:18:03.236498Z","steps":["trace[1566959499] 'process raft request' (duration: 446.929122ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:18:03.236683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.620454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T17:18:03.236726Z","caller":"traceutil/trace.go:171","msg":"trace[239249977] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1971; }","duration":"382.725597ms","start":"2026-03-13T17:18:02.853987Z","end":"2026-03-13T17:18:03.236713Z","steps":["trace[239249977] 'agreement among raft nodes before linearized reading' (duration: 382.615944ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:18:03.236752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:18:02.78911Z","time spent":"447.533966ms","remote":"127.0.0.1:40352","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-03-13T17:18:03.236775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:18:02.853977Z","time spent":"382.789338ms","remote":"127.0.0.1:40110","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-03-13T17:18:03.236856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.066712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-03-13T17:18:03.23753Z","caller":"traceutil/trace.go:171","msg":"trace[158300662] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1971; }","duration":"338.757437ms","start":"2026-03-13T17:18:02.898739Z","end":"2026-03-13T17:18:03.237497Z","steps":["trace[158300662] 'agreement among raft nodes before linearized reading' (duration: 337.974519ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:18:03.237683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:18:02.898728Z","time spent":"338.913661ms","remote":"127.0.0.1:40186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":153,"response size":31,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "}