{"level":"warn","ts":"2026-03-26T09:52:19.137625Z","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-26T09:52:19.137718Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.10:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.10:2380","--initial-cluster=instance=https://199.19.213.10:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.10:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.10: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-26T09:52:19.137806Z","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-26T09:52:19.13782Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.10:2380"]} {"level":"info","ts":"2026-03-26T09:52:19.137838Z","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-26T09:52:19.138512Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.10:2379"]} {"level":"info","ts":"2026-03-26T09:52:19.138588Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.10:2380"],"listen-peer-urls":["https://199.19.213.10:2380"],"advertise-client-urls":["https://199.19.213.10:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.10:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.10: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-26T09:52:19.143175Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.379744ms"} {"level":"info","ts":"2026-03-26T09:52:19.158255Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"93eaf76b69bfff2c","cluster-id":"800615b58ad8280"} {"level":"info","ts":"2026-03-26T09:52:19.158389Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"93eaf76b69bfff2c switched to configuration voters=()"} {"level":"info","ts":"2026-03-26T09:52:19.158427Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"93eaf76b69bfff2c became follower at term 0"} {"level":"info","ts":"2026-03-26T09:52:19.158467Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 93eaf76b69bfff2c [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-26T09:52:19.158503Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"93eaf76b69bfff2c became follower at term 1"} {"level":"info","ts":"2026-03-26T09:52:19.158561Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"93eaf76b69bfff2c switched to configuration voters=(10658603508833451820)"} {"level":"warn","ts":"2026-03-26T09:52:19.16741Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-26T09:52:19.168458Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-26T09:52:19.169865Z","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-26T09:52:19.172007Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"93eaf76b69bfff2c","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-26T09:52:19.173432Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"93eaf76b69bfff2c","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-26T09:52:19.173666Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"93eaf76b69bfff2c switched to configuration voters=(10658603508833451820)"} {"level":"info","ts":"2026-03-26T09:52:19.173334Z","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-26T09:52:19.173786Z","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-26T09:52:19.173798Z","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-26T09:52:19.173809Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"800615b58ad8280","local-member-id":"93eaf76b69bfff2c","added-peer-id":"93eaf76b69bfff2c","added-peer-peer-urls":["https://199.19.213.10:2380"]} {"level":"info","ts":"2026-03-26T09:52:19.176488Z","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-26T09:52:19.176658Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.10:2380"} {"level":"info","ts":"2026-03-26T09:52:19.176697Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.10:2380"} {"level":"info","ts":"2026-03-26T09:52:19.176889Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"93eaf76b69bfff2c","initial-advertise-peer-urls":["https://199.19.213.10:2380"],"listen-peer-urls":["https://199.19.213.10:2380"],"advertise-client-urls":["https://199.19.213.10:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.10:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-26T09:52:19.177003Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-26T09:52:19.359043Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"93eaf76b69bfff2c is starting a new election at term 1"} {"level":"info","ts":"2026-03-26T09:52:19.359117Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"93eaf76b69bfff2c became pre-candidate at term 1"} {"level":"info","ts":"2026-03-26T09:52:19.35933Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"93eaf76b69bfff2c received MsgPreVoteResp from 93eaf76b69bfff2c at term 1"} {"level":"info","ts":"2026-03-26T09:52:19.359371Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"93eaf76b69bfff2c became candidate at term 2"} {"level":"info","ts":"2026-03-26T09:52:19.359394Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"93eaf76b69bfff2c received MsgVoteResp from 93eaf76b69bfff2c at term 2"} {"level":"info","ts":"2026-03-26T09:52:19.359494Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"93eaf76b69bfff2c became leader at term 2"} {"level":"info","ts":"2026-03-26T09:52:19.359535Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 93eaf76b69bfff2c elected leader 93eaf76b69bfff2c at term 2"} {"level":"info","ts":"2026-03-26T09:52:19.360645Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-26T09:52:19.361526Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-26T09:52:19.361517Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"93eaf76b69bfff2c","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.10:2379]}","request-path":"/0/members/93eaf76b69bfff2c/attributes","cluster-id":"800615b58ad8280","publish-timeout":"7s"} {"level":"info","ts":"2026-03-26T09:52:19.361718Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-26T09:52:19.362233Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"800615b58ad8280","local-member-id":"93eaf76b69bfff2c","cluster-version":"3.5"} {"level":"info","ts":"2026-03-26T09:52:19.362352Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-26T09:52:19.362395Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-26T09:52:19.363583Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-26T09:52:19.363738Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-26T09:52:19.365467Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.10:2379"} {"level":"info","ts":"2026-03-26T09:52:19.366437Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-26T09:52:28.035906Z","caller":"traceutil/trace.go:171","msg":"trace[937820309] linearizableReadLoop","detail":"{readStateIndex:365; appliedIndex:364; }","duration":"186.644782ms","start":"2026-03-26T09:52:27.849242Z","end":"2026-03-26T09:52:28.035887Z","steps":["trace[937820309] 'read index received' (duration: 96.071318ms)","trace[937820309] 'applied index is now lower than readState.Index' (duration: 90.572494ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T09:52:28.036008Z","caller":"traceutil/trace.go:171","msg":"trace[1852939728] transaction","detail":"{read_only:false; response_revision:351; number_of_response:1; }","duration":"212.525504ms","start":"2026-03-26T09:52:27.823477Z","end":"2026-03-26T09:52:28.036002Z","steps":["trace[1852939728] 'process raft request' (duration: 121.907509ms)","trace[1852939728] 'compare' (duration: 90.386828ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T09:52:28.036143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.86858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-03-26T09:52:28.03617Z","caller":"traceutil/trace.go:171","msg":"trace[1979623237] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:351; }","duration":"186.970913ms","start":"2026-03-26T09:52:27.849192Z","end":"2026-03-26T09:52:28.036163Z","steps":["trace[1979623237] 'agreement among raft nodes before linearized reading' (duration: 186.88525ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:52:28.03637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.270902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" ","response":"range_response_count:1 size:185"} {"level":"info","ts":"2026-03-26T09:52:28.036391Z","caller":"traceutil/trace.go:171","msg":"trace[810458167] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/kube-proxy; range_end:; response_count:1; response_revision:351; }","duration":"100.330784ms","start":"2026-03-26T09:52:27.936055Z","end":"2026-03-26T09:52:28.036386Z","steps":["trace[810458167] 'agreement among raft nodes before linearized reading' (duration: 100.290473ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:52:28.036373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.290939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-03-26T09:52:28.036528Z","caller":"traceutil/trace.go:171","msg":"trace[391809707] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:351; }","duration":"138.486755ms","start":"2026-03-26T09:52:27.898018Z","end":"2026-03-26T09:52:28.036504Z","steps":["trace[391809707] 'agreement among raft nodes before linearized reading' (duration: 138.249537ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:52:28.384597Z","caller":"traceutil/trace.go:171","msg":"trace[1634382004] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"127.515066ms","start":"2026-03-26T09:52:28.257054Z","end":"2026-03-26T09:52:28.384569Z","steps":["trace[1634382004] 'process raft request' (duration: 120.722461ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:52:28.38565Z","caller":"traceutil/trace.go:171","msg":"trace[1101803568] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"126.497884ms","start":"2026-03-26T09:52:28.259087Z","end":"2026-03-26T09:52:28.385585Z","steps":["trace[1101803568] 'process raft request' (duration: 126.279667ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:52:37.029499Z","caller":"traceutil/trace.go:171","msg":"trace[1841272624] linearizableReadLoop","detail":"{readStateIndex:426; appliedIndex:425; }","duration":"379.81497ms","start":"2026-03-26T09:52:36.64966Z","end":"2026-03-26T09:52:37.029475Z","steps":["trace[1841272624] 'read index received' (duration: 379.670655ms)","trace[1841272624] 'applied index is now lower than readState.Index' (duration: 143.225µs)"],"step_count":2} {"level":"info","ts":"2026-03-26T09:52:37.029602Z","caller":"traceutil/trace.go:171","msg":"trace[1736159100] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"476.872834ms","start":"2026-03-26T09:52:36.552696Z","end":"2026-03-26T09:52:37.029569Z","steps":["trace[1736159100] 'process raft request' (duration: 476.625186ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:52:37.029824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.288627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-26T09:52:37.029884Z","caller":"traceutil/trace.go:171","msg":"trace[1206687876] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:410; }","duration":"113.38876ms","start":"2026-03-26T09:52:36.916473Z","end":"2026-03-26T09:52:37.029862Z","steps":["trace[1206687876] 'agreement among raft nodes before linearized reading' (duration: 113.289087ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:52:37.030243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:52:36.552676Z","time spent":"477.008007ms","remote":"127.0.0.1:57250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7279,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T09:52:37.030416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.76962ms","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-26T09:52:37.030614Z","caller":"traceutil/trace.go:171","msg":"trace[2095582690] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:410; }","duration":"381.030758ms","start":"2026-03-26T09:52:36.64957Z","end":"2026-03-26T09:52:37.0306Z","steps":["trace[2095582690] 'agreement among raft nodes before linearized reading' (duration: 380.164111ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:52:37.031939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:52:36.649535Z","time spent":"382.37624ms","remote":"127.0.0.1:57334","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-26T09:52:43.069486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:52:42.542245Z","time spent":"527.231593ms","remote":"127.0.0.1:57088","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-03-26T09:52:43.574585Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18387244180467407346,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-26T09:52:43.779549Z","caller":"traceutil/trace.go:171","msg":"trace[1977411130] transaction","detail":"{read_only:false; response_revision:456; number_of_response:1; }","duration":"708.780614ms","start":"2026-03-26T09:52:43.070744Z","end":"2026-03-26T09:52:43.779525Z","steps":["trace[1977411130] 'process raft request' (duration: 705.895163ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:52:43.779666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:52:43.070712Z","time spent":"708.890777ms","remote":"127.0.0.1:57088","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-26T09:52:43.783993Z","caller":"traceutil/trace.go:171","msg":"trace[480233242] transaction","detail":"{read_only:false; response_revision:458; number_of_response:1; }","duration":"708.792024ms","start":"2026-03-26T09:52:43.07519Z","end":"2026-03-26T09:52:43.783982Z","steps":["trace[480233242] 'process raft request' (duration: 708.687721ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:52:43.783969Z","caller":"traceutil/trace.go:171","msg":"trace[463156765] linearizableReadLoop","detail":"{readStateIndex:477; appliedIndex:474; }","duration":"710.332772ms","start":"2026-03-26T09:52:43.073595Z","end":"2026-03-26T09:52:43.783928Z","steps":["trace[463156765] 'read index received' (duration: 703.009501ms)","trace[463156765] 'applied index is now lower than readState.Index' (duration: 7.321741ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T09:52:43.784048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:52:43.07518Z","time spent":"708.844865ms","remote":"127.0.0.1:57334","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-26T09:52:43.784021Z","caller":"traceutil/trace.go:171","msg":"trace[917581016] transaction","detail":"{read_only:false; response_revision:457; number_of_response:1; }","duration":"709.135225ms","start":"2026-03-26T09:52:43.074847Z","end":"2026-03-26T09:52:43.783982Z","steps":["trace[917581016] 'process raft request' (duration: 708.860316ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:52:43.784226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:52:43.074823Z","time spent":"709.31047ms","remote":"127.0.0.1:57334","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T09:52:43.78427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"710.55435ms","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-26T09:52:43.784362Z","caller":"traceutil/trace.go:171","msg":"trace[1514848805] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:458; }","duration":"710.798237ms","start":"2026-03-26T09:52:43.073549Z","end":"2026-03-26T09:52:43.784347Z","steps":["trace[1514848805] 'agreement among raft nodes before linearized reading' (duration: 710.541398ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:52:43.784315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.416822ms","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-26T09:52:43.784459Z","caller":"traceutil/trace.go:171","msg":"trace[1620233991] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:458; }","duration":"414.636839ms","start":"2026-03-26T09:52:43.369788Z","end":"2026-03-26T09:52:43.784424Z","steps":["trace[1620233991] 'agreement among raft nodes before linearized reading' (duration: 414.36143ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:52:43.784522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:52:43.369752Z","time spent":"414.758803ms","remote":"127.0.0.1:57334","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-26T09:52:43.78448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:52:43.073533Z","time spent":"710.871039ms","remote":"127.0.0.1:57334","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-26T09:52:57.402801Z","caller":"traceutil/trace.go:171","msg":"trace[1380282507] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"640.723206ms","start":"2026-03-26T09:52:56.762048Z","end":"2026-03-26T09:52:57.402771Z","steps":["trace[1380282507] 'process raft request' (duration: 640.478998ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:52:57.403308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:52:56.76203Z","time spent":"641.105297ms","remote":"127.0.0.1:57104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-26T09:52:57.416115Z","caller":"traceutil/trace.go:171","msg":"trace[391157740] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"638.695621ms","start":"2026-03-26T09:52:56.77739Z","end":"2026-03-26T09:52:57.416086Z","steps":["trace[391157740] 'process raft request' (duration: 638.387851ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:52:57.416173Z","caller":"traceutil/trace.go:171","msg":"trace[1876928209] linearizableReadLoop","detail":"{readStateIndex:555; appliedIndex:543; }","duration":"174.367164ms","start":"2026-03-26T09:52:57.241778Z","end":"2026-03-26T09:52:57.416145Z","steps":["trace[1876928209] 'read index received' (duration: 160.817166ms)","trace[1876928209] 'applied index is now lower than readState.Index' (duration: 13.548688ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T09:52:57.416323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:52:56.777384Z","time spent":"638.858386ms","remote":"127.0.0.1:57104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-26T09:52:57.416386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.588271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T09:52:57.416446Z","caller":"traceutil/trace.go:171","msg":"trace[882736512] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:534; }","duration":"174.718095ms","start":"2026-03-26T09:52:57.241719Z","end":"2026-03-26T09:52:57.416437Z","steps":["trace[882736512] 'agreement among raft nodes before linearized reading' (duration: 174.55618ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:52:57.416471Z","caller":"traceutil/trace.go:171","msg":"trace[1207256977] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"646.944362ms","start":"2026-03-26T09:52:56.769515Z","end":"2026-03-26T09:52:57.41646Z","steps":["trace[1207256977] 'process raft request' (duration: 646.012223ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:52:57.41653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:52:56.769487Z","time spent":"647.018584ms","remote":"127.0.0.1:57104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-26T09:52:57.416622Z","caller":"traceutil/trace.go:171","msg":"trace[1798814554] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"647.025955ms","start":"2026-03-26T09:52:56.769587Z","end":"2026-03-26T09:52:57.416613Z","steps":["trace[1798814554] 'process raft request' (duration: 646.075125ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:52:57.416678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:52:56.769563Z","time spent":"647.089106ms","remote":"127.0.0.1:57104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-26T09:52:57.416788Z","caller":"traceutil/trace.go:171","msg":"trace[667235850] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"639.60878ms","start":"2026-03-26T09:52:56.777171Z","end":"2026-03-26T09:52:57.416779Z","steps":["trace[667235850] 'process raft request' (duration: 638.527516ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:52:57.416819Z","caller":"traceutil/trace.go:171","msg":"trace[773781667] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"635.871882ms","start":"2026-03-26T09:52:56.780942Z","end":"2026-03-26T09:52:57.416814Z","steps":["trace[773781667] 'process raft request' (duration: 634.937283ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:52:57.416853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:52:56.777156Z","time spent":"639.671142ms","remote":"127.0.0.1:57104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-26T09:52:57.416936Z","caller":"traceutil/trace.go:171","msg":"trace[803613394] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"639.699963ms","start":"2026-03-26T09:52:56.777226Z","end":"2026-03-26T09:52:57.416926Z","steps":["trace[803613394] 'process raft request' (duration: 638.521856ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:52:57.416989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:52:56.777223Z","time spent":"639.744694ms","remote":"127.0.0.1:57104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-26T09:52:57.416793Z","caller":"traceutil/trace.go:171","msg":"trace[260028952] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"638.853966ms","start":"2026-03-26T09:52:56.777929Z","end":"2026-03-26T09:52:57.416783Z","steps":["trace[260028952] 'process raft request' (duration: 637.919857ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:52:57.417118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:52:56.777894Z","time spent":"639.201807ms","remote":"127.0.0.1:57104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-26T09:52:57.416987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:52:56.780927Z","time spent":"635.972956ms","remote":"127.0.0.1:57104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-26T09:52:57.41703Z","caller":"traceutil/trace.go:171","msg":"trace[1257320107] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"625.900218ms","start":"2026-03-26T09:52:56.79112Z","end":"2026-03-26T09:52:57.417021Z","steps":["trace[1257320107] 'process raft request' (duration: 624.786423ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:52:57.417328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:52:56.791101Z","time spent":"626.192816ms","remote":"127.0.0.1:57104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-26T09:52:57.417366Z","caller":"traceutil/trace.go:171","msg":"trace[1718389820] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"583.573261ms","start":"2026-03-26T09:52:56.833779Z","end":"2026-03-26T09:52:57.417352Z","steps":["trace[1718389820] 'process raft request' (duration: 582.206918ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:52:57.417402Z","caller":"traceutil/trace.go:171","msg":"trace[1592121150] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"608.579651ms","start":"2026-03-26T09:52:56.808814Z","end":"2026-03-26T09:52:57.417394Z","steps":["trace[1592121150] 'process raft request' (duration: 607.131135ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:52:57.418516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:52:56.833748Z","time spent":"584.675766ms","remote":"127.0.0.1:57104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-26T09:52:57.418616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:52:56.80879Z","time spent":"609.783429ms","remote":"127.0.0.1:57104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-26T09:52:57.417453Z","caller":"traceutil/trace.go:171","msg":"trace[1144111431] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"472.308179ms","start":"2026-03-26T09:52:56.945124Z","end":"2026-03-26T09:52:57.417432Z","steps":["trace[1144111431] 'process raft request' (duration: 470.911625ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:52:57.418766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:52:56.945084Z","time spent":"473.642321ms","remote":"127.0.0.1:57104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-26T09:53:00.04658Z","caller":"traceutil/trace.go:171","msg":"trace[64530333] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"145.795292ms","start":"2026-03-26T09:52:59.900762Z","end":"2026-03-26T09:53:00.046557Z","steps":["trace[64530333] 'process raft request' (duration: 145.562744ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:53:00.096105Z","caller":"traceutil/trace.go:171","msg":"trace[1048206678] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"192.048122ms","start":"2026-03-26T09:52:59.904006Z","end":"2026-03-26T09:53:00.096054Z","steps":["trace[1048206678] 'process raft request' (duration: 191.954379ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:53:00.096305Z","caller":"traceutil/trace.go:171","msg":"trace[128637114] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"192.148485ms","start":"2026-03-26T09:52:59.904115Z","end":"2026-03-26T09:53:00.096264Z","steps":["trace[128637114] 'process raft request' (duration: 191.903817ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:53:00.096316Z","caller":"traceutil/trace.go:171","msg":"trace[1170879868] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"192.271849ms","start":"2026-03-26T09:52:59.903916Z","end":"2026-03-26T09:53:00.096188Z","steps":["trace[1170879868] 'process raft request' (duration: 191.876397ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:53:04.245719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.894056ms","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-26T09:53:04.245751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.535308ms","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-26T09:53:04.245815Z","caller":"traceutil/trace.go:171","msg":"trace[809768036] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:630; }","duration":"134.010591ms","start":"2026-03-26T09:53:04.111783Z","end":"2026-03-26T09:53:04.245794Z","steps":["trace[809768036] 'range keys from in-memory index tree' (duration: 133.730372ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:53:04.245843Z","caller":"traceutil/trace.go:171","msg":"trace[1928317463] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:630; }","duration":"128.656331ms","start":"2026-03-26T09:53:04.117162Z","end":"2026-03-26T09:53:04.245818Z","steps":["trace[1928317463] 'range keys from in-memory index tree' (duration: 128.356782ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:53:04.24591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.790555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-03-26T09:53:04.246039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.974481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-03-26T09:53:04.246051Z","caller":"traceutil/trace.go:171","msg":"trace[55321533] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:630; }","duration":"186.016772ms","start":"2026-03-26T09:53:04.060014Z","end":"2026-03-26T09:53:04.246031Z","steps":["trace[55321533] 'range keys from in-memory index tree' (duration: 185.632879ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:53:04.246283Z","caller":"traceutil/trace.go:171","msg":"trace[1096336332] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:630; }","duration":"129.052004ms","start":"2026-03-26T09:53:04.117021Z","end":"2026-03-26T09:53:04.246073Z","steps":["trace[1096336332] 'range keys from in-memory index tree' (duration: 128.879349ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:53:04.655453Z","caller":"traceutil/trace.go:171","msg":"trace[231943749] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"417.039914ms","start":"2026-03-26T09:53:04.238396Z","end":"2026-03-26T09:53:04.655436Z","steps":["trace[231943749] 'process raft request' (duration: 416.516028ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:53:04.655594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:53:04.238365Z","time spent":"417.144038ms","remote":"127.0.0.1:57162","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036301,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-26T09:53:05.457909Z","caller":"traceutil/trace.go:171","msg":"trace[390802254] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"1.20826353s","start":"2026-03-26T09:53:04.249621Z","end":"2026-03-26T09:53:05.457884Z","steps":["trace[390802254] 'process raft request' (duration: 1.208140616s)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:53:05.457934Z","caller":"traceutil/trace.go:171","msg":"trace[553382652] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"1.208494977s","start":"2026-03-26T09:53:04.249404Z","end":"2026-03-26T09:53:05.457899Z","steps":["trace[553382652] 'process raft request' (duration: 1.164271191s)","trace[553382652] 'compare' (duration: 43.935807ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T09:53:05.458089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:53:04.249385Z","time spent":"1.208624972s","remote":"127.0.0.1:57334","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-26T09:53:05.458309Z","caller":"traceutil/trace.go:171","msg":"trace[1533793224] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"1.208445486s","start":"2026-03-26T09:53:04.249852Z","end":"2026-03-26T09:53:05.458297Z","steps":["trace[1533793224] 'process raft request' (duration: 1.207996112s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:53:05.458392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:53:04.249803Z","time spent":"1.20854961s","remote":"127.0.0.1:57334","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T09:53:05.458087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:53:04.249614Z","time spent":"1.208386164s","remote":"127.0.0.1:57334","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-26T09:53:05.458664Z","caller":"traceutil/trace.go:171","msg":"trace[503095383] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"1.208478927s","start":"2026-03-26T09:53:04.250169Z","end":"2026-03-26T09:53:05.458648Z","steps":["trace[503095383] 'process raft request' (duration: 1.208097235s)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:53:05.458695Z","caller":"traceutil/trace.go:171","msg":"trace[774811567] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"746.3433ms","start":"2026-03-26T09:53:04.712326Z","end":"2026-03-26T09:53:05.458669Z","steps":["trace[774811567] 'process raft request' (duration: 746.244086ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:53:05.458752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:53:04.250161Z","time spent":"1.208547309s","remote":"127.0.0.1:57334","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-26T09:53:05.458835Z","caller":"traceutil/trace.go:171","msg":"trace[1019662006] linearizableReadLoop","detail":"{readStateIndex:660; appliedIndex:655; }","duration":"805.632921ms","start":"2026-03-26T09:53:04.653188Z","end":"2026-03-26T09:53:05.458821Z","steps":["trace[1019662006] 'read index received' (duration: 1.704004ms)","trace[1019662006] 'applied index is now lower than readState.Index' (duration: 803.927247ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T09:53:05.458857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:53:04.712299Z","time spent":"746.489714ms","remote":"127.0.0.1:57296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-26T09:53:05.458977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.257146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-26T09:53:05.458993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"805.805576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-d8cvs\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-03-26T09:53:05.459029Z","caller":"traceutil/trace.go:171","msg":"trace[1907506609] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:636; }","duration":"216.308178ms","start":"2026-03-26T09:53:05.242707Z","end":"2026-03-26T09:53:05.459015Z","steps":["trace[1907506609] 'agreement among raft nodes before linearized reading' (duration: 216.233445ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:53:05.459042Z","caller":"traceutil/trace.go:171","msg":"trace[1863493127] range","detail":"{range_begin:/registry/pods/kube-system/cilium-d8cvs; range_end:; response_count:1; response_revision:636; }","duration":"805.890279ms","start":"2026-03-26T09:53:04.653137Z","end":"2026-03-26T09:53:05.459027Z","steps":["trace[1863493127] 'agreement among raft nodes before linearized reading' (duration: 805.770125ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:53:05.459083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:53:04.653118Z","time spent":"805.954921ms","remote":"127.0.0.1:57250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16984,"request content":"key:\"/registry/pods/kube-system/cilium-d8cvs\" "} {"level":"warn","ts":"2026-03-26T09:53:07.301823Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.564787064s","expected-duration":"1s"} {"level":"warn","ts":"2026-03-26T09:53:08.394353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.657018242s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-26T09:53:08.394575Z","caller":"traceutil/trace.go:171","msg":"trace[891783048] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"2.838722547s","start":"2026-03-26T09:53:05.555821Z","end":"2026-03-26T09:53:08.394543Z","steps":["trace[891783048] 'process raft request' (duration: 181.22388ms)","trace[891783048] 'compare' (duration: 2.656879197s)"],"step_count":2} {"level":"info","ts":"2026-03-26T09:53:08.394639Z","caller":"traceutil/trace.go:171","msg":"trace[1300725549] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"2.760824298s","start":"2026-03-26T09:53:05.633782Z","end":"2026-03-26T09:53:08.394607Z","steps":["trace[1300725549] 'process raft request' (duration: 2.760691044s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:53:08.394697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.153733789s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-26T09:53:08.394719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:53:05.555706Z","time spent":"2.838848501s","remote":"127.0.0.1:57250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-26T09:53:08.394595Z","caller":"traceutil/trace.go:171","msg":"trace[1988661070] linearizableReadLoop","detail":"{readStateIndex:664; appliedIndex:662; }","duration":"1.153624316s","start":"2026-03-26T09:53:07.240948Z","end":"2026-03-26T09:53:08.394572Z","steps":["trace[1988661070] 'read index received' (duration: 61.078778ms)","trace[1988661070] 'applied index is now lower than readState.Index' (duration: 1.092544378s)"],"step_count":2} {"level":"info","ts":"2026-03-26T09:53:08.394742Z","caller":"traceutil/trace.go:171","msg":"trace[1982071216] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:639; }","duration":"1.153818322s","start":"2026-03-26T09:53:07.240911Z","end":"2026-03-26T09:53:08.394729Z","steps":["trace[1982071216] 'agreement among raft nodes before linearized reading' (duration: 1.153724719s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:53:08.394784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:53:07.240894Z","time spent":"1.153879214s","remote":"127.0.0.1:57058","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-26T09:53:08.394781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:53:05.633746Z","time spent":"2.760968403s","remote":"127.0.0.1:57334","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-26T09:53:08.488646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.023137276s","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-26T09:53:08.488755Z","caller":"traceutil/trace.go:171","msg":"trace[704917129] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:639; }","duration":"1.023260951s","start":"2026-03-26T09:53:07.465456Z","end":"2026-03-26T09:53:08.488717Z","steps":["trace[704917129] 'agreement among raft nodes before linearized reading' (duration: 1.023013243s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:53:08.488758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.02325372s","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-26T09:53:08.488821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:53:07.465445Z","time spent":"1.023360113s","remote":"127.0.0.1:57334","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-26T09:53:08.488837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.024257381s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-03-26T09:53:08.488901Z","caller":"traceutil/trace.go:171","msg":"trace[1179873476] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:639; }","duration":"1.024369235s","start":"2026-03-26T09:53:07.464516Z","end":"2026-03-26T09:53:08.488886Z","steps":["trace[1179873476] 'agreement among raft nodes before linearized reading' (duration: 1.024014444s)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:53:08.488843Z","caller":"traceutil/trace.go:171","msg":"trace[2110493199] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:639; }","duration":"1.023364684s","start":"2026-03-26T09:53:07.465461Z","end":"2026-03-26T09:53:08.488825Z","steps":["trace[2110493199] 'agreement among raft nodes before linearized reading' (duration: 1.023013303s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:53:08.488971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:53:07.464489Z","time spent":"1.024465098s","remote":"127.0.0.1:57334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-26T09:53:08.489033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:53:07.465451Z","time spent":"1.02356258s","remote":"127.0.0.1:57334","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-26T09:53:08.48895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.024553781s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-26T09:53:08.489129Z","caller":"traceutil/trace.go:171","msg":"trace[1302768323] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:639; }","duration":"1.024765078s","start":"2026-03-26T09:53:07.464347Z","end":"2026-03-26T09:53:08.489112Z","steps":["trace[1302768323] 'agreement among raft nodes before linearized reading' (duration: 1.02420539s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:53:08.489204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:53:07.464325Z","time spent":"1.02486271s","remote":"127.0.0.1:57334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-26T09:53:10.494609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.460731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T09:53:10.494679Z","caller":"traceutil/trace.go:171","msg":"trace[1523558705] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:658; }","duration":"253.575134ms","start":"2026-03-26T09:53:10.241088Z","end":"2026-03-26T09:53:10.494663Z","steps":["trace[1523558705] 'range keys from in-memory index tree' (duration: 253.344607ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:53:24.117486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"877.997795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T09:53:24.117545Z","caller":"traceutil/trace.go:171","msg":"trace[2055691455] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:800; }","duration":"878.101448ms","start":"2026-03-26T09:53:23.239432Z","end":"2026-03-26T09:53:24.117533Z","steps":["trace[2055691455] 'range keys from in-memory index tree' (duration: 877.85941ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:53:24.117566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:53:23.239412Z","time spent":"878.14973ms","remote":"127.0.0.1:57058","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-26T09:53:24.142614Z","caller":"traceutil/trace.go:171","msg":"trace[2096512017] linearizableReadLoop","detail":"{readStateIndex:832; appliedIndex:831; }","duration":"349.208923ms","start":"2026-03-26T09:53:23.793375Z","end":"2026-03-26T09:53:24.142584Z","steps":["trace[2096512017] 'read index received' (duration: 348.987566ms)","trace[2096512017] 'applied index is now lower than readState.Index' (duration: 220.197µs)"],"step_count":2} {"level":"info","ts":"2026-03-26T09:53:24.142748Z","caller":"traceutil/trace.go:171","msg":"trace[1865345042] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"377.808485ms","start":"2026-03-26T09:53:23.764926Z","end":"2026-03-26T09:53:24.142735Z","steps":["trace[1865345042] 'process raft request' (duration: 377.495556ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:53:24.142865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:53:23.764909Z","time spent":"377.870737ms","remote":"127.0.0.1:57296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T09:53:24.143231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.846922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-26T09:53:24.143293Z","caller":"traceutil/trace.go:171","msg":"trace[970850004] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:801; }","duration":"349.958256ms","start":"2026-03-26T09:53:23.793318Z","end":"2026-03-26T09:53:24.143277Z","steps":["trace[970850004] 'agreement among raft nodes before linearized reading' (duration: 349.856192ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:53:24.143337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:53:23.793296Z","time spent":"350.028578ms","remote":"127.0.0.1:57168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":13,"response size":30,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"info","ts":"2026-03-26T09:54:01.127525Z","caller":"traceutil/trace.go:171","msg":"trace[1952647680] transaction","detail":"{read_only:false; response_revision:1025; number_of_response:1; }","duration":"291.509478ms","start":"2026-03-26T09:54:00.835986Z","end":"2026-03-26T09:54:01.127495Z","steps":["trace[1952647680] 'process raft request' (duration: 272.918546ms)","trace[1952647680] 'compare' (duration: 18.192779ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T09:54:01.567373Z","caller":"traceutil/trace.go:171","msg":"trace[1866582232] transaction","detail":"{read_only:false; response_revision:1026; number_of_response:1; }","duration":"697.219217ms","start":"2026-03-26T09:54:00.87011Z","end":"2026-03-26T09:54:01.56733Z","steps":["trace[1866582232] 'process raft request' (duration: 694.611405ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:54:01.567505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.317326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T09:54:01.567502Z","caller":"traceutil/trace.go:171","msg":"trace[737776744] transaction","detail":"{read_only:false; response_revision:1027; number_of_response:1; }","duration":"695.721741ms","start":"2026-03-26T09:54:00.87174Z","end":"2026-03-26T09:54:01.567462Z","steps":["trace[737776744] 'process raft request' (duration: 695.467173ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:54:01.567554Z","caller":"traceutil/trace.go:171","msg":"trace[1237865423] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1028; }","duration":"326.408919ms","start":"2026-03-26T09:54:01.241131Z","end":"2026-03-26T09:54:01.56754Z","steps":["trace[1237865423] 'agreement among raft nodes before linearized reading' (duration: 326.298596ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:54:01.567591Z","caller":"traceutil/trace.go:171","msg":"trace[708976724] transaction","detail":"{read_only:false; response_revision:1028; number_of_response:1; }","duration":"555.927177ms","start":"2026-03-26T09:54:01.011651Z","end":"2026-03-26T09:54:01.567578Z","steps":["trace[708976724] 'process raft request' (duration: 555.657969ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:54:01.567603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:54:01.241115Z","time spent":"326.480252ms","remote":"127.0.0.1:57060","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-26T09:54:01.567539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:54:00.870089Z","time spent":"697.366091ms","remote":"127.0.0.1:57334","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-26T09:54:01.567664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:54:01.011609Z","time spent":"556.011391ms","remote":"127.0.0.1:57334","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T09:54:01.567704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:54:00.871709Z","time spent":"695.883135ms","remote":"127.0.0.1:57334","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-26T09:54:01.56738Z","caller":"traceutil/trace.go:171","msg":"trace[1860435883] linearizableReadLoop","detail":"{readStateIndex:1068; appliedIndex:1065; }","duration":"326.184582ms","start":"2026-03-26T09:54:01.241172Z","end":"2026-03-26T09:54:01.567357Z","steps":["trace[1860435883] 'read index received' (duration: 323.414686ms)","trace[1860435883] 'applied index is now lower than readState.Index' (duration: 2.768866ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T09:54:02.259675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.712237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T09:54:02.259746Z","caller":"traceutil/trace.go:171","msg":"trace[1071438924] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:1028; }","duration":"482.833291ms","start":"2026-03-26T09:54:01.776891Z","end":"2026-03-26T09:54:02.259725Z","steps":["trace[1071438924] 'count revisions from in-memory index tree' (duration: 482.615865ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:54:02.259785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:54:01.776874Z","time spent":"482.900204ms","remote":"127.0.0.1:57310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":28,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"warn","ts":"2026-03-26T09:54:02.259675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.963354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T09:54:02.259894Z","caller":"traceutil/trace.go:171","msg":"trace[777205315] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:1028; }","duration":"137.202211ms","start":"2026-03-26T09:54:02.122657Z","end":"2026-03-26T09:54:02.259859Z","steps":["trace[777205315] 'count revisions from in-memory index tree' (duration: 136.900511ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:54:27.252637Z","caller":"traceutil/trace.go:171","msg":"trace[1173936144] linearizableReadLoop","detail":"{readStateIndex:1154; appliedIndex:1153; }","duration":"270.895473ms","start":"2026-03-26T09:54:26.981726Z","end":"2026-03-26T09:54:27.252621Z","steps":["trace[1173936144] 'read index received' (duration: 270.78052ms)","trace[1173936144] 'applied index is now lower than readState.Index' (duration: 113.973µs)"],"step_count":2} {"level":"warn","ts":"2026-03-26T09:54:27.252876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.15093ms","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-26T09:54:27.252982Z","caller":"traceutil/trace.go:171","msg":"trace[1968957630] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1109; }","duration":"271.317796ms","start":"2026-03-26T09:54:26.981644Z","end":"2026-03-26T09:54:27.252962Z","steps":["trace[1968957630] 'agreement among raft nodes before linearized reading' (duration: 271.089869ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:54:27.253214Z","caller":"traceutil/trace.go:171","msg":"trace[993224709] transaction","detail":"{read_only:false; response_revision:1109; number_of_response:1; }","duration":"297.929968ms","start":"2026-03-26T09:54:26.95527Z","end":"2026-03-26T09:54:27.2532Z","steps":["trace[993224709] 'process raft request' (duration: 297.234476ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:54:27.914434Z","caller":"traceutil/trace.go:171","msg":"trace[458572809] linearizableReadLoop","detail":"{readStateIndex:1158; appliedIndex:1156; }","duration":"204.546737ms","start":"2026-03-26T09:54:27.709858Z","end":"2026-03-26T09:54:27.914405Z","steps":["trace[458572809] 'read index received' (duration: 63.873108ms)","trace[458572809] 'applied index is now lower than readState.Index' (duration: 140.672539ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T09:54:27.914619Z","caller":"traceutil/trace.go:171","msg":"trace[1474279655] transaction","detail":"{read_only:false; response_revision:1113; number_of_response:1; }","duration":"210.447202ms","start":"2026-03-26T09:54:27.704153Z","end":"2026-03-26T09:54:27.9146Z","steps":["trace[1474279655] 'process raft request' (duration: 171.103471ms)","trace[1474279655] 'compare' (duration: 39.01437ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T09:54:27.91464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.758254ms","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-26T09:54:27.914717Z","caller":"traceutil/trace.go:171","msg":"trace[1816856785] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1113; }","duration":"204.872318ms","start":"2026-03-26T09:54:27.709826Z","end":"2026-03-26T09:54:27.914698Z","steps":["trace[1816856785] 'agreement among raft nodes before linearized reading' (duration: 204.682322ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:54:28.475339Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18387244180467408781,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-26T09:54:28.658338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"689.696601ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-03-26T09:54:28.658658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.875763ms","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-26T09:54:28.658748Z","caller":"traceutil/trace.go:171","msg":"trace[460068611] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1113; }","duration":"232.972506ms","start":"2026-03-26T09:54:28.425762Z","end":"2026-03-26T09:54:28.658734Z","steps":["trace[460068611] 'range keys from in-memory index tree' (duration: 232.862972ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:54:28.975809Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18387244180467408781,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-26T09:54:29.096621Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.127889926s","expected-duration":"1s"} {"level":"info","ts":"2026-03-26T09:54:29.09714Z","caller":"traceutil/trace.go:171","msg":"trace[1720147057] linearizableReadLoop","detail":"{readStateIndex:1160; appliedIndex:1159; }","duration":"1.122152116s","start":"2026-03-26T09:54:27.97492Z","end":"2026-03-26T09:54:29.097073Z","steps":["trace[1720147057] 'read index received' (duration: 1.121882858s)","trace[1720147057] 'applied index is now lower than readState.Index' (duration: 268.158µs)"],"step_count":2} {"level":"info","ts":"2026-03-26T09:54:29.097295Z","caller":"traceutil/trace.go:171","msg":"trace[660296388] transaction","detail":"{read_only:false; response_revision:1114; number_of_response:1; }","duration":"1.17761497s","start":"2026-03-26T09:54:27.919652Z","end":"2026-03-26T09:54:29.097267Z","steps":["trace[660296388] 'process raft request' (duration: 1.177177407s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:54:29.097435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:54:27.919637Z","time spent":"1.177736165s","remote":"127.0.0.1:57334","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-26T09:54:29.097559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.122619331s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T09:54:29.09775Z","caller":"traceutil/trace.go:171","msg":"trace[1009013911] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:1114; }","duration":"1.122857658s","start":"2026-03-26T09:54:27.974874Z","end":"2026-03-26T09:54:29.097732Z","steps":["trace[1009013911] 'agreement among raft nodes before linearized reading' (duration: 1.122340682s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:54:29.097857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:54:27.974854Z","time spent":"1.122990402s","remote":"127.0.0.1:57270","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"info","ts":"2026-03-26T09:54:30.288653Z","caller":"traceutil/trace.go:171","msg":"trace[987562984] transaction","detail":"{read_only:false; response_revision:1120; number_of_response:1; }","duration":"166.26905ms","start":"2026-03-26T09:54:30.12235Z","end":"2026-03-26T09:54:30.288619Z","steps":["trace[987562984] 'process raft request' (duration: 165.923059ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:54:31.242704Z","caller":"traceutil/trace.go:171","msg":"trace[686143645] transaction","detail":"{read_only:false; response_revision:1121; number_of_response:1; }","duration":"135.092696ms","start":"2026-03-26T09:54:31.107591Z","end":"2026-03-26T09:54:31.242684Z","steps":["trace[686143645] 'process raft request' (duration: 134.439475ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:54:31.458318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.774316ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-26T09:54:31.459147Z","caller":"traceutil/trace.go:171","msg":"trace[948133771] transaction","detail":"{read_only:false; response_revision:1122; number_of_response:1; }","duration":"187.844236ms","start":"2026-03-26T09:54:31.271288Z","end":"2026-03-26T09:54:31.459132Z","steps":["trace[948133771] 'process raft request' (duration: 20.166951ms)","trace[948133771] 'compare' (duration: 166.210149ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T09:54:31.459098Z","caller":"traceutil/trace.go:171","msg":"trace[271556888] linearizableReadLoop","detail":"{readStateIndex:1168; appliedIndex:1167; }","duration":"176.026446ms","start":"2026-03-26T09:54:31.283055Z","end":"2026-03-26T09:54:31.459082Z","steps":["trace[271556888] 'read index received' (duration: 8.374702ms)","trace[271556888] 'applied index is now lower than readState.Index' (duration: 167.647404ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T09:54:31.45952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.423918ms","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-26T09:54:31.45961Z","caller":"traceutil/trace.go:171","msg":"trace[403876144] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1122; }","duration":"176.588373ms","start":"2026-03-26T09:54:31.283004Z","end":"2026-03-26T09:54:31.459593Z","steps":["trace[403876144] 'agreement among raft nodes before linearized reading' (duration: 176.324195ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:54:31.459732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.713479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-26T09:54:31.459783Z","caller":"traceutil/trace.go:171","msg":"trace[947950219] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1122; }","duration":"162.799572ms","start":"2026-03-26T09:54:31.296973Z","end":"2026-03-26T09:54:31.459773Z","steps":["trace[947950219] 'agreement among raft nodes before linearized reading' (duration: 162.600275ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:54:42.521561Z","caller":"traceutil/trace.go:171","msg":"trace[1918350617] linearizableReadLoop","detail":"{readStateIndex:1261; appliedIndex:1260; }","duration":"280.07034ms","start":"2026-03-26T09:54:42.241459Z","end":"2026-03-26T09:54:42.52153Z","steps":["trace[1918350617] 'read index received' (duration: 279.871063ms)","trace[1918350617] 'applied index is now lower than readState.Index' (duration: 198.117µs)"],"step_count":2} {"level":"warn","ts":"2026-03-26T09:54:42.521752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.259746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T09:54:42.521791Z","caller":"traceutil/trace.go:171","msg":"trace[922712038] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1211; }","duration":"280.378639ms","start":"2026-03-26T09:54:42.241401Z","end":"2026-03-26T09:54:42.52178Z","steps":["trace[922712038] 'agreement among raft nodes before linearized reading' (duration: 280.254746ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:54:42.522148Z","caller":"traceutil/trace.go:171","msg":"trace[196598626] transaction","detail":"{read_only:false; response_revision:1211; number_of_response:1; }","duration":"408.761625ms","start":"2026-03-26T09:54:42.113371Z","end":"2026-03-26T09:54:42.522133Z","steps":["trace[196598626] 'process raft request' (duration: 407.956229ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:54:42.522289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:54:42.11336Z","time spent":"408.852888ms","remote":"127.0.0.1:57334","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T09:54:42.677383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.13612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.10\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-03-26T09:54:42.677484Z","caller":"traceutil/trace.go:171","msg":"trace[725837974] range","detail":"{range_begin:/registry/masterleases/199.19.213.10; range_end:; response_count:1; response_revision:1211; }","duration":"130.310885ms","start":"2026-03-26T09:54:42.547147Z","end":"2026-03-26T09:54:42.677458Z","steps":["trace[725837974] 'range keys from in-memory index tree' (duration: 129.893983ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:54:43.91332Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18387244180467408998,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-26T09:54:44.413436Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18387244180467408998,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-26T09:54:44.914365Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18387244180467408998,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-26T09:54:45.414833Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18387244180467408998,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-26T09:54:45.853953Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.519551493s","expected-duration":"1s"} {"level":"info","ts":"2026-03-26T09:54:45.854526Z","caller":"traceutil/trace.go:171","msg":"trace[434208384] transaction","detail":"{read_only:false; response_revision:1213; number_of_response:1; }","duration":"2.520332018s","start":"2026-03-26T09:54:43.334162Z","end":"2026-03-26T09:54:45.854494Z","steps":["trace[434208384] 'process raft request' (duration: 2.520127191s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:54:45.854736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:54:43.334136Z","time spent":"2.520495663s","remote":"127.0.0.1:57334","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-26T09:54:45.856865Z","caller":"traceutil/trace.go:171","msg":"trace[160251546] linearizableReadLoop","detail":"{readStateIndex:1266; appliedIndex:1263; }","duration":"2.444409203s","start":"2026-03-26T09:54:43.412428Z","end":"2026-03-26T09:54:45.856837Z","steps":["trace[160251546] 'read index received' (duration: 2.441855303s)","trace[160251546] 'applied index is now lower than readState.Index' (duration: 2.55278ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T09:54:45.857086Z","caller":"traceutil/trace.go:171","msg":"trace[257398923] transaction","detail":"{read_only:false; response_revision:1215; number_of_response:1; }","duration":"1.922617603s","start":"2026-03-26T09:54:43.934427Z","end":"2026-03-26T09:54:45.857045Z","steps":["trace[257398923] 'process raft request' (duration: 1.922349684s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:54:45.857142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.444676912s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T09:54:45.857215Z","caller":"traceutil/trace.go:171","msg":"trace[1040140123] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:1215; }","duration":"2.444839006s","start":"2026-03-26T09:54:43.412355Z","end":"2026-03-26T09:54:45.857195Z","steps":["trace[1040140123] 'agreement among raft nodes before linearized reading' (duration: 2.444667861s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:54:45.857272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:54:43.934405Z","time spent":"1.922747237s","remote":"127.0.0.1:57334","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T09:54:45.85736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.258683435s","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-26T09:54:45.857447Z","caller":"traceutil/trace.go:171","msg":"trace[198526648] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1215; }","duration":"2.258823109s","start":"2026-03-26T09:54:43.598604Z","end":"2026-03-26T09:54:45.857427Z","steps":["trace[198526648] 'agreement among raft nodes before linearized reading' (duration: 2.25855348s)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:54:45.857466Z","caller":"traceutil/trace.go:171","msg":"trace[193189745] transaction","detail":"{read_only:false; response_revision:1214; number_of_response:1; }","duration":"2.017248043s","start":"2026-03-26T09:54:43.840206Z","end":"2026-03-26T09:54:45.857454Z","steps":["trace[193189745] 'process raft request' (duration: 2.016377955s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:54:45.8575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:54:43.598585Z","time spent":"2.258906732s","remote":"127.0.0.1:57334","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-26T09:54:45.857527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:54:43.840185Z","time spent":"2.017311865s","remote":"127.0.0.1:57334","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T09:54:45.857276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:54:43.412321Z","time spent":"2.44494274s","remote":"127.0.0.1:33462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true "} {"level":"warn","ts":"2026-03-26T09:54:45.857695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.320070268s","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-26T09:54:45.857729Z","caller":"traceutil/trace.go:171","msg":"trace[93005708] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1215; }","duration":"1.32014052s","start":"2026-03-26T09:54:44.537579Z","end":"2026-03-26T09:54:45.857719Z","steps":["trace[93005708] 'agreement among raft nodes before linearized reading' (duration: 1.320056197s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:54:45.857784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:54:44.537551Z","time spent":"1.320225893s","remote":"127.0.0.1:57334","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-26T09:54:45.857954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.617469759s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T09:54:45.858029Z","caller":"traceutil/trace.go:171","msg":"trace[480613697] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1215; }","duration":"1.617586173s","start":"2026-03-26T09:54:44.240431Z","end":"2026-03-26T09:54:45.858017Z","steps":["trace[480613697] 'agreement among raft nodes before linearized reading' (duration: 1.61746971s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:54:45.858028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.062106886s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-03-26T09:54:45.85807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:54:44.240411Z","time spent":"1.617652095s","remote":"127.0.0.1:57058","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-26T09:54:45.858077Z","caller":"traceutil/trace.go:171","msg":"trace[11959078] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1215; }","duration":"2.062165898s","start":"2026-03-26T09:54:43.795899Z","end":"2026-03-26T09:54:45.858065Z","steps":["trace[11959078] 'agreement among raft nodes before linearized reading' (duration: 2.061998342s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:54:45.858094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.063284133s","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-26T09:54:45.858116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:54:43.795895Z","time spent":"2.06221438s","remote":"127.0.0.1:57334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-26T09:54:45.858129Z","caller":"traceutil/trace.go:171","msg":"trace[312334305] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1215; }","duration":"2.063349415s","start":"2026-03-26T09:54:43.79477Z","end":"2026-03-26T09:54:45.85812Z","steps":["trace[312334305] 'agreement among raft nodes before linearized reading' (duration: 2.063259163s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:54:45.85829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:54:43.794754Z","time spent":"2.063528621s","remote":"127.0.0.1:57334","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-26T09:55:00.237528Z","caller":"traceutil/trace.go:171","msg":"trace[1158702044] linearizableReadLoop","detail":"{readStateIndex:1321; appliedIndex:1320; }","duration":"310.073878ms","start":"2026-03-26T09:54:59.927425Z","end":"2026-03-26T09:55:00.237499Z","steps":["trace[1158702044] 'read index received' (duration: 309.730677ms)","trace[1158702044] 'applied index is now lower than readState.Index' (duration: 342.221µs)"],"step_count":2} {"level":"info","ts":"2026-03-26T09:55:00.237703Z","caller":"traceutil/trace.go:171","msg":"trace[758791496] transaction","detail":"{read_only:false; response_revision:1267; number_of_response:1; }","duration":"317.770349ms","start":"2026-03-26T09:54:59.919921Z","end":"2026-03-26T09:55:00.237692Z","steps":["trace[758791496] 'process raft request' (duration: 317.369016ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:55:00.237843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:54:59.919886Z","time spent":"317.845841ms","remote":"127.0.0.1:57334","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T09:55:00.23792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.536923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-26T09:55:00.237991Z","caller":"traceutil/trace.go:171","msg":"trace[954170233] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1267; }","duration":"263.655356ms","start":"2026-03-26T09:54:59.974317Z","end":"2026-03-26T09:55:00.237972Z","steps":["trace[954170233] 'agreement among raft nodes before linearized reading' (duration: 263.472841ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:55:00.238024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.592562ms","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-26T09:55:00.238033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.609695ms","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-26T09:55:00.238068Z","caller":"traceutil/trace.go:171","msg":"trace[1904841006] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1267; }","duration":"277.638894ms","start":"2026-03-26T09:54:59.960413Z","end":"2026-03-26T09:55:00.238052Z","steps":["trace[1904841006] 'agreement among raft nodes before linearized reading' (duration: 277.550381ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:55:00.238077Z","caller":"traceutil/trace.go:171","msg":"trace[515719568] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1267; }","duration":"310.687027ms","start":"2026-03-26T09:54:59.927377Z","end":"2026-03-26T09:55:00.238063Z","steps":["trace[515719568] 'agreement among raft nodes before linearized reading' (duration: 310.591694ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:55:00.237926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.427265ms","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-26T09:55:00.238112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:54:59.927361Z","time spent":"310.742679ms","remote":"127.0.0.1:57334","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-26T09:55:00.238134Z","caller":"traceutil/trace.go:171","msg":"trace[1635883799] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1267; }","duration":"291.691343ms","start":"2026-03-26T09:54:59.946429Z","end":"2026-03-26T09:55:00.238121Z","steps":["trace[1635883799] 'agreement among raft nodes before linearized reading' (duration: 291.316191ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:55:00.237934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.418799ms","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-26T09:55:00.238222Z","caller":"traceutil/trace.go:171","msg":"trace[305525878] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1267; }","duration":"263.788891ms","start":"2026-03-26T09:54:59.974418Z","end":"2026-03-26T09:55:00.238207Z","steps":["trace[305525878] 'agreement among raft nodes before linearized reading' (duration: 263.354547ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:55:00.454761Z","caller":"traceutil/trace.go:171","msg":"trace[1360171185] transaction","detail":"{read_only:false; response_revision:1270; number_of_response:1; }","duration":"211.470105ms","start":"2026-03-26T09:55:00.243281Z","end":"2026-03-26T09:55:00.454751Z","steps":["trace[1360171185] 'process raft request' (duration: 211.32941ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:55:00.454939Z","caller":"traceutil/trace.go:171","msg":"trace[339133425] transaction","detail":"{read_only:false; response_revision:1272; number_of_response:1; }","duration":"179.339419ms","start":"2026-03-26T09:55:00.275575Z","end":"2026-03-26T09:55:00.454915Z","steps":["trace[339133425] 'process raft request' (duration: 179.260886ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:55:00.454759Z","caller":"traceutil/trace.go:171","msg":"trace[200913775] transaction","detail":"{read_only:false; response_revision:1269; number_of_response:1; }","duration":"211.494175ms","start":"2026-03-26T09:55:00.243232Z","end":"2026-03-26T09:55:00.454726Z","steps":["trace[200913775] 'process raft request' (duration: 209.508283ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:55:00.455106Z","caller":"traceutil/trace.go:171","msg":"trace[1350109339] transaction","detail":"{read_only:false; response_revision:1271; number_of_response:1; }","duration":"211.684871ms","start":"2026-03-26T09:55:00.243401Z","end":"2026-03-26T09:55:00.455086Z","steps":["trace[1350109339] 'process raft request' (duration: 211.310729ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:55:01.853622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"878.505257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-26T09:55:01.853759Z","caller":"traceutil/trace.go:171","msg":"trace[2055762723] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1272; }","duration":"878.697562ms","start":"2026-03-26T09:55:00.975042Z","end":"2026-03-26T09:55:01.85374Z","steps":["trace[2055762723] 'count revisions from in-memory index tree' (duration: 878.426224ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:55:01.853805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:55:00.975014Z","time spent":"878.779195ms","remote":"127.0.0.1:33618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":30,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"info","ts":"2026-03-26T09:55:01.862433Z","caller":"traceutil/trace.go:171","msg":"trace[944286129] transaction","detail":"{read_only:false; response_revision:1273; number_of_response:1; }","duration":"719.437782ms","start":"2026-03-26T09:55:01.142979Z","end":"2026-03-26T09:55:01.862417Z","steps":["trace[944286129] 'process raft request' (duration: 719.313088ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:55:01.862526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:55:01.142957Z","time spent":"719.513455ms","remote":"127.0.0.1:57334","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-26T09:55:01.905349Z","caller":"traceutil/trace.go:171","msg":"trace[869577594] transaction","detail":"{read_only:false; response_revision:1274; number_of_response:1; }","duration":"703.662859ms","start":"2026-03-26T09:55:01.201653Z","end":"2026-03-26T09:55:01.905316Z","steps":["trace[869577594] 'process raft request' (duration: 703.467352ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:55:01.905573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:55:01.201634Z","time spent":"703.821663ms","remote":"127.0.0.1:57138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":870,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-26T09:55:02.529909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.193514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T09:55:02.530004Z","caller":"traceutil/trace.go:171","msg":"trace[2102429714] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1274; }","duration":"288.366779ms","start":"2026-03-26T09:55:02.241616Z","end":"2026-03-26T09:55:02.529983Z","steps":["trace[2102429714] 'range keys from in-memory index tree' (duration: 288.057299ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:55:02.530334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.646902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T09:55:02.530424Z","caller":"traceutil/trace.go:171","msg":"trace[389617319] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1274; }","duration":"322.757665ms","start":"2026-03-26T09:55:02.207643Z","end":"2026-03-26T09:55:02.5304Z","steps":["trace[389617319] 'count revisions from in-memory index tree' (duration: 322.496177ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:55:02.530335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.92641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-26T09:55:02.530475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:55:02.207621Z","time spent":"322.843867ms","remote":"127.0.0.1:57232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"info","ts":"2026-03-26T09:55:02.530515Z","caller":"traceutil/trace.go:171","msg":"trace[718544847] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:1274; }","duration":"487.195017ms","start":"2026-03-26T09:55:02.043308Z","end":"2026-03-26T09:55:02.530503Z","steps":["trace[718544847] 'count revisions from in-memory index tree' (duration: 486.783555ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:55:02.530561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:55:02.043277Z","time spent":"487.26846ms","remote":"127.0.0.1:54404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":4,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"info","ts":"2026-03-26T09:55:02.530993Z","caller":"traceutil/trace.go:171","msg":"trace[1822538720] transaction","detail":"{read_only:false; response_revision:1275; number_of_response:1; }","duration":"286.894663ms","start":"2026-03-26T09:55:02.244086Z","end":"2026-03-26T09:55:02.530981Z","steps":["trace[1822538720] 'process raft request' (duration: 197.509708ms)","trace[1822538720] 'compare' (duration: 88.492957ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T09:55:02.530946Z","caller":"traceutil/trace.go:171","msg":"trace[1356433108] linearizableReadLoop","detail":"{readStateIndex:1329; appliedIndex:1328; }","duration":"238.880002ms","start":"2026-03-26T09:55:02.292046Z","end":"2026-03-26T09:55:02.530926Z","steps":["trace[1356433108] 'read index received' (duration: 149.593279ms)","trace[1356433108] 'applied index is now lower than readState.Index' (duration: 89.285413ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T09:55:02.531488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.444499ms","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-26T09:55:02.531528Z","caller":"traceutil/trace.go:171","msg":"trace[244869509] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1275; }","duration":"239.518301ms","start":"2026-03-26T09:55:02.291998Z","end":"2026-03-26T09:55:02.531517Z","steps":["trace[244869509] 'agreement among raft nodes before linearized reading' (duration: 239.369266ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:55:02.688994Z","caller":"traceutil/trace.go:171","msg":"trace[187413549] linearizableReadLoop","detail":"{readStateIndex:1334; appliedIndex:1330; }","duration":"141.279819ms","start":"2026-03-26T09:55:02.547688Z","end":"2026-03-26T09:55:02.688968Z","steps":["trace[187413549] 'read index received' (duration: 9.974392ms)","trace[187413549] 'applied index is now lower than readState.Index' (duration: 131.304497ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T09:55:02.689252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.540467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.10\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-03-26T09:55:02.689299Z","caller":"traceutil/trace.go:171","msg":"trace[556379034] range","detail":"{range_begin:/registry/masterleases/199.19.213.10; range_end:; response_count:1; response_revision:1280; }","duration":"141.62203ms","start":"2026-03-26T09:55:02.547664Z","end":"2026-03-26T09:55:02.689286Z","steps":["trace[556379034] 'agreement among raft nodes before linearized reading' (duration: 141.411883ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:55:02.689604Z","caller":"traceutil/trace.go:171","msg":"trace[425438292] transaction","detail":"{read_only:false; response_revision:1280; number_of_response:1; }","duration":"149.94782ms","start":"2026-03-26T09:55:02.539637Z","end":"2026-03-26T09:55:02.689584Z","steps":["trace[425438292] 'process raft request' (duration: 91.696548ms)","trace[425438292] 'compare' (duration: 57.522659ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T09:55:02.94442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.460426ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-26T09:55:02.944578Z","caller":"traceutil/trace.go:171","msg":"trace[984109920] transaction","detail":"{read_only:false; response_revision:1281; number_of_response:1; }","duration":"165.386583ms","start":"2026-03-26T09:55:02.779172Z","end":"2026-03-26T09:55:02.944558Z","steps":["trace[984109920] 'process raft request' (duration: 53.71068ms)","trace[984109920] 'compare' (duration: 111.341992ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T09:55:04.753406Z","caller":"traceutil/trace.go:171","msg":"trace[1865054057] linearizableReadLoop","detail":"{readStateIndex:1337; appliedIndex:1336; }","duration":"192.761809ms","start":"2026-03-26T09:55:04.56062Z","end":"2026-03-26T09:55:04.753382Z","steps":["trace[1865054057] 'read index received' (duration: 192.592404ms)","trace[1865054057] 'applied index is now lower than readState.Index' (duration: 168.345µs)"],"step_count":2} {"level":"info","ts":"2026-03-26T09:55:04.753527Z","caller":"traceutil/trace.go:171","msg":"trace[2046657571] transaction","detail":"{read_only:false; response_revision:1282; number_of_response:1; }","duration":"216.004706ms","start":"2026-03-26T09:55:04.537488Z","end":"2026-03-26T09:55:04.753493Z","steps":["trace[2046657571] 'process raft request' (duration: 215.749158ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:55:04.753642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.779935ms","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-26T09:55:04.753685Z","caller":"traceutil/trace.go:171","msg":"trace[185040711] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1282; }","duration":"118.883318ms","start":"2026-03-26T09:55:04.634788Z","end":"2026-03-26T09:55:04.753671Z","steps":["trace[185040711] 'agreement among raft nodes before linearized reading' (duration: 118.738874ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:55:04.753676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.034397ms","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-26T09:55:04.753769Z","caller":"traceutil/trace.go:171","msg":"trace[1042794501] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1282; }","duration":"193.182652ms","start":"2026-03-26T09:55:04.56057Z","end":"2026-03-26T09:55:04.753753Z","steps":["trace[1042794501] 'agreement among raft nodes before linearized reading' (duration: 192.934774ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:55:04.753851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.329081ms","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-26T09:55:04.753879Z","caller":"traceutil/trace.go:171","msg":"trace[939015442] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1282; }","duration":"118.374652ms","start":"2026-03-26T09:55:04.635497Z","end":"2026-03-26T09:55:04.753871Z","steps":["trace[939015442] 'agreement among raft nodes before linearized reading' (duration: 118.2928ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:55:04.754062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.814946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-26T09:55:04.754113Z","caller":"traceutil/trace.go:171","msg":"trace[623767515] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1282; }","duration":"118.901459ms","start":"2026-03-26T09:55:04.635197Z","end":"2026-03-26T09:55:04.754098Z","steps":["trace[623767515] 'agreement among raft nodes before linearized reading' (duration: 118.706542ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:55:05.657245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"677.938794ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-26T09:55:05.657373Z","caller":"traceutil/trace.go:171","msg":"trace[1715909472] transaction","detail":"{read_only:false; response_revision:1283; number_of_response:1; }","duration":"899.628498ms","start":"2026-03-26T09:55:04.757723Z","end":"2026-03-26T09:55:05.657352Z","steps":["trace[1715909472] 'process raft request' (duration: 221.488447ms)","trace[1715909472] 'compare' (duration: 677.78987ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T09:55:05.657495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:55:04.7577Z","time spent":"899.752782ms","remote":"127.0.0.1:57334","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-26T09:55:05.914538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.594762ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-26T09:55:05.914744Z","caller":"traceutil/trace.go:171","msg":"trace[1517036428] transaction","detail":"{read_only:false; response_revision:1284; number_of_response:1; }","duration":"1.15644653s","start":"2026-03-26T09:55:04.758267Z","end":"2026-03-26T09:55:05.914714Z","steps":["trace[1517036428] 'process raft request' (duration: 1.03558778s)","trace[1517036428] 'compare' (duration: 120.471638ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T09:55:05.914824Z","caller":"traceutil/trace.go:171","msg":"trace[466676676] transaction","detail":"{read_only:false; response_revision:1286; number_of_response:1; }","duration":"1.155973445s","start":"2026-03-26T09:55:04.758831Z","end":"2026-03-26T09:55:05.914804Z","steps":["trace[466676676] 'process raft request' (duration: 1.155890542s)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:55:05.914868Z","caller":"traceutil/trace.go:171","msg":"trace[691628097] transaction","detail":"{read_only:false; response_revision:1285; number_of_response:1; }","duration":"1.156070869s","start":"2026-03-26T09:55:04.758753Z","end":"2026-03-26T09:55:05.914823Z","steps":["trace[691628097] 'process raft request' (duration: 1.155912174s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:55:05.9149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:55:04.75881Z","time spent":"1.156056198s","remote":"127.0.0.1:57334","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T09:55:05.914912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:55:04.75824Z","time spent":"1.156566374s","remote":"127.0.0.1:57334","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-26T09:55:05.91497Z","caller":"traceutil/trace.go:171","msg":"trace[1562709000] transaction","detail":"{read_only:false; response_revision:1287; number_of_response:1; }","duration":"1.154001694s","start":"2026-03-26T09:55:04.76095Z","end":"2026-03-26T09:55:05.914952Z","steps":["trace[1562709000] 'process raft request' (duration: 1.153805387s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:55:05.915045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:55:04.76094Z","time spent":"1.154069095s","remote":"127.0.0.1:57334","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-26T09:55:05.915049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:55:04.758733Z","time spent":"1.156217343s","remote":"127.0.0.1:57334","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T09:55:07.252774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.930391ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-26T09:55:07.252979Z","caller":"traceutil/trace.go:171","msg":"trace[2019192832] linearizableReadLoop","detail":"{readStateIndex:1347; appliedIndex:1345; }","duration":"441.827069ms","start":"2026-03-26T09:55:06.811137Z","end":"2026-03-26T09:55:07.252964Z","steps":["trace[2019192832] 'read index received' (duration: 8.658341ms)","trace[2019192832] 'applied index is now lower than readState.Index' (duration: 433.167938ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T09:55:07.253067Z","caller":"traceutil/trace.go:171","msg":"trace[1269483874] transaction","detail":"{read_only:false; response_revision:1291; number_of_response:1; }","duration":"846.147585ms","start":"2026-03-26T09:55:06.406909Z","end":"2026-03-26T09:55:07.253056Z","steps":["trace[1269483874] 'process raft request' (duration: 412.874453ms)","trace[1269483874] 'compare' (duration: 432.806437ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T09:55:07.253112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.672486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-26T09:55:07.253123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:55:06.406895Z","time spent":"846.203416ms","remote":"127.0.0.1:57138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":823,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-26T09:55:07.253142Z","caller":"traceutil/trace.go:171","msg":"trace[1827500333] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1292; }","duration":"294.747508ms","start":"2026-03-26T09:55:06.958388Z","end":"2026-03-26T09:55:07.253135Z","steps":["trace[1827500333] 'agreement among raft nodes before linearized reading' (duration: 294.684096ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:55:07.253373Z","caller":"traceutil/trace.go:171","msg":"trace[1150206124] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"493.874027ms","start":"2026-03-26T09:55:06.759471Z","end":"2026-03-26T09:55:07.253345Z","steps":["trace[1150206124] 'process raft request' (duration: 493.426343ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:55:07.253552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:55:06.759447Z","time spent":"494.023452ms","remote":"127.0.0.1:57334","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T09:55:07.254047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.909873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5330"} {"level":"info","ts":"2026-03-26T09:55:07.254073Z","caller":"traceutil/trace.go:171","msg":"trace[1781157000] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1292; }","duration":"442.977346ms","start":"2026-03-26T09:55:06.81109Z","end":"2026-03-26T09:55:07.254067Z","steps":["trace[1781157000] 'agreement among raft nodes before linearized reading' (duration: 442.871812ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:55:07.25409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:55:06.81105Z","time spent":"443.036467ms","remote":"127.0.0.1:57240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":5353,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-03-26T09:55:07.933314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.485511ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-26T09:55:07.933453Z","caller":"traceutil/trace.go:171","msg":"trace[1386257850] transaction","detail":"{read_only:false; response_revision:1293; number_of_response:1; }","duration":"672.438581ms","start":"2026-03-26T09:55:07.260981Z","end":"2026-03-26T09:55:07.93342Z","steps":["trace[1386257850] 'process raft request' (duration: 400.776605ms)","trace[1386257850] 'compare' (duration: 271.318255ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T09:55:07.933589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:55:07.260959Z","time spent":"672.557005ms","remote":"127.0.0.1:57240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5456,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-26T09:55:07.933829Z","caller":"traceutil/trace.go:171","msg":"trace[533313566] linearizableReadLoop","detail":"{readStateIndex:1348; appliedIndex:1347; }","duration":"270.905442ms","start":"2026-03-26T09:55:07.662892Z","end":"2026-03-26T09:55:07.933797Z","steps":["trace[533313566] 'read index received' (duration: 42.972µs)","trace[533313566] 'applied index is now lower than readState.Index' (duration: 270.8585ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T09:55:07.965338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.435278ms","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-26T09:55:07.965408Z","caller":"traceutil/trace.go:171","msg":"trace[1667723738] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1293; }","duration":"302.538852ms","start":"2026-03-26T09:55:07.662852Z","end":"2026-03-26T09:55:07.965391Z","steps":["trace[1667723738] 'agreement among raft nodes before linearized reading' (duration: 271.106608ms)","trace[1667723738] 'range keys from in-memory index tree' (duration: 31.255597ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T09:55:07.965447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:55:07.662836Z","time spent":"302.603344ms","remote":"127.0.0.1:57334","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-26T09:55:08.3457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.70021ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-26T09:55:08.345857Z","caller":"traceutil/trace.go:171","msg":"trace[1313034998] transaction","detail":"{read_only:false; response_revision:1295; number_of_response:1; }","duration":"376.809246ms","start":"2026-03-26T09:55:07.969022Z","end":"2026-03-26T09:55:08.345832Z","steps":["trace[1313034998] 'process raft request' (duration: 244.90241ms)","trace[1313034998] 'compare' (duration: 131.495743ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T09:55:08.345992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:55:07.969001Z","time spent":"376.905679ms","remote":"127.0.0.1:57334","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-26T09:55:08.346157Z","caller":"traceutil/trace.go:171","msg":"trace[1451718565] linearizableReadLoop","detail":"{readStateIndex:1355; appliedIndex:1350; }","duration":"218.174614ms","start":"2026-03-26T09:55:08.127968Z","end":"2026-03-26T09:55:08.346142Z","steps":["trace[1451718565] 'read index received' (duration: 85.890827ms)","trace[1451718565] 'applied index is now lower than readState.Index' (duration: 132.282207ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T09:55:08.346338Z","caller":"traceutil/trace.go:171","msg":"trace[1185976297] transaction","detail":"{read_only:false; response_revision:1297; number_of_response:1; }","duration":"376.246738ms","start":"2026-03-26T09:55:07.97008Z","end":"2026-03-26T09:55:08.346327Z","steps":["trace[1185976297] 'process raft request' (duration: 375.766113ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:55:08.346369Z","caller":"traceutil/trace.go:171","msg":"trace[2014744435] transaction","detail":"{read_only:false; response_revision:1298; number_of_response:1; }","duration":"375.807254ms","start":"2026-03-26T09:55:07.970549Z","end":"2026-03-26T09:55:08.346356Z","steps":["trace[2014744435] 'process raft request' (duration: 375.332639ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:55:08.3465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:55:07.970536Z","time spent":"375.918308ms","remote":"127.0.0.1:57334","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-26T09:55:08.346569Z","caller":"traceutil/trace.go:171","msg":"trace[2062342194] transaction","detail":"{read_only:false; response_revision:1296; number_of_response:1; }","duration":"376.95425ms","start":"2026-03-26T09:55:07.969603Z","end":"2026-03-26T09:55:08.346557Z","steps":["trace[2062342194] 'process raft request' (duration: 376.186936ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:55:08.34663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:55:07.96959Z","time spent":"377.013142ms","remote":"127.0.0.1:57334","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-26T09:55:08.346665Z","caller":"traceutil/trace.go:171","msg":"trace[1458046840] transaction","detail":"{read_only:false; response_revision:1299; number_of_response:1; }","duration":"375.926538ms","start":"2026-03-26T09:55:07.970728Z","end":"2026-03-26T09:55:08.346655Z","steps":["trace[1458046840] 'process raft request' (duration: 375.219146ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:55:08.34672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:55:07.970719Z","time spent":"375.972829ms","remote":"127.0.0.1:57334","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-26T09:55:08.346821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.852965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-26T09:55:08.346855Z","caller":"traceutil/trace.go:171","msg":"trace[448238708] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:1299; }","duration":"218.916237ms","start":"2026-03-26T09:55:08.127928Z","end":"2026-03-26T09:55:08.346845Z","steps":["trace[448238708] 'agreement among raft nodes before linearized reading' (duration: 218.862256ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:55:08.347019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.712508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T09:55:08.347075Z","caller":"traceutil/trace.go:171","msg":"trace[960649627] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1299; }","duration":"107.780741ms","start":"2026-03-26T09:55:08.239262Z","end":"2026-03-26T09:55:08.347043Z","steps":["trace[960649627] 'agreement among raft nodes before linearized reading' (duration: 107.73443ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:55:08.346419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:55:07.970069Z","time spent":"376.30501ms","remote":"127.0.0.1:57334","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T09:55:18.744432Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18387244180467409333,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-26T09:55:19.245309Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18387244180467409333,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-26T09:55:19.488514Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.261388211s","expected-duration":"1s"} {"level":"warn","ts":"2026-03-26T09:55:19.886073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.07634ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-26T09:55:19.886575Z","caller":"traceutil/trace.go:171","msg":"trace[1324321856] linearizableReadLoop","detail":"{readStateIndex:1434; appliedIndex:1433; }","duration":"1.642931165s","start":"2026-03-26T09:55:18.243615Z","end":"2026-03-26T09:55:19.886546Z","steps":["trace[1324321856] 'read index received' (duration: 1.245464953s)","trace[1324321856] 'applied index is now lower than readState.Index' (duration: 397.463182ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T09:55:19.886874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.643237324s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T09:55:19.886966Z","caller":"traceutil/trace.go:171","msg":"trace[1962950905] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1376; }","duration":"1.643377128s","start":"2026-03-26T09:55:18.243564Z","end":"2026-03-26T09:55:19.886941Z","steps":["trace[1962950905] 'agreement among raft nodes before linearized reading' (duration: 1.643181932s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:55:19.887019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:55:18.243541Z","time spent":"1.643468251s","remote":"127.0.0.1:57058","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-26T09:55:19.887231Z","caller":"traceutil/trace.go:171","msg":"trace[717586035] transaction","detail":"{read_only:false; response_revision:1377; number_of_response:1; }","duration":"581.947132ms","start":"2026-03-26T09:55:19.305272Z","end":"2026-03-26T09:55:19.887219Z","steps":["trace[717586035] 'process raft request' (duration: 580.989552ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:55:19.887349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:55:19.305244Z","time spent":"582.016654ms","remote":"127.0.0.1:57334","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T09:55:19.896055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.950677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-26T09:55:19.896134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.280861472s","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-26T09:55:19.896246Z","caller":"traceutil/trace.go:171","msg":"trace[897450143] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1377; }","duration":"1.281021736s","start":"2026-03-26T09:55:18.615197Z","end":"2026-03-26T09:55:19.896219Z","steps":["trace[897450143] 'agreement among raft nodes before linearized reading' (duration: 1.272033255s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:55:19.896265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.495766292s","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-26T09:55:19.896268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.495858795s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-03-26T09:55:19.896256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.496305048s","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-26T09:55:19.896304Z","caller":"traceutil/trace.go:171","msg":"trace[1719190616] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1377; }","duration":"1.495832024s","start":"2026-03-26T09:55:18.400462Z","end":"2026-03-26T09:55:19.896294Z","steps":["trace[1719190616] 'agreement among raft nodes before linearized reading' (duration: 1.486792011s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:55:19.896309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:55:18.615175Z","time spent":"1.28110964s","remote":"127.0.0.1:57334","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":"info","ts":"2026-03-26T09:55:19.896314Z","caller":"traceutil/trace.go:171","msg":"trace[41685177] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1377; }","duration":"1.495928107s","start":"2026-03-26T09:55:18.400373Z","end":"2026-03-26T09:55:19.896301Z","steps":["trace[41685177] 'agreement among raft nodes before linearized reading' (duration: 1.486889374s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:55:19.896329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:55:18.400445Z","time spent":"1.495880016s","remote":"127.0.0.1:57334","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-26T09:55:19.896341Z","caller":"traceutil/trace.go:171","msg":"trace[1055017467] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1377; }","duration":"1.496468094s","start":"2026-03-26T09:55:18.399853Z","end":"2026-03-26T09:55:19.896321Z","steps":["trace[1055017467] 'agreement among raft nodes before linearized reading' (duration: 1.487373839s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:55:19.896358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:55:18.400358Z","time spent":"1.495989679s","remote":"127.0.0.1:57334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-26T09:55:19.896448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:55:18.399825Z","time spent":"1.496568957s","remote":"127.0.0.1:57334","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-26T09:55:19.89649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.8433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-03-26T09:55:19.896511Z","caller":"traceutil/trace.go:171","msg":"trace[1795295777] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1377; }","duration":"308.912782ms","start":"2026-03-26T09:55:19.587592Z","end":"2026-03-26T09:55:19.896504Z","steps":["trace[1795295777] 'agreement among raft nodes before linearized reading' (duration: 299.676873ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:55:19.896516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"780.727769ms","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:530"} {"level":"warn","ts":"2026-03-26T09:55:19.896529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:55:19.58757Z","time spent":"308.954283ms","remote":"127.0.0.1:57334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":497,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-03-26T09:55:19.896545Z","caller":"traceutil/trace.go:171","msg":"trace[617966763] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1377; }","duration":"780.795901ms","start":"2026-03-26T09:55:19.11574Z","end":"2026-03-26T09:55:19.896535Z","steps":["trace[617966763] 'agreement among raft nodes before linearized reading' (duration: 771.540532ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:55:19.896573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:55:19.115696Z","time spent":"780.870883ms","remote":"127.0.0.1:57334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":553,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-03-26T09:55:19.896591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.496620249s","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-26T09:55:19.896612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.496682661s","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-26T09:55:19.896639Z","caller":"traceutil/trace.go:171","msg":"trace[1560724567] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1377; }","duration":"1.496761023s","start":"2026-03-26T09:55:18.399871Z","end":"2026-03-26T09:55:19.896633Z","steps":["trace[1560724567] 'agreement among raft nodes before linearized reading' (duration: 1.487411551s)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:55:19.896643Z","caller":"traceutil/trace.go:171","msg":"trace[1880834719] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1377; }","duration":"1.49667665s","start":"2026-03-26T09:55:18.399943Z","end":"2026-03-26T09:55:19.89662Z","steps":["trace[1880834719] 'agreement among raft nodes before linearized reading' (duration: 1.487325867s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:55:19.896655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:55:18.399843Z","time spent":"1.496807855s","remote":"127.0.0.1:57334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-26T09:55:19.896647Z","caller":"traceutil/trace.go:171","msg":"trace[1714487433] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:1377; }","duration":"140.167064ms","start":"2026-03-26T09:55:19.756Z","end":"2026-03-26T09:55:19.896167Z","steps":["trace[1714487433] 'agreement among raft nodes before linearized reading' (duration: 131.257775ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:55:19.896676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:55:18.399935Z","time spent":"1.496733813s","remote":"127.0.0.1:57334","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-26T09:56:23.997942Z","caller":"traceutil/trace.go:171","msg":"trace[755751060] transaction","detail":"{read_only:false; response_revision:1673; number_of_response:1; }","duration":"135.053066ms","start":"2026-03-26T09:56:23.86286Z","end":"2026-03-26T09:56:23.997914Z","steps":["trace[755751060] 'process raft request' (duration: 134.88914ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:58:35.151918Z","caller":"traceutil/trace.go:171","msg":"trace[1434168844] linearizableReadLoop","detail":"{readStateIndex:2395; appliedIndex:2394; }","duration":"184.451033ms","start":"2026-03-26T09:58:34.967432Z","end":"2026-03-26T09:58:35.151883Z","steps":["trace[1434168844] 'read index received' (duration: 184.143793ms)","trace[1434168844] 'applied index is now lower than readState.Index' (duration: 306.13µs)"],"step_count":2} {"level":"info","ts":"2026-03-26T09:58:35.151981Z","caller":"traceutil/trace.go:171","msg":"trace[539474914] transaction","detail":"{read_only:false; response_revision:2297; number_of_response:1; }","duration":"292.345851ms","start":"2026-03-26T09:58:34.85959Z","end":"2026-03-26T09:58:35.151936Z","steps":["trace[539474914] 'process raft request' (duration: 292.066143ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:58:35.152283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.813984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-03-26T09:58:35.152338Z","caller":"traceutil/trace.go:171","msg":"trace[374422738] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2297; }","duration":"184.938398ms","start":"2026-03-26T09:58:34.967387Z","end":"2026-03-26T09:58:35.152326Z","steps":["trace[374422738] 'agreement among raft nodes before linearized reading' (duration: 184.68008ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:58:35.152379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.759093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-03-26T09:58:35.152525Z","caller":"traceutil/trace.go:171","msg":"trace[675171842] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2297; }","duration":"184.961448ms","start":"2026-03-26T09:58:34.967525Z","end":"2026-03-26T09:58:35.152486Z","steps":["trace[675171842] 'agreement among raft nodes before linearized reading' (duration: 184.643798ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:58:35.152567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.066299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-03-26T09:58:35.152423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.603664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-03-26T09:58:35.152683Z","caller":"traceutil/trace.go:171","msg":"trace[709389737] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2297; }","duration":"180.931255ms","start":"2026-03-26T09:58:34.971741Z","end":"2026-03-26T09:58:35.152672Z","steps":["trace[709389737] 'agreement among raft nodes before linearized reading' (duration: 180.518182ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:58:35.152683Z","caller":"traceutil/trace.go:171","msg":"trace[1380698433] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2297; }","duration":"168.199993ms","start":"2026-03-26T09:58:34.98446Z","end":"2026-03-26T09:58:35.15266Z","steps":["trace[1380698433] 'agreement among raft nodes before linearized reading' (duration: 167.784221ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:58:35.153248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.868472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-03-26T09:58:35.153341Z","caller":"traceutil/trace.go:171","msg":"trace[1251877609] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2297; }","duration":"178.996785ms","start":"2026-03-26T09:58:34.974323Z","end":"2026-03-26T09:58:35.15332Z","steps":["trace[1251877609] 'agreement among raft nodes before linearized reading' (duration: 177.883121ms)"],"step_count":1}