{"level":"warn","ts":"2026-04-02T06:09:51.868165Z","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-02T06:09:51.868317Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.15:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.15:2380","--initial-cluster=instance=https://199.19.213.15:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.15:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.15: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-02T06:09:51.868449Z","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-02T06:09:51.86847Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.15:2380"]} {"level":"info","ts":"2026-04-02T06:09:51.868497Z","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-02T06:09:51.869432Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.15:2379"]} {"level":"info","ts":"2026-04-02T06:09:51.869538Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.15:2380"],"listen-peer-urls":["https://199.19.213.15:2380"],"advertise-client-urls":["https://199.19.213.15:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.15:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.15: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-02T06:09:51.874221Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.370386ms"} {"level":"info","ts":"2026-04-02T06:09:51.885934Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"11371113779d2d76","cluster-id":"1921549e667ce7d9"} {"level":"info","ts":"2026-04-02T06:09:51.88607Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11371113779d2d76 switched to configuration voters=()"} {"level":"info","ts":"2026-04-02T06:09:51.886109Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11371113779d2d76 became follower at term 0"} {"level":"info","ts":"2026-04-02T06:09:51.886127Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 11371113779d2d76 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-02T06:09:51.886143Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11371113779d2d76 became follower at term 1"} {"level":"info","ts":"2026-04-02T06:09:51.886187Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11371113779d2d76 switched to configuration voters=(1240478997672701302)"} {"level":"warn","ts":"2026-04-02T06:09:51.899639Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-02T06:09:51.901258Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-02T06:09:51.902548Z","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-02T06:09:51.905246Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"11371113779d2d76","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-02T06:09:51.905387Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"11371113779d2d76","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-02T06:09:51.905678Z","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-02T06:09:51.905771Z","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-02T06:09:51.905829Z","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-02T06:09:51.90884Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11371113779d2d76 switched to configuration voters=(1240478997672701302)"} {"level":"info","ts":"2026-04-02T06:09:51.909178Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"1921549e667ce7d9","local-member-id":"11371113779d2d76","added-peer-id":"11371113779d2d76","added-peer-peer-urls":["https://199.19.213.15:2380"]} {"level":"info","ts":"2026-04-02T06:09:51.911202Z","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-02T06:09:51.911412Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.15:2380"} {"level":"info","ts":"2026-04-02T06:09:51.911441Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.15:2380"} {"level":"info","ts":"2026-04-02T06:09:51.911493Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"11371113779d2d76","initial-advertise-peer-urls":["https://199.19.213.15:2380"],"listen-peer-urls":["https://199.19.213.15:2380"],"advertise-client-urls":["https://199.19.213.15:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.15:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-02T06:09:51.911534Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-02T06:09:52.786983Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11371113779d2d76 is starting a new election at term 1"} {"level":"info","ts":"2026-04-02T06:09:52.787056Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11371113779d2d76 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-02T06:09:52.787106Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11371113779d2d76 received MsgPreVoteResp from 11371113779d2d76 at term 1"} {"level":"info","ts":"2026-04-02T06:09:52.787128Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11371113779d2d76 became candidate at term 2"} {"level":"info","ts":"2026-04-02T06:09:52.78714Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11371113779d2d76 received MsgVoteResp from 11371113779d2d76 at term 2"} {"level":"info","ts":"2026-04-02T06:09:52.787164Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11371113779d2d76 became leader at term 2"} {"level":"info","ts":"2026-04-02T06:09:52.787185Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 11371113779d2d76 elected leader 11371113779d2d76 at term 2"} {"level":"info","ts":"2026-04-02T06:09:52.788383Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T06:09:52.78857Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"11371113779d2d76","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.15:2379]}","request-path":"/0/members/11371113779d2d76/attributes","cluster-id":"1921549e667ce7d9","publish-timeout":"7s"} {"level":"info","ts":"2026-04-02T06:09:52.78859Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-02T06:09:52.788619Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-02T06:09:52.789244Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-02T06:09:52.789272Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-02T06:09:52.791569Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.15:2379"} {"level":"info","ts":"2026-04-02T06:09:52.79221Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-02T06:09:52.793178Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"1921549e667ce7d9","local-member-id":"11371113779d2d76","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T06:09:52.793296Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T06:09:52.793333Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T06:10:03.726476Z","caller":"traceutil/trace.go:171","msg":"trace[1648899499] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"107.944819ms","start":"2026-04-02T06:10:03.618506Z","end":"2026-04-02T06:10:03.72645Z","steps":["trace[1648899499] 'process raft request' (duration: 107.816936ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:10:03.726527Z","caller":"traceutil/trace.go:171","msg":"trace[1427552821] linearizableReadLoop","detail":"{readStateIndex:382; appliedIndex:380; }","duration":"120.025862ms","start":"2026-04-02T06:10:03.606478Z","end":"2026-04-02T06:10:03.726504Z","steps":["trace[1427552821] 'read index received' (duration: 30.197522ms)","trace[1427552821] 'applied index is now lower than readState.Index' (duration: 89.82648ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T06:10:03.726749Z","caller":"traceutil/trace.go:171","msg":"trace[492737868] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"149.430726ms","start":"2026-04-02T06:10:03.577304Z","end":"2026-04-02T06:10:03.726734Z","steps":["trace[492737868] 'process raft request' (duration: 148.815511ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:10:03.726946Z","caller":"traceutil/trace.go:171","msg":"trace[478017993] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"156.691003ms","start":"2026-04-02T06:10:03.569491Z","end":"2026-04-02T06:10:03.726182Z","steps":["trace[478017993] 'process raft request' (duration: 67.1676ms)","trace[478017993] 'compare' (duration: 89.296107ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T06:10:03.727028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.523625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-04-02T06:10:03.727092Z","caller":"traceutil/trace.go:171","msg":"trace[1757444625] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:374; }","duration":"120.630617ms","start":"2026-04-02T06:10:03.606451Z","end":"2026-04-02T06:10:03.727081Z","steps":["trace[1757444625] 'agreement among raft nodes before linearized reading' (duration: 120.459253ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:10:17.310359Z","caller":"traceutil/trace.go:171","msg":"trace[991442210] transaction","detail":"{read_only:false; response_revision:462; number_of_response:1; }","duration":"269.496289ms","start":"2026-04-02T06:10:17.040827Z","end":"2026-04-02T06:10:17.310323Z","steps":["trace[991442210] 'process raft request' (duration: 269.340406ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:10:22.512127Z","caller":"traceutil/trace.go:171","msg":"trace[1310282174] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"111.077016ms","start":"2026-04-02T06:10:22.401006Z","end":"2026-04-02T06:10:22.512083Z","steps":["trace[1310282174] 'process raft request' (duration: 110.223356ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:10:23.848445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.797172ms","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-02T06:10:23.848553Z","caller":"traceutil/trace.go:171","msg":"trace[369205847] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:510; }","duration":"399.957676ms","start":"2026-04-02T06:10:23.448572Z","end":"2026-04-02T06:10:23.848529Z","steps":["trace[369205847] 'range keys from in-memory index tree' (duration: 399.539136ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:10:23.848605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:10:23.448519Z","time spent":"400.075408ms","remote":"127.0.0.1:42872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-02T06:10:23.95712Z","caller":"traceutil/trace.go:171","msg":"trace[473705620] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"401.80352ms","start":"2026-04-02T06:10:23.555283Z","end":"2026-04-02T06:10:23.957087Z","steps":["trace[473705620] 'process raft request' (duration: 401.606185ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:10:23.958238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:10:23.555259Z","time spent":"401.961794ms","remote":"127.0.0.1:42638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T06:10:23.988187Z","caller":"traceutil/trace.go:171","msg":"trace[484580920] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"429.078882ms","start":"2026-04-02T06:10:23.559059Z","end":"2026-04-02T06:10:23.988138Z","steps":["trace[484580920] 'process raft request' (duration: 428.878297ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:10:23.98826Z","caller":"traceutil/trace.go:171","msg":"trace[580056725] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"428.818025ms","start":"2026-04-02T06:10:23.559389Z","end":"2026-04-02T06:10:23.988207Z","steps":["trace[580056725] 'process raft request' (duration: 428.666472ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:10:23.988531Z","caller":"traceutil/trace.go:171","msg":"trace[1959206738] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"384.963792ms","start":"2026-04-02T06:10:23.60355Z","end":"2026-04-02T06:10:23.988513Z","steps":["trace[1959206738] 'process raft request' (duration: 384.654004ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:10:23.988601Z","caller":"traceutil/trace.go:171","msg":"trace[1755399558] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"379.764524ms","start":"2026-04-02T06:10:23.608824Z","end":"2026-04-02T06:10:23.988589Z","steps":["trace[1755399558] 'process raft request' (duration: 379.457857ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:10:23.98867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:10:23.608817Z","time spent":"379.814535ms","remote":"127.0.0.1:42638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T06:10:23.988654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:10:23.559382Z","time spent":"429.178564ms","remote":"127.0.0.1:42638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T06:10:23.988636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:10:23.603539Z","time spent":"385.035293ms","remote":"127.0.0.1:42638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T06:10:23.98834Z","caller":"traceutil/trace.go:171","msg":"trace[184750334] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"420.213307ms","start":"2026-04-02T06:10:23.568079Z","end":"2026-04-02T06:10:23.988292Z","steps":["trace[184750334] 'process raft request' (duration: 420.015053ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:10:23.988367Z","caller":"traceutil/trace.go:171","msg":"trace[1328007073] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"398.948901ms","start":"2026-04-02T06:10:23.589361Z","end":"2026-04-02T06:10:23.98831Z","steps":["trace[1328007073] 'process raft request' (duration: 398.811867ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:10:23.988287Z","caller":"traceutil/trace.go:171","msg":"trace[645830712] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"398.989482ms","start":"2026-04-02T06:10:23.589273Z","end":"2026-04-02T06:10:23.988262Z","steps":["trace[645830712] 'process raft request' (duration: 398.869679ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:10:23.989123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:10:23.589353Z","time spent":"399.732819ms","remote":"127.0.0.1:42638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T06:10:23.988957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:10:23.568062Z","time spent":"420.804511ms","remote":"127.0.0.1:42638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T06:10:23.989323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:10:23.589246Z","time spent":"399.978915ms","remote":"127.0.0.1:42638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T06:10:23.989193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:10:23.559031Z","time spent":"429.503822ms","remote":"127.0.0.1:42638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T06:10:23.989803Z","caller":"traceutil/trace.go:171","msg":"trace[469491442] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"380.877132ms","start":"2026-04-02T06:10:23.608913Z","end":"2026-04-02T06:10:23.98979Z","steps":["trace[469491442] 'process raft request' (duration: 380.290497ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:10:23.990385Z","caller":"traceutil/trace.go:171","msg":"trace[1419914986] linearizableReadLoop","detail":"{readStateIndex:531; appliedIndex:525; }","duration":"392.264999ms","start":"2026-04-02T06:10:23.598013Z","end":"2026-04-02T06:10:23.990278Z","steps":["trace[1419914986] 'read index received' (duration: 358.92976ms)","trace[1419914986] 'applied index is now lower than readState.Index' (duration: 33.333819ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T06:10:23.990501Z","caller":"traceutil/trace.go:171","msg":"trace[132467742] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"232.817839ms","start":"2026-04-02T06:10:23.75766Z","end":"2026-04-02T06:10:23.990478Z","steps":["trace[132467742] 'process raft request' (duration: 232.494752ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:10:23.990913Z","caller":"traceutil/trace.go:171","msg":"trace[484045366] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"378.221407ms","start":"2026-04-02T06:10:23.612677Z","end":"2026-04-02T06:10:23.990898Z","steps":["trace[484045366] 'process raft request' (duration: 376.983727ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:10:23.990952Z","caller":"traceutil/trace.go:171","msg":"trace[716160487] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"350.73858ms","start":"2026-04-02T06:10:23.640195Z","end":"2026-04-02T06:10:23.990934Z","steps":["trace[716160487] 'process raft request' (duration: 349.87819ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:10:23.991002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:10:23.61267Z","time spent":"378.28338ms","remote":"127.0.0.1:42638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T06:10:23.991089Z","caller":"traceutil/trace.go:171","msg":"trace[743184905] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"137.578948ms","start":"2026-04-02T06:10:23.853487Z","end":"2026-04-02T06:10:23.991066Z","steps":["trace[743184905] 'process raft request' (duration: 136.726448ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:10:23.99116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:10:23.640155Z","time spent":"350.933165ms","remote":"127.0.0.1:42638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T06:10:23.991368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.513645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T06:10:23.991494Z","caller":"traceutil/trace.go:171","msg":"trace[714255070] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:523; }","duration":"393.499238ms","start":"2026-04-02T06:10:23.597978Z","end":"2026-04-02T06:10:23.991477Z","steps":["trace[714255070] 'agreement among raft nodes before linearized reading' (duration: 392.498664ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:10:23.991579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:10:23.597956Z","time spent":"393.5744ms","remote":"127.0.0.1:42576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-02T06:10:23.993267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:10:23.60891Z","time spent":"380.919363ms","remote":"127.0.0.1:42638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T06:10:24.393228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.506876ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T06:10:24.393456Z","caller":"traceutil/trace.go:171","msg":"trace[1711590390] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"119.50173ms","start":"2026-04-02T06:10:24.27394Z","end":"2026-04-02T06:10:24.393441Z","steps":["trace[1711590390] 'process raft request' (duration: 119.391137ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:10:24.39353Z","caller":"traceutil/trace.go:171","msg":"trace[2128230953] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"124.951173ms","start":"2026-04-02T06:10:24.268533Z","end":"2026-04-02T06:10:24.393485Z","steps":["trace[2128230953] 'compare' (duration: 118.387712ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:10:24.469555Z","caller":"traceutil/trace.go:171","msg":"trace[1717331642] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"185.867381ms","start":"2026-04-02T06:10:24.283642Z","end":"2026-04-02T06:10:24.469509Z","steps":["trace[1717331642] 'process raft request' (duration: 185.639686ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:10:24.531324Z","caller":"traceutil/trace.go:171","msg":"trace[1847149750] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"124.834199ms","start":"2026-04-02T06:10:24.406456Z","end":"2026-04-02T06:10:24.53129Z","steps":["trace[1847149750] 'process raft request' (duration: 122.278247ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:10:24.53198Z","caller":"traceutil/trace.go:171","msg":"trace[874644724] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"113.186666ms","start":"2026-04-02T06:10:24.418747Z","end":"2026-04-02T06:10:24.531933Z","steps":["trace[874644724] 'process raft request' (duration: 112.464428ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:10:25.870061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.977597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/clienttrafficpolicies.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:65436"} {"level":"info","ts":"2026-04-02T06:10:25.87018Z","caller":"traceutil/trace.go:171","msg":"trace[1769421372] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/clienttrafficpolicies.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:590; }","duration":"133.163792ms","start":"2026-04-02T06:10:25.73699Z","end":"2026-04-02T06:10:25.870154Z","steps":["trace[1769421372] 'range keys from in-memory index tree' (duration: 132.718232ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:10:31.210425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.889284ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-02T06:10:31.210602Z","caller":"traceutil/trace.go:171","msg":"trace[1897153931] linearizableReadLoop","detail":"{readStateIndex:639; appliedIndex:638; }","duration":"407.284023ms","start":"2026-04-02T06:10:30.803298Z","end":"2026-04-02T06:10:31.210582Z","steps":["trace[1897153931] 'read index received' (duration: 82.037271ms)","trace[1897153931] 'applied index is now lower than readState.Index' (duration: 325.245302ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T06:10:31.210631Z","caller":"traceutil/trace.go:171","msg":"trace[1084940974] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"514.775471ms","start":"2026-04-02T06:10:30.695831Z","end":"2026-04-02T06:10:31.210607Z","steps":["trace[1084940974] 'process raft request' (duration: 189.619942ms)","trace[1084940974] 'compare' (duration: 324.383101ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T06:10:31.210742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:10:30.695804Z","time spent":"514.898915ms","remote":"127.0.0.1:42668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":688,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T06:10:31.210875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.578589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-nf7d5\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-04-02T06:10:31.21092Z","caller":"traceutil/trace.go:171","msg":"trace[1577435800] range","detail":"{range_begin:/registry/pods/kube-system/cilium-nf7d5; range_end:; response_count:1; response_revision:620; }","duration":"407.672242ms","start":"2026-04-02T06:10:30.803236Z","end":"2026-04-02T06:10:31.210908Z","steps":["trace[1577435800] 'agreement among raft nodes before linearized reading' (duration: 407.397055ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:10:31.210954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:10:30.80321Z","time spent":"407.735264ms","remote":"127.0.0.1:42770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16983,"request content":"key:\"/registry/pods/kube-system/cilium-nf7d5\" "} {"level":"info","ts":"2026-04-02T06:10:31.718564Z","caller":"traceutil/trace.go:171","msg":"trace[2127976799] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"800.995816ms","start":"2026-04-02T06:10:30.917535Z","end":"2026-04-02T06:10:31.718531Z","steps":["trace[2127976799] 'process raft request' (duration: 800.346601ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:10:31.718797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:10:30.917497Z","time spent":"801.189111ms","remote":"127.0.0.1:42690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T06:10:31.873226Z","caller":"traceutil/trace.go:171","msg":"trace[1585214195] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"644.591511ms","start":"2026-04-02T06:10:31.228593Z","end":"2026-04-02T06:10:31.873184Z","steps":["trace[1585214195] 'process raft request' (duration: 644.313114ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:10:31.873457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:10:31.228526Z","time spent":"644.830637ms","remote":"127.0.0.1:42770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T06:10:32.595862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"722.732718ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-02T06:10:32.5962Z","caller":"traceutil/trace.go:171","msg":"trace[1614616739] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"815.804187ms","start":"2026-04-02T06:10:31.780376Z","end":"2026-04-02T06:10:32.59618Z","steps":["trace[1614616739] 'process raft request' (duration: 815.628413ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:10:32.596203Z","caller":"traceutil/trace.go:171","msg":"trace[1486114735] linearizableReadLoop","detail":"{readStateIndex:641; appliedIndex:639; }","duration":"1.179666006s","start":"2026-04-02T06:10:31.416499Z","end":"2026-04-02T06:10:32.596165Z","steps":["trace[1486114735] 'read index received' (duration: 300.932002ms)","trace[1486114735] 'applied index is now lower than readState.Index' (duration: 878.730204ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T06:10:32.596334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:10:31.780345Z","time spent":"815.91965ms","remote":"127.0.0.1:43110","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T06:10:32.596467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.008340818s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T06:10:32.596803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.180301211s","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-02T06:10:32.596816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.05586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-02T06:10:32.59686Z","caller":"traceutil/trace.go:171","msg":"trace[1569379674] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:623; }","duration":"1.180437294s","start":"2026-04-02T06:10:31.416409Z","end":"2026-04-02T06:10:32.596847Z","steps":["trace[1569379674] 'agreement among raft nodes before linearized reading' (duration: 1.179919462s)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:10:32.596892Z","caller":"traceutil/trace.go:171","msg":"trace[626869675] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:623; }","duration":"560.194294ms","start":"2026-04-02T06:10:32.03668Z","end":"2026-04-02T06:10:32.596874Z","steps":["trace[626869675] 'agreement among raft nodes before linearized reading' (duration: 560.03307ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:10:32.596919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:10:31.416388Z","time spent":"1.180519246s","remote":"127.0.0.1:42872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-02T06:10:32.596929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:10:32.036653Z","time spent":"560.267376ms","remote":"127.0.0.1:42872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-02T06:10:32.596727Z","caller":"traceutil/trace.go:171","msg":"trace[1943993790] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:623; }","duration":"1.008692227s","start":"2026-04-02T06:10:31.588018Z","end":"2026-04-02T06:10:32.59671Z","steps":["trace[1943993790] 'agreement among raft nodes before linearized reading' (duration: 1.008348058s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:10:32.597038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:10:31.587995Z","time spent":"1.009028864s","remote":"127.0.0.1:42578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-02T06:10:33.073446Z","caller":"traceutil/trace.go:171","msg":"trace[179355958] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"466.800817ms","start":"2026-04-02T06:10:32.606616Z","end":"2026-04-02T06:10:33.073417Z","steps":["trace[179355958] 'process raft request' (duration: 422.507302ms)","trace[179355958] 'compare' (duration: 43.856875ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T06:10:33.073604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:10:32.606599Z","time spent":"466.926081ms","remote":"127.0.0.1:42872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T06:10:33.073996Z","caller":"traceutil/trace.go:171","msg":"trace[223783758] linearizableReadLoop","detail":"{readStateIndex:646; appliedIndex:643; }","duration":"462.109983ms","start":"2026-04-02T06:10:32.611855Z","end":"2026-04-02T06:10:33.073965Z","steps":["trace[223783758] 'read index received' (duration: 61.611855ms)","trace[223783758] 'applied index is now lower than readState.Index' (duration: 400.495958ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T06:10:33.074093Z","caller":"traceutil/trace.go:171","msg":"trace[1907225663] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"467.31292ms","start":"2026-04-02T06:10:32.606714Z","end":"2026-04-02T06:10:33.074027Z","steps":["trace[1907225663] 'process raft request' (duration: 466.643234ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:10:33.074247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.012792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T06:10:33.07429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.38083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T06:10:33.074306Z","caller":"traceutil/trace.go:171","msg":"trace[222920528] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:626; }","duration":"462.073573ms","start":"2026-04-02T06:10:32.612217Z","end":"2026-04-02T06:10:33.074291Z","steps":["trace[222920528] 'agreement among raft nodes before linearized reading' (duration: 461.985251ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:10:33.074397Z","caller":"traceutil/trace.go:171","msg":"trace[241130561] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:626; }","duration":"462.500973ms","start":"2026-04-02T06:10:32.61183Z","end":"2026-04-02T06:10:33.074331Z","steps":["trace[241130561] 'agreement among raft nodes before linearized reading' (duration: 462.287888ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:10:33.074407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:10:32.612202Z","time spent":"462.192846ms","remote":"127.0.0.1:42576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-02T06:10:33.074452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:10:32.611805Z","time spent":"462.634096ms","remote":"127.0.0.1:42834","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":27,"request content":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"warn","ts":"2026-04-02T06:10:33.074845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:10:32.606697Z","time spent":"467.552975ms","remote":"127.0.0.1:42872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T06:10:33.074927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.886171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T06:10:33.074974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.962001ms","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-02T06:10:33.075022Z","caller":"traceutil/trace.go:171","msg":"trace[948019782] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:626; }","duration":"319.072403ms","start":"2026-04-02T06:10:32.755937Z","end":"2026-04-02T06:10:33.07501Z","steps":["trace[948019782] 'agreement among raft nodes before linearized reading' (duration: 318.978581ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:10:33.075029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.863255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T06:10:33.075034Z","caller":"traceutil/trace.go:171","msg":"trace[1636935959] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:626; }","duration":"200.028004ms","start":"2026-04-02T06:10:32.874979Z","end":"2026-04-02T06:10:33.075007Z","steps":["trace[1636935959] 'agreement among raft nodes before linearized reading' (duration: 199.83922ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:10:33.075075Z","caller":"traceutil/trace.go:171","msg":"trace[1161053128] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:626; }","duration":"200.923596ms","start":"2026-04-02T06:10:32.874132Z","end":"2026-04-02T06:10:33.075055Z","steps":["trace[1161053128] 'agreement among raft nodes before linearized reading' (duration: 200.870145ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:10:33.075074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:10:32.755912Z","time spent":"319.137094ms","remote":"127.0.0.1:51584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":27,"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-02T06:10:33.074963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.856438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T06:10:33.075273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.839344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T06:10:33.074968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.830684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T06:10:33.075305Z","caller":"traceutil/trace.go:171","msg":"trace[569857887] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:626; }","duration":"336.878935ms","start":"2026-04-02T06:10:32.738417Z","end":"2026-04-02T06:10:33.075296Z","steps":["trace[569857887] 'agreement among raft nodes before linearized reading' (duration: 336.835324ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:10:33.075327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:10:32.738412Z","time spent":"336.909186ms","remote":"127.0.0.1:51578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:10000 "} {"level":"info","ts":"2026-04-02T06:10:33.075324Z","caller":"traceutil/trace.go:171","msg":"trace[1425531703] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:626; }","duration":"201.239343ms","start":"2026-04-02T06:10:32.874072Z","end":"2026-04-02T06:10:33.075311Z","steps":["trace[1425531703] 'agreement among raft nodes before linearized reading' (duration: 200.759692ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:10:33.07539Z","caller":"traceutil/trace.go:171","msg":"trace[817697210] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:626; }","duration":"319.280438ms","start":"2026-04-02T06:10:32.755999Z","end":"2026-04-02T06:10:33.07528Z","steps":["trace[817697210] 'agreement among raft nodes before linearized reading' (duration: 318.613071ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:10:33.075534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.761786ms","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-02T06:10:33.0756Z","caller":"traceutil/trace.go:171","msg":"trace[491170382] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:626; }","duration":"405.830598ms","start":"2026-04-02T06:10:32.669755Z","end":"2026-04-02T06:10:33.075586Z","steps":["trace[491170382] 'agreement among raft nodes before linearized reading' (duration: 405.632793ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:10:33.07564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:10:32.669723Z","time spent":"405.90848ms","remote":"127.0.0.1:42872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-02T06:10:33.075235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.984874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T06:10:33.07572Z","caller":"traceutil/trace.go:171","msg":"trace[769834537] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:626; }","duration":"336.480515ms","start":"2026-04-02T06:10:32.739227Z","end":"2026-04-02T06:10:33.075707Z","steps":["trace[769834537] 'agreement among raft nodes before linearized reading' (duration: 335.989203ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:10:33.075785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:10:32.739218Z","time spent":"336.531946ms","remote":"127.0.0.1:51578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:500 "} {"level":"warn","ts":"2026-04-02T06:10:33.0752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.795474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T06:10:33.075861Z","caller":"traceutil/trace.go:171","msg":"trace[842524674] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:626; }","duration":"337.477719ms","start":"2026-04-02T06:10:32.738372Z","end":"2026-04-02T06:10:33.07585Z","steps":["trace[842524674] 'agreement among raft nodes before linearized reading' (duration: 336.803063ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:10:33.075899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:10:32.738356Z","time spent":"337.529701ms","remote":"127.0.0.1:51578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true "} {"level":"warn","ts":"2026-04-02T06:10:33.075729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:10:32.755995Z","time spent":"319.575535ms","remote":"127.0.0.1:51584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:10000 "} {"level":"warn","ts":"2026-04-02T06:10:33.076067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.626702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T06:10:33.07616Z","caller":"traceutil/trace.go:171","msg":"trace[722699687] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:626; }","duration":"319.246357ms","start":"2026-04-02T06:10:32.756898Z","end":"2026-04-02T06:10:33.076144Z","steps":["trace[722699687] 'agreement among raft nodes before linearized reading' (duration: 318.608372ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:10:33.076254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:10:32.756892Z","time spent":"319.34263ms","remote":"127.0.0.1:51584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:500 "} {"level":"info","ts":"2026-04-02T06:10:38.475793Z","caller":"traceutil/trace.go:171","msg":"trace[1794958147] transaction","detail":"{read_only:false; response_revision:707; number_of_response:1; }","duration":"129.122044ms","start":"2026-04-02T06:10:38.346643Z","end":"2026-04-02T06:10:38.475765Z","steps":["trace[1794958147] 'process raft request' (duration: 128.905707ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:11:07.532649Z","caller":"traceutil/trace.go:171","msg":"trace[1591979785] transaction","detail":"{read_only:false; response_revision:947; number_of_response:1; }","duration":"176.112162ms","start":"2026-04-02T06:11:07.356498Z","end":"2026-04-02T06:11:07.53261Z","steps":["trace[1591979785] 'process raft request' (duration: 93.938715ms)","trace[1591979785] 'compare' (duration: 82.048335ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T06:11:25.359488Z","caller":"traceutil/trace.go:171","msg":"trace[2122904683] linearizableReadLoop","detail":"{readStateIndex:1040; appliedIndex:1039; }","duration":"102.735185ms","start":"2026-04-02T06:11:25.256721Z","end":"2026-04-02T06:11:25.359456Z","steps":["trace[2122904683] 'read index received' (duration: 102.416427ms)","trace[2122904683] 'applied index is now lower than readState.Index' (duration: 317.388µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T06:11:25.359698Z","caller":"traceutil/trace.go:171","msg":"trace[1583017510] transaction","detail":"{read_only:false; response_revision:1006; number_of_response:1; }","duration":"107.190188ms","start":"2026-04-02T06:11:25.252497Z","end":"2026-04-02T06:11:25.359687Z","steps":["trace[1583017510] 'process raft request' (duration: 106.791048ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:11:25.359977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.223736ms","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-02T06:11:25.360025Z","caller":"traceutil/trace.go:171","msg":"trace[210502275] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1006; }","duration":"103.344679ms","start":"2026-04-02T06:11:25.256669Z","end":"2026-04-02T06:11:25.360014Z","steps":["trace[210502275] 'agreement among raft nodes before linearized reading' (duration: 103.216816ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:11:25.534621Z","caller":"traceutil/trace.go:171","msg":"trace[1518324535] transaction","detail":"{read_only:false; response_revision:1007; number_of_response:1; }","duration":"121.248296ms","start":"2026-04-02T06:11:25.413322Z","end":"2026-04-02T06:11:25.53457Z","steps":["trace[1518324535] 'process raft request' (duration: 43.631202ms)","trace[1518324535] 'compare' (duration: 77.244486ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T06:11:25.901308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.732351ms","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-02T06:11:25.90141Z","caller":"traceutil/trace.go:171","msg":"trace[931355255] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1008; }","duration":"258.905425ms","start":"2026-04-02T06:11:25.642482Z","end":"2026-04-02T06:11:25.901388Z","steps":["trace[931355255] 'range keys from in-memory index tree' (duration: 258.456555ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:11:26.498553Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3275978732191028852,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-02T06:11:26.665437Z","caller":"traceutil/trace.go:171","msg":"trace[102329399] transaction","detail":"{read_only:false; response_revision:1009; number_of_response:1; }","duration":"759.554665ms","start":"2026-04-02T06:11:25.905851Z","end":"2026-04-02T06:11:26.665406Z","steps":["trace[102329399] 'process raft request' (duration: 759.412542ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:11:26.665586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:11:25.905823Z","time spent":"759.683148ms","remote":"127.0.0.1:42872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T06:11:26.668782Z","caller":"traceutil/trace.go:171","msg":"trace[514690947] linearizableReadLoop","detail":"{readStateIndex:1044; appliedIndex:1042; }","duration":"671.845592ms","start":"2026-04-02T06:11:25.996921Z","end":"2026-04-02T06:11:26.668767Z","steps":["trace[514690947] 'read index received' (duration: 668.397404ms)","trace[514690947] 'applied index is now lower than readState.Index' (duration: 3.447188ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T06:11:26.668881Z","caller":"traceutil/trace.go:171","msg":"trace[75305862] transaction","detail":"{read_only:false; response_revision:1010; number_of_response:1; }","duration":"524.913741ms","start":"2026-04-02T06:11:26.143953Z","end":"2026-04-02T06:11:26.668867Z","steps":["trace[75305862] 'process raft request' (duration: 524.720997ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:11:26.668968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:11:26.143933Z","time spent":"524.985512ms","remote":"127.0.0.1:42872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T06:11:26.66898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"672.069366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.15\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-02T06:11:26.669074Z","caller":"traceutil/trace.go:171","msg":"trace[1644403433] range","detail":"{range_begin:/registry/masterleases/199.19.213.15; range_end:; response_count:1; response_revision:1010; }","duration":"672.21285ms","start":"2026-04-02T06:11:25.996829Z","end":"2026-04-02T06:11:26.669042Z","steps":["trace[1644403433] 'agreement among raft nodes before linearized reading' (duration: 672.027786ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:11:26.669175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:11:25.996801Z","time spent":"672.339502ms","remote":"127.0.0.1:42614","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/199.19.213.15\" "} {"level":"info","ts":"2026-04-02T06:11:47.399433Z","caller":"traceutil/trace.go:171","msg":"trace[56998082] linearizableReadLoop","detail":"{readStateIndex:1116; appliedIndex:1115; }","duration":"236.454924ms","start":"2026-04-02T06:11:47.162943Z","end":"2026-04-02T06:11:47.399398Z","steps":["trace[56998082] 'read index received' (duration: 236.28731ms)","trace[56998082] 'applied index is now lower than readState.Index' (duration: 166.224µs)"],"step_count":2} {"level":"warn","ts":"2026-04-02T06:11:47.399707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.740831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-02T06:11:47.399754Z","caller":"traceutil/trace.go:171","msg":"trace[2050573482] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1077; }","duration":"236.852653ms","start":"2026-04-02T06:11:47.162887Z","end":"2026-04-02T06:11:47.39974Z","steps":["trace[2050573482] 'agreement among raft nodes before linearized reading' (duration: 236.647078ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:11:47.400109Z","caller":"traceutil/trace.go:171","msg":"trace[1918764269] transaction","detail":"{read_only:false; response_revision:1077; number_of_response:1; }","duration":"301.487052ms","start":"2026-04-02T06:11:47.098604Z","end":"2026-04-02T06:11:47.400091Z","steps":["trace[1918764269] 'process raft request' (duration: 300.584172ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:11:47.400295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:11:47.098574Z","time spent":"301.579294ms","remote":"127.0.0.1:42872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T06:11:48.010546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.694656ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T06:11:48.01077Z","caller":"traceutil/trace.go:171","msg":"trace[991709036] transaction","detail":"{read_only:false; response_revision:1078; number_of_response:1; }","duration":"724.779284ms","start":"2026-04-02T06:11:47.285966Z","end":"2026-04-02T06:11:48.010745Z","steps":["trace[991709036] 'process raft request' (duration: 393.77555ms)","trace[991709036] 'compare' (duration: 330.519953ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T06:11:48.010844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:11:47.285934Z","time spent":"724.874296ms","remote":"127.0.0.1:42872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T06:11:48.057179Z","caller":"traceutil/trace.go:171","msg":"trace[1987198415] linearizableReadLoop","detail":"{readStateIndex:1119; appliedIndex:1116; }","duration":"391.277424ms","start":"2026-04-02T06:11:47.66587Z","end":"2026-04-02T06:11:48.057148Z","steps":["trace[1987198415] 'read index received' (duration: 13.825024ms)","trace[1987198415] 'applied index is now lower than readState.Index' (duration: 377.45102ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T06:11:48.05755Z","caller":"traceutil/trace.go:171","msg":"trace[112638831] transaction","detail":"{read_only:false; response_revision:1079; number_of_response:1; }","duration":"652.710355ms","start":"2026-04-02T06:11:47.404826Z","end":"2026-04-02T06:11:48.057537Z","steps":["trace[112638831] 'process raft request' (duration: 652.112272ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:11:48.057713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:11:47.4048Z","time spent":"652.799638ms","remote":"127.0.0.1:42872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T06:11:48.058101Z","caller":"traceutil/trace.go:171","msg":"trace[32936305] transaction","detail":"{read_only:false; response_revision:1080; number_of_response:1; }","duration":"552.954728ms","start":"2026-04-02T06:11:47.50512Z","end":"2026-04-02T06:11:48.058075Z","steps":["trace[32936305] 'process raft request' (duration: 551.982547ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:11:48.058204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.332857ms","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-02T06:11:48.058205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:11:47.505083Z","time spent":"553.07132ms","remote":"127.0.0.1:42872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T06:11:48.058183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.572431ms","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-02T06:11:48.058245Z","caller":"traceutil/trace.go:171","msg":"trace[1130464054] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1080; }","duration":"392.410149ms","start":"2026-04-02T06:11:47.665824Z","end":"2026-04-02T06:11:48.058234Z","steps":["trace[1130464054] 'agreement among raft nodes before linearized reading' (duration: 392.269356ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:11:48.058279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:11:47.665797Z","time spent":"392.475871ms","remote":"127.0.0.1:42872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-02T06:11:48.058302Z","caller":"traceutil/trace.go:171","msg":"trace[1281067450] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1080; }","duration":"384.718864ms","start":"2026-04-02T06:11:47.673548Z","end":"2026-04-02T06:11:48.058267Z","steps":["trace[1281067450] 'agreement among raft nodes before linearized reading' (duration: 384.326765ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:11:48.058375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:11:47.673541Z","time spent":"384.819336ms","remote":"127.0.0.1:42872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-02T06:11:48.164376Z","caller":"traceutil/trace.go:171","msg":"trace[492440520] transaction","detail":"{read_only:false; response_revision:1081; number_of_response:1; }","duration":"102.363616ms","start":"2026-04-02T06:11:48.06198Z","end":"2026-04-02T06:11:48.164343Z","steps":["trace[492440520] 'process raft request' (duration: 50.554829ms)","trace[492440520] 'compare' (duration: 51.540671ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T06:11:49.008715Z","caller":"traceutil/trace.go:171","msg":"trace[1683887886] transaction","detail":"{read_only:false; response_revision:1083; number_of_response:1; }","duration":"191.115075ms","start":"2026-04-02T06:11:48.817569Z","end":"2026-04-02T06:11:49.008684Z","steps":["trace[1683887886] 'process raft request' (duration: 190.94932ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:11:49.657585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.773805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-02T06:11:49.657926Z","caller":"traceutil/trace.go:171","msg":"trace[1693764711] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1083; }","duration":"253.175314ms","start":"2026-04-02T06:11:49.404736Z","end":"2026-04-02T06:11:49.657911Z","steps":["trace[1693764711] 'range keys from in-memory index tree' (duration: 252.598371ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:11:49.657716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.936997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T06:11:49.658065Z","caller":"traceutil/trace.go:171","msg":"trace[1694133425] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1083; }","duration":"168.290485ms","start":"2026-04-02T06:11:49.489735Z","end":"2026-04-02T06:11:49.658026Z","steps":["trace[1694133425] 'count revisions from in-memory index tree' (duration: 167.837045ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:11:49.762135Z","caller":"traceutil/trace.go:171","msg":"trace[583404034] transaction","detail":"{read_only:false; response_revision:1084; number_of_response:1; }","duration":"100.608696ms","start":"2026-04-02T06:11:49.661499Z","end":"2026-04-02T06:11:49.762108Z","steps":["trace[583404034] 'process raft request' (duration: 100.457883ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:11:50.204447Z","caller":"traceutil/trace.go:171","msg":"trace[1625899737] linearizableReadLoop","detail":"{readStateIndex:1124; appliedIndex:1123; }","duration":"141.282791ms","start":"2026-04-02T06:11:50.0631Z","end":"2026-04-02T06:11:50.204383Z","steps":["trace[1625899737] 'read index received' (duration: 140.979334ms)","trace[1625899737] 'applied index is now lower than readState.Index' (duration: 302.087µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T06:11:50.204541Z","caller":"traceutil/trace.go:171","msg":"trace[731733017] transaction","detail":"{read_only:false; response_revision:1085; number_of_response:1; }","duration":"278.529951ms","start":"2026-04-02T06:11:49.925969Z","end":"2026-04-02T06:11:50.204499Z","steps":["trace[731733017] 'process raft request' (duration: 278.216844ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:11:50.204778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.649699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-02T06:11:50.204824Z","caller":"traceutil/trace.go:171","msg":"trace[178624031] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1085; }","duration":"141.773872ms","start":"2026-04-02T06:11:50.063038Z","end":"2026-04-02T06:11:50.204812Z","steps":["trace[178624031] 'agreement among raft nodes before linearized reading' (duration: 141.546277ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:11:50.266957Z","caller":"traceutil/trace.go:171","msg":"trace[1783197867] transaction","detail":"{read_only:false; response_revision:1086; number_of_response:1; }","duration":"201.843677ms","start":"2026-04-02T06:11:50.065082Z","end":"2026-04-02T06:11:50.266925Z","steps":["trace[1783197867] 'process raft request' (duration: 201.636623ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:11:50.435872Z","caller":"traceutil/trace.go:171","msg":"trace[1373974587] transaction","detail":"{read_only:false; response_revision:1087; number_of_response:1; }","duration":"226.197642ms","start":"2026-04-02T06:11:50.209638Z","end":"2026-04-02T06:11:50.435836Z","steps":["trace[1373974587] 'process raft request' (duration: 216.905211ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:11:50.477262Z","caller":"traceutil/trace.go:171","msg":"trace[1758257534] transaction","detail":"{read_only:false; response_revision:1089; number_of_response:1; }","duration":"206.186308ms","start":"2026-04-02T06:11:50.271045Z","end":"2026-04-02T06:11:50.477231Z","steps":["trace[1758257534] 'process raft request' (duration: 206.130006ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:11:50.477353Z","caller":"traceutil/trace.go:171","msg":"trace[1727138212] transaction","detail":"{read_only:false; response_revision:1088; number_of_response:1; }","duration":"206.943414ms","start":"2026-04-02T06:11:50.270364Z","end":"2026-04-02T06:11:50.477307Z","steps":["trace[1727138212] 'process raft request' (duration: 206.658518ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:11:51.365089Z","caller":"traceutil/trace.go:171","msg":"trace[1830831355] linearizableReadLoop","detail":"{readStateIndex:1129; appliedIndex:1128; }","duration":"212.063969ms","start":"2026-04-02T06:11:51.152994Z","end":"2026-04-02T06:11:51.365058Z","steps":["trace[1830831355] 'read index received' (duration: 211.924455ms)","trace[1830831355] 'applied index is now lower than readState.Index' (duration: 137.914µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T06:11:51.365193Z","caller":"traceutil/trace.go:171","msg":"trace[2089429701] transaction","detail":"{read_only:false; response_revision:1090; number_of_response:1; }","duration":"347.558343ms","start":"2026-04-02T06:11:51.017596Z","end":"2026-04-02T06:11:51.365155Z","steps":["trace[2089429701] 'process raft request' (duration: 347.273006ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:11:51.365343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:11:51.017553Z","time spent":"347.708206ms","remote":"127.0.0.1:42872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T06:11:51.365311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.291515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T06:11:51.36549Z","caller":"traceutil/trace.go:171","msg":"trace[1220121122] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1090; }","duration":"212.478769ms","start":"2026-04-02T06:11:51.152936Z","end":"2026-04-02T06:11:51.365415Z","steps":["trace[1220121122] 'agreement among raft nodes before linearized reading' (duration: 212.270934ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:11:52.5047Z","caller":"traceutil/trace.go:171","msg":"trace[1172881721] transaction","detail":"{read_only:false; response_revision:1092; number_of_response:1; }","duration":"231.985108ms","start":"2026-04-02T06:11:52.272663Z","end":"2026-04-02T06:11:52.504648Z","steps":["trace[1172881721] 'process raft request' (duration: 231.562018ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:11:53.565337Z","caller":"traceutil/trace.go:171","msg":"trace[2072614405] transaction","detail":"{read_only:false; response_revision:1096; number_of_response:1; }","duration":"190.369626ms","start":"2026-04-02T06:11:53.374941Z","end":"2026-04-02T06:11:53.565311Z","steps":["trace[2072614405] 'process raft request' (duration: 189.821703ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:12:06.286139Z","caller":"traceutil/trace.go:171","msg":"trace[206943578] transaction","detail":"{read_only:false; response_revision:1187; number_of_response:1; }","duration":"353.195286ms","start":"2026-04-02T06:12:05.93288Z","end":"2026-04-02T06:12:06.286076Z","steps":["trace[206943578] 'process raft request' (duration: 352.914089ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:12:06.286334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.03045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.15\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-04-02T06:12:06.286361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:12:05.932856Z","time spent":"353.394381ms","remote":"127.0.0.1:42872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T06:12:06.286416Z","caller":"traceutil/trace.go:171","msg":"trace[466413662] range","detail":"{range_begin:/registry/masterleases/199.19.213.15; range_end:; response_count:1; response_revision:1187; }","duration":"287.196354ms","start":"2026-04-02T06:12:05.999205Z","end":"2026-04-02T06:12:06.286401Z","steps":["trace[466413662] 'agreement among raft nodes before linearized reading' (duration: 286.950148ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:12:06.286037Z","caller":"traceutil/trace.go:171","msg":"trace[155408378] linearizableReadLoop","detail":"{readStateIndex:1231; appliedIndex:1230; }","duration":"286.731333ms","start":"2026-04-02T06:12:05.999273Z","end":"2026-04-02T06:12:06.286005Z","steps":["trace[155408378] 'read index received' (duration: 286.399695ms)","trace[155408378] 'applied index is now lower than readState.Index' (duration: 330.298µs)"],"step_count":2} {"level":"warn","ts":"2026-04-02T06:12:07.134858Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3275978732191029292,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T06:12:07.593442Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.025852225s","expected-duration":"1s"} {"level":"info","ts":"2026-04-02T06:12:07.593797Z","caller":"traceutil/trace.go:171","msg":"trace[1241431947] linearizableReadLoop","detail":"{readStateIndex:1234; appliedIndex:1233; }","duration":"959.981597ms","start":"2026-04-02T06:12:06.633798Z","end":"2026-04-02T06:12:07.59378Z","steps":["trace[1241431947] 'read index received' (duration: 959.828103ms)","trace[1241431947] 'applied index is now lower than readState.Index' (duration: 150.364µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T06:12:07.593927Z","caller":"traceutil/trace.go:171","msg":"trace[1608124194] transaction","detail":"{read_only:false; response_revision:1189; number_of_response:1; }","duration":"1.026456799s","start":"2026-04-02T06:12:06.567435Z","end":"2026-04-02T06:12:07.593892Z","steps":["trace[1608124194] 'process raft request' (duration: 1.026204893s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:12:07.594014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"960.198912ms","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-02T06:12:07.594046Z","caller":"traceutil/trace.go:171","msg":"trace[435139905] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1189; }","duration":"960.290684ms","start":"2026-04-02T06:12:06.633747Z","end":"2026-04-02T06:12:07.594037Z","steps":["trace[435139905] 'agreement among raft nodes before linearized reading' (duration: 960.150881ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:12:07.594066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:12:06.63372Z","time spent":"960.340414ms","remote":"127.0.0.1:42872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-02T06:12:07.594076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:12:06.567389Z","time spent":"1.026589522s","remote":"127.0.0.1:42872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T06:12:07.594326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"930.257799ms","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-02T06:12:07.594404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"673.090908ms","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-02T06:12:07.594436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"706.792991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T06:12:07.594445Z","caller":"traceutil/trace.go:171","msg":"trace[1865358447] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1189; }","duration":"673.17991ms","start":"2026-04-02T06:12:06.921252Z","end":"2026-04-02T06:12:07.594432Z","steps":["trace[1865358447] 'agreement among raft nodes before linearized reading' (duration: 673.096788ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:12:07.594468Z","caller":"traceutil/trace.go:171","msg":"trace[1330083512] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:1189; }","duration":"706.874273ms","start":"2026-04-02T06:12:06.887583Z","end":"2026-04-02T06:12:07.594457Z","steps":["trace[1330083512] 'agreement among raft nodes before linearized reading' (duration: 706.812891ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:12:07.594423Z","caller":"traceutil/trace.go:171","msg":"trace[1797362260] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1189; }","duration":"930.405022ms","start":"2026-04-02T06:12:06.663991Z","end":"2026-04-02T06:12:07.594396Z","steps":["trace[1797362260] 'agreement among raft nodes before linearized reading' (duration: 929.953642ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:12:07.594479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:12:06.921225Z","time spent":"673.246952ms","remote":"127.0.0.1:51756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":29,"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-02T06:12:07.594486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:12:06.887557Z","time spent":"706.925294ms","remote":"127.0.0.1:52392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"warn","ts":"2026-04-02T06:12:07.594547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:12:06.663968Z","time spent":"930.568057ms","remote":"127.0.0.1:42872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-02T06:12:07.595328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"920.7686ms","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-02T06:12:07.595391Z","caller":"traceutil/trace.go:171","msg":"trace[1488609446] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1189; }","duration":"920.874693ms","start":"2026-04-02T06:12:06.6745Z","end":"2026-04-02T06:12:07.595375Z","steps":["trace[1488609446] 'agreement among raft nodes before linearized reading' (duration: 920.680368ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:12:07.595427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:12:06.67448Z","time spent":"920.939414ms","remote":"127.0.0.1:42872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-02T06:12:07.871888Z","caller":"traceutil/trace.go:171","msg":"trace[1137893519] transaction","detail":"{read_only:false; response_revision:1190; number_of_response:1; }","duration":"272.218643ms","start":"2026-04-02T06:12:07.599629Z","end":"2026-04-02T06:12:07.871847Z","steps":["trace[1137893519] 'process raft request' (duration: 268.682918ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:12:07.872819Z","caller":"traceutil/trace.go:171","msg":"trace[272776752] linearizableReadLoop","detail":"{readStateIndex:1237; appliedIndex:1234; }","duration":"151.629746ms","start":"2026-04-02T06:12:07.721163Z","end":"2026-04-02T06:12:07.872793Z","steps":["trace[272776752] 'read index received' (duration: 147.195879ms)","trace[272776752] 'applied index is now lower than readState.Index' (duration: 4.432947ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T06:12:07.872884Z","caller":"traceutil/trace.go:171","msg":"trace[1107046041] transaction","detail":"{read_only:false; response_revision:1191; number_of_response:1; }","duration":"272.777366ms","start":"2026-04-02T06:12:07.600077Z","end":"2026-04-02T06:12:07.872854Z","steps":["trace[1107046041] 'process raft request' (duration: 272.51915ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:12:07.873015Z","caller":"traceutil/trace.go:171","msg":"trace[732772550] transaction","detail":"{read_only:false; response_revision:1192; number_of_response:1; }","duration":"272.720306ms","start":"2026-04-02T06:12:07.600241Z","end":"2026-04-02T06:12:07.872961Z","steps":["trace[732772550] 'process raft request' (duration: 272.510431ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:12:07.873099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.82819ms","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-02T06:12:07.873138Z","caller":"traceutil/trace.go:171","msg":"trace[308054413] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1192; }","duration":"152.023956ms","start":"2026-04-02T06:12:07.721103Z","end":"2026-04-02T06:12:07.873127Z","steps":["trace[308054413] 'agreement among raft nodes before linearized reading' (duration: 151.78636ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:12:24.076031Z","caller":"traceutil/trace.go:171","msg":"trace[87402880] linearizableReadLoop","detail":"{readStateIndex:1291; appliedIndex:1290; }","duration":"113.054786ms","start":"2026-04-02T06:12:23.962953Z","end":"2026-04-02T06:12:24.076008Z","steps":["trace[87402880] 'read index received' (duration: 112.981314ms)","trace[87402880] 'applied index is now lower than readState.Index' (duration: 72.952µs)"],"step_count":2} {"level":"warn","ts":"2026-04-02T06:12:24.076214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.25103ms","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-02T06:12:24.076238Z","caller":"traceutil/trace.go:171","msg":"trace[1789348374] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1243; }","duration":"113.310072ms","start":"2026-04-02T06:12:23.962922Z","end":"2026-04-02T06:12:24.076232Z","steps":["trace[1789348374] 'agreement among raft nodes before linearized reading' (duration: 113.19945ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:12:24.076199Z","caller":"traceutil/trace.go:171","msg":"trace[1030648055] transaction","detail":"{read_only:false; response_revision:1243; number_of_response:1; }","duration":"113.997448ms","start":"2026-04-02T06:12:23.962131Z","end":"2026-04-02T06:12:24.076128Z","steps":["trace[1030648055] 'process raft request' (duration: 113.767512ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:12:24.270342Z","caller":"traceutil/trace.go:171","msg":"trace[1579396225] transaction","detail":"{read_only:false; response_revision:1244; number_of_response:1; }","duration":"188.737621ms","start":"2026-04-02T06:12:24.081572Z","end":"2026-04-02T06:12:24.27031Z","steps":["trace[1579396225] 'process raft request' (duration: 163.610335ms)","trace[1579396225] 'compare' (duration: 24.685286ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T06:12:24.295392Z","caller":"traceutil/trace.go:171","msg":"trace[893735899] transaction","detail":"{read_only:false; response_revision:1246; number_of_response:1; }","duration":"213.591761ms","start":"2026-04-02T06:12:24.081777Z","end":"2026-04-02T06:12:24.295369Z","steps":["trace[893735899] 'process raft request' (duration: 213.539099ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:12:24.296345Z","caller":"traceutil/trace.go:171","msg":"trace[637227071] transaction","detail":"{read_only:false; response_revision:1245; number_of_response:1; }","duration":"214.602325ms","start":"2026-04-02T06:12:24.081731Z","end":"2026-04-02T06:12:24.296333Z","steps":["trace[637227071] 'process raft request' (duration: 213.472398ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:12:26.295015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.017289ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-02T06:12:26.295155Z","caller":"traceutil/trace.go:171","msg":"trace[1946921240] linearizableReadLoop","detail":"{readStateIndex:1299; appliedIndex:1298; }","duration":"293.942807ms","start":"2026-04-02T06:12:26.001192Z","end":"2026-04-02T06:12:26.295135Z","steps":["trace[1946921240] 'read index received' (duration: 157.693082ms)","trace[1946921240] 'applied index is now lower than readState.Index' (duration: 136.246915ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T06:12:26.295257Z","caller":"traceutil/trace.go:171","msg":"trace[1875646194] transaction","detail":"{read_only:false; response_revision:1251; number_of_response:1; }","duration":"300.870735ms","start":"2026-04-02T06:12:25.994377Z","end":"2026-04-02T06:12:26.295247Z","steps":["trace[1875646194] 'process raft request' (duration: 164.541457ms)","trace[1875646194] 'compare' (duration: 135.734233ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T06:12:26.295327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:12:25.99435Z","time spent":"300.932677ms","remote":"127.0.0.1:42668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":751,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T06:12:26.295732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.28672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T06:12:26.295809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.597963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.15\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-02T06:12:26.295843Z","caller":"traceutil/trace.go:171","msg":"trace[966947653] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1251; }","duration":"266.455074ms","start":"2026-04-02T06:12:26.029364Z","end":"2026-04-02T06:12:26.295819Z","steps":["trace[966947653] 'agreement among raft nodes before linearized reading' (duration: 266.215799ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:12:26.295851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.019204ms","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-02T06:12:26.295886Z","caller":"traceutil/trace.go:171","msg":"trace[1473020453] range","detail":"{range_begin:/registry/masterleases/199.19.213.15; range_end:; response_count:1; response_revision:1251; }","duration":"294.718776ms","start":"2026-04-02T06:12:26.001151Z","end":"2026-04-02T06:12:26.29587Z","steps":["trace[1473020453] 'agreement among raft nodes before linearized reading' (duration: 294.447159ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:12:26.295932Z","caller":"traceutil/trace.go:171","msg":"trace[1129618010] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1251; }","duration":"215.110897ms","start":"2026-04-02T06:12:26.080802Z","end":"2026-04-02T06:12:26.295913Z","steps":["trace[1129618010] 'agreement among raft nodes before linearized reading' (duration: 214.81794ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:12:26.507881Z","caller":"traceutil/trace.go:171","msg":"trace[1699273816] linearizableReadLoop","detail":"{readStateIndex:1303; appliedIndex:1299; }","duration":"206.291113ms","start":"2026-04-02T06:12:26.301562Z","end":"2026-04-02T06:12:26.507853Z","steps":["trace[1699273816] 'read index received' (duration: 5.433361ms)","trace[1699273816] 'applied index is now lower than readState.Index' (duration: 200.856722ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T06:12:26.50802Z","caller":"traceutil/trace.go:171","msg":"trace[678282657] transaction","detail":"{read_only:false; response_revision:1252; number_of_response:1; }","duration":"208.090858ms","start":"2026-04-02T06:12:26.299916Z","end":"2026-04-02T06:12:26.508006Z","steps":["trace[678282657] 'process raft request' (duration: 140.804295ms)","trace[678282657] 'compare' (duration: 65.736505ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T06:12:26.508184Z","caller":"traceutil/trace.go:171","msg":"trace[432727261] transaction","detail":"{read_only:false; response_revision:1253; number_of_response:1; }","duration":"207.914734ms","start":"2026-04-02T06:12:26.300238Z","end":"2026-04-02T06:12:26.508153Z","steps":["trace[432727261] 'process raft request' (duration: 206.989901ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:12:26.508313Z","caller":"traceutil/trace.go:171","msg":"trace[1494954936] transaction","detail":"{read_only:false; response_revision:1254; number_of_response:1; }","duration":"207.415221ms","start":"2026-04-02T06:12:26.300884Z","end":"2026-04-02T06:12:26.508299Z","steps":["trace[1494954936] 'process raft request' (duration: 206.920778ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:12:26.508549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.970581ms","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-02T06:12:26.508588Z","caller":"traceutil/trace.go:171","msg":"trace[1738685421] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1254; }","duration":"207.070653ms","start":"2026-04-02T06:12:26.301507Z","end":"2026-04-02T06:12:26.508578Z","steps":["trace[1738685421] 'agreement among raft nodes before linearized reading' (duration: 206.880439ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:12:26.520904Z","caller":"traceutil/trace.go:171","msg":"trace[492991185] transaction","detail":"{read_only:false; response_revision:1255; number_of_response:1; }","duration":"212.891243ms","start":"2026-04-02T06:12:26.307974Z","end":"2026-04-02T06:12:26.520866Z","steps":["trace[492991185] 'process raft request' (duration: 212.725929ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:12:26.521015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.518849ms","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-02T06:12:26.521089Z","caller":"traceutil/trace.go:171","msg":"trace[857364997] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1255; }","duration":"218.633092ms","start":"2026-04-02T06:12:26.302436Z","end":"2026-04-02T06:12:26.521069Z","steps":["trace[857364997] 'agreement among raft nodes before linearized reading' (duration: 218.407317ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:12:26.731947Z","caller":"traceutil/trace.go:171","msg":"trace[1334290539] transaction","detail":"{read_only:false; response_revision:1257; number_of_response:1; }","duration":"205.479864ms","start":"2026-04-02T06:12:26.526429Z","end":"2026-04-02T06:12:26.731909Z","steps":["trace[1334290539] 'process raft request' (duration: 205.142916ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:12:26.731971Z","caller":"traceutil/trace.go:171","msg":"trace[105963241] linearizableReadLoop","detail":"{readStateIndex:1305; appliedIndex:1304; }","duration":"206.388125ms","start":"2026-04-02T06:12:26.525487Z","end":"2026-04-02T06:12:26.731875Z","steps":["trace[105963241] 'read index received' (duration: 116.490758ms)","trace[105963241] 'applied index is now lower than readState.Index' (duration: 89.895037ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T06:12:26.731962Z","caller":"traceutil/trace.go:171","msg":"trace[33128548] transaction","detail":"{read_only:false; response_revision:1256; number_of_response:1; }","duration":"207.861471ms","start":"2026-04-02T06:12:26.524049Z","end":"2026-04-02T06:12:26.731911Z","steps":["trace[33128548] 'process raft request' (duration: 117.867261ms)","trace[33128548] 'compare' (duration: 89.535569ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T06:12:26.732176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.230122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T06:12:26.732168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.658623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-02T06:12:26.73221Z","caller":"traceutil/trace.go:171","msg":"trace[1584340075] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1258; }","duration":"145.328214ms","start":"2026-04-02T06:12:26.586872Z","end":"2026-04-02T06:12:26.7322Z","steps":["trace[1584340075] 'agreement among raft nodes before linearized reading' (duration: 145.235042ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:12:26.732228Z","caller":"traceutil/trace.go:171","msg":"trace[957193405] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:1258; }","duration":"206.755475ms","start":"2026-04-02T06:12:26.525458Z","end":"2026-04-02T06:12:26.732213Z","steps":["trace[957193405] 'agreement among raft nodes before linearized reading' (duration: 206.520589ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:12:26.733197Z","caller":"traceutil/trace.go:171","msg":"trace[1240733895] transaction","detail":"{read_only:false; response_revision:1258; number_of_response:1; }","duration":"203.965827ms","start":"2026-04-02T06:12:26.527661Z","end":"2026-04-02T06:12:26.731627Z","steps":["trace[1240733895] 'process raft request' (duration: 203.936267ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:12:39.139043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.244018ms","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-02T06:12:39.139167Z","caller":"traceutil/trace.go:171","msg":"trace[1841770156] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:1354; }","duration":"402.410492ms","start":"2026-04-02T06:12:38.736715Z","end":"2026-04-02T06:12:39.139126Z","steps":["trace[1841770156] 'count revisions from in-memory index tree' (duration: 402.130456ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:12:39.139144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"551.889097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T06:12:39.139227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:12:38.736691Z","time spent":"402.519355ms","remote":"127.0.0.1:50962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"warn","ts":"2026-04-02T06:12:39.13939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"567.822692ms","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-02T06:12:39.139503Z","caller":"traceutil/trace.go:171","msg":"trace[317777081] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1354; }","duration":"568.000816ms","start":"2026-04-02T06:12:38.571485Z","end":"2026-04-02T06:12:39.139486Z","steps":["trace[317777081] 'range keys from in-memory index tree' (duration: 567.255097ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:12:39.139617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:12:38.571452Z","time spent":"568.144369ms","remote":"127.0.0.1:42872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-02T06:12:39.139244Z","caller":"traceutil/trace.go:171","msg":"trace[1173391448] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1354; }","duration":"552.01756ms","start":"2026-04-02T06:12:38.587199Z","end":"2026-04-02T06:12:39.139216Z","steps":["trace[1173391448] 'range keys from in-memory index tree' (duration: 551.728512ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:12:39.140201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:12:38.587181Z","time spent":"552.983634ms","remote":"127.0.0.1:42576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-02T06:12:39.140473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"569.465341ms","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-02T06:12:39.140539Z","caller":"traceutil/trace.go:171","msg":"trace[670739924] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1354; }","duration":"569.574773ms","start":"2026-04-02T06:12:38.570949Z","end":"2026-04-02T06:12:39.140524Z","steps":["trace[670739924] 'range keys from in-memory index tree' (duration: 569.283186ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:12:39.140592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:12:38.570927Z","time spent":"569.650995ms","remote":"127.0.0.1:42872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-02T06:12:39.140576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"712.974901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-02T06:12:39.14065Z","caller":"traceutil/trace.go:171","msg":"trace[1775644284] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1354; }","duration":"713.097824ms","start":"2026-04-02T06:12:38.427537Z","end":"2026-04-02T06:12:39.140634Z","steps":["trace[1775644284] 'count revisions from in-memory index tree' (duration: 712.868879ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:12:39.140689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:12:38.427503Z","time spent":"713.175176ms","remote":"127.0.0.1:42760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":5,"response size":29,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"warn","ts":"2026-04-02T06:12:39.139719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"620.533883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-04-02T06:12:39.140745Z","caller":"traceutil/trace.go:171","msg":"trace[425067750] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1354; }","duration":"621.602878ms","start":"2026-04-02T06:12:38.519124Z","end":"2026-04-02T06:12:39.140727Z","steps":["trace[425067750] 'range keys from in-memory index tree' (duration: 620.345748ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:12:39.140798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:12:38.519102Z","time spent":"621.68378ms","remote":"127.0.0.1:42872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":548,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-02T06:12:39.13914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.197259697s","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":"info","ts":"2026-04-02T06:12:39.141693Z","caller":"traceutil/trace.go:171","msg":"trace[2076233135] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1354; }","duration":"1.199893241s","start":"2026-04-02T06:12:37.94178Z","end":"2026-04-02T06:12:39.141673Z","steps":["trace[2076233135] 'count revisions from in-memory index tree' (duration: 1.197156175s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:12:39.142361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:12:37.941752Z","time spent":"1.200585668s","remote":"127.0.0.1:51742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"info","ts":"2026-04-02T06:12:39.167125Z","caller":"traceutil/trace.go:171","msg":"trace[1335200288] linearizableReadLoop","detail":"{readStateIndex:1406; appliedIndex:1405; }","duration":"342.102068ms","start":"2026-04-02T06:12:38.824994Z","end":"2026-04-02T06:12:39.167097Z","steps":["trace[1335200288] 'read index received' (duration: 342.039367ms)","trace[1335200288] 'applied index is now lower than readState.Index' (duration: 61.671µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T06:12:39.167192Z","caller":"traceutil/trace.go:171","msg":"trace[1974182987] transaction","detail":"{read_only:false; response_revision:1355; number_of_response:1; }","duration":"377.708346ms","start":"2026-04-02T06:12:38.789462Z","end":"2026-04-02T06:12:39.167171Z","steps":["trace[1974182987] 'process raft request' (duration: 377.490041ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:12:39.167436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.428097ms","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-02T06:12:39.167479Z","caller":"traceutil/trace.go:171","msg":"trace[1698291793] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1355; }","duration":"342.521299ms","start":"2026-04-02T06:12:38.824946Z","end":"2026-04-02T06:12:39.167468Z","steps":["trace[1698291793] 'agreement among raft nodes before linearized reading' (duration: 342.290822ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:12:39.167513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:12:38.824922Z","time spent":"342.580931ms","remote":"127.0.0.1:42872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-02T06:12:39.167639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:12:38.789434Z","time spent":"377.943363ms","remote":"127.0.0.1:42872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T06:12:39.436048Z","caller":"traceutil/trace.go:171","msg":"trace[504911347] linearizableReadLoop","detail":"{readStateIndex:1409; appliedIndex:1406; }","duration":"268.813261ms","start":"2026-04-02T06:12:39.167207Z","end":"2026-04-02T06:12:39.436021Z","steps":["trace[504911347] 'read index received' (duration: 268.552525ms)","trace[504911347] 'applied index is now lower than readState.Index' (duration: 236.596µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T06:12:39.436089Z","caller":"traceutil/trace.go:171","msg":"trace[545947311] transaction","detail":"{read_only:false; response_revision:1358; number_of_response:1; }","duration":"290.35437ms","start":"2026-04-02T06:12:39.145686Z","end":"2026-04-02T06:12:39.436041Z","steps":["trace[545947311] 'process raft request' (duration: 290.274858ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:12:39.436258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"602.705291ms","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-02T06:12:39.436291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"611.223148ms","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-02T06:12:39.436329Z","caller":"traceutil/trace.go:171","msg":"trace[1897971162] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1358; }","duration":"611.32386ms","start":"2026-04-02T06:12:38.824994Z","end":"2026-04-02T06:12:39.436318Z","steps":["trace[1897971162] 'agreement among raft nodes before linearized reading' (duration: 611.132485ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:12:39.436259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.721278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:472"} {"level":"warn","ts":"2026-04-02T06:12:39.436328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.117186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T06:12:39.436354Z","caller":"traceutil/trace.go:171","msg":"trace[1657034160] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1358; }","duration":"314.877983ms","start":"2026-04-02T06:12:39.121472Z","end":"2026-04-02T06:12:39.43635Z","steps":["trace[1657034160] 'agreement among raft nodes before linearized reading' (duration: 314.616026ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:12:39.436379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:12:39.121454Z","time spent":"314.918643ms","remote":"127.0.0.1:42872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":494,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-04-02T06:12:39.436377Z","caller":"traceutil/trace.go:171","msg":"trace[498910726] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1358; }","duration":"295.184637ms","start":"2026-04-02T06:12:39.141178Z","end":"2026-04-02T06:12:39.436362Z","steps":["trace[498910726] 'agreement among raft nodes before linearized reading' (duration: 295.123076ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:12:39.43633Z","caller":"traceutil/trace.go:171","msg":"trace[1853280738] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1358; }","duration":"602.836156ms","start":"2026-04-02T06:12:38.833479Z","end":"2026-04-02T06:12:39.436315Z","steps":["trace[1853280738] 'agreement among raft nodes before linearized reading' (duration: 602.651431ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:12:39.436412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:12:38.833456Z","time spent":"602.949788ms","remote":"127.0.0.1:42872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-02T06:12:39.436472Z","caller":"traceutil/trace.go:171","msg":"trace[1153495484] transaction","detail":"{read_only:false; response_revision:1357; number_of_response:1; }","duration":"291.065888ms","start":"2026-04-02T06:12:39.145398Z","end":"2026-04-02T06:12:39.436464Z","steps":["trace[1153495484] 'process raft request' (duration: 290.524774ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:12:39.436406Z","caller":"traceutil/trace.go:171","msg":"trace[1819187958] transaction","detail":"{read_only:false; response_revision:1356; number_of_response:1; }","duration":"291.863388ms","start":"2026-04-02T06:12:39.14453Z","end":"2026-04-02T06:12:39.436394Z","steps":["trace[1819187958] 'process raft request' (duration: 291.241612ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:12:39.436365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:12:38.824985Z","time spent":"611.371881ms","remote":"127.0.0.1:42872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-02T06:12:39.455171Z","caller":"traceutil/trace.go:171","msg":"trace[1369625546] transaction","detail":"{read_only:false; response_revision:1359; number_of_response:1; }","duration":"283.084675ms","start":"2026-04-02T06:12:39.17207Z","end":"2026-04-02T06:12:39.455154Z","steps":["trace[1369625546] 'process raft request' (duration: 282.960042ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:15:54.58562Z","caller":"traceutil/trace.go:171","msg":"trace[183452214] transaction","detail":"{read_only:false; response_revision:2283; number_of_response:1; }","duration":"164.321479ms","start":"2026-04-02T06:15:54.42126Z","end":"2026-04-02T06:15:54.585581Z","steps":["trace[183452214] 'process raft request' (duration: 164.199326ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:15:54.58579Z","caller":"traceutil/trace.go:171","msg":"trace[553731463] transaction","detail":"{read_only:false; response_revision:2284; number_of_response:1; }","duration":"164.236878ms","start":"2026-04-02T06:15:54.421534Z","end":"2026-04-02T06:15:54.585771Z","steps":["trace[553731463] 'process raft request' (duration: 163.961251ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:15:54.585986Z","caller":"traceutil/trace.go:171","msg":"trace[1172231264] transaction","detail":"{read_only:false; response_revision:2282; number_of_response:1; }","duration":"165.021777ms","start":"2026-04-02T06:15:54.420932Z","end":"2026-04-02T06:15:54.585954Z","steps":["trace[1172231264] 'process raft request' (duration: 158.776165ms)"],"step_count":1}