{"level":"warn","ts":"2026-04-09T22:27:20.659163Z","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-09T22:27:20.659273Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.19: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.19:2380","--initial-cluster=instance=https://199.204.45.19:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.19:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.19: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-09T22:27:20.659363Z","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-09T22:27:20.659398Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.19:2380"]} {"level":"info","ts":"2026-04-09T22:27:20.659428Z","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-09T22:27:20.660129Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.19:2379"]} {"level":"info","ts":"2026-04-09T22:27:20.66025Z","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.19:2380"],"listen-peer-urls":["https://199.204.45.19:2380"],"advertise-client-urls":["https://199.204.45.19:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.19:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.19: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-09T22:27:20.6694Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"8.938596ms"} {"level":"info","ts":"2026-04-09T22:27:20.683002Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a847c2e9c7010db6","cluster-id":"2b11a68e27507b05"} {"level":"info","ts":"2026-04-09T22:27:20.683196Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 switched to configuration voters=()"} {"level":"info","ts":"2026-04-09T22:27:20.683235Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 became follower at term 0"} {"level":"info","ts":"2026-04-09T22:27:20.683272Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a847c2e9c7010db6 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-09T22:27:20.683304Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 became follower at term 1"} {"level":"info","ts":"2026-04-09T22:27:20.683376Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 switched to configuration voters=(12125874831040253366)"} {"level":"warn","ts":"2026-04-09T22:27:20.692224Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-09T22:27:20.694425Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-09T22:27:20.695526Z","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-09T22:27:20.698458Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a847c2e9c7010db6","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-09T22:27:20.700358Z","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-09T22:27:20.700473Z","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-09T22:27:20.700694Z","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-09T22:27:20.701097Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 switched to configuration voters=(12125874831040253366)"} {"level":"info","ts":"2026-04-09T22:27:20.701205Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a847c2e9c7010db6","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-09T22:27:20.701324Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"2b11a68e27507b05","local-member-id":"a847c2e9c7010db6","added-peer-id":"a847c2e9c7010db6","added-peer-peer-urls":["https://199.204.45.19:2380"]} {"level":"info","ts":"2026-04-09T22:27:20.704129Z","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-09T22:27:20.704297Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.19:2380"} {"level":"info","ts":"2026-04-09T22:27:20.704363Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.19:2380"} {"level":"info","ts":"2026-04-09T22:27:20.704479Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a847c2e9c7010db6","initial-advertise-peer-urls":["https://199.204.45.19:2380"],"listen-peer-urls":["https://199.204.45.19:2380"],"advertise-client-urls":["https://199.204.45.19:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.19:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-09T22:27:20.704533Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-09T22:27:21.184166Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 is starting a new election at term 1"} {"level":"info","ts":"2026-04-09T22:27:21.184386Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-09T22:27:21.184488Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 received MsgPreVoteResp from a847c2e9c7010db6 at term 1"} {"level":"info","ts":"2026-04-09T22:27:21.184556Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 became candidate at term 2"} {"level":"info","ts":"2026-04-09T22:27:21.184621Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 received MsgVoteResp from a847c2e9c7010db6 at term 2"} {"level":"info","ts":"2026-04-09T22:27:21.184679Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 became leader at term 2"} {"level":"info","ts":"2026-04-09T22:27:21.18472Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a847c2e9c7010db6 elected leader a847c2e9c7010db6 at term 2"} {"level":"info","ts":"2026-04-09T22:27:21.185842Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T22:27:21.186278Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a847c2e9c7010db6","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.19:2379]}","request-path":"/0/members/a847c2e9c7010db6/attributes","cluster-id":"2b11a68e27507b05","publish-timeout":"7s"} {"level":"info","ts":"2026-04-09T22:27:21.186519Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-09T22:27:21.186539Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-09T22:27:21.186823Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"2b11a68e27507b05","local-member-id":"a847c2e9c7010db6","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T22:27:21.187058Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T22:27:21.187306Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T22:27:21.211141Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.19:2379"} {"level":"info","ts":"2026-04-09T22:27:21.212987Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-09T22:27:21.230766Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-09T22:27:21.230874Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-09T22:27:49.931404Z","caller":"traceutil/trace.go:171","msg":"trace[1877888029] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"147.608985ms","start":"2026-04-09T22:27:49.783767Z","end":"2026-04-09T22:27:49.931376Z","steps":["trace[1877888029] 'process raft request' (duration: 147.41961ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:27:50.722362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.292971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T22:27:50.722477Z","caller":"traceutil/trace.go:171","msg":"trace[782393898] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:466; }","duration":"339.459985ms","start":"2026-04-09T22:27:50.382994Z","end":"2026-04-09T22:27:50.722454Z","steps":["trace[782393898] 'range keys from in-memory index tree' (duration: 339.175377ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:27:50.72244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"658.488037ms","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:0 size:5"} {"level":"warn","ts":"2026-04-09T22:27:50.722544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:27:50.382973Z","time spent":"339.561027ms","remote":"127.0.0.1:56172","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-09T22:27:50.722581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.432224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-09T22:27:50.722712Z","caller":"traceutil/trace.go:171","msg":"trace[181084139] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:466; }","duration":"459.619979ms","start":"2026-04-09T22:27:50.263033Z","end":"2026-04-09T22:27:50.722653Z","steps":["trace[181084139] 'range keys from in-memory index tree' (duration: 459.263719ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:27:50.722599Z","caller":"traceutil/trace.go:171","msg":"trace[2146248693] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:466; }","duration":"658.677522ms","start":"2026-04-09T22:27:50.063887Z","end":"2026-04-09T22:27:50.722564Z","steps":["trace[2146248693] 'range keys from in-memory index tree' (duration: 658.355824ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:27:50.722759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:27:50.263014Z","time spent":"459.734562ms","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-09T22:27:50.722773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:27:50.063843Z","time spent":"658.914048ms","remote":"127.0.0.1:56250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" "} {"level":"warn","ts":"2026-04-09T22:27:52.724071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.567394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/xbackendtrafficpolicies.gateway.networking.x-k8s.io\" ","response":"range_response_count:1 size:23081"} {"level":"info","ts":"2026-04-09T22:27:52.724163Z","caller":"traceutil/trace.go:171","msg":"trace[495611138] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/xbackendtrafficpolicies.gateway.networking.x-k8s.io; range_end:; response_count:1; response_revision:542; }","duration":"460.683498ms","start":"2026-04-09T22:27:52.263461Z","end":"2026-04-09T22:27:52.724144Z","steps":["trace[495611138] 'range keys from in-memory index tree' (duration: 460.383839ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:27:52.72422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:27:52.263448Z","time spent":"460.755449ms","remote":"127.0.0.1:56222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":1,"response size":23104,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/xbackendtrafficpolicies.gateway.networking.x-k8s.io\" "} {"level":"warn","ts":"2026-04-09T22:27:52.883486Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":988150291329707907,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-09T22:27:53.079843Z","caller":"traceutil/trace.go:171","msg":"trace[221363971] linearizableReadLoop","detail":"{readStateIndex:569; appliedIndex:568; }","duration":"696.6938ms","start":"2026-04-09T22:27:52.383132Z","end":"2026-04-09T22:27:53.079826Z","steps":["trace[221363971] 'read index received' (duration: 696.297859ms)","trace[221363971] 'applied index is now lower than readState.Index' (duration: 395.011µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T22:27:53.079904Z","caller":"traceutil/trace.go:171","msg":"trace[653770524] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"815.933459ms","start":"2026-04-09T22:27:52.263934Z","end":"2026-04-09T22:27:53.079868Z","steps":["trace[653770524] 'process raft request' (duration: 815.646191ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:27:53.079958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"696.806873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T22:27:53.079991Z","caller":"traceutil/trace.go:171","msg":"trace[486966318] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:543; }","duration":"696.879625ms","start":"2026-04-09T22:27:52.3831Z","end":"2026-04-09T22:27:53.079979Z","steps":["trace[486966318] 'agreement among raft nodes before linearized reading' (duration: 696.802943ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:27:53.080011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:27:52.383084Z","time spent":"696.923256ms","remote":"127.0.0.1:56172","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-09T22:27:53.08005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.291147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/xlistenersets.gateway.networking.x-k8s.io\" ","response":"range_response_count:1 size:29476"} {"level":"info","ts":"2026-04-09T22:27:53.080068Z","caller":"traceutil/trace.go:171","msg":"trace[1657097109] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/xlistenersets.gateway.networking.x-k8s.io; range_end:; response_count:1; response_revision:543; }","duration":"347.320767ms","start":"2026-04-09T22:27:52.73274Z","end":"2026-04-09T22:27:53.080061Z","steps":["trace[1657097109] 'agreement among raft nodes before linearized reading' (duration: 347.237805ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:27:53.080088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:27:52.732734Z","time spent":"347.349048ms","remote":"127.0.0.1:56222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":1,"response size":29499,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/xlistenersets.gateway.networking.x-k8s.io\" "} {"level":"warn","ts":"2026-04-09T22:27:53.079966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.289987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-09T22:27:53.080162Z","caller":"traceutil/trace.go:171","msg":"trace[22646733] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:543; }","duration":"347.524253ms","start":"2026-04-09T22:27:52.732632Z","end":"2026-04-09T22:27:53.080157Z","steps":["trace[22646733] 'agreement among raft nodes before linearized reading' (duration: 347.290036ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:27:53.08018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:27:52.732617Z","time spent":"347.556964ms","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-09T22:27:53.080225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.712639ms","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-09T22:27:53.08032Z","caller":"traceutil/trace.go:171","msg":"trace[1461844267] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:543; }","duration":"290.842432ms","start":"2026-04-09T22:27:52.789447Z","end":"2026-04-09T22:27:53.08029Z","steps":["trace[1461844267] 'agreement among raft nodes before linearized reading' (duration: 290.506243ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:27:53.080482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:27:52.263711Z","time spent":"816.256498ms","remote":"127.0.0.1:56222","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-09T22:27:57.284153Z","caller":"traceutil/trace.go:171","msg":"trace[1302426436] linearizableReadLoop","detail":"{readStateIndex:597; appliedIndex:596; }","duration":"194.86944ms","start":"2026-04-09T22:27:57.089262Z","end":"2026-04-09T22:27:57.284131Z","steps":["trace[1302426436] 'read index received' (duration: 194.083579ms)","trace[1302426436] 'applied index is now lower than readState.Index' (duration: 785.061µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T22:27:57.284257Z","caller":"traceutil/trace.go:171","msg":"trace[564634727] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"561.017576ms","start":"2026-04-09T22:27:56.7232Z","end":"2026-04-09T22:27:57.284217Z","steps":["trace[564634727] 'process raft request' (duration: 560.340797ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:27:57.284358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.086086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-jg7wh\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-04-09T22:27:57.284393Z","caller":"traceutil/trace.go:171","msg":"trace[142024423] range","detail":"{range_begin:/registry/pods/kube-system/cilium-jg7wh; range_end:; response_count:1; response_revision:570; }","duration":"195.170718ms","start":"2026-04-09T22:27:57.089212Z","end":"2026-04-09T22:27:57.284383Z","steps":["trace[142024423] 'agreement among raft nodes before linearized reading' (duration: 195.043905ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:27:57.284427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:27:56.723161Z","time spent":"561.17494ms","remote":"127.0.0.1:56250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036225,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T22:27:57.284636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.154691ms","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-09T22:27:57.284739Z","caller":"traceutil/trace.go:171","msg":"trace[1174555280] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:570; }","duration":"134.285724ms","start":"2026-04-09T22:27:57.15043Z","end":"2026-04-09T22:27:57.284716Z","steps":["trace[1174555280] 'agreement among raft nodes before linearized reading' (duration: 133.970136ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:27:57.285199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.331083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-09T22:27:57.28527Z","caller":"traceutil/trace.go:171","msg":"trace[56459064] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:570; }","duration":"135.441796ms","start":"2026-04-09T22:27:57.149814Z","end":"2026-04-09T22:27:57.285255Z","steps":["trace[56459064] 'agreement among raft nodes before linearized reading' (duration: 134.520911ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:27:57.422106Z","caller":"traceutil/trace.go:171","msg":"trace[1952350814] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"126.911207ms","start":"2026-04-09T22:27:57.295172Z","end":"2026-04-09T22:27:57.422083Z","steps":["trace[1952350814] 'process raft request' (duration: 126.721922ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:27:57.422273Z","caller":"traceutil/trace.go:171","msg":"trace[532810642] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"123.332439ms","start":"2026-04-09T22:27:57.298911Z","end":"2026-04-09T22:27:57.422243Z","steps":["trace[532810642] 'process raft request' (duration: 123.109023ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:27:58.458907Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":988150291329707991,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-09T22:27:58.959823Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":988150291329707991,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-09T22:27:58.985496Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.137934422s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-09T22:27:58.989854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:27:58.543178Z","time spent":"446.67168ms","remote":"127.0.0.1:45208","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-09T22:27:58.989951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.435165427s","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-09T22:27:58.990003Z","caller":"traceutil/trace.go:171","msg":"trace[2145929206] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:574; }","duration":"1.435224629s","start":"2026-04-09T22:27:57.55476Z","end":"2026-04-09T22:27:58.989985Z","steps":["trace[2145929206] 'range keys from in-memory index tree' (duration: 1.434990672s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:27:58.990044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:27:57.55469Z","time spent":"1.435343312s","remote":"127.0.0.1:56442","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-09T22:27:58.990028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.094312ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T22:27:58.990118Z","caller":"traceutil/trace.go:171","msg":"trace[1785433826] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:574; }","duration":"310.194805ms","start":"2026-04-09T22:27:58.679906Z","end":"2026-04-09T22:27:58.990101Z","steps":["trace[1785433826] 'range keys from in-memory index tree' (duration: 310.080272ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:27:58.991191Z","caller":"traceutil/trace.go:171","msg":"trace[1665856440] linearizableReadLoop","detail":"{readStateIndex:602; appliedIndex:601; }","duration":"1.032484635s","start":"2026-04-09T22:27:57.95866Z","end":"2026-04-09T22:27:58.991144Z","steps":["trace[1665856440] 'read index received' (duration: 1.02710927s)","trace[1665856440] 'applied index is now lower than readState.Index' (duration: 5.373565ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T22:27:58.991355Z","caller":"traceutil/trace.go:171","msg":"trace[1623627260] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"1.14525994s","start":"2026-04-09T22:27:57.846077Z","end":"2026-04-09T22:27:58.991337Z","steps":["trace[1623627260] 'process raft request' (duration: 1.140021848s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:27:58.991903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:27:57.846047Z","time spent":"1.145793475s","remote":"127.0.0.1:56250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036261,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T22:27:58.991493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.032826095s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-09T22:27:58.991937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"607.958814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T22:27:58.991979Z","caller":"traceutil/trace.go:171","msg":"trace[492954960] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:575; }","duration":"1.033339549s","start":"2026-04-09T22:27:57.958615Z","end":"2026-04-09T22:27:58.991954Z","steps":["trace[492954960] 'agreement among raft nodes before linearized reading' (duration: 1.032719602s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:27:58.992045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:27:57.958597Z","time spent":"1.033430181s","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-09T22:27:58.992038Z","caller":"traceutil/trace.go:171","msg":"trace[42602428] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:575; }","duration":"608.103567ms","start":"2026-04-09T22:27:58.383917Z","end":"2026-04-09T22:27:58.992021Z","steps":["trace[42602428] 'agreement among raft nodes before linearized reading' (duration: 607.912022ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:27:58.992204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:27:58.383901Z","time spent":"608.251432ms","remote":"127.0.0.1:56172","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-09T22:28:00.763782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.572196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-09T22:28:00.763866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.085821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T22:28:00.76388Z","caller":"traceutil/trace.go:171","msg":"trace[2060175787] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:600; }","duration":"356.71752ms","start":"2026-04-09T22:28:00.407142Z","end":"2026-04-09T22:28:00.76386Z","steps":["trace[2060175787] 'count revisions from in-memory index tree' (duration: 356.413943ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:28:00.763898Z","caller":"traceutil/trace.go:171","msg":"trace[1948086761] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:600; }","duration":"217.137102ms","start":"2026-04-09T22:28:00.546749Z","end":"2026-04-09T22:28:00.763886Z","steps":["trace[1948086761] 'range keys from in-memory index tree' (duration: 217.037889ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:28:00.763934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:28:00.407117Z","time spent":"356.805583ms","remote":"127.0.0.1:45274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true "} {"level":"warn","ts":"2026-04-09T22:28:00.763961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.155723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-09T22:28:00.76386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.23976ms","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-09T22:28:00.764063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.918111ms","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":"warn","ts":"2026-04-09T22:28:00.764149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.034529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-09T22:28:00.764178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.304517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T22:28:00.764124Z","caller":"traceutil/trace.go:171","msg":"trace[927319140] 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:600; }","duration":"312.544088ms","start":"2026-04-09T22:28:00.451558Z","end":"2026-04-09T22:28:00.764103Z","steps":["trace[927319140] 'range keys from in-memory index tree' (duration: 312.191318ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:28:00.764208Z","caller":"traceutil/trace.go:171","msg":"trace[1253876981] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:600; }","duration":"284.08986ms","start":"2026-04-09T22:28:00.480099Z","end":"2026-04-09T22:28:00.764189Z","steps":["trace[1253876981] 'range keys from in-memory index tree' (duration: 283.941466ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:28:00.76425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.489402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-09T22:28:00.764276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:28:00.451541Z","time spent":"312.716983ms","remote":"127.0.0.1:45278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:10000 "} {"level":"warn","ts":"2026-04-09T22:28:00.764291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.06667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T22:28:00.764176Z","caller":"traceutil/trace.go:171","msg":"trace[591196498] 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:600; }","duration":"312.098576ms","start":"2026-04-09T22:28:00.452066Z","end":"2026-04-09T22:28:00.764165Z","steps":["trace[591196498] 'range keys from in-memory index tree' (duration: 311.865409ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:28:00.764353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.926985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-09T22:28:00.764368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.884246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T22:28:00.764046Z","caller":"traceutil/trace.go:171","msg":"trace[115119485] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:600; }","duration":"467.272166ms","start":"2026-04-09T22:28:00.296754Z","end":"2026-04-09T22:28:00.764026Z","steps":["trace[115119485] 'range keys from in-memory index tree' (duration: 467.025789ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:28:00.764389Z","caller":"traceutil/trace.go:171","msg":"trace[798878426] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:600; }","duration":"216.937116ms","start":"2026-04-09T22:28:00.547445Z","end":"2026-04-09T22:28:00.764382Z","steps":["trace[798878426] 'range keys from in-memory index tree' (duration: 216.799403ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:28:00.764385Z","caller":"traceutil/trace.go:171","msg":"trace[2065776486] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:600; }","duration":"380.994696ms","start":"2026-04-09T22:28:00.383381Z","end":"2026-04-09T22:28:00.764376Z","steps":["trace[2065776486] 'range keys from in-memory index tree' (duration: 380.76659ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:28:00.764426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:28:00.383358Z","time spent":"381.061057ms","remote":"127.0.0.1:56172","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-09T22:28:00.76444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:28:00.452059Z","time spent":"312.307072ms","remote":"127.0.0.1:45278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:500 "} {"level":"warn","ts":"2026-04-09T22:28:00.764465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.912528ms","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":"warn","ts":"2026-04-09T22:28:00.764507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.345416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T22:28:00.764536Z","caller":"traceutil/trace.go:171","msg":"trace[44135655] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:600; }","duration":"138.399996ms","start":"2026-04-09T22:28:00.62613Z","end":"2026-04-09T22:28:00.76453Z","steps":["trace[44135655] 'range keys from in-memory index tree' (duration: 138.295103ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:28:00.764425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:28:00.296703Z","time spent":"467.709767ms","remote":"127.0.0.1:45268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 "} {"level":"warn","ts":"2026-04-09T22:28:00.764635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.228792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T22:28:00.764219Z","caller":"traceutil/trace.go:171","msg":"trace[1811953803] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:600; }","duration":"467.389159ms","start":"2026-04-09T22:28:00.296818Z","end":"2026-04-09T22:28:00.764208Z","steps":["trace[1811953803] 'range keys from in-memory index tree' (duration: 467.163462ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:28:00.764333Z","caller":"traceutil/trace.go:171","msg":"trace[42469213] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:600; }","duration":"217.582684ms","start":"2026-04-09T22:28:00.546724Z","end":"2026-04-09T22:28:00.764307Z","steps":["trace[42469213] 'count revisions from in-memory index tree' (duration: 217.404909ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:28:00.764724Z","caller":"traceutil/trace.go:171","msg":"trace[1849140870] 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:600; }","duration":"312.976879ms","start":"2026-04-09T22:28:00.451519Z","end":"2026-04-09T22:28:00.764495Z","steps":["trace[1849140870] 'count revisions from in-memory index tree' (duration: 312.831215ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:28:00.764773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.997222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T22:28:00.764656Z","caller":"traceutil/trace.go:171","msg":"trace[244878421] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:600; }","duration":"138.274654ms","start":"2026-04-09T22:28:00.626377Z","end":"2026-04-09T22:28:00.764652Z","steps":["trace[244878421] 'count revisions from in-memory index tree' (duration: 138.172591ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:28:00.764776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:28:00.451501Z","time spent":"313.257328ms","remote":"127.0.0.1:45278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true "} {"level":"info","ts":"2026-04-09T22:28:00.764325Z","caller":"traceutil/trace.go:171","msg":"trace[681370648] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:600; }","duration":"357.121232ms","start":"2026-04-09T22:28:00.407191Z","end":"2026-04-09T22:28:00.764313Z","steps":["trace[681370648] 'range keys from in-memory index tree' (duration: 356.992528ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:28:00.764258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.345998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-09T22:28:00.765091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:28:00.407179Z","time spent":"357.898772ms","remote":"127.0.0.1:45274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:10000 "} {"level":"info","ts":"2026-04-09T22:28:00.765103Z","caller":"traceutil/trace.go:171","msg":"trace[2043502311] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:600; }","duration":"468.233801ms","start":"2026-04-09T22:28:00.296854Z","end":"2026-04-09T22:28:00.765087Z","steps":["trace[2043502311] 'count revisions from in-memory index tree' (duration: 467.217104ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:28:00.765108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.202974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-09T22:28:00.765147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:28:00.296794Z","time spent":"468.339645ms","remote":"127.0.0.1:45268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"info","ts":"2026-04-09T22:28:00.765159Z","caller":"traceutil/trace.go:171","msg":"trace[1488251113] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:600; }","duration":"357.261915ms","start":"2026-04-09T22:28:00.407881Z","end":"2026-04-09T22:28:00.765143Z","steps":["trace[1488251113] 'range keys from in-memory index tree' (duration: 357.168382ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:28:00.764093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.650265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-09T22:28:00.765199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:28:00.407875Z","time spent":"357.309957ms","remote":"127.0.0.1:45274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:500 "} {"level":"info","ts":"2026-04-09T22:28:00.765219Z","caller":"traceutil/trace.go:171","msg":"trace[1434445533] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:600; }","duration":"498.788867ms","start":"2026-04-09T22:28:00.266419Z","end":"2026-04-09T22:28:00.765208Z","steps":["trace[1434445533] 'count revisions from in-memory index tree' (duration: 497.596934ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:28:00.765257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:28:00.266414Z","time spent":"498.832308ms","remote":"127.0.0.1:45256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-09T22:28:00.763817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.439935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T22:28:00.765321Z","caller":"traceutil/trace.go:171","msg":"trace[1727818077] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:600; }","duration":"284.002807ms","start":"2026-04-09T22:28:00.4813Z","end":"2026-04-09T22:28:00.765303Z","steps":["trace[1727818077] 'range keys from in-memory index tree' (duration: 282.383104ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:28:00.764709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:28:00.296794Z","time spent":"467.896133ms","remote":"127.0.0.1:45256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:500 "} {"level":"warn","ts":"2026-04-09T22:28:00.765418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.642719ms","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":"warn","ts":"2026-04-09T22:28:00.763948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.421402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T22:28:00.765493Z","caller":"traceutil/trace.go:171","msg":"trace[307304197] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:600; }","duration":"285.229861ms","start":"2026-04-09T22:28:00.480253Z","end":"2026-04-09T22:28:00.765483Z","steps":["trace[307304197] 'count revisions from in-memory index tree' (duration: 283.330069ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:28:00.764815Z","caller":"traceutil/trace.go:171","msg":"trace[1896130072] 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:600; }","duration":"336.069993ms","start":"2026-04-09T22:28:00.428731Z","end":"2026-04-09T22:28:00.764801Z","steps":["trace[1896130072] 'count revisions from in-memory index tree' (duration: 335.871328ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:28:00.765625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:28:00.428719Z","time spent":"336.893186ms","remote":"127.0.0.1:45276","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-09T22:28:00.763834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.38809ms","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-09T22:28:00.765458Z","caller":"traceutil/trace.go:171","msg":"trace[1360119502] 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:600; }","duration":"336.719241ms","start":"2026-04-09T22:28:00.428729Z","end":"2026-04-09T22:28:00.765448Z","steps":["trace[1360119502] 'range keys from in-memory index tree' (duration: 336.513095ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:28:00.765718Z","caller":"traceutil/trace.go:171","msg":"trace[1886023778] 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:600; }","duration":"335.328063ms","start":"2026-04-09T22:28:00.430369Z","end":"2026-04-09T22:28:00.765697Z","steps":["trace[1886023778] 'range keys from in-memory index tree' (duration: 333.295018ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:28:00.765766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:28:00.430353Z","time spent":"335.397685ms","remote":"127.0.0.1:45276","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-09T22:28:00.765762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:28:00.428709Z","time spent":"337.038569ms","remote":"127.0.0.1:45276","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-09T22:28:00.764126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.772109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T22:28:00.765856Z","caller":"traceutil/trace.go:171","msg":"trace[596472689] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:600; }","duration":"499.530726ms","start":"2026-04-09T22:28:00.266313Z","end":"2026-04-09T22:28:00.765844Z","steps":["trace[596472689] 'range keys from in-memory index tree' (duration: 497.604774ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:28:00.765899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:28:00.266289Z","time spent":"499.596808ms","remote":"127.0.0.1:45256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 "} {"level":"warn","ts":"2026-04-09T22:28:15.283946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.09355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-09T22:28:15.283994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.419645ms","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-09T22:28:15.284056Z","caller":"traceutil/trace.go:171","msg":"trace[52232767] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:741; }","duration":"418.265716ms","start":"2026-04-09T22:28:14.865767Z","end":"2026-04-09T22:28:15.284033Z","steps":["trace[52232767] 'range keys from in-memory index tree' (duration: 417.896644ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:28:15.284083Z","caller":"traceutil/trace.go:171","msg":"trace[2034370849] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:741; }","duration":"199.538359ms","start":"2026-04-09T22:28:15.084525Z","end":"2026-04-09T22:28:15.284063Z","steps":["trace[2034370849] 'range keys from in-memory index tree' (duration: 199.272161ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:28:15.284119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:28:14.865745Z","time spent":"418.356239ms","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-09T22:28:15.284315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.068121ms","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-09T22:28:15.284375Z","caller":"traceutil/trace.go:171","msg":"trace[1329955542] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:741; }","duration":"203.153173ms","start":"2026-04-09T22:28:15.081203Z","end":"2026-04-09T22:28:15.284357Z","steps":["trace[1329955542] 'range keys from in-memory index tree' (duration: 202.930706ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:28:15.284584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"569.117266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T22:28:15.284629Z","caller":"traceutil/trace.go:171","msg":"trace[1676020663] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:741; }","duration":"569.203759ms","start":"2026-04-09T22:28:14.715413Z","end":"2026-04-09T22:28:15.284616Z","steps":["trace[1676020663] 'range keys from in-memory index tree' (duration: 569.041644ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:28:15.284687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:28:14.715392Z","time spent":"569.283731ms","remote":"127.0.0.1:56172","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-09T22:28:49.940586Z","caller":"traceutil/trace.go:171","msg":"trace[905152505] linearizableReadLoop","detail":"{readStateIndex:1003; appliedIndex:1002; }","duration":"216.676758ms","start":"2026-04-09T22:28:49.72388Z","end":"2026-04-09T22:28:49.940557Z","steps":["trace[905152505] 'read index received' (duration: 216.459241ms)","trace[905152505] 'applied index is now lower than readState.Index' (duration: 216.417µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T22:28:49.940676Z","caller":"traceutil/trace.go:171","msg":"trace[1943713410] transaction","detail":"{read_only:false; response_revision:962; number_of_response:1; }","duration":"242.627448ms","start":"2026-04-09T22:28:49.698018Z","end":"2026-04-09T22:28:49.940646Z","steps":["trace[1943713410] 'process raft request' (duration: 242.352319ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:28:49.940805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.888525ms","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-09T22:28:49.94085Z","caller":"traceutil/trace.go:171","msg":"trace[649745012] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:962; }","duration":"216.997758ms","start":"2026-04-09T22:28:49.723838Z","end":"2026-04-09T22:28:49.940836Z","steps":["trace[649745012] 'agreement among raft nodes before linearized reading' (duration: 216.826723ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:28:51.473264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.068799988s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-09T22:28:51.473489Z","caller":"traceutil/trace.go:171","msg":"trace[1009769297] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"1.527729033s","start":"2026-04-09T22:28:49.945737Z","end":"2026-04-09T22:28:51.473466Z","steps":["trace[1009769297] 'process raft request' (duration: 456.781427ms)","trace[1009769297] 'compare' (duration: 1.068414786s)"],"step_count":2} {"level":"warn","ts":"2026-04-09T22:28:51.47356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:28:49.945717Z","time spent":"1.527810645s","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T22:28:51.473759Z","caller":"traceutil/trace.go:171","msg":"trace[140273935] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"752.389158ms","start":"2026-04-09T22:28:50.721362Z","end":"2026-04-09T22:28:51.473751Z","steps":["trace[140273935] 'process raft request' (duration: 752.021347ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:28:51.473812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:28:50.721331Z","time spent":"752.45034ms","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T22:28:51.836333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.765248ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-09T22:28:51.836429Z","caller":"traceutil/trace.go:171","msg":"trace[612549383] linearizableReadLoop","detail":"{readStateIndex:1006; appliedIndex:1005; }","duration":"267.483614ms","start":"2026-04-09T22:28:51.568929Z","end":"2026-04-09T22:28:51.836413Z","steps":["trace[612549383] 'read index received' (duration: 114.505529ms)","trace[612549383] 'applied index is now lower than readState.Index' (duration: 152.976615ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T22:28:51.836701Z","caller":"traceutil/trace.go:171","msg":"trace[1098249697] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"336.275488ms","start":"2026-04-09T22:28:51.500414Z","end":"2026-04-09T22:28:51.836689Z","steps":["trace[1098249697] 'process raft request' (duration: 183.079066ms)","trace[1098249697] 'compare' (duration: 152.659185ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T22:28:51.836762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:28:51.500382Z","time spent":"336.34677ms","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T22:28:51.836813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.142584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-09T22:28:51.836879Z","caller":"traceutil/trace.go:171","msg":"trace[1294972828] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:965; }","duration":"267.247616ms","start":"2026-04-09T22:28:51.569614Z","end":"2026-04-09T22:28:51.836862Z","steps":["trace[1294972828] 'agreement among raft nodes before linearized reading' (duration: 267.015149ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:28:51.836979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.022931ms","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-09T22:28:51.837056Z","caller":"traceutil/trace.go:171","msg":"trace[1594876719] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:965; }","duration":"268.134805ms","start":"2026-04-09T22:28:51.5689Z","end":"2026-04-09T22:28:51.837035Z","steps":["trace[1594876719] 'agreement among raft nodes before linearized reading' (duration: 267.923588ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:29:13.869152Z","caller":"traceutil/trace.go:171","msg":"trace[1580875089] linearizableReadLoop","detail":"{readStateIndex:1080; appliedIndex:1079; }","duration":"170.018243ms","start":"2026-04-09T22:29:13.699102Z","end":"2026-04-09T22:29:13.86912Z","steps":["trace[1580875089] 'read index received' (duration: 169.725994ms)","trace[1580875089] 'applied index is now lower than readState.Index' (duration: 291.019µs)"],"step_count":2} {"level":"warn","ts":"2026-04-09T22:29:13.869391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.260191ms","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-09T22:29:13.869469Z","caller":"traceutil/trace.go:171","msg":"trace[1280819443] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1035; }","duration":"170.390725ms","start":"2026-04-09T22:29:13.69906Z","end":"2026-04-09T22:29:13.869451Z","steps":["trace[1280819443] 'agreement among raft nodes before linearized reading' (duration: 170.125847ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:29:13.869778Z","caller":"traceutil/trace.go:171","msg":"trace[257954671] transaction","detail":"{read_only:false; response_revision:1035; number_of_response:1; }","duration":"207.381775ms","start":"2026-04-09T22:29:13.662364Z","end":"2026-04-09T22:29:13.869745Z","steps":["trace[257954671] 'process raft request' (duration: 206.587009ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:29:14.495618Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":988150291329709029,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-09T22:29:14.629744Z","caller":"traceutil/trace.go:171","msg":"trace[235726655] linearizableReadLoop","detail":"{readStateIndex:1081; appliedIndex:1080; }","duration":"635.044811ms","start":"2026-04-09T22:29:13.994671Z","end":"2026-04-09T22:29:14.629716Z","steps":["trace[235726655] 'read index received' (duration: 574.967612ms)","trace[235726655] 'applied index is now lower than readState.Index' (duration: 60.075809ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T22:29:14.629754Z","caller":"traceutil/trace.go:171","msg":"trace[62057071] transaction","detail":"{read_only:false; response_revision:1036; number_of_response:1; }","duration":"756.896312ms","start":"2026-04-09T22:29:13.872832Z","end":"2026-04-09T22:29:14.629728Z","steps":["trace[62057071] 'process raft request' (duration: 696.884275ms)","trace[62057071] 'compare' (duration: 59.711467ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T22:29:14.630001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"632.674046ms","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-09T22:29:14.630042Z","caller":"traceutil/trace.go:171","msg":"trace[1446855393] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1036; }","duration":"632.767729ms","start":"2026-04-09T22:29:13.997262Z","end":"2026-04-09T22:29:14.63003Z","steps":["trace[1446855393] 'agreement among raft nodes before linearized reading' (duration: 632.603333ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:29:14.630034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"635.360951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-09T22:29:14.630001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"592.286089ms","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-09T22:29:14.630105Z","caller":"traceutil/trace.go:171","msg":"trace[2030299233] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1036; }","duration":"635.444954ms","start":"2026-04-09T22:29:13.994624Z","end":"2026-04-09T22:29:14.630069Z","steps":["trace[2030299233] 'agreement among raft nodes before linearized reading' (duration: 635.208296ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:29:14.630036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:29:13.872809Z","time spent":"757.009816ms","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T22:29:14.630159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"597.657099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.19\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-04-09T22:29:14.630177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:29:13.994603Z","time spent":"635.537756ms","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-09T22:29:14.630204Z","caller":"traceutil/trace.go:171","msg":"trace[1916410226] range","detail":"{range_begin:/registry/masterleases/199.204.45.19; range_end:; response_count:1; response_revision:1036; }","duration":"597.720951ms","start":"2026-04-09T22:29:14.032471Z","end":"2026-04-09T22:29:14.630192Z","steps":["trace[1916410226] 'agreement among raft nodes before linearized reading' (duration: 597.623178ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:29:14.630247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:29:14.03246Z","time spent":"597.776783ms","remote":"127.0.0.1:56210","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.19\" "} {"level":"info","ts":"2026-04-09T22:29:14.63012Z","caller":"traceutil/trace.go:171","msg":"trace[1292017834] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1036; }","duration":"592.434074ms","start":"2026-04-09T22:29:14.037672Z","end":"2026-04-09T22:29:14.630106Z","steps":["trace[1292017834] 'agreement among raft nodes before linearized reading' (duration: 592.198126ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:29:14.630425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"629.974781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-09T22:29:14.630451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:29:14.037663Z","time spent":"592.732683ms","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-09T22:29:14.630511Z","caller":"traceutil/trace.go:171","msg":"trace[1509489797] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1036; }","duration":"630.027962ms","start":"2026-04-09T22:29:14.000435Z","end":"2026-04-09T22:29:14.630463Z","steps":["trace[1509489797] 'agreement among raft nodes before linearized reading' (duration: 629.891088ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:29:14.630554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:29:14.000399Z","time spent":"630.140286ms","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-09T22:29:14.630075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:29:13.997242Z","time spent":"632.824451ms","remote":"127.0.0.1:56442","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-09T22:29:15.135525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:29:14.632309Z","time spent":"503.212314ms","remote":"127.0.0.1:56210","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-09T22:29:15.146597Z","caller":"traceutil/trace.go:171","msg":"trace[1678595559] transaction","detail":"{read_only:false; response_revision:1037; number_of_response:1; }","duration":"511.569618ms","start":"2026-04-09T22:29:14.635Z","end":"2026-04-09T22:29:15.14657Z","steps":["trace[1678595559] 'process raft request' (duration: 511.361421ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:29:15.146775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:29:14.634981Z","time spent":"511.712013ms","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T22:29:15.146985Z","caller":"traceutil/trace.go:171","msg":"trace[52401136] transaction","detail":"{read_only:false; response_revision:1038; number_of_response:1; }","duration":"511.775635ms","start":"2026-04-09T22:29:14.635177Z","end":"2026-04-09T22:29:15.146952Z","steps":["trace[52401136] 'process raft request' (duration: 511.354312ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:29:15.147164Z","caller":"traceutil/trace.go:171","msg":"trace[701430893] transaction","detail":"{read_only:false; response_revision:1040; number_of_response:1; }","duration":"511.745804ms","start":"2026-04-09T22:29:14.635407Z","end":"2026-04-09T22:29:15.147153Z","steps":["trace[701430893] 'process raft request' (duration: 511.662402ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:29:15.147229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:29:14.635395Z","time spent":"511.804536ms","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T22:29:15.147214Z","caller":"traceutil/trace.go:171","msg":"trace[1204841520] transaction","detail":"{read_only:false; response_revision:1039; number_of_response:1; }","duration":"511.753974ms","start":"2026-04-09T22:29:14.635392Z","end":"2026-04-09T22:29:15.147146Z","steps":["trace[1204841520] 'process raft request' (duration: 511.422634ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:29:15.147308Z","caller":"traceutil/trace.go:171","msg":"trace[1806867966] transaction","detail":"{read_only:false; response_revision:1041; number_of_response:1; }","duration":"205.129303ms","start":"2026-04-09T22:29:14.942172Z","end":"2026-04-09T22:29:15.147302Z","steps":["trace[1806867966] 'process raft request' (duration: 204.945127ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:29:15.147219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:29:14.635154Z","time spent":"511.93411ms","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T22:29:15.147301Z","caller":"traceutil/trace.go:171","msg":"trace[129839960] linearizableReadLoop","detail":"{readStateIndex:1086; appliedIndex:1081; }","duration":"431.731095ms","start":"2026-04-09T22:29:14.715536Z","end":"2026-04-09T22:29:15.147267Z","steps":["trace[129839960] 'read index received' (duration: 419.881591ms)","trace[129839960] 'applied index is now lower than readState.Index' (duration: 11.848244ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T22:29:15.147422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:29:14.635381Z","time spent":"511.95243ms","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T22:29:15.147526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.962002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T22:29:15.148192Z","caller":"traceutil/trace.go:171","msg":"trace[1349063720] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1041; }","duration":"432.669525ms","start":"2026-04-09T22:29:14.715501Z","end":"2026-04-09T22:29:15.148171Z","steps":["trace[1349063720] 'agreement among raft nodes before linearized reading' (duration: 431.933291ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:29:15.14838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:29:14.715489Z","time spent":"432.85988ms","remote":"127.0.0.1:56172","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-09T22:29:15.312329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.494626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-04-09T22:29:15.312435Z","caller":"traceutil/trace.go:171","msg":"trace[1943228750] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1042; }","duration":"131.643701ms","start":"2026-04-09T22:29:15.180775Z","end":"2026-04-09T22:29:15.312419Z","steps":["trace[1943228750] 'range keys from in-memory index tree' (duration: 131.399423ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:29:16.877319Z","caller":"traceutil/trace.go:171","msg":"trace[1099283027] transaction","detail":"{read_only:false; response_revision:1044; number_of_response:1; }","duration":"238.130926ms","start":"2026-04-09T22:29:16.639163Z","end":"2026-04-09T22:29:16.877294Z","steps":["trace[1099283027] 'process raft request' (duration: 237.977191ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:29:18.228832Z","caller":"traceutil/trace.go:171","msg":"trace[425030481] transaction","detail":"{read_only:false; response_revision:1049; number_of_response:1; }","duration":"448.783504ms","start":"2026-04-09T22:29:17.780023Z","end":"2026-04-09T22:29:18.228806Z","steps":["trace[425030481] 'process raft request' (duration: 448.624819ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:29:18.228986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:29:17.780001Z","time spent":"448.907258ms","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T22:29:18.237051Z","caller":"traceutil/trace.go:171","msg":"trace[1564245497] linearizableReadLoop","detail":"{readStateIndex:1096; appliedIndex:1094; }","duration":"255.172515ms","start":"2026-04-09T22:29:17.981863Z","end":"2026-04-09T22:29:18.237036Z","steps":["trace[1564245497] 'read index received' (duration: 246.877413ms)","trace[1564245497] 'applied index is now lower than readState.Index' (duration: 8.293892ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T22:29:18.237292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.420382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T22:29:18.237353Z","caller":"traceutil/trace.go:171","msg":"trace[980774113] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:1050; }","duration":"255.516135ms","start":"2026-04-09T22:29:17.981822Z","end":"2026-04-09T22:29:18.237338Z","steps":["trace[980774113] 'agreement among raft nodes before linearized reading' (duration: 255.35398ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:29:18.237291Z","caller":"traceutil/trace.go:171","msg":"trace[1648616111] transaction","detail":"{read_only:false; response_revision:1050; number_of_response:1; }","duration":"271.438099ms","start":"2026-04-09T22:29:17.965825Z","end":"2026-04-09T22:29:18.237263Z","steps":["trace[1648616111] 'process raft request' (duration: 271.14184ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:29:19.397087Z","caller":"traceutil/trace.go:171","msg":"trace[1500039205] transaction","detail":"{read_only:false; response_revision:1052; number_of_response:1; }","duration":"178.359357ms","start":"2026-04-09T22:29:19.218701Z","end":"2026-04-09T22:29:19.397061Z","steps":["trace[1500039205] 'process raft request' (duration: 178.210003ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:29:19.397104Z","caller":"traceutil/trace.go:171","msg":"trace[29024484] linearizableReadLoop","detail":"{readStateIndex:1098; appliedIndex:1098; }","duration":"169.722364ms","start":"2026-04-09T22:29:19.227332Z","end":"2026-04-09T22:29:19.397054Z","steps":["trace[29024484] 'read index received' (duration: 169.698333ms)","trace[29024484] 'applied index is now lower than readState.Index' (duration: 20.821µs)"],"step_count":2} {"level":"warn","ts":"2026-04-09T22:29:19.397941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.567881ms","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-09T22:29:19.397994Z","caller":"traceutil/trace.go:171","msg":"trace[1594538475] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1052; }","duration":"170.689164ms","start":"2026-04-09T22:29:19.227292Z","end":"2026-04-09T22:29:19.397981Z","steps":["trace[1594538475] 'agreement among raft nodes before linearized reading' (duration: 170.416366ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:29:19.589159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.923283ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-09T22:29:19.589403Z","caller":"traceutil/trace.go:171","msg":"trace[1400280289] transaction","detail":"{read_only:false; response_revision:1053; number_of_response:1; }","duration":"187.077522ms","start":"2026-04-09T22:29:19.402307Z","end":"2026-04-09T22:29:19.589385Z","steps":["trace[1400280289] 'process raft request' (duration: 186.952858ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:29:19.589413Z","caller":"traceutil/trace.go:171","msg":"trace[1963915095] linearizableReadLoop","detail":"{readStateIndex:1099; appliedIndex:1098; }","duration":"192.173582ms","start":"2026-04-09T22:29:19.397203Z","end":"2026-04-09T22:29:19.589377Z","steps":["trace[1963915095] 'read index received' (duration: 70.088345ms)","trace[1963915095] 'applied index is now lower than readState.Index' (duration: 122.078497ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T22:29:19.589811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.01348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-09T22:29:19.589832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.313735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-09T22:29:19.589899Z","caller":"traceutil/trace.go:171","msg":"trace[1215987997] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1053; }","duration":"122.439398ms","start":"2026-04-09T22:29:19.467443Z","end":"2026-04-09T22:29:19.589882Z","steps":["trace[1215987997] 'agreement among raft nodes before linearized reading' (duration: 122.317234ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:29:19.589856Z","caller":"traceutil/trace.go:171","msg":"trace[1705038964] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1053; }","duration":"362.098223ms","start":"2026-04-09T22:29:19.227745Z","end":"2026-04-09T22:29:19.589843Z","steps":["trace[1705038964] 'agreement among raft nodes before linearized reading' (duration: 361.858185ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:29:19.589998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.616549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-04-09T22:29:19.590001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:29:19.227734Z","time spent":"362.256098ms","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-09T22:29:19.590039Z","caller":"traceutil/trace.go:171","msg":"trace[837744515] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1053; }","duration":"362.703732ms","start":"2026-04-09T22:29:19.227324Z","end":"2026-04-09T22:29:19.590028Z","steps":["trace[837744515] 'agreement among raft nodes before linearized reading' (duration: 362.567018ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:29:19.590073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:29:19.227303Z","time spent":"362.762754ms","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-09T22:29:29.788634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.124835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-09T22:29:29.788622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.318413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T22:29:29.788693Z","caller":"traceutil/trace.go:171","msg":"trace[1480889140] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1136; }","duration":"100.226748ms","start":"2026-04-09T22:29:29.688455Z","end":"2026-04-09T22:29:29.788682Z","steps":["trace[1480889140] 'range keys from in-memory index tree' (duration: 99.982981ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:29:29.788725Z","caller":"traceutil/trace.go:171","msg":"trace[1876800672] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1136; }","duration":"107.480277ms","start":"2026-04-09T22:29:29.681219Z","end":"2026-04-09T22:29:29.788699Z","steps":["trace[1876800672] 'count revisions from in-memory index tree' (duration: 107.223589ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:29:29.788744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.071314ms","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-09T22:29:29.788826Z","caller":"traceutil/trace.go:171","msg":"trace[397196475] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1136; }","duration":"163.194448ms","start":"2026-04-09T22:29:29.625609Z","end":"2026-04-09T22:29:29.788803Z","steps":["trace[397196475] 'range keys from in-memory index tree' (duration: 162.910789ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:29:29.788944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.425654ms","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-09T22:29:29.788968Z","caller":"traceutil/trace.go:171","msg":"trace[1374204261] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1136; }","duration":"100.481746ms","start":"2026-04-09T22:29:29.688481Z","end":"2026-04-09T22:29:29.788963Z","steps":["trace[1374204261] 'range keys from in-memory index tree' (duration: 99.928788ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:29:29.940187Z","caller":"traceutil/trace.go:171","msg":"trace[1809920486] transaction","detail":"{read_only:false; response_revision:1138; number_of_response:1; }","duration":"147.524903ms","start":"2026-04-09T22:29:29.792639Z","end":"2026-04-09T22:29:29.940164Z","steps":["trace[1809920486] 'process raft request' (duration: 147.466201ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:29:29.940376Z","caller":"traceutil/trace.go:171","msg":"trace[1635238394] transaction","detail":"{read_only:false; response_revision:1137; number_of_response:1; }","duration":"147.724459ms","start":"2026-04-09T22:29:29.792638Z","end":"2026-04-09T22:29:29.940363Z","steps":["trace[1635238394] 'process raft request' (duration: 147.335267ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:29:29.942881Z","caller":"traceutil/trace.go:171","msg":"trace[310412530] transaction","detail":"{read_only:false; response_revision:1139; number_of_response:1; }","duration":"148.817254ms","start":"2026-04-09T22:29:29.794008Z","end":"2026-04-09T22:29:29.942826Z","steps":["trace[310412530] 'process raft request' (duration: 148.208064ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:29:31.356578Z","caller":"traceutil/trace.go:171","msg":"trace[811180582] transaction","detail":"{read_only:false; response_revision:1141; number_of_response:1; }","duration":"390.261265ms","start":"2026-04-09T22:29:30.966271Z","end":"2026-04-09T22:29:31.356532Z","steps":["trace[811180582] 'process raft request' (duration: 390.10155ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:29:31.35674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:29:30.966262Z","time spent":"390.396499ms","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T22:29:32.502156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.055686035s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-09T22:29:32.502215Z","caller":"traceutil/trace.go:171","msg":"trace[1250645214] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1141; }","duration":"1.055779628s","start":"2026-04-09T22:29:31.446421Z","end":"2026-04-09T22:29:32.502201Z","steps":["trace[1250645214] 'range keys from in-memory index tree' (duration: 1.055489319s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:29:32.502237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:29:31.446398Z","time spent":"1.05583409s","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-09T22:29:32.502225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.032337ms","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-09T22:29:32.502323Z","caller":"traceutil/trace.go:171","msg":"trace[567622824] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1141; }","duration":"558.14004ms","start":"2026-04-09T22:29:31.944135Z","end":"2026-04-09T22:29:32.502275Z","steps":["trace[567622824] 'range keys from in-memory index tree' (duration: 557.878362ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:29:32.502341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"725.565882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-09T22:29:32.502363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:29:31.944122Z","time spent":"558.232103ms","remote":"127.0.0.1:56442","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-09T22:29:32.50239Z","caller":"traceutil/trace.go:171","msg":"trace[1807959343] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:1141; }","duration":"725.674795ms","start":"2026-04-09T22:29:31.7767Z","end":"2026-04-09T22:29:32.502375Z","steps":["trace[1807959343] 'count revisions from in-memory index tree' (duration: 725.468349ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:29:32.502429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:29:31.776682Z","time spent":"725.736187ms","remote":"127.0.0.1:45232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true "} {"level":"warn","ts":"2026-04-09T22:29:32.502428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"556.693345ms","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-09T22:29:32.502697Z","caller":"traceutil/trace.go:171","msg":"trace[2003633909] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1141; }","duration":"556.789307ms","start":"2026-04-09T22:29:31.94568Z","end":"2026-04-09T22:29:32.502469Z","steps":["trace[2003633909] 'range keys from in-memory index tree' (duration: 556.517219ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:29:32.502708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"556.382054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-09T22:29:32.502748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:29:31.94566Z","time spent":"557.075707ms","remote":"127.0.0.1:56442","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-09T22:29:32.502779Z","caller":"traceutil/trace.go:171","msg":"trace[661288729] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1141; }","duration":"556.706255ms","start":"2026-04-09T22:29:31.946055Z","end":"2026-04-09T22:29:32.502761Z","steps":["trace[661288729] 'range keys from in-memory index tree' (duration: 556.22121ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:29:32.502821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:29:31.946041Z","time spent":"556.770856ms","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-09T22:29:32.503676Z","caller":"traceutil/trace.go:171","msg":"trace[1780145960] transaction","detail":"{read_only:false; response_revision:1142; number_of_response:1; }","duration":"229.207194ms","start":"2026-04-09T22:29:32.274447Z","end":"2026-04-09T22:29:32.503654Z","steps":["trace[1780145960] 'process raft request' (duration: 228.877003ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:29:47.57563Z","caller":"traceutil/trace.go:171","msg":"trace[268755516] linearizableReadLoop","detail":"{readStateIndex:1262; appliedIndex:1261; }","duration":"221.316632ms","start":"2026-04-09T22:29:47.354293Z","end":"2026-04-09T22:29:47.575609Z","steps":["trace[268755516] 'read index received' (duration: 221.095755ms)","trace[268755516] 'applied index is now lower than readState.Index' (duration: 219.627µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T22:29:47.575752Z","caller":"traceutil/trace.go:171","msg":"trace[931677971] transaction","detail":"{read_only:false; response_revision:1208; number_of_response:1; }","duration":"409.932092ms","start":"2026-04-09T22:29:47.165809Z","end":"2026-04-09T22:29:47.575742Z","steps":["trace[931677971] 'process raft request' (duration: 409.600712ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:29:47.575857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:29:47.165799Z","time spent":"409.983605ms","remote":"127.0.0.1:56344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5456,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T22:29:47.575992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.692864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T22:29:47.576033Z","caller":"traceutil/trace.go:171","msg":"trace[266677693] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1208; }","duration":"221.758526ms","start":"2026-04-09T22:29:47.354261Z","end":"2026-04-09T22:29:47.576019Z","steps":["trace[266677693] 'agreement among raft nodes before linearized reading' (duration: 221.698084ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:29:47.576423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.75489ms","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-09T22:29:47.576469Z","caller":"traceutil/trace.go:171","msg":"trace[609012222] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1208; }","duration":"152.822121ms","start":"2026-04-09T22:29:47.423636Z","end":"2026-04-09T22:29:47.576459Z","steps":["trace[609012222] 'agreement among raft nodes before linearized reading' (duration: 152.686567ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:29:47.752328Z","caller":"traceutil/trace.go:171","msg":"trace[1079494907] transaction","detail":"{read_only:false; response_revision:1210; number_of_response:1; }","duration":"162.945861ms","start":"2026-04-09T22:29:47.589359Z","end":"2026-04-09T22:29:47.752305Z","steps":["trace[1079494907] 'process raft request' (duration: 162.797396ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:29:47.813444Z","caller":"traceutil/trace.go:171","msg":"trace[1396197261] transaction","detail":"{read_only:false; response_revision:1211; number_of_response:1; }","duration":"129.502506ms","start":"2026-04-09T22:29:47.683918Z","end":"2026-04-09T22:29:47.813421Z","steps":["trace[1396197261] 'process raft request' (duration: 106.126099ms)","trace[1396197261] 'compare' (duration: 23.269524ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T22:29:49.052258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"668.783079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T22:29:49.052335Z","caller":"traceutil/trace.go:171","msg":"trace[2107534557] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1214; }","duration":"668.900723ms","start":"2026-04-09T22:29:48.383412Z","end":"2026-04-09T22:29:49.052313Z","steps":["trace[2107534557] 'range keys from in-memory index tree' (duration: 668.708347ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:29:49.052363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:29:48.383396Z","time spent":"668.960375ms","remote":"127.0.0.1:56172","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-09T22:29:49.052444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.107431ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T22:29:49.052526Z","caller":"traceutil/trace.go:171","msg":"trace[228268405] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1214; }","duration":"373.192934ms","start":"2026-04-09T22:29:48.679322Z","end":"2026-04-09T22:29:49.052515Z","steps":["trace[228268405] 'range keys from in-memory index tree' (duration: 373.096731ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:29:49.125139Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":988150291329709439,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-09T22:29:49.62556Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":988150291329709439,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-09T22:29:49.716141Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.098330911s","expected-duration":"1s"} {"level":"info","ts":"2026-04-09T22:29:49.716607Z","caller":"traceutil/trace.go:171","msg":"trace[909623187] transaction","detail":"{read_only:false; response_revision:1215; number_of_response:1; }","duration":"1.098869348s","start":"2026-04-09T22:29:48.617711Z","end":"2026-04-09T22:29:49.71658Z","steps":["trace[909623187] 'process raft request' (duration: 1.098715323s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:29:49.71677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:29:48.617688Z","time spent":"1.098983441s","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T22:29:49.753664Z","caller":"traceutil/trace.go:171","msg":"trace[1679818514] transaction","detail":"{read_only:false; response_revision:1216; number_of_response:1; }","duration":"984.640744ms","start":"2026-04-09T22:29:48.769001Z","end":"2026-04-09T22:29:49.753642Z","steps":["trace[1679818514] 'process raft request' (duration: 984.282053ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:29:49.7538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:29:48.768976Z","time spent":"984.747728ms","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T22:29:50.090548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.114855ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-09T22:29:50.09064Z","caller":"traceutil/trace.go:171","msg":"trace[1653026673] linearizableReadLoop","detail":"{readStateIndex:1271; appliedIndex:1268; }","duration":"1.465579677s","start":"2026-04-09T22:29:48.625044Z","end":"2026-04-09T22:29:50.090623Z","steps":["trace[1653026673] 'read index received' (duration: 1.091371332s)","trace[1653026673] 'applied index is now lower than readState.Index' (duration: 374.206765ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T22:29:50.090833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.465787323s","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-09T22:29:50.090866Z","caller":"traceutil/trace.go:171","msg":"trace[702692144] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1216; }","duration":"1.465865506s","start":"2026-04-09T22:29:48.624992Z","end":"2026-04-09T22:29:50.090858Z","steps":["trace[702692144] 'agreement among raft nodes before linearized reading' (duration: 1.465726461s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:29:50.090905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:29:48.624939Z","time spent":"1.465957959s","remote":"127.0.0.1:56442","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-09T22:29:50.091037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.463529373s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-09T22:29:50.091108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.463505991s","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-09T22:29:50.09114Z","caller":"traceutil/trace.go:171","msg":"trace[1449704026] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1216; }","duration":"1.463638855s","start":"2026-04-09T22:29:48.627455Z","end":"2026-04-09T22:29:50.091094Z","steps":["trace[1449704026] 'agreement among raft nodes before linearized reading' (duration: 1.463441629s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:29:50.09115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.283409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-09T22:29:50.091187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:29:48.627443Z","time spent":"1.463735459s","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-09T22:29:50.091179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.463611954s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-09T22:29:50.091186Z","caller":"traceutil/trace.go:171","msg":"trace[21764570] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1216; }","duration":"1.463607554s","start":"2026-04-09T22:29:48.627555Z","end":"2026-04-09T22:29:50.091162Z","steps":["trace[21764570] 'agreement among raft nodes before linearized reading' (duration: 1.463390677s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:29:50.091027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.037241723s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T22:29:50.091225Z","caller":"traceutil/trace.go:171","msg":"trace[432293745] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1216; }","duration":"407.412493ms","start":"2026-04-09T22:29:49.683796Z","end":"2026-04-09T22:29:50.091208Z","steps":["trace[432293745] 'agreement among raft nodes before linearized reading' (duration: 407.153125ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:29:50.091237Z","caller":"traceutil/trace.go:171","msg":"trace[1508362774] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1216; }","duration":"1.463702937s","start":"2026-04-09T22:29:48.62752Z","end":"2026-04-09T22:29:50.091223Z","steps":["trace[1508362774] 'agreement among raft nodes before linearized reading' (duration: 1.463573173s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:29:50.091251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:29:48.627533Z","time spent":"1.463704368s","remote":"127.0.0.1:56442","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-09T22:29:50.091268Z","caller":"traceutil/trace.go:171","msg":"trace[519726156] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1216; }","duration":"1.037553943s","start":"2026-04-09T22:29:49.053704Z","end":"2026-04-09T22:29:50.091258Z","steps":["trace[519726156] 'agreement among raft nodes before linearized reading' (duration: 1.037240213s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:29:50.091273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:29:49.683774Z","time spent":"407.491606ms","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-09T22:29:50.091281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:29:48.627491Z","time spent":"1.46377913s","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-09T22:29:50.091308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:29:49.053685Z","time spent":"1.037610585s","remote":"127.0.0.1:56186","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-09T22:29:50.333092Z","caller":"traceutil/trace.go:171","msg":"trace[1223065128] transaction","detail":"{read_only:false; response_revision:1218; number_of_response:1; }","duration":"236.677197ms","start":"2026-04-09T22:29:50.096358Z","end":"2026-04-09T22:29:50.333035Z","steps":["trace[1223065128] 'process raft request' (duration: 235.954274ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:29:50.333297Z","caller":"traceutil/trace.go:171","msg":"trace[1508402860] linearizableReadLoop","detail":"{readStateIndex:1276; appliedIndex:1271; }","duration":"233.746874ms","start":"2026-04-09T22:29:50.099524Z","end":"2026-04-09T22:29:50.333271Z","steps":["trace[1508402860] 'read index received' (duration: 43.321467ms)","trace[1508402860] 'applied index is now lower than readState.Index' (duration: 190.424047ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T22:29:50.333331Z","caller":"traceutil/trace.go:171","msg":"trace[1959351756] transaction","detail":"{read_only:false; response_revision:1220; number_of_response:1; }","duration":"236.503261ms","start":"2026-04-09T22:29:50.096798Z","end":"2026-04-09T22:29:50.333301Z","steps":["trace[1959351756] 'process raft request' (duration: 236.382398ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:29:50.333318Z","caller":"traceutil/trace.go:171","msg":"trace[810176015] transaction","detail":"{read_only:false; response_revision:1219; number_of_response:1; }","duration":"236.543863ms","start":"2026-04-09T22:29:50.096729Z","end":"2026-04-09T22:29:50.333273Z","steps":["trace[810176015] 'process raft request' (duration: 236.398148ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:29:50.333387Z","caller":"traceutil/trace.go:171","msg":"trace[222302613] transaction","detail":"{read_only:false; response_revision:1221; number_of_response:1; }","duration":"236.489711ms","start":"2026-04-09T22:29:50.096845Z","end":"2026-04-09T22:29:50.333335Z","steps":["trace[222302613] 'process raft request' (duration: 236.390138ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:29:50.333499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.951081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T22:29:50.333558Z","caller":"traceutil/trace.go:171","msg":"trace[1939934844] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1221; }","duration":"234.055244ms","start":"2026-04-09T22:29:50.099492Z","end":"2026-04-09T22:29:50.333547Z","steps":["trace[1939934844] 'agreement among raft nodes before linearized reading' (duration: 233.875648ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:29:51.881891Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":988150291329709454,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-09T22:29:52.148293Z","caller":"traceutil/trace.go:171","msg":"trace[1693251157] transaction","detail":"{read_only:false; response_revision:1224; number_of_response:1; }","duration":"807.747061ms","start":"2026-04-09T22:29:51.340515Z","end":"2026-04-09T22:29:52.148263Z","steps":["trace[1693251157] 'process raft request' (duration: 743.442202ms)","trace[1693251157] 'compare' (duration: 64.140494ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T22:29:52.148438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:29:51.340501Z","time spent":"807.872776ms","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-09T22:29:53.382054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000378338s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-09T22:29:53.38214Z","caller":"traceutil/trace.go:171","msg":"trace[517883128] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000517362s","start":"2026-04-09T22:29:51.381603Z","end":"2026-04-09T22:29:53.382121Z","steps":["trace[517883128] 'agreement among raft nodes before linearized reading' (duration: 2.000409209s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:29:53.3822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:29:51.381586Z","time spent":"2.000604245s","remote":"127.0.0.1:56172","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-09T22:29:53.39396Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.309976408s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-09T22:29:53.588056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.858126ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-09T22:29:53.588156Z","caller":"traceutil/trace.go:171","msg":"trace[1864244880] linearizableReadLoop","detail":"{readStateIndex:1280; appliedIndex:1278; }","duration":"2.2064961s","start":"2026-04-09T22:29:51.381644Z","end":"2026-04-09T22:29:53.58814Z","steps":["trace[1864244880] 'read index received' (duration: 702.332056ms)","trace[1864244880] 'applied index is now lower than readState.Index' (duration: 1.504162684s)"],"step_count":2} {"level":"info","ts":"2026-04-09T22:29:53.588387Z","caller":"traceutil/trace.go:171","msg":"trace[1885288463] transaction","detail":"{read_only:false; response_revision:1225; number_of_response:1; }","duration":"1.86696609s","start":"2026-04-09T22:29:51.721408Z","end":"2026-04-09T22:29:53.588374Z","steps":["trace[1885288463] 'process raft request' (duration: 1.672701061s)","trace[1885288463] 'compare' (duration: 192.923876ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T22:29:53.58846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:29:51.72139Z","time spent":"1.867037791s","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T22:29:53.588894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.541768551s","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":"warn","ts":"2026-04-09T22:29:53.588923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.053202877s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-09T22:29:53.588947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.251011758s","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-09T22:29:53.588966Z","caller":"traceutil/trace.go:171","msg":"trace[563628344] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:1227; }","duration":"1.05330472s","start":"2026-04-09T22:29:52.53565Z","end":"2026-04-09T22:29:53.588954Z","steps":["trace[563628344] 'agreement among raft nodes before linearized reading' (duration: 1.053188066s)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:29:53.588994Z","caller":"traceutil/trace.go:171","msg":"trace[2030992586] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1227; }","duration":"1.251099441s","start":"2026-04-09T22:29:52.337883Z","end":"2026-04-09T22:29:53.588983Z","steps":["trace[2030992586] 'agreement among raft nodes before linearized reading' (duration: 1.250968907s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:29:53.589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:29:52.535625Z","time spent":"1.053366622s","remote":"127.0.0.1:56602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":4,"response size":30,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"warn","ts":"2026-04-09T22:29:53.589023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:29:52.337866Z","time spent":"1.251152352s","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-09T22:29:53.589048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.251964838s","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-09T22:29:53.589072Z","caller":"traceutil/trace.go:171","msg":"trace[362526585] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1227; }","duration":"1.25202156s","start":"2026-04-09T22:29:52.337045Z","end":"2026-04-09T22:29:53.589066Z","steps":["trace[362526585] 'agreement among raft nodes before linearized reading' (duration: 1.251937807s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:29:53.589097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:29:52.337024Z","time spent":"1.252068801s","remote":"127.0.0.1:56442","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-09T22:29:53.589166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.250781731s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-09T22:29:53.589187Z","caller":"traceutil/trace.go:171","msg":"trace[539780611] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1227; }","duration":"1.250842552s","start":"2026-04-09T22:29:52.33834Z","end":"2026-04-09T22:29:53.589182Z","steps":["trace[539780611] 'agreement among raft nodes before linearized reading' (duration: 1.2507739s)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:29:53.589185Z","caller":"traceutil/trace.go:171","msg":"trace[1868357867] transaction","detail":"{read_only:false; response_revision:1226; number_of_response:1; }","duration":"1.434829787s","start":"2026-04-09T22:29:52.154344Z","end":"2026-04-09T22:29:53.589173Z","steps":["trace[1868357867] 'process raft request' (duration: 1.434322601s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:29:53.589201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:29:52.33832Z","time spent":"1.250877684s","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-09T22:29:53.589237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:29:52.154326Z","time spent":"1.434884949s","remote":"127.0.0.1:56240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":773,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-09T22:29:53.589258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.250966717s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-09T22:29:53.589273Z","caller":"traceutil/trace.go:171","msg":"trace[420041923] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1227; }","duration":"1.251013858s","start":"2026-04-09T22:29:52.338253Z","end":"2026-04-09T22:29:53.589267Z","steps":["trace[420041923] 'agreement among raft nodes before linearized reading' (duration: 1.250980677s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:29:53.589287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:29:52.338238Z","time spent":"1.251044788s","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-09T22:29:53.588963Z","caller":"traceutil/trace.go:171","msg":"trace[2044519554] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1227; }","duration":"1.541856754s","start":"2026-04-09T22:29:52.04709Z","end":"2026-04-09T22:29:53.588947Z","steps":["trace[2044519554] 'agreement among raft nodes before linearized reading' (duration: 1.541691689s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:29:53.589327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:29:52.047027Z","time spent":"1.542293138s","remote":"127.0.0.1:45216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"info","ts":"2026-04-09T22:29:53.589381Z","caller":"traceutil/trace.go:171","msg":"trace[1666214756] transaction","detail":"{read_only:false; response_revision:1227; number_of_response:1; }","duration":"1.265938498s","start":"2026-04-09T22:29:52.323433Z","end":"2026-04-09T22:29:53.589372Z","steps":["trace[1666214756] 'process raft request' (duration: 1.265317039s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:29:53.589406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.435685144s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-04-09T22:29:53.589427Z","caller":"traceutil/trace.go:171","msg":"trace[1444108913] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1227; }","duration":"1.435741356s","start":"2026-04-09T22:29:52.15368Z","end":"2026-04-09T22:29:53.589421Z","steps":["trace[1444108913] 'agreement among raft nodes before linearized reading' (duration: 1.435681404s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:29:53.589448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:29:52.153663Z","time spent":"1.435778366s","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":497,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-09T22:29:53.589443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:29:52.323394Z","time spent":"1.266024122s","remote":"127.0.0.1:56240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-09T22:29:53.589549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.442100527s","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-09T22:29:53.58957Z","caller":"traceutil/trace.go:171","msg":"trace[2051622361] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1227; }","duration":"1.442152708s","start":"2026-04-09T22:29:52.147412Z","end":"2026-04-09T22:29:53.589565Z","steps":["trace[2051622361] 'agreement among raft nodes before linearized reading' (duration: 1.442103047s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:29:53.589583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:29:52.147397Z","time spent":"1.442183469s","remote":"127.0.0.1:56442","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-09T22:29:53.589722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.654999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T22:29:53.589761Z","caller":"traceutil/trace.go:171","msg":"trace[4946558] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1227; }","duration":"206.734492ms","start":"2026-04-09T22:29:53.383018Z","end":"2026-04-09T22:29:53.589752Z","steps":["trace[4946558] 'agreement among raft nodes before linearized reading' (duration: 206.66968ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:29:53.589777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.323694ms","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-09T22:29:53.589795Z","caller":"traceutil/trace.go:171","msg":"trace[1010448031] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:1227; }","duration":"480.372045ms","start":"2026-04-09T22:29:53.109418Z","end":"2026-04-09T22:29:53.58979Z","steps":["trace[1010448031] 'agreement among raft nodes before linearized reading' (duration: 480.340735ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:29:53.589816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:29:53.1094Z","time spent":"480.410866ms","remote":"127.0.0.1:45242","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-09T22:29:53.589907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"761.99611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-09T22:29:53.589926Z","caller":"traceutil/trace.go:171","msg":"trace[1990283611] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1227; }","duration":"762.047752ms","start":"2026-04-09T22:29:52.827873Z","end":"2026-04-09T22:29:53.589921Z","steps":["trace[1990283611] 'agreement among raft nodes before linearized reading' (duration: 762.0097ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:29:53.589939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:29:52.827855Z","time spent":"762.080462ms","remote":"127.0.0.1:56222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":31,"response size":30,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"warn","ts":"2026-04-09T22:29:53.870845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.363454ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-09T22:29:53.870982Z","caller":"traceutil/trace.go:171","msg":"trace[1547916170] transaction","detail":"{read_only:false; response_revision:1228; number_of_response:1; }","duration":"276.909526ms","start":"2026-04-09T22:29:53.594047Z","end":"2026-04-09T22:29:53.870956Z","steps":["trace[1547916170] 'process raft request' (duration: 126.322465ms)","trace[1547916170] 'compare' (duration: 150.081675ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T22:29:53.939442Z","caller":"traceutil/trace.go:171","msg":"trace[1586605863] linearizableReadLoop","detail":"{readStateIndex:1288; appliedIndex:1282; }","duration":"337.827859ms","start":"2026-04-09T22:29:53.60159Z","end":"2026-04-09T22:29:53.939418Z","steps":["trace[1586605863] 'read index received' (duration: 118.926533ms)","trace[1586605863] 'applied index is now lower than readState.Index' (duration: 218.900266ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T22:29:53.939787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.165569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T22:29:53.939833Z","caller":"traceutil/trace.go:171","msg":"trace[2013385388] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1234; }","duration":"338.247762ms","start":"2026-04-09T22:29:53.601574Z","end":"2026-04-09T22:29:53.939821Z","steps":["trace[2013385388] 'agreement among raft nodes before linearized reading' (duration: 337.937602ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:29:53.939871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:29:53.601569Z","time spent":"338.293923ms","remote":"127.0.0.1:56172","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-09T22:29:53.94035Z","caller":"traceutil/trace.go:171","msg":"trace[1674178647] transaction","detail":"{read_only:false; response_revision:1229; number_of_response:1; }","duration":"345.599053ms","start":"2026-04-09T22:29:53.594735Z","end":"2026-04-09T22:29:53.940334Z","steps":["trace[1674178647] 'process raft request' (duration: 344.359924ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:29:53.940453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:29:53.59472Z","time spent":"345.671136ms","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T22:29:53.940605Z","caller":"traceutil/trace.go:171","msg":"trace[1752992712] transaction","detail":"{read_only:false; response_revision:1230; number_of_response:1; }","duration":"345.81284ms","start":"2026-04-09T22:29:53.594782Z","end":"2026-04-09T22:29:53.940594Z","steps":["trace[1752992712] 'process raft request' (duration: 344.440757ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:29:53.940665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:29:53.594778Z","time spent":"345.856221ms","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T22:29:53.940819Z","caller":"traceutil/trace.go:171","msg":"trace[1640966261] transaction","detail":"{read_only:false; response_revision:1234; number_of_response:1; }","duration":"326.794339ms","start":"2026-04-09T22:29:53.614002Z","end":"2026-04-09T22:29:53.940797Z","steps":["trace[1640966261] 'process raft request' (duration: 325.381515ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:29:53.940938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:29:53.613997Z","time spent":"326.880753ms","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T22:29:53.941104Z","caller":"traceutil/trace.go:171","msg":"trace[1521274351] transaction","detail":"{read_only:false; response_revision:1231; number_of_response:1; }","duration":"345.346805ms","start":"2026-04-09T22:29:53.595745Z","end":"2026-04-09T22:29:53.941092Z","steps":["trace[1521274351] 'process raft request' (duration: 343.532507ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:29:53.941164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:29:53.595739Z","time spent":"345.395907ms","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T22:29:53.941272Z","caller":"traceutil/trace.go:171","msg":"trace[195555456] transaction","detail":"{read_only:false; response_revision:1232; number_of_response:1; }","duration":"343.648852ms","start":"2026-04-09T22:29:53.597614Z","end":"2026-04-09T22:29:53.941263Z","steps":["trace[195555456] 'process raft request' (duration: 341.696421ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:29:53.941342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:29:53.597589Z","time spent":"343.703373ms","remote":"127.0.0.1:56240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":836,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-09T22:29:53.941725Z","caller":"traceutil/trace.go:171","msg":"trace[63013053] transaction","detail":"{read_only:false; response_revision:1233; number_of_response:1; }","duration":"340.482762ms","start":"2026-04-09T22:29:53.601223Z","end":"2026-04-09T22:29:53.941706Z","steps":["trace[63013053] 'process raft request' (duration: 338.131498ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:29:53.941807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:29:53.601216Z","time spent":"340.546434ms","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T22:29:54.095314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.571714ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-09T22:29:54.095642Z","caller":"traceutil/trace.go:171","msg":"trace[32928819] transaction","detail":"{read_only:false; response_revision:1235; number_of_response:1; }","duration":"149.144425ms","start":"2026-04-09T22:29:53.946468Z","end":"2026-04-09T22:29:54.095612Z","steps":["trace[32928819] 'process raft request' (duration: 47.175848ms)","trace[32928819] 'compare' (duration: 101.338187ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T22:29:54.513449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.224617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T22:29:54.513502Z","caller":"traceutil/trace.go:171","msg":"trace[1728295056] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1236; }","duration":"129.33118ms","start":"2026-04-09T22:29:54.384158Z","end":"2026-04-09T22:29:54.513489Z","steps":["trace[1728295056] 'range keys from in-memory index tree' (duration: 129.128034ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:29:56.449978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.112791ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-09T22:29:56.450074Z","caller":"traceutil/trace.go:171","msg":"trace[1417750860] linearizableReadLoop","detail":"{readStateIndex:1294; appliedIndex:1293; }","duration":"504.766294ms","start":"2026-04-09T22:29:55.945275Z","end":"2026-04-09T22:29:56.450041Z","steps":["trace[1417750860] 'read index received' (duration: 176.385174ms)","trace[1417750860] 'applied index is now lower than readState.Index' (duration: 328.38041ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T22:29:56.450134Z","caller":"traceutil/trace.go:171","msg":"trace[1703503984] transaction","detail":"{read_only:false; response_revision:1238; number_of_response:1; }","duration":"634.791356ms","start":"2026-04-09T22:29:55.815318Z","end":"2026-04-09T22:29:56.45011Z","steps":["trace[1703503984] 'process raft request' (duration: 306.436097ms)","trace[1703503984] 'compare' (duration: 327.810752ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T22:29:56.450194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.919849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-09T22:29:56.450222Z","caller":"traceutil/trace.go:171","msg":"trace[1547897107] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1238; }","duration":"504.985092ms","start":"2026-04-09T22:29:55.945227Z","end":"2026-04-09T22:29:56.450213Z","steps":["trace[1547897107] 'agreement among raft nodes before linearized reading' (duration: 504.901769ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:29:56.45024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:29:55.815293Z","time spent":"634.89425ms","remote":"127.0.0.1:56442","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-09T22:29:56.450307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.888808ms","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-09T22:29:56.45033Z","caller":"traceutil/trace.go:171","msg":"trace[1046256890] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1238; }","duration":"504.926109ms","start":"2026-04-09T22:29:55.945398Z","end":"2026-04-09T22:29:56.450324Z","steps":["trace[1046256890] 'agreement among raft nodes before linearized reading' (duration: 504.872507ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:29:56.450247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:29:55.945166Z","time spent":"505.075895ms","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-09T22:29:56.450349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:29:55.945392Z","time spent":"504.953431ms","remote":"127.0.0.1:56442","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-09T22:29:56.450704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.441454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-09T22:29:56.450792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.272695ms","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-09T22:29:56.450827Z","caller":"traceutil/trace.go:171","msg":"trace[764921328] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1238; }","duration":"501.326626ms","start":"2026-04-09T22:29:55.94949Z","end":"2026-04-09T22:29:56.450817Z","steps":["trace[764921328] 'agreement among raft nodes before linearized reading' (duration: 501.237893ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:29:56.45081Z","caller":"traceutil/trace.go:171","msg":"trace[1257500048] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1238; }","duration":"504.62874ms","start":"2026-04-09T22:29:55.946162Z","end":"2026-04-09T22:29:56.450791Z","steps":["trace[1257500048] 'agreement among raft nodes before linearized reading' (duration: 504.367812ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:29:56.450865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:29:55.949481Z","time spent":"501.377718ms","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-09T22:29:56.450884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:29:55.946148Z","time spent":"504.723073ms","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-09T22:29:56.450924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.677772ms","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-09T22:29:56.45102Z","caller":"traceutil/trace.go:171","msg":"trace[1038468638] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1238; }","duration":"504.809016ms","start":"2026-04-09T22:29:55.946185Z","end":"2026-04-09T22:29:56.450994Z","steps":["trace[1038468638] 'agreement among raft nodes before linearized reading' (duration: 504.563849ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:29:56.451074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:29:55.946172Z","time spent":"504.891338ms","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-09T22:29:56.698798Z","caller":"traceutil/trace.go:171","msg":"trace[942837315] transaction","detail":"{read_only:false; response_revision:1239; number_of_response:1; }","duration":"243.520372ms","start":"2026-04-09T22:29:56.455252Z","end":"2026-04-09T22:29:56.698772Z","steps":["trace[942837315] 'process raft request' (duration: 195.143446ms)","trace[942837315] 'compare' (duration: 48.269813ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T22:29:57.02809Z","caller":"traceutil/trace.go:171","msg":"trace[994671786] transaction","detail":"{read_only:false; response_revision:1240; number_of_response:1; }","duration":"572.042537ms","start":"2026-04-09T22:29:56.456016Z","end":"2026-04-09T22:29:57.028058Z","steps":["trace[994671786] 'process raft request' (duration: 548.702561ms)","trace[994671786] 'compare' (duration: 22.910312ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T22:29:57.028246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:29:56.456004Z","time spent":"572.165211ms","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T22:29:57.028336Z","caller":"traceutil/trace.go:171","msg":"trace[298044132] transaction","detail":"{read_only:false; response_revision:1241; number_of_response:1; }","duration":"572.240663ms","start":"2026-04-09T22:29:56.456078Z","end":"2026-04-09T22:29:57.028318Z","steps":["trace[298044132] 'process raft request' (duration: 571.889692ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:29:57.028382Z","caller":"traceutil/trace.go:171","msg":"trace[2055921277] transaction","detail":"{read_only:false; response_revision:1243; number_of_response:1; }","duration":"570.849199ms","start":"2026-04-09T22:29:56.457503Z","end":"2026-04-09T22:29:57.028352Z","steps":["trace[2055921277] 'process raft request' (duration: 570.803308ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:29:57.028415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:29:56.456071Z","time spent":"572.300655ms","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T22:29:57.028522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:29:56.457484Z","time spent":"570.962043ms","remote":"127.0.0.1:56442","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-09T22:29:57.028614Z","caller":"traceutil/trace.go:171","msg":"trace[1505606232] transaction","detail":"{read_only:false; response_revision:1242; number_of_response:1; }","duration":"572.46442ms","start":"2026-04-09T22:29:56.456139Z","end":"2026-04-09T22:29:57.028603Z","steps":["trace[1505606232] 'process raft request' (duration: 572.044877ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:29:57.028679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:29:56.456117Z","time spent":"572.526332ms","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T22:30:02.144399Z","caller":"traceutil/trace.go:171","msg":"trace[218489900] transaction","detail":"{read_only:false; response_revision:1287; number_of_response:1; }","duration":"755.835666ms","start":"2026-04-09T22:30:01.388538Z","end":"2026-04-09T22:30:02.144374Z","steps":["trace[218489900] 'process raft request' (duration: 755.679961ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:30:02.144662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:30:01.388505Z","time spent":"755.96294ms","remote":"127.0.0.1:56356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13708,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T22:30:02.63585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.633355ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-09T22:30:02.636014Z","caller":"traceutil/trace.go:171","msg":"trace[1800541490] transaction","detail":"{read_only:false; response_revision:1288; number_of_response:1; }","duration":"952.351844ms","start":"2026-04-09T22:30:01.683629Z","end":"2026-04-09T22:30:02.635981Z","steps":["trace[1800541490] 'process raft request' (duration: 851.459971ms)","trace[1800541490] 'compare' (duration: 100.4719ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T22:30:02.636187Z","caller":"traceutil/trace.go:171","msg":"trace[1199930707] linearizableReadLoop","detail":"{readStateIndex:1345; appliedIndex:1343; }","duration":"635.371644ms","start":"2026-04-09T22:30:02.000799Z","end":"2026-04-09T22:30:02.63617Z","steps":["trace[1199930707] 'read index received' (duration: 143.450936ms)","trace[1199930707] 'applied index is now lower than readState.Index' (duration: 491.919088ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T22:30:02.63635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"635.464277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-09T22:30:02.636961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:30:01.683602Z","time spent":"953.216302ms","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T22:30:02.637079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.524814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T22:30:02.637112Z","caller":"traceutil/trace.go:171","msg":"trace[1340582483] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1291; }","duration":"361.608488ms","start":"2026-04-09T22:30:02.275496Z","end":"2026-04-09T22:30:02.637104Z","steps":["trace[1340582483] 'agreement among raft nodes before linearized reading' (duration: 361.547866ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:30:02.637148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:30:02.275475Z","time spent":"361.663439ms","remote":"127.0.0.1:45256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"info","ts":"2026-04-09T22:30:02.637013Z","caller":"traceutil/trace.go:171","msg":"trace[24802384] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:1290; }","duration":"636.236801ms","start":"2026-04-09T22:30:02.000765Z","end":"2026-04-09T22:30:02.637002Z","steps":["trace[24802384] 'agreement among raft nodes before linearized reading' (duration: 635.447366ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:30:02.637334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:30:02.000748Z","time spent":"636.555982ms","remote":"127.0.0.1:56618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"warn","ts":"2026-04-09T22:30:02.637384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.073905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T22:30:02.637418Z","caller":"traceutil/trace.go:171","msg":"trace[1365722069] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1291; }","duration":"254.108776ms","start":"2026-04-09T22:30:02.383302Z","end":"2026-04-09T22:30:02.63741Z","steps":["trace[1365722069] 'agreement among raft nodes before linearized reading' (duration: 254.045314ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:30:02.636582Z","caller":"traceutil/trace.go:171","msg":"trace[1822109037] transaction","detail":"{read_only:false; response_revision:1289; number_of_response:1; }","duration":"486.771226ms","start":"2026-04-09T22:30:02.149797Z","end":"2026-04-09T22:30:02.636568Z","steps":["trace[1822109037] 'process raft request' (duration: 486.175497ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:30:02.637569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:30:02.149775Z","time spent":"487.753887ms","remote":"127.0.0.1:56334","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":926,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T22:30:02.637659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.83918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T22:30:02.637731Z","caller":"traceutil/trace.go:171","msg":"trace[1336017697] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:1291; }","duration":"300.913353ms","start":"2026-04-09T22:30:02.336798Z","end":"2026-04-09T22:30:02.637712Z","steps":["trace[1336017697] 'agreement among raft nodes before linearized reading' (duration: 300.807519ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:30:02.636619Z","caller":"traceutil/trace.go:171","msg":"trace[2074694581] transaction","detail":"{read_only:false; response_revision:1290; number_of_response:1; }","duration":"485.980591ms","start":"2026-04-09T22:30:02.150622Z","end":"2026-04-09T22:30:02.636603Z","steps":["trace[2074694581] 'process raft request' (duration: 485.554047ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:30:02.637809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:30:02.336717Z","time spent":"301.074387ms","remote":"127.0.0.1:45290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true "} {"level":"warn","ts":"2026-04-09T22:30:02.637868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:30:02.15059Z","time spent":"487.22708ms","remote":"127.0.0.1:56446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T22:30:02.636334Z","caller":"traceutil/trace.go:171","msg":"trace[184868980] transaction","detail":"{read_only:false; response_revision:1291; number_of_response:1; }","duration":"481.963715ms","start":"2026-04-09T22:30:02.154348Z","end":"2026-04-09T22:30:02.636312Z","steps":["trace[184868980] 'process raft request' (duration: 481.890732ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:30:02.638185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:30:02.154308Z","time spent":"483.74553ms","remote":"127.0.0.1:56640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10921,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T22:30:02.877327Z","caller":"traceutil/trace.go:171","msg":"trace[1279746812] linearizableReadLoop","detail":"{readStateIndex:1349; appliedIndex:1348; }","duration":"157.074985ms","start":"2026-04-09T22:30:02.720217Z","end":"2026-04-09T22:30:02.877292Z","steps":["trace[1279746812] 'read index received' (duration: 104.383703ms)","trace[1279746812] 'applied index is now lower than readState.Index' (duration: 52.689952ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T22:30:02.87764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.380465ms","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-09T22:30:02.877685Z","caller":"traceutil/trace.go:171","msg":"trace[1381734262] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1292; }","duration":"157.52929ms","start":"2026-04-09T22:30:02.720144Z","end":"2026-04-09T22:30:02.877674Z","steps":["trace[1381734262] 'agreement among raft nodes before linearized reading' (duration: 157.272372ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:30:02.877818Z","caller":"traceutil/trace.go:171","msg":"trace[756653749] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"228.481268ms","start":"2026-04-09T22:30:02.649289Z","end":"2026-04-09T22:30:02.87777Z","steps":["trace[756653749] 'process raft request' (duration: 175.227048ms)","trace[756653749] 'compare' (duration: 52.589879ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T22:33:11.940874Z","caller":"traceutil/trace.go:171","msg":"trace[777902150] transaction","detail":"{read_only:false; response_revision:2192; number_of_response:1; }","duration":"187.707279ms","start":"2026-04-09T22:33:11.753136Z","end":"2026-04-09T22:33:11.940844Z","steps":["trace[777902150] 'process raft request' (duration: 187.527113ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:33:12.37739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.915295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-09T22:33:12.377475Z","caller":"traceutil/trace.go:171","msg":"trace[300714971] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2192; }","duration":"341.04425ms","start":"2026-04-09T22:33:12.03641Z","end":"2026-04-09T22:33:12.377455Z","steps":["trace[300714971] 'range keys from in-memory index tree' (duration: 340.747419ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:33:12.377515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:33:12.03639Z","time spent":"341.116461ms","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-09T22:33:12.377778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.299938ms","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-09T22:33:12.377819Z","caller":"traceutil/trace.go:171","msg":"trace[1512352678] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2192; }","duration":"341.38417ms","start":"2026-04-09T22:33:12.036426Z","end":"2026-04-09T22:33:12.37781Z","steps":["trace[1512352678] 'range keys from in-memory index tree' (duration: 341.146722ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:33:12.377847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:33:12.036403Z","time spent":"341.438272ms","remote":"127.0.0.1:56442","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-09T22:33:12.378016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.436941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-09T22:33:12.37805Z","caller":"traceutil/trace.go:171","msg":"trace[1232246561] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2192; }","duration":"341.487423ms","start":"2026-04-09T22:33:12.036555Z","end":"2026-04-09T22:33:12.378042Z","steps":["trace[1232246561] 'range keys from in-memory index tree' (duration: 341.359879ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:33:12.378079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:33:12.036548Z","time spent":"341.522884ms","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-09T22:33:12.378249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.726321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-04-09T22:33:12.378287Z","caller":"traceutil/trace.go:171","msg":"trace[722685851] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2192; }","duration":"341.796313ms","start":"2026-04-09T22:33:12.03648Z","end":"2026-04-09T22:33:12.378276Z","steps":["trace[722685851] 'range keys from in-memory index tree' (duration: 341.582767ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:33:12.378313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:33:12.03646Z","time spent":"341.846875ms","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":553,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-04-09T22:33:12.378472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.941758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-09T22:33:12.378534Z","caller":"traceutil/trace.go:171","msg":"trace[1826385464] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2192; }","duration":"342.023121ms","start":"2026-04-09T22:33:12.036503Z","end":"2026-04-09T22:33:12.378526Z","steps":["trace[1826385464] 'range keys from in-memory index tree' (duration: 341.811594ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:33:12.37856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:33:12.036493Z","time spent":"342.061182ms","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-09T22:33:12.378803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.810915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-09T22:33:12.378884Z","caller":"traceutil/trace.go:171","msg":"trace[1704779948] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:2192; }","duration":"225.928899ms","start":"2026-04-09T22:33:12.152939Z","end":"2026-04-09T22:33:12.378868Z","steps":["trace[1704779948] 'count revisions from in-memory index tree' (duration: 225.743004ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:33:12.528978Z","caller":"traceutil/trace.go:171","msg":"trace[1905809130] transaction","detail":"{read_only:false; response_revision:2193; number_of_response:1; }","duration":"145.631474ms","start":"2026-04-09T22:33:12.383321Z","end":"2026-04-09T22:33:12.528952Z","steps":["trace[1905809130] 'process raft request' (duration: 145.488189ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:33:12.530822Z","caller":"traceutil/trace.go:171","msg":"trace[335974014] transaction","detail":"{read_only:false; response_revision:2194; number_of_response:1; }","duration":"147.328108ms","start":"2026-04-09T22:33:12.383445Z","end":"2026-04-09T22:33:12.530773Z","steps":["trace[335974014] 'process raft request' (duration: 146.939286ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:33:12.531032Z","caller":"traceutil/trace.go:171","msg":"trace[6674294] transaction","detail":"{read_only:false; response_revision:2195; number_of_response:1; }","duration":"147.495343ms","start":"2026-04-09T22:33:12.383509Z","end":"2026-04-09T22:33:12.531004Z","steps":["trace[6674294] 'process raft request' (duration: 147.1139ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:33:12.531159Z","caller":"traceutil/trace.go:171","msg":"trace[963786338] linearizableReadLoop","detail":"{readStateIndex:2293; appliedIndex:2288; }","duration":"144.677203ms","start":"2026-04-09T22:33:12.386448Z","end":"2026-04-09T22:33:12.531125Z","steps":["trace[963786338] 'read index received' (duration: 142.366208ms)","trace[963786338] 'applied index is now lower than readState.Index' (duration: 2.285454ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T22:33:12.531276Z","caller":"traceutil/trace.go:171","msg":"trace[1600444414] transaction","detail":"{read_only:false; response_revision:2197; number_of_response:1; }","duration":"146.979887ms","start":"2026-04-09T22:33:12.38428Z","end":"2026-04-09T22:33:12.53126Z","steps":["trace[1600444414] 'process raft request' (duration: 146.628296ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:33:12.531385Z","caller":"traceutil/trace.go:171","msg":"trace[439638692] transaction","detail":"{read_only:false; response_revision:2196; number_of_response:1; }","duration":"147.08259ms","start":"2026-04-09T22:33:12.384113Z","end":"2026-04-09T22:33:12.531196Z","steps":["trace[439638692] 'process raft request' (duration: 146.749359ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:33:12.532005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.53342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T22:33:12.532079Z","caller":"traceutil/trace.go:171","msg":"trace[1088031854] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2197; }","duration":"145.672685ms","start":"2026-04-09T22:33:12.386386Z","end":"2026-04-09T22:33:12.532058Z","steps":["trace[1088031854] 'agreement among raft nodes before linearized reading' (duration: 144.91343ms)"],"step_count":1}