{"level":"warn","ts":"2026-04-14T06:17:45.87297Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-14T06:17:45.873048Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.138:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.138:2380","--initial-cluster=instance=https://199.204.45.138:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.138:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.138:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-14T06:17:45.873117Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-14T06:17:45.873129Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.138:2380"]} {"level":"info","ts":"2026-04-14T06:17:45.873144Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-14T06:17:45.873791Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.138:2379"]} {"level":"info","ts":"2026-04-14T06:17:45.873876Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.138:2380"],"listen-peer-urls":["https://199.204.45.138:2380"],"advertise-client-urls":["https://199.204.45.138:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.138:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.138:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-14T06:17:45.881318Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.560015ms"} {"level":"info","ts":"2026-04-14T06:17:45.890966Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f3d52791b64f7591","cluster-id":"90076b89649fe294"} {"level":"info","ts":"2026-04-14T06:17:45.891051Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 switched to configuration voters=()"} {"level":"info","ts":"2026-04-14T06:17:45.891073Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became follower at term 0"} {"level":"info","ts":"2026-04-14T06:17:45.891083Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f3d52791b64f7591 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-14T06:17:45.891093Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became follower at term 1"} {"level":"info","ts":"2026-04-14T06:17:45.891134Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 switched to configuration voters=(17569993028038260113)"} {"level":"warn","ts":"2026-04-14T06:17:45.905969Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-14T06:17:45.910068Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-14T06:17:45.911331Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-14T06:17:45.915872Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f3d52791b64f7591","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-14T06:17:45.916735Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f3d52791b64f7591","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-14T06:17:45.916798Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-14T06:17:45.916822Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-14T06:17:45.916828Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-14T06:17:45.924031Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 switched to configuration voters=(17569993028038260113)"} {"level":"info","ts":"2026-04-14T06:17:45.924348Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"90076b89649fe294","local-member-id":"f3d52791b64f7591","added-peer-id":"f3d52791b64f7591","added-peer-peer-urls":["https://199.204.45.138:2380"]} {"level":"info","ts":"2026-04-14T06:17:45.924768Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-14T06:17:45.924974Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f3d52791b64f7591","initial-advertise-peer-urls":["https://199.204.45.138:2380"],"listen-peer-urls":["https://199.204.45.138:2380"],"advertise-client-urls":["https://199.204.45.138:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.138:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-14T06:17:45.925004Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-14T06:17:45.9251Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.138:2380"} {"level":"info","ts":"2026-04-14T06:17:45.925114Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.138:2380"} {"level":"info","ts":"2026-04-14T06:17:46.692592Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 is starting a new election at term 1"} {"level":"info","ts":"2026-04-14T06:17:46.692645Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-14T06:17:46.692668Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 received MsgPreVoteResp from f3d52791b64f7591 at term 1"} {"level":"info","ts":"2026-04-14T06:17:46.692685Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became candidate at term 2"} {"level":"info","ts":"2026-04-14T06:17:46.692696Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 received MsgVoteResp from f3d52791b64f7591 at term 2"} {"level":"info","ts":"2026-04-14T06:17:46.692711Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became leader at term 2"} {"level":"info","ts":"2026-04-14T06:17:46.692724Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f3d52791b64f7591 elected leader f3d52791b64f7591 at term 2"} {"level":"info","ts":"2026-04-14T06:17:46.698382Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f3d52791b64f7591","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.138:2379]}","request-path":"/0/members/f3d52791b64f7591/attributes","cluster-id":"90076b89649fe294","publish-timeout":"7s"} {"level":"info","ts":"2026-04-14T06:17:46.698572Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-14T06:17:46.698672Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-14T06:17:46.699092Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-14T06:17:46.701275Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-14T06:17:46.703249Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-14T06:17:46.703307Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-14T06:17:46.703957Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"90076b89649fe294","local-member-id":"f3d52791b64f7591","cluster-version":"3.5"} {"level":"info","ts":"2026-04-14T06:17:46.704075Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-14T06:17:46.70412Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-14T06:17:46.712841Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.138:2379"} {"level":"warn","ts":"2026-04-14T06:18:01.926888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.123255ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T06:18:01.927048Z","caller":"traceutil/trace.go:171","msg":"trace[840917663] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:366; }","duration":"128.32074ms","start":"2026-04-14T06:18:01.798711Z","end":"2026-04-14T06:18:01.927032Z","steps":["trace[840917663] 'range keys from in-memory index tree' (duration: 128.042533ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:18:02.766092Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471725592835171937,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-14T06:18:03.092705Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.010731422s","expected-duration":"1s"} {"level":"info","ts":"2026-04-14T06:18:03.093196Z","caller":"traceutil/trace.go:171","msg":"trace[942733972] linearizableReadLoop","detail":"{readStateIndex:386; appliedIndex:385; }","duration":"828.119333ms","start":"2026-04-14T06:18:02.265056Z","end":"2026-04-14T06:18:03.093175Z","steps":["trace[942733972] 'read index received' (duration: 828.093582ms)","trace[942733972] 'applied index is now lower than readState.Index' (duration: 24.801µs)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:18:03.093253Z","caller":"traceutil/trace.go:171","msg":"trace[14400850] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"1.158545925s","start":"2026-04-14T06:18:01.934665Z","end":"2026-04-14T06:18:03.093211Z","steps":["trace[14400850] 'process raft request' (duration: 1.158394902s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:18:03.093408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"680.159005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-14T06:18:03.093467Z","caller":"traceutil/trace.go:171","msg":"trace[2013048095] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:368; }","duration":"680.279338ms","start":"2026-04-14T06:18:02.413173Z","end":"2026-04-14T06:18:03.093453Z","steps":["trace[2013048095] 'agreement among raft nodes before linearized reading' (duration: 680.128415ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:18:03.09344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"828.357178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-04-14T06:18:03.093513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:18:02.413158Z","time spent":"680.34601ms","remote":"127.0.0.1:42820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-14T06:18:03.093608Z","caller":"traceutil/trace.go:171","msg":"trace[1338595104] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:368; }","duration":"828.532012ms","start":"2026-04-14T06:18:02.26501Z","end":"2026-04-14T06:18:03.093542Z","steps":["trace[1338595104] 'agreement among raft nodes before linearized reading' (duration: 828.230775ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:18:03.093696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:18:02.26499Z","time spent":"828.665545ms","remote":"127.0.0.1:42820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-14T06:18:03.093916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:18:01.934651Z","time spent":"1.15868833s","remote":"127.0.0.1:42738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5321,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T06:18:03.220178Z","caller":"traceutil/trace.go:171","msg":"trace[2146899256] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"121.14579ms","start":"2026-04-14T06:18:03.099008Z","end":"2026-04-14T06:18:03.220154Z","steps":["trace[2146899256] 'process raft request' (duration: 120.962365ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:18:03.226542Z","caller":"traceutil/trace.go:171","msg":"trace[463275950] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"123.544706ms","start":"2026-04-14T06:18:03.102984Z","end":"2026-04-14T06:18:03.226529Z","steps":["trace[463275950] 'process raft request' (duration: 123.315731ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:18:03.226757Z","caller":"traceutil/trace.go:171","msg":"trace[1399449886] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"127.08655ms","start":"2026-04-14T06:18:03.099662Z","end":"2026-04-14T06:18:03.226749Z","steps":["trace[1399449886] 'process raft request' (duration: 126.506627ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:18:03.226967Z","caller":"traceutil/trace.go:171","msg":"trace[32332029] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"119.561682ms","start":"2026-04-14T06:18:03.107378Z","end":"2026-04-14T06:18:03.226939Z","steps":["trace[32332029] 'process raft request' (duration: 118.966258ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:18:13.812036Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471725592835172009,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-14T06:18:13.876452Z","caller":"traceutil/trace.go:171","msg":"trace[214152329] transaction","detail":"{read_only:false; response_revision:394; number_of_response:1; }","duration":"577.6889ms","start":"2026-04-14T06:18:13.29874Z","end":"2026-04-14T06:18:13.876429Z","steps":["trace[214152329] 'process raft request' (duration: 577.561407ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:18:13.876599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:18:13.29872Z","time spent":"577.803562ms","remote":"127.0.0.1:42820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T06:18:14.436454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.133099ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-14T06:18:14.436779Z","caller":"traceutil/trace.go:171","msg":"trace[1133149584] transaction","detail":"{read_only:false; response_revision:395; number_of_response:1; }","duration":"1.137473535s","start":"2026-04-14T06:18:13.299209Z","end":"2026-04-14T06:18:14.436683Z","steps":["trace[1133149584] 'process raft request' (duration: 954.701911ms)","trace[1133149584] 'compare' (duration: 181.308259ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T06:18:14.436863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.124970427s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-14T06:18:14.436934Z","caller":"traceutil/trace.go:171","msg":"trace[639399890] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:395; }","duration":"1.12508058s","start":"2026-04-14T06:18:13.311835Z","end":"2026-04-14T06:18:14.436916Z","steps":["trace[639399890] 'agreement among raft nodes before linearized reading' (duration: 1.124932607s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:18:14.436982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:18:13.311818Z","time spent":"1.125154542s","remote":"127.0.0.1:42820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-14T06:18:14.436974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:18:13.299202Z","time spent":"1.13771569s","remote":"127.0.0.1:42820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T06:18:14.436643Z","caller":"traceutil/trace.go:171","msg":"trace[211409741] linearizableReadLoop","detail":"{readStateIndex:415; appliedIndex:413; }","duration":"1.124755152s","start":"2026-04-14T06:18:13.311866Z","end":"2026-04-14T06:18:14.436622Z","steps":["trace[211409741] 'read index received' (duration: 564.531027ms)","trace[211409741] 'applied index is now lower than readState.Index' (duration: 560.222075ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T06:18:14.437434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"734.903866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-14T06:18:14.437472Z","caller":"traceutil/trace.go:171","msg":"trace[459638254] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:395; }","duration":"734.978638ms","start":"2026-04-14T06:18:13.702483Z","end":"2026-04-14T06:18:14.437461Z","steps":["trace[459638254] 'agreement among raft nodes before linearized reading' (duration: 734.913067ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:18:14.437501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:18:13.702461Z","time spent":"735.03376ms","remote":"127.0.0.1:42820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-14T06:18:14.437654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.170047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T06:18:14.437688Z","caller":"traceutil/trace.go:171","msg":"trace[305453963] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:395; }","duration":"245.237738ms","start":"2026-04-14T06:18:14.192442Z","end":"2026-04-14T06:18:14.437679Z","steps":["trace[305453963] 'agreement among raft nodes before linearized reading' (duration: 245.192247ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:18:26.420389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.60077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T06:18:26.420479Z","caller":"traceutil/trace.go:171","msg":"trace[730279972] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:422; }","duration":"230.745914ms","start":"2026-04-14T06:18:26.189715Z","end":"2026-04-14T06:18:26.420461Z","steps":["trace[730279972] 'range keys from in-memory index tree' (duration: 230.487568ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:18:26.843692Z","caller":"traceutil/trace.go:171","msg":"trace[1539110705] linearizableReadLoop","detail":"{readStateIndex:446; appliedIndex:445; }","duration":"332.289838ms","start":"2026-04-14T06:18:26.511377Z","end":"2026-04-14T06:18:26.843667Z","steps":["trace[1539110705] 'read index received' (duration: 332.172165ms)","trace[1539110705] 'applied index is now lower than readState.Index' (duration: 116.353µs)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:18:26.843808Z","caller":"traceutil/trace.go:171","msg":"trace[1305934304] transaction","detail":"{read_only:false; response_revision:423; number_of_response:1; }","duration":"335.334091ms","start":"2026-04-14T06:18:26.508416Z","end":"2026-04-14T06:18:26.84375Z","steps":["trace[1305934304] 'process raft request' (duration: 335.097725ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:18:26.84387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.295618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-14T06:18:26.843914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.506223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-14T06:18:26.844145Z","caller":"traceutil/trace.go:171","msg":"trace[1811779010] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:423; }","duration":"223.635716ms","start":"2026-04-14T06:18:26.620494Z","end":"2026-04-14T06:18:26.84413Z","steps":["trace[1811779010] 'agreement among raft nodes before linearized reading' (duration: 223.307598ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:18:26.844208Z","caller":"traceutil/trace.go:171","msg":"trace[258057265] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:423; }","duration":"332.818311ms","start":"2026-04-14T06:18:26.511352Z","end":"2026-04-14T06:18:26.84417Z","steps":["trace[258057265] 'agreement among raft nodes before linearized reading' (duration: 332.411841ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:18:26.844326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:18:26.511346Z","time spent":"332.944473ms","remote":"127.0.0.1:42820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-14T06:18:26.844296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:18:26.508399Z","time spent":"335.667878ms","remote":"127.0.0.1:42820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T06:18:27.154176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.677238ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-14T06:18:27.154292Z","caller":"traceutil/trace.go:171","msg":"trace[1258425594] transaction","detail":"{read_only:false; response_revision:424; number_of_response:1; }","duration":"305.354238ms","start":"2026-04-14T06:18:26.84892Z","end":"2026-04-14T06:18:27.154274Z","steps":["trace[1258425594] 'process raft request' (duration: 184.473665ms)","trace[1258425594] 'compare' (duration: 120.541845ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T06:18:27.154357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:18:26.848893Z","time spent":"305.435159ms","remote":"127.0.0.1:42820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T06:18:27.183346Z","caller":"traceutil/trace.go:171","msg":"trace[1295253980] transaction","detail":"{read_only:false; response_revision:426; number_of_response:1; }","duration":"324.879082ms","start":"2026-04-14T06:18:26.858429Z","end":"2026-04-14T06:18:27.183308Z","steps":["trace[1295253980] 'process raft request' (duration: 324.79555ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:18:27.183412Z","caller":"traceutil/trace.go:171","msg":"trace[363341804] transaction","detail":"{read_only:false; response_revision:425; number_of_response:1; }","duration":"333.719112ms","start":"2026-04-14T06:18:26.849633Z","end":"2026-04-14T06:18:27.183352Z","steps":["trace[363341804] 'process raft request' (duration: 333.412015ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:18:27.183699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:18:26.849613Z","time spent":"333.977778ms","remote":"127.0.0.1:42820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T06:18:27.183936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:18:26.858411Z","time spent":"325.062636ms","remote":"127.0.0.1:43000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4180,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T06:18:27.851906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.734585ms","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-04-14T06:18:27.85215Z","caller":"traceutil/trace.go:171","msg":"trace[1275312834] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:426; }","duration":"347.973751ms","start":"2026-04-14T06:18:27.504139Z","end":"2026-04-14T06:18:27.852113Z","steps":["trace[1275312834] 'range keys from in-memory index tree' (duration: 347.720505ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:18:27.852198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.96107ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-14T06:18:27.852256Z","caller":"traceutil/trace.go:171","msg":"trace[1253602699] transaction","detail":"{read_only:false; response_revision:427; number_of_response:1; }","duration":"659.978306ms","start":"2026-04-14T06:18:27.192266Z","end":"2026-04-14T06:18:27.852245Z","steps":["trace[1253602699] 'process raft request' (duration: 327.916904ms)","trace[1253602699] 'compare' (duration: 331.885088ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T06:18:27.852295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:18:27.19225Z","time spent":"660.028527ms","remote":"127.0.0.1:43022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3678,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-14T06:18:28.313604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.608781ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-14T06:18:28.313747Z","caller":"traceutil/trace.go:171","msg":"trace[204573773] transaction","detail":"{read_only:false; response_revision:429; number_of_response:1; }","duration":"454.578644ms","start":"2026-04-14T06:18:27.859144Z","end":"2026-04-14T06:18:28.313723Z","steps":["trace[204573773] 'process raft request' (duration: 191.761628ms)","trace[204573773] 'compare' (duration: 262.506839ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T06:18:28.313827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:18:27.859119Z","time spent":"454.667817ms","remote":"127.0.0.1:43000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4180,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T06:18:28.395856Z","caller":"traceutil/trace.go:171","msg":"trace[1138891945] linearizableReadLoop","detail":"{readStateIndex:453; appliedIndex:451; }","duration":"383.456824ms","start":"2026-04-14T06:18:28.012379Z","end":"2026-04-14T06:18:28.395835Z","steps":["trace[1138891945] 'read index received' (duration: 38.590917ms)","trace[1138891945] 'applied index is now lower than readState.Index' (duration: 344.865047ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:18:28.395935Z","caller":"traceutil/trace.go:171","msg":"trace[312079039] transaction","detail":"{read_only:false; response_revision:430; number_of_response:1; }","duration":"506.226252ms","start":"2026-04-14T06:18:27.889664Z","end":"2026-04-14T06:18:28.39589Z","steps":["trace[312079039] 'process raft request' (duration: 506.015367ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:18:28.396026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.626657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-14T06:18:28.396073Z","caller":"traceutil/trace.go:171","msg":"trace[19158004] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:430; }","duration":"383.70757ms","start":"2026-04-14T06:18:28.012355Z","end":"2026-04-14T06:18:28.396062Z","steps":["trace[19158004] 'agreement among raft nodes before linearized reading' (duration: 383.553227ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:18:28.396113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:18:28.012278Z","time spent":"383.816282ms","remote":"127.0.0.1:42820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-14T06:18:28.396112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:18:27.88964Z","time spent":"506.376405ms","remote":"127.0.0.1:42738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3563,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-14T06:18:28.896149Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471725592835172102,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-14T06:18:29.185226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.438018ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-14T06:18:29.185348Z","caller":"traceutil/trace.go:171","msg":"trace[972620839] transaction","detail":"{read_only:false; response_revision:431; number_of_response:1; }","duration":"864.401243ms","start":"2026-04-14T06:18:28.320923Z","end":"2026-04-14T06:18:29.185324Z","steps":["trace[972620839] 'process raft request' (duration: 731.789451ms)","trace[972620839] 'compare' (duration: 132.335986ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T06:18:29.185692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:18:28.320899Z","time spent":"864.555267ms","remote":"127.0.0.1:43022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3804,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T06:18:29.88088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.244386ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-14T06:18:29.881055Z","caller":"traceutil/trace.go:171","msg":"trace[1063699820] transaction","detail":"{read_only:false; response_revision:432; number_of_response:1; }","duration":"1.480368463s","start":"2026-04-14T06:18:28.40066Z","end":"2026-04-14T06:18:29.881029Z","steps":["trace[1063699820] 'process raft request' (duration: 985.923402ms)","trace[1063699820] 'compare' (duration: 494.146754ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T06:18:29.881164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:18:28.400638Z","time spent":"1.480473826s","remote":"127.0.0.1:38540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-14T06:18:29.881243Z","caller":"traceutil/trace.go:171","msg":"trace[1881272530] transaction","detail":"{read_only:false; response_revision:438; number_of_response:1; }","duration":"689.59207ms","start":"2026-04-14T06:18:29.191637Z","end":"2026-04-14T06:18:29.881229Z","steps":["trace[1881272530] 'process raft request' (duration: 689.565439ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:18:29.881302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:18:29.191621Z","time spent":"689.658271ms","remote":"127.0.0.1:38540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":689,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-14T06:18:29.881337Z","caller":"traceutil/trace.go:171","msg":"trace[620457644] transaction","detail":"{read_only:false; response_revision:433; number_of_response:1; }","duration":"1.480600299s","start":"2026-04-14T06:18:28.40072Z","end":"2026-04-14T06:18:29.88132Z","steps":["trace[620457644] 'process raft request' (duration: 1.48023819s)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:18:29.881347Z","caller":"traceutil/trace.go:171","msg":"trace[1153091245] transaction","detail":"{read_only:false; response_revision:434; number_of_response:1; }","duration":"1.480516107s","start":"2026-04-14T06:18:28.400802Z","end":"2026-04-14T06:18:29.881318Z","steps":["trace[1153091245] 'process raft request' (duration: 1.48020479s)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:18:29.881342Z","caller":"traceutil/trace.go:171","msg":"trace[2075722455] transaction","detail":"{read_only:false; response_revision:435; number_of_response:1; }","duration":"1.480492097s","start":"2026-04-14T06:18:28.400816Z","end":"2026-04-14T06:18:29.881308Z","steps":["trace[2075722455] 'process raft request' (duration: 1.48021168s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:18:29.881428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:18:28.400792Z","time spent":"1.480596669s","remote":"127.0.0.1:38540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":830,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-14T06:18:29.881431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:18:28.400693Z","time spent":"1.480688622s","remote":"127.0.0.1:43022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3795,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T06:18:29.881451Z","caller":"traceutil/trace.go:171","msg":"trace[1227864601] transaction","detail":"{read_only:false; response_revision:437; number_of_response:1; }","duration":"1.082482387s","start":"2026-04-14T06:18:28.798949Z","end":"2026-04-14T06:18:29.881431Z","steps":["trace[1227864601] 'process raft request' (duration: 1.0822221s)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:18:29.881485Z","caller":"traceutil/trace.go:171","msg":"trace[1906629519] linearizableReadLoop","detail":"{readStateIndex:460; appliedIndex:453; }","duration":"1.485561357s","start":"2026-04-14T06:18:28.395911Z","end":"2026-04-14T06:18:29.881473Z","steps":["trace[1906629519] 'read index received' (duration: 656.812629ms)","trace[1906629519] 'applied index is now lower than readState.Index' (duration: 828.747768ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T06:18:29.881497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:18:28.400794Z","time spent":"1.480620049s","remote":"127.0.0.1:42820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T06:18:29.881544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:18:28.798917Z","time spent":"1.082575719s","remote":"127.0.0.1:42820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T06:18:29.881599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.689210227s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T06:18:29.881632Z","caller":"traceutil/trace.go:171","msg":"trace[301941416] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:438; }","duration":"1.689285299s","start":"2026-04-14T06:18:28.192338Z","end":"2026-04-14T06:18:29.881623Z","steps":["trace[301941416] 'agreement among raft nodes before linearized reading' (duration: 1.689227748s)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:18:29.881633Z","caller":"traceutil/trace.go:171","msg":"trace[1088363999] transaction","detail":"{read_only:false; response_revision:436; number_of_response:1; }","duration":"1.479724758s","start":"2026-04-14T06:18:28.401897Z","end":"2026-04-14T06:18:29.881622Z","steps":["trace[1088363999] 'process raft request' (duration: 1.479232296s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:18:29.881659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:18:28.192317Z","time spent":"1.6893351s","remote":"127.0.0.1:38446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-14T06:18:29.881683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:18:28.401884Z","time spent":"1.479771519s","remote":"127.0.0.1:42738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4037,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T06:18:29.881721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.030448971s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-14T06:18:29.881766Z","caller":"traceutil/trace.go:171","msg":"trace[754510693] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:438; }","duration":"1.030520662s","start":"2026-04-14T06:18:28.851234Z","end":"2026-04-14T06:18:29.881755Z","steps":["trace[754510693] 'agreement among raft nodes before linearized reading' (duration: 1.030445351s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:18:29.881795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:18:28.851212Z","time spent":"1.030576704s","remote":"127.0.0.1:42820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-14T06:18:29.881805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"692.790776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-14T06:18:29.881852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"722.619315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-14T06:18:29.881841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"693.462742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-885sm\" ","response":"range_response_count:1 size:4016"} {"level":"info","ts":"2026-04-14T06:18:29.881884Z","caller":"traceutil/trace.go:171","msg":"trace[490783482] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:438; }","duration":"722.708067ms","start":"2026-04-14T06:18:29.159169Z","end":"2026-04-14T06:18:29.881877Z","steps":["trace[490783482] 'agreement among raft nodes before linearized reading' (duration: 722.657226ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:18:29.881904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:18:29.159148Z","time spent":"722.751478ms","remote":"127.0.0.1:42820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-14T06:18:29.881908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.699299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.138\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-14T06:18:29.881906Z","caller":"traceutil/trace.go:171","msg":"trace[91895220] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-885sm; range_end:; response_count:1; response_revision:438; }","duration":"693.557024ms","start":"2026-04-14T06:18:29.188332Z","end":"2026-04-14T06:18:29.881889Z","steps":["trace[91895220] 'agreement among raft nodes before linearized reading' (duration: 693.457091ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:18:29.881934Z","caller":"traceutil/trace.go:171","msg":"trace[1990192044] range","detail":"{range_begin:/registry/masterleases/199.204.45.138; range_end:; response_count:1; response_revision:438; }","duration":"221.72697ms","start":"2026-04-14T06:18:29.6602Z","end":"2026-04-14T06:18:29.881927Z","steps":["trace[1990192044] 'agreement among raft nodes before linearized reading' (duration: 221.680918ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:18:29.881933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"687.027138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4195"} {"level":"warn","ts":"2026-04-14T06:18:29.881947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:18:29.188316Z","time spent":"693.620796ms","remote":"127.0.0.1:42738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":4040,"request content":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-885sm\" "} {"level":"info","ts":"2026-04-14T06:18:29.881858Z","caller":"traceutil/trace.go:171","msg":"trace[1892930785] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:438; }","duration":"692.861748ms","start":"2026-04-14T06:18:29.188981Z","end":"2026-04-14T06:18:29.881843Z","steps":["trace[1892930785] 'agreement among raft nodes before linearized reading' (duration: 692.762085ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:18:29.881986Z","caller":"traceutil/trace.go:171","msg":"trace[1647795460] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:438; }","duration":"687.065229ms","start":"2026-04-14T06:18:29.194895Z","end":"2026-04-14T06:18:29.88196Z","steps":["trace[1647795460] 'agreement among raft nodes before linearized reading' (duration: 686.990788ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:18:29.882044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:18:29.194871Z","time spent":"687.160132ms","remote":"127.0.0.1:43000","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":4219,"request content":"key:\"/registry/deployments/kube-system/coredns\" "} {"level":"warn","ts":"2026-04-14T06:18:29.881992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:18:29.188969Z","time spent":"693.014061ms","remote":"127.0.0.1:42820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-14T06:18:30.324249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.268494ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"warn","ts":"2026-04-14T06:18:30.324662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:18:29.885297Z","time spent":"439.359883ms","remote":"127.0.0.1:38492","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-14T06:18:30.324707Z","caller":"traceutil/trace.go:171","msg":"trace[1853894912] linearizableReadLoop","detail":"{readStateIndex:462; appliedIndex:461; }","duration":"439.324853ms","start":"2026-04-14T06:18:29.885361Z","end":"2026-04-14T06:18:30.324686Z","steps":["trace[1853894912] 'read index received' (duration: 118.563µs)","trace[1853894912] 'applied index is now lower than readState.Index' (duration: 439.20474ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:18:30.32473Z","caller":"traceutil/trace.go:171","msg":"trace[514274316] transaction","detail":"{read_only:false; response_revision:441; number_of_response:1; }","duration":"438.230716ms","start":"2026-04-14T06:18:29.886442Z","end":"2026-04-14T06:18:30.324672Z","steps":["trace[514274316] 'process raft request' (duration: 438.143234ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:18:30.324754Z","caller":"traceutil/trace.go:171","msg":"trace[1103660113] transaction","detail":"{read_only:false; response_revision:439; number_of_response:1; }","duration":"439.274741ms","start":"2026-04-14T06:18:29.88546Z","end":"2026-04-14T06:18:30.324735Z","steps":["trace[1103660113] 'process raft request' (duration: 438.903382ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:18:30.324817Z","caller":"traceutil/trace.go:171","msg":"trace[1190015107] transaction","detail":"{read_only:false; response_revision:440; number_of_response:1; }","duration":"438.770009ms","start":"2026-04-14T06:18:29.886013Z","end":"2026-04-14T06:18:30.324783Z","steps":["trace[1190015107] 'process raft request' (duration: 438.500333ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:18:30.32486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:18:29.886424Z","time spent":"438.355959ms","remote":"127.0.0.1:42820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T06:18:30.324931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.552608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T06:18:30.324906Z","caller":"traceutil/trace.go:171","msg":"trace[1937185531] transaction","detail":"{read_only:false; response_revision:442; number_of_response:1; }","duration":"437.573911ms","start":"2026-04-14T06:18:29.887312Z","end":"2026-04-14T06:18:30.324886Z","steps":["trace[1937185531] 'process raft request' (duration: 437.315824ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:18:30.324945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:18:29.88544Z","time spent":"439.366284ms","remote":"127.0.0.1:42738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4013,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T06:18:30.324961Z","caller":"traceutil/trace.go:171","msg":"trace[2030173332] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:442; }","duration":"439.6322ms","start":"2026-04-14T06:18:29.885319Z","end":"2026-04-14T06:18:30.324951Z","steps":["trace[2030173332] 'agreement among raft nodes before linearized reading' (duration: 439.437495ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:18:30.325021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:18:29.887294Z","time spent":"437.673093ms","remote":"127.0.0.1:42820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T06:18:30.325055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:18:29.885297Z","time spent":"439.744562ms","remote":"127.0.0.1:38434","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-14T06:18:30.325043Z","caller":"traceutil/trace.go:171","msg":"trace[2309054] transaction","detail":"{read_only:false; response_revision:443; number_of_response:1; }","duration":"436.007743ms","start":"2026-04-14T06:18:29.88903Z","end":"2026-04-14T06:18:30.325038Z","steps":["trace[2309054] 'process raft request' (duration: 435.766217ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:18:30.325155Z","caller":"traceutil/trace.go:171","msg":"trace[1117178065] transaction","detail":"{read_only:false; number_of_response:1; response_revision:443; }","duration":"433.427672ms","start":"2026-04-14T06:18:29.891722Z","end":"2026-04-14T06:18:30.32515Z","steps":["trace[1117178065] 'process raft request' (duration: 433.282488ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:18:30.324978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:18:29.885999Z","time spent":"438.887692ms","remote":"127.0.0.1:42820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T06:18:30.325209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:18:29.891713Z","time spent":"433.461652ms","remote":"127.0.0.1:43022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":56,"response count":0,"response size":3890,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T06:18:30.325206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:18:29.889021Z","time spent":"436.140076ms","remote":"127.0.0.1:43022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3840,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T06:18:30.550593Z","caller":"traceutil/trace.go:171","msg":"trace[281234255] transaction","detail":"{read_only:false; number_of_response:1; response_revision:444; }","duration":"220.508041ms","start":"2026-04-14T06:18:30.330065Z","end":"2026-04-14T06:18:30.550573Z","steps":["trace[281234255] 'process raft request' (duration: 161.019948ms)","trace[281234255] 'compare' (duration: 59.36647ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:18:30.551284Z","caller":"traceutil/trace.go:171","msg":"trace[1447477160] transaction","detail":"{read_only:false; response_revision:445; number_of_response:1; }","duration":"216.767072ms","start":"2026-04-14T06:18:30.334507Z","end":"2026-04-14T06:18:30.551274Z","steps":["trace[1447477160] 'process raft request' (duration: 216.025114ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:18:30.551375Z","caller":"traceutil/trace.go:171","msg":"trace[1945489330] transaction","detail":"{read_only:false; response_revision:446; number_of_response:1; }","duration":"216.354591ms","start":"2026-04-14T06:18:30.334995Z","end":"2026-04-14T06:18:30.55135Z","steps":["trace[1945489330] 'process raft request' (duration: 216.146036ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:18:30.551265Z","caller":"traceutil/trace.go:171","msg":"trace[1376476789] linearizableReadLoop","detail":"{readStateIndex:472; appliedIndex:468; }","duration":"215.102342ms","start":"2026-04-14T06:18:30.33614Z","end":"2026-04-14T06:18:30.551242Z","steps":["trace[1376476789] 'read index received' (duration: 34.825928ms)","trace[1376476789] 'applied index is now lower than readState.Index' (duration: 180.275554ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T06:18:30.551639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.782221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-14T06:18:30.551804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.434071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-04-14T06:18:30.551829Z","caller":"traceutil/trace.go:171","msg":"trace[232049378] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:446; }","duration":"213.889064ms","start":"2026-04-14T06:18:30.337803Z","end":"2026-04-14T06:18:30.551692Z","steps":["trace[232049378] 'agreement among raft nodes before linearized reading' (duration: 213.764121ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:18:30.551859Z","caller":"traceutil/trace.go:171","msg":"trace[1599560164] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:446; }","duration":"178.534833ms","start":"2026-04-14T06:18:30.37331Z","end":"2026-04-14T06:18:30.551845Z","steps":["trace[1599560164] 'agreement among raft nodes before linearized reading' (duration: 178.435151ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:18:30.552237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.071335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/coredns-67659f764b\" ","response":"range_response_count:1 size:3855"} {"level":"info","ts":"2026-04-14T06:18:30.552349Z","caller":"traceutil/trace.go:171","msg":"trace[1356240246] range","detail":"{range_begin:/registry/replicasets/kube-system/coredns-67659f764b; range_end:; response_count:1; response_revision:446; }","duration":"216.234989ms","start":"2026-04-14T06:18:30.336094Z","end":"2026-04-14T06:18:30.552329Z","steps":["trace[1356240246] 'agreement among raft nodes before linearized reading' (duration: 215.263236ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:18:30.807759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.959441ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-14T06:18:30.808028Z","caller":"traceutil/trace.go:171","msg":"trace[1745679866] linearizableReadLoop","detail":"{readStateIndex:475; appliedIndex:473; }","duration":"236.727456ms","start":"2026-04-14T06:18:30.571289Z","end":"2026-04-14T06:18:30.808016Z","steps":["trace[1745679866] 'read index received' (duration: 116.452408ms)","trace[1745679866] 'applied index is now lower than readState.Index' (duration: 120.274238ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:18:30.808108Z","caller":"traceutil/trace.go:171","msg":"trace[1261162711] transaction","detail":"{read_only:false; response_revision:448; number_of_response:1; }","duration":"252.019879ms","start":"2026-04-14T06:18:30.556073Z","end":"2026-04-14T06:18:30.808092Z","steps":["trace[1261162711] 'process raft request' (duration: 131.677259ms)","trace[1261162711] 'compare' (duration: 119.838278ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:18:30.808139Z","caller":"traceutil/trace.go:171","msg":"trace[2106240170] transaction","detail":"{read_only:false; response_revision:449; number_of_response:1; }","duration":"248.879315ms","start":"2026-04-14T06:18:30.559235Z","end":"2026-04-14T06:18:30.808115Z","steps":["trace[2106240170] 'process raft request' (duration: 248.731271ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:18:30.808269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.723773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/coredns-67659f764b\" ","response":"range_response_count:1 size:3855"} {"level":"info","ts":"2026-04-14T06:18:30.808349Z","caller":"traceutil/trace.go:171","msg":"trace[743499381] range","detail":"{range_begin:/registry/replicasets/kube-system/coredns-67659f764b; range_end:; response_count:1; response_revision:449; }","duration":"251.845965ms","start":"2026-04-14T06:18:30.556486Z","end":"2026-04-14T06:18:30.808332Z","steps":["trace[743499381] 'agreement among raft nodes before linearized reading' (duration: 251.670841ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:18:30.811995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.465428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-04-14T06:18:30.81203Z","caller":"traceutil/trace.go:171","msg":"trace[1525265164] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:451; }","duration":"238.589121ms","start":"2026-04-14T06:18:30.573433Z","end":"2026-04-14T06:18:30.812022Z","steps":["trace[1525265164] 'agreement among raft nodes before linearized reading' (duration: 238.467238ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:18:30.812146Z","caller":"traceutil/trace.go:171","msg":"trace[1649752797] transaction","detail":"{read_only:false; response_revision:451; number_of_response:1; }","duration":"234.900862ms","start":"2026-04-14T06:18:30.577236Z","end":"2026-04-14T06:18:30.812137Z","steps":["trace[1649752797] 'process raft request' (duration: 234.630436ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:18:30.812324Z","caller":"traceutil/trace.go:171","msg":"trace[57989459] transaction","detail":"{read_only:false; response_revision:450; number_of_response:1; }","duration":"235.663611ms","start":"2026-04-14T06:18:30.576631Z","end":"2026-04-14T06:18:30.812294Z","steps":["trace[57989459] 'process raft request' (duration: 235.1893ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:18:31.057608Z","caller":"traceutil/trace.go:171","msg":"trace[1404030044] transaction","detail":"{read_only:false; response_revision:452; number_of_response:1; }","duration":"241.359017ms","start":"2026-04-14T06:18:30.816229Z","end":"2026-04-14T06:18:31.057588Z","steps":["trace[1404030044] 'process raft request' (duration: 142.345534ms)","trace[1404030044] 'compare' (duration: 98.909631ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:18:31.069219Z","caller":"traceutil/trace.go:171","msg":"trace[1966845338] transaction","detail":"{read_only:false; response_revision:453; number_of_response:1; }","duration":"252.718117ms","start":"2026-04-14T06:18:30.816481Z","end":"2026-04-14T06:18:31.069199Z","steps":["trace[1966845338] 'process raft request' (duration: 252.563283ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:18:31.069314Z","caller":"traceutil/trace.go:171","msg":"trace[60839698] transaction","detail":"{read_only:false; response_revision:454; number_of_response:1; }","duration":"250.144336ms","start":"2026-04-14T06:18:30.819152Z","end":"2026-04-14T06:18:31.069296Z","steps":["trace[60839698] 'process raft request' (duration: 250.000633ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:18:31.270427Z","caller":"traceutil/trace.go:171","msg":"trace[353315773] linearizableReadLoop","detail":"{readStateIndex:485; appliedIndex:484; }","duration":"138.080042ms","start":"2026-04-14T06:18:31.132323Z","end":"2026-04-14T06:18:31.270403Z","steps":["trace[353315773] 'read index received' (duration: 47.877228ms)","trace[353315773] 'applied index is now lower than readState.Index' (duration: 90.201764ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:18:31.270555Z","caller":"traceutil/trace.go:171","msg":"trace[547295838] transaction","detail":"{read_only:false; response_revision:459; number_of_response:1; }","duration":"194.494222ms","start":"2026-04-14T06:18:31.07602Z","end":"2026-04-14T06:18:31.270514Z","steps":["trace[547295838] 'process raft request' (duration: 104.273628ms)","trace[547295838] 'compare' (duration: 89.992419ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T06:18:31.270589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.251046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/coredns-7c96b6546b\" ","response":"range_response_count:1 size:3819"} {"level":"warn","ts":"2026-04-14T06:18:31.270633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.238511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/coredns-67659f764b\" ","response":"range_response_count:1 size:3855"} {"level":"info","ts":"2026-04-14T06:18:31.270647Z","caller":"traceutil/trace.go:171","msg":"trace[1392900859] range","detail":"{range_begin:/registry/replicasets/kube-system/coredns-7c96b6546b; range_end:; response_count:1; response_revision:459; }","duration":"138.357078ms","start":"2026-04-14T06:18:31.132276Z","end":"2026-04-14T06:18:31.270633Z","steps":["trace[1392900859] 'agreement among raft nodes before linearized reading' (duration: 138.266596ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:18:31.27067Z","caller":"traceutil/trace.go:171","msg":"trace[751693882] range","detail":"{range_begin:/registry/replicasets/kube-system/coredns-67659f764b; range_end:; response_count:1; response_revision:459; }","duration":"137.304533ms","start":"2026-04-14T06:18:31.133356Z","end":"2026-04-14T06:18:31.270661Z","steps":["trace[751693882] 'agreement among raft nodes before linearized reading' (duration: 137.231731ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:18:31.563031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.7153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/coredns-67659f764b\" ","response":"range_response_count:1 size:3855"} {"level":"info","ts":"2026-04-14T06:18:31.563116Z","caller":"traceutil/trace.go:171","msg":"trace[1172023608] range","detail":"{range_begin:/registry/replicasets/kube-system/coredns-67659f764b; range_end:; response_count:1; response_revision:460; }","duration":"192.856844ms","start":"2026-04-14T06:18:31.37024Z","end":"2026-04-14T06:18:31.563096Z","steps":["trace[1172023608] 'range keys from in-memory index tree' (duration: 192.612649ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:18:31.594049Z","caller":"traceutil/trace.go:171","msg":"trace[1467621142] transaction","detail":"{read_only:false; response_revision:461; number_of_response:1; }","duration":"223.039362ms","start":"2026-04-14T06:18:31.370978Z","end":"2026-04-14T06:18:31.594017Z","steps":["trace[1467621142] 'process raft request' (duration: 222.492689ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:18:31.998869Z","caller":"traceutil/trace.go:171","msg":"trace[1614602686] transaction","detail":"{read_only:false; response_revision:462; number_of_response:1; }","duration":"109.105113ms","start":"2026-04-14T06:18:31.889745Z","end":"2026-04-14T06:18:31.99885Z","steps":["trace[1614602686] 'process raft request' (duration: 108.95163ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:18:32.555835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.326393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-14T06:18:32.555876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.908258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-14T06:18:32.555936Z","caller":"traceutil/trace.go:171","msg":"trace[627894528] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:463; }","duration":"222.482887ms","start":"2026-04-14T06:18:32.33343Z","end":"2026-04-14T06:18:32.555913Z","steps":["trace[627894528] 'range keys from in-memory index tree' (duration: 222.21207ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:18:32.555857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.094378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-14T06:18:32.555977Z","caller":"traceutil/trace.go:171","msg":"trace[357747418] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:463; }","duration":"226.066782ms","start":"2026-04-14T06:18:32.329889Z","end":"2026-04-14T06:18:32.555955Z","steps":["trace[357747418] 'range keys from in-memory index tree' (duration: 225.718464ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:18:32.556014Z","caller":"traceutil/trace.go:171","msg":"trace[634670407] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:463; }","duration":"222.283552ms","start":"2026-04-14T06:18:32.333714Z","end":"2026-04-14T06:18:32.555998Z","steps":["trace[634670407] 'range keys from in-memory index tree' (duration: 222.028076ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:18:32.777373Z","caller":"traceutil/trace.go:171","msg":"trace[55057813] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"216.652949ms","start":"2026-04-14T06:18:32.560699Z","end":"2026-04-14T06:18:32.777352Z","steps":["trace[55057813] 'process raft request' (duration: 216.525746ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:18:32.788264Z","caller":"traceutil/trace.go:171","msg":"trace[283550450] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"226.826671ms","start":"2026-04-14T06:18:32.561418Z","end":"2026-04-14T06:18:32.788245Z","steps":["trace[283550450] 'process raft request' (duration: 226.78724ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:18:32.788549Z","caller":"traceutil/trace.go:171","msg":"trace[1088049370] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"227.253132ms","start":"2026-04-14T06:18:32.561274Z","end":"2026-04-14T06:18:32.788527Z","steps":["trace[1088049370] 'process raft request' (duration: 226.834502ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:18:34.914077Z","caller":"traceutil/trace.go:171","msg":"trace[957290536] linearizableReadLoop","detail":"{readStateIndex:494; appliedIndex:493; }","duration":"123.10142ms","start":"2026-04-14T06:18:34.790957Z","end":"2026-04-14T06:18:34.914058Z","steps":["trace[957290536] 'read index received' (duration: 122.992717ms)","trace[957290536] 'applied index is now lower than readState.Index' (duration: 107.663µs)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:18:34.914191Z","caller":"traceutil/trace.go:171","msg":"trace[1699428061] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"129.41474ms","start":"2026-04-14T06:18:34.784768Z","end":"2026-04-14T06:18:34.914182Z","steps":["trace[1699428061] 'process raft request' (duration: 129.177424ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:18:34.914335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.361177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-14T06:18:34.914365Z","caller":"traceutil/trace.go:171","msg":"trace[618726733] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:468; }","duration":"123.415948ms","start":"2026-04-14T06:18:34.79094Z","end":"2026-04-14T06:18:34.914356Z","steps":["trace[618726733] 'agreement among raft nodes before linearized reading' (duration: 123.355346ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:18:34.914527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.606818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-14T06:18:34.914609Z","caller":"traceutil/trace.go:171","msg":"trace[673391631] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:468; }","duration":"122.745541ms","start":"2026-04-14T06:18:34.791842Z","end":"2026-04-14T06:18:34.914588Z","steps":["trace[673391631] 'agreement among raft nodes before linearized reading' (duration: 122.584817ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:18:35.179214Z","caller":"traceutil/trace.go:171","msg":"trace[643586690] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"260.863687ms","start":"2026-04-14T06:18:34.918316Z","end":"2026-04-14T06:18:35.17918Z","steps":["trace[643586690] 'process raft request' (duration: 260.631902ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:18:35.412504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.409146ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"warn","ts":"2026-04-14T06:18:39.47981Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471725592835172193,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-14T06:18:39.98025Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471725592835172193,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-14T06:18:40.022887Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.091376825s","expected-duration":"1s"} {"level":"info","ts":"2026-04-14T06:18:40.023225Z","caller":"traceutil/trace.go:171","msg":"trace[1034780130] linearizableReadLoop","detail":"{readStateIndex:503; appliedIndex:502; }","duration":"1.043474079s","start":"2026-04-14T06:18:38.979736Z","end":"2026-04-14T06:18:40.02321Z","steps":["trace[1034780130] 'read index received' (duration: 1.043344676s)","trace[1034780130] 'applied index is now lower than readState.Index' (duration: 128.803µs)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:18:40.023278Z","caller":"traceutil/trace.go:171","msg":"trace[304003004] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"1.091839636s","start":"2026-04-14T06:18:38.931433Z","end":"2026-04-14T06:18:40.023273Z","steps":["trace[304003004] 'process raft request' (duration: 1.091686652s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:18:40.023339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:18:38.931419Z","time spent":"1.091873927s","remote":"127.0.0.1:42820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T06:18:40.023417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.043681524s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-14T06:18:40.023434Z","caller":"traceutil/trace.go:171","msg":"trace[1333709197] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:476; }","duration":"1.043734106s","start":"2026-04-14T06:18:38.979695Z","end":"2026-04-14T06:18:40.023429Z","steps":["trace[1333709197] 'agreement among raft nodes before linearized reading' (duration: 1.043704755s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:18:40.023451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:18:38.979676Z","time spent":"1.043771196s","remote":"127.0.0.1:42820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-14T06:18:40.023517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"831.922082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-14T06:18:40.023531Z","caller":"traceutil/trace.go:171","msg":"trace[1439323368] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:476; }","duration":"831.971593ms","start":"2026-04-14T06:18:39.191555Z","end":"2026-04-14T06:18:40.023527Z","steps":["trace[1439323368] 'agreement among raft nodes before linearized reading' (duration: 831.947912ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:18:40.023542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:18:39.191538Z","time spent":"832.002043ms","remote":"127.0.0.1:42820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-14T06:18:40.02364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.267692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.138\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-14T06:18:40.023657Z","caller":"traceutil/trace.go:171","msg":"trace[82387017] range","detail":"{range_begin:/registry/masterleases/199.204.45.138; range_end:; response_count:1; response_revision:476; }","duration":"362.339834ms","start":"2026-04-14T06:18:39.661312Z","end":"2026-04-14T06:18:40.023652Z","steps":["trace[82387017] 'agreement among raft nodes before linearized reading' (duration: 362.306603ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:18:40.023673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:18:39.661296Z","time spent":"362.373564ms","remote":"127.0.0.1:38492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.204.45.138\" "} {"level":"warn","ts":"2026-04-14T06:18:40.023768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"832.118285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T06:18:40.023837Z","caller":"traceutil/trace.go:171","msg":"trace[2036438721] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:476; }","duration":"832.218568ms","start":"2026-04-14T06:18:39.191613Z","end":"2026-04-14T06:18:40.023831Z","steps":["trace[2036438721] 'agreement among raft nodes before linearized reading' (duration: 832.142456ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:18:40.023856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:18:39.191595Z","time spent":"832.25654ms","remote":"127.0.0.1:38446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-14T06:18:40.266968Z","caller":"traceutil/trace.go:171","msg":"trace[563780812] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"237.123524ms","start":"2026-04-14T06:18:40.029819Z","end":"2026-04-14T06:18:40.266943Z","steps":["trace[563780812] 'process raft request' (duration: 237.017762ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:18:40.266979Z","caller":"traceutil/trace.go:171","msg":"trace[75771345] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"237.34631ms","start":"2026-04-14T06:18:40.029609Z","end":"2026-04-14T06:18:40.266956Z","steps":["trace[75771345] 'process raft request' (duration: 237.042262ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:18:40.267033Z","caller":"traceutil/trace.go:171","msg":"trace[1014724185] linearizableReadLoop","detail":"{readStateIndex:507; appliedIndex:503; }","duration":"215.629694ms","start":"2026-04-14T06:18:40.051382Z","end":"2026-04-14T06:18:40.267011Z","steps":["trace[1014724185] 'read index received' (duration: 167.626556ms)","trace[1014724185] 'applied index is now lower than readState.Index' (duration: 48.001728ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:18:40.267109Z","caller":"traceutil/trace.go:171","msg":"trace[1496447552] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"234.261966ms","start":"2026-04-14T06:18:40.032829Z","end":"2026-04-14T06:18:40.267091Z","steps":["trace[1496447552] 'process raft request' (duration: 234.067341ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:18:40.267196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.795979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-14T06:18:40.267244Z","caller":"traceutil/trace.go:171","msg":"trace[398593378] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:479; }","duration":"215.89302ms","start":"2026-04-14T06:18:40.051336Z","end":"2026-04-14T06:18:40.267229Z","steps":["trace[398593378] 'agreement among raft nodes before linearized reading' (duration: 215.790398ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:18:40.965371Z","caller":"traceutil/trace.go:171","msg":"trace[1021103249] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"106.276791ms","start":"2026-04-14T06:18:40.85907Z","end":"2026-04-14T06:18:40.965346Z","steps":["trace[1021103249] 'process raft request' (duration: 63.284582ms)","trace[1021103249] 'compare' (duration: 42.381015ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:18:40.965662Z","caller":"traceutil/trace.go:171","msg":"trace[1843137058] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"105.82885ms","start":"2026-04-14T06:18:40.859814Z","end":"2026-04-14T06:18:40.965643Z","steps":["trace[1843137058] 'process raft request' (duration: 105.082163ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:18:40.965673Z","caller":"traceutil/trace.go:171","msg":"trace[399446804] linearizableReadLoop","detail":"{readStateIndex:534; appliedIndex:533; }","duration":"106.280461ms","start":"2026-04-14T06:18:40.859381Z","end":"2026-04-14T06:18:40.965661Z","steps":["trace[399446804] 'read index received' (duration: 16.295086ms)","trace[399446804] 'applied index is now lower than readState.Index' (duration: 89.984445ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T06:18:40.965745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.346682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/cilium-operator\" ","response":"range_response_count:1 size:4335"} {"level":"info","ts":"2026-04-14T06:18:40.965796Z","caller":"traceutil/trace.go:171","msg":"trace[1554134704] range","detail":"{range_begin:/registry/deployments/kube-system/cilium-operator; range_end:; response_count:1; response_revision:509; }","duration":"106.437005ms","start":"2026-04-14T06:18:40.859348Z","end":"2026-04-14T06:18:40.965785Z","steps":["trace[1554134704] 'agreement among raft nodes before linearized reading' (duration: 106.349413ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:18:40.965964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.7755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/cilium\" ","response":"range_response_count:1 size:14463"} {"level":"info","ts":"2026-04-14T06:18:40.966048Z","caller":"traceutil/trace.go:171","msg":"trace[257936505] range","detail":"{range_begin:/registry/daemonsets/kube-system/cilium; range_end:; response_count:1; response_revision:509; }","duration":"100.889353ms","start":"2026-04-14T06:18:40.865136Z","end":"2026-04-14T06:18:40.966025Z","steps":["trace[257936505] 'agreement among raft nodes before linearized reading' (duration: 100.709529ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:18:43.238311Z","caller":"traceutil/trace.go:171","msg":"trace[99928587] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"783.227377ms","start":"2026-04-14T06:18:42.455061Z","end":"2026-04-14T06:18:43.238288Z","steps":["trace[99928587] 'process raft request' (duration: 783.204417ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:18:43.238355Z","caller":"traceutil/trace.go:171","msg":"trace[1913798457] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"800.859465ms","start":"2026-04-14T06:18:42.437469Z","end":"2026-04-14T06:18:43.238328Z","steps":["trace[1913798457] 'process raft request' (duration: 800.718792ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:18:43.238446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:18:42.455041Z","time spent":"783.36372ms","remote":"127.0.0.1:43072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":995,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-14T06:18:43.238508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:18:42.437451Z","time spent":"800.978307ms","remote":"127.0.0.1:38500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-14T06:18:43.246873Z","caller":"traceutil/trace.go:171","msg":"trace[1975197560] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"790.596792ms","start":"2026-04-14T06:18:42.456239Z","end":"2026-04-14T06:18:43.246836Z","steps":["trace[1975197560] 'process raft request' (duration: 790.349666ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:18:43.247037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:18:42.456232Z","time spent":"790.721265ms","remote":"127.0.0.1:38500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-14T06:18:43.247084Z","caller":"traceutil/trace.go:171","msg":"trace[1770399979] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"779.353015ms","start":"2026-04-14T06:18:42.467714Z","end":"2026-04-14T06:18:43.247067Z","steps":["trace[1770399979] 'process raft request' (duration: 779.159421ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:18:43.247135Z","caller":"traceutil/trace.go:171","msg":"trace[760398292] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"789.535856ms","start":"2026-04-14T06:18:42.457573Z","end":"2026-04-14T06:18:43.247109Z","steps":["trace[760398292] 'process raft request' (duration: 789.156857ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:18:43.247201Z","caller":"traceutil/trace.go:171","msg":"trace[1540695099] transaction","detail":"{read_only:false; number_of_response:1; response_revision:527; }","duration":"785.238994ms","start":"2026-04-14T06:18:42.461948Z","end":"2026-04-14T06:18:43.247187Z","steps":["trace[1540695099] 'process raft request' (duration: 784.889316ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:18:43.247228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:18:42.467705Z","time spent":"779.459548ms","remote":"127.0.0.1:38500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-14T06:18:43.24727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:18:42.457565Z","time spent":"789.633728ms","remote":"127.0.0.1:38500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6888,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T06:18:43.247306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:18:42.461941Z","time spent":"785.301837ms","remote":"127.0.0.1:38500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":6938,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T06:18:43.247386Z","caller":"traceutil/trace.go:171","msg":"trace[1660391369] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"776.389675ms","start":"2026-04-14T06:18:42.470988Z","end":"2026-04-14T06:18:43.247378Z","steps":["trace[1660391369] 'process raft request' (duration: 775.936694ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:18:43.247426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:18:42.470981Z","time spent":"776.428145ms","remote":"127.0.0.1:38500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-14T06:18:43.247596Z","caller":"traceutil/trace.go:171","msg":"trace[1468450994] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"767.837322ms","start":"2026-04-14T06:18:42.479745Z","end":"2026-04-14T06:18:43.247582Z","steps":["trace[1468450994] 'process raft request' (duration: 767.215857ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:18:43.247644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:18:42.47973Z","time spent":"767.893084ms","remote":"127.0.0.1:43072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1004,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-14T06:18:43.247946Z","caller":"traceutil/trace.go:171","msg":"trace[166678312] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"768.150799ms","start":"2026-04-14T06:18:42.479788Z","end":"2026-04-14T06:18:43.247938Z","steps":["trace[166678312] 'process raft request' (duration: 767.200936ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:18:43.247988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:18:42.479784Z","time spent":"768.18595ms","remote":"127.0.0.1:43072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":980,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-14T06:18:43.248117Z","caller":"traceutil/trace.go:171","msg":"trace[276359235] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"768.116629ms","start":"2026-04-14T06:18:42.479981Z","end":"2026-04-14T06:18:43.248098Z","steps":["trace[276359235] 'process raft request' (duration: 767.905704ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:18:43.248229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:18:42.479976Z","time spent":"768.18853ms","remote":"127.0.0.1:38500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-14T06:18:43.248283Z","caller":"traceutil/trace.go:171","msg":"trace[612545258] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"762.910345ms","start":"2026-04-14T06:18:42.485363Z","end":"2026-04-14T06:18:43.248274Z","steps":["trace[612545258] 'process raft request' (duration: 762.584787ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:18:43.248336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:18:42.485336Z","time spent":"762.973037ms","remote":"127.0.0.1:38500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10063,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T06:18:43.248871Z","caller":"traceutil/trace.go:171","msg":"trace[1669381540] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"743.291539ms","start":"2026-04-14T06:18:42.50551Z","end":"2026-04-14T06:18:43.248802Z","steps":["trace[1669381540] 'process raft request' (duration: 743.190307ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:18:43.248996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:18:42.505482Z","time spent":"743.450754ms","remote":"127.0.0.1:38500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-14T06:18:43.249161Z","caller":"traceutil/trace.go:171","msg":"trace[906910609] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"751.266119ms","start":"2026-04-14T06:18:42.497886Z","end":"2026-04-14T06:18:43.249153Z","steps":["trace[906910609] 'process raft request' (duration: 750.738116ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:18:43.249279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:18:42.497866Z","time spent":"751.363981ms","remote":"127.0.0.1:38500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-14T06:18:43.249398Z","caller":"traceutil/trace.go:171","msg":"trace[553328843] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"701.504698ms","start":"2026-04-14T06:18:42.547868Z","end":"2026-04-14T06:18:43.249373Z","steps":["trace[553328843] 'process raft request' (duration: 700.877513ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:18:43.249529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:18:42.547843Z","time spent":"701.619371ms","remote":"127.0.0.1:42820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T06:18:43.249632Z","caller":"traceutil/trace.go:171","msg":"trace[214693823] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"634.13383ms","start":"2026-04-14T06:18:42.615485Z","end":"2026-04-14T06:18:43.249618Z","steps":["trace[214693823] 'process raft request' (duration: 633.365292ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:18:43.249742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:18:42.615456Z","time spent":"634.259073ms","remote":"127.0.0.1:38500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-14T06:18:46.99922Z","caller":"traceutil/trace.go:171","msg":"trace[1770495917] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"100.998556ms","start":"2026-04-14T06:18:46.898186Z","end":"2026-04-14T06:18:46.999185Z","steps":["trace[1770495917] 'process raft request' (duration: 100.382851ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:18:47.182894Z","caller":"traceutil/trace.go:171","msg":"trace[1513866696] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"126.838519ms","start":"2026-04-14T06:18:47.05603Z","end":"2026-04-14T06:18:47.182868Z","steps":["trace[1513866696] 'process raft request' (duration: 53.279894ms)","trace[1513866696] 'compare' (duration: 73.37585ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:18:50.601501Z","caller":"traceutil/trace.go:171","msg":"trace[1352342280] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"484.149352ms","start":"2026-04-14T06:18:50.117324Z","end":"2026-04-14T06:18:50.601473Z","steps":["trace[1352342280] 'process raft request' (duration: 479.189024ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:18:50.601644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:18:50.117292Z","time spent":"484.283566ms","remote":"127.0.0.1:38568","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036313,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T06:18:50.915782Z","caller":"traceutil/trace.go:171","msg":"trace[2052164258] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"770.396853ms","start":"2026-04-14T06:18:50.145366Z","end":"2026-04-14T06:18:50.915763Z","steps":["trace[2052164258] 'process raft request' (duration: 770.190508ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:18:50.91587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"723.744386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T06:18:50.915905Z","caller":"traceutil/trace.go:171","msg":"trace[2027950863] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:630; }","duration":"723.850198ms","start":"2026-04-14T06:18:50.192046Z","end":"2026-04-14T06:18:50.915896Z","steps":["trace[2027950863] 'agreement among raft nodes before linearized reading' (duration: 723.782286ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:18:50.915943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:18:50.192025Z","time spent":"723.91151ms","remote":"127.0.0.1:38434","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-14T06:18:50.915787Z","caller":"traceutil/trace.go:171","msg":"trace[582601890] linearizableReadLoop","detail":"{readStateIndex:661; appliedIndex:659; }","duration":"723.619073ms","start":"2026-04-14T06:18:50.192114Z","end":"2026-04-14T06:18:50.915733Z","steps":["trace[582601890] 'read index received' (duration: 403.614973ms)","trace[582601890] 'applied index is now lower than readState.Index' (duration: 320.00324ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:18:50.916034Z","caller":"traceutil/trace.go:171","msg":"trace[110873827] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"449.090272ms","start":"2026-04-14T06:18:50.466898Z","end":"2026-04-14T06:18:50.915989Z","steps":["trace[110873827] 'process raft request' (duration: 448.793095ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:18:50.916056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:18:50.145315Z","time spent":"770.529756ms","remote":"127.0.0.1:42820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T06:18:50.916177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:18:50.466871Z","time spent":"449.231735ms","remote":"127.0.0.1:42820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T06:18:50.930196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"586.40886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-14T06:18:50.930252Z","caller":"traceutil/trace.go:171","msg":"trace[1896093075] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:631; }","duration":"586.509252ms","start":"2026-04-14T06:18:50.343727Z","end":"2026-04-14T06:18:50.930237Z","steps":["trace[1896093075] 'agreement among raft nodes before linearized reading' (duration: 586.358939ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:18:50.930306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:18:50.343708Z","time spent":"586.588844ms","remote":"127.0.0.1:42820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-14T06:18:50.930305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.21337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-t4n8d\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-14T06:18:50.93064Z","caller":"traceutil/trace.go:171","msg":"trace[230812924] range","detail":"{range_begin:/registry/pods/kube-system/cilium-t4n8d; range_end:; response_count:1; response_revision:631; }","duration":"117.599209ms","start":"2026-04-14T06:18:50.813021Z","end":"2026-04-14T06:18:50.930621Z","steps":["trace[230812924] 'agreement among raft nodes before linearized reading' (duration: 117.107138ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:18:50.9304Z","caller":"traceutil/trace.go:171","msg":"trace[384060360] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"265.884546ms","start":"2026-04-14T06:18:50.664493Z","end":"2026-04-14T06:18:50.930378Z","steps":["trace[384060360] 'process raft request' (duration: 265.511618ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:18:50.930464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.57743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-14T06:18:50.930947Z","caller":"traceutil/trace.go:171","msg":"trace[1254034873] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:631; }","duration":"492.116453ms","start":"2026-04-14T06:18:50.43882Z","end":"2026-04-14T06:18:50.930937Z","steps":["trace[1254034873] 'agreement among raft nodes before linearized reading' (duration: 491.474578ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:18:50.931011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:18:50.438765Z","time spent":"492.204224ms","remote":"127.0.0.1:42820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-14T06:18:50.930551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.905875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-14T06:18:50.931145Z","caller":"traceutil/trace.go:171","msg":"trace[266343446] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:631; }","duration":"529.5163ms","start":"2026-04-14T06:18:50.401609Z","end":"2026-04-14T06:18:50.931125Z","steps":["trace[266343446] 'agreement among raft nodes before linearized reading' (duration: 528.809913ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:18:50.931196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:18:50.401596Z","time spent":"529.585492ms","remote":"127.0.0.1:42820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-14T06:18:52.200077Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.198973989s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-14T06:18:52.427329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.019665ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-14T06:18:52.427529Z","caller":"traceutil/trace.go:171","msg":"trace[1596488327] linearizableReadLoop","detail":"{readStateIndex:666; appliedIndex:663; }","duration":"1.490262568s","start":"2026-04-14T06:18:50.937253Z","end":"2026-04-14T06:18:52.427516Z","steps":["trace[1596488327] 'read index received' (duration: 63.856514ms)","trace[1596488327] 'applied index is now lower than readState.Index' (duration: 1.426405364s)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:18:52.427601Z","caller":"traceutil/trace.go:171","msg":"trace[1561200018] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"1.491073588s","start":"2026-04-14T06:18:50.936518Z","end":"2026-04-14T06:18:52.427591Z","steps":["trace[1561200018] 'process raft request' (duration: 1.263711935s)","trace[1561200018] 'compare' (duration: 226.81201ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T06:18:52.427641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:18:50.936508Z","time spent":"1.491109978s","remote":"127.0.0.1:42820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T06:18:52.42773Z","caller":"traceutil/trace.go:171","msg":"trace[478475107] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"1.48317534s","start":"2026-04-14T06:18:50.944531Z","end":"2026-04-14T06:18:52.427706Z","steps":["trace[478475107] 'process raft request' (duration: 1.482902294s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:18:52.42779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.236757825s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-14T06:18:52.427819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"963.399751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-14T06:18:52.427823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.490541635s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"warn","ts":"2026-04-14T06:18:52.427851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:18:50.944512Z","time spent":"1.483271852s","remote":"127.0.0.1:42738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T06:18:52.427873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.211348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-14T06:18:52.427893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.169365407s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-14T06:18:52.42789Z","caller":"traceutil/trace.go:171","msg":"trace[314082824] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:635; }","duration":"963.525344ms","start":"2026-04-14T06:18:51.464345Z","end":"2026-04-14T06:18:52.427871Z","steps":["trace[314082824] 'agreement among raft nodes before linearized reading' (duration: 963.389211ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:18:52.427907Z","caller":"traceutil/trace.go:171","msg":"trace[1961927889] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:635; }","duration":"259.27163ms","start":"2026-04-14T06:18:52.168627Z","end":"2026-04-14T06:18:52.427898Z","steps":["trace[1961927889] 'agreement among raft nodes before linearized reading' (duration: 259.216689ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:18:52.427922Z","caller":"traceutil/trace.go:171","msg":"trace[1584887941] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:635; }","duration":"1.169441848s","start":"2026-04-14T06:18:51.258472Z","end":"2026-04-14T06:18:52.427914Z","steps":["trace[1584887941] 'agreement among raft nodes before linearized reading' (duration: 1.169392587s)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:18:52.427917Z","caller":"traceutil/trace.go:171","msg":"trace[821032992] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:635; }","duration":"1.490655698s","start":"2026-04-14T06:18:50.937236Z","end":"2026-04-14T06:18:52.427892Z","steps":["trace[821032992] 'agreement among raft nodes before linearized reading' (duration: 1.490442943s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:18:52.427941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:18:51.464323Z","time spent":"963.603227ms","remote":"127.0.0.1:42936","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":29,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true "} {"level":"info","ts":"2026-04-14T06:18:52.427844Z","caller":"traceutil/trace.go:171","msg":"trace[1068803124] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:635; }","duration":"1.236872318s","start":"2026-04-14T06:18:51.190957Z","end":"2026-04-14T06:18:52.427829Z","steps":["trace[1068803124] 'agreement among raft nodes before linearized reading' (duration: 1.236740975s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:18:52.427967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:18:51.258446Z","time spent":"1.169508s","remote":"127.0.0.1:43012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":2,"response size":31,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"warn","ts":"2026-04-14T06:18:52.427993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:18:50.937227Z","time spent":"1.490732209s","remote":"127.0.0.1:42742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":217,"request content":"key:\"/registry/serviceaccounts/kube-system/job-controller\" "} {"level":"warn","ts":"2026-04-14T06:18:52.42801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:18:51.190931Z","time spent":"1.237065682s","remote":"127.0.0.1:38446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-14T06:18:54.454131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.175402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T06:18:54.454222Z","caller":"traceutil/trace.go:171","msg":"trace[1590559646] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:653; }","duration":"263.320056ms","start":"2026-04-14T06:18:54.190882Z","end":"2026-04-14T06:18:54.454202Z","steps":["trace[1590559646] 'range keys from in-memory index tree' (duration: 263.04308ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:18:55.146069Z","caller":"traceutil/trace.go:171","msg":"trace[286722580] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"183.929573ms","start":"2026-04-14T06:18:54.962117Z","end":"2026-04-14T06:18:55.146047Z","steps":["trace[286722580] 'process raft request' (duration: 183.81545ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:18:55.189344Z","caller":"traceutil/trace.go:171","msg":"trace[1344038423] linearizableReadLoop","detail":"{readStateIndex:713; appliedIndex:711; }","duration":"111.116465ms","start":"2026-04-14T06:18:55.078208Z","end":"2026-04-14T06:18:55.189325Z","steps":["trace[1344038423] 'read index received' (duration: 67.817058ms)","trace[1344038423] 'applied index is now lower than readState.Index' (duration: 43.298207ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:18:55.189456Z","caller":"traceutil/trace.go:171","msg":"trace[1784860901] transaction","detail":"{read_only:false; response_revision:680; number_of_response:1; }","duration":"115.348776ms","start":"2026-04-14T06:18:55.074065Z","end":"2026-04-14T06:18:55.189414Z","steps":["trace[1784860901] 'process raft request' (duration: 115.163561ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:18:55.189781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.45732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-14T06:18:55.189863Z","caller":"traceutil/trace.go:171","msg":"trace[499498734] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:680; }","duration":"110.565932ms","start":"2026-04-14T06:18:55.079287Z","end":"2026-04-14T06:18:55.189853Z","steps":["trace[499498734] 'agreement among raft nodes before linearized reading' (duration: 110.202943ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:18:55.189773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.547795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-14T06:18:55.190083Z","caller":"traceutil/trace.go:171","msg":"trace[1742843472] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:680; }","duration":"111.894164ms","start":"2026-04-14T06:18:55.078169Z","end":"2026-04-14T06:18:55.190063Z","steps":["trace[1742843472] 'agreement among raft nodes before linearized reading' (duration: 111.273709ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:19:08.387083Z","caller":"traceutil/trace.go:171","msg":"trace[54402018] linearizableReadLoop","detail":"{readStateIndex:834; appliedIndex:833; }","duration":"172.818159ms","start":"2026-04-14T06:19:08.214243Z","end":"2026-04-14T06:19:08.387061Z","steps":["trace[54402018] 'read index received' (duration: 172.634435ms)","trace[54402018] 'applied index is now lower than readState.Index' (duration: 182.904µs)"],"step_count":2} {"level":"warn","ts":"2026-04-14T06:19:08.387193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.937002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T06:19:08.387216Z","caller":"traceutil/trace.go:171","msg":"trace[191529773] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:797; }","duration":"173.017743ms","start":"2026-04-14T06:19:08.214192Z","end":"2026-04-14T06:19:08.387209Z","steps":["trace[191529773] 'agreement among raft nodes before linearized reading' (duration: 172.954092ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:19:08.387333Z","caller":"traceutil/trace.go:171","msg":"trace[1332116498] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"468.415529ms","start":"2026-04-14T06:19:07.91871Z","end":"2026-04-14T06:19:08.387125Z","steps":["trace[1332116498] 'process raft request' (duration: 468.244395ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:19:08.387747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:19:07.918683Z","time spent":"468.86002ms","remote":"127.0.0.1:42738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4028,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T06:19:43.203127Z","caller":"traceutil/trace.go:171","msg":"trace[1682228044] transaction","detail":"{read_only:false; response_revision:1009; number_of_response:1; }","duration":"505.215327ms","start":"2026-04-14T06:19:42.697891Z","end":"2026-04-14T06:19:43.203106Z","steps":["trace[1682228044] 'process raft request' (duration: 505.082124ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:19:43.203221Z","caller":"traceutil/trace.go:171","msg":"trace[380727126] linearizableReadLoop","detail":"{readStateIndex:1055; appliedIndex:1055; }","duration":"263.628206ms","start":"2026-04-14T06:19:42.93957Z","end":"2026-04-14T06:19:43.203198Z","steps":["trace[380727126] 'read index received' (duration: 263.616206ms)","trace[380727126] 'applied index is now lower than readState.Index' (duration: 9.4µs)"],"step_count":2} {"level":"warn","ts":"2026-04-14T06:19:43.203319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.744599ms","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-04-14T06:19:43.203363Z","caller":"traceutil/trace.go:171","msg":"trace[702543590] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:1009; }","duration":"263.828521ms","start":"2026-04-14T06:19:42.939522Z","end":"2026-04-14T06:19:43.203351Z","steps":["trace[702543590] 'agreement among raft nodes before linearized reading' (duration: 263.752899ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:19:43.203736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:19:42.697817Z","time spent":"505.402501ms","remote":"127.0.0.1:42820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T06:19:44.22846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"767.544521ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-14T06:19:44.228538Z","caller":"traceutil/trace.go:171","msg":"trace[1942731770] linearizableReadLoop","detail":"{readStateIndex:1056; appliedIndex:1055; }","duration":"1.025252376s","start":"2026-04-14T06:19:43.203274Z","end":"2026-04-14T06:19:44.228527Z","steps":["trace[1942731770] 'read index received' (duration: 257.549631ms)","trace[1942731770] 'applied index is now lower than readState.Index' (duration: 767.701935ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:19:44.228705Z","caller":"traceutil/trace.go:171","msg":"trace[1689267639] transaction","detail":"{read_only:false; response_revision:1010; number_of_response:1; }","duration":"1.107947728s","start":"2026-04-14T06:19:43.120748Z","end":"2026-04-14T06:19:44.228696Z","steps":["trace[1689267639] 'process raft request' (duration: 340.10542ms)","trace[1689267639] 'compare' (duration: 767.475119ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T06:19:44.228781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:19:43.120728Z","time spent":"1.107995819s","remote":"127.0.0.1:42820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T06:19:44.228926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.116041301s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T06:19:44.228968Z","caller":"traceutil/trace.go:171","msg":"trace[1927080304] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:1010; }","duration":"1.116124072s","start":"2026-04-14T06:19:43.112831Z","end":"2026-04-14T06:19:44.228955Z","steps":["trace[1927080304] 'agreement among raft nodes before linearized reading' (duration: 1.116062291s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:19:44.228988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:19:43.11281Z","time spent":"1.116172504s","remote":"127.0.0.1:43008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":29,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"warn","ts":"2026-04-14T06:19:44.229162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.263486346s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-14T06:19:44.229257Z","caller":"traceutil/trace.go:171","msg":"trace[1844713060] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1010; }","duration":"1.26362646s","start":"2026-04-14T06:19:42.965612Z","end":"2026-04-14T06:19:44.229239Z","steps":["trace[1844713060] 'agreement among raft nodes before linearized reading' (duration: 1.263353313s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:19:44.22932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:19:42.965597Z","time spent":"1.263709912s","remote":"127.0.0.1:42820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-14T06:19:44.229394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.01199501s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-14T06:19:44.229419Z","caller":"traceutil/trace.go:171","msg":"trace[2111887889] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1010; }","duration":"1.012056851s","start":"2026-04-14T06:19:43.217355Z","end":"2026-04-14T06:19:44.229412Z","steps":["trace[2111887889] 'agreement among raft nodes before linearized reading' (duration: 1.011963189s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:19:44.229439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:19:43.217338Z","time spent":"1.012096302s","remote":"127.0.0.1:42820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-14T06:19:44.229449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"724.865533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-14T06:19:44.229516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"755.429103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-14T06:19:44.229517Z","caller":"traceutil/trace.go:171","msg":"trace[1551117332] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1010; }","duration":"724.953635ms","start":"2026-04-14T06:19:43.504533Z","end":"2026-04-14T06:19:44.229487Z","steps":["trace[1551117332] 'agreement among raft nodes before linearized reading' (duration: 724.788661ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:19:44.229532Z","caller":"traceutil/trace.go:171","msg":"trace[1598565253] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1010; }","duration":"755.471393ms","start":"2026-04-14T06:19:43.474055Z","end":"2026-04-14T06:19:44.229527Z","steps":["trace[1598565253] 'agreement among raft nodes before linearized reading' (duration: 755.425942ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:19:44.22955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:19:43.474039Z","time spent":"755.507504ms","remote":"127.0.0.1:42820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-14T06:19:44.22956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:19:43.504515Z","time spent":"725.034907ms","remote":"127.0.0.1:42820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-14T06:19:44.577025Z","caller":"traceutil/trace.go:171","msg":"trace[338708034] transaction","detail":"{read_only:false; response_revision:1011; number_of_response:1; }","duration":"344.038344ms","start":"2026-04-14T06:19:44.232965Z","end":"2026-04-14T06:19:44.577003Z","steps":["trace[338708034] 'process raft request' (duration: 325.274336ms)","trace[338708034] 'compare' (duration: 18.625404ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T06:19:44.57713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:19:44.232949Z","time spent":"344.125446ms","remote":"127.0.0.1:42820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T06:19:44.579281Z","caller":"traceutil/trace.go:171","msg":"trace[1754740428] transaction","detail":"{read_only:false; response_revision:1012; number_of_response:1; }","duration":"345.825616ms","start":"2026-04-14T06:19:44.233438Z","end":"2026-04-14T06:19:44.579264Z","steps":["trace[1754740428] 'process raft request' (duration: 345.714624ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:19:44.579406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:19:44.233421Z","time spent":"345.916648ms","remote":"127.0.0.1:42820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T06:19:44.57949Z","caller":"traceutil/trace.go:171","msg":"trace[369034372] transaction","detail":"{read_only:false; response_revision:1013; number_of_response:1; }","duration":"345.812776ms","start":"2026-04-14T06:19:44.233661Z","end":"2026-04-14T06:19:44.579474Z","steps":["trace[369034372] 'process raft request' (duration: 345.568111ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:19:44.579547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:19:44.233652Z","time spent":"345.869898ms","remote":"127.0.0.1:42820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T06:19:44.579558Z","caller":"traceutil/trace.go:171","msg":"trace[2067252217] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"345.812976ms","start":"2026-04-14T06:19:44.233724Z","end":"2026-04-14T06:19:44.579537Z","steps":["trace[2067252217] 'process raft request' (duration: 345.639112ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:19:44.57968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:19:44.233717Z","time spent":"345.908158ms","remote":"127.0.0.1:42820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T06:19:44.579814Z","caller":"traceutil/trace.go:171","msg":"trace[1568951094] transaction","detail":"{read_only:false; response_revision:1015; number_of_response:1; }","duration":"114.112191ms","start":"2026-04-14T06:19:44.465694Z","end":"2026-04-14T06:19:44.579807Z","steps":["trace[1568951094] 'process raft request' (duration: 113.792344ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:20:07.241039Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471725592835173506,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-14T06:20:07.741388Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471725592835173506,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-14T06:20:07.7758Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.035651235s","expected-duration":"1s"} {"level":"info","ts":"2026-04-14T06:20:07.776292Z","caller":"traceutil/trace.go:171","msg":"trace[1832624951] linearizableReadLoop","detail":"{readStateIndex:1135; appliedIndex:1134; }","duration":"1.035343807s","start":"2026-04-14T06:20:06.740929Z","end":"2026-04-14T06:20:07.776273Z","steps":["trace[1832624951] 'read index received' (duration: 1.03502462s)","trace[1832624951] 'applied index is now lower than readState.Index' (duration: 318.337µs)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:20:07.77638Z","caller":"traceutil/trace.go:171","msg":"trace[1247594155] transaction","detail":"{read_only:false; response_revision:1084; number_of_response:1; }","duration":"1.03629349s","start":"2026-04-14T06:20:06.740043Z","end":"2026-04-14T06:20:07.776337Z","steps":["trace[1247594155] 'process raft request' (duration: 1.036100465s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:20:07.776525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.173803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-14T06:20:07.776559Z","caller":"traceutil/trace.go:171","msg":"trace[427435166] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1084; }","duration":"408.224344ms","start":"2026-04-14T06:20:07.368326Z","end":"2026-04-14T06:20:07.776551Z","steps":["trace[427435166] 'agreement among raft nodes before linearized reading' (duration: 408.134752ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:20:07.776603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:20:07.368265Z","time spent":"408.332127ms","remote":"127.0.0.1:42670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":5,"response size":31,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"warn","ts":"2026-04-14T06:20:07.776675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.010931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-14T06:20:07.776709Z","caller":"traceutil/trace.go:171","msg":"trace[1798612936] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1084; }","duration":"398.056711ms","start":"2026-04-14T06:20:07.378642Z","end":"2026-04-14T06:20:07.776699Z","steps":["trace[1798612936] 'agreement among raft nodes before linearized reading' (duration: 397.915748ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:20:07.776674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"987.944457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-14T06:20:07.77673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:20:07.378635Z","time spent":"398.089892ms","remote":"127.0.0.1:42820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-14T06:20:07.776767Z","caller":"traceutil/trace.go:171","msg":"trace[1470519365] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1084; }","duration":"988.08498ms","start":"2026-04-14T06:20:06.788659Z","end":"2026-04-14T06:20:07.776744Z","steps":["trace[1470519365] 'agreement among raft nodes before linearized reading' (duration: 987.837804ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:20:07.776771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.035822748s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-14T06:20:07.776823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:20:06.788638Z","time spent":"988.171663ms","remote":"127.0.0.1:42820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-14T06:20:07.776848Z","caller":"traceutil/trace.go:171","msg":"trace[1885723780] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1084; }","duration":"1.035937671s","start":"2026-04-14T06:20:06.740895Z","end":"2026-04-14T06:20:07.776832Z","steps":["trace[1885723780] 'agreement among raft nodes before linearized reading' (duration: 1.035482941s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:20:07.776674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.022165773s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-14T06:20:07.776893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:20:06.740884Z","time spent":"1.035998403s","remote":"127.0.0.1:42820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-14T06:20:07.776905Z","caller":"traceutil/trace.go:171","msg":"trace[903683586] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1084; }","duration":"1.02244997s","start":"2026-04-14T06:20:06.754447Z","end":"2026-04-14T06:20:07.776897Z","steps":["trace[903683586] 'agreement among raft nodes before linearized reading' (duration: 1.021997859s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:20:07.777373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:20:06.754431Z","time spent":"1.022925421s","remote":"127.0.0.1:42820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-14T06:20:07.776935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:20:06.740024Z","time spent":"1.036412663s","remote":"127.0.0.1:42820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T06:20:10.063367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.638113ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-14T06:20:10.063464Z","caller":"traceutil/trace.go:171","msg":"trace[1099934978] linearizableReadLoop","detail":"{readStateIndex:1142; appliedIndex:1141; }","duration":"281.879481ms","start":"2026-04-14T06:20:09.781567Z","end":"2026-04-14T06:20:10.063446Z","steps":["trace[1099934978] 'read index received' (duration: 76.094065ms)","trace[1099934978] 'applied index is now lower than readState.Index' (duration: 205.783056ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:20:10.063586Z","caller":"traceutil/trace.go:171","msg":"trace[1823856262] transaction","detail":"{read_only:false; response_revision:1090; number_of_response:1; }","duration":"299.888561ms","start":"2026-04-14T06:20:09.763656Z","end":"2026-04-14T06:20:10.063544Z","steps":["trace[1823856262] 'process raft request' (duration: 93.995941ms)","trace[1823856262] 'compare' (duration: 205.341727ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T06:20:10.063704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.131767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-14T06:20:10.063743Z","caller":"traceutil/trace.go:171","msg":"trace[949779968] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1090; }","duration":"282.201249ms","start":"2026-04-14T06:20:09.781532Z","end":"2026-04-14T06:20:10.063733Z","steps":["trace[949779968] 'agreement among raft nodes before linearized reading' (duration: 281.995094ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:20:10.063735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:20:09.763634Z","time spent":"300.022764ms","remote":"127.0.0.1:38492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T06:20:10.063833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.3255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-14T06:20:10.063962Z","caller":"traceutil/trace.go:171","msg":"trace[312310977] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1090; }","duration":"258.492333ms","start":"2026-04-14T06:20:09.805454Z","end":"2026-04-14T06:20:10.063946Z","steps":["trace[312310977] 'agreement among raft nodes before linearized reading' (duration: 258.239457ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:20:10.063949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.326262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-14T06:20:10.064032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.62371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-14T06:20:10.063881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.888399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-14T06:20:10.064068Z","caller":"traceutil/trace.go:171","msg":"trace[2037870066] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1090; }","duration":"220.680192ms","start":"2026-04-14T06:20:09.843379Z","end":"2026-04-14T06:20:10.064059Z","steps":["trace[2037870066] 'agreement among raft nodes before linearized reading' (duration: 220.59059ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:20:10.064052Z","caller":"traceutil/trace.go:171","msg":"trace[1047974809] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1090; }","duration":"118.468965ms","start":"2026-04-14T06:20:09.945558Z","end":"2026-04-14T06:20:10.064027Z","steps":["trace[1047974809] 'agreement among raft nodes before linearized reading' (duration: 118.26142ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:20:10.064084Z","caller":"traceutil/trace.go:171","msg":"trace[321614445] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1090; }","duration":"219.163055ms","start":"2026-04-14T06:20:09.844905Z","end":"2026-04-14T06:20:10.064068Z","steps":["trace[321614445] 'agreement among raft nodes before linearized reading' (duration: 218.799506ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:20:10.064122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.780504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-14T06:20:10.064187Z","caller":"traceutil/trace.go:171","msg":"trace[1346924315] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1090; }","duration":"220.864817ms","start":"2026-04-14T06:20:09.843305Z","end":"2026-04-14T06:20:10.06417Z","steps":["trace[1346924315] 'agreement among raft nodes before linearized reading' (duration: 220.733043ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:20:10.273179Z","caller":"traceutil/trace.go:171","msg":"trace[2121562087] transaction","detail":"{read_only:false; response_revision:1091; number_of_response:1; }","duration":"205.012759ms","start":"2026-04-14T06:20:10.068134Z","end":"2026-04-14T06:20:10.273147Z","steps":["trace[2121562087] 'process raft request' (duration: 117.85884ms)","trace[2121562087] 'compare' (duration: 86.963654ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:20:10.273199Z","caller":"traceutil/trace.go:171","msg":"trace[1780612251] transaction","detail":"{read_only:false; response_revision:1092; number_of_response:1; }","duration":"204.058486ms","start":"2026-04-14T06:20:10.069115Z","end":"2026-04-14T06:20:10.273174Z","steps":["trace[1780612251] 'process raft request' (duration: 203.975064ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:20:10.273352Z","caller":"traceutil/trace.go:171","msg":"trace[49242805] linearizableReadLoop","detail":"{readStateIndex:1146; appliedIndex:1142; }","duration":"203.81099ms","start":"2026-04-14T06:20:10.069526Z","end":"2026-04-14T06:20:10.273337Z","steps":["trace[49242805] 'read index received' (duration: 116.479358ms)","trace[49242805] 'applied index is now lower than readState.Index' (duration: 87.330852ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:20:10.273348Z","caller":"traceutil/trace.go:171","msg":"trace[318287856] transaction","detail":"{read_only:false; response_revision:1093; number_of_response:1; }","duration":"204.185469ms","start":"2026-04-14T06:20:10.069132Z","end":"2026-04-14T06:20:10.273317Z","steps":["trace[318287856] 'process raft request' (duration: 204.000124ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:20:10.273471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.940413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-04-14T06:20:10.27347Z","caller":"traceutil/trace.go:171","msg":"trace[1025916954] transaction","detail":"{read_only:false; response_revision:1094; number_of_response:1; }","duration":"204.264221ms","start":"2026-04-14T06:20:10.069166Z","end":"2026-04-14T06:20:10.27343Z","steps":["trace[1025916954] 'process raft request' (duration: 204.066426ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:20:10.273513Z","caller":"traceutil/trace.go:171","msg":"trace[1399346957] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:1095; }","duration":"204.029075ms","start":"2026-04-14T06:20:10.069474Z","end":"2026-04-14T06:20:10.273504Z","steps":["trace[1399346957] 'agreement among raft nodes before linearized reading' (duration: 203.918143ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:20:10.273511Z","caller":"traceutil/trace.go:171","msg":"trace[1112109829] transaction","detail":"{read_only:false; response_revision:1095; number_of_response:1; }","duration":"203.074372ms","start":"2026-04-14T06:20:10.070421Z","end":"2026-04-14T06:20:10.273495Z","steps":["trace[1112109829] 'process raft request' (duration: 202.878857ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:20:10.504821Z","caller":"traceutil/trace.go:171","msg":"trace[1236039346] transaction","detail":"{read_only:false; response_revision:1096; number_of_response:1; }","duration":"151.846931ms","start":"2026-04-14T06:20:10.352954Z","end":"2026-04-14T06:20:10.504801Z","steps":["trace[1236039346] 'process raft request' (duration: 85.582351ms)","trace[1236039346] 'compare' (duration: 66.173608ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:20:12.562783Z","caller":"traceutil/trace.go:171","msg":"trace[2115517091] transaction","detail":"{read_only:false; response_revision:1097; number_of_response:1; }","duration":"279.73322ms","start":"2026-04-14T06:20:12.283028Z","end":"2026-04-14T06:20:12.562761Z","steps":["trace[2115517091] 'process raft request' (duration: 279.595827ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:20:13.056372Z","caller":"traceutil/trace.go:171","msg":"trace[539237915] transaction","detail":"{read_only:false; response_revision:1098; number_of_response:1; }","duration":"773.050492ms","start":"2026-04-14T06:20:12.283292Z","end":"2026-04-14T06:20:13.056343Z","steps":["trace[539237915] 'process raft request' (duration: 772.835497ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:20:13.056691Z","caller":"traceutil/trace.go:171","msg":"trace[1873364731] transaction","detail":"{read_only:false; response_revision:1099; number_of_response:1; }","duration":"773.237546ms","start":"2026-04-14T06:20:12.283438Z","end":"2026-04-14T06:20:13.056675Z","steps":["trace[1873364731] 'process raft request' (duration: 772.860687ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:20:13.056785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:20:12.283431Z","time spent":"773.306769ms","remote":"127.0.0.1:42820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T06:20:13.05713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:20:12.283268Z","time spent":"773.291899ms","remote":"127.0.0.1:42820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T06:20:13.057235Z","caller":"traceutil/trace.go:171","msg":"trace[1221271859] transaction","detail":"{read_only:false; response_revision:1100; number_of_response:1; }","duration":"773.127804ms","start":"2026-04-14T06:20:12.284095Z","end":"2026-04-14T06:20:13.057223Z","steps":["trace[1221271859] 'process raft request' (duration: 772.52743ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:20:13.057308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:20:12.284077Z","time spent":"773.196026ms","remote":"127.0.0.1:42820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T06:20:13.057565Z","caller":"traceutil/trace.go:171","msg":"trace[50896800] transaction","detail":"{read_only:false; response_revision:1101; number_of_response:1; }","duration":"772.752345ms","start":"2026-04-14T06:20:12.284796Z","end":"2026-04-14T06:20:13.057549Z","steps":["trace[50896800] 'process raft request' (duration: 772.386346ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:20:13.057642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:20:12.284784Z","time spent":"772.818796ms","remote":"127.0.0.1:42820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T06:20:13.057867Z","caller":"traceutil/trace.go:171","msg":"trace[588125230] transaction","detail":"{read_only:false; response_revision:1102; number_of_response:1; }","duration":"595.099439ms","start":"2026-04-14T06:20:12.462749Z","end":"2026-04-14T06:20:13.057848Z","steps":["trace[588125230] 'process raft request' (duration: 594.7288ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:20:13.058006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:20:12.462723Z","time spent":"595.198191ms","remote":"127.0.0.1:42820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T06:20:13.058233Z","caller":"traceutil/trace.go:171","msg":"trace[1437557054] linearizableReadLoop","detail":"{readStateIndex:1155; appliedIndex:1149; }","duration":"292.604397ms","start":"2026-04-14T06:20:12.765617Z","end":"2026-04-14T06:20:13.058222Z","steps":["trace[1437557054] 'read index received' (duration: 290.372604ms)","trace[1437557054] 'applied index is now lower than readState.Index' (duration: 2.230613ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T06:20:13.058313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.693079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T06:20:13.058341Z","caller":"traceutil/trace.go:171","msg":"trace[386508721] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:1103; }","duration":"292.751201ms","start":"2026-04-14T06:20:12.765582Z","end":"2026-04-14T06:20:13.058333Z","steps":["trace[386508721] 'agreement among raft nodes before linearized reading' (duration: 292.678759ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:20:13.058437Z","caller":"traceutil/trace.go:171","msg":"trace[1879622494] transaction","detail":"{read_only:false; response_revision:1103; number_of_response:1; }","duration":"548.888587ms","start":"2026-04-14T06:20:12.509525Z","end":"2026-04-14T06:20:13.058413Z","steps":["trace[1879622494] 'process raft request' (duration: 548.025066ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:20:13.058646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:20:12.509497Z","time spent":"549.01121ms","remote":"127.0.0.1:42820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T06:20:15.189027Z","caller":"traceutil/trace.go:171","msg":"trace[677728605] transaction","detail":"{read_only:false; response_revision:1106; number_of_response:1; }","duration":"123.532946ms","start":"2026-04-14T06:20:15.065461Z","end":"2026-04-14T06:20:15.188994Z","steps":["trace[677728605] 'process raft request' (duration: 101.374048ms)","trace[677728605] 'compare' (duration: 21.954503ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:20:15.189039Z","caller":"traceutil/trace.go:171","msg":"trace[880027720] transaction","detail":"{read_only:false; response_revision:1107; number_of_response:1; }","duration":"123.302101ms","start":"2026-04-14T06:20:15.065711Z","end":"2026-04-14T06:20:15.189013Z","steps":["trace[880027720] 'process raft request' (duration: 123.185308ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:20:15.189252Z","caller":"traceutil/trace.go:171","msg":"trace[352509053] transaction","detail":"{read_only:false; response_revision:1108; number_of_response:1; }","duration":"123.362072ms","start":"2026-04-14T06:20:15.065875Z","end":"2026-04-14T06:20:15.189237Z","steps":["trace[352509053] 'process raft request' (duration: 123.091855ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:20:15.189381Z","caller":"traceutil/trace.go:171","msg":"trace[699282967] transaction","detail":"{read_only:false; response_revision:1109; number_of_response:1; }","duration":"123.036064ms","start":"2026-04-14T06:20:15.066313Z","end":"2026-04-14T06:20:15.189349Z","steps":["trace[699282967] 'process raft request' (duration: 122.86458ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:20:20.745512Z","caller":"traceutil/trace.go:171","msg":"trace[764306849] transaction","detail":"{read_only:false; response_revision:1124; number_of_response:1; }","duration":"140.537212ms","start":"2026-04-14T06:20:20.604944Z","end":"2026-04-14T06:20:20.745482Z","steps":["trace[764306849] 'process raft request' (duration: 140.282835ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:20:29.987519Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471725592835173782,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-14T06:20:30.488002Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471725592835173782,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-14T06:20:30.545931Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.061784507s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-14T06:20:31.86599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.319776799s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-14T06:20:31.866103Z","caller":"traceutil/trace.go:171","msg":"trace[537933888] linearizableReadLoop","detail":"{readStateIndex:1262; appliedIndex:1261; }","duration":"2.378808111s","start":"2026-04-14T06:20:29.487277Z","end":"2026-04-14T06:20:31.866086Z","steps":["trace[537933888] 'read index received' (duration: 1.058770085s)","trace[537933888] 'applied index is now lower than readState.Index' (duration: 1.320036186s)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:20:31.866842Z","caller":"traceutil/trace.go:171","msg":"trace[728267546] transaction","detail":"{read_only:false; response_revision:1205; number_of_response:1; }","duration":"2.382718984s","start":"2026-04-14T06:20:29.484047Z","end":"2026-04-14T06:20:31.866766Z","steps":["trace[728267546] 'process raft request' (duration: 1.062071234s)","trace[728267546] 'compare' (duration: 1.319434751s)"],"step_count":2} {"level":"warn","ts":"2026-04-14T06:20:31.866868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.379502258s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-14T06:20:31.867001Z","caller":"traceutil/trace.go:171","msg":"trace[2097906390] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1205; }","duration":"2.379741633s","start":"2026-04-14T06:20:29.487236Z","end":"2026-04-14T06:20:31.866978Z","steps":["trace[2097906390] 'agreement among raft nodes before linearized reading' (duration: 2.379386975s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:20:31.867041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:20:29.484027Z","time spent":"2.382875999s","remote":"127.0.0.1:42820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T06:20:31.867079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:20:29.487225Z","time spent":"2.379839776s","remote":"127.0.0.1:42820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-14T06:20:32.191394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999962848s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-14T06:20:32.191469Z","caller":"traceutil/trace.go:171","msg":"trace[303975153] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000085181s","start":"2026-04-14T06:20:30.191366Z","end":"2026-04-14T06:20:32.191451Z","steps":["trace[303975153] 'agreement among raft nodes before linearized reading' (duration: 1.999993299s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:20:32.191518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:20:30.191348Z","time spent":"2.000161263s","remote":"127.0.0.1:38446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/14 06:20:32 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-14T06:20:32.366834Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471725592835173785,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-14T06:20:32.854945Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.528337193s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-14T06:20:33.484274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"629.029709ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-14T06:20:33.484442Z","caller":"traceutil/trace.go:171","msg":"trace[768438576] linearizableReadLoop","detail":"{readStateIndex:1264; appliedIndex:1262; }","duration":"1.618295118s","start":"2026-04-14T06:20:31.866131Z","end":"2026-04-14T06:20:33.484426Z","steps":["trace[768438576] 'read index received' (duration: 989.026213ms)","trace[768438576] 'applied index is now lower than readState.Index' (duration: 629.267775ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T06:20:33.48464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.025397065s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-14T06:20:33.484671Z","caller":"traceutil/trace.go:171","msg":"trace[1122909123] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1207; }","duration":"2.025429766s","start":"2026-04-14T06:20:31.459232Z","end":"2026-04-14T06:20:33.484662Z","steps":["trace[1122909123] 'agreement among raft nodes before linearized reading' (duration: 2.025308983s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:20:33.484704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:20:31.45922Z","time spent":"2.025476008s","remote":"127.0.0.1:42820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-14T06:20:33.484759Z","caller":"traceutil/trace.go:171","msg":"trace[860549768] transaction","detail":"{read_only:false; response_revision:1206; number_of_response:1; }","duration":"2.158231182s","start":"2026-04-14T06:20:31.326502Z","end":"2026-04-14T06:20:33.484733Z","steps":["trace[860549768] 'process raft request' (duration: 1.52863932s)","trace[860549768] 'compare' (duration: 628.713592ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T06:20:33.48486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:20:31.326476Z","time spent":"2.158325965s","remote":"127.0.0.1:42820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T06:20:33.48493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.68903711s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-14T06:20:33.484975Z","caller":"traceutil/trace.go:171","msg":"trace[263805580] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1207; }","duration":"2.689111911s","start":"2026-04-14T06:20:30.79585Z","end":"2026-04-14T06:20:33.484962Z","steps":["trace[263805580] 'agreement among raft nodes before linearized reading' (duration: 2.689008258s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:20:33.485019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:20:30.795834Z","time spent":"2.689173623s","remote":"127.0.0.1:42820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-14T06:20:33.484674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.003508713s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-14T06:20:33.485167Z","caller":"traceutil/trace.go:171","msg":"trace[1146272782] transaction","detail":"{read_only:false; response_revision:1207; number_of_response:1; }","duration":"1.614973879s","start":"2026-04-14T06:20:31.870171Z","end":"2026-04-14T06:20:33.485145Z","steps":["trace[1146272782] 'process raft request' (duration: 1.614196391s)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:20:33.485171Z","caller":"traceutil/trace.go:171","msg":"trace[1462638941] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1207; }","duration":"2.004053586s","start":"2026-04-14T06:20:31.481085Z","end":"2026-04-14T06:20:33.485138Z","steps":["trace[1462638941] 'agreement among raft nodes before linearized reading' (duration: 2.003409911s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:20:33.485263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:20:31.870154Z","time spent":"1.615056011s","remote":"127.0.0.1:42820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T06:20:33.485292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:20:31.481065Z","time spent":"2.00417996s","remote":"127.0.0.1:42820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-14T06:20:33.48558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.816557544s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.138\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-14T06:20:33.485655Z","caller":"traceutil/trace.go:171","msg":"trace[1169202375] range","detail":"{range_begin:/registry/masterleases/199.204.45.138; range_end:; response_count:1; response_revision:1207; }","duration":"3.816646007s","start":"2026-04-14T06:20:29.668985Z","end":"2026-04-14T06:20:33.485631Z","steps":["trace[1169202375] 'agreement among raft nodes before linearized reading' (duration: 3.816439952s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:20:33.485702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:20:29.668969Z","time spent":"3.816723418s","remote":"127.0.0.1:38492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.204.45.138\" "} {"level":"warn","ts":"2026-04-14T06:20:33.535305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.889994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-14T06:20:33.535322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.34367578s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T06:20:33.535392Z","caller":"traceutil/trace.go:171","msg":"trace[1045715082] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:1208; }","duration":"241.031308ms","start":"2026-04-14T06:20:33.29434Z","end":"2026-04-14T06:20:33.535371Z","steps":["trace[1045715082] 'agreement among raft nodes before linearized reading' (duration: 240.859504ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:20:33.535315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.373772087s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-14T06:20:33.535449Z","caller":"traceutil/trace.go:171","msg":"trace[1504165797] transaction","detail":"{read_only:false; response_revision:1208; number_of_response:1; }","duration":"146.194816ms","start":"2026-04-14T06:20:33.389235Z","end":"2026-04-14T06:20:33.53543Z","steps":["trace[1504165797] 'process raft request' (duration: 145.749826ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:20:33.53532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.246968944s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T06:20:33.535553Z","caller":"traceutil/trace.go:171","msg":"trace[1475715633] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1208; }","duration":"1.343801043s","start":"2026-04-14T06:20:32.19159Z","end":"2026-04-14T06:20:33.535391Z","steps":["trace[1475715633] 'agreement among raft nodes before linearized reading' (duration: 1.34366689s)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:20:33.535562Z","caller":"traceutil/trace.go:171","msg":"trace[1358341391] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:1208; }","duration":"1.374062234s","start":"2026-04-14T06:20:32.161449Z","end":"2026-04-14T06:20:33.535511Z","steps":["trace[1358341391] 'agreement among raft nodes before linearized reading' (duration: 1.373714866s)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:20:33.535609Z","caller":"traceutil/trace.go:171","msg":"trace[2128858332] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:1208; }","duration":"1.247329092s","start":"2026-04-14T06:20:32.288266Z","end":"2026-04-14T06:20:33.535596Z","steps":["trace[2128858332] 'agreement among raft nodes before linearized reading' (duration: 1.246956943s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:20:33.535627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:20:32.191572Z","time spent":"1.344040819s","remote":"127.0.0.1:38434","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-14T06:20:33.535652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:20:32.288245Z","time spent":"1.247389034s","remote":"127.0.0.1:39384","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "} {"level":"warn","ts":"2026-04-14T06:20:33.535629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:20:32.161431Z","time spent":"1.374181698s","remote":"127.0.0.1:43072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":29,"response size":31,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"info","ts":"2026-04-14T06:20:33.701282Z","caller":"traceutil/trace.go:171","msg":"trace[2048895543] transaction","detail":"{read_only:false; response_revision:1211; number_of_response:1; }","duration":"212.164969ms","start":"2026-04-14T06:20:33.489096Z","end":"2026-04-14T06:20:33.701261Z","steps":["trace[2048895543] 'process raft request' (duration: 212.132948ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:20:33.701582Z","caller":"traceutil/trace.go:171","msg":"trace[1398179912] transaction","detail":"{read_only:false; response_revision:1209; number_of_response:1; }","duration":"212.841075ms","start":"2026-04-14T06:20:33.488683Z","end":"2026-04-14T06:20:33.701524Z","steps":["trace[1398179912] 'process raft request' (duration: 212.432125ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:20:33.701577Z","caller":"traceutil/trace.go:171","msg":"trace[2117366809] transaction","detail":"{read_only:false; response_revision:1210; number_of_response:1; }","duration":"212.572848ms","start":"2026-04-14T06:20:33.488928Z","end":"2026-04-14T06:20:33.701501Z","steps":["trace[2117366809] 'process raft request' (duration: 212.273331ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:20:33.706864Z","caller":"traceutil/trace.go:171","msg":"trace[1218393529] transaction","detail":"{read_only:false; response_revision:1212; number_of_response:1; }","duration":"165.994938ms","start":"2026-04-14T06:20:33.540846Z","end":"2026-04-14T06:20:33.706841Z","steps":["trace[1218393529] 'process raft request' (duration: 165.853895ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:20:33.706856Z","caller":"traceutil/trace.go:171","msg":"trace[1416171227] linearizableReadLoop","detail":"{readStateIndex:1270; appliedIndex:1265; }","duration":"162.350811ms","start":"2026-04-14T06:20:33.544478Z","end":"2026-04-14T06:20:33.706829Z","steps":["trace[1416171227] 'read index received' (duration: 156.558833ms)","trace[1416171227] 'applied index is now lower than readState.Index' (duration: 5.790748ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T06:20:33.707074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.565036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T06:20:33.707118Z","caller":"traceutil/trace.go:171","msg":"trace[1127594502] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1212; }","duration":"162.668549ms","start":"2026-04-14T06:20:33.544441Z","end":"2026-04-14T06:20:33.70711Z","steps":["trace[1127594502] 'agreement among raft nodes before linearized reading' (duration: 162.499755ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:20:43.867376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.68001ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-14T06:20:43.867512Z","caller":"traceutil/trace.go:171","msg":"trace[897022416] transaction","detail":"{read_only:false; response_revision:1243; number_of_response:1; }","duration":"235.392865ms","start":"2026-04-14T06:20:43.632103Z","end":"2026-04-14T06:20:43.867496Z","steps":["trace[897022416] 'process raft request' (duration: 115.506501ms)","trace[897022416] 'compare' (duration: 119.578937ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:20:43.867506Z","caller":"traceutil/trace.go:171","msg":"trace[1608902097] linearizableReadLoop","detail":"{readStateIndex:1303; appliedIndex:1302; }","duration":"126.902302ms","start":"2026-04-14T06:20:43.74059Z","end":"2026-04-14T06:20:43.867493Z","steps":["trace[1608902097] 'read index received' (duration: 6.972266ms)","trace[1608902097] 'applied index is now lower than readState.Index' (duration: 119.928906ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T06:20:43.867691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.097917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-14T06:20:43.86774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.247569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-14T06:20:43.867737Z","caller":"traceutil/trace.go:171","msg":"trace[799060975] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1243; }","duration":"127.363203ms","start":"2026-04-14T06:20:43.740361Z","end":"2026-04-14T06:20:43.867724Z","steps":["trace[799060975] 'agreement among raft nodes before linearized reading' (duration: 127.221479ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:20:43.86776Z","caller":"traceutil/trace.go:171","msg":"trace[30744362] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1243; }","duration":"119.294431ms","start":"2026-04-14T06:20:43.748461Z","end":"2026-04-14T06:20:43.867756Z","steps":["trace[30744362] 'agreement among raft nodes before linearized reading' (duration: 119.25389ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:20:43.867705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.557576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-14T06:20:43.867852Z","caller":"traceutil/trace.go:171","msg":"trace[686783845] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1243; }","duration":"124.71687ms","start":"2026-04-14T06:20:43.743129Z","end":"2026-04-14T06:20:43.867846Z","steps":["trace[686783845] 'agreement among raft nodes before linearized reading' (duration: 124.539766ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:20:44.335351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.414861ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-14T06:20:44.335464Z","caller":"traceutil/trace.go:171","msg":"trace[1782494688] transaction","detail":"{read_only:false; response_revision:1244; number_of_response:1; }","duration":"464.851519ms","start":"2026-04-14T06:20:43.870593Z","end":"2026-04-14T06:20:44.335444Z","steps":["trace[1782494688] 'process raft request' (duration: 107.263734ms)","trace[1782494688] 'compare' (duration: 356.772766ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T06:20:44.335523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:20:43.87057Z","time spent":"464.926951ms","remote":"127.0.0.1:42820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T06:20:44.7344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.863754ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-14T06:20:44.734543Z","caller":"traceutil/trace.go:171","msg":"trace[1147351954] linearizableReadLoop","detail":"{readStateIndex:1306; appliedIndex:1303; }","duration":"787.352868ms","start":"2026-04-14T06:20:43.947178Z","end":"2026-04-14T06:20:44.734531Z","steps":["trace[1147351954] 'read index received' (duration: 30.688431ms)","trace[1147351954] 'applied index is now lower than readState.Index' (duration: 756.663497ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:20:44.734541Z","caller":"traceutil/trace.go:171","msg":"trace[245996607] transaction","detail":"{read_only:false; response_revision:1245; number_of_response:1; }","duration":"862.677953ms","start":"2026-04-14T06:20:43.871833Z","end":"2026-04-14T06:20:44.734511Z","steps":["trace[245996607] 'process raft request' (duration: 624.530122ms)","trace[245996607] 'compare' (duration: 237.591988ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:20:44.734652Z","caller":"traceutil/trace.go:171","msg":"trace[172182794] transaction","detail":"{read_only:false; response_revision:1246; number_of_response:1; }","duration":"862.504968ms","start":"2026-04-14T06:20:43.872139Z","end":"2026-04-14T06:20:44.734644Z","steps":["trace[172182794] 'process raft request' (duration: 862.324694ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:20:44.734653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:20:43.871812Z","time spent":"862.779285ms","remote":"127.0.0.1:42820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T06:20:44.73469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:20:43.872119Z","time spent":"862.55201ms","remote":"127.0.0.1:42820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T06:20:44.734814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"543.094332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T06:20:44.734829Z","caller":"traceutil/trace.go:171","msg":"trace[112336690] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1246; }","duration":"543.108032ms","start":"2026-04-14T06:20:44.191715Z","end":"2026-04-14T06:20:44.734823Z","steps":["trace[112336690] 'agreement among raft nodes before linearized reading' (duration: 543.082092ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:20:44.734854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:20:44.191673Z","time spent":"543.178364ms","remote":"127.0.0.1:38446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-14T06:20:44.735092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"787.922952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-14T06:20:44.735108Z","caller":"traceutil/trace.go:171","msg":"trace[131762857] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1246; }","duration":"787.973683ms","start":"2026-04-14T06:20:43.947129Z","end":"2026-04-14T06:20:44.735103Z","steps":["trace[131762857] 'agreement among raft nodes before linearized reading' (duration: 787.649935ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:20:44.735121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:20:43.947111Z","time spent":"788.007064ms","remote":"127.0.0.1:42820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-14T06:20:45.192441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.159879ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-14T06:20:45.192587Z","caller":"traceutil/trace.go:171","msg":"trace[498568668] linearizableReadLoop","detail":"{readStateIndex:1307; appliedIndex:1306; }","duration":"211.575819ms","start":"2026-04-14T06:20:44.980993Z","end":"2026-04-14T06:20:45.192569Z","steps":["trace[498568668] 'read index received' (duration: 48.922µs)","trace[498568668] 'applied index is now lower than readState.Index' (duration: 211.525117ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:20:45.1926Z","caller":"traceutil/trace.go:171","msg":"trace[285218962] transaction","detail":"{read_only:false; response_revision:1247; number_of_response:1; }","duration":"454.556623ms","start":"2026-04-14T06:20:44.738001Z","end":"2026-04-14T06:20:45.192558Z","steps":["trace[285218962] 'process raft request' (duration: 156.194339ms)","trace[285218962] 'compare' (duration: 298.053767ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T06:20:45.192654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.660621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T06:20:45.192702Z","caller":"traceutil/trace.go:171","msg":"trace[1866665188] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:1247; }","duration":"211.740043ms","start":"2026-04-14T06:20:44.980952Z","end":"2026-04-14T06:20:45.192692Z","steps":["trace[1866665188] 'agreement among raft nodes before linearized reading' (duration: 211.668891ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:20:45.192741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:20:44.73799Z","time spent":"454.676406ms","remote":"127.0.0.1:42820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T06:20:45.192772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.362348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-14T06:20:45.19283Z","caller":"traceutil/trace.go:171","msg":"trace[1832571662] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:1247; }","duration":"178.44099ms","start":"2026-04-14T06:20:45.014373Z","end":"2026-04-14T06:20:45.192814Z","steps":["trace[1832571662] 'agreement among raft nodes before linearized reading' (duration: 178.319487ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:20:46.372701Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471725592835173889,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-14T06:20:46.788322Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.153894477s","expected-duration":"1s"} {"level":"info","ts":"2026-04-14T06:20:46.789157Z","caller":"traceutil/trace.go:171","msg":"trace[504839532] transaction","detail":"{read_only:false; response_revision:1248; number_of_response:1; }","duration":"1.154792049s","start":"2026-04-14T06:20:45.634339Z","end":"2026-04-14T06:20:46.789131Z","steps":["trace[504839532] 'process raft request' (duration: 1.154464631s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:20:46.78928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:20:45.634312Z","time spent":"1.154891321s","remote":"127.0.0.1:42820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T06:20:46.805288Z","caller":"traceutil/trace.go:171","msg":"trace[254858808] linearizableReadLoop","detail":"{readStateIndex:1309; appliedIndex:1308; }","duration":"933.162291ms","start":"2026-04-14T06:20:45.872106Z","end":"2026-04-14T06:20:46.805269Z","steps":["trace[254858808] 'read index received' (duration: 917.218061ms)","trace[254858808] 'applied index is now lower than readState.Index' (duration: 15.94358ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:20:46.805479Z","caller":"traceutil/trace.go:171","msg":"trace[1946694348] transaction","detail":"{read_only:false; response_revision:1249; number_of_response:1; }","duration":"763.188383ms","start":"2026-04-14T06:20:46.042267Z","end":"2026-04-14T06:20:46.805456Z","steps":["trace[1946694348] 'process raft request' (duration: 762.894487ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:20:46.80555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"933.436057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-14T06:20:46.805582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:20:46.042243Z","time spent":"763.287706ms","remote":"127.0.0.1:42820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T06:20:46.805591Z","caller":"traceutil/trace.go:171","msg":"trace[2016532280] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1249; }","duration":"933.534589ms","start":"2026-04-14T06:20:45.872045Z","end":"2026-04-14T06:20:46.805579Z","steps":["trace[2016532280] 'agreement among raft nodes before linearized reading' (duration: 933.340265ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:20:46.805623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:20:45.872022Z","time spent":"933.588911ms","remote":"127.0.0.1:42820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-14T06:20:46.805608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"614.293058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T06:20:46.80568Z","caller":"traceutil/trace.go:171","msg":"trace[79290785] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1249; }","duration":"614.40292ms","start":"2026-04-14T06:20:46.191259Z","end":"2026-04-14T06:20:46.805662Z","steps":["trace[79290785] 'agreement among raft nodes before linearized reading' (duration: 614.266257ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:20:46.805718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:20:46.191247Z","time spent":"614.463563ms","remote":"127.0.0.1:38446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-14T06:20:46.805721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.062878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-14T06:20:46.805754Z","caller":"traceutil/trace.go:171","msg":"trace[635312923] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1249; }","duration":"466.126699ms","start":"2026-04-14T06:20:46.339618Z","end":"2026-04-14T06:20:46.805745Z","steps":["trace[635312923] 'agreement among raft nodes before linearized reading' (duration: 466.037247ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:20:46.805787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:20:46.339604Z","time spent":"466.17626ms","remote":"127.0.0.1:42820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-14T06:20:48.522277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.017375676s","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-04-14T06:20:48.522392Z","caller":"traceutil/trace.go:171","msg":"trace[728088197] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1249; }","duration":"1.017500609s","start":"2026-04-14T06:20:47.504874Z","end":"2026-04-14T06:20:48.522375Z","steps":["trace[728088197] 'range keys from in-memory index tree' (duration: 1.017360846s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:20:48.522581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.55423589s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-14T06:20:48.522692Z","caller":"traceutil/trace.go:171","msg":"trace[14653873] transaction","detail":"{read_only:false; response_revision:1250; number_of_response:1; }","duration":"1.71216189s","start":"2026-04-14T06:20:46.810511Z","end":"2026-04-14T06:20:48.522672Z","steps":["trace[14653873] 'process raft request' (duration: 157.754636ms)","trace[14653873] 'compare' (duration: 1.553560124s)"],"step_count":2} {"level":"warn","ts":"2026-04-14T06:20:48.522749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:20:46.810492Z","time spent":"1.712229672s","remote":"127.0.0.1:42820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T06:20:49.192671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.001215233s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-14T06:20:49.19276Z","caller":"traceutil/trace.go:171","msg":"trace[185203369] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.001333146s","start":"2026-04-14T06:20:47.191407Z","end":"2026-04-14T06:20:49.19274Z","steps":["trace[185203369] 'agreement among raft nodes before linearized reading' (duration: 2.001226313s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:20:49.192809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:20:47.191397Z","time spent":"2.001402917s","remote":"127.0.0.1:38446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/14 06:20:49 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-14T06:20:49.523747Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.55541501s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-14T06:20:49.999951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.991065ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-14T06:20:50.000501Z","caller":"traceutil/trace.go:171","msg":"trace[510592918] linearizableReadLoop","detail":"{readStateIndex:1313; appliedIndex:1309; }","duration":"3.092359466s","start":"2026-04-14T06:20:46.908115Z","end":"2026-04-14T06:20:50.000475Z","steps":["trace[510592918] 'read index received' (duration: 60.205384ms)","trace[510592918] 'applied index is now lower than readState.Index' (duration: 3.032151752s)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:20:50.000541Z","caller":"traceutil/trace.go:171","msg":"trace[1757206847] transaction","detail":"{read_only:false; response_revision:1252; number_of_response:1; }","duration":"3.188787972s","start":"2026-04-14T06:20:46.811687Z","end":"2026-04-14T06:20:50.000475Z","steps":["trace[1757206847] 'process raft request' (duration: 3.188332421s)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:20:50.000654Z","caller":"traceutil/trace.go:171","msg":"trace[1346049935] transaction","detail":"{read_only:false; response_revision:1253; number_of_response:1; }","duration":"3.188927765s","start":"2026-04-14T06:20:46.811714Z","end":"2026-04-14T06:20:50.000642Z","steps":["trace[1346049935] 'process raft request' (duration: 3.188368252s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:20:50.000705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:20:46.811674Z","time spent":"3.188951336s","remote":"127.0.0.1:42820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T06:20:50.000757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:20:46.811684Z","time spent":"3.189004607s","remote":"127.0.0.1:42820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T06:20:50.000949Z","caller":"traceutil/trace.go:171","msg":"trace[2104341032] transaction","detail":"{read_only:false; response_revision:1251; number_of_response:1; }","duration":"3.189344536s","start":"2026-04-14T06:20:46.811584Z","end":"2026-04-14T06:20:50.000929Z","steps":["trace[2104341032] 'process raft request' (duration: 2.712312516s)","trace[2104341032] 'compare' (duration: 475.842412ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T06:20:50.001043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:20:46.811574Z","time spent":"3.189428418s","remote":"127.0.0.1:42820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T06:20:50.004369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.932195983s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-14T06:20:50.004412Z","caller":"traceutil/trace.go:171","msg":"trace[1634812240] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1254; }","duration":"2.932267414s","start":"2026-04-14T06:20:47.072134Z","end":"2026-04-14T06:20:50.004401Z","steps":["trace[1634812240] 'agreement among raft nodes before linearized reading' (duration: 2.932201872s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:20:50.004436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:20:47.072118Z","time spent":"2.932311675s","remote":"127.0.0.1:39430","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":31,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-04-14T06:20:50.004623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.096515395s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T06:20:50.004637Z","caller":"traceutil/trace.go:171","msg":"trace[1522114385] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:1254; }","duration":"3.096563406s","start":"2026-04-14T06:20:46.908069Z","end":"2026-04-14T06:20:50.004633Z","steps":["trace[1522114385] 'agreement among raft nodes before linearized reading' (duration: 3.09293791s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:20:50.00465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:20:46.90805Z","time spent":"3.096596467s","remote":"127.0.0.1:42702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":29,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"warn","ts":"2026-04-14T06:20:50.00477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.12705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.138\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-14T06:20:50.004782Z","caller":"traceutil/trace.go:171","msg":"trace[1461993796] range","detail":"{range_begin:/registry/masterleases/199.204.45.138; range_end:; response_count:1; response_revision:1254; }","duration":"335.174062ms","start":"2026-04-14T06:20:49.669604Z","end":"2026-04-14T06:20:50.004778Z","steps":["trace[1461993796] 'agreement among raft nodes before linearized reading' (duration: 335.11567ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:20:50.004794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:20:49.669588Z","time spent":"335.203772ms","remote":"127.0.0.1:38492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.204.45.138\" "} {"level":"warn","ts":"2026-04-14T06:20:50.004916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.171705383s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T06:20:50.004934Z","caller":"traceutil/trace.go:171","msg":"trace[1011518843] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:1254; }","duration":"2.171758304s","start":"2026-04-14T06:20:47.83317Z","end":"2026-04-14T06:20:50.004928Z","steps":["trace[1011518843] 'agreement among raft nodes before linearized reading' (duration: 2.171732124s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:20:50.004948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:20:47.833149Z","time spent":"2.171794715s","remote":"127.0.0.1:46762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":130,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-14T06:20:50.004951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.995543778s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-04-14T06:20:50.004987Z","caller":"traceutil/trace.go:171","msg":"trace[1062398517] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:1254; }","duration":"1.99563221s","start":"2026-04-14T06:20:48.009346Z","end":"2026-04-14T06:20:50.004978Z","steps":["trace[1062398517] 'agreement among raft nodes before linearized reading' (duration: 1.995538868s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:20:50.005009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:20:48.009321Z","time spent":"1.995682921s","remote":"127.0.0.1:38524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":35,"response count":1,"response size":142,"request content":"key:\"/registry/ranges/servicenodeports\" "} {"level":"warn","ts":"2026-04-14T06:20:50.005045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.563763698s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T06:20:50.005086Z","caller":"traceutil/trace.go:171","msg":"trace[1106676122] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1254; }","duration":"2.563832411s","start":"2026-04-14T06:20:47.441241Z","end":"2026-04-14T06:20:50.005074Z","steps":["trace[1106676122] 'agreement among raft nodes before linearized reading' (duration: 2.563755209s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:20:50.005122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:20:47.441229Z","time spent":"2.563883642s","remote":"127.0.0.1:42778","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":29,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-04-14T06:20:50.005125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"812.378025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-14T06:20:50.005199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.7457664s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T06:20:50.005221Z","caller":"traceutil/trace.go:171","msg":"trace[1408615077] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:1254; }","duration":"1.745819501s","start":"2026-04-14T06:20:48.259395Z","end":"2026-04-14T06:20:50.005215Z","steps":["trace[1408615077] 'agreement among raft nodes before linearized reading' (duration: 1.74578877s)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:20:50.005211Z","caller":"traceutil/trace.go:171","msg":"trace[1674611146] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1254; }","duration":"812.529748ms","start":"2026-04-14T06:20:49.192665Z","end":"2026-04-14T06:20:50.005194Z","steps":["trace[1674611146] 'agreement among raft nodes before linearized reading' (duration: 812.233841ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:20:50.005239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:20:48.259376Z","time spent":"1.745858772s","remote":"127.0.0.1:43008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":29,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"warn","ts":"2026-04-14T06:20:50.005262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:20:49.192646Z","time spent":"812.608141ms","remote":"127.0.0.1:38434","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-14T06:20:50.005317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.076935ms","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-04-14T06:20:50.005339Z","caller":"traceutil/trace.go:171","msg":"trace[1594114445] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1254; }","duration":"368.124966ms","start":"2026-04-14T06:20:49.637208Z","end":"2026-04-14T06:20:50.005333Z","steps":["trace[1594114445] 'agreement among raft nodes before linearized reading' (duration: 368.080855ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:20:50.005353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:20:49.637187Z","time spent":"368.162827ms","remote":"127.0.0.1:38584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":17,"response size":31,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"warn","ts":"2026-04-14T06:20:50.005371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.808492906s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-14T06:20:50.005383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.990951489s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:111844"} {"level":"info","ts":"2026-04-14T06:20:50.005403Z","caller":"traceutil/trace.go:171","msg":"trace[2102126689] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:1254; }","duration":"1.99101134s","start":"2026-04-14T06:20:48.014386Z","end":"2026-04-14T06:20:50.005398Z","steps":["trace[2102126689] 'agreement among raft nodes before linearized reading' (duration: 1.990929078s)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:20:50.005403Z","caller":"traceutil/trace.go:171","msg":"trace[1144621934] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1254; }","duration":"2.808533957s","start":"2026-04-14T06:20:47.19686Z","end":"2026-04-14T06:20:50.005394Z","steps":["trace[1144621934] 'agreement among raft nodes before linearized reading' (duration: 2.808420964s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:20:50.005418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:20:48.014364Z","time spent":"1.991050561s","remote":"127.0.0.1:38516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":111868,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"warn","ts":"2026-04-14T06:20:50.005431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:20:47.196855Z","time spent":"2.808568628s","remote":"127.0.0.1:42820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-14T06:20:50.005488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"653.173753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-14T06:20:50.00552Z","caller":"traceutil/trace.go:171","msg":"trace[1194240320] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:1254; }","duration":"653.233945ms","start":"2026-04-14T06:20:49.352277Z","end":"2026-04-14T06:20:50.005511Z","steps":["trace[1194240320] 'agreement among raft nodes before linearized reading' (duration: 653.169203ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:20:50.005556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:20:49.352264Z","time spent":"653.278276ms","remote":"127.0.0.1:42820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":8,"response size":31,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"warn","ts":"2026-04-14T06:20:50.005171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.025520391s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-14T06:20:50.005783Z","caller":"traceutil/trace.go:171","msg":"trace[263483310] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1254; }","duration":"1.026121365s","start":"2026-04-14T06:20:48.979643Z","end":"2026-04-14T06:20:50.005764Z","steps":["trace[263483310] 'agreement among raft nodes before linearized reading' (duration: 1.02548414s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:20:50.005838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:20:48.979591Z","time spent":"1.026228867s","remote":"127.0.0.1:42742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":45,"response size":31,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"info","ts":"2026-04-14T06:20:50.006846Z","caller":"traceutil/trace.go:171","msg":"trace[1575652027] transaction","detail":"{read_only:false; response_revision:1254; number_of_response:1; }","duration":"1.212296878s","start":"2026-04-14T06:20:48.794518Z","end":"2026-04-14T06:20:50.006815Z","steps":["trace[1575652027] 'process raft request' (duration: 1.205589338s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:20:50.006994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:20:48.794498Z","time spent":"1.21241483s","remote":"127.0.0.1:42820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T06:20:52.517206Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471725592835173922,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-14T06:20:52.835928Z","caller":"traceutil/trace.go:171","msg":"trace[352259541] transaction","detail":"{read_only:false; response_revision:1259; number_of_response:1; }","duration":"827.198438ms","start":"2026-04-14T06:20:52.008705Z","end":"2026-04-14T06:20:52.835903Z","steps":["trace[352259541] 'process raft request' (duration: 827.039594ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:20:52.836097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:20:52.008684Z","time spent":"827.337111ms","remote":"127.0.0.1:42820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T06:20:53.229195Z","caller":"traceutil/trace.go:171","msg":"trace[1570533708] transaction","detail":"{read_only:false; response_revision:1261; number_of_response:1; }","duration":"1.216910998s","start":"2026-04-14T06:20:52.012262Z","end":"2026-04-14T06:20:53.229173Z","steps":["trace[1570533708] 'process raft request' (duration: 1.216841466s)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:20:53.229283Z","caller":"traceutil/trace.go:171","msg":"trace[858412339] linearizableReadLoop","detail":"{readStateIndex:1324; appliedIndex:1320; }","duration":"1.213030425s","start":"2026-04-14T06:20:52.016244Z","end":"2026-04-14T06:20:53.229275Z","steps":["trace[858412339] 'read index received' (duration: 819.547545ms)","trace[858412339] 'applied index is now lower than readState.Index' (duration: 393.48177ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T06:20:53.229325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:20:52.012233Z","time spent":"1.217029101s","remote":"127.0.0.1:42820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T06:20:53.229343Z","caller":"traceutil/trace.go:171","msg":"trace[844823634] transaction","detail":"{read_only:false; response_revision:1262; number_of_response:1; }","duration":"1.216824076s","start":"2026-04-14T06:20:52.012497Z","end":"2026-04-14T06:20:53.229322Z","steps":["trace[844823634] 'process raft request' (duration: 1.216645232s)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:20:53.229206Z","caller":"traceutil/trace.go:171","msg":"trace[1119114313] transaction","detail":"{read_only:false; response_revision:1260; number_of_response:1; }","duration":"1.218347412s","start":"2026-04-14T06:20:52.010836Z","end":"2026-04-14T06:20:53.229184Z","steps":["trace[1119114313] 'process raft request' (duration: 1.218124067s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:20:53.229429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.213172019s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-14T06:20:53.22947Z","caller":"traceutil/trace.go:171","msg":"trace[936212518] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1262; }","duration":"1.21325379s","start":"2026-04-14T06:20:52.016206Z","end":"2026-04-14T06:20:53.22946Z","steps":["trace[936212518] 'agreement among raft nodes before linearized reading' (duration: 1.213116287s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:20:53.229479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:20:52.012475Z","time spent":"1.216933858s","remote":"127.0.0.1:42820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T06:20:53.22951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:20:52.016195Z","time spent":"1.213308282s","remote":"127.0.0.1:42820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-14T06:20:53.229481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:20:52.010819Z","time spent":"1.218590768s","remote":"127.0.0.1:42820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T06:20:53.245789Z","caller":"traceutil/trace.go:171","msg":"trace[35725094] transaction","detail":"{read_only:false; response_revision:1263; number_of_response:1; }","duration":"404.980554ms","start":"2026-04-14T06:20:52.84078Z","end":"2026-04-14T06:20:53.24576Z","steps":["trace[35725094] 'process raft request' (duration: 404.799709ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:20:53.245822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.114316ms","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":"warn","ts":"2026-04-14T06:20:53.245824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"710.206962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-14T06:20:53.245864Z","caller":"traceutil/trace.go:171","msg":"trace[18874510] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1263; }","duration":"710.249203ms","start":"2026-04-14T06:20:52.535604Z","end":"2026-04-14T06:20:53.245853Z","steps":["trace[18874510] 'agreement among raft nodes before linearized reading' (duration: 710.097909ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:20:53.245868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:20:52.840757Z","time spent":"405.078416ms","remote":"127.0.0.1:38540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":839,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-14T06:20:53.245873Z","caller":"traceutil/trace.go:171","msg":"trace[192857343] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1263; }","duration":"182.199789ms","start":"2026-04-14T06:20:53.063643Z","end":"2026-04-14T06:20:53.245843Z","steps":["trace[192857343] 'agreement among raft nodes before linearized reading' (duration: 182.083846ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:20:53.245893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:20:52.535548Z","time spent":"710.338575ms","remote":"127.0.0.1:42820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-14T06:20:54.202048Z","caller":"traceutil/trace.go:171","msg":"trace[1849367796] linearizableReadLoop","detail":"{readStateIndex:1330; appliedIndex:1329; }","duration":"294.7761ms","start":"2026-04-14T06:20:53.907257Z","end":"2026-04-14T06:20:54.202033Z","steps":["trace[1849367796] 'read index received' (duration: 294.638056ms)","trace[1849367796] 'applied index is now lower than readState.Index' (duration: 137.234µs)"],"step_count":2} {"level":"warn","ts":"2026-04-14T06:20:54.202261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.983985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5331"} {"level":"info","ts":"2026-04-14T06:20:54.202324Z","caller":"traceutil/trace.go:171","msg":"trace[1143484231] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1268; }","duration":"295.086126ms","start":"2026-04-14T06:20:53.907224Z","end":"2026-04-14T06:20:54.20231Z","steps":["trace[1143484231] 'agreement among raft nodes before linearized reading' (duration: 294.893762ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:20:54.202085Z","caller":"traceutil/trace.go:171","msg":"trace[1321479110] transaction","detail":"{read_only:false; response_revision:1268; number_of_response:1; }","duration":"306.913838ms","start":"2026-04-14T06:20:53.89514Z","end":"2026-04-14T06:20:54.202054Z","steps":["trace[1321479110] 'process raft request' (duration: 306.801735ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:20:54.202632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:20:53.895121Z","time spent":"307.40848ms","remote":"127.0.0.1:38540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":751,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-14T06:20:54.501736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.03755ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-14T06:20:54.501811Z","caller":"traceutil/trace.go:171","msg":"trace[2124339608] linearizableReadLoop","detail":"{readStateIndex:1331; appliedIndex:1330; }","duration":"246.118891ms","start":"2026-04-14T06:20:54.255681Z","end":"2026-04-14T06:20:54.5018Z","steps":["trace[2124339608] 'read index received' (duration: 136.013819ms)","trace[2124339608] 'applied index is now lower than readState.Index' (duration: 110.104302ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T06:20:54.501879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.201533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T06:20:54.501898Z","caller":"traceutil/trace.go:171","msg":"trace[522378819] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1269; }","duration":"246.225873ms","start":"2026-04-14T06:20:54.255667Z","end":"2026-04-14T06:20:54.501893Z","steps":["trace[522378819] 'agreement among raft nodes before linearized reading' (duration: 246.169852ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:20:54.501888Z","caller":"traceutil/trace.go:171","msg":"trace[781638769] transaction","detail":"{read_only:false; response_revision:1269; number_of_response:1; }","duration":"296.584012ms","start":"2026-04-14T06:20:54.20526Z","end":"2026-04-14T06:20:54.501844Z","steps":["trace[781638769] 'process raft request' (duration: 186.390238ms)","trace[781638769] 'compare' (duration: 109.922437ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T06:20:55.603993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.029283ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-14T06:20:55.604343Z","caller":"traceutil/trace.go:171","msg":"trace[42915357] transaction","detail":"{read_only:false; response_revision:1271; number_of_response:1; }","duration":"368.3555ms","start":"2026-04-14T06:20:55.235974Z","end":"2026-04-14T06:20:55.604329Z","steps":["trace[42915357] 'process raft request' (duration: 368.216377ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:20:55.604476Z","caller":"traceutil/trace.go:171","msg":"trace[962007733] linearizableReadLoop","detail":"{readStateIndex:1333; appliedIndex:1332; }","duration":"413.372292ms","start":"2026-04-14T06:20:55.191063Z","end":"2026-04-14T06:20:55.604436Z","steps":["trace[962007733] 'read index received' (duration: 293.912369ms)","trace[962007733] 'applied index is now lower than readState.Index' (duration: 119.454213ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T06:20:55.604527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:20:55.23595Z","time spent":"368.465923ms","remote":"127.0.0.1:42820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T06:20:55.604714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.619218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-14T06:20:55.604777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.332699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-04-14T06:20:55.604795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.791444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-14T06:20:55.604793Z","caller":"traceutil/trace.go:171","msg":"trace[948568007] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1271; }","duration":"413.751082ms","start":"2026-04-14T06:20:55.191024Z","end":"2026-04-14T06:20:55.604775Z","steps":["trace[948568007] 'agreement among raft nodes before linearized reading' (duration: 413.543057ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:20:55.604816Z","caller":"traceutil/trace.go:171","msg":"trace[890523160] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1271; }","duration":"352.40875ms","start":"2026-04-14T06:20:55.252397Z","end":"2026-04-14T06:20:55.604805Z","steps":["trace[890523160] 'agreement among raft nodes before linearized reading' (duration: 352.286647ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:20:55.604843Z","caller":"traceutil/trace.go:171","msg":"trace[2022738178] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1271; }","duration":"369.885327ms","start":"2026-04-14T06:20:55.234944Z","end":"2026-04-14T06:20:55.604829Z","steps":["trace[2022738178] 'agreement among raft nodes before linearized reading' (duration: 369.691982ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:20:55.604852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:20:55.252377Z","time spent":"352.464852ms","remote":"127.0.0.1:42820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-14T06:20:55.604862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:20:55.191006Z","time spent":"413.839033ms","remote":"127.0.0.1:38434","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-14T06:20:55.604885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:20:55.234928Z","time spent":"369.949328ms","remote":"127.0.0.1:42820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-14T06:20:55.605042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.028661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-14T06:20:55.605125Z","caller":"traceutil/trace.go:171","msg":"trace[1958694365] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1271; }","duration":"370.122753ms","start":"2026-04-14T06:20:55.234989Z","end":"2026-04-14T06:20:55.605112Z","steps":["trace[1958694365] 'agreement among raft nodes before linearized reading' (duration: 369.724863ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:20:55.605207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.786226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-14T06:20:55.605287Z","caller":"traceutil/trace.go:171","msg":"trace[186964757] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1271; }","duration":"351.898578ms","start":"2026-04-14T06:20:55.253378Z","end":"2026-04-14T06:20:55.605277Z","steps":["trace[186964757] 'agreement among raft nodes before linearized reading' (duration: 351.260403ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:20:55.605315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:20:55.253362Z","time spent":"351.945499ms","remote":"127.0.0.1:42820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-14T06:20:55.605445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:20:55.234985Z","time spent":"370.278666ms","remote":"127.0.0.1:42820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-14T06:20:55.945421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.122257ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-14T06:20:55.945659Z","caller":"traceutil/trace.go:171","msg":"trace[1079905882] transaction","detail":"{read_only:false; response_revision:1275; number_of_response:1; }","duration":"332.057107ms","start":"2026-04-14T06:20:55.613586Z","end":"2026-04-14T06:20:55.945643Z","steps":["trace[1079905882] 'process raft request' (duration: 332.021377ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:20:55.94573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:20:55.613579Z","time spent":"332.118849ms","remote":"127.0.0.1:42820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T06:20:55.94585Z","caller":"traceutil/trace.go:171","msg":"trace[344600676] transaction","detail":"{read_only:false; response_revision:1272; number_of_response:1; }","duration":"333.297727ms","start":"2026-04-14T06:20:55.612284Z","end":"2026-04-14T06:20:55.945581Z","steps":["trace[344600676] 'process raft request' (duration: 108.947245ms)","trace[344600676] 'compare' (duration: 223.900351ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:20:55.946019Z","caller":"traceutil/trace.go:171","msg":"trace[353346823] transaction","detail":"{read_only:false; response_revision:1273; number_of_response:1; }","duration":"333.669795ms","start":"2026-04-14T06:20:55.612339Z","end":"2026-04-14T06:20:55.946009Z","steps":["trace[353346823] 'process raft request' (duration: 333.171193ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:20:55.946082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:20:55.612319Z","time spent":"333.735047ms","remote":"127.0.0.1:42820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T06:20:55.946039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:20:55.612264Z","time spent":"333.649695ms","remote":"127.0.0.1:42820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T06:20:55.946202Z","caller":"traceutil/trace.go:171","msg":"trace[1392811960] transaction","detail":"{read_only:false; response_revision:1274; number_of_response:1; }","duration":"333.756028ms","start":"2026-04-14T06:20:55.612414Z","end":"2026-04-14T06:20:55.94617Z","steps":["trace[1392811960] 'process raft request' (duration: 333.160824ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:20:55.946447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:20:55.612407Z","time spent":"333.979003ms","remote":"127.0.0.1:42820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T06:20:56.372973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.826753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T06:20:56.373066Z","caller":"traceutil/trace.go:171","msg":"trace[1932275420] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1275; }","duration":"182.965217ms","start":"2026-04-14T06:20:56.190084Z","end":"2026-04-14T06:20:56.373049Z","steps":["trace[1932275420] 'range keys from in-memory index tree' (duration: 182.702031ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:21:03.090515Z","caller":"traceutil/trace.go:171","msg":"trace[1047559055] transaction","detail":"{read_only:false; response_revision:1334; number_of_response:1; }","duration":"158.385452ms","start":"2026-04-14T06:21:02.93211Z","end":"2026-04-14T06:21:03.090495Z","steps":["trace[1047559055] 'process raft request' (duration: 158.265689ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:21:03.907875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.338124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T06:21:03.907937Z","caller":"traceutil/trace.go:171","msg":"trace[196834638] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:1334; }","duration":"416.450477ms","start":"2026-04-14T06:21:03.491471Z","end":"2026-04-14T06:21:03.907922Z","steps":["trace[196834638] 'count revisions from in-memory index tree' (duration: 416.213621ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:21:03.907944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"715.651422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T06:21:03.90802Z","caller":"traceutil/trace.go:171","msg":"trace[268858712] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1334; }","duration":"715.774735ms","start":"2026-04-14T06:21:03.192226Z","end":"2026-04-14T06:21:03.908001Z","steps":["trace[268858712] 'range keys from in-memory index tree' (duration: 715.531559ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:21:03.908067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.002186ms","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":"warn","ts":"2026-04-14T06:21:03.908068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:21:03.192207Z","time spent":"715.849526ms","remote":"127.0.0.1:38446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-14T06:21:03.90797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:21:03.491451Z","time spent":"416.510938ms","remote":"127.0.0.1:39412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"info","ts":"2026-04-14T06:21:03.908471Z","caller":"traceutil/trace.go:171","msg":"trace[829890839] linearizableReadLoop","detail":"{readStateIndex:1399; appliedIndex:1398; }","duration":"178.251735ms","start":"2026-04-14T06:21:03.730164Z","end":"2026-04-14T06:21:03.908416Z","steps":["trace[829890839] 'read index received' (duration: 166.078135ms)","trace[829890839] 'applied index is now lower than readState.Index' (duration: 12.17257ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:21:03.9081Z","caller":"traceutil/trace.go:171","msg":"trace[1501697316] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1334; }","duration":"403.086539ms","start":"2026-04-14T06:21:03.505003Z","end":"2026-04-14T06:21:03.90809Z","steps":["trace[1501697316] 'range keys from in-memory index tree' (duration: 402.836923ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:21:03.908622Z","caller":"traceutil/trace.go:171","msg":"trace[58352036] transaction","detail":"{read_only:false; response_revision:1335; number_of_response:1; }","duration":"277.883387ms","start":"2026-04-14T06:21:03.630722Z","end":"2026-04-14T06:21:03.908605Z","steps":["trace[58352036] 'process raft request' (duration: 265.582764ms)","trace[58352036] 'compare' (duration: 11.32165ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T06:21:03.908643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:21:03.504973Z","time spent":"403.658772ms","remote":"127.0.0.1:42820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":498,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-14T06:21:03.908726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.505001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T06:21:03.908778Z","caller":"traceutil/trace.go:171","msg":"trace[1884233] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1335; }","duration":"178.624704ms","start":"2026-04-14T06:21:03.730124Z","end":"2026-04-14T06:21:03.908748Z","steps":["trace[1884233] 'agreement among raft nodes before linearized reading' (duration: 178.45759ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:21:06.203896Z","caller":"traceutil/trace.go:171","msg":"trace[1373479835] transaction","detail":"{read_only:false; response_revision:1364; number_of_response:1; }","duration":"168.532703ms","start":"2026-04-14T06:21:06.035344Z","end":"2026-04-14T06:21:06.203877Z","steps":["trace[1373479835] 'process raft request' (duration: 168.40628ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:21:06.274956Z","caller":"traceutil/trace.go:171","msg":"trace[1478778496] transaction","detail":"{read_only:false; response_revision:1367; number_of_response:1; }","duration":"237.157658ms","start":"2026-04-14T06:21:06.03778Z","end":"2026-04-14T06:21:06.274938Z","steps":["trace[1478778496] 'process raft request' (duration: 237.085776ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:21:06.274966Z","caller":"traceutil/trace.go:171","msg":"trace[1007992859] transaction","detail":"{read_only:false; response_revision:1366; number_of_response:1; }","duration":"238.130801ms","start":"2026-04-14T06:21:06.036809Z","end":"2026-04-14T06:21:06.27494Z","steps":["trace[1007992859] 'process raft request' (duration: 238.027788ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:21:06.275173Z","caller":"traceutil/trace.go:171","msg":"trace[1143293838] transaction","detail":"{read_only:false; response_revision:1365; number_of_response:1; }","duration":"238.439278ms","start":"2026-04-14T06:21:06.03671Z","end":"2026-04-14T06:21:06.27515Z","steps":["trace[1143293838] 'process raft request' (duration: 208.517125ms)","trace[1143293838] 'compare' (duration: 29.494552ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:22:57.134285Z","caller":"traceutil/trace.go:171","msg":"trace[1719023806] linearizableReadLoop","detail":"{readStateIndex:1962; appliedIndex:1960; }","duration":"114.772244ms","start":"2026-04-14T06:22:57.019486Z","end":"2026-04-14T06:22:57.134258Z","steps":["trace[1719023806] 'read index received' (duration: 5.079962ms)","trace[1719023806] 'applied index is now lower than readState.Index' (duration: 109.690902ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T06:22:57.134498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.990249ms","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":"info","ts":"2026-04-14T06:22:57.13449Z","caller":"traceutil/trace.go:171","msg":"trace[591843884] transaction","detail":"{read_only:false; response_revision:1875; number_of_response:1; }","duration":"137.888574ms","start":"2026-04-14T06:22:56.996519Z","end":"2026-04-14T06:22:57.134408Z","steps":["trace[591843884] 'process raft request' (duration: 67.946878ms)","trace[591843884] 'compare' (duration: 69.593707ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:22:57.13457Z","caller":"traceutil/trace.go:171","msg":"trace[1292732889] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1875; }","duration":"115.107751ms","start":"2026-04-14T06:22:57.019445Z","end":"2026-04-14T06:22:57.134553Z","steps":["trace[1292732889] 'agreement among raft nodes before linearized reading' (duration: 114.915036ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:23:04.630361Z","caller":"traceutil/trace.go:171","msg":"trace[1019586380] transaction","detail":"{read_only:false; response_revision:1907; number_of_response:1; }","duration":"185.504718ms","start":"2026-04-14T06:23:04.444825Z","end":"2026-04-14T06:23:04.63033Z","steps":["trace[1019586380] 'process raft request' (duration: 135.102598ms)","trace[1019586380] 'compare' (duration: 50.163984ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T06:24:22.563519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"741.313212ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-14T06:24:22.563659Z","caller":"traceutil/trace.go:171","msg":"trace[1239214421] linearizableReadLoop","detail":"{readStateIndex:2398; appliedIndex:2396; }","duration":"371.88546ms","start":"2026-04-14T06:24:22.19176Z","end":"2026-04-14T06:24:22.563645Z","steps":["trace[1239214421] 'read index received' (duration: 278.981434ms)","trace[1239214421] 'applied index is now lower than readState.Index' (duration: 92.903166ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T06:24:22.563818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.603982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-14T06:24:22.563846Z","caller":"traceutil/trace.go:171","msg":"trace[464378349] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2293; }","duration":"229.760666ms","start":"2026-04-14T06:24:22.334077Z","end":"2026-04-14T06:24:22.563837Z","steps":["trace[464378349] 'agreement among raft nodes before linearized reading' (duration: 229.652933ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:24:22.563868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.056975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T06:24:22.56392Z","caller":"traceutil/trace.go:171","msg":"trace[1525093783] transaction","detail":"{read_only:false; response_revision:2292; number_of_response:1; }","duration":"854.720825ms","start":"2026-04-14T06:24:21.709175Z","end":"2026-04-14T06:24:22.563896Z","steps":["trace[1525093783] 'process raft request' (duration: 112.959762ms)","trace[1525093783] 'compare' (duration: 741.093037ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:24:22.563954Z","caller":"traceutil/trace.go:171","msg":"trace[2128830933] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2293; }","duration":"372.218138ms","start":"2026-04-14T06:24:22.191716Z","end":"2026-04-14T06:24:22.563934Z","steps":["trace[2128830933] 'agreement among raft nodes before linearized reading' (duration: 372.007123ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:24:22.564039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:24:21.709157Z","time spent":"854.827487ms","remote":"127.0.0.1:42820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":516,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T06:24:22.564123Z","caller":"traceutil/trace.go:171","msg":"trace[1908642891] transaction","detail":"{read_only:false; response_revision:2293; number_of_response:1; }","duration":"737.115883ms","start":"2026-04-14T06:24:21.826996Z","end":"2026-04-14T06:24:22.564112Z","steps":["trace[1908642891] 'process raft request' (duration: 736.608361ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:24:22.564027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:24:22.191693Z","time spent":"372.325691ms","remote":"127.0.0.1:38446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-14T06:24:22.564224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:24:21.826972Z","time spent":"737.182574ms","remote":"127.0.0.1:42820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T06:24:22.915933Z","caller":"traceutil/trace.go:171","msg":"trace[742687315] transaction","detail":"{read_only:false; response_revision:2295; number_of_response:1; }","duration":"119.523898ms","start":"2026-04-14T06:24:22.796375Z","end":"2026-04-14T06:24:22.915899Z","steps":["trace[742687315] 'process raft request' (duration: 119.227051ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:24:48.877048Z","caller":"traceutil/trace.go:171","msg":"trace[1164991581] transaction","detail":"{read_only:false; response_revision:2419; number_of_response:1; }","duration":"153.101776ms","start":"2026-04-14T06:24:48.723879Z","end":"2026-04-14T06:24:48.876981Z","steps":["trace[1164991581] 'process raft request' (duration: 106.093319ms)","trace[1164991581] 'compare' (duration: 46.75232ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T06:24:48.877432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.663594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/kube-system/extension-apiserver-authentication-reader\" ","response":"range_response_count:1 size:671"} {"level":"info","ts":"2026-04-14T06:24:48.877664Z","caller":"traceutil/trace.go:171","msg":"trace[155276209] linearizableReadLoop","detail":"{readStateIndex:2529; appliedIndex:2528; }","duration":"136.06545ms","start":"2026-04-14T06:24:48.740741Z","end":"2026-04-14T06:24:48.876806Z","steps":["trace[155276209] 'read index received' (duration: 89.183767ms)","trace[155276209] 'applied index is now lower than readState.Index' (duration: 46.880753ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:24:48.877737Z","caller":"traceutil/trace.go:171","msg":"trace[20107437] range","detail":"{range_begin:/registry/roles/kube-system/extension-apiserver-authentication-reader; range_end:; response_count:1; response_revision:2419; }","duration":"137.003073ms","start":"2026-04-14T06:24:48.740675Z","end":"2026-04-14T06:24:48.877678Z","steps":["trace[20107437] 'agreement among raft nodes before linearized reading' (duration: 136.645425ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:24:48.877818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.766527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/kube-system/kube-proxy\" ","response":"range_response_count:1 size:317"} {"level":"info","ts":"2026-04-14T06:24:48.878472Z","caller":"traceutil/trace.go:171","msg":"trace[911503526] range","detail":"{range_begin:/registry/roles/kube-system/kube-proxy; range_end:; response_count:1; response_revision:2419; }","duration":"121.341891ms","start":"2026-04-14T06:24:48.756996Z","end":"2026-04-14T06:24:48.878338Z","steps":["trace[911503526] 'agreement among raft nodes before linearized reading' (duration: 120.768387ms)"],"step_count":1}