{"level":"warn","ts":"2026-04-07T09:35:46.135713Z","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-07T09:35:46.135815Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.22:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.22:2380","--initial-cluster=instance=https://199.204.45.22:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.22:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.22: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-07T09:35:46.135906Z","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-07T09:35:46.135923Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.22:2380"]} {"level":"info","ts":"2026-04-07T09:35:46.135951Z","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-07T09:35:46.137006Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.22:2379"]} {"level":"info","ts":"2026-04-07T09:35:46.137135Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.22:2380"],"listen-peer-urls":["https://199.204.45.22:2380"],"advertise-client-urls":["https://199.204.45.22:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.22:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.22: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-07T09:35:46.14411Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.427136ms"} {"level":"info","ts":"2026-04-07T09:35:46.1528Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"fed19d9291630e09","cluster-id":"6ebf1e01320cc017"} {"level":"info","ts":"2026-04-07T09:35:46.152942Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 switched to configuration voters=()"} {"level":"info","ts":"2026-04-07T09:35:46.152972Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became follower at term 0"} {"level":"info","ts":"2026-04-07T09:35:46.153005Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft fed19d9291630e09 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-07T09:35:46.15302Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became follower at term 1"} {"level":"info","ts":"2026-04-07T09:35:46.153062Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 switched to configuration voters=(18361630408596196873)"} {"level":"warn","ts":"2026-04-07T09:35:46.160078Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-07T09:35:46.161359Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-07T09:35:46.16316Z","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-07T09:35:46.16546Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"fed19d9291630e09","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-07T09:35:46.16695Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"fed19d9291630e09","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-07T09:35:46.167314Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 switched to configuration voters=(18361630408596196873)"} {"level":"info","ts":"2026-04-07T09:35:46.171208Z","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-07T09:35:46.171281Z","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-07T09:35:46.171329Z","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-07T09:35:46.171812Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"6ebf1e01320cc017","local-member-id":"fed19d9291630e09","added-peer-id":"fed19d9291630e09","added-peer-peer-urls":["https://199.204.45.22:2380"]} {"level":"info","ts":"2026-04-07T09:35:46.172673Z","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-07T09:35:46.172832Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.22:2380"} {"level":"info","ts":"2026-04-07T09:35:46.173056Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"fed19d9291630e09","initial-advertise-peer-urls":["https://199.204.45.22:2380"],"listen-peer-urls":["https://199.204.45.22:2380"],"advertise-client-urls":["https://199.204.45.22:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.22:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-07T09:35:46.173114Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-07T09:35:46.172873Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.22:2380"} {"level":"info","ts":"2026-04-07T09:35:47.153865Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 is starting a new election at term 1"} {"level":"info","ts":"2026-04-07T09:35:47.153927Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-07T09:35:47.15397Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 received MsgPreVoteResp from fed19d9291630e09 at term 1"} {"level":"info","ts":"2026-04-07T09:35:47.153989Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became candidate at term 2"} {"level":"info","ts":"2026-04-07T09:35:47.154Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 received MsgVoteResp from fed19d9291630e09 at term 2"} {"level":"info","ts":"2026-04-07T09:35:47.154016Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became leader at term 2"} {"level":"info","ts":"2026-04-07T09:35:47.154029Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: fed19d9291630e09 elected leader fed19d9291630e09 at term 2"} {"level":"info","ts":"2026-04-07T09:35:47.15507Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T09:35:47.156513Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"fed19d9291630e09","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.22:2379]}","request-path":"/0/members/fed19d9291630e09/attributes","cluster-id":"6ebf1e01320cc017","publish-timeout":"7s"} {"level":"info","ts":"2026-04-07T09:35:47.156517Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T09:35:47.156546Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T09:35:47.156942Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-07T09:35:47.156975Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-07T09:35:47.159813Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.22:2379"} {"level":"info","ts":"2026-04-07T09:35:47.161073Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-07T09:35:47.170913Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"6ebf1e01320cc017","local-member-id":"fed19d9291630e09","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T09:35:47.171221Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T09:35:47.171255Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T09:35:51.6162Z","caller":"traceutil/trace.go:171","msg":"trace[2105547162] linearizableReadLoop","detail":"{readStateIndex:210; appliedIndex:209; }","duration":"318.093347ms","start":"2026-04-07T09:35:51.298077Z","end":"2026-04-07T09:35:51.616171Z","steps":["trace[2105547162] 'read index received' (duration: 317.879744ms)","trace[2105547162] 'applied index is now lower than readState.Index' (duration: 212.483µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T09:35:51.616317Z","caller":"traceutil/trace.go:171","msg":"trace[1853820561] transaction","detail":"{read_only:false; response_revision:205; number_of_response:1; }","duration":"671.581509ms","start":"2026-04-07T09:35:50.9447Z","end":"2026-04-07T09:35:51.616281Z","steps":["trace[1853820561] 'process raft request' (duration: 671.313546ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:35:51.616515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.284929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T09:35:51.616594Z","caller":"traceutil/trace.go:171","msg":"trace[967324262] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:205; }","duration":"318.554962ms","start":"2026-04-07T09:35:51.298025Z","end":"2026-04-07T09:35:51.61658Z","steps":["trace[967324262] 'agreement among raft nodes before linearized reading' (duration: 318.281809ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:35:51.616705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:35:51.297998Z","time spent":"318.695223ms","remote":"127.0.0.1:50742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-07T09:35:51.616871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:35:50.94468Z","time spent":"671.709611ms","remote":"127.0.0.1:50642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3694,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T09:35:52.490991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.194606ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-07T09:35:52.491258Z","caller":"traceutil/trace.go:171","msg":"trace[1102683335] linearizableReadLoop","detail":"{readStateIndex:213; appliedIndex:210; }","duration":"706.811582ms","start":"2026-04-07T09:35:51.784434Z","end":"2026-04-07T09:35:52.491246Z","steps":["trace[1102683335] 'read index received' (duration: 251.324913ms)","trace[1102683335] 'applied index is now lower than readState.Index' (duration: 455.486109ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T09:35:52.491348Z","caller":"traceutil/trace.go:171","msg":"trace[934049583] transaction","detail":"{read_only:false; response_revision:207; number_of_response:1; }","duration":"866.936146ms","start":"2026-04-07T09:35:51.624405Z","end":"2026-04-07T09:35:52.491341Z","steps":["trace[934049583] 'process raft request' (duration: 866.744634ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:35:52.491408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:35:51.624388Z","time spent":"867.001297ms","remote":"127.0.0.1:50574","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":525,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T09:35:52.491406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:35:51.745197Z","time spent":"746.201562ms","remote":"127.0.0.1:50556","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-07T09:35:52.491387Z","caller":"traceutil/trace.go:171","msg":"trace[492873875] transaction","detail":"{read_only:false; response_revision:206; number_of_response:1; }","duration":"869.37859ms","start":"2026-04-07T09:35:51.621967Z","end":"2026-04-07T09:35:52.491345Z","steps":["trace[492873875] 'process raft request' (duration: 413.659119ms)","trace[492873875] 'compare' (duration: 455.004234ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T09:35:52.491496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"707.043044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T09:35:52.491568Z","caller":"traceutil/trace.go:171","msg":"trace[1645355293] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:207; }","duration":"707.172195ms","start":"2026-04-07T09:35:51.784378Z","end":"2026-04-07T09:35:52.49155Z","steps":["trace[1645355293] 'agreement among raft nodes before linearized reading' (duration: 707.006284ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:35:52.491615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:35:51.784355Z","time spent":"707.248366ms","remote":"127.0.0.1:50742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-07T09:35:52.491624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:35:51.621946Z","time spent":"869.580652ms","remote":"127.0.0.1:50742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T09:35:52.992422Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011512658308492030,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-07T09:35:53.063141Z","caller":"traceutil/trace.go:171","msg":"trace[1385569665] transaction","detail":"{read_only:false; response_revision:208; number_of_response:1; }","duration":"901.645541ms","start":"2026-04-07T09:35:52.16146Z","end":"2026-04-07T09:35:53.063105Z","steps":["trace[1385569665] 'process raft request' (duration: 884.557475ms)","trace[1385569665] 'compare' (duration: 16.671402ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T09:35:53.063189Z","caller":"traceutil/trace.go:171","msg":"trace[119440216] linearizableReadLoop","detail":"{readStateIndex:217; appliedIndex:213; }","duration":"571.835056ms","start":"2026-04-07T09:35:52.491319Z","end":"2026-04-07T09:35:53.063154Z","steps":["trace[119440216] 'read index received' (duration: 554.713579ms)","trace[119440216] 'applied index is now lower than readState.Index' (duration: 17.119947ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T09:35:53.063391Z","caller":"traceutil/trace.go:171","msg":"trace[644779713] transaction","detail":"{read_only:false; response_revision:209; number_of_response:1; }","duration":"570.612584ms","start":"2026-04-07T09:35:52.492764Z","end":"2026-04-07T09:35:53.063376Z","steps":["trace[644779713] 'process raft request' (duration: 570.21857ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:35:53.06348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:35:52.161436Z","time spent":"901.789023ms","remote":"127.0.0.1:50650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5877,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T09:35:53.063546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:35:52.492751Z","time spent":"570.701705ms","remote":"127.0.0.1:50556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":592,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T09:35:53.063817Z","caller":"traceutil/trace.go:171","msg":"trace[125168181] transaction","detail":"{read_only:false; response_revision:210; number_of_response:1; }","duration":"568.18575ms","start":"2026-04-07T09:35:52.495623Z","end":"2026-04-07T09:35:53.063808Z","steps":["trace[125168181] 'process raft request' (duration: 567.429753ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:35:53.063865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:35:52.495606Z","time spent":"568.236741ms","remote":"127.0.0.1:50742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T09:35:53.064102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"569.280031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T09:35:53.064162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.882612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T09:35:53.064197Z","caller":"traceutil/trace.go:171","msg":"trace[664539544] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:211; }","duration":"219.961813ms","start":"2026-04-07T09:35:52.844226Z","end":"2026-04-07T09:35:53.064188Z","steps":["trace[664539544] 'agreement among raft nodes before linearized reading' (duration: 219.880422ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:35:53.064177Z","caller":"traceutil/trace.go:171","msg":"trace[732484909] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:211; }","duration":"569.391192ms","start":"2026-04-07T09:35:52.494772Z","end":"2026-04-07T09:35:53.064164Z","steps":["trace[732484909] 'agreement among raft nodes before linearized reading' (duration: 569.158399ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:35:53.064269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:35:52.494757Z","time spent":"569.502423ms","remote":"127.0.0.1:50504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-07T09:35:53.064462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.838462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/bootstrap-token-x80gzk\" ","response":"range_response_count:1 size:540"} {"level":"info","ts":"2026-04-07T09:35:53.064492Z","caller":"traceutil/trace.go:171","msg":"trace[182320046] range","detail":"{range_begin:/registry/secrets/kube-system/bootstrap-token-x80gzk; range_end:; response_count:1; response_revision:211; }","duration":"430.928703ms","start":"2026-04-07T09:35:52.633557Z","end":"2026-04-07T09:35:53.064486Z","steps":["trace[182320046] 'agreement among raft nodes before linearized reading' (duration: 430.869242ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:35:53.064514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:35:52.633528Z","time spent":"430.980144ms","remote":"127.0.0.1:50574","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":563,"request content":"key:\"/registry/secrets/kube-system/bootstrap-token-x80gzk\" "} {"level":"warn","ts":"2026-04-07T09:35:53.064699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"569.789986ms","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-07T09:35:53.064725Z","caller":"traceutil/trace.go:171","msg":"trace[1428037083] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:211; }","duration":"569.850876ms","start":"2026-04-07T09:35:52.494869Z","end":"2026-04-07T09:35:53.06472Z","steps":["trace[1428037083] 'agreement among raft nodes before linearized reading' (duration: 569.793345ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:35:53.064745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:35:52.494852Z","time spent":"569.889106ms","remote":"127.0.0.1:50742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-07T09:35:53.064321Z","caller":"traceutil/trace.go:171","msg":"trace[194471550] transaction","detail":"{read_only:false; response_revision:211; number_of_response:1; }","duration":"568.461832ms","start":"2026-04-07T09:35:52.495853Z","end":"2026-04-07T09:35:53.064314Z","steps":["trace[194471550] 'process raft request' (duration: 567.25305ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:35:53.064924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:35:52.495841Z","time spent":"569.006517ms","remote":"127.0.0.1:50556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":753,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T09:35:53.064354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"971.250284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T09:35:53.065117Z","caller":"traceutil/trace.go:171","msg":"trace[1462526086] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:211; }","duration":"972.044673ms","start":"2026-04-07T09:35:52.093053Z","end":"2026-04-07T09:35:53.065098Z","steps":["trace[1462526086] 'agreement among raft nodes before linearized reading' (duration: 971.282005ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:35:53.065202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:35:52.093034Z","time spent":"972.149643ms","remote":"127.0.0.1:50742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-07T09:35:53.401569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.193877ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T09:35:53.401881Z","caller":"traceutil/trace.go:171","msg":"trace[1971553343] transaction","detail":"{read_only:false; response_revision:234; number_of_response:1; }","duration":"238.681404ms","start":"2026-04-07T09:35:53.16318Z","end":"2026-04-07T09:35:53.401861Z","steps":["trace[1971553343] 'process raft request' (duration: 238.619923ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:35:53.401997Z","caller":"traceutil/trace.go:171","msg":"trace[1924171960] transaction","detail":"{read_only:false; response_revision:233; number_of_response:1; }","duration":"239.791014ms","start":"2026-04-07T09:35:53.162171Z","end":"2026-04-07T09:35:53.401962Z","steps":["trace[1924171960] 'process raft request' (duration: 31.094152ms)","trace[1924171960] 'compare' (duration: 208.061775ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T09:35:53.885383Z","caller":"traceutil/trace.go:171","msg":"trace[622386686] transaction","detail":"{read_only:false; response_revision:284; number_of_response:1; }","duration":"154.682636ms","start":"2026-04-07T09:35:53.730679Z","end":"2026-04-07T09:35:53.885361Z","steps":["trace[622386686] 'process raft request' (duration: 151.824578ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:35:56.053626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.195811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T09:35:56.053701Z","caller":"traceutil/trace.go:171","msg":"trace[108150620] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:0; response_revision:326; }","duration":"114.315182ms","start":"2026-04-07T09:35:55.939371Z","end":"2026-04-07T09:35:56.053686Z","steps":["trace[108150620] 'range keys from in-memory index tree' (duration: 114.12508ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:35:56.335768Z","caller":"traceutil/trace.go:171","msg":"trace[499345480] transaction","detail":"{read_only:false; response_revision:328; number_of_response:1; }","duration":"154.356817ms","start":"2026-04-07T09:35:56.181395Z","end":"2026-04-07T09:35:56.335752Z","steps":["trace[499345480] 'process raft request' (duration: 154.253576ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:35:57.337622Z","caller":"traceutil/trace.go:171","msg":"trace[913318517] transaction","detail":"{read_only:false; response_revision:339; number_of_response:1; }","duration":"117.044415ms","start":"2026-04-07T09:35:57.220555Z","end":"2026-04-07T09:35:57.337599Z","steps":["trace[913318517] 'process raft request' (duration: 116.921434ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:35:58.289647Z","caller":"traceutil/trace.go:171","msg":"trace[364931298] linearizableReadLoop","detail":"{readStateIndex:380; appliedIndex:377; }","duration":"109.8694ms","start":"2026-04-07T09:35:58.179747Z","end":"2026-04-07T09:35:58.289617Z","steps":["trace[364931298] 'read index received' (duration: 41.190656ms)","trace[364931298] 'applied index is now lower than readState.Index' (duration: 68.677284ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T09:35:58.289678Z","caller":"traceutil/trace.go:171","msg":"trace[1639247025] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"118.500241ms","start":"2026-04-07T09:35:58.171136Z","end":"2026-04-07T09:35:58.289636Z","steps":["trace[1639247025] 'process raft request' (duration: 90.67023ms)","trace[1639247025] 'compare' (duration: 27.657669ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T09:35:58.290181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.089802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-04-07T09:35:58.290243Z","caller":"traceutil/trace.go:171","msg":"trace[1674850318] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:368; }","duration":"110.513986ms","start":"2026-04-07T09:35:58.179713Z","end":"2026-04-07T09:35:58.290227Z","steps":["trace[1674850318] 'agreement among raft nodes before linearized reading' (duration: 110.075212ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:35:58.290404Z","caller":"traceutil/trace.go:171","msg":"trace[217235523] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"114.744066ms","start":"2026-04-07T09:35:58.175649Z","end":"2026-04-07T09:35:58.290393Z","steps":["trace[217235523] 'process raft request' (duration: 113.919958ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:35:58.878481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.959344ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-07T09:35:58.878705Z","caller":"traceutil/trace.go:171","msg":"trace[581605358] linearizableReadLoop","detail":"{readStateIndex:394; appliedIndex:390; }","duration":"395.358897ms","start":"2026-04-07T09:35:58.483325Z","end":"2026-04-07T09:35:58.878683Z","steps":["trace[581605358] 'read index received' (duration: 16.196902ms)","trace[581605358] 'applied index is now lower than readState.Index' (duration: 379.160965ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T09:35:58.878789Z","caller":"traceutil/trace.go:171","msg":"trace[553474458] transaction","detail":"{read_only:false; response_revision:381; number_of_response:1; }","duration":"438.41555ms","start":"2026-04-07T09:35:58.440364Z","end":"2026-04-07T09:35:58.87878Z","steps":["trace[553474458] 'process raft request' (duration: 438.222939ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:35:58.878851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:35:58.440353Z","time spent":"438.468061ms","remote":"127.0.0.1:50650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3527,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T09:35:58.878991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.649399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T09:35:58.87893Z","caller":"traceutil/trace.go:171","msg":"trace[894111975] transaction","detail":"{read_only:false; response_revision:382; number_of_response:1; }","duration":"434.603205ms","start":"2026-04-07T09:35:58.444307Z","end":"2026-04-07T09:35:58.87891Z","steps":["trace[894111975] 'process raft request' (duration: 434.331862ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:35:58.87907Z","caller":"traceutil/trace.go:171","msg":"trace[1419191309] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:382; }","duration":"395.774781ms","start":"2026-04-07T09:35:58.483283Z","end":"2026-04-07T09:35:58.879058Z","steps":["trace[1419191309] 'agreement among raft nodes before linearized reading' (duration: 395.6659ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:35:58.879007Z","caller":"traceutil/trace.go:171","msg":"trace[1406425006] transaction","detail":"{read_only:false; response_revision:380; number_of_response:1; }","duration":"440.019735ms","start":"2026-04-07T09:35:58.43883Z","end":"2026-04-07T09:35:58.878849Z","steps":["trace[1406425006] 'process raft request' (duration: 125.620147ms)","trace[1406425006] 'compare' (duration: 313.823083ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T09:35:58.879142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:35:58.444287Z","time spent":"434.805796ms","remote":"127.0.0.1:50650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4001,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T09:35:58.879271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:35:58.438803Z","time spent":"440.360159ms","remote":"127.0.0.1:50556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":830,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T09:35:58.879101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:35:58.483264Z","time spent":"395.831061ms","remote":"127.0.0.1:50504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-07T09:35:59.381484Z","caller":"traceutil/trace.go:171","msg":"trace[1179087100] transaction","detail":"{read_only:false; response_revision:383; number_of_response:1; }","duration":"542.515041ms","start":"2026-04-07T09:35:58.838924Z","end":"2026-04-07T09:35:59.381439Z","steps":["trace[1179087100] 'process raft request' (duration: 506.167252ms)","trace[1179087100] 'compare' (duration: 36.111637ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T09:35:59.381529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.856677ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T09:35:59.381886Z","caller":"traceutil/trace.go:171","msg":"trace[1946198965] transaction","detail":"{read_only:false; response_revision:384; number_of_response:1; }","duration":"498.116205ms","start":"2026-04-07T09:35:58.883733Z","end":"2026-04-07T09:35:59.381849Z","steps":["trace[1946198965] 'process raft request' (duration: 497.907663ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:35:59.381923Z","caller":"traceutil/trace.go:171","msg":"trace[1443303311] transaction","detail":"{read_only:false; response_revision:385; number_of_response:1; }","duration":"497.445119ms","start":"2026-04-07T09:35:58.884449Z","end":"2026-04-07T09:35:59.381894Z","steps":["trace[1443303311] 'process raft request' (duration: 497.298618ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:35:59.381936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"796.844125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6961"} {"level":"info","ts":"2026-04-07T09:35:59.381946Z","caller":"traceutil/trace.go:171","msg":"trace[246541208] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:383; }","duration":"538.292861ms","start":"2026-04-07T09:35:58.843638Z","end":"2026-04-07T09:35:59.38193Z","steps":["trace[246541208] 'agreement among raft nodes before linearized reading' (duration: 537.803267ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:35:59.382088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:35:58.884435Z","time spent":"497.57652ms","remote":"127.0.0.1:50556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":830,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T09:35:59.382092Z","caller":"traceutil/trace.go:171","msg":"trace[446932289] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:385; }","duration":"796.981026ms","start":"2026-04-07T09:35:58.58505Z","end":"2026-04-07T09:35:59.382031Z","steps":["trace[446932289] 'agreement among raft nodes before linearized reading' (duration: 796.730044ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:35:59.382184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:35:58.585033Z","time spent":"797.109778ms","remote":"127.0.0.1:50650","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":6984,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"info","ts":"2026-04-07T09:35:59.382209Z","caller":"traceutil/trace.go:171","msg":"trace[2073179632] transaction","detail":"{read_only:false; response_revision:386; number_of_response:1; }","duration":"496.48858ms","start":"2026-04-07T09:35:58.885705Z","end":"2026-04-07T09:35:59.382194Z","steps":["trace[2073179632] 'process raft request' (duration: 496.104316ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:35:59.382308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:35:58.885698Z","time spent":"496.55107ms","remote":"127.0.0.1:50976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3759,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T09:35:59.38237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:35:58.883715Z","time spent":"498.515928ms","remote":"127.0.0.1:50556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T09:35:59.381966Z","caller":"traceutil/trace.go:171","msg":"trace[1748373879] transaction","detail":"{read_only:false; response_revision:387; number_of_response:1; }","duration":"496.138467ms","start":"2026-04-07T09:35:58.885811Z","end":"2026-04-07T09:35:59.38195Z","steps":["trace[1748373879] 'process raft request' (duration: 496.070746ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:35:59.382701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:35:58.885806Z","time spent":"496.847833ms","remote":"127.0.0.1:50650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4001,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T09:35:59.382842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:35:58.838903Z","time spent":"542.677582ms","remote":"127.0.0.1:50742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T09:35:59.381405Z","caller":"traceutil/trace.go:171","msg":"trace[1545259628] linearizableReadLoop","detail":"{readStateIndex:395; appliedIndex:394; }","duration":"502.631427ms","start":"2026-04-07T09:35:58.878748Z","end":"2026-04-07T09:35:59.38138Z","steps":["trace[1545259628] 'read index received' (duration: 466.44028ms)","trace[1545259628] 'applied index is now lower than readState.Index' (duration: 36.189857ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T09:35:59.383302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.168152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T09:35:59.38336Z","caller":"traceutil/trace.go:171","msg":"trace[1281321203] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:387; }","duration":"503.253262ms","start":"2026-04-07T09:35:58.880089Z","end":"2026-04-07T09:35:59.383342Z","steps":["trace[1281321203] 'agreement among raft nodes before linearized reading' (duration: 503.169942ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:35:59.383407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:35:58.880074Z","time spent":"503.322783ms","remote":"127.0.0.1:50510","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-07T09:35:59.383559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.959033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-04-07T09:35:59.383614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.106764ms","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-07T09:35:59.383668Z","caller":"traceutil/trace.go:171","msg":"trace[1577888082] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:387; }","duration":"169.205705ms","start":"2026-04-07T09:35:59.214449Z","end":"2026-04-07T09:35:59.383655Z","steps":["trace[1577888082] 'agreement among raft nodes before linearized reading' (duration: 169.116575ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:35:59.383613Z","caller":"traceutil/trace.go:171","msg":"trace[1906514406] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:387; }","duration":"169.061674ms","start":"2026-04-07T09:35:59.214539Z","end":"2026-04-07T09:35:59.3836Z","steps":["trace[1906514406] 'agreement among raft nodes before linearized reading' (duration: 168.932493ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:35:59.383886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.898182ms","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-07T09:35:59.383938Z","caller":"traceutil/trace.go:171","msg":"trace[169326367] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:387; }","duration":"227.988063ms","start":"2026-04-07T09:35:59.155938Z","end":"2026-04-07T09:35:59.383926Z","steps":["trace[169326367] 'agreement among raft nodes before linearized reading' (duration: 227.902122ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:35:59.384118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.353957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-07T09:35:59.384163Z","caller":"traceutil/trace.go:171","msg":"trace[387383380] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:387; }","duration":"169.419797ms","start":"2026-04-07T09:35:59.214732Z","end":"2026-04-07T09:35:59.384151Z","steps":["trace[387383380] 'agreement among raft nodes before linearized reading' (duration: 169.293476ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:35:59.549755Z","caller":"traceutil/trace.go:171","msg":"trace[337733392] transaction","detail":"{read_only:false; response_revision:389; number_of_response:1; }","duration":"161.10775ms","start":"2026-04-07T09:35:59.38861Z","end":"2026-04-07T09:35:59.549717Z","steps":["trace[337733392] 'process raft request' (duration: 121.503662ms)","trace[337733392] 'compare' (duration: 39.391686ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T09:35:59.54979Z","caller":"traceutil/trace.go:171","msg":"trace[1879608389] linearizableReadLoop","detail":"{readStateIndex:403; appliedIndex:400; }","duration":"158.524136ms","start":"2026-04-07T09:35:59.391243Z","end":"2026-04-07T09:35:59.549767Z","steps":["trace[1879608389] 'read index received' (duration: 40.893111ms)","trace[1879608389] 'applied index is now lower than readState.Index' (duration: 117.629985ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T09:35:59.549945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.673787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/coredns-7c96b6546b\" ","response":"range_response_count:1 size:3774"} {"level":"info","ts":"2026-04-07T09:35:59.550164Z","caller":"traceutil/trace.go:171","msg":"trace[2053715372] transaction","detail":"{read_only:false; response_revision:391; number_of_response:1; }","duration":"161.240792ms","start":"2026-04-07T09:35:59.388713Z","end":"2026-04-07T09:35:59.549954Z","steps":["trace[2053715372] 'process raft request' (duration: 160.963979ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:35:59.55021Z","caller":"traceutil/trace.go:171","msg":"trace[608789472] range","detail":"{range_begin:/registry/replicasets/kube-system/coredns-7c96b6546b; range_end:; response_count:1; response_revision:392; }","duration":"158.98583ms","start":"2026-04-07T09:35:59.391206Z","end":"2026-04-07T09:35:59.550192Z","steps":["trace[608789472] 'agreement among raft nodes before linearized reading' (duration: 158.662877ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:35:59.550208Z","caller":"traceutil/trace.go:171","msg":"trace[1956186836] transaction","detail":"{read_only:false; response_revision:390; number_of_response:1; }","duration":"161.495064ms","start":"2026-04-07T09:35:59.388688Z","end":"2026-04-07T09:35:59.550183Z","steps":["trace[1956186836] 'process raft request' (duration: 160.944079ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:35:59.550275Z","caller":"traceutil/trace.go:171","msg":"trace[67862693] transaction","detail":"{read_only:false; response_revision:392; number_of_response:1; }","duration":"158.334754ms","start":"2026-04-07T09:35:59.391903Z","end":"2026-04-07T09:35:59.550238Z","steps":["trace[67862693] 'process raft request' (duration: 157.811519ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:35:59.693745Z","caller":"traceutil/trace.go:171","msg":"trace[79730544] transaction","detail":"{read_only:false; response_revision:394; number_of_response:1; }","duration":"131.881128ms","start":"2026-04-07T09:35:59.561835Z","end":"2026-04-07T09:35:59.693716Z","steps":["trace[79730544] 'process raft request' (duration: 98.906671ms)","trace[79730544] 'compare' (duration: 32.790346ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T09:36:07.22728Z","caller":"traceutil/trace.go:171","msg":"trace[2085544600] transaction","detail":"{read_only:false; response_revision:423; number_of_response:1; }","duration":"174.531046ms","start":"2026-04-07T09:36:07.052725Z","end":"2026-04-07T09:36:07.227256Z","steps":["trace[2085544600] 'process raft request' (duration: 174.356675ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:36:07.434263Z","caller":"traceutil/trace.go:171","msg":"trace[43770203] transaction","detail":"{read_only:false; response_revision:428; number_of_response:1; }","duration":"102.998533ms","start":"2026-04-07T09:36:07.33125Z","end":"2026-04-07T09:36:07.434248Z","steps":["trace[43770203] 'process raft request' (duration: 102.819721ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:36:07.434329Z","caller":"traceutil/trace.go:171","msg":"trace[644761872] transaction","detail":"{read_only:false; response_revision:429; number_of_response:1; }","duration":"102.890041ms","start":"2026-04-07T09:36:07.331411Z","end":"2026-04-07T09:36:07.434301Z","steps":["trace[644761872] 'process raft request' (duration: 102.76088ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:36:07.434482Z","caller":"traceutil/trace.go:171","msg":"trace[1311010308] transaction","detail":"{read_only:false; response_revision:430; number_of_response:1; }","duration":"102.115965ms","start":"2026-04-07T09:36:07.332361Z","end":"2026-04-07T09:36:07.434477Z","steps":["trace[1311010308] 'process raft request' (duration: 101.863633ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:36:07.434359Z","caller":"traceutil/trace.go:171","msg":"trace[55521258] transaction","detail":"{read_only:false; response_revision:431; number_of_response:1; }","duration":"100.500911ms","start":"2026-04-07T09:36:07.333843Z","end":"2026-04-07T09:36:07.434344Z","steps":["trace[55521258] 'process raft request' (duration: 100.44359ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:36:19.04739Z","caller":"traceutil/trace.go:171","msg":"trace[8808440] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"104.114413ms","start":"2026-04-07T09:36:18.943257Z","end":"2026-04-07T09:36:19.047372Z","steps":["trace[8808440] 'process raft request' (duration: 104.087842ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:36:19.047441Z","caller":"traceutil/trace.go:171","msg":"trace[870550859] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"104.266124ms","start":"2026-04-07T09:36:18.943131Z","end":"2026-04-07T09:36:19.047397Z","steps":["trace[870550859] 'process raft request' (duration: 104.152443ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:36:19.04743Z","caller":"traceutil/trace.go:171","msg":"trace[1014486975] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"104.388696ms","start":"2026-04-07T09:36:18.942997Z","end":"2026-04-07T09:36:19.047386Z","steps":["trace[1014486975] 'process raft request' (duration: 80.877939ms)","trace[1014486975] 'compare' (duration: 23.112753ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T09:36:20.021491Z","caller":"traceutil/trace.go:171","msg":"trace[305337642] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"164.096852ms","start":"2026-04-07T09:36:19.85737Z","end":"2026-04-07T09:36:20.021466Z","steps":["trace[305337642] 'process raft request' (duration: 163.951ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:36:22.512212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"692.943227ms","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-07T09:36:22.512344Z","caller":"traceutil/trace.go:171","msg":"trace[1174788967] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:590; }","duration":"693.106017ms","start":"2026-04-07T09:36:21.819212Z","end":"2026-04-07T09:36:22.512318Z","steps":["trace[1174788967] 'range keys from in-memory index tree' (duration: 692.85045ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:36:22.512405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:36:21.819181Z","time spent":"693.209804ms","remote":"127.0.0.1:50742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-07T09:36:22.512461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"761.974516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:29570"} {"level":"info","ts":"2026-04-07T09:36:22.512535Z","caller":"traceutil/trace.go:171","msg":"trace[1369202845] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:590; }","duration":"762.084463ms","start":"2026-04-07T09:36:21.75043Z","end":"2026-04-07T09:36:22.512514Z","steps":["trace[1369202845] 'range keys from in-memory index tree' (duration: 761.794744ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:36:22.512578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:36:21.750417Z","time spent":"762.153087ms","remote":"127.0.0.1:50536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":103,"response count":1,"response size":29593,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io\" "} {"level":"warn","ts":"2026-04-07T09:36:22.512619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"713.369833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-07T09:36:22.512718Z","caller":"traceutil/trace.go:171","msg":"trace[139679800] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:590; }","duration":"713.518943ms","start":"2026-04-07T09:36:21.799181Z","end":"2026-04-07T09:36:22.5127Z","steps":["trace[139679800] 'range keys from in-memory index tree' (duration: 713.216553ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:36:22.512453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"700.284787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/envoyproxies.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:570096"} {"level":"info","ts":"2026-04-07T09:36:22.512986Z","caller":"traceutil/trace.go:171","msg":"trace[695414682] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/envoyproxies.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:590; }","duration":"700.860844ms","start":"2026-04-07T09:36:21.812071Z","end":"2026-04-07T09:36:22.512932Z","steps":["trace[695414682] 'range keys from in-memory index tree' (duration: 699.953085ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:36:22.512829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"695.580138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-07T09:36:22.513046Z","caller":"traceutil/trace.go:171","msg":"trace[1353134665] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:590; }","duration":"695.905229ms","start":"2026-04-07T09:36:21.817128Z","end":"2026-04-07T09:36:22.513033Z","steps":["trace[1353134665] 'range keys from in-memory index tree' (duration: 695.491963ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:36:22.513048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:36:21.812062Z","time spent":"700.966851ms","remote":"127.0.0.1:50536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":93,"response count":1,"response size":570119,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/envoyproxies.gateway.envoyproxy.io\" "} {"level":"warn","ts":"2026-04-07T09:36:22.513079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:36:21.817116Z","time spent":"695.952733ms","remote":"127.0.0.1:50742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-07T09:36:22.513629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:36:21.79916Z","time spent":"714.426292ms","remote":"127.0.0.1:50742","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-07T09:36:22.525923Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011512658308492729,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-07T09:36:22.621022Z","caller":"traceutil/trace.go:171","msg":"trace[1345105250] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"777.637643ms","start":"2026-04-07T09:36:21.843347Z","end":"2026-04-07T09:36:22.620984Z","steps":["trace[1345105250] 'process raft request' (duration: 776.721634ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:36:22.621258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:36:21.843324Z","time spent":"777.764511ms","remote":"127.0.0.1:50536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570090,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T09:36:22.67791Z","caller":"traceutil/trace.go:171","msg":"trace[358132336] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"160.705661ms","start":"2026-04-07T09:36:22.517187Z","end":"2026-04-07T09:36:22.677893Z","steps":["trace[358132336] 'process raft request' (duration: 160.551931ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:36:22.678291Z","caller":"traceutil/trace.go:171","msg":"trace[139071716] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"160.043159ms","start":"2026-04-07T09:36:22.518242Z","end":"2026-04-07T09:36:22.678285Z","steps":["trace[139071716] 'process raft request' (duration: 159.60244ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:36:22.67846Z","caller":"traceutil/trace.go:171","msg":"trace[667089198] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"160.138975ms","start":"2026-04-07T09:36:22.518316Z","end":"2026-04-07T09:36:22.678455Z","steps":["trace[667089198] 'process raft request' (duration: 159.547797ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:36:22.678631Z","caller":"traceutil/trace.go:171","msg":"trace[773606500] linearizableReadLoop","detail":"{readStateIndex:614; appliedIndex:610; }","duration":"653.25205ms","start":"2026-04-07T09:36:22.025372Z","end":"2026-04-07T09:36:22.678624Z","steps":["trace[773606500] 'read index received' (duration: 592.536754ms)","trace[773606500] 'applied index is now lower than readState.Index' (duration: 60.714536ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T09:36:22.678949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"653.575861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-07T09:36:22.678984Z","caller":"traceutil/trace.go:171","msg":"trace[287564546] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:595; }","duration":"653.653955ms","start":"2026-04-07T09:36:22.02532Z","end":"2026-04-07T09:36:22.678974Z","steps":["trace[287564546] 'agreement among raft nodes before linearized reading' (duration: 653.556219ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:36:22.679011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:36:22.0253Z","time spent":"653.704299ms","remote":"127.0.0.1:50742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-07T09:36:22.858609Z","caller":"traceutil/trace.go:171","msg":"trace[1381382646] linearizableReadLoop","detail":"{readStateIndex:616; appliedIndex:615; }","duration":"179.938765ms","start":"2026-04-07T09:36:22.678649Z","end":"2026-04-07T09:36:22.858588Z","steps":["trace[1381382646] 'read index received' (duration: 112.056896ms)","trace[1381382646] 'applied index is now lower than readState.Index' (duration: 67.880639ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T09:36:22.858614Z","caller":"traceutil/trace.go:171","msg":"trace[2039140060] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"181.070018ms","start":"2026-04-07T09:36:22.677468Z","end":"2026-04-07T09:36:22.858538Z","steps":["trace[2039140060] 'process raft request' (duration: 113.165077ms)","trace[2039140060] 'compare' (duration: 67.73353ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T09:36:22.858889Z","caller":"traceutil/trace.go:171","msg":"trace[211992052] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"175.132834ms","start":"2026-04-07T09:36:22.683723Z","end":"2026-04-07T09:36:22.858856Z","steps":["trace[211992052] 'process raft request' (duration: 174.801773ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:36:22.859104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.246853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T09:36:22.859124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.297175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/gatewayclasses.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:10870"} {"level":"info","ts":"2026-04-07T09:36:22.859281Z","caller":"traceutil/trace.go:171","msg":"trace[1455171914] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/gatewayclasses.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:597; }","duration":"336.495118ms","start":"2026-04-07T09:36:22.522764Z","end":"2026-04-07T09:36:22.859259Z","steps":["trace[1455171914] 'agreement among raft nodes before linearized reading' (duration: 335.857026ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:36:22.859333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:36:22.522752Z","time spent":"336.568673ms","remote":"127.0.0.1:50536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":1,"response size":10893,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/gatewayclasses.gateway.networking.k8s.io\" "} {"level":"info","ts":"2026-04-07T09:36:22.859195Z","caller":"traceutil/trace.go:171","msg":"trace[1637787222] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:597; }","duration":"375.416055ms","start":"2026-04-07T09:36:22.483755Z","end":"2026-04-07T09:36:22.859171Z","steps":["trace[1637787222] 'agreement among raft nodes before linearized reading' (duration: 374.929143ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:36:22.859516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:36:22.483726Z","time spent":"375.770808ms","remote":"127.0.0.1:50504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-07T09:36:23.109818Z","caller":"traceutil/trace.go:171","msg":"trace[2127552186] linearizableReadLoop","detail":"{readStateIndex:618; appliedIndex:617; }","duration":"148.646268ms","start":"2026-04-07T09:36:22.961142Z","end":"2026-04-07T09:36:23.109788Z","steps":["trace[2127552186] 'read index received' (duration: 108.573049ms)","trace[2127552186] 'applied index is now lower than readState.Index' (duration: 40.071978ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T09:36:23.110062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.898714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/referencegrants.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:7708"} {"level":"info","ts":"2026-04-07T09:36:23.110102Z","caller":"traceutil/trace.go:171","msg":"trace[1663052150] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/referencegrants.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:598; }","duration":"148.982869ms","start":"2026-04-07T09:36:22.961109Z","end":"2026-04-07T09:36:23.110092Z","steps":["trace[1663052150] 'agreement among raft nodes before linearized reading' (duration: 148.786026ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:36:23.110239Z","caller":"traceutil/trace.go:171","msg":"trace[150084981] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"190.539637ms","start":"2026-04-07T09:36:22.919686Z","end":"2026-04-07T09:36:23.110226Z","steps":["trace[150084981] 'process raft request' (duration: 150.121125ms)","trace[150084981] 'compare' (duration: 39.717807ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T09:36:28.348548Z","caller":"traceutil/trace.go:171","msg":"trace[211863153] linearizableReadLoop","detail":"{readStateIndex:643; appliedIndex:642; }","duration":"484.682639ms","start":"2026-04-07T09:36:27.863839Z","end":"2026-04-07T09:36:28.348521Z","steps":["trace[211863153] 'read index received' (duration: 483.808716ms)","trace[211863153] 'applied index is now lower than readState.Index' (duration: 872.873µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T09:36:28.348722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.850769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T09:36:28.34877Z","caller":"traceutil/trace.go:171","msg":"trace[939581594] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:622; }","duration":"484.946485ms","start":"2026-04-07T09:36:27.863811Z","end":"2026-04-07T09:36:28.348757Z","steps":["trace[939581594] 'agreement among raft nodes before linearized reading' (duration: 484.842669ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:36:28.348766Z","caller":"traceutil/trace.go:171","msg":"trace[1410936] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"502.204654ms","start":"2026-04-07T09:36:27.846436Z","end":"2026-04-07T09:36:28.348641Z","steps":["trace[1410936] 'process raft request' (duration: 501.47726ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:36:28.348778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.687099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T09:36:28.348891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.781959ms","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":"warn","ts":"2026-04-07T09:36:28.348908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:36:27.84642Z","time spent":"502.409116ms","remote":"127.0.0.1:50574","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T09:36:28.348929Z","caller":"traceutil/trace.go:171","msg":"trace[1279338404] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:622; }","duration":"312.868065ms","start":"2026-04-07T09:36:28.036049Z","end":"2026-04-07T09:36:28.348917Z","steps":["trace[1279338404] 'agreement among raft nodes before linearized reading' (duration: 312.811181ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:36:28.348875Z","caller":"traceutil/trace.go:171","msg":"trace[1015090589] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:622; }","duration":"484.814447ms","start":"2026-04-07T09:36:27.864039Z","end":"2026-04-07T09:36:28.348853Z","steps":["trace[1015090589] 'agreement among raft nodes before linearized reading' (duration: 484.658357ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:36:28.349012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:36:27.864022Z","time spent":"484.973786ms","remote":"127.0.0.1:55366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true "} {"level":"warn","ts":"2026-04-07T09:36:28.349401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:36:28.036028Z","time spent":"312.928288ms","remote":"127.0.0.1:55410","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-07T09:36:28.349417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.82838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T09:36:28.349473Z","caller":"traceutil/trace.go:171","msg":"trace[330015519] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:622; }","duration":"450.910784ms","start":"2026-04-07T09:36:27.898546Z","end":"2026-04-07T09:36:28.349456Z","steps":["trace[330015519] 'agreement among raft nodes before linearized reading' (duration: 450.809688ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:36:28.349454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.862184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T09:36:28.349517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:36:27.898535Z","time spent":"450.968308ms","remote":"127.0.0.1:55386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:10000 "} {"level":"info","ts":"2026-04-07T09:36:28.349539Z","caller":"traceutil/trace.go:171","msg":"trace[34741561] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:622; }","duration":"465.948979ms","start":"2026-04-07T09:36:27.883573Z","end":"2026-04-07T09:36:28.349522Z","steps":["trace[34741561] 'agreement among raft nodes before linearized reading' (duration: 465.79394ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:36:28.349615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:36:27.883558Z","time spent":"466.039755ms","remote":"127.0.0.1:55370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":130,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-07T09:36:28.349697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.367879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T09:36:28.349744Z","caller":"traceutil/trace.go:171","msg":"trace[1629261474] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:622; }","duration":"108.443534ms","start":"2026-04-07T09:36:28.241288Z","end":"2026-04-07T09:36:28.349732Z","steps":["trace[1629261474] 'agreement among raft nodes before linearized reading' (duration: 108.360759ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:36:28.349759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.769388ms","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-07T09:36:28.349804Z","caller":"traceutil/trace.go:171","msg":"trace[436192370] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:622; }","duration":"313.837493ms","start":"2026-04-07T09:36:28.035954Z","end":"2026-04-07T09:36:28.349791Z","steps":["trace[436192370] 'agreement among raft nodes before linearized reading' (duration: 313.733777ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:36:28.349847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:36:28.035939Z","time spent":"313.895726ms","remote":"127.0.0.1:55410","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:10000 "} {"level":"warn","ts":"2026-04-07T09:36:28.34999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.698379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T09:36:28.350046Z","caller":"traceutil/trace.go:171","msg":"trace[137248209] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:622; }","duration":"108.757803ms","start":"2026-04-07T09:36:28.241277Z","end":"2026-04-07T09:36:28.350035Z","steps":["trace[137248209] 'agreement among raft nodes before linearized reading' (duration: 108.621975ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:36:28.350052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.390241ms","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":"warn","ts":"2026-04-07T09:36:28.349948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.483122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T09:36:28.350094Z","caller":"traceutil/trace.go:171","msg":"trace[1785643080] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:622; }","duration":"435.451445ms","start":"2026-04-07T09:36:27.91463Z","end":"2026-04-07T09:36:28.350081Z","steps":["trace[1785643080] 'agreement among raft nodes before linearized reading' (duration: 435.384611ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:36:28.35014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:36:27.914621Z","time spent":"435.505267ms","remote":"127.0.0.1:55400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:500 "} {"level":"info","ts":"2026-04-07T09:36:28.350127Z","caller":"traceutil/trace.go:171","msg":"trace[233492151] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:622; }","duration":"466.693654ms","start":"2026-04-07T09:36:27.883417Z","end":"2026-04-07T09:36:28.350111Z","steps":["trace[233492151] 'agreement among raft nodes before linearized reading' (duration: 466.488142ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:36:28.350202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.38575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T09:36:28.350232Z","caller":"traceutil/trace.go:171","msg":"trace[1048625700] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:622; }","duration":"231.445713ms","start":"2026-04-07T09:36:28.118778Z","end":"2026-04-07T09:36:28.350224Z","steps":["trace[1048625700] 'agreement among raft nodes before linearized reading' (duration: 231.38466ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:36:28.350225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:36:27.883403Z","time spent":"466.800921ms","remote":"127.0.0.1:55370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":131,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:500 "} {"level":"warn","ts":"2026-04-07T09:36:28.35033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.744722ms","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-07T09:36:28.350376Z","caller":"traceutil/trace.go:171","msg":"trace[1049506579] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:622; }","duration":"435.803535ms","start":"2026-04-07T09:36:27.914557Z","end":"2026-04-07T09:36:28.35036Z","steps":["trace[1049506579] 'agreement among raft nodes before linearized reading' (duration: 435.735251ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:36:28.350419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:36:27.914546Z","time spent":"435.859569ms","remote":"127.0.0.1:55400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"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-07T09:36:28.350444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.822956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T09:36:28.350482Z","caller":"traceutil/trace.go:171","msg":"trace[1901217735] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:622; }","duration":"231.8853ms","start":"2026-04-07T09:36:28.118585Z","end":"2026-04-07T09:36:28.350471Z","steps":["trace[1901217735] 'agreement among raft nodes before linearized reading' (duration: 231.820216ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:36:28.350507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.108019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T09:36:28.350583Z","caller":"traceutil/trace.go:171","msg":"trace[580427665] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:622; }","duration":"467.187594ms","start":"2026-04-07T09:36:27.883382Z","end":"2026-04-07T09:36:28.35057Z","steps":["trace[580427665] 'agreement among raft nodes before linearized reading' (duration: 467.084828ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:36:28.350596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.013068ms","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":"info","ts":"2026-04-07T09:36:28.350637Z","caller":"traceutil/trace.go:171","msg":"trace[133554963] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:622; }","duration":"436.082192ms","start":"2026-04-07T09:36:27.914544Z","end":"2026-04-07T09:36:28.350626Z","steps":["trace[133554963] 'agreement among raft nodes before linearized reading' (duration: 436.017658ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:36:28.35064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.05272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T09:36:28.350678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:36:27.914526Z","time spent":"436.138186ms","remote":"127.0.0.1:55400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:10000 "} {"level":"info","ts":"2026-04-07T09:36:28.350695Z","caller":"traceutil/trace.go:171","msg":"trace[1281826186] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:622; }","duration":"232.115634ms","start":"2026-04-07T09:36:28.118565Z","end":"2026-04-07T09:36:28.350681Z","steps":["trace[1281826186] 'agreement among raft nodes before linearized reading' (duration: 232.034449ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:36:28.350726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.031529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T09:36:28.350759Z","caller":"traceutil/trace.go:171","msg":"trace[200562078] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:622; }","duration":"486.143156ms","start":"2026-04-07T09:36:27.864605Z","end":"2026-04-07T09:36:28.350748Z","steps":["trace[200562078] 'agreement among raft nodes before linearized reading' (duration: 486.071111ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:36:28.350818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:36:27.864595Z","time spent":"486.186639ms","remote":"127.0.0.1:55366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":89,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:500 "} {"level":"warn","ts":"2026-04-07T09:36:28.350826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.214953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T09:36:28.350899Z","caller":"traceutil/trace.go:171","msg":"trace[1859865141] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:622; }","duration":"452.304088ms","start":"2026-04-07T09:36:27.898579Z","end":"2026-04-07T09:36:28.350883Z","steps":["trace[1859865141] 'agreement among raft nodes before linearized reading' (duration: 452.194211ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:36:28.350957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:36:27.898575Z","time spent":"452.363562ms","remote":"127.0.0.1:55386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-07T09:36:28.351153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.557243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T09:36:28.348817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:36:27.863795Z","time spent":"485.009369ms","remote":"127.0.0.1:55366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":89,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:10000 "} {"level":"info","ts":"2026-04-07T09:36:28.351209Z","caller":"traceutil/trace.go:171","msg":"trace[191524645] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:622; }","duration":"452.630628ms","start":"2026-04-07T09:36:27.898565Z","end":"2026-04-07T09:36:28.351196Z","steps":["trace[191524645] 'agreement among raft nodes before linearized reading' (duration: 452.547533ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:36:28.351267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:36:27.898558Z","time spent":"452.693252ms","remote":"127.0.0.1:55386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:500 "} {"level":"warn","ts":"2026-04-07T09:36:28.352216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.020679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T09:36:28.353272Z","caller":"traceutil/trace.go:171","msg":"trace[261386871] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:622; }","duration":"255.093633ms","start":"2026-04-07T09:36:28.098158Z","end":"2026-04-07T09:36:28.353252Z","steps":["trace[261386871] 'agreement among raft nodes before linearized reading' (duration: 254.019449ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:36:28.350653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:36:27.883362Z","time spent":"467.278209ms","remote":"127.0.0.1:55370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":131,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-04-07T09:36:28.352564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.932304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T09:36:28.353567Z","caller":"traceutil/trace.go:171","msg":"trace[264297063] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:622; }","duration":"255.953435ms","start":"2026-04-07T09:36:28.097604Z","end":"2026-04-07T09:36:28.353558Z","steps":["trace[264297063] 'agreement among raft nodes before linearized reading' (duration: 254.903932ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:36:28.352611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.013159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T09:36:28.353726Z","caller":"traceutil/trace.go:171","msg":"trace[1338205800] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:622; }","duration":"256.148417ms","start":"2026-04-07T09:36:28.097567Z","end":"2026-04-07T09:36:28.353715Z","steps":["trace[1338205800] 'agreement among raft nodes before linearized reading' (duration: 255.022309ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:36:28.352647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.67625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T09:36:28.354103Z","caller":"traceutil/trace.go:171","msg":"trace[1799668024] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:622; }","duration":"281.168191ms","start":"2026-04-07T09:36:28.072922Z","end":"2026-04-07T09:36:28.35409Z","steps":["trace[1799668024] 'agreement among raft nodes before linearized reading' (duration: 279.703563ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:36:28.352682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.788937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T09:36:28.354661Z","caller":"traceutil/trace.go:171","msg":"trace[2057574032] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:622; }","duration":"281.790047ms","start":"2026-04-07T09:36:28.072864Z","end":"2026-04-07T09:36:28.354654Z","steps":["trace[2057574032] 'agreement among raft nodes before linearized reading' (duration: 279.797108ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:36:28.352712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.877755ms","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":"info","ts":"2026-04-07T09:36:28.354782Z","caller":"traceutil/trace.go:171","msg":"trace[1719939892] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:622; }","duration":"317.94792ms","start":"2026-04-07T09:36:28.036825Z","end":"2026-04-07T09:36:28.354773Z","steps":["trace[1719939892] 'agreement among raft nodes before linearized reading' (duration: 315.869745ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:36:28.354852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:36:28.036812Z","time spent":"318.019834ms","remote":"127.0.0.1:55410","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:500 "} {"level":"warn","ts":"2026-04-07T09:36:30.711366Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011512658308492880,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T09:36:31.212377Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011512658308492880,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T09:36:31.437481Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.491691265s","expected-duration":"1s"} {"level":"info","ts":"2026-04-07T09:36:31.437959Z","caller":"traceutil/trace.go:171","msg":"trace[1971843072] linearizableReadLoop","detail":"{readStateIndex:660; appliedIndex:659; }","duration":"1.227168471s","start":"2026-04-07T09:36:30.210763Z","end":"2026-04-07T09:36:31.437931Z","steps":["trace[1971843072] 'read index received' (duration: 1.226922387s)","trace[1971843072] 'applied index is now lower than readState.Index' (duration: 244.814µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T09:36:31.438152Z","caller":"traceutil/trace.go:171","msg":"trace[67011497] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"1.492377525s","start":"2026-04-07T09:36:29.945659Z","end":"2026-04-07T09:36:31.438036Z","steps":["trace[67011497] 'process raft request' (duration: 1.492085838s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:36:31.438274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.227479149s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.22\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-07T09:36:31.438318Z","caller":"traceutil/trace.go:171","msg":"trace[1546637578] range","detail":"{range_begin:/registry/masterleases/199.204.45.22; range_end:; response_count:1; response_revision:639; }","duration":"1.227569835s","start":"2026-04-07T09:36:30.210733Z","end":"2026-04-07T09:36:31.438303Z","steps":["trace[1546637578] 'agreement among raft nodes before linearized reading' (duration: 1.227362223s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:36:31.438367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:36:30.210717Z","time spent":"1.227633049s","remote":"127.0.0.1:50528","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.22\" "} {"level":"warn","ts":"2026-04-07T09:36:31.438458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"662.548475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-07T09:36:31.438481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"664.832978ms","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-07T09:36:31.438454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.23016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-07T09:36:31.438524Z","caller":"traceutil/trace.go:171","msg":"trace[930354745] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:639; }","duration":"662.654521ms","start":"2026-04-07T09:36:30.775852Z","end":"2026-04-07T09:36:31.438507Z","steps":["trace[930354745] 'agreement among raft nodes before linearized reading' (duration: 662.441868ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:36:31.43853Z","caller":"traceutil/trace.go:171","msg":"trace[1455085601] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:639; }","duration":"664.928694ms","start":"2026-04-07T09:36:30.773583Z","end":"2026-04-07T09:36:31.438512Z","steps":["trace[1455085601] 'agreement among raft nodes before linearized reading' (duration: 664.722872ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:36:31.438512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"662.451699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-07T09:36:31.438563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:36:30.773558Z","time spent":"664.997669ms","remote":"127.0.0.1:50742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-07T09:36:31.43865Z","caller":"traceutil/trace.go:171","msg":"trace[474884494] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:639; }","duration":"662.623489ms","start":"2026-04-07T09:36:30.775992Z","end":"2026-04-07T09:36:31.438615Z","steps":["trace[474884494] 'agreement among raft nodes before linearized reading' (duration: 662.255838ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:36:31.438714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:36:30.775974Z","time spent":"662.725185ms","remote":"127.0.0.1:50742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-07T09:36:31.438557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:36:30.775839Z","time spent":"662.711864ms","remote":"127.0.0.1:50742","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-07T09:36:31.439043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"958.376861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T09:36:31.439173Z","caller":"traceutil/trace.go:171","msg":"trace[1836365481] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:639; }","duration":"534.380179ms","start":"2026-04-07T09:36:30.904146Z","end":"2026-04-07T09:36:31.438526Z","steps":["trace[1836365481] 'agreement among raft nodes before linearized reading' (duration: 534.113813ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:36:31.43911Z","caller":"traceutil/trace.go:171","msg":"trace[1011095016] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:639; }","duration":"958.493157ms","start":"2026-04-07T09:36:30.480591Z","end":"2026-04-07T09:36:31.439085Z","steps":["trace[1011095016] 'agreement among raft nodes before linearized reading' (duration: 957.622467ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:36:31.439236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:36:30.904126Z","time spent":"535.096851ms","remote":"127.0.0.1:50742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-07T09:36:31.439339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:36:30.480576Z","time spent":"958.692309ms","remote":"127.0.0.1:50504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-07T09:36:31.438987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:36:29.945634Z","time spent":"1.492603578s","remote":"127.0.0.1:50742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T09:36:31.759759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.421479ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-04-07T09:36:31.759927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:36:31.4411Z","time spent":"318.824101ms","remote":"127.0.0.1:50528","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-07T09:36:31.76018Z","caller":"traceutil/trace.go:171","msg":"trace[969626180] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"316.961563ms","start":"2026-04-07T09:36:31.443203Z","end":"2026-04-07T09:36:31.760165Z","steps":["trace[969626180] 'process raft request' (duration: 316.888208ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:36:31.760214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.410196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T09:36:31.760251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:36:31.443197Z","time spent":"317.010125ms","remote":"127.0.0.1:50742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T09:36:31.760213Z","caller":"traceutil/trace.go:171","msg":"trace[2032124021] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"316.178288ms","start":"2026-04-07T09:36:31.444001Z","end":"2026-04-07T09:36:31.760179Z","steps":["trace[2032124021] 'process raft request' (duration: 316.118144ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:36:31.760271Z","caller":"traceutil/trace.go:171","msg":"trace[812264704] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"315.35912ms","start":"2026-04-07T09:36:31.444892Z","end":"2026-04-07T09:36:31.760251Z","steps":["trace[812264704] 'process raft request' (duration: 315.248843ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:36:31.760293Z","caller":"traceutil/trace.go:171","msg":"trace[2014945919] linearizableReadLoop","detail":"{readStateIndex:665; appliedIndex:660; }","duration":"275.366213ms","start":"2026-04-07T09:36:31.484799Z","end":"2026-04-07T09:36:31.760165Z","steps":["trace[2014945919] 'read index received' (duration: 131.52402ms)","trace[2014945919] 'applied index is now lower than readState.Index' (duration: 143.841533ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T09:36:31.760344Z","caller":"traceutil/trace.go:171","msg":"trace[1685539382] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"317.302433ms","start":"2026-04-07T09:36:31.443033Z","end":"2026-04-07T09:36:31.760336Z","steps":["trace[1685539382] 'process raft request' (duration: 316.817965ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:36:31.760358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:36:31.443981Z","time spent":"316.312015ms","remote":"127.0.0.1:50742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T09:36:31.760377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:36:31.444887Z","time spent":"315.434185ms","remote":"127.0.0.1:50742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T09:36:31.760239Z","caller":"traceutil/trace.go:171","msg":"trace[152322821] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:643; }","duration":"275.46966ms","start":"2026-04-07T09:36:31.484761Z","end":"2026-04-07T09:36:31.760231Z","steps":["trace[152322821] 'agreement among raft nodes before linearized reading' (duration: 275.406506ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:36:31.760434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:36:31.443014Z","time spent":"317.347675ms","remote":"127.0.0.1:50742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T09:36:32.593708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.697157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T09:36:32.593786Z","caller":"traceutil/trace.go:171","msg":"trace[838261160] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:648; }","duration":"110.827404ms","start":"2026-04-07T09:36:32.482941Z","end":"2026-04-07T09:36:32.593769Z","steps":["trace[838261160] 'range keys from in-memory index tree' (duration: 110.591951ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:36:33.678224Z","caller":"traceutil/trace.go:171","msg":"trace[738002019] linearizableReadLoop","detail":"{readStateIndex:694; appliedIndex:693; }","duration":"194.410937ms","start":"2026-04-07T09:36:33.483789Z","end":"2026-04-07T09:36:33.6782Z","steps":["trace[738002019] 'read index received' (duration: 194.124991ms)","trace[738002019] 'applied index is now lower than readState.Index' (duration: 284.986µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T09:36:33.678274Z","caller":"traceutil/trace.go:171","msg":"trace[1695883744] transaction","detail":"{read_only:false; response_revision:672; number_of_response:1; }","duration":"235.061556ms","start":"2026-04-07T09:36:33.443178Z","end":"2026-04-07T09:36:33.67824Z","steps":["trace[1695883744] 'process raft request' (duration: 234.856364ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:36:33.678564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.768197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T09:36:33.678642Z","caller":"traceutil/trace.go:171","msg":"trace[695296791] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:672; }","duration":"194.875563ms","start":"2026-04-07T09:36:33.483736Z","end":"2026-04-07T09:36:33.678612Z","steps":["trace[695296791] 'agreement among raft nodes before linearized reading' (duration: 194.580946ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:36:35.380548Z","caller":"traceutil/trace.go:171","msg":"trace[1481774060] transaction","detail":"{read_only:false; response_revision:697; number_of_response:1; }","duration":"159.073567ms","start":"2026-04-07T09:36:35.221453Z","end":"2026-04-07T09:36:35.380526Z","steps":["trace[1481774060] 'process raft request' (duration: 153.311589ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:36:35.591418Z","caller":"traceutil/trace.go:171","msg":"trace[1699065587] transaction","detail":"{read_only:false; response_revision:698; number_of_response:1; }","duration":"351.342787ms","start":"2026-04-07T09:36:35.240053Z","end":"2026-04-07T09:36:35.591396Z","steps":["trace[1699065587] 'process raft request' (duration: 309.244622ms)","trace[1699065587] 'compare' (duration: 41.952497ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T09:36:35.591519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:36:35.240032Z","time spent":"351.435433ms","remote":"127.0.0.1:50650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18048,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T09:36:35.667492Z","caller":"traceutil/trace.go:171","msg":"trace[1197639375] linearizableReadLoop","detail":"{readStateIndex:721; appliedIndex:719; }","duration":"265.513316ms","start":"2026-04-07T09:36:35.401957Z","end":"2026-04-07T09:36:35.66747Z","steps":["trace[1197639375] 'read index received' (duration: 147.292606ms)","trace[1197639375] 'applied index is now lower than readState.Index' (duration: 118.21967ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T09:36:35.667708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.727968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"info","ts":"2026-04-07T09:36:35.667856Z","caller":"traceutil/trace.go:171","msg":"trace[1226361264] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:698; }","duration":"265.914279ms","start":"2026-04-07T09:36:35.401926Z","end":"2026-04-07T09:36:35.667841Z","steps":["trace[1226361264] 'agreement among raft nodes before linearized reading' (duration: 265.664915ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:36:35.668121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.502672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T09:36:35.668201Z","caller":"traceutil/trace.go:171","msg":"trace[769846026] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:698; }","duration":"184.726353ms","start":"2026-04-07T09:36:35.483454Z","end":"2026-04-07T09:36:35.66818Z","steps":["trace[769846026] 'agreement among raft nodes before linearized reading' (duration: 184.248617ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:36:36.002404Z","caller":"traceutil/trace.go:171","msg":"trace[595281667] transaction","detail":"{read_only:false; response_revision:700; number_of_response:1; }","duration":"213.725716ms","start":"2026-04-07T09:36:35.788655Z","end":"2026-04-07T09:36:36.002381Z","steps":["trace[595281667] 'process raft request' (duration: 213.560676ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:36:36.200917Z","caller":"traceutil/trace.go:171","msg":"trace[35875800] linearizableReadLoop","detail":"{readStateIndex:728; appliedIndex:723; }","duration":"198.514668ms","start":"2026-04-07T09:36:36.002382Z","end":"2026-04-07T09:36:36.200897Z","steps":["trace[35875800] 'read index received' (duration: 198.155649ms)","trace[35875800] 'applied index is now lower than readState.Index' (duration: 358.239µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T09:36:36.201219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.754872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:1 size:4083"} {"level":"info","ts":"2026-04-07T09:36:36.201264Z","caller":"traceutil/trace.go:171","msg":"trace[387262646] transaction","detail":"{read_only:false; response_revision:704; number_of_response:1; }","duration":"395.050454ms","start":"2026-04-07T09:36:35.806174Z","end":"2026-04-07T09:36:36.201224Z","steps":["trace[387262646] 'process raft request' (duration: 394.637962ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:36:36.201275Z","caller":"traceutil/trace.go:171","msg":"trace[701135547] transaction","detail":"{read_only:false; response_revision:703; number_of_response:1; }","duration":"412.417234ms","start":"2026-04-07T09:36:35.788835Z","end":"2026-04-07T09:36:36.201252Z","steps":["trace[701135547] 'process raft request' (duration: 411.942908ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:36:36.201307Z","caller":"traceutil/trace.go:171","msg":"trace[152523899] transaction","detail":"{read_only:false; response_revision:705; number_of_response:1; }","duration":"231.762184ms","start":"2026-04-07T09:36:35.969522Z","end":"2026-04-07T09:36:36.201284Z","steps":["trace[152523899] 'process raft request' (duration: 231.339291ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:36:36.201327Z","caller":"traceutil/trace.go:171","msg":"trace[497771592] transaction","detail":"{read_only:false; response_revision:701; number_of_response:1; }","duration":"412.563661ms","start":"2026-04-07T09:36:35.788724Z","end":"2026-04-07T09:36:36.201288Z","steps":["trace[497771592] 'process raft request' (duration: 411.888525ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:36:36.201458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:36:35.806153Z","time spent":"395.183931ms","remote":"127.0.0.1:50536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12244,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T09:36:36.201511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:36:35.788699Z","time spent":"412.704069ms","remote":"127.0.0.1:50742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T09:36:36.201501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:36:35.788816Z","time spent":"412.574692ms","remote":"127.0.0.1:50742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T09:36:36.201282Z","caller":"traceutil/trace.go:171","msg":"trace[712012757] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:705; }","duration":"198.928881ms","start":"2026-04-07T09:36:36.002332Z","end":"2026-04-07T09:36:36.201261Z","steps":["trace[712012757] 'agreement among raft nodes before linearized reading' (duration: 198.645996ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:36:36.201631Z","caller":"traceutil/trace.go:171","msg":"trace[750676865] transaction","detail":"{read_only:false; response_revision:702; number_of_response:1; }","duration":"412.523009ms","start":"2026-04-07T09:36:35.788796Z","end":"2026-04-07T09:36:36.201319Z","steps":["trace[750676865] 'process raft request' (duration: 411.946208ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:36:36.201776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:36:35.788773Z","time spent":"412.937212ms","remote":"127.0.0.1:50742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T09:36:36.819554Z","caller":"traceutil/trace.go:171","msg":"trace[1296009098] transaction","detail":"{read_only:false; response_revision:714; number_of_response:1; }","duration":"212.224965ms","start":"2026-04-07T09:36:36.607307Z","end":"2026-04-07T09:36:36.819532Z","steps":["trace[1296009098] 'process raft request' (duration: 212.017154ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:36:44.378513Z","caller":"traceutil/trace.go:171","msg":"trace[1073532647] transaction","detail":"{read_only:false; response_revision:776; number_of_response:1; }","duration":"139.131943ms","start":"2026-04-07T09:36:44.239347Z","end":"2026-04-07T09:36:44.378479Z","steps":["trace[1073532647] 'process raft request' (duration: 138.945893ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:36:44.384047Z","caller":"traceutil/trace.go:171","msg":"trace[1091487857] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"143.140882ms","start":"2026-04-07T09:36:44.240891Z","end":"2026-04-07T09:36:44.384032Z","steps":["trace[1091487857] 'process raft request' (duration: 143.066398ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:36:44.384121Z","caller":"traceutil/trace.go:171","msg":"trace[1155257878] transaction","detail":"{read_only:false; response_revision:777; number_of_response:1; }","duration":"144.387134ms","start":"2026-04-07T09:36:44.239713Z","end":"2026-04-07T09:36:44.3841Z","steps":["trace[1155257878] 'process raft request' (duration: 144.189995ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:36:46.985091Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011512658308493174,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-07T09:36:47.330272Z","caller":"traceutil/trace.go:171","msg":"trace[1207726122] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"929.307102ms","start":"2026-04-07T09:36:46.400944Z","end":"2026-04-07T09:36:47.330251Z","steps":["trace[1207726122] 'process raft request' (duration: 929.239069ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:36:47.330425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:36:46.400919Z","time spent":"929.419827ms","remote":"127.0.0.1:50742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T09:36:47.330588Z","caller":"traceutil/trace.go:171","msg":"trace[1262149876] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"930.157303ms","start":"2026-04-07T09:36:46.400417Z","end":"2026-04-07T09:36:47.330574Z","steps":["trace[1262149876] 'process raft request' (duration: 830.103471ms)","trace[1262149876] 'compare' (duration: 99.340077ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T09:36:47.330885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:36:46.400404Z","time spent":"930.217266ms","remote":"127.0.0.1:50742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T09:36:47.645625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.375938ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-07T09:36:47.64572Z","caller":"traceutil/trace.go:171","msg":"trace[947446810] linearizableReadLoop","detail":"{readStateIndex:819; appliedIndex:816; }","duration":"1.16163268s","start":"2026-04-07T09:36:46.484071Z","end":"2026-04-07T09:36:47.645703Z","steps":["trace[947446810] 'read index received' (duration: 746.500904ms)","trace[947446810] 'applied index is now lower than readState.Index' (duration: 415.130376ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T09:36:47.646034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.161959326s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T09:36:47.646083Z","caller":"traceutil/trace.go:171","msg":"trace[308135758] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:793; }","duration":"1.162012299s","start":"2026-04-07T09:36:46.484058Z","end":"2026-04-07T09:36:47.64607Z","steps":["trace[308135758] 'agreement among raft nodes before linearized reading' (duration: 1.161930915s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:36:47.646117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:36:46.484002Z","time spent":"1.162106553s","remote":"127.0.0.1:50504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-07T09:36:47.650129Z","caller":"traceutil/trace.go:171","msg":"trace[1035845654] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"331.517144ms","start":"2026-04-07T09:36:47.3186Z","end":"2026-04-07T09:36:47.650117Z","steps":["trace[1035845654] 'process raft request' (duration: 331.384828ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:36:47.65024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:36:47.318575Z","time spent":"331.585437ms","remote":"127.0.0.1:50706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T09:36:47.65036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.279705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-04-07T09:36:47.650436Z","caller":"traceutil/trace.go:171","msg":"trace[1645918055] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:794; }","duration":"377.402572ms","start":"2026-04-07T09:36:47.273017Z","end":"2026-04-07T09:36:47.65042Z","steps":["trace[1645918055] 'agreement among raft nodes before linearized reading' (duration: 377.192412ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:36:47.650519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:36:47.272998Z","time spent":"377.511136ms","remote":"127.0.0.1:50742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-07T09:37:01.644756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.069742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T09:37:01.644863Z","caller":"traceutil/trace.go:171","msg":"trace[1532809159] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:962; }","duration":"161.25484ms","start":"2026-04-07T09:37:01.483582Z","end":"2026-04-07T09:37:01.644837Z","steps":["trace[1532809159] 'range keys from in-memory index tree' (duration: 161.00599ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:37:29.650871Z","caller":"traceutil/trace.go:171","msg":"trace[662286699] transaction","detail":"{read_only:false; response_revision:1069; number_of_response:1; }","duration":"105.891133ms","start":"2026-04-07T09:37:29.544952Z","end":"2026-04-07T09:37:29.650844Z","steps":["trace[662286699] 'process raft request' (duration: 105.769909ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:38:15.984148Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011512658308494166,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T09:38:17.490131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.006717985s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-07T09:38:17.490276Z","caller":"traceutil/trace.go:171","msg":"trace[1843861950] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.00689445s","start":"2026-04-07T09:38:15.483333Z","end":"2026-04-07T09:38:17.490227Z","steps":["trace[1843861950] 'agreement among raft nodes before linearized reading' (duration: 2.006741166s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:38:17.490353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:38:15.483318Z","time spent":"2.007017862s","remote":"127.0.0.1:50504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-07T09:38:17.528949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.28173878s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-07T09:38:17.529443Z","caller":"traceutil/trace.go:171","msg":"trace[1337716663] linearizableReadLoop","detail":"{readStateIndex:1266; appliedIndex:1265; }","duration":"2.046046127s","start":"2026-04-07T09:38:15.483369Z","end":"2026-04-07T09:38:17.529415Z","steps":["trace[1337716663] 'read index received' (duration: 763.747775ms)","trace[1337716663] 'applied index is now lower than readState.Index' (duration: 1.282296582s)"],"step_count":2} {"level":"warn","ts":"2026-04-07T09:38:17.533554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.861133364s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T09:38:17.533628Z","caller":"traceutil/trace.go:171","msg":"trace[865700755] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:1221; }","duration":"1.861258427s","start":"2026-04-07T09:38:15.67235Z","end":"2026-04-07T09:38:17.533608Z","steps":["trace[865700755] 'agreement among raft nodes before linearized reading' (duration: 1.861154455s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:38:17.533652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:38:15.672331Z","time spent":"1.861315659s","remote":"127.0.0.1:55386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-07T09:38:17.534584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.982447265s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:5 size:1050240"} {"level":"info","ts":"2026-04-07T09:38:17.534647Z","caller":"traceutil/trace.go:171","msg":"trace[773433022] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:5; response_revision:1221; }","duration":"1.982548968s","start":"2026-04-07T09:38:15.552081Z","end":"2026-04-07T09:38:17.53463Z","steps":["trace[773433022] 'agreement among raft nodes before linearized reading' (duration: 1.981044475s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:38:17.534727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:38:15.552067Z","time spent":"1.98264371s","remote":"127.0.0.1:50574","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":5,"response size":1050263,"request content":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" "} {"level":"info","ts":"2026-04-07T09:38:23.707709Z","caller":"traceutil/trace.go:171","msg":"trace[2042607905] linearizableReadLoop","detail":"{readStateIndex:1290; appliedIndex:1289; }","duration":"127.04212ms","start":"2026-04-07T09:38:23.580647Z","end":"2026-04-07T09:38:23.707689Z","steps":["trace[2042607905] 'read index received' (duration: 126.883556ms)","trace[2042607905] 'applied index is now lower than readState.Index' (duration: 157.794µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T09:38:23.707975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.314435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-07T09:38:23.708019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.336857ms","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-07T09:38:23.708051Z","caller":"traceutil/trace.go:171","msg":"trace[1935766970] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1241; }","duration":"127.399777ms","start":"2026-04-07T09:38:23.580641Z","end":"2026-04-07T09:38:23.708041Z","steps":["trace[1935766970] 'agreement among raft nodes before linearized reading' (duration: 127.305105ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:38:23.708078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.462336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-07T09:38:23.708139Z","caller":"traceutil/trace.go:171","msg":"trace[803496935] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1241; }","duration":"125.532938ms","start":"2026-04-07T09:38:23.582586Z","end":"2026-04-07T09:38:23.708118Z","steps":["trace[803496935] 'agreement among raft nodes before linearized reading' (duration: 125.383715ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:38:23.708252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.917205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-07T09:38:23.70834Z","caller":"traceutil/trace.go:171","msg":"trace[1659109559] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1241; }","duration":"125.031767ms","start":"2026-04-07T09:38:23.583289Z","end":"2026-04-07T09:38:23.708321Z","steps":["trace[1659109559] 'agreement among raft nodes before linearized reading' (duration: 124.748761ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:38:23.708284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.595951ms","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-07T09:38:23.708385Z","caller":"traceutil/trace.go:171","msg":"trace[465503723] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1241; }","duration":"127.743224ms","start":"2026-04-07T09:38:23.580633Z","end":"2026-04-07T09:38:23.708377Z","steps":["trace[465503723] 'agreement among raft nodes before linearized reading' (duration: 127.591321ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:38:23.707781Z","caller":"traceutil/trace.go:171","msg":"trace[1348025587] transaction","detail":"{read_only:false; response_revision:1241; number_of_response:1; }","duration":"155.533658ms","start":"2026-04-07T09:38:23.552218Z","end":"2026-04-07T09:38:23.707752Z","steps":["trace[1348025587] 'process raft request' (duration: 155.328664ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:38:23.708577Z","caller":"traceutil/trace.go:171","msg":"trace[1145692939] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1241; }","duration":"127.408888ms","start":"2026-04-07T09:38:23.580607Z","end":"2026-04-07T09:38:23.708016Z","steps":["trace[1145692939] 'agreement among raft nodes before linearized reading' (duration: 127.241914ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:38:46.805865Z","caller":"traceutil/trace.go:171","msg":"trace[280214321] transaction","detail":"{read_only:false; response_revision:1353; number_of_response:1; }","duration":"101.776197ms","start":"2026-04-07T09:38:46.704068Z","end":"2026-04-07T09:38:46.805845Z","steps":["trace[280214321] 'process raft request' (duration: 101.652104ms)"],"step_count":1}