{"level":"warn","ts":"2026-05-19T18:25:26.739032Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-19T18:25:26.739151Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.205: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.205:2380","--initial-cluster=instance=https://199.204.45.205:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.205:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.205: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-05-19T18:25:26.739227Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-19T18:25:26.739242Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.205:2380"]} {"level":"info","ts":"2026-05-19T18:25:26.739259Z","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-05-19T18:25:26.73995Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.205:2379"]} {"level":"info","ts":"2026-05-19T18:25:26.740036Z","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.205:2380"],"listen-peer-urls":["https://199.204.45.205:2380"],"advertise-client-urls":["https://199.204.45.205:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.205:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.205: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-05-19T18:25:26.772192Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"31.881884ms"} {"level":"info","ts":"2026-05-19T18:25:26.804108Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"71d28e9ac1bc4e5d","cluster-id":"4a4ae660c7a9a6a7"} {"level":"info","ts":"2026-05-19T18:25:26.804242Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d switched to configuration voters=()"} {"level":"info","ts":"2026-05-19T18:25:26.804275Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became follower at term 0"} {"level":"info","ts":"2026-05-19T18:25:26.804296Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 71d28e9ac1bc4e5d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-19T18:25:26.80431Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became follower at term 1"} {"level":"info","ts":"2026-05-19T18:25:26.804366Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d switched to configuration voters=(8201774666721545821)"} {"level":"warn","ts":"2026-05-19T18:25:26.863297Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-19T18:25:26.871905Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-19T18:25:26.875768Z","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-05-19T18:25:26.878973Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"71d28e9ac1bc4e5d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-19T18:25:26.879263Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"71d28e9ac1bc4e5d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-19T18:25:26.879838Z","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-05-19T18:25:26.879929Z","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-05-19T18:25:26.880015Z","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-05-19T18:25:26.881069Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d switched to configuration voters=(8201774666721545821)"} {"level":"info","ts":"2026-05-19T18:25:26.881208Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4a4ae660c7a9a6a7","local-member-id":"71d28e9ac1bc4e5d","added-peer-id":"71d28e9ac1bc4e5d","added-peer-peer-urls":["https://199.204.45.205:2380"]} {"level":"info","ts":"2026-05-19T18:25:26.883795Z","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-05-19T18:25:26.88395Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.205:2380"} {"level":"info","ts":"2026-05-19T18:25:26.883973Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.205:2380"} {"level":"info","ts":"2026-05-19T18:25:26.884171Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"71d28e9ac1bc4e5d","initial-advertise-peer-urls":["https://199.204.45.205:2380"],"listen-peer-urls":["https://199.204.45.205:2380"],"advertise-client-urls":["https://199.204.45.205:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.205:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-19T18:25:26.884301Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-19T18:25:27.505133Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d is starting a new election at term 1"} {"level":"info","ts":"2026-05-19T18:25:27.505191Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became pre-candidate at term 1"} {"level":"info","ts":"2026-05-19T18:25:27.50525Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d received MsgPreVoteResp from 71d28e9ac1bc4e5d at term 1"} {"level":"info","ts":"2026-05-19T18:25:27.505276Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became candidate at term 2"} {"level":"info","ts":"2026-05-19T18:25:27.505292Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d received MsgVoteResp from 71d28e9ac1bc4e5d at term 2"} {"level":"info","ts":"2026-05-19T18:25:27.505313Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became leader at term 2"} {"level":"info","ts":"2026-05-19T18:25:27.505331Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 71d28e9ac1bc4e5d elected leader 71d28e9ac1bc4e5d at term 2"} {"level":"info","ts":"2026-05-19T18:25:27.512293Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"71d28e9ac1bc4e5d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.205:2379]}","request-path":"/0/members/71d28e9ac1bc4e5d/attributes","cluster-id":"4a4ae660c7a9a6a7","publish-timeout":"7s"} {"level":"info","ts":"2026-05-19T18:25:27.512459Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-19T18:25:27.51259Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-19T18:25:27.513181Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T18:25:27.513421Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-19T18:25:27.513453Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-19T18:25:27.516269Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-19T18:25:27.516638Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4a4ae660c7a9a6a7","local-member-id":"71d28e9ac1bc4e5d","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T18:25:27.516744Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T18:25:27.516777Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T18:25:27.527826Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.205:2379"} {"level":"info","ts":"2026-05-19T18:25:33.437498Z","caller":"traceutil/trace.go:171","msg":"trace[1619456231] transaction","detail":"{read_only:false; response_revision:266; number_of_response:1; }","duration":"137.253002ms","start":"2026-05-19T18:25:33.300221Z","end":"2026-05-19T18:25:33.437474Z","steps":["trace[1619456231] 'process raft request' (duration: 137.206591ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:25:33.437854Z","caller":"traceutil/trace.go:171","msg":"trace[1880100087] linearizableReadLoop","detail":"{readStateIndex:275; appliedIndex:274; }","duration":"138.473134ms","start":"2026-05-19T18:25:33.299355Z","end":"2026-05-19T18:25:33.437828Z","steps":["trace[1880100087] 'read index received' (duration: 96.384337ms)","trace[1880100087] 'applied index is now lower than readState.Index' (duration: 42.086037ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:25:33.437908Z","caller":"traceutil/trace.go:171","msg":"trace[1032308883] transaction","detail":"{read_only:false; response_revision:265; number_of_response:1; }","duration":"144.470298ms","start":"2026-05-19T18:25:33.293399Z","end":"2026-05-19T18:25:33.437869Z","steps":["trace[1032308883] 'process raft request' (duration: 102.304819ms)","trace[1032308883] 'compare' (duration: 41.586058ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T18:25:33.438019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.638107ms","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-05-19T18:25:33.438074Z","caller":"traceutil/trace.go:171","msg":"trace[1501586261] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:266; }","duration":"138.715369ms","start":"2026-05-19T18:25:33.299351Z","end":"2026-05-19T18:25:33.438066Z","steps":["trace[1501586261] 'agreement among raft nodes before linearized reading' (duration: 138.596246ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:25:33.438048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.797407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/disruption-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T18:25:33.438339Z","caller":"traceutil/trace.go:171","msg":"trace[1057100069] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/disruption-controller; range_end:; response_count:0; response_revision:266; }","duration":"124.109502ms","start":"2026-05-19T18:25:33.314215Z","end":"2026-05-19T18:25:33.438324Z","steps":["trace[1057100069] 'agreement among raft nodes before linearized reading' (duration: 123.763026ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:25:56.63386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.79587ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-19T18:25:56.63421Z","caller":"traceutil/trace.go:171","msg":"trace[1163512958] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"637.217521ms","start":"2026-05-19T18:25:55.996964Z","end":"2026-05-19T18:25:56.634181Z","steps":["trace[1163512958] 'process raft request' (duration: 637.019588ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:25:56.634248Z","caller":"traceutil/trace.go:171","msg":"trace[601217372] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"633.325578ms","start":"2026-05-19T18:25:56.00088Z","end":"2026-05-19T18:25:56.634206Z","steps":["trace[601217372] 'process raft request' (duration: 633.178696ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:25:56.634219Z","caller":"traceutil/trace.go:171","msg":"trace[1963502874] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"638.928247ms","start":"2026-05-19T18:25:55.995241Z","end":"2026-05-19T18:25:56.634169Z","steps":["trace[1963502874] 'process raft request' (duration: 278.277573ms)","trace[1963502874] 'compare' (duration: 359.667788ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T18:25:56.634495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:25:55.996957Z","time spent":"637.302442ms","remote":"127.0.0.1:54634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10063,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T18:25:56.634567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:25:55.995233Z","time spent":"639.164921ms","remote":"127.0.0.1:55136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":980,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-19T18:25:56.634564Z","caller":"traceutil/trace.go:171","msg":"trace[1635334446] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"629.831353ms","start":"2026-05-19T18:25:56.004715Z","end":"2026-05-19T18:25:56.634547Z","steps":["trace[1635334446] 'process raft request' (duration: 629.398496ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:25:56.634666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:25:56.004706Z","time spent":"629.925144ms","remote":"127.0.0.1:55136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-19T18:25:56.634669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:25:56.00086Z","time spent":"633.480701ms","remote":"127.0.0.1:54634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6888,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T18:25:56.634765Z","caller":"traceutil/trace.go:171","msg":"trace[907810569] transaction","detail":"{read_only:false; number_of_response:0; response_revision:482; }","duration":"615.593058ms","start":"2026-05-19T18:25:56.019164Z","end":"2026-05-19T18:25:56.634757Z","steps":["trace[907810569] 'process raft request' (duration: 615.360404ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:25:56.634779Z","caller":"traceutil/trace.go:171","msg":"trace[688234174] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"613.219981ms","start":"2026-05-19T18:25:56.021548Z","end":"2026-05-19T18:25:56.634768Z","steps":["trace[688234174] 'process raft request' (duration: 613.002758ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:25:56.634782Z","caller":"traceutil/trace.go:171","msg":"trace[200546210] linearizableReadLoop","detail":"{readStateIndex:500; appliedIndex:493; }","duration":"606.887511ms","start":"2026-05-19T18:25:56.027877Z","end":"2026-05-19T18:25:56.634764Z","steps":["trace[200546210] 'read index received' (duration: 245.808991ms)","trace[200546210] 'applied index is now lower than readState.Index' (duration: 361.07739ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T18:25:56.634808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:25:56.019148Z","time spent":"615.640649ms","remote":"127.0.0.1:55136","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-19T18:25:56.634834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:25:56.021529Z","time spent":"613.273792ms","remote":"127.0.0.1:54634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28739,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T18:25:56.634869Z","caller":"traceutil/trace.go:171","msg":"trace[1494500431] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"606.826781ms","start":"2026-05-19T18:25:56.028036Z","end":"2026-05-19T18:25:56.634863Z","steps":["trace[1494500431] 'process raft request' (duration: 606.572487ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:25:56.634915Z","caller":"traceutil/trace.go:171","msg":"trace[304892343] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"630.098437ms","start":"2026-05-19T18:25:56.004805Z","end":"2026-05-19T18:25:56.634904Z","steps":["trace[304892343] 'process raft request' (duration: 629.61527ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:25:56.634961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:25:56.028027Z","time spent":"606.861751ms","remote":"127.0.0.1:54634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T18:25:56.634973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:25:56.004797Z","time spent":"630.144308ms","remote":"127.0.0.1:54634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30393,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T18:25:56.635099Z","caller":"traceutil/trace.go:171","msg":"trace[1270484196] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"602.796086ms","start":"2026-05-19T18:25:56.032287Z","end":"2026-05-19T18:25:56.635083Z","steps":["trace[1270484196] 'process raft request' (duration: 602.359019ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:25:56.635117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"607.222846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/udproutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:30389"} {"level":"warn","ts":"2026-05-19T18:25:56.635129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.831972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T18:25:56.635137Z","caller":"traceutil/trace.go:171","msg":"trace[2100182457] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"558.537958ms","start":"2026-05-19T18:25:56.076587Z","end":"2026-05-19T18:25:56.635125Z","steps":["trace[2100182457] 'process raft request' (duration: 558.134151ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:25:56.635156Z","caller":"traceutil/trace.go:171","msg":"trace[1681571802] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/udproutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:486; }","duration":"607.293157ms","start":"2026-05-19T18:25:56.027851Z","end":"2026-05-19T18:25:56.635145Z","steps":["trace[1681571802] 'agreement among raft nodes before linearized reading' (duration: 607.073264ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:25:56.635163Z","caller":"traceutil/trace.go:171","msg":"trace[1396986735] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:486; }","duration":"252.906812ms","start":"2026-05-19T18:25:56.382246Z","end":"2026-05-19T18:25:56.635153Z","steps":["trace[1396986735] 'agreement among raft nodes before linearized reading' (duration: 252.817891ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:25:56.635185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:25:56.027838Z","time spent":"607.342028ms","remote":"127.0.0.1:54634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":30411,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/udproutes.gateway.networking.k8s.io\" "} {"level":"warn","ts":"2026-05-19T18:25:56.635181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:25:56.032271Z","time spent":"602.860567ms","remote":"127.0.0.1:54634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T18:25:56.635196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:25:56.076561Z","time spent":"558.604218ms","remote":"127.0.0.1:54634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-19T18:25:59.513434Z","caller":"traceutil/trace.go:171","msg":"trace[1284756118] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"102.911577ms","start":"2026-05-19T18:25:59.4105Z","end":"2026-05-19T18:25:59.513412Z","steps":["trace[1284756118] 'process raft request' (duration: 102.758284ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:25:59.737144Z","caller":"traceutil/trace.go:171","msg":"trace[1047619297] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"117.955101ms","start":"2026-05-19T18:25:59.619157Z","end":"2026-05-19T18:25:59.737112Z","steps":["trace[1047619297] 'process raft request' (duration: 108.939391ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:26:00.286122Z","caller":"traceutil/trace.go:171","msg":"trace[558984795] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"169.227361ms","start":"2026-05-19T18:26:00.116868Z","end":"2026-05-19T18:26:00.286096Z","steps":["trace[558984795] 'process raft request' (duration: 168.612012ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:26:00.987764Z","caller":"traceutil/trace.go:171","msg":"trace[988316179] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"130.467735ms","start":"2026-05-19T18:26:00.857239Z","end":"2026-05-19T18:26:00.987707Z","steps":["trace[988316179] 'process raft request' (duration: 126.246989ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:26:01.149359Z","caller":"traceutil/trace.go:171","msg":"trace[1813342465] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"104.135449ms","start":"2026-05-19T18:26:01.045198Z","end":"2026-05-19T18:26:01.149333Z","steps":["trace[1813342465] 'process raft request' (duration: 99.666401ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:26:04.025549Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5646843511888222403,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T18:26:04.526305Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5646843511888222403,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T18:26:04.530202Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.034643788s","expected-duration":"1s"} {"level":"info","ts":"2026-05-19T18:26:04.531101Z","caller":"traceutil/trace.go:171","msg":"trace[230970020] linearizableReadLoop","detail":"{readStateIndex:591; appliedIndex:590; }","duration":"1.005644207s","start":"2026-05-19T18:26:03.525432Z","end":"2026-05-19T18:26:04.531076Z","steps":["trace[230970020] 'read index received' (duration: 1.004850704s)","trace[230970020] 'applied index is now lower than readState.Index' (duration: 792.483µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:26:04.531226Z","caller":"traceutil/trace.go:171","msg":"trace[741754305] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"1.037424431s","start":"2026-05-19T18:26:03.493792Z","end":"2026-05-19T18:26:04.531217Z","steps":["trace[741754305] 'process raft request' (duration: 1.03675877s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:26:04.531358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:26:03.493759Z","time spent":"1.037499801s","remote":"127.0.0.1:54698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036317,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T18:26:04.531694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"780.045649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-19T18:26:04.531777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.384115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T18:26:04.53182Z","caller":"traceutil/trace.go:171","msg":"trace[343415103] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:573; }","duration":"150.473806ms","start":"2026-05-19T18:26:04.381334Z","end":"2026-05-19T18:26:04.531808Z","steps":["trace[343415103] 'agreement among raft nodes before linearized reading' (duration: 150.402115ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:26:04.531814Z","caller":"traceutil/trace.go:171","msg":"trace[1485183483] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:573; }","duration":"780.245691ms","start":"2026-05-19T18:26:03.751531Z","end":"2026-05-19T18:26:04.531777Z","steps":["trace[1485183483] 'agreement among raft nodes before linearized reading' (duration: 779.853765ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:26:04.531882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:26:03.751514Z","time spent":"780.357922ms","remote":"127.0.0.1:54858","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-05-19T18:26:04.532059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.006634061s","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-05-19T18:26:04.532087Z","caller":"traceutil/trace.go:171","msg":"trace[442372108] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:573; }","duration":"1.006693942s","start":"2026-05-19T18:26:03.525385Z","end":"2026-05-19T18:26:04.532079Z","steps":["trace[442372108] 'agreement among raft nodes before linearized reading' (duration: 1.006566051s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:26:04.532113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:26:03.525366Z","time spent":"1.006740923s","remote":"127.0.0.1:54858","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-05-19T18:26:04.532309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.040747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-z5c4b\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-19T18:26:04.532336Z","caller":"traceutil/trace.go:171","msg":"trace[1227377045] range","detail":"{range_begin:/registry/pods/kube-system/cilium-z5c4b; range_end:; response_count:1; response_revision:573; }","duration":"290.104888ms","start":"2026-05-19T18:26:04.242223Z","end":"2026-05-19T18:26:04.532328Z","steps":["trace[1227377045] 'agreement among raft nodes before linearized reading' (duration: 290.049287ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:26:05.070701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.5136ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T18:26:05.070819Z","caller":"traceutil/trace.go:171","msg":"trace[263502980] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"533.419173ms","start":"2026-05-19T18:26:04.537379Z","end":"2026-05-19T18:26:05.070798Z","steps":["trace[263502980] 'process raft request' (duration: 37.71801ms)","trace[263502980] 'compare' (duration: 495.387738ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T18:26:05.070883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:26:04.537357Z","time spent":"533.496775ms","remote":"127.0.0.1:54858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T18:26:05.169425Z","caller":"traceutil/trace.go:171","msg":"trace[1575009123] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"629.955916ms","start":"2026-05-19T18:26:04.539444Z","end":"2026-05-19T18:26:05.1694Z","steps":["trace[1575009123] 'process raft request' (duration: 629.710663ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:26:05.169653Z","caller":"traceutil/trace.go:171","msg":"trace[1155809530] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"619.581581ms","start":"2026-05-19T18:26:04.550043Z","end":"2026-05-19T18:26:05.169624Z","steps":["trace[1155809530] 'process raft request' (duration: 619.268775ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:26:05.169815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:26:04.550011Z","time spent":"619.735733ms","remote":"127.0.0.1:54784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T18:26:05.169806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:26:04.539435Z","time spent":"630.087529ms","remote":"127.0.0.1:54858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T18:26:05.377469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.310504ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-19T18:26:05.377588Z","caller":"traceutil/trace.go:171","msg":"trace[2137286137] linearizableReadLoop","detail":"{readStateIndex:595; appliedIndex:592; }","duration":"531.841598ms","start":"2026-05-19T18:26:04.84573Z","end":"2026-05-19T18:26:05.377572Z","steps":["trace[2137286137] 'read index received' (duration: 323.389729ms)","trace[2137286137] 'applied index is now lower than readState.Index' (duration: 208.450689ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T18:26:05.377886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"532.125423ms","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-05-19T18:26:05.37802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.802807ms","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-05-19T18:26:05.378067Z","caller":"traceutil/trace.go:171","msg":"trace[897289674] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:577; }","duration":"365.914889ms","start":"2026-05-19T18:26:05.01214Z","end":"2026-05-19T18:26:05.378055Z","steps":["trace[897289674] 'agreement among raft nodes before linearized reading' (duration: 365.702056ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:26:05.378108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:26:05.012119Z","time spent":"365.98142ms","remote":"127.0.0.1:54858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-19T18:26:05.377913Z","caller":"traceutil/trace.go:171","msg":"trace[1483139867] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"794.065856ms","start":"2026-05-19T18:26:04.583817Z","end":"2026-05-19T18:26:05.377883Z","steps":["trace[1483139867] 'process raft request' (duration: 615.277584ms)","trace[1483139867] 'compare' (duration: 178.097091ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:26:05.378191Z","caller":"traceutil/trace.go:171","msg":"trace[516688981] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:577; }","duration":"532.358656ms","start":"2026-05-19T18:26:04.845681Z","end":"2026-05-19T18:26:05.37804Z","steps":["trace[516688981] 'agreement among raft nodes before linearized reading' (duration: 532.020641ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:26:05.378222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:26:04.583792Z","time spent":"794.36541ms","remote":"127.0.0.1:54828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-19T18:26:05.37831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:26:04.845662Z","time spent":"532.633581ms","remote":"127.0.0.1:54858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-19T18:26:05.88735Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5646843511888222416,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T18:26:06.388121Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5646843511888222416,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T18:26:06.889131Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5646843511888222416,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T18:26:07.009734Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.62472845s","expected-duration":"1s"} {"level":"info","ts":"2026-05-19T18:26:07.019109Z","caller":"traceutil/trace.go:171","msg":"trace[1434492616] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"1.634184462s","start":"2026-05-19T18:26:05.384902Z","end":"2026-05-19T18:26:07.019086Z","steps":["trace[1434492616] 'process raft request' (duration: 1.625097036s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:26:07.019285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:26:05.384882Z","time spent":"1.634307503s","remote":"127.0.0.1:54858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T18:26:07.028013Z","caller":"traceutil/trace.go:171","msg":"trace[791269573] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"1.642963983s","start":"2026-05-19T18:26:05.384994Z","end":"2026-05-19T18:26:07.027958Z","steps":["trace[791269573] 'process raft request' (duration: 1.642721709s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:26:07.028264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:26:05.38497Z","time spent":"1.643168775s","remote":"127.0.0.1:54858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T18:26:07.035516Z","caller":"traceutil/trace.go:171","msg":"trace[2025187573] linearizableReadLoop","detail":"{readStateIndex:598; appliedIndex:595; }","duration":"1.648411203s","start":"2026-05-19T18:26:05.387084Z","end":"2026-05-19T18:26:07.035496Z","steps":["trace[2025187573] 'read index received' (duration: 1.622900923s)","trace[2025187573] 'applied index is now lower than readState.Index' (duration: 25.50919ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T18:26:07.035645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"652.555557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T18:26:07.035705Z","caller":"traceutil/trace.go:171","msg":"trace[2095664892] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:579; }","duration":"652.663958ms","start":"2026-05-19T18:26:06.383032Z","end":"2026-05-19T18:26:07.035696Z","steps":["trace[2095664892] 'agreement among raft nodes before linearized reading' (duration: 652.569767ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:26:07.035755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:26:06.383013Z","time spent":"652.715199ms","remote":"127.0.0.1:54576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-19T18:26:07.035654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.648562266s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-05-19T18:26:07.035802Z","caller":"traceutil/trace.go:171","msg":"trace[1277800108] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:579; }","duration":"1.648742539s","start":"2026-05-19T18:26:05.387045Z","end":"2026-05-19T18:26:07.035788Z","steps":["trace[1277800108] 'agreement among raft nodes before linearized reading' (duration: 1.648528336s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:26:07.035844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:26:05.387028Z","time spent":"1.648807009s","remote":"127.0.0.1:54794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":215,"request content":"key:\"/registry/serviceaccounts/kube-system/job-controller\" "} {"level":"warn","ts":"2026-05-19T18:26:07.44267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.728863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T18:26:07.442741Z","caller":"traceutil/trace.go:171","msg":"trace[728433483] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:588; }","duration":"102.816105ms","start":"2026-05-19T18:26:07.339908Z","end":"2026-05-19T18:26:07.442724Z","steps":["trace[728433483] 'range keys from in-memory index tree' (duration: 102.691532ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:26:07.442928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.350882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T18:26:07.442922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.312481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T18:26:07.443022Z","caller":"traceutil/trace.go:171","msg":"trace[2029328343] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:588; }","duration":"103.491624ms","start":"2026-05-19T18:26:07.33951Z","end":"2026-05-19T18:26:07.443002Z","steps":["trace[2029328343] 'range keys from in-memory index tree' (duration: 103.251281ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:26:07.443029Z","caller":"traceutil/trace.go:171","msg":"trace[1800143900] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:588; }","duration":"103.466424ms","start":"2026-05-19T18:26:07.339542Z","end":"2026-05-19T18:26:07.443009Z","steps":["trace[1800143900] 'count revisions from in-memory index tree' (duration: 103.22422ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:26:22.611999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.887952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-gzhtj\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-05-19T18:26:22.612082Z","caller":"traceutil/trace.go:171","msg":"trace[307622094] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-gzhtj; range_end:; response_count:1; response_revision:738; }","duration":"271.024945ms","start":"2026-05-19T18:26:22.34104Z","end":"2026-05-19T18:26:22.612065Z","steps":["trace[307622094] 'range keys from in-memory index tree' (duration: 270.7261ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:26:23.329656Z","caller":"traceutil/trace.go:171","msg":"trace[2130941283] linearizableReadLoop","detail":"{readStateIndex:762; appliedIndex:761; }","duration":"484.900599ms","start":"2026-05-19T18:26:22.844739Z","end":"2026-05-19T18:26:23.32964Z","steps":["trace[2130941283] 'read index received' (duration: 484.857899ms)","trace[2130941283] 'applied index is now lower than readState.Index' (duration: 42.12µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:26:23.329747Z","caller":"traceutil/trace.go:171","msg":"trace[1506519585] transaction","detail":"{read_only:false; response_revision:739; number_of_response:1; }","duration":"708.748762ms","start":"2026-05-19T18:26:22.620991Z","end":"2026-05-19T18:26:23.32974Z","steps":["trace[1506519585] 'process raft request' (duration: 708.569769ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:26:23.329819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:26:22.620961Z","time spent":"708.802033ms","remote":"127.0.0.1:54784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4028,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T18:26:23.329949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.210814ms","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-05-19T18:26:23.329977Z","caller":"traceutil/trace.go:171","msg":"trace[1842853153] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:739; }","duration":"485.272345ms","start":"2026-05-19T18:26:22.844698Z","end":"2026-05-19T18:26:23.329971Z","steps":["trace[1842853153] 'agreement among raft nodes before linearized reading' (duration: 485.190314ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:26:23.329998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:26:22.844681Z","time spent":"485.312236ms","remote":"127.0.0.1:54858","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-05-19T18:26:23.33458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.323465ms","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-05-19T18:26:23.33466Z","caller":"traceutil/trace.go:171","msg":"trace[1035780555] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:740; }","duration":"239.450996ms","start":"2026-05-19T18:26:23.095192Z","end":"2026-05-19T18:26:23.334643Z","steps":["trace[1035780555] 'agreement among raft nodes before linearized reading' (duration: 239.237773ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:26:23.334818Z","caller":"traceutil/trace.go:171","msg":"trace[1377299707] transaction","detail":"{read_only:false; response_revision:740; number_of_response:1; }","duration":"206.80658ms","start":"2026-05-19T18:26:23.127978Z","end":"2026-05-19T18:26:23.334785Z","steps":["trace[1377299707] 'process raft request' (duration: 206.337933ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:26:23.334857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.75269ms","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-05-19T18:26:23.334899Z","caller":"traceutil/trace.go:171","msg":"trace[213004183] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:740; }","duration":"188.811662ms","start":"2026-05-19T18:26:23.146078Z","end":"2026-05-19T18:26:23.334889Z","steps":["trace[213004183] 'agreement among raft nodes before linearized reading' (duration: 188.71114ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:26:23.334941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.176236ms","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-05-19T18:26:23.334994Z","caller":"traceutil/trace.go:171","msg":"trace[1426444334] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:740; }","duration":"238.267287ms","start":"2026-05-19T18:26:23.096709Z","end":"2026-05-19T18:26:23.334977Z","steps":["trace[1426444334] 'agreement among raft nodes before linearized reading' (duration: 238.103235ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:26:26.517964Z","caller":"traceutil/trace.go:171","msg":"trace[1688720820] transaction","detail":"{read_only:false; response_revision:756; number_of_response:1; }","duration":"106.316734ms","start":"2026-05-19T18:26:26.411626Z","end":"2026-05-19T18:26:26.517943Z","steps":["trace[1688720820] 'process raft request' (duration: 103.322158ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:26:27.45947Z","caller":"traceutil/trace.go:171","msg":"trace[1816772247] linearizableReadLoop","detail":"{readStateIndex:810; appliedIndex:809; }","duration":"105.857622ms","start":"2026-05-19T18:26:27.353583Z","end":"2026-05-19T18:26:27.459441Z","steps":["trace[1816772247] 'read index received' (duration: 105.409006ms)","trace[1816772247] 'applied index is now lower than readState.Index' (duration: 447.106µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:26:27.459513Z","caller":"traceutil/trace.go:171","msg":"trace[51622848] transaction","detail":"{read_only:false; response_revision:786; number_of_response:1; }","duration":"110.330402ms","start":"2026-05-19T18:26:27.349158Z","end":"2026-05-19T18:26:27.459488Z","steps":["trace[51622848] 'process raft request' (duration: 109.885135ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:26:27.459772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.802187ms","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-05-19T18:26:27.459842Z","caller":"traceutil/trace.go:171","msg":"trace[601812790] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:786; }","duration":"104.889609ms","start":"2026-05-19T18:26:27.354931Z","end":"2026-05-19T18:26:27.45982Z","steps":["trace[601812790] 'agreement among raft nodes before linearized reading' (duration: 104.676485ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:26:27.459861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.849738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-19T18:26:27.459891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.896549ms","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-05-19T18:26:27.459952Z","caller":"traceutil/trace.go:171","msg":"trace[2004531964] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:786; }","duration":"104.962941ms","start":"2026-05-19T18:26:27.354969Z","end":"2026-05-19T18:26:27.459932Z","steps":["trace[2004531964] 'agreement among raft nodes before linearized reading' (duration: 104.697466ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:26:27.45997Z","caller":"traceutil/trace.go:171","msg":"trace[198891085] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:786; }","duration":"104.97517ms","start":"2026-05-19T18:26:27.354981Z","end":"2026-05-19T18:26:27.459956Z","steps":["trace[198891085] 'agreement among raft nodes before linearized reading' (duration: 104.842528ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:26:27.459772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.165167ms","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-05-19T18:26:27.460083Z","caller":"traceutil/trace.go:171","msg":"trace[187159937] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:786; }","duration":"106.510804ms","start":"2026-05-19T18:26:27.353553Z","end":"2026-05-19T18:26:27.460064Z","steps":["trace[187159937] 'agreement among raft nodes before linearized reading' (duration: 106.025265ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:26:27.460107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.135883ms","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-05-19T18:26:27.460349Z","caller":"traceutil/trace.go:171","msg":"trace[1686154844] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:786; }","duration":"105.382217ms","start":"2026-05-19T18:26:27.354957Z","end":"2026-05-19T18:26:27.460339Z","steps":["trace[1686154844] 'agreement among raft nodes before linearized reading' (duration: 105.100362ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:26:27.723616Z","caller":"traceutil/trace.go:171","msg":"trace[1801039068] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"105.133863ms","start":"2026-05-19T18:26:27.618461Z","end":"2026-05-19T18:26:27.723595Z","steps":["trace[1801039068] 'process raft request' (duration: 105.010931ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:26:36.216923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.59101ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-05-19T18:26:36.251118Z","caller":"traceutil/trace.go:171","msg":"trace[1029520875] transaction","detail":"{read_only:false; response_revision:895; number_of_response:1; }","duration":"193.886168ms","start":"2026-05-19T18:26:36.057209Z","end":"2026-05-19T18:26:36.251095Z","steps":["trace[1029520875] 'process raft request' (duration: 193.664645ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:26:41.003153Z","caller":"traceutil/trace.go:171","msg":"trace[1267825758] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"117.279022ms","start":"2026-05-19T18:26:40.885848Z","end":"2026-05-19T18:26:41.003127Z","steps":["trace[1267825758] 'process raft request' (duration: 117.16476ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:26:41.003198Z","caller":"traceutil/trace.go:171","msg":"trace[1770595061] transaction","detail":"{read_only:false; response_revision:972; number_of_response:1; }","duration":"124.069673ms","start":"2026-05-19T18:26:40.879091Z","end":"2026-05-19T18:26:41.003161Z","steps":["trace[1770595061] 'process raft request' (duration: 123.040578ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:26:41.028724Z","caller":"traceutil/trace.go:171","msg":"trace[559010002] transaction","detail":"{read_only:false; response_revision:974; number_of_response:1; }","duration":"126.299965ms","start":"2026-05-19T18:26:40.902394Z","end":"2026-05-19T18:26:41.028694Z","steps":["trace[559010002] 'process raft request' (duration: 126.022361ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:26:41.028847Z","caller":"traceutil/trace.go:171","msg":"trace[1065143752] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"121.676446ms","start":"2026-05-19T18:26:40.907142Z","end":"2026-05-19T18:26:41.028819Z","steps":["trace[1065143752] 'process raft request' (duration: 121.569365ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:26:41.028908Z","caller":"traceutil/trace.go:171","msg":"trace[1152815368] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"125.580584ms","start":"2026-05-19T18:26:40.903317Z","end":"2026-05-19T18:26:41.028898Z","steps":["trace[1152815368] 'process raft request' (duration: 125.27434ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:26:41.028997Z","caller":"traceutil/trace.go:171","msg":"trace[2018612605] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"125.92032ms","start":"2026-05-19T18:26:40.903058Z","end":"2026-05-19T18:26:41.028978Z","steps":["trace[2018612605] 'process raft request' (duration: 125.490593ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:26:41.029293Z","caller":"traceutil/trace.go:171","msg":"trace[1426271901] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"122.976195ms","start":"2026-05-19T18:26:40.906294Z","end":"2026-05-19T18:26:41.02927Z","steps":["trace[1426271901] 'process raft request' (duration: 122.327755ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:26:51.864467Z","caller":"traceutil/trace.go:171","msg":"trace[76026402] linearizableReadLoop","detail":"{readStateIndex:1167; appliedIndex:1166; }","duration":"142.387547ms","start":"2026-05-19T18:26:51.722056Z","end":"2026-05-19T18:26:51.864443Z","steps":["trace[76026402] 'read index received' (duration: 142.248365ms)","trace[76026402] 'applied index is now lower than readState.Index' (duration: 138.082µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:26:51.864605Z","caller":"traceutil/trace.go:171","msg":"trace[1602515089] transaction","detail":"{read_only:false; response_revision:1135; number_of_response:1; }","duration":"149.43718ms","start":"2026-05-19T18:26:51.715155Z","end":"2026-05-19T18:26:51.864593Z","steps":["trace[1602515089] 'process raft request' (duration: 149.071375ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:26:51.864765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.6078ms","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-05-19T18:26:51.864814Z","caller":"traceutil/trace.go:171","msg":"trace[783698676] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1135; }","duration":"142.687221ms","start":"2026-05-19T18:26:51.722112Z","end":"2026-05-19T18:26:51.8648Z","steps":["trace[783698676] 'agreement among raft nodes before linearized reading' (duration: 142.540739ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:26:51.864941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.880694ms","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-05-19T18:26:51.864966Z","caller":"traceutil/trace.go:171","msg":"trace[441105451] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1135; }","duration":"142.928045ms","start":"2026-05-19T18:26:51.72203Z","end":"2026-05-19T18:26:51.864958Z","steps":["trace[441105451] 'agreement among raft nodes before linearized reading' (duration: 142.856894ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:26:52.429825Z","caller":"traceutil/trace.go:171","msg":"trace[1564566310] transaction","detail":"{read_only:false; response_revision:1139; number_of_response:1; }","duration":"109.457171ms","start":"2026-05-19T18:26:52.32034Z","end":"2026-05-19T18:26:52.429797Z","steps":["trace[1564566310] 'process raft request' (duration: 109.303929ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:26:54.643813Z","caller":"traceutil/trace.go:171","msg":"trace[114665278] transaction","detail":"{read_only:false; response_revision:1148; number_of_response:1; }","duration":"128.443791ms","start":"2026-05-19T18:26:54.51533Z","end":"2026-05-19T18:26:54.643774Z","steps":["trace[114665278] 'process raft request' (duration: 88.763813ms)","trace[114665278] 'compare' (duration: 39.548366ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:26:57.131201Z","caller":"traceutil/trace.go:171","msg":"trace[2025441846] transaction","detail":"{read_only:false; response_revision:1173; number_of_response:1; }","duration":"228.935136ms","start":"2026-05-19T18:26:56.902234Z","end":"2026-05-19T18:26:57.131169Z","steps":["trace[2025441846] 'process raft request' (duration: 228.562251ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:27:16.95216Z","caller":"traceutil/trace.go:171","msg":"trace[183911632] transaction","detail":"{read_only:false; response_revision:1405; number_of_response:1; }","duration":"290.389536ms","start":"2026-05-19T18:27:16.661737Z","end":"2026-05-19T18:27:16.952126Z","steps":["trace[183911632] 'process raft request' (duration: 290.218343ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:27:26.261367Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5646843511888224280,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-19T18:27:26.374429Z","caller":"traceutil/trace.go:171","msg":"trace[870544605] linearizableReadLoop","detail":"{readStateIndex:1571; appliedIndex:1570; }","duration":"613.498179ms","start":"2026-05-19T18:27:25.760896Z","end":"2026-05-19T18:27:26.374394Z","steps":["trace[870544605] 'read index received' (duration: 571.376338ms)","trace[870544605] 'applied index is now lower than readState.Index' (duration: 42.120201ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T18:27:26.374765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"613.651111ms","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-05-19T18:27:26.374852Z","caller":"traceutil/trace.go:171","msg":"trace[1393603750] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1512; }","duration":"613.980846ms","start":"2026-05-19T18:27:25.760853Z","end":"2026-05-19T18:27:26.374834Z","steps":["trace[1393603750] 'agreement among raft nodes before linearized reading' (duration: 613.621811ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:27:26.374904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:27:25.760834Z","time spent":"614.059037ms","remote":"127.0.0.1:57110","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-19T18:27:26.374891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"583.606729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-19T18:27:26.37495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.327681ms","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-05-19T18:27:26.375008Z","caller":"traceutil/trace.go:171","msg":"trace[1594461001] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1512; }","duration":"583.749031ms","start":"2026-05-19T18:27:25.79122Z","end":"2026-05-19T18:27:26.374969Z","steps":["trace[1594461001] 'agreement among raft nodes before linearized reading' (duration: 583.467717ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:27:26.375041Z","caller":"traceutil/trace.go:171","msg":"trace[136092177] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1512; }","duration":"285.473153ms","start":"2026-05-19T18:27:26.089555Z","end":"2026-05-19T18:27:26.375028Z","steps":["trace[136092177] 'agreement among raft nodes before linearized reading' (duration: 285.138358ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:27:26.375056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:27:25.791209Z","time spent":"583.837563ms","remote":"127.0.0.1:54858","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-05-19T18:27:26.375279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.620475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-19T18:27:26.375322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.038803ms","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-05-19T18:27:26.375326Z","caller":"traceutil/trace.go:171","msg":"trace[990395042] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1512; }","duration":"285.691616ms","start":"2026-05-19T18:27:26.089626Z","end":"2026-05-19T18:27:26.375318Z","steps":["trace[990395042] 'agreement among raft nodes before linearized reading' (duration: 285.564955ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:27:26.375348Z","caller":"traceutil/trace.go:171","msg":"trace[670178576] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1512; }","duration":"356.091284ms","start":"2026-05-19T18:27:26.019249Z","end":"2026-05-19T18:27:26.375341Z","steps":["trace[670178576] 'agreement among raft nodes before linearized reading' (duration: 355.981052ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:27:26.375369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:27:26.019236Z","time spent":"356.129204ms","remote":"127.0.0.1:54858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-19T18:27:26.375678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.092133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-19T18:27:26.375712Z","caller":"traceutil/trace.go:171","msg":"trace[287725002] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1512; }","duration":"291.170654ms","start":"2026-05-19T18:27:26.084534Z","end":"2026-05-19T18:27:26.375704Z","steps":["trace[287725002] 'agreement among raft nodes before linearized reading' (duration: 290.90723ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:27:26.960783Z","caller":"traceutil/trace.go:171","msg":"trace[1879161511] transaction","detail":"{read_only:false; response_revision:1518; number_of_response:1; }","duration":"271.604919ms","start":"2026-05-19T18:27:26.689161Z","end":"2026-05-19T18:27:26.960766Z","steps":["trace[1879161511] 'process raft request' (duration: 271.501628ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:27:27.451548Z","caller":"traceutil/trace.go:171","msg":"trace[54388518] transaction","detail":"{read_only:false; response_revision:1527; number_of_response:1; }","duration":"318.721337ms","start":"2026-05-19T18:27:27.13281Z","end":"2026-05-19T18:27:27.451532Z","steps":["trace[54388518] 'process raft request' (duration: 318.478843ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:27:27.451567Z","caller":"traceutil/trace.go:171","msg":"trace[522602436] transaction","detail":"{read_only:false; response_revision:1526; number_of_response:1; }","duration":"364.841263ms","start":"2026-05-19T18:27:27.086716Z","end":"2026-05-19T18:27:27.451557Z","steps":["trace[522602436] 'process raft request' (duration: 364.540359ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:27:27.451632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:27:27.086708Z","time spent":"364.889513ms","remote":"127.0.0.1:54924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1288,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-19T18:27:27.451646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:27:27.132794Z","time spent":"318.801527ms","remote":"127.0.0.1:54858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T18:27:27.451602Z","caller":"traceutil/trace.go:171","msg":"trace[888766782] transaction","detail":"{read_only:false; response_revision:1525; number_of_response:1; }","duration":"364.990454ms","start":"2026-05-19T18:27:27.086533Z","end":"2026-05-19T18:27:27.451524Z","steps":["trace[888766782] 'process raft request' (duration: 356.510666ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:27:27.452324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:27:27.086522Z","time spent":"365.626923ms","remote":"127.0.0.1:54924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1330,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-19T18:27:33.572616Z","caller":"traceutil/trace.go:171","msg":"trace[1825471199] transaction","detail":"{read_only:false; response_revision:1675; number_of_response:1; }","duration":"130.922105ms","start":"2026-05-19T18:27:33.441662Z","end":"2026-05-19T18:27:33.572584Z","steps":["trace[1825471199] 'process raft request' (duration: 130.200905ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:27:33.615438Z","caller":"traceutil/trace.go:171","msg":"trace[482378189] linearizableReadLoop","detail":"{readStateIndex:1744; appliedIndex:1740; }","duration":"165.067771ms","start":"2026-05-19T18:27:33.450351Z","end":"2026-05-19T18:27:33.615419Z","steps":["trace[482378189] 'read index received' (duration: 121.521964ms)","trace[482378189] 'applied index is now lower than readState.Index' (duration: 43.545227ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:27:33.615558Z","caller":"traceutil/trace.go:171","msg":"trace[1575617018] transaction","detail":"{read_only:false; response_revision:1677; number_of_response:1; }","duration":"170.532957ms","start":"2026-05-19T18:27:33.445004Z","end":"2026-05-19T18:27:33.615537Z","steps":["trace[1575617018] 'process raft request' (duration: 170.370735ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:27:33.615579Z","caller":"traceutil/trace.go:171","msg":"trace[529577795] transaction","detail":"{read_only:false; response_revision:1676; number_of_response:1; }","duration":"171.625731ms","start":"2026-05-19T18:27:33.443912Z","end":"2026-05-19T18:27:33.615537Z","steps":["trace[529577795] 'process raft request' (duration: 171.379668ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:27:33.623992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.579069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/ingress-nginx/ingress-nginx-controller-5plcl\" ","response":"range_response_count:1 size:7308"} {"level":"info","ts":"2026-05-19T18:27:33.62411Z","caller":"traceutil/trace.go:171","msg":"trace[1489974169] range","detail":"{range_begin:/registry/pods/ingress-nginx/ingress-nginx-controller-5plcl; range_end:; response_count:1; response_revision:1677; }","duration":"173.772632ms","start":"2026-05-19T18:27:33.4503Z","end":"2026-05-19T18:27:33.624073Z","steps":["trace[1489974169] 'agreement among raft nodes before linearized reading' (duration: 165.190773ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:27:33.902239Z","caller":"traceutil/trace.go:171","msg":"trace[1755430342] transaction","detail":"{read_only:false; response_revision:1693; number_of_response:1; }","duration":"131.551494ms","start":"2026-05-19T18:27:33.770655Z","end":"2026-05-19T18:27:33.902206Z","steps":["trace[1755430342] 'process raft request' (duration: 67.672833ms)","trace[1755430342] 'compare' (duration: 63.735288ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:27:33.942298Z","caller":"traceutil/trace.go:171","msg":"trace[667641045] linearizableReadLoop","detail":"{readStateIndex:1761; appliedIndex:1759; }","duration":"109.404525ms","start":"2026-05-19T18:27:33.832878Z","end":"2026-05-19T18:27:33.942282Z","steps":["trace[667641045] 'read index received' (duration: 5.431906ms)","trace[667641045] 'applied index is now lower than readState.Index' (duration: 103.971879ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:27:33.942389Z","caller":"traceutil/trace.go:171","msg":"trace[1954861021] transaction","detail":"{read_only:false; response_revision:1694; number_of_response:1; }","duration":"171.552081ms","start":"2026-05-19T18:27:33.770808Z","end":"2026-05-19T18:27:33.942361Z","steps":["trace[1954861021] 'process raft request' (duration: 171.359048ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:27:33.942522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.638238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" ","response":"range_response_count:3 size:222195"} {"level":"info","ts":"2026-05-19T18:27:33.942559Z","caller":"traceutil/trace.go:171","msg":"trace[1976040516] range","detail":"{range_begin:/registry/secrets/openstack/; range_end:/registry/secrets/openstack0; response_count:3; response_revision:1694; }","duration":"109.73774ms","start":"2026-05-19T18:27:33.832811Z","end":"2026-05-19T18:27:33.942549Z","steps":["trace[1976040516] 'agreement among raft nodes before linearized reading' (duration: 109.559547ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:27:42.543904Z","caller":"traceutil/trace.go:171","msg":"trace[1404273101] transaction","detail":"{read_only:false; response_revision:1823; number_of_response:1; }","duration":"140.720878ms","start":"2026-05-19T18:27:42.403162Z","end":"2026-05-19T18:27:42.543883Z","steps":["trace[1404273101] 'process raft request' (duration: 140.570115ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:27:43.16039Z","caller":"traceutil/trace.go:171","msg":"trace[2143493878] transaction","detail":"{read_only:false; response_revision:1824; number_of_response:1; }","duration":"697.133103ms","start":"2026-05-19T18:27:42.463223Z","end":"2026-05-19T18:27:43.160356Z","steps":["trace[2143493878] 'process raft request' (duration: 669.178857ms)","trace[2143493878] 'compare' (duration: 27.813254ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T18:27:43.160865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:27:42.4632Z","time spent":"697.57025ms","remote":"127.0.0.1:54858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T18:27:43.160447Z","caller":"traceutil/trace.go:171","msg":"trace[147944645] linearizableReadLoop","detail":"{readStateIndex:1893; appliedIndex:1891; }","duration":"650.897274ms","start":"2026-05-19T18:27:42.509527Z","end":"2026-05-19T18:27:43.160425Z","steps":["trace[147944645] 'read index received' (duration: 34.264214ms)","trace[147944645] 'applied index is now lower than readState.Index' (duration: 616.63123ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T18:27:43.160631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"651.099277ms","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-05-19T18:27:43.161324Z","caller":"traceutil/trace.go:171","msg":"trace[356416768] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1824; }","duration":"651.820447ms","start":"2026-05-19T18:27:42.509478Z","end":"2026-05-19T18:27:43.161299Z","steps":["trace[356416768] 'agreement among raft nodes before linearized reading' (duration: 651.034065ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:27:43.1614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:27:42.509464Z","time spent":"651.915879ms","remote":"127.0.0.1:54858","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-05-19T18:27:43.160693Z","caller":"traceutil/trace.go:171","msg":"trace[216839170] transaction","detail":"{read_only:false; response_revision:1825; number_of_response:1; }","duration":"331.828229ms","start":"2026-05-19T18:27:42.828807Z","end":"2026-05-19T18:27:43.160635Z","steps":["trace[216839170] 'process raft request' (duration: 331.636106ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:27:43.161582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:27:42.828784Z","time spent":"332.744832ms","remote":"127.0.0.1:54858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T18:27:43.912209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.926583ms","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-05-19T18:27:43.912341Z","caller":"traceutil/trace.go:171","msg":"trace[307336432] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1825; }","duration":"435.082135ms","start":"2026-05-19T18:27:43.47724Z","end":"2026-05-19T18:27:43.912322Z","steps":["trace[307336432] 'range keys from in-memory index tree' (duration: 434.904743ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:27:43.912982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.385946ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T18:27:43.913393Z","caller":"traceutil/trace.go:171","msg":"trace[606608960] transaction","detail":"{read_only:false; response_revision:1826; number_of_response:1; }","duration":"764.204857ms","start":"2026-05-19T18:27:43.149163Z","end":"2026-05-19T18:27:43.913368Z","steps":["trace[606608960] 'process raft request' (duration: 452.340885ms)","trace[606608960] 'compare' (duration: 310.603605ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T18:27:43.913541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:27:43.14914Z","time spent":"764.310348ms","remote":"127.0.0.1:54858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T18:27:43.913519Z","caller":"traceutil/trace.go:171","msg":"trace[1338615600] linearizableReadLoop","detail":"{readStateIndex:1895; appliedIndex:1894; }","duration":"752.248871ms","start":"2026-05-19T18:27:43.161246Z","end":"2026-05-19T18:27:43.913495Z","steps":["trace[1338615600] 'read index received' (duration: 440.398989ms)","trace[1338615600] 'applied index is now lower than readState.Index' (duration: 311.847692ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T18:27:43.913672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.401598494s","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-05-19T18:27:43.913703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.38044528s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c62bf567-0ba3-4a15-850e-c2bfbf0412d8\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-05-19T18:27:43.91373Z","caller":"traceutil/trace.go:171","msg":"trace[1186195552] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1827; }","duration":"1.401698015s","start":"2026-05-19T18:27:42.51201Z","end":"2026-05-19T18:27:43.913708Z","steps":["trace[1186195552] 'agreement among raft nodes before linearized reading' (duration: 1.401554783s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:27:43.913768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:27:42.511989Z","time spent":"1.401769866s","remote":"127.0.0.1:54858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-19T18:27:43.91379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.39913105s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-19T18:27:43.913788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.397876073s","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-05-19T18:27:43.913834Z","caller":"traceutil/trace.go:171","msg":"trace[31752875] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1827; }","duration":"1.397942803s","start":"2026-05-19T18:27:42.515882Z","end":"2026-05-19T18:27:43.913825Z","steps":["trace[31752875] 'agreement among raft nodes before linearized reading' (duration: 1.397794791s)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:27:43.913848Z","caller":"traceutil/trace.go:171","msg":"trace[1986564756] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1827; }","duration":"1.399227651s","start":"2026-05-19T18:27:42.51459Z","end":"2026-05-19T18:27:43.913818Z","steps":["trace[1986564756] 'agreement among raft nodes before linearized reading' (duration: 1.399094029s)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:27:43.91374Z","caller":"traceutil/trace.go:171","msg":"trace[1412488633] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c62bf567-0ba3-4a15-850e-c2bfbf0412d8; range_end:; response_count:1; response_revision:1827; }","duration":"1.380528911s","start":"2026-05-19T18:27:42.5332Z","end":"2026-05-19T18:27:43.913729Z","steps":["trace[1412488633] 'agreement among raft nodes before linearized reading' (duration: 1.380342708s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:27:43.913891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:27:42.51458Z","time spent":"1.399303942s","remote":"127.0.0.1:54858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-19T18:27:43.913924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.394397053s","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-05-19T18:27:43.913923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.159173238s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-05-19T18:27:43.913894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:27:42.533192Z","time spent":"1.380694604s","remote":"127.0.0.1:54784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3649,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c62bf567-0ba3-4a15-850e-c2bfbf0412d8\" "} {"level":"warn","ts":"2026-05-19T18:27:43.913979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.191355384s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c62bf567-0ba3-4a15-850e-c2bfbf0412d8\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-05-19T18:27:43.914009Z","caller":"traceutil/trace.go:171","msg":"trace[971281223] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c62bf567-0ba3-4a15-850e-c2bfbf0412d8; range_end:; response_count:1; response_revision:1827; }","duration":"1.191420865s","start":"2026-05-19T18:27:42.72258Z","end":"2026-05-19T18:27:43.914001Z","steps":["trace[971281223] 'agreement among raft nodes before linearized reading' (duration: 1.191345664s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:27:43.914047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:27:42.722555Z","time spent":"1.191484956s","remote":"127.0.0.1:54784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3649,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c62bf567-0ba3-4a15-850e-c2bfbf0412d8\" "} {"level":"info","ts":"2026-05-19T18:27:43.91395Z","caller":"traceutil/trace.go:171","msg":"trace[1884547655] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1827; }","duration":"1.394440854s","start":"2026-05-19T18:27:42.519502Z","end":"2026-05-19T18:27:43.913943Z","steps":["trace[1884547655] 'agreement among raft nodes before linearized reading' (duration: 1.394373303s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:27:43.913863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:27:42.515874Z","time spent":"1.397981794s","remote":"127.0.0.1:54858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-19T18:27:43.91399Z","caller":"traceutil/trace.go:171","msg":"trace[237131748] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1827; }","duration":"1.15927516s","start":"2026-05-19T18:27:42.754704Z","end":"2026-05-19T18:27:43.913979Z","steps":["trace[237131748] 'agreement among raft nodes before linearized reading' (duration: 1.159089748s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:27:43.914086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:27:42.519494Z","time spent":"1.394583127s","remote":"127.0.0.1:54858","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-05-19T18:27:43.914122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:27:42.754688Z","time spent":"1.159421872s","remote":"127.0.0.1:58630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-05-19T18:27:43.914038Z","caller":"traceutil/trace.go:171","msg":"trace[61863514] transaction","detail":"{read_only:false; response_revision:1827; number_of_response:1; }","duration":"747.640037ms","start":"2026-05-19T18:27:43.166386Z","end":"2026-05-19T18:27:43.914026Z","steps":["trace[61863514] 'process raft request' (duration: 746.997168ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:27:43.914931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:27:43.166362Z","time spent":"748.52532ms","remote":"127.0.0.1:54858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T18:27:43.923581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"619.483205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T18:27:43.923651Z","caller":"traceutil/trace.go:171","msg":"trace[1252624263] 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:1828; }","duration":"619.608267ms","start":"2026-05-19T18:27:43.304027Z","end":"2026-05-19T18:27:43.923636Z","steps":["trace[1252624263] 'agreement among raft nodes before linearized reading' (duration: 619.478946ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:27:43.923713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.949774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T18:27:43.923814Z","caller":"traceutil/trace.go:171","msg":"trace[1532583141] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1828; }","duration":"542.080426ms","start":"2026-05-19T18:27:43.381721Z","end":"2026-05-19T18:27:43.923801Z","steps":["trace[1532583141] 'agreement among raft nodes before linearized reading' (duration: 541.961934ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:27:43.923845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:27:43.381703Z","time spent":"542.134876ms","remote":"127.0.0.1:54576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-19T18:27:43.923845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:27:43.304Z","time spent":"619.83179ms","remote":"127.0.0.1:57140","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true "} {"level":"warn","ts":"2026-05-19T18:27:55.895724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.505172ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-05-19T18:27:56.085192Z","caller":"traceutil/trace.go:171","msg":"trace[1821612687] transaction","detail":"{read_only:false; response_revision:1927; number_of_response:1; }","duration":"286.315166ms","start":"2026-05-19T18:27:55.79884Z","end":"2026-05-19T18:27:56.085155Z","steps":["trace[1821612687] 'process raft request' (duration: 286.054952ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:27:57.261858Z","caller":"traceutil/trace.go:171","msg":"trace[294319874] transaction","detail":"{read_only:false; response_revision:1928; number_of_response:1; }","duration":"1.310312917s","start":"2026-05-19T18:27:55.951523Z","end":"2026-05-19T18:27:57.261836Z","steps":["trace[294319874] 'process raft request' (duration: 1.310139845s)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:27:57.262015Z","caller":"traceutil/trace.go:171","msg":"trace[1165540788] transaction","detail":"{read_only:false; response_revision:1929; number_of_response:1; }","duration":"1.309077189s","start":"2026-05-19T18:27:55.952926Z","end":"2026-05-19T18:27:57.262004Z","steps":["trace[1165540788] 'process raft request' (duration: 1.308874286s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:27:57.262079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:27:55.952841Z","time spent":"1.309207022s","remote":"127.0.0.1:54858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T18:27:57.262168Z","caller":"traceutil/trace.go:171","msg":"trace[83676942] transaction","detail":"{read_only:false; response_revision:1930; number_of_response:1; }","duration":"1.233082438s","start":"2026-05-19T18:27:56.029079Z","end":"2026-05-19T18:27:57.262161Z","steps":["trace[83676942] 'process raft request' (duration: 1.232883566s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:27:57.262243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:27:55.951495Z","time spent":"1.310431118s","remote":"127.0.0.1:54858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T18:27:57.262275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:27:56.02906Z","time spent":"1.233192039s","remote":"127.0.0.1:54858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T18:27:57.262125Z","caller":"traceutil/trace.go:171","msg":"trace[495794281] linearizableReadLoop","detail":"{readStateIndex:2002; appliedIndex:1999; }","duration":"1.282610927s","start":"2026-05-19T18:27:55.979493Z","end":"2026-05-19T18:27:57.262104Z","steps":["trace[495794281] 'read index received' (duration: 105.372135ms)","trace[495794281] 'applied index is now lower than readState.Index' (duration: 1.177236892s)"],"step_count":2} {"level":"warn","ts":"2026-05-19T18:27:57.26342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.283916346s","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-05-19T18:27:57.263494Z","caller":"traceutil/trace.go:171","msg":"trace[1767482504] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1930; }","duration":"1.283996867s","start":"2026-05-19T18:27:55.979463Z","end":"2026-05-19T18:27:57.263459Z","steps":["trace[1767482504] 'agreement among raft nodes before linearized reading' (duration: 1.282741299s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:27:57.263536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:27:55.979451Z","time spent":"1.284077407s","remote":"127.0.0.1:54858","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-05-19T18:27:57.422826Z","caller":"traceutil/trace.go:171","msg":"trace[1533155366] transaction","detail":"{read_only:false; response_revision:1931; number_of_response:1; }","duration":"199.99145ms","start":"2026-05-19T18:27:57.222801Z","end":"2026-05-19T18:27:57.422792Z","steps":["trace[1533155366] 'process raft request' (duration: 114.481048ms)","trace[1533155366] 'compare' (duration: 85.214938ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:27:57.422911Z","caller":"traceutil/trace.go:171","msg":"trace[2112030983] transaction","detail":"{read_only:false; response_revision:1932; number_of_response:1; }","duration":"154.901372ms","start":"2026-05-19T18:27:57.267986Z","end":"2026-05-19T18:27:57.422887Z","steps":["trace[2112030983] 'process raft request' (duration: 154.777161ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:27:57.423102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.442478277s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-19T18:27:57.423114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"532.739408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-19T18:27:57.423154Z","caller":"traceutil/trace.go:171","msg":"trace[239169395] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1932; }","duration":"1.442607579s","start":"2026-05-19T18:27:55.980536Z","end":"2026-05-19T18:27:57.423143Z","steps":["trace[239169395] 'agreement among raft nodes before linearized reading' (duration: 1.442437926s)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:27:57.423164Z","caller":"traceutil/trace.go:171","msg":"trace[1752874417] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:1932; }","duration":"532.8448ms","start":"2026-05-19T18:27:56.890305Z","end":"2026-05-19T18:27:57.42315Z","steps":["trace[1752874417] 'agreement among raft nodes before linearized reading' (duration: 532.741998ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:27:57.423188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:27:55.980448Z","time spent":"1.44273202s","remote":"127.0.0.1:54858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-19T18:27:57.422922Z","caller":"traceutil/trace.go:171","msg":"trace[1098612424] linearizableReadLoop","detail":"{readStateIndex:2004; appliedIndex:2003; }","duration":"160.444418ms","start":"2026-05-19T18:27:57.26245Z","end":"2026-05-19T18:27:57.422895Z","steps":["trace[1098612424] 'read index received' (duration: 74.864096ms)","trace[1098612424] 'applied index is now lower than readState.Index' (duration: 85.577452ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T18:27:57.423276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.43177254s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-05-19T18:27:57.423356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.43325165s","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-05-19T18:27:57.423363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.43325603s","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-05-19T18:27:57.423375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.433340752s","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-05-19T18:27:57.423413Z","caller":"traceutil/trace.go:171","msg":"trace[1115053056] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1932; }","duration":"1.433304801s","start":"2026-05-19T18:27:55.990094Z","end":"2026-05-19T18:27:57.423399Z","steps":["trace[1115053056] 'agreement among raft nodes before linearized reading' (duration: 1.433152119s)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:27:57.423424Z","caller":"traceutil/trace.go:171","msg":"trace[539678889] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1932; }","duration":"1.433410952s","start":"2026-05-19T18:27:55.990004Z","end":"2026-05-19T18:27:57.423415Z","steps":["trace[539678889] 'agreement among raft nodes before linearized reading' (duration: 1.433305231s)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:27:57.423432Z","caller":"traceutil/trace.go:171","msg":"trace[57189053] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1932; }","duration":"1.433364942s","start":"2026-05-19T18:27:55.990047Z","end":"2026-05-19T18:27:57.423412Z","steps":["trace[57189053] 'agreement among raft nodes before linearized reading' (duration: 1.43317912s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:27:57.423453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:27:55.989989Z","time spent":"1.433458534s","remote":"127.0.0.1:54858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-19T18:27:57.423441Z","caller":"traceutil/trace.go:171","msg":"trace[2091387229] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1932; }","duration":"1.431877551s","start":"2026-05-19T18:27:55.991462Z","end":"2026-05-19T18:27:57.42334Z","steps":["trace[2091387229] 'agreement among raft nodes before linearized reading' (duration: 1.431487966s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:27:57.423532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:27:55.990027Z","time spent":"1.433435373s","remote":"127.0.0.1:54858","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-05-19T18:27:57.423625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.127439659s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11982"} {"level":"warn","ts":"2026-05-19T18:27:57.423563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:27:55.99145Z","time spent":"1.432097884s","remote":"127.0.0.1:58630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-05-19T18:27:57.423667Z","caller":"traceutil/trace.go:171","msg":"trace[1948442584] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:1932; }","duration":"1.12752044s","start":"2026-05-19T18:27:56.296134Z","end":"2026-05-19T18:27:57.423655Z","steps":["trace[1948442584] 'agreement among raft nodes before linearized reading' (duration: 1.127382759s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:27:57.423723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:27:56.296115Z","time spent":"1.127579931s","remote":"127.0.0.1:54784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12004,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-05-19T18:27:57.423454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:27:55.990038Z","time spent":"1.433405343s","remote":"127.0.0.1:54858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-19T18:27:57.423209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:27:56.890284Z","time spent":"532.91159ms","remote":"127.0.0.1:55108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":5,"response size":29,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"info","ts":"2026-05-19T18:27:57.646893Z","caller":"traceutil/trace.go:171","msg":"trace[1823443245] transaction","detail":"{read_only:false; response_revision:1933; number_of_response:1; }","duration":"217.689502ms","start":"2026-05-19T18:27:57.429174Z","end":"2026-05-19T18:27:57.646864Z","steps":["trace[1823443245] 'process raft request' (duration: 206.706821ms)","trace[1823443245] 'compare' (duration: 10.759698ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:27:57.666858Z","caller":"traceutil/trace.go:171","msg":"trace[1034730216] linearizableReadLoop","detail":"{readStateIndex:2009; appliedIndex:2005; }","duration":"234.265618ms","start":"2026-05-19T18:27:57.432578Z","end":"2026-05-19T18:27:57.666844Z","steps":["trace[1034730216] 'read index received' (duration: 203.306584ms)","trace[1034730216] 'applied index is now lower than readState.Index' (duration: 30.958154ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T18:27:57.666955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.366941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T18:27:57.666924Z","caller":"traceutil/trace.go:171","msg":"trace[486477451] transaction","detail":"{read_only:false; response_revision:1935; number_of_response:1; }","duration":"236.977646ms","start":"2026-05-19T18:27:57.429909Z","end":"2026-05-19T18:27:57.666887Z","steps":["trace[486477451] 'process raft request' (duration: 236.859054ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:27:57.666966Z","caller":"traceutil/trace.go:171","msg":"trace[434875690] transaction","detail":"{read_only:false; response_revision:1934; number_of_response:1; }","duration":"237.179489ms","start":"2026-05-19T18:27:57.429764Z","end":"2026-05-19T18:27:57.666944Z","steps":["trace[434875690] 'process raft request' (duration: 236.911665ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:27:57.666986Z","caller":"traceutil/trace.go:171","msg":"trace[337071598] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:1936; }","duration":"234.439941ms","start":"2026-05-19T18:27:57.432536Z","end":"2026-05-19T18:27:57.666976Z","steps":["trace[337071598] 'agreement among raft nodes before linearized reading' (duration: 234.37367ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:27:57.667292Z","caller":"traceutil/trace.go:171","msg":"trace[1320801183] transaction","detail":"{read_only:false; response_revision:1936; number_of_response:1; }","duration":"236.877094ms","start":"2026-05-19T18:27:57.4304Z","end":"2026-05-19T18:27:57.667277Z","steps":["trace[1320801183] 'process raft request' (duration: 236.411708ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:27:58.088588Z","caller":"traceutil/trace.go:171","msg":"trace[1265554348] transaction","detail":"{read_only:false; response_revision:1938; number_of_response:1; }","duration":"126.691265ms","start":"2026-05-19T18:27:57.961861Z","end":"2026-05-19T18:27:58.088552Z","steps":["trace[1265554348] 'process raft request' (duration: 61.419191ms)","trace[1265554348] 'compare' (duration: 65.085201ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:27:58.174573Z","caller":"traceutil/trace.go:171","msg":"trace[75260016] transaction","detail":"{read_only:false; response_revision:1939; number_of_response:1; }","duration":"212.078834ms","start":"2026-05-19T18:27:57.962467Z","end":"2026-05-19T18:27:58.174546Z","steps":["trace[75260016] 'process raft request' (duration: 211.959313ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:27:59.85408Z","caller":"traceutil/trace.go:171","msg":"trace[801990492] transaction","detail":"{read_only:false; response_revision:1956; number_of_response:1; }","duration":"125.178293ms","start":"2026-05-19T18:27:59.728875Z","end":"2026-05-19T18:27:59.854053Z","steps":["trace[801990492] 'process raft request' (duration: 100.208612ms)","trace[801990492] 'compare' (duration: 24.85686ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:27:59.857819Z","caller":"traceutil/trace.go:171","msg":"trace[1793584230] transaction","detail":"{read_only:false; response_revision:1959; number_of_response:1; }","duration":"127.514705ms","start":"2026-05-19T18:27:59.730285Z","end":"2026-05-19T18:27:59.8578Z","steps":["trace[1793584230] 'process raft request' (duration: 127.474425ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:27:59.857821Z","caller":"traceutil/trace.go:171","msg":"trace[863631272] transaction","detail":"{read_only:false; response_revision:1957; number_of_response:1; }","duration":"128.366966ms","start":"2026-05-19T18:27:59.729427Z","end":"2026-05-19T18:27:59.857794Z","steps":["trace[863631272] 'process raft request' (duration: 128.206314ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:27:59.857931Z","caller":"traceutil/trace.go:171","msg":"trace[157106063] transaction","detail":"{read_only:false; response_revision:1958; number_of_response:1; }","duration":"128.428078ms","start":"2026-05-19T18:27:59.729483Z","end":"2026-05-19T18:27:59.857911Z","steps":["trace[157106063] 'process raft request' (duration: 128.253225ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:28:00.776722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.427617ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T18:28:00.776826Z","caller":"traceutil/trace.go:171","msg":"trace[1953022428] linearizableReadLoop","detail":"{readStateIndex:2034; appliedIndex:2033; }","duration":"173.689685ms","start":"2026-05-19T18:28:00.60312Z","end":"2026-05-19T18:28:00.776809Z","steps":["trace[1953022428] 'read index received' (duration: 41.091µs)","trace[1953022428] 'applied index is now lower than readState.Index' (duration: 173.647004ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T18:28:00.777013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.889308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-05-19T18:28:00.777049Z","caller":"traceutil/trace.go:171","msg":"trace[1734517775] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:1960; }","duration":"173.962128ms","start":"2026-05-19T18:28:00.603077Z","end":"2026-05-19T18:28:00.777039Z","steps":["trace[1734517775] 'agreement among raft nodes before linearized reading' (duration: 173.786916ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:28:00.776925Z","caller":"traceutil/trace.go:171","msg":"trace[1607472486] transaction","detail":"{read_only:false; response_revision:1960; number_of_response:1; }","duration":"243.651701ms","start":"2026-05-19T18:28:00.533246Z","end":"2026-05-19T18:28:00.776898Z","steps":["trace[1607472486] 'process raft request' (duration: 32.966201ms)","trace[1607472486] 'compare' (duration: 210.121803ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:28:01.609821Z","caller":"traceutil/trace.go:171","msg":"trace[1166818783] transaction","detail":"{read_only:false; response_revision:1962; number_of_response:1; }","duration":"309.773123ms","start":"2026-05-19T18:28:01.300022Z","end":"2026-05-19T18:28:01.609795Z","steps":["trace[1166818783] 'process raft request' (duration: 309.56424ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:28:01.61001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:28:01.300001Z","time spent":"309.902584ms","remote":"127.0.0.1:54858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T18:28:01.610834Z","caller":"traceutil/trace.go:171","msg":"trace[1425734112] linearizableReadLoop","detail":"{readStateIndex:2038; appliedIndex:2035; }","duration":"229.295824ms","start":"2026-05-19T18:28:01.38149Z","end":"2026-05-19T18:28:01.610785Z","steps":["trace[1425734112] 'read index received' (duration: 228.153438ms)","trace[1425734112] 'applied index is now lower than readState.Index' (duration: 1.141216ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:28:01.610933Z","caller":"traceutil/trace.go:171","msg":"trace[1899332643] transaction","detail":"{read_only:false; response_revision:1964; number_of_response:1; }","duration":"257.57687ms","start":"2026-05-19T18:28:01.353341Z","end":"2026-05-19T18:28:01.610918Z","steps":["trace[1899332643] 'process raft request' (duration: 257.351437ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:28:01.610995Z","caller":"traceutil/trace.go:171","msg":"trace[2063869673] transaction","detail":"{read_only:false; response_revision:1965; number_of_response:1; }","duration":"174.409974ms","start":"2026-05-19T18:28:01.436566Z","end":"2026-05-19T18:28:01.610976Z","steps":["trace[2063869673] 'process raft request' (duration: 174.176061ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:28:01.611268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.75891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T18:28:01.611313Z","caller":"traceutil/trace.go:171","msg":"trace[2146815317] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1965; }","duration":"229.853881ms","start":"2026-05-19T18:28:01.381447Z","end":"2026-05-19T18:28:01.611301Z","steps":["trace[2146815317] 'agreement among raft nodes before linearized reading' (duration: 229.742839ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:28:01.611713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.063391ms","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-05-19T18:28:01.61178Z","caller":"traceutil/trace.go:171","msg":"trace[1761394325] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1965; }","duration":"172.171283ms","start":"2026-05-19T18:28:01.439591Z","end":"2026-05-19T18:28:01.611763Z","steps":["trace[1761394325] 'agreement among raft nodes before linearized reading' (duration: 171.887629ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:28:01.610859Z","caller":"traceutil/trace.go:171","msg":"trace[1861741156] transaction","detail":"{read_only:false; response_revision:1963; number_of_response:1; }","duration":"257.54826ms","start":"2026-05-19T18:28:01.353245Z","end":"2026-05-19T18:28:01.610794Z","steps":["trace[1861741156] 'process raft request' (duration: 257.294286ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:28:01.611979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.751574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-19T18:28:01.612029Z","caller":"traceutil/trace.go:171","msg":"trace[1658283876] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1965; }","duration":"159.824994ms","start":"2026-05-19T18:28:01.452186Z","end":"2026-05-19T18:28:01.612011Z","steps":["trace[1658283876] 'agreement among raft nodes before linearized reading' (duration: 159.698372ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:28:07.763693Z","caller":"traceutil/trace.go:171","msg":"trace[1620848771] linearizableReadLoop","detail":"{readStateIndex:2071; appliedIndex:2068; }","duration":"104.990919ms","start":"2026-05-19T18:28:07.658678Z","end":"2026-05-19T18:28:07.763669Z","steps":["trace[1620848771] 'read index received' (duration: 104.751406ms)","trace[1620848771] 'applied index is now lower than readState.Index' (duration: 238.543µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:28:07.763817Z","caller":"traceutil/trace.go:171","msg":"trace[1155430803] transaction","detail":"{read_only:false; response_revision:1996; number_of_response:1; }","duration":"116.887712ms","start":"2026-05-19T18:28:07.646917Z","end":"2026-05-19T18:28:07.763805Z","steps":["trace[1155430803] 'process raft request' (duration: 116.708889ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:28:07.763817Z","caller":"traceutil/trace.go:171","msg":"trace[1458270890] transaction","detail":"{read_only:false; response_revision:1995; number_of_response:1; }","duration":"118.842699ms","start":"2026-05-19T18:28:07.644948Z","end":"2026-05-19T18:28:07.763791Z","steps":["trace[1458270890] 'process raft request' (duration: 118.643336ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:28:07.764058Z","caller":"traceutil/trace.go:171","msg":"trace[50240725] transaction","detail":"{read_only:false; response_revision:1994; number_of_response:1; }","duration":"120.099076ms","start":"2026-05-19T18:28:07.64395Z","end":"2026-05-19T18:28:07.764049Z","steps":["trace[50240725] 'process raft request' (duration: 119.504638ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:28:07.764243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.548236ms","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-05-19T18:28:07.764265Z","caller":"traceutil/trace.go:171","msg":"trace[2014873827] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1996; }","duration":"105.604538ms","start":"2026-05-19T18:28:07.658654Z","end":"2026-05-19T18:28:07.764259Z","steps":["trace[2014873827] 'agreement among raft nodes before linearized reading' (duration: 105.492786ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:28:14.967466Z","caller":"traceutil/trace.go:171","msg":"trace[632588022] transaction","detail":"{read_only:false; response_revision:2040; number_of_response:1; }","duration":"102.194588ms","start":"2026-05-19T18:28:14.86524Z","end":"2026-05-19T18:28:14.967435Z","steps":["trace[632588022] 'process raft request' (duration: 101.991215ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:28:16.151504Z","caller":"traceutil/trace.go:171","msg":"trace[553488041] linearizableReadLoop","detail":"{readStateIndex:2128; appliedIndex:2127; }","duration":"171.546117ms","start":"2026-05-19T18:28:15.979933Z","end":"2026-05-19T18:28:16.151479Z","steps":["trace[553488041] 'read index received' (duration: 168.133581ms)","trace[553488041] 'applied index is now lower than readState.Index' (duration: 3.411556ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:28:16.151554Z","caller":"traceutil/trace.go:171","msg":"trace[1724182225] transaction","detail":"{read_only:false; response_revision:2051; number_of_response:1; }","duration":"193.17727ms","start":"2026-05-19T18:28:15.958354Z","end":"2026-05-19T18:28:16.151532Z","steps":["trace[1724182225] 'process raft request' (duration: 189.780995ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:28:16.151674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.158007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1853"} {"level":"warn","ts":"2026-05-19T18:28:16.151675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.730809ms","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-05-19T18:28:16.151709Z","caller":"traceutil/trace.go:171","msg":"trace[377651697] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2051; }","duration":"170.192958ms","start":"2026-05-19T18:28:15.981504Z","end":"2026-05-19T18:28:16.151697Z","steps":["trace[377651697] 'agreement among raft nodes before linearized reading' (duration: 170.100816ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:28:16.151714Z","caller":"traceutil/trace.go:171","msg":"trace[1952138669] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2051; }","duration":"171.81958ms","start":"2026-05-19T18:28:15.979884Z","end":"2026-05-19T18:28:16.151704Z","steps":["trace[1952138669] 'agreement among raft nodes before linearized reading' (duration: 171.671538ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:28:16.152019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.397796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-19T18:28:16.152046Z","caller":"traceutil/trace.go:171","msg":"trace[8934761] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:2051; }","duration":"149.444276ms","start":"2026-05-19T18:28:16.002595Z","end":"2026-05-19T18:28:16.152039Z","steps":["trace[8934761] 'agreement among raft nodes before linearized reading' (duration: 149.206133ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:28:17.024279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"643.387396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T18:28:17.024351Z","caller":"traceutil/trace.go:171","msg":"trace[156901666] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2053; }","duration":"643.513818ms","start":"2026-05-19T18:28:16.380821Z","end":"2026-05-19T18:28:17.024334Z","steps":["trace[156901666] 'range keys from in-memory index tree' (duration: 643.217113ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:28:17.024397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:28:16.380799Z","time spent":"643.589278ms","remote":"127.0.0.1:54576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-19T18:28:17.024564Z","caller":"traceutil/trace.go:171","msg":"trace[2016597042] linearizableReadLoop","detail":"{readStateIndex:2131; appliedIndex:2130; }","duration":"198.223369ms","start":"2026-05-19T18:28:16.826325Z","end":"2026-05-19T18:28:17.024549Z","steps":["trace[2016597042] 'read index received' (duration: 190.884348ms)","trace[2016597042] 'applied index is now lower than readState.Index' (duration: 7.338051ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:28:17.024786Z","caller":"traceutil/trace.go:171","msg":"trace[1584578683] transaction","detail":"{read_only:false; response_revision:2054; number_of_response:1; }","duration":"553.529148ms","start":"2026-05-19T18:28:16.471241Z","end":"2026-05-19T18:28:17.024771Z","steps":["trace[1584578683] 'process raft request' (duration: 545.988515ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:28:17.024777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.445191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-19T18:28:17.024969Z","caller":"traceutil/trace.go:171","msg":"trace[675268595] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2054; }","duration":"198.666515ms","start":"2026-05-19T18:28:16.826274Z","end":"2026-05-19T18:28:17.024941Z","steps":["trace[675268595] 'agreement among raft nodes before linearized reading' (duration: 198.387021ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:28:17.025105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:28:16.471216Z","time spent":"553.682799ms","remote":"127.0.0.1:54858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T18:28:18.73098Z","caller":"traceutil/trace.go:171","msg":"trace[1661950010] linearizableReadLoop","detail":"{readStateIndex:2139; appliedIndex:2138; }","duration":"127.603859ms","start":"2026-05-19T18:28:18.60335Z","end":"2026-05-19T18:28:18.730954Z","steps":["trace[1661950010] 'read index received' (duration: 127.328705ms)","trace[1661950010] 'applied index is now lower than readState.Index' (duration: 274.414µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:28:18.731117Z","caller":"traceutil/trace.go:171","msg":"trace[1085090750] transaction","detail":"{read_only:false; response_revision:2062; number_of_response:1; }","duration":"159.497051ms","start":"2026-05-19T18:28:18.571602Z","end":"2026-05-19T18:28:18.731099Z","steps":["trace[1085090750] 'process raft request' (duration: 159.182697ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:28:18.73135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.658935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T18:28:18.731459Z","caller":"traceutil/trace.go:171","msg":"trace[2116336142] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2062; }","duration":"126.812808ms","start":"2026-05-19T18:28:18.604625Z","end":"2026-05-19T18:28:18.731438Z","steps":["trace[2116336142] 'agreement among raft nodes before linearized reading' (duration: 126.661836ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:28:18.731668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.697067ms","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-05-19T18:28:18.731754Z","caller":"traceutil/trace.go:171","msg":"trace[1925968669] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2062; }","duration":"160.800589ms","start":"2026-05-19T18:28:18.570935Z","end":"2026-05-19T18:28:18.731735Z","steps":["trace[1925968669] 'agreement among raft nodes before linearized reading' (duration: 160.465714ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:28:20.316828Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5646843511888225400,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T18:28:20.336664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"763.846093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-19T18:28:20.33674Z","caller":"traceutil/trace.go:171","msg":"trace[2131384347] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2063; }","duration":"763.967775ms","start":"2026-05-19T18:28:19.572754Z","end":"2026-05-19T18:28:20.336722Z","steps":["trace[2131384347] 'range keys from in-memory index tree' (duration: 763.67339ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:28:20.336788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:28:19.572733Z","time spent":"764.044196ms","remote":"127.0.0.1:58630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-05-19T18:28:20.337075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"711.281882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1988"} {"level":"info","ts":"2026-05-19T18:28:20.337084Z","caller":"traceutil/trace.go:171","msg":"trace[823798696] transaction","detail":"{read_only:false; response_revision:2064; number_of_response:1; }","duration":"595.120588ms","start":"2026-05-19T18:28:19.741938Z","end":"2026-05-19T18:28:20.337059Z","steps":["trace[823798696] 'process raft request' (duration: 591.891035ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:28:20.337196Z","caller":"traceutil/trace.go:171","msg":"trace[944725124] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:2063; }","duration":"711.412713ms","start":"2026-05-19T18:28:19.625729Z","end":"2026-05-19T18:28:20.337142Z","steps":["trace[944725124] 'range keys from in-memory index tree' (duration: 711.119869ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:28:20.33723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:28:19.741914Z","time spent":"595.23965ms","remote":"127.0.0.1:54858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T18:28:20.337276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:28:19.625708Z","time spent":"711.549566ms","remote":"127.0.0.1:54790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":2010,"request content":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" "} {"level":"info","ts":"2026-05-19T18:28:20.339924Z","caller":"traceutil/trace.go:171","msg":"trace[417455294] linearizableReadLoop","detail":"{readStateIndex:2144; appliedIndex:2140; }","duration":"524.142627ms","start":"2026-05-19T18:28:19.815762Z","end":"2026-05-19T18:28:20.339905Z","steps":["trace[417455294] 'read index received' (duration: 518.190626ms)","trace[417455294] 'applied index is now lower than readState.Index' (duration: 5.950861ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:28:20.340119Z","caller":"traceutil/trace.go:171","msg":"trace[233015584] transaction","detail":"{read_only:false; response_revision:2065; number_of_response:1; }","duration":"529.632901ms","start":"2026-05-19T18:28:19.810475Z","end":"2026-05-19T18:28:20.340108Z","steps":["trace[233015584] 'process raft request' (duration: 529.234866ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:28:20.340273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:28:19.810449Z","time spent":"529.747793ms","remote":"127.0.0.1:54858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T18:28:20.34049Z","caller":"traceutil/trace.go:171","msg":"trace[785930431] transaction","detail":"{read_only:false; response_revision:2066; number_of_response:1; }","duration":"523.996585ms","start":"2026-05-19T18:28:19.816463Z","end":"2026-05-19T18:28:20.340459Z","steps":["trace[785930431] 'process raft request' (duration: 523.378256ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:28:20.340609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:28:19.816449Z","time spent":"524.124316ms","remote":"127.0.0.1:54858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T18:28:20.340734Z","caller":"traceutil/trace.go:171","msg":"trace[1264622991] transaction","detail":"{read_only:false; response_revision:2067; number_of_response:1; }","duration":"523.140733ms","start":"2026-05-19T18:28:19.817561Z","end":"2026-05-19T18:28:20.340701Z","steps":["trace[1264622991] 'process raft request' (duration: 522.314481ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:28:20.340781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.019539ms","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-05-19T18:28:20.340816Z","caller":"traceutil/trace.go:171","msg":"trace[917795732] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2067; }","duration":"525.084271ms","start":"2026-05-19T18:28:19.815723Z","end":"2026-05-19T18:28:20.340807Z","steps":["trace[917795732] 'agreement among raft nodes before linearized reading' (duration: 524.945889ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:28:20.34083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:28:19.817517Z","time spent":"523.266895ms","remote":"127.0.0.1:54858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T18:28:20.340868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:28:19.815711Z","time spent":"525.149951ms","remote":"127.0.0.1:54858","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-05-19T18:28:20.341073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.073915ms","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-05-19T18:28:20.341189Z","caller":"traceutil/trace.go:171","msg":"trace[1191963642] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2067; }","duration":"487.191607ms","start":"2026-05-19T18:28:19.853918Z","end":"2026-05-19T18:28:20.34111Z","steps":["trace[1191963642] 'agreement among raft nodes before linearized reading' (duration: 486.944363ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:28:20.341227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:28:19.8539Z","time spent":"487.318098ms","remote":"127.0.0.1:54858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-19T18:28:21.075361Z","caller":"traceutil/trace.go:171","msg":"trace[219137850] transaction","detail":"{read_only:false; response_revision:2073; number_of_response:1; }","duration":"337.363946ms","start":"2026-05-19T18:28:20.737977Z","end":"2026-05-19T18:28:21.075341Z","steps":["trace[219137850] 'process raft request' (duration: 336.13597ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:28:21.075503Z","caller":"traceutil/trace.go:171","msg":"trace[1370940176] linearizableReadLoop","detail":"{readStateIndex:2152; appliedIndex:2150; }","duration":"325.341084ms","start":"2026-05-19T18:28:20.750142Z","end":"2026-05-19T18:28:21.075483Z","steps":["trace[1370940176] 'read index received' (duration: 323.979256ms)","trace[1370940176] 'applied index is now lower than readState.Index' (duration: 1.360588ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T18:28:21.075591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:28:20.737953Z","time spent":"337.537988ms","remote":"127.0.0.1:54858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T18:28:21.075659Z","caller":"traceutil/trace.go:171","msg":"trace[1298948365] transaction","detail":"{read_only:false; response_revision:2074; number_of_response:1; }","duration":"336.87828ms","start":"2026-05-19T18:28:20.738769Z","end":"2026-05-19T18:28:21.075647Z","steps":["trace[1298948365] 'process raft request' (duration: 336.537386ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:28:21.075694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.550287ms","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-05-19T18:28:21.075747Z","caller":"traceutil/trace.go:171","msg":"trace[1642831110] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2074; }","duration":"325.636117ms","start":"2026-05-19T18:28:20.750098Z","end":"2026-05-19T18:28:21.075734Z","steps":["trace[1642831110] 'agreement among raft nodes before linearized reading' (duration: 325.456745ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:28:21.075712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:28:20.738745Z","time spent":"336.941981ms","remote":"127.0.0.1:54858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T18:28:21.075792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:28:20.750083Z","time spent":"325.698659ms","remote":"127.0.0.1:54858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-19T18:28:24.666528Z","caller":"traceutil/trace.go:171","msg":"trace[503418014] transaction","detail":"{read_only:false; response_revision:2087; number_of_response:1; }","duration":"258.972501ms","start":"2026-05-19T18:28:24.407532Z","end":"2026-05-19T18:28:24.666504Z","steps":["trace[503418014] 'process raft request' (duration: 258.809239ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:28:24.666841Z","caller":"traceutil/trace.go:171","msg":"trace[657054263] transaction","detail":"{read_only:false; response_revision:2088; number_of_response:1; }","duration":"259.275895ms","start":"2026-05-19T18:28:24.407536Z","end":"2026-05-19T18:28:24.666812Z","steps":["trace[657054263] 'process raft request' (duration: 258.931951ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:28:24.666989Z","caller":"traceutil/trace.go:171","msg":"trace[403157496] linearizableReadLoop","detail":"{readStateIndex:2167; appliedIndex:2163; }","duration":"254.921126ms","start":"2026-05-19T18:28:24.412048Z","end":"2026-05-19T18:28:24.666969Z","steps":["trace[403157496] 'read index received' (duration: 20.398206ms)","trace[403157496] 'applied index is now lower than readState.Index' (duration: 234.52202ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T18:28:24.667267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.734219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-19T18:28:24.6673Z","caller":"traceutil/trace.go:171","msg":"trace[1899378304] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2089; }","duration":"250.79532ms","start":"2026-05-19T18:28:24.416493Z","end":"2026-05-19T18:28:24.667289Z","steps":["trace[1899378304] 'agreement among raft nodes before linearized reading' (duration: 250.671138ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:28:24.667473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.505389ms","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-05-19T18:28:24.667831Z","caller":"traceutil/trace.go:171","msg":"trace[36991316] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2089; }","duration":"248.894103ms","start":"2026-05-19T18:28:24.418901Z","end":"2026-05-19T18:28:24.667795Z","steps":["trace[36991316] 'agreement among raft nodes before linearized reading' (duration: 248.342166ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:28:24.667088Z","caller":"traceutil/trace.go:171","msg":"trace[547596459] transaction","detail":"{read_only:false; response_revision:2089; number_of_response:1; }","duration":"258.301181ms","start":"2026-05-19T18:28:24.408732Z","end":"2026-05-19T18:28:24.667033Z","steps":["trace[547596459] 'process raft request' (duration: 258.107399ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:28:24.667288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.23097ms","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-05-19T18:28:24.668212Z","caller":"traceutil/trace.go:171","msg":"trace[1764475532] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2089; }","duration":"256.174972ms","start":"2026-05-19T18:28:24.412018Z","end":"2026-05-19T18:28:24.668193Z","steps":["trace[1764475532] 'agreement among raft nodes before linearized reading' (duration: 255.120329ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:28:25.194685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.205211ms","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-05-19T18:28:25.194758Z","caller":"traceutil/trace.go:171","msg":"trace[1754708028] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2092; }","duration":"105.311442ms","start":"2026-05-19T18:28:25.08943Z","end":"2026-05-19T18:28:25.194742Z","steps":["trace[1754708028] 'range keys from in-memory index tree' (duration: 105.051679ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:28:26.386571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.566533ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-05-19T18:28:26.386679Z","caller":"traceutil/trace.go:171","msg":"trace[248837298] linearizableReadLoop","detail":"{readStateIndex:2173; appliedIndex:2172; }","duration":"713.998804ms","start":"2026-05-19T18:28:25.672661Z","end":"2026-05-19T18:28:26.38666Z","steps":["trace[248837298] 'read index received' (duration: 278.34725ms)","trace[248837298] 'applied index is now lower than readState.Index' (duration: 435.650054ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T18:28:26.387074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"714.39591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-19T18:28:26.387121Z","caller":"traceutil/trace.go:171","msg":"trace[1261581570] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2094; }","duration":"714.478141ms","start":"2026-05-19T18:28:25.672628Z","end":"2026-05-19T18:28:26.387106Z","steps":["trace[1261581570] 'agreement among raft nodes before linearized reading' (duration: 714.298609ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:28:26.387136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"681.706198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18b10ae1555d0fb3\" ","response":"range_response_count:1 size:846"} {"level":"warn","ts":"2026-05-19T18:28:26.387156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:28:25.672612Z","time spent":"714.535492ms","remote":"127.0.0.1:58630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-05-19T18:28:26.387203Z","caller":"traceutil/trace.go:171","msg":"trace[1378350312] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.18b10ae1555d0fb3; range_end:; response_count:1; response_revision:2094; }","duration":"681.800299ms","start":"2026-05-19T18:28:25.705382Z","end":"2026-05-19T18:28:26.387183Z","steps":["trace[1378350312] 'agreement among raft nodes before linearized reading' (duration: 681.609557ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:28:26.387235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:28:25.705372Z","time spent":"681.85705ms","remote":"127.0.0.1:54684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":868,"request content":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18b10ae1555d0fb3\" "} {"level":"warn","ts":"2026-05-19T18:28:26.387207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.323868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T18:28:26.387282Z","caller":"traceutil/trace.go:171","msg":"trace[716184752] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:2094; }","duration":"144.44825ms","start":"2026-05-19T18:28:26.242821Z","end":"2026-05-19T18:28:26.387269Z","steps":["trace[716184752] 'agreement among raft nodes before linearized reading' (duration: 144.325829ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:28:26.387312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.824433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-19T18:28:26.387381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"672.713937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-19T18:28:26.387438Z","caller":"traceutil/trace.go:171","msg":"trace[882465185] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:2094; }","duration":"401.030746ms","start":"2026-05-19T18:28:25.986377Z","end":"2026-05-19T18:28:26.387408Z","steps":["trace[882465185] 'agreement among raft nodes before linearized reading' (duration: 400.823014ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:28:26.387486Z","caller":"traceutil/trace.go:171","msg":"trace[1059566508] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:2094; }","duration":"672.870769ms","start":"2026-05-19T18:28:25.714582Z","end":"2026-05-19T18:28:26.387453Z","steps":["trace[1059566508] 'agreement among raft nodes before linearized reading' (duration: 672.661996ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:28:26.387499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:28:25.986335Z","time spent":"401.151467ms","remote":"127.0.0.1:49528","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":2,"response size":29,"request content":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true "} {"level":"warn","ts":"2026-05-19T18:28:26.387543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:28:25.714568Z","time spent":"672.96292ms","remote":"127.0.0.1:49534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":2,"response size":29,"request content":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true "} {"level":"warn","ts":"2026-05-19T18:28:26.892017Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5646843511888225472,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-19T18:28:26.961203Z","caller":"traceutil/trace.go:171","msg":"trace[2061253454] transaction","detail":"{read_only:false; response_revision:2095; number_of_response:1; }","duration":"569.986827ms","start":"2026-05-19T18:28:26.391176Z","end":"2026-05-19T18:28:26.961163Z","steps":["trace[2061253454] 'process raft request' (duration: 569.806615ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:28:26.961596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:28:26.391154Z","time spent":"570.15395ms","remote":"127.0.0.1:54684","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":831,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T18:28:26.97209Z","caller":"traceutil/trace.go:171","msg":"trace[1215968099] transaction","detail":"{read_only:false; response_revision:2097; number_of_response:1; }","duration":"300.5935ms","start":"2026-05-19T18:28:26.671458Z","end":"2026-05-19T18:28:26.972051Z","steps":["trace[1215968099] 'process raft request' (duration: 300.510719ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:28:26.972238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:28:26.671435Z","time spent":"300.729941ms","remote":"127.0.0.1:54858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T18:28:26.972383Z","caller":"traceutil/trace.go:171","msg":"trace[735744281] transaction","detail":"{read_only:false; response_revision:2096; number_of_response:1; }","duration":"535.196188ms","start":"2026-05-19T18:28:26.43716Z","end":"2026-05-19T18:28:26.972356Z","steps":["trace[735744281] 'process raft request' (duration: 534.683471ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:28:26.972584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:28:26.437111Z","time spent":"535.33282ms","remote":"127.0.0.1:54858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T18:28:26.972611Z","caller":"traceutil/trace.go:171","msg":"trace[1578913706] transaction","detail":"{read_only:false; response_revision:2098; number_of_response:1; }","duration":"299.685687ms","start":"2026-05-19T18:28:26.672867Z","end":"2026-05-19T18:28:26.972553Z","steps":["trace[1578913706] 'process raft request' (duration: 299.14892ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:28:26.972806Z","caller":"traceutil/trace.go:171","msg":"trace[1183227516] linearizableReadLoop","detail":"{readStateIndex:2178; appliedIndex:2174; }","duration":"581.476263ms","start":"2026-05-19T18:28:26.391314Z","end":"2026-05-19T18:28:26.97279Z","steps":["trace[1183227516] 'read index received' (duration: 569.914467ms)","trace[1183227516] 'applied index is now lower than readState.Index' (duration: 11.560246ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:28:26.972931Z","caller":"traceutil/trace.go:171","msg":"trace[2077971468] transaction","detail":"{read_only:false; response_revision:2099; number_of_response:1; }","duration":"299.587176ms","start":"2026-05-19T18:28:26.673334Z","end":"2026-05-19T18:28:26.972921Z","steps":["trace[2077971468] 'process raft request' (duration: 299.11888ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:28:26.973166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.619384ms","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-05-19T18:28:26.973247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.915012ms","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-05-19T18:28:26.973306Z","caller":"traceutil/trace.go:171","msg":"trace[752229363] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2099; }","duration":"269.049134ms","start":"2026-05-19T18:28:26.704244Z","end":"2026-05-19T18:28:26.973293Z","steps":["trace[752229363] 'agreement among raft nodes before linearized reading' (duration: 268.920202ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:28:26.973573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"582.254444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T18:28:26.973608Z","caller":"traceutil/trace.go:171","msg":"trace[732408742] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2099; }","duration":"582.316374ms","start":"2026-05-19T18:28:26.391284Z","end":"2026-05-19T18:28:26.9736Z","steps":["trace[732408742] 'agreement among raft nodes before linearized reading' (duration: 582.246763ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:28:26.973765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:28:26.391272Z","time spent":"582.486447ms","remote":"127.0.0.1:54584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-19T18:28:26.973247Z","caller":"traceutil/trace.go:171","msg":"trace[1764068923] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2099; }","duration":"284.734775ms","start":"2026-05-19T18:28:26.688497Z","end":"2026-05-19T18:28:26.973231Z","steps":["trace[1764068923] 'agreement among raft nodes before linearized reading' (duration: 284.450131ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:28:26.974456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.608262ms","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-05-19T18:28:26.974787Z","caller":"traceutil/trace.go:171","msg":"trace[1521146402] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2099; }","duration":"270.100988ms","start":"2026-05-19T18:28:26.704668Z","end":"2026-05-19T18:28:26.974769Z","steps":["trace[1521146402] 'agreement among raft nodes before linearized reading' (duration: 268.363615ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:28:27.620133Z","caller":"traceutil/trace.go:171","msg":"trace[1717167243] linearizableReadLoop","detail":"{readStateIndex:2181; appliedIndex:2178; }","duration":"544.703413ms","start":"2026-05-19T18:28:27.075405Z","end":"2026-05-19T18:28:27.620108Z","steps":["trace[1717167243] 'read index received' (duration: 485.16713ms)","trace[1717167243] 'applied index is now lower than readState.Index' (duration: 59.535253ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:28:27.620156Z","caller":"traceutil/trace.go:171","msg":"trace[274156027] transaction","detail":"{read_only:false; response_revision:2101; number_of_response:1; }","duration":"641.220486ms","start":"2026-05-19T18:28:26.978906Z","end":"2026-05-19T18:28:27.620127Z","steps":["trace[274156027] 'process raft request' (duration: 641.068864ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:28:27.620249Z","caller":"traceutil/trace.go:171","msg":"trace[1663526645] transaction","detail":"{read_only:false; response_revision:2102; number_of_response:1; }","duration":"640.619748ms","start":"2026-05-19T18:28:26.979618Z","end":"2026-05-19T18:28:27.620238Z","steps":["trace[1663526645] 'process raft request' (duration: 640.416005ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:28:27.620354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:28:26.979594Z","time spent":"640.680558ms","remote":"127.0.0.1:54858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T18:28:27.620376Z","caller":"traceutil/trace.go:171","msg":"trace[1372862835] transaction","detail":"{read_only:false; response_revision:2100; number_of_response:1; }","duration":"642.722186ms","start":"2026-05-19T18:28:26.977631Z","end":"2026-05-19T18:28:27.620353Z","steps":["trace[1372862835] 'process raft request' (duration: 582.991191ms)","trace[1372862835] 'compare' (duration: 59.181258ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T18:28:27.620551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:28:26.977609Z","time spent":"642.894078ms","remote":"127.0.0.1:54858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T18:28:27.620576Z","caller":"traceutil/trace.go:171","msg":"trace[1565249410] transaction","detail":"{read_only:false; response_revision:2103; number_of_response:1; }","duration":"428.677107ms","start":"2026-05-19T18:28:27.191867Z","end":"2026-05-19T18:28:27.620544Z","steps":["trace[1565249410] 'process raft request' (duration: 428.207451ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:28:27.620862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.346776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-05-19T18:28:27.620913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:28:27.191843Z","time spent":"428.805518ms","remote":"127.0.0.1:54858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T18:28:27.621198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.527956ms","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-05-19T18:28:27.621246Z","caller":"traceutil/trace.go:171","msg":"trace[1807395726] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2103; }","duration":"331.641398ms","start":"2026-05-19T18:28:27.289592Z","end":"2026-05-19T18:28:27.621234Z","steps":["trace[1807395726] 'agreement among raft nodes before linearized reading' (duration: 331.443845ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:28:27.621278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:28:27.289561Z","time spent":"331.710189ms","remote":"127.0.0.1:54858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-19T18:28:27.621589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.11433ms","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-05-19T18:28:27.62164Z","caller":"traceutil/trace.go:171","msg":"trace[1414176963] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2103; }","duration":"388.16792ms","start":"2026-05-19T18:28:27.23346Z","end":"2026-05-19T18:28:27.621628Z","steps":["trace[1414176963] 'agreement among raft nodes before linearized reading' (duration: 386.990914ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:28:27.621715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:28:27.233379Z","time spent":"388.325602ms","remote":"127.0.0.1:54858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-19T18:28:27.621621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:28:26.978898Z","time spent":"641.321369ms","remote":"127.0.0.1:54858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T18:28:27.622281Z","caller":"traceutil/trace.go:171","msg":"trace[696182750] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2103; }","duration":"223.479487ms","start":"2026-05-19T18:28:27.397451Z","end":"2026-05-19T18:28:27.620931Z","steps":["trace[696182750] 'agreement among raft nodes before linearized reading' (duration: 223.083271ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:28:27.622542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"547.100816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-19T18:28:27.622879Z","caller":"traceutil/trace.go:171","msg":"trace[1405939537] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:2103; }","duration":"547.46311ms","start":"2026-05-19T18:28:27.075389Z","end":"2026-05-19T18:28:27.622852Z","steps":["trace[1405939537] 'agreement among raft nodes before linearized reading' (duration: 544.980127ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:28:27.623027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:28:27.075331Z","time spent":"547.675454ms","remote":"127.0.0.1:54924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":4,"response size":29,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"info","ts":"2026-05-19T18:28:33.308249Z","caller":"traceutil/trace.go:171","msg":"trace[93568437] transaction","detail":"{read_only:false; response_revision:2129; number_of_response:1; }","duration":"315.976897ms","start":"2026-05-19T18:28:32.992247Z","end":"2026-05-19T18:28:33.308224Z","steps":["trace[93568437] 'process raft request' (duration: 315.790504ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:28:33.308409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:28:32.992219Z","time spent":"316.104609ms","remote":"127.0.0.1:54858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T18:28:33.318948Z","caller":"traceutil/trace.go:171","msg":"trace[1621333294] transaction","detail":"{read_only:false; response_revision:2130; number_of_response:1; }","duration":"324.703064ms","start":"2026-05-19T18:28:32.994223Z","end":"2026-05-19T18:28:33.318926Z","steps":["trace[1621333294] 'process raft request' (duration: 324.45711ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:28:33.31918Z","caller":"traceutil/trace.go:171","msg":"trace[476218941] transaction","detail":"{read_only:false; response_revision:2132; number_of_response:1; }","duration":"317.745711ms","start":"2026-05-19T18:28:33.001419Z","end":"2026-05-19T18:28:33.319165Z","steps":["trace[476218941] 'process raft request' (duration: 317.63408ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:28:33.319175Z","caller":"traceutil/trace.go:171","msg":"trace[1371887100] transaction","detail":"{read_only:false; response_revision:2131; number_of_response:1; }","duration":"320.019922ms","start":"2026-05-19T18:28:32.999132Z","end":"2026-05-19T18:28:33.319152Z","steps":["trace[1371887100] 'process raft request' (duration: 319.664897ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:28:33.319161Z","caller":"traceutil/trace.go:171","msg":"trace[903823322] linearizableReadLoop","detail":"{readStateIndex:2212; appliedIndex:2208; }","duration":"142.364229ms","start":"2026-05-19T18:28:33.176775Z","end":"2026-05-19T18:28:33.31914Z","steps":["trace[903823322] 'read index received' (duration: 131.246479ms)","trace[903823322] 'applied index is now lower than readState.Index' (duration: 11.11662ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T18:28:33.319328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:28:33.001371Z","time spent":"317.881193ms","remote":"127.0.0.1:54858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T18:28:33.319359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:28:32.999087Z","time spent":"320.185674ms","remote":"127.0.0.1:54858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T18:28:33.319387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.570351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-19T18:28:33.31957Z","caller":"traceutil/trace.go:171","msg":"trace[632276510] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:2132; }","duration":"142.832454ms","start":"2026-05-19T18:28:33.176716Z","end":"2026-05-19T18:28:33.319549Z","steps":["trace[632276510] 'agreement among raft nodes before linearized reading' (duration: 142.51954ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:28:33.321151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:28:32.994207Z","time spent":"324.886337ms","remote":"127.0.0.1:54858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T18:29:40.866451Z","caller":"traceutil/trace.go:171","msg":"trace[1336493768] linearizableReadLoop","detail":"{readStateIndex:2693; appliedIndex:2692; }","duration":"151.305789ms","start":"2026-05-19T18:29:40.715122Z","end":"2026-05-19T18:29:40.866427Z","steps":["trace[1336493768] 'read index received' (duration: 151.124007ms)","trace[1336493768] 'applied index is now lower than readState.Index' (duration: 180.662µs)"],"step_count":2} {"level":"warn","ts":"2026-05-19T18:29:40.866587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.438341ms","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-05-19T18:29:40.86663Z","caller":"traceutil/trace.go:171","msg":"trace[1408495227] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:2596; }","duration":"151.491232ms","start":"2026-05-19T18:29:40.715117Z","end":"2026-05-19T18:29:40.866608Z","steps":["trace[1408495227] 'agreement among raft nodes before linearized reading' (duration: 151.40912ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:29:40.866875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:29:40.466578Z","time spent":"400.293895ms","remote":"127.0.0.1:54628","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-19T18:29:51.905868Z","caller":"traceutil/trace.go:171","msg":"trace[443854023] transaction","detail":"{read_only:false; response_revision:2663; number_of_response:1; }","duration":"170.878306ms","start":"2026-05-19T18:29:51.734964Z","end":"2026-05-19T18:29:51.905843Z","steps":["trace[443854023] 'process raft request' (duration: 170.486751ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:30:08.223815Z","caller":"traceutil/trace.go:171","msg":"trace[1597298623] transaction","detail":"{read_only:false; response_revision:2744; number_of_response:1; }","duration":"508.180769ms","start":"2026-05-19T18:30:07.715607Z","end":"2026-05-19T18:30:08.223788Z","steps":["trace[1597298623] 'process raft request' (duration: 507.912316ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:30:08.223955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:30:07.715566Z","time spent":"508.317401ms","remote":"127.0.0.1:54858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T18:30:08.636665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.58205ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T18:30:08.636824Z","caller":"traceutil/trace.go:171","msg":"trace[1080812560] linearizableReadLoop","detail":"{readStateIndex:2849; appliedIndex:2846; }","duration":"402.521948ms","start":"2026-05-19T18:30:08.234291Z","end":"2026-05-19T18:30:08.636813Z","steps":["trace[1080812560] 'read index received' (duration: 107.659225ms)","trace[1080812560] 'applied index is now lower than readState.Index' (duration: 294.861963ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:30:08.636881Z","caller":"traceutil/trace.go:171","msg":"trace[218509381] transaction","detail":"{read_only:false; response_revision:2745; number_of_response:1; }","duration":"803.406636ms","start":"2026-05-19T18:30:07.833468Z","end":"2026-05-19T18:30:08.636875Z","steps":["trace[218509381] 'process raft request' (duration: 508.551693ms)","trace[218509381] 'compare' (duration: 294.433598ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T18:30:08.636915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:30:07.833446Z","time spent":"803.451288ms","remote":"127.0.0.1:54858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T18:30:08.636988Z","caller":"traceutil/trace.go:171","msg":"trace[343090310] transaction","detail":"{read_only:false; response_revision:2746; number_of_response:1; }","duration":"765.09986ms","start":"2026-05-19T18:30:07.871883Z","end":"2026-05-19T18:30:08.636983Z","steps":["trace[343090310] 'process raft request' (duration: 764.853096ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:30:08.637014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:30:07.871864Z","time spent":"765.137311ms","remote":"127.0.0.1:54858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T18:30:08.637063Z","caller":"traceutil/trace.go:171","msg":"trace[1250422106] transaction","detail":"{read_only:false; response_revision:2747; number_of_response:1; }","duration":"633.934453ms","start":"2026-05-19T18:30:08.003111Z","end":"2026-05-19T18:30:08.637045Z","steps":["trace[1250422106] 'process raft request' (duration: 633.67865ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:30:08.637087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:30:08.003089Z","time spent":"633.986644ms","remote":"127.0.0.1:54858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T18:30:08.637202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.845831ms","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-05-19T18:30:08.637219Z","caller":"traceutil/trace.go:171","msg":"trace[372819348] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2747; }","duration":"244.882272ms","start":"2026-05-19T18:30:08.392332Z","end":"2026-05-19T18:30:08.637214Z","steps":["trace[372819348] 'agreement among raft nodes before linearized reading' (duration: 244.820301ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:30:08.637227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.689754ms","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-05-19T18:30:08.637281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.998784ms","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-05-19T18:30:08.637298Z","caller":"traceutil/trace.go:171","msg":"trace[84739822] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2747; }","duration":"403.045866ms","start":"2026-05-19T18:30:08.234248Z","end":"2026-05-19T18:30:08.637294Z","steps":["trace[84739822] 'agreement among raft nodes before linearized reading' (duration: 403.002065ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:30:08.637292Z","caller":"traceutil/trace.go:171","msg":"trace[63667005] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2747; }","duration":"313.801365ms","start":"2026-05-19T18:30:08.323465Z","end":"2026-05-19T18:30:08.637266Z","steps":["trace[63667005] 'agreement among raft nodes before linearized reading' (duration: 313.619293ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:30:08.637313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:30:08.234231Z","time spent":"403.078786ms","remote":"127.0.0.1:54858","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-05-19T18:30:08.637341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:30:08.323437Z","time spent":"313.889106ms","remote":"127.0.0.1:54858","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-05-19T18:30:08.637381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.361659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-05-19T18:30:08.637392Z","caller":"traceutil/trace.go:171","msg":"trace[1358573192] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2747; }","duration":"236.40288ms","start":"2026-05-19T18:30:08.400985Z","end":"2026-05-19T18:30:08.637388Z","steps":["trace[1358573192] 'agreement among raft nodes before linearized reading' (duration: 236.358559ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:30:08.637476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.262665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-19T18:30:08.637569Z","caller":"traceutil/trace.go:171","msg":"trace[674479013] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2747; }","duration":"247.374906ms","start":"2026-05-19T18:30:08.390175Z","end":"2026-05-19T18:30:08.63755Z","steps":["trace[674479013] 'agreement among raft nodes before linearized reading' (duration: 247.182852ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:30:08.637495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.544934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T18:30:08.63764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.297833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-19T18:30:08.637739Z","caller":"traceutil/trace.go:171","msg":"trace[921604950] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2747; }","duration":"268.443844ms","start":"2026-05-19T18:30:08.369271Z","end":"2026-05-19T18:30:08.637714Z","steps":["trace[921604950] 'agreement among raft nodes before linearized reading' (duration: 268.16489ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:30:08.637757Z","caller":"traceutil/trace.go:171","msg":"trace[795615444] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2747; }","duration":"255.898419ms","start":"2026-05-19T18:30:08.38178Z","end":"2026-05-19T18:30:08.637678Z","steps":["trace[795615444] 'agreement among raft nodes before linearized reading' (duration: 255.375271ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:30:08.940976Z","caller":"traceutil/trace.go:171","msg":"trace[278094041] transaction","detail":"{read_only:false; response_revision:2750; number_of_response:1; }","duration":"298.121088ms","start":"2026-05-19T18:30:08.642835Z","end":"2026-05-19T18:30:08.940956Z","steps":["trace[278094041] 'process raft request' (duration: 298.035727ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:30:08.941011Z","caller":"traceutil/trace.go:171","msg":"trace[275034393] transaction","detail":"{read_only:false; response_revision:2749; number_of_response:1; }","duration":"298.218609ms","start":"2026-05-19T18:30:08.64275Z","end":"2026-05-19T18:30:08.940968Z","steps":["trace[275034393] 'process raft request' (duration: 272.112644ms)","trace[275034393] 'compare' (duration: 25.70258ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:30:08.941105Z","caller":"traceutil/trace.go:171","msg":"trace[1403762898] transaction","detail":"{read_only:false; response_revision:2751; number_of_response:1; }","duration":"297.966175ms","start":"2026-05-19T18:30:08.643131Z","end":"2026-05-19T18:30:08.941098Z","steps":["trace[1403762898] 'process raft request' (duration: 297.798173ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:30:08.941126Z","caller":"traceutil/trace.go:171","msg":"trace[1344650712] transaction","detail":"{read_only:false; response_revision:2752; number_of_response:1; }","duration":"297.026003ms","start":"2026-05-19T18:30:08.644082Z","end":"2026-05-19T18:30:08.941108Z","steps":["trace[1344650712] 'process raft request' (duration: 296.939292ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:30:08.941125Z","caller":"traceutil/trace.go:171","msg":"trace[2043553420] linearizableReadLoop","detail":"{readStateIndex:2854; appliedIndex:2849; }","duration":"296.437846ms","start":"2026-05-19T18:30:08.644659Z","end":"2026-05-19T18:30:08.941097Z","steps":["trace[2043553420] 'read index received' (duration: 30.053859ms)","trace[2043553420] 'applied index is now lower than readState.Index' (duration: 266.382877ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T18:30:08.941334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.174254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18b10af95047e0e8\" ","response":"range_response_count:1 size:779"} {"level":"info","ts":"2026-05-19T18:30:08.941359Z","caller":"traceutil/trace.go:171","msg":"trace[541317208] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18b10af95047e0e8; range_end:; response_count:1; response_revision:2752; }","duration":"241.257874ms","start":"2026-05-19T18:30:08.700094Z","end":"2026-05-19T18:30:08.941352Z","steps":["trace[541317208] 'agreement among raft nodes before linearized reading' (duration: 241.156953ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:30:08.941475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.79165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak\" ","response":"range_response_count:1 size:1317"} {"level":"info","ts":"2026-05-19T18:30:08.94166Z","caller":"traceutil/trace.go:171","msg":"trace[1425694828] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak; range_end:; response_count:1; response_revision:2752; }","duration":"296.947902ms","start":"2026-05-19T18:30:08.644635Z","end":"2026-05-19T18:30:08.941583Z","steps":["trace[1425694828] 'agreement among raft nodes before linearized reading' (duration: 296.595618ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:30:14.108831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.399427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-19T18:30:14.10891Z","caller":"traceutil/trace.go:171","msg":"trace[1117622410] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:2775; }","duration":"257.527819ms","start":"2026-05-19T18:30:13.851361Z","end":"2026-05-19T18:30:14.108889Z","steps":["trace[1117622410] 'count revisions from in-memory index tree' (duration: 257.308825ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:30:14.371455Z","caller":"traceutil/trace.go:171","msg":"trace[930556779] transaction","detail":"{read_only:false; response_revision:2776; number_of_response:1; }","duration":"131.603271ms","start":"2026-05-19T18:30:14.23983Z","end":"2026-05-19T18:30:14.371433Z","steps":["trace[930556779] 'process raft request' (duration: 131.453349ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:30:14.964064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"549.049947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak\" ","response":"range_response_count:1 size:1317"} {"level":"info","ts":"2026-05-19T18:30:14.964145Z","caller":"traceutil/trace.go:171","msg":"trace[362815346] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak; range_end:; response_count:1; response_revision:2776; }","duration":"549.149688ms","start":"2026-05-19T18:30:14.414976Z","end":"2026-05-19T18:30:14.964126Z","steps":["trace[362815346] 'range keys from in-memory index tree' (duration: 548.708312ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:30:14.964188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:30:14.414944Z","time spent":"549.235409ms","remote":"127.0.0.1:54790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":1339,"request content":"key:\"/registry/services/specs/auth-system/keycloak\" "} {"level":"info","ts":"2026-05-19T18:30:14.964558Z","caller":"traceutil/trace.go:171","msg":"trace[451044579] transaction","detail":"{read_only:false; response_revision:2777; number_of_response:1; }","duration":"307.209456ms","start":"2026-05-19T18:30:14.657325Z","end":"2026-05-19T18:30:14.964534Z","steps":["trace[451044579] 'process raft request' (duration: 307.058744ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:30:14.965635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:30:14.657301Z","time spent":"307.323887ms","remote":"127.0.0.1:54858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T18:30:15.215447Z","caller":"traceutil/trace.go:171","msg":"trace[426475116] transaction","detail":"{read_only:false; response_revision:2778; number_of_response:1; }","duration":"555.193719ms","start":"2026-05-19T18:30:14.660235Z","end":"2026-05-19T18:30:15.215428Z","steps":["trace[426475116] 'process raft request' (duration: 549.130599ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:30:15.215563Z","caller":"traceutil/trace.go:171","msg":"trace[450093880] linearizableReadLoop","detail":"{readStateIndex:2882; appliedIndex:2879; }","duration":"517.534139ms","start":"2026-05-19T18:30:14.69801Z","end":"2026-05-19T18:30:15.215544Z","steps":["trace[450093880] 'read index received' (duration: 266.413786ms)","trace[450093880] 'applied index is now lower than readState.Index' (duration: 251.119543ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:30:15.215726Z","caller":"traceutil/trace.go:171","msg":"trace[1856064605] transaction","detail":"{read_only:false; response_revision:2779; number_of_response:1; }","duration":"555.331309ms","start":"2026-05-19T18:30:14.660385Z","end":"2026-05-19T18:30:15.215717Z","steps":["trace[1856064605] 'process raft request' (duration: 554.953995ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:30:15.215807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:30:14.660378Z","time spent":"555.37354ms","remote":"127.0.0.1:54858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T18:30:15.21593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:30:14.66021Z","time spent":"555.365581ms","remote":"127.0.0.1:54858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T18:30:15.215999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.984345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-05-19T18:30:15.216281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.693835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-05-19T18:30:15.216307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.999632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/auth-system/keycloak\" ","response":"range_response_count:1 size:7462"} {"level":"warn","ts":"2026-05-19T18:30:15.216393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.493315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-19T18:30:15.2164Z","caller":"traceutil/trace.go:171","msg":"trace[60494606] range","detail":"{range_begin:/registry/statefulsets/auth-system/keycloak; range_end:; response_count:1; response_revision:2780; }","duration":"248.093043ms","start":"2026-05-19T18:30:14.968298Z","end":"2026-05-19T18:30:15.216391Z","steps":["trace[60494606] 'agreement among raft nodes before linearized reading' (duration: 247.84088ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:30:15.216399Z","caller":"traceutil/trace.go:171","msg":"trace[1343511821] transaction","detail":"{read_only:false; response_revision:2780; number_of_response:1; }","duration":"146.308746ms","start":"2026-05-19T18:30:15.070082Z","end":"2026-05-19T18:30:15.216391Z","steps":["trace[1343511821] 'process raft request' (duration: 145.994932ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:30:15.216532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.990201ms","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-05-19T18:30:15.216581Z","caller":"traceutil/trace.go:171","msg":"trace[413838918] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2780; }","duration":"248.060832ms","start":"2026-05-19T18:30:14.968513Z","end":"2026-05-19T18:30:15.216574Z","steps":["trace[413838918] 'agreement among raft nodes before linearized reading' (duration: 247.83906ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:30:15.216311Z","caller":"traceutil/trace.go:171","msg":"trace[479934594] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2780; }","duration":"246.758735ms","start":"2026-05-19T18:30:14.969545Z","end":"2026-05-19T18:30:15.216303Z","steps":["trace[479934594] 'agreement among raft nodes before linearized reading' (duration: 246.671944ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:30:15.216325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.746388ms","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-05-19T18:30:15.216883Z","caller":"traceutil/trace.go:171","msg":"trace[2034280689] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2780; }","duration":"248.319115ms","start":"2026-05-19T18:30:14.968554Z","end":"2026-05-19T18:30:15.216873Z","steps":["trace[2034280689] 'agreement among raft nodes before linearized reading' (duration: 247.682897ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:30:15.217346Z","caller":"traceutil/trace.go:171","msg":"trace[2002195096] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2780; }","duration":"247.518895ms","start":"2026-05-19T18:30:14.968895Z","end":"2026-05-19T18:30:15.216414Z","steps":["trace[2002195096] 'agreement among raft nodes before linearized reading' (duration: 247.453115ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:30:15.216295Z","caller":"traceutil/trace.go:171","msg":"trace[353168051] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2779; }","duration":"518.31387ms","start":"2026-05-19T18:30:14.69797Z","end":"2026-05-19T18:30:15.216284Z","steps":["trace[353168051] 'agreement among raft nodes before linearized reading' (duration: 517.954685ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:30:15.218053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:30:14.697951Z","time spent":"520.074083ms","remote":"127.0.0.1:54858","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-05-19T18:32:01.621704Z","caller":"traceutil/trace.go:171","msg":"trace[688093993] transaction","detail":"{read_only:false; response_revision:3416; number_of_response:1; }","duration":"146.926731ms","start":"2026-05-19T18:32:01.474757Z","end":"2026-05-19T18:32:01.621684Z","steps":["trace[688093993] 'process raft request' (duration: 146.786807ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:32:10.036287Z","caller":"traceutil/trace.go:171","msg":"trace[1050399314] linearizableReadLoop","detail":"{readStateIndex:3599; appliedIndex:3597; }","duration":"149.633548ms","start":"2026-05-19T18:32:09.886623Z","end":"2026-05-19T18:32:10.036257Z","steps":["trace[1050399314] 'read index received' (duration: 73.988496ms)","trace[1050399314] 'applied index is now lower than readState.Index' (duration: 75.643762ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T18:32:10.036613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.953246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-19T18:32:10.036675Z","caller":"traceutil/trace.go:171","msg":"trace[1988732600] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3468; }","duration":"150.086899ms","start":"2026-05-19T18:32:09.88657Z","end":"2026-05-19T18:32:10.036657Z","steps":["trace[1988732600] 'agreement among raft nodes before linearized reading' (duration: 149.809533ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:32:10.036868Z","caller":"traceutil/trace.go:171","msg":"trace[864820450] transaction","detail":"{read_only:false; response_revision:3468; number_of_response:1; }","duration":"145.984134ms","start":"2026-05-19T18:32:09.89086Z","end":"2026-05-19T18:32:10.036844Z","steps":["trace[864820450] 'process raft request' (duration: 145.297469ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:32:10.036865Z","caller":"traceutil/trace.go:171","msg":"trace[882088909] transaction","detail":"{read_only:false; response_revision:3467; number_of_response:1; }","duration":"151.283826ms","start":"2026-05-19T18:32:09.885409Z","end":"2026-05-19T18:32:10.036693Z","steps":["trace[882088909] 'process raft request' (duration: 141.308418ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:33:33.94978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.328322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T18:33:33.949872Z","caller":"traceutil/trace.go:171","msg":"trace[56156633] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3992; }","duration":"570.463765ms","start":"2026-05-19T18:33:33.379388Z","end":"2026-05-19T18:33:33.949852Z","steps":["trace[56156633] 'range keys from in-memory index tree' (duration: 570.260341ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:33:33.949919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:33:33.379373Z","time spent":"570.537187ms","remote":"127.0.0.1:54576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-19T18:33:33.949925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.419885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-19T18:33:33.950002Z","caller":"traceutil/trace.go:171","msg":"trace[1959841340] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:3992; }","duration":"120.524677ms","start":"2026-05-19T18:33:33.829459Z","end":"2026-05-19T18:33:33.949984Z","steps":["trace[1959841340] 'count revisions from in-memory index tree' (duration: 120.344324ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:33:33.950299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"649.980535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/superstreams/\" range_end:\"/registry/rabbitmq.com/superstreams0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T18:33:33.950358Z","caller":"traceutil/trace.go:171","msg":"trace[224437962] range","detail":"{range_begin:/registry/rabbitmq.com/superstreams/; range_end:/registry/rabbitmq.com/superstreams0; response_count:0; response_revision:3992; }","duration":"650.072887ms","start":"2026-05-19T18:33:33.300272Z","end":"2026-05-19T18:33:33.950344Z","steps":["trace[224437962] 'count revisions from in-memory index tree' (duration: 649.885882ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:33:33.950395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:33:33.300255Z","time spent":"650.130778ms","remote":"127.0.0.1:58424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":27,"request content":"key:\"/registry/rabbitmq.com/superstreams/\" range_end:\"/registry/rabbitmq.com/superstreams0\" count_only:true "} {"level":"warn","ts":"2026-05-19T18:33:33.950496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"710.488108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T18:33:33.950539Z","caller":"traceutil/trace.go:171","msg":"trace[2106713564] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretimports/; range_end:/registry/secretgen.carvel.dev/secretimports0; response_count:0; response_revision:3992; }","duration":"710.56944ms","start":"2026-05-19T18:33:33.239959Z","end":"2026-05-19T18:33:33.950528Z","steps":["trace[2106713564] 'count revisions from in-memory index tree' (duration: 710.435777ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:33:33.950568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:33:33.239947Z","time spent":"710.612531ms","remote":"127.0.0.1:53820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":27,"request content":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true "} {"level":"warn","ts":"2026-05-19T18:33:33.950789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.542453ms","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-05-19T18:33:33.95091Z","caller":"traceutil/trace.go:171","msg":"trace[1063278836] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:3992; }","duration":"473.665695ms","start":"2026-05-19T18:33:33.477225Z","end":"2026-05-19T18:33:33.950891Z","steps":["trace[1063278836] 'range keys from in-memory index tree' (duration: 473.528083ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:33:53.432621Z","caller":"traceutil/trace.go:171","msg":"trace[583461173] transaction","detail":"{read_only:false; response_revision:4553; number_of_response:1; }","duration":"120.748371ms","start":"2026-05-19T18:33:53.311851Z","end":"2026-05-19T18:33:53.4326Z","steps":["trace[583461173] 'process raft request' (duration: 120.347194ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:34:01.405076Z","caller":"traceutil/trace.go:171","msg":"trace[828816894] linearizableReadLoop","detail":"{readStateIndex:4860; appliedIndex:4859; }","duration":"389.832399ms","start":"2026-05-19T18:34:01.015215Z","end":"2026-05-19T18:34:01.405047Z","steps":["trace[828816894] 'read index received' (duration: 389.663766ms)","trace[828816894] 'applied index is now lower than readState.Index' (duration: 167.463µs)"],"step_count":2} {"level":"warn","ts":"2026-05-19T18:34:01.40535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.121004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:528"} {"level":"info","ts":"2026-05-19T18:34:01.40544Z","caller":"traceutil/trace.go:171","msg":"trace[1914037135] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:4684; }","duration":"390.243217ms","start":"2026-05-19T18:34:01.015172Z","end":"2026-05-19T18:34:01.405415Z","steps":["trace[1914037135] 'agreement among raft nodes before linearized reading' (duration: 389.976302ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:34:01.405483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:34:01.015159Z","time spent":"390.315568ms","remote":"127.0.0.1:54858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":550,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"info","ts":"2026-05-19T18:34:01.40568Z","caller":"traceutil/trace.go:171","msg":"trace[69377403] transaction","detail":"{read_only:false; response_revision:4684; number_of_response:1; }","duration":"507.336729ms","start":"2026-05-19T18:34:00.897769Z","end":"2026-05-19T18:34:01.405106Z","steps":["trace[69377403] 'process raft request' (duration: 507.059994ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:34:01.405904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:34:00.897748Z","time spent":"507.98653ms","remote":"127.0.0.1:58400","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1950,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T18:34:01.632287Z","caller":"traceutil/trace.go:171","msg":"trace[1569326460] linearizableReadLoop","detail":"{readStateIndex:4861; appliedIndex:4860; }","duration":"227.017367ms","start":"2026-05-19T18:34:01.405241Z","end":"2026-05-19T18:34:01.632259Z","steps":["trace[1569326460] 'read index received' (duration: 163.178109ms)","trace[1569326460] 'applied index is now lower than readState.Index' (duration: 63.838238ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T18:34:01.632447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.251669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-19T18:34:01.632524Z","caller":"traceutil/trace.go:171","msg":"trace[1696603243] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:4685; }","duration":"491.383041ms","start":"2026-05-19T18:34:01.141126Z","end":"2026-05-19T18:34:01.632509Z","steps":["trace[1696603243] 'agreement among raft nodes before linearized reading' (duration: 491.261209ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:34:01.632559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:34:01.141106Z","time spent":"491.442693ms","remote":"127.0.0.1:49528","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":3,"response size":29,"request content":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true "} {"level":"warn","ts":"2026-05-19T18:34:01.632416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.543225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-19T18:34:01.632686Z","caller":"traceutil/trace.go:171","msg":"trace[1312810768] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:4685; }","duration":"414.875961ms","start":"2026-05-19T18:34:01.217787Z","end":"2026-05-19T18:34:01.632663Z","steps":["trace[1312810768] 'agreement among raft nodes before linearized reading' (duration: 414.519335ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:34:01.632917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:34:01.217774Z","time spent":"415.126295ms","remote":"127.0.0.1:58620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":29,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"info","ts":"2026-05-19T18:34:01.632343Z","caller":"traceutil/trace.go:171","msg":"trace[1376983428] transaction","detail":"{read_only:false; response_revision:4685; number_of_response:1; }","duration":"463.63055ms","start":"2026-05-19T18:34:01.168673Z","end":"2026-05-19T18:34:01.632304Z","steps":["trace[1376983428] 'process raft request' (duration: 399.862803ms)","trace[1376983428] 'compare' (duration: 63.615174ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T18:34:01.634159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:34:01.168644Z","time spent":"465.415253ms","remote":"127.0.0.1:54858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T18:34:01.647292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.681493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-54b5f49799-wtskz.18b10b32c4ae4d61\" ","response":"range_response_count:1 size:896"} {"level":"info","ts":"2026-05-19T18:34:01.647362Z","caller":"traceutil/trace.go:171","msg":"trace[580163658] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-grafana-54b5f49799-wtskz.18b10b32c4ae4d61; range_end:; response_count:1; response_revision:4687; }","duration":"173.760855ms","start":"2026-05-19T18:34:01.473586Z","end":"2026-05-19T18:34:01.647346Z","steps":["trace[580163658] 'agreement among raft nodes before linearized reading' (duration: 173.574521ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:34:01.647666Z","caller":"traceutil/trace.go:171","msg":"trace[782745690] transaction","detail":"{read_only:false; response_revision:4686; number_of_response:1; }","duration":"237.033952ms","start":"2026-05-19T18:34:01.410621Z","end":"2026-05-19T18:34:01.647655Z","steps":["trace[782745690] 'process raft request' (duration: 236.38402ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:34:01.647917Z","caller":"traceutil/trace.go:171","msg":"trace[955324366] transaction","detail":"{read_only:false; response_revision:4687; number_of_response:1; }","duration":"132.346621ms","start":"2026-05-19T18:34:01.515557Z","end":"2026-05-19T18:34:01.647903Z","steps":["trace[955324366] 'process raft request' (duration: 131.546545ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:34:01.648112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.783651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-05-19T18:34:01.648152Z","caller":"traceutil/trace.go:171","msg":"trace[227391761] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4687; }","duration":"111.857323ms","start":"2026-05-19T18:34:01.536283Z","end":"2026-05-19T18:34:01.64814Z","steps":["trace[227391761] 'agreement among raft nodes before linearized reading' (duration: 111.758811ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:34:01.648358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.723372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:16233"} {"level":"info","ts":"2026-05-19T18:34:01.648563Z","caller":"traceutil/trace.go:171","msg":"trace[807338254] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4687; }","duration":"174.966796ms","start":"2026-05-19T18:34:01.473577Z","end":"2026-05-19T18:34:01.648543Z","steps":["trace[807338254] 'agreement among raft nodes before linearized reading' (duration: 174.58494ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:34:02.258843Z","caller":"traceutil/trace.go:171","msg":"trace[1094493196] transaction","detail":"{read_only:false; response_revision:4709; number_of_response:1; }","duration":"187.699447ms","start":"2026-05-19T18:34:02.071113Z","end":"2026-05-19T18:34:02.258812Z","steps":["trace[1094493196] 'process raft request' (duration: 187.382751ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:34:02.258899Z","caller":"traceutil/trace.go:171","msg":"trace[16211925] linearizableReadLoop","detail":"{readStateIndex:4885; appliedIndex:4884; }","duration":"183.692523ms","start":"2026-05-19T18:34:02.07518Z","end":"2026-05-19T18:34:02.258872Z","steps":["trace[16211925] 'read index received' (duration: 25.604621ms)","trace[16211925] 'applied index is now lower than readState.Index' (duration: 158.086702ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:34:02.258957Z","caller":"traceutil/trace.go:171","msg":"trace[1725398196] transaction","detail":"{read_only:false; response_revision:4710; number_of_response:1; }","duration":"163.94997ms","start":"2026-05-19T18:34:02.094945Z","end":"2026-05-19T18:34:02.258895Z","steps":["trace[1725398196] 'process raft request' (duration: 163.772146ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:34:02.259106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.908047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1131"} {"level":"info","ts":"2026-05-19T18:34:02.259155Z","caller":"traceutil/trace.go:171","msg":"trace[2098394382] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:4710; }","duration":"183.997049ms","start":"2026-05-19T18:34:02.075146Z","end":"2026-05-19T18:34:02.259143Z","steps":["trace[2098394382] 'agreement among raft nodes before linearized reading' (duration: 183.844446ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:34:02.278155Z","caller":"traceutil/trace.go:171","msg":"trace[503092317] transaction","detail":"{read_only:false; response_revision:4713; number_of_response:1; }","duration":"103.669619ms","start":"2026-05-19T18:34:02.174463Z","end":"2026-05-19T18:34:02.278133Z","steps":["trace[503092317] 'process raft request' (duration: 103.629208ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:34:02.278256Z","caller":"traceutil/trace.go:171","msg":"trace[323551110] transaction","detail":"{read_only:false; response_revision:4712; number_of_response:1; }","duration":"173.718009ms","start":"2026-05-19T18:34:02.104525Z","end":"2026-05-19T18:34:02.278243Z","steps":["trace[323551110] 'process raft request' (duration: 173.524495ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:34:02.278165Z","caller":"traceutil/trace.go:171","msg":"trace[1184155096] transaction","detail":"{read_only:false; response_revision:4711; number_of_response:1; }","duration":"176.656193ms","start":"2026-05-19T18:34:02.101471Z","end":"2026-05-19T18:34:02.278127Z","steps":["trace[1184155096] 'process raft request' (duration: 176.464109ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:34:02.478756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.998017ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-19T18:34:02.479256Z","caller":"traceutil/trace.go:171","msg":"trace[726040147] transaction","detail":"{read_only:false; response_revision:4715; number_of_response:1; }","duration":"195.126974ms","start":"2026-05-19T18:34:02.28411Z","end":"2026-05-19T18:34:02.479237Z","steps":["trace[726040147] 'process raft request' (duration: 195.039742ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:34:02.479537Z","caller":"traceutil/trace.go:171","msg":"trace[69820265] transaction","detail":"{read_only:false; response_revision:4714; number_of_response:1; }","duration":"216.379275ms","start":"2026-05-19T18:34:02.263111Z","end":"2026-05-19T18:34:02.47949Z","steps":["trace[69820265] 'process raft request' (duration: 106.589523ms)","trace[69820265] 'compare' (duration: 108.862775ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:34:02.479686Z","caller":"traceutil/trace.go:171","msg":"trace[1088315997] linearizableReadLoop","detail":"{readStateIndex:4890; appliedIndex:4889; }","duration":"201.135855ms","start":"2026-05-19T18:34:02.278148Z","end":"2026-05-19T18:34:02.479284Z","steps":["trace[1088315997] 'read index received' (duration: 91.575326ms)","trace[1088315997] 'applied index is now lower than readState.Index' (duration: 109.556789ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T18:34:02.48001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.360953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" ","response":"range_response_count:1 size:650"} {"level":"info","ts":"2026-05-19T18:34:02.480059Z","caller":"traceutil/trace.go:171","msg":"trace[980128397] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:4715; }","duration":"217.452165ms","start":"2026-05-19T18:34:02.262591Z","end":"2026-05-19T18:34:02.480043Z","steps":["trace[980128397] 'agreement among raft nodes before linearized reading' (duration: 217.222941ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:34:02.480143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.58781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1825"} {"level":"info","ts":"2026-05-19T18:34:02.480267Z","caller":"traceutil/trace.go:171","msg":"trace[1885308440] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4715; }","duration":"171.836204ms","start":"2026-05-19T18:34:02.308412Z","end":"2026-05-19T18:34:02.480248Z","steps":["trace[1885308440] 'agreement among raft nodes before linearized reading' (duration: 171.440677ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:34:02.480588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.047928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-19T18:34:02.480637Z","caller":"traceutil/trace.go:171","msg":"trace[396616796] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4715; }","duration":"147.134019ms","start":"2026-05-19T18:34:02.33349Z","end":"2026-05-19T18:34:02.480624Z","steps":["trace[396616796] 'agreement among raft nodes before linearized reading' (duration: 146.930015ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:34:02.70976Z","caller":"traceutil/trace.go:171","msg":"trace[783085437] linearizableReadLoop","detail":"{readStateIndex:4895; appliedIndex:4894; }","duration":"154.690288ms","start":"2026-05-19T18:34:02.555045Z","end":"2026-05-19T18:34:02.709735Z","steps":["trace[783085437] 'read index received' (duration: 54.658726ms)","trace[783085437] 'applied index is now lower than readState.Index' (duration: 100.030452ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:34:02.709998Z","caller":"traceutil/trace.go:171","msg":"trace[1062783342] transaction","detail":"{read_only:false; response_revision:4719; number_of_response:1; }","duration":"156.334089ms","start":"2026-05-19T18:34:02.553638Z","end":"2026-05-19T18:34:02.709972Z","steps":["trace[1062783342] 'process raft request' (duration: 56.094182ms)","trace[1062783342] 'compare' (duration: 99.775678ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T18:34:02.710233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.162187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:discovery\" ","response":"range_response_count:1 size:647"} {"level":"info","ts":"2026-05-19T18:34:02.710317Z","caller":"traceutil/trace.go:171","msg":"trace[269582567] range","detail":"{range_begin:/registry/clusterroles/system:discovery; range_end:; response_count:1; response_revision:4719; }","duration":"155.762949ms","start":"2026-05-19T18:34:02.554536Z","end":"2026-05-19T18:34:02.710299Z","steps":["trace[269582567] 'agreement among raft nodes before linearized reading' (duration: 155.477963ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:34:02.742899Z","caller":"traceutil/trace.go:171","msg":"trace[742752142] transaction","detail":"{read_only:false; response_revision:4720; number_of_response:1; }","duration":"181.678706ms","start":"2026-05-19T18:34:02.561184Z","end":"2026-05-19T18:34:02.742862Z","steps":["trace[742752142] 'process raft request' (duration: 181.3835ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:34:02.743014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.441512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" ","response":"range_response_count:1 size:708"} {"level":"info","ts":"2026-05-19T18:34:02.743115Z","caller":"traceutil/trace.go:171","msg":"trace[1584972430] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:4720; }","duration":"187.581635ms","start":"2026-05-19T18:34:02.55551Z","end":"2026-05-19T18:34:02.743092Z","steps":["trace[1584972430] 'agreement among raft nodes before linearized reading' (duration: 187.30157ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:34:02.743166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.647036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3506"} {"level":"info","ts":"2026-05-19T18:34:02.743237Z","caller":"traceutil/trace.go:171","msg":"trace[1990737469] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4720; }","duration":"187.751408ms","start":"2026-05-19T18:34:02.555468Z","end":"2026-05-19T18:34:02.743219Z","steps":["trace[1990737469] 'agreement among raft nodes before linearized reading' (duration: 187.547854ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:34:02.743267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.579809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-05-19T18:34:02.743382Z","caller":"traceutil/trace.go:171","msg":"trace[964981330] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4720; }","duration":"178.761082ms","start":"2026-05-19T18:34:02.564597Z","end":"2026-05-19T18:34:02.743358Z","steps":["trace[964981330] 'agreement among raft nodes before linearized reading' (duration: 178.354735ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:34:03.101098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.285312ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T18:34:03.101215Z","caller":"traceutil/trace.go:171","msg":"trace[1171581706] transaction","detail":"{read_only:false; response_revision:4721; number_of_response:1; }","duration":"351.695955ms","start":"2026-05-19T18:34:02.749492Z","end":"2026-05-19T18:34:03.101188Z","steps":["trace[1171581706] 'process raft request' (duration: 111.237349ms)","trace[1171581706] 'compare' (duration: 239.869524ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T18:34:03.101286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:34:02.749474Z","time spent":"351.784056ms","remote":"127.0.0.1:54774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1283,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T18:34:03.124862Z","caller":"traceutil/trace.go:171","msg":"trace[1888227642] transaction","detail":"{read_only:false; response_revision:4722; number_of_response:1; }","duration":"375.266818ms","start":"2026-05-19T18:34:02.74957Z","end":"2026-05-19T18:34:03.124837Z","steps":["trace[1888227642] 'process raft request' (duration: 375.100265ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:34:03.125009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:34:02.749557Z","time spent":"375.377759ms","remote":"127.0.0.1:54858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T18:34:03.125197Z","caller":"traceutil/trace.go:171","msg":"trace[2065017350] transaction","detail":"{read_only:false; response_revision:4723; number_of_response:1; }","duration":"375.450082ms","start":"2026-05-19T18:34:02.749709Z","end":"2026-05-19T18:34:03.125159Z","steps":["trace[2065017350] 'process raft request' (duration: 375.082185ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:34:03.125295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:34:02.749694Z","time spent":"375.554083ms","remote":"127.0.0.1:54858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T18:34:03.125412Z","caller":"traceutil/trace.go:171","msg":"trace[364058189] transaction","detail":"{read_only:false; response_revision:4725; number_of_response:1; }","duration":"375.509432ms","start":"2026-05-19T18:34:02.749892Z","end":"2026-05-19T18:34:03.125401Z","steps":["trace[364058189] 'process raft request' (duration: 375.284508ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:34:03.125451Z","caller":"traceutil/trace.go:171","msg":"trace[1218338180] linearizableReadLoop","detail":"{readStateIndex:4901; appliedIndex:4896; }","duration":"375.252397ms","start":"2026-05-19T18:34:02.750176Z","end":"2026-05-19T18:34:03.125428Z","steps":["trace[1218338180] 'read index received' (duration: 110.653308ms)","trace[1218338180] 'applied index is now lower than readState.Index' (duration: 264.597989ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:34:03.125606Z","caller":"traceutil/trace.go:171","msg":"trace[688665706] transaction","detail":"{read_only:false; response_revision:4728; number_of_response:1; }","duration":"374.138646ms","start":"2026-05-19T18:34:02.751452Z","end":"2026-05-19T18:34:03.125591Z","steps":["trace[688665706] 'process raft request' (duration: 373.918392ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:34:03.125474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:34:02.749878Z","time spent":"375.564194ms","remote":"127.0.0.1:54774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":906,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T18:34:03.125694Z","caller":"traceutil/trace.go:171","msg":"trace[1927340530] transaction","detail":"{read_only:false; response_revision:4724; number_of_response:1; }","duration":"375.810038ms","start":"2026-05-19T18:34:02.74987Z","end":"2026-05-19T18:34:03.12568Z","steps":["trace[1927340530] 'process raft request' (duration: 375.173356ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:34:03.125751Z","caller":"traceutil/trace.go:171","msg":"trace[1283399796] transaction","detail":"{read_only:false; response_revision:4726; number_of_response:1; }","duration":"374.941992ms","start":"2026-05-19T18:34:02.750795Z","end":"2026-05-19T18:34:03.125737Z","steps":["trace[1283399796] 'process raft request' (duration: 374.462843ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:34:03.125741Z","caller":"traceutil/trace.go:171","msg":"trace[794546709] transaction","detail":"{read_only:false; response_revision:4727; number_of_response:1; }","duration":"374.392081ms","start":"2026-05-19T18:34:02.751333Z","end":"2026-05-19T18:34:03.125725Z","steps":["trace[794546709] 'process raft request' (duration: 373.990373ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:34:03.125777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:34:02.74986Z","time spent":"375.876029ms","remote":"127.0.0.1:54780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11114,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T18:34:03.125825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:34:02.750783Z","time spent":"374.996123ms","remote":"127.0.0.1:54870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1455,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T18:34:03.125892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.839899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-05-19T18:34:03.125958Z","caller":"traceutil/trace.go:171","msg":"trace[1432774128] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4728; }","duration":"374.937821ms","start":"2026-05-19T18:34:02.751003Z","end":"2026-05-19T18:34:03.125941Z","steps":["trace[1432774128] 'agreement among raft nodes before linearized reading' (duration: 374.758397ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:34:03.125925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.737586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/openstack/rabbitmq-cluster-operator\" ","response":"range_response_count:1 size:1189"} {"level":"warn","ts":"2026-05-19T18:34:03.12601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:34:02.750991Z","time spent":"375.006483ms","remote":"127.0.0.1:54762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":147,"response count":1,"response size":1957,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" "} {"level":"info","ts":"2026-05-19T18:34:03.126017Z","caller":"traceutil/trace.go:171","msg":"trace[691824894] range","detail":"{range_begin:/registry/roles/openstack/rabbitmq-cluster-operator; range_end:; response_count:1; response_revision:4728; }","duration":"375.839459ms","start":"2026-05-19T18:34:02.750162Z","end":"2026-05-19T18:34:03.126001Z","steps":["trace[691824894] 'agreement among raft nodes before linearized reading' (duration: 375.629095ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:34:03.126133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.660682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-19T18:34:03.126145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:34:02.750154Z","time spent":"375.94238ms","remote":"127.0.0.1:54934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":1211,"request content":"key:\"/registry/roles/openstack/rabbitmq-cluster-operator\" "} {"level":"info","ts":"2026-05-19T18:34:03.126164Z","caller":"traceutil/trace.go:171","msg":"trace[2113203301] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:4728; }","duration":"106.693612ms","start":"2026-05-19T18:34:03.019463Z","end":"2026-05-19T18:34:03.126157Z","steps":["trace[2113203301] 'agreement among raft nodes before linearized reading' (duration: 106.634251ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:34:03.126321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.432633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" ","response":"range_response_count:1 size:1346"} {"level":"info","ts":"2026-05-19T18:34:03.126387Z","caller":"traceutil/trace.go:171","msg":"trace[2008105588] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0; range_end:; response_count:1; response_revision:4728; }","duration":"374.509724ms","start":"2026-05-19T18:34:02.751861Z","end":"2026-05-19T18:34:03.126371Z","steps":["trace[2008105588] 'agreement among raft nodes before linearized reading' (duration: 374.32698ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:34:03.126432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:34:02.751855Z","time spent":"374.566485ms","remote":"127.0.0.1:54698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":87,"response count":1,"response size":1368,"request content":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" "} {"level":"warn","ts":"2026-05-19T18:34:03.126575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.876651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" ","response":"range_response_count:1 size:708"} {"level":"info","ts":"2026-05-19T18:34:03.126632Z","caller":"traceutil/trace.go:171","msg":"trace[1353677871] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:4728; }","duration":"374.947112ms","start":"2026-05-19T18:34:02.751671Z","end":"2026-05-19T18:34:03.126618Z","steps":["trace[1353677871] 'agreement among raft nodes before linearized reading' (duration: 374.518583ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:34:03.126636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.433247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T18:34:03.126673Z","caller":"traceutil/trace.go:171","msg":"trace[1484734222] range","detail":"{range_begin:/registry/rabbitmq.com/bindings/; range_end:/registry/rabbitmq.com/bindings0; response_count:0; response_revision:4728; }","duration":"258.504459ms","start":"2026-05-19T18:34:02.868157Z","end":"2026-05-19T18:34:03.126662Z","steps":["trace[1484734222] 'agreement among raft nodes before linearized reading' (duration: 258.442957ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:34:03.126674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:34:02.751665Z","time spent":"374.997584ms","remote":"127.0.0.1:54698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":89,"response count":1,"response size":730,"request content":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" "} {"level":"warn","ts":"2026-05-19T18:34:03.125854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:34:02.751325Z","time spent":"374.470713ms","remote":"127.0.0.1:54858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T18:34:03.127836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:34:02.751447Z","time spent":"374.203067ms","remote":"127.0.0.1:54870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1869,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T18:34:10.609848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.245714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.205\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-19T18:34:10.609945Z","caller":"traceutil/trace.go:171","msg":"trace[452079807] range","detail":"{range_begin:/registry/masterleases/199.204.45.205; range_end:; response_count:1; response_revision:4874; }","duration":"130.397056ms","start":"2026-05-19T18:34:10.479523Z","end":"2026-05-19T18:34:10.609921Z","steps":["trace[452079807] 'range keys from in-memory index tree' (duration: 130.102181ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:34:15.353988Z","caller":"traceutil/trace.go:171","msg":"trace[1530818266] transaction","detail":"{read_only:false; response_revision:4929; number_of_response:1; }","duration":"158.655802ms","start":"2026-05-19T18:34:15.19531Z","end":"2026-05-19T18:34:15.353966Z","steps":["trace[1530818266] 'process raft request' (duration: 158.53866ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:34:15.821126Z","caller":"traceutil/trace.go:171","msg":"trace[931851262] transaction","detail":"{read_only:false; response_revision:4931; number_of_response:1; }","duration":"623.713245ms","start":"2026-05-19T18:34:15.197375Z","end":"2026-05-19T18:34:15.821088Z","steps":["trace[931851262] 'process raft request' (duration: 623.612553ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:34:15.821152Z","caller":"traceutil/trace.go:171","msg":"trace[1428137946] transaction","detail":"{read_only:false; response_revision:4930; number_of_response:1; }","duration":"624.295846ms","start":"2026-05-19T18:34:15.196812Z","end":"2026-05-19T18:34:15.821107Z","steps":["trace[1428137946] 'process raft request' (duration: 623.977071ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:34:15.82129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:34:15.197361Z","time spent":"623.842467ms","remote":"127.0.0.1:54858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T18:34:15.821381Z","caller":"traceutil/trace.go:171","msg":"trace[755720692] linearizableReadLoop","detail":"{readStateIndex:5114; appliedIndex:5111; }","duration":"428.49268ms","start":"2026-05-19T18:34:15.392855Z","end":"2026-05-19T18:34:15.821348Z","steps":["trace[755720692] 'read index received' (duration: 427.912289ms)","trace[755720692] 'applied index is now lower than readState.Index' (duration: 578.911µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:34:15.821523Z","caller":"traceutil/trace.go:171","msg":"trace[1282810347] transaction","detail":"{read_only:false; response_revision:4932; number_of_response:1; }","duration":"521.075566ms","start":"2026-05-19T18:34:15.300428Z","end":"2026-05-19T18:34:15.821504Z","steps":["trace[1282810347] 'process raft request' (duration: 520.612018ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:34:15.821605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:34:15.300403Z","time spent":"521.157598ms","remote":"127.0.0.1:54858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T18:34:15.821675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.803205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-05-19T18:34:15.82172Z","caller":"traceutil/trace.go:171","msg":"trace[1323936767] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:4932; }","duration":"428.904747ms","start":"2026-05-19T18:34:15.392801Z","end":"2026-05-19T18:34:15.821705Z","steps":["trace[1323936767] 'agreement among raft nodes before linearized reading' (duration: 428.697283ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:34:15.821755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:34:15.392776Z","time spent":"428.970328ms","remote":"127.0.0.1:54858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":581,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-05-19T18:34:15.822091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:34:15.196787Z","time spent":"624.435908ms","remote":"127.0.0.1:54858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T18:34:15.942404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.049699ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-05-19T18:34:15.942653Z","caller":"traceutil/trace.go:171","msg":"trace[295779764] linearizableReadLoop","detail":"{readStateIndex:5116; appliedIndex:5114; }","duration":"121.160385ms","start":"2026-05-19T18:34:15.82146Z","end":"2026-05-19T18:34:15.942621Z","steps":["trace[295779764] 'read index received' (duration: 8.801819ms)","trace[295779764] 'applied index is now lower than readState.Index' (duration: 112.355056ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T18:34:15.943008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.013969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T18:34:15.943098Z","caller":"traceutil/trace.go:171","msg":"trace[561228689] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:4933; }","duration":"387.180972ms","start":"2026-05-19T18:34:15.555901Z","end":"2026-05-19T18:34:15.943081Z","steps":["trace[561228689] 'agreement among raft nodes before linearized reading' (duration: 386.835046ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:34:15.943097Z","caller":"traceutil/trace.go:171","msg":"trace[351963857] transaction","detail":"{read_only:false; response_revision:4934; number_of_response:1; }","duration":"115.854969ms","start":"2026-05-19T18:34:15.827216Z","end":"2026-05-19T18:34:15.943071Z","steps":["trace[351963857] 'process raft request' (duration: 115.294709ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:34:15.943159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:34:15.555845Z","time spent":"387.288315ms","remote":"127.0.0.1:57332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-19T18:34:15.943241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.577336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-05-19T18:34:15.943294Z","caller":"traceutil/trace.go:171","msg":"trace[134022913] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4933; }","duration":"331.684727ms","start":"2026-05-19T18:34:15.611593Z","end":"2026-05-19T18:34:15.943277Z","steps":["trace[134022913] 'agreement among raft nodes before linearized reading' (duration: 331.056096ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:34:15.943325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:34:15.611572Z","time spent":"331.745059ms","remote":"127.0.0.1:54762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":139,"response count":1,"response size":2104,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" "} {"level":"info","ts":"2026-05-19T18:34:16.547695Z","caller":"traceutil/trace.go:171","msg":"trace[1599340365] transaction","detail":"{read_only:false; response_revision:4935; number_of_response:1; }","duration":"246.139612ms","start":"2026-05-19T18:34:16.30152Z","end":"2026-05-19T18:34:16.547659Z","steps":["trace[1599340365] 'process raft request' (duration: 245.953348ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:34:16.687029Z","caller":"traceutil/trace.go:171","msg":"trace[483479877] transaction","detail":"{read_only:false; response_revision:4936; number_of_response:1; }","duration":"367.535198ms","start":"2026-05-19T18:34:16.31947Z","end":"2026-05-19T18:34:16.687006Z","steps":["trace[483479877] 'process raft request' (duration: 269.894481ms)","trace[483479877] 'compare' (duration: 97.420423ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T18:34:16.687186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.584006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T18:34:16.687216Z","caller":"traceutil/trace.go:171","msg":"trace[47381926] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4937; }","duration":"305.667208ms","start":"2026-05-19T18:34:16.381541Z","end":"2026-05-19T18:34:16.687209Z","steps":["trace[47381926] 'agreement among raft nodes before linearized reading' (duration: 305.592407ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:34:16.687237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:34:16.381523Z","time spent":"305.708629ms","remote":"127.0.0.1:54576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-19T18:34:16.68725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:34:16.319442Z","time spent":"367.71105ms","remote":"127.0.0.1:54858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T18:34:16.687403Z","caller":"traceutil/trace.go:171","msg":"trace[2142670206] transaction","detail":"{read_only:false; response_revision:4937; number_of_response:1; }","duration":"362.271222ms","start":"2026-05-19T18:34:16.325124Z","end":"2026-05-19T18:34:16.687395Z","steps":["trace[2142670206] 'process raft request' (duration: 361.847524ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:34:16.687447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:34:16.325109Z","time spent":"362.314442ms","remote":"127.0.0.1:54858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T18:34:16.687112Z","caller":"traceutil/trace.go:171","msg":"trace[1648060730] linearizableReadLoop","detail":"{readStateIndex:5120; appliedIndex:5117; }","duration":"305.507135ms","start":"2026-05-19T18:34:16.38159Z","end":"2026-05-19T18:34:16.687097Z","steps":["trace[1648060730] 'read index received' (duration: 165.793089ms)","trace[1648060730] 'applied index is now lower than readState.Index' (duration: 139.713426ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:35:05.245829Z","caller":"traceutil/trace.go:171","msg":"trace[886850416] transaction","detail":"{read_only:false; response_revision:5342; number_of_response:1; }","duration":"151.569095ms","start":"2026-05-19T18:35:05.09423Z","end":"2026-05-19T18:35:05.2458Z","steps":["trace[886850416] 'process raft request' (duration: 81.361898ms)","trace[886850416] 'compare' (duration: 70.118975ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:35:27.553703Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2848} {"level":"info","ts":"2026-05-19T18:35:27.63106Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2848,"took":"75.790299ms","hash":2317850892,"current-db-size-bytes":52416512,"current-db-size":"52 MB","current-db-size-in-use-bytes":28659712,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-05-19T18:35:27.631168Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2317850892,"revision":2848,"compact-revision":-1} {"level":"info","ts":"2026-05-19T18:38:14.883493Z","caller":"traceutil/trace.go:171","msg":"trace[612986887] linearizableReadLoop","detail":"{readStateIndex:6911; appliedIndex:6910; }","duration":"120.660483ms","start":"2026-05-19T18:38:14.762808Z","end":"2026-05-19T18:38:14.883468Z","steps":["trace[612986887] 'read index received' (duration: 120.496731ms)","trace[612986887] 'applied index is now lower than readState.Index' (duration: 162.772µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:38:14.883588Z","caller":"traceutil/trace.go:171","msg":"trace[477844271] transaction","detail":"{read_only:false; response_revision:6671; number_of_response:1; }","duration":"158.724571ms","start":"2026-05-19T18:38:14.724837Z","end":"2026-05-19T18:38:14.883561Z","steps":["trace[477844271] 'process raft request' (duration: 158.494567ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:38:14.883633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.801575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/exchanges.rabbitmq.com\" ","response":"range_response_count:1 size:5733"} {"level":"info","ts":"2026-05-19T18:38:14.883724Z","caller":"traceutil/trace.go:171","msg":"trace[896955815] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/exchanges.rabbitmq.com; range_end:; response_count:1; response_revision:6671; }","duration":"120.889747ms","start":"2026-05-19T18:38:14.762771Z","end":"2026-05-19T18:38:14.883661Z","steps":["trace[896955815] 'agreement among raft nodes before linearized reading' (duration: 120.790746ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:38:14.909789Z","caller":"traceutil/trace.go:171","msg":"trace[8590954] transaction","detail":"{read_only:false; response_revision:6672; number_of_response:1; }","duration":"112.770001ms","start":"2026-05-19T18:38:14.797002Z","end":"2026-05-19T18:38:14.909772Z","steps":["trace[8590954] 'process raft request' (duration: 111.330388ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:40:27.56019Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5514} {"level":"info","ts":"2026-05-19T18:40:27.626578Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5514,"took":"64.893779ms","hash":910640067,"current-db-size-bytes":52416512,"current-db-size":"52 MB","current-db-size-in-use-bytes":18358272,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-05-19T18:40:27.626629Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":910640067,"revision":5514,"compact-revision":2848} {"level":"info","ts":"2026-05-19T18:40:37.516717Z","caller":"traceutil/trace.go:171","msg":"trace[1122294936] transaction","detail":"{read_only:false; response_revision:7421; number_of_response:1; }","duration":"118.082642ms","start":"2026-05-19T18:40:37.398613Z","end":"2026-05-19T18:40:37.516696Z","steps":["trace[1122294936] 'process raft request' (duration: 110.969121ms)"],"step_count":1}