{"level":"warn","ts":"2026-04-08T06:10:57.179046Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-08T06:10:57.179131Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.235: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.235:2380","--initial-cluster=instance=https://199.204.45.235:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.235:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.235:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-08T06:10:57.179192Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-08T06:10:57.179254Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.235:2380"]} {"level":"info","ts":"2026-04-08T06:10:57.179272Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-08T06:10:57.179923Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.235:2379"]} {"level":"info","ts":"2026-04-08T06:10:57.180009Z","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.235:2380"],"listen-peer-urls":["https://199.204.45.235:2380"],"advertise-client-urls":["https://199.204.45.235:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.235:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.235:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-08T06:10:57.183711Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.473995ms"} {"level":"info","ts":"2026-04-08T06:10:57.192156Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"aab6040abcf11","cluster-id":"2bae7d3d8857c059"} {"level":"info","ts":"2026-04-08T06:10:57.192253Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 switched to configuration voters=()"} {"level":"info","ts":"2026-04-08T06:10:57.192289Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became follower at term 0"} {"level":"info","ts":"2026-04-08T06:10:57.192363Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft aab6040abcf11 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-08T06:10:57.192486Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became follower at term 1"} {"level":"info","ts":"2026-04-08T06:10:57.192551Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 switched to configuration voters=(3003179657318161)"} {"level":"warn","ts":"2026-04-08T06:10:57.199443Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-08T06:10:57.203848Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-08T06:10:57.205374Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-08T06:10:57.207849Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"aab6040abcf11","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-08T06:10:57.208537Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"aab6040abcf11","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-08T06:10:57.208779Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-08T06:10:57.208874Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-08T06:10:57.208889Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-08T06:10:57.211089Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 switched to configuration voters=(3003179657318161)"} {"level":"info","ts":"2026-04-08T06:10:57.211463Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"2bae7d3d8857c059","local-member-id":"aab6040abcf11","added-peer-id":"aab6040abcf11","added-peer-peer-urls":["https://199.204.45.235:2380"]} {"level":"info","ts":"2026-04-08T06:10:57.213458Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-08T06:10:57.213726Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.235:2380"} {"level":"info","ts":"2026-04-08T06:10:57.214618Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.235:2380"} {"level":"info","ts":"2026-04-08T06:10:57.215707Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-08T06:10:57.215663Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"aab6040abcf11","initial-advertise-peer-urls":["https://199.204.45.235:2380"],"listen-peer-urls":["https://199.204.45.235:2380"],"advertise-client-urls":["https://199.204.45.235:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.235:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-08T06:10:57.593195Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 is starting a new election at term 1"} {"level":"info","ts":"2026-04-08T06:10:57.593269Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-08T06:10:57.593298Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 received MsgPreVoteResp from aab6040abcf11 at term 1"} {"level":"info","ts":"2026-04-08T06:10:57.593319Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became candidate at term 2"} {"level":"info","ts":"2026-04-08T06:10:57.593327Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 received MsgVoteResp from aab6040abcf11 at term 2"} {"level":"info","ts":"2026-04-08T06:10:57.593338Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became leader at term 2"} {"level":"info","ts":"2026-04-08T06:10:57.593349Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: aab6040abcf11 elected leader aab6040abcf11 at term 2"} {"level":"info","ts":"2026-04-08T06:10:57.634972Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"aab6040abcf11","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.235:2379]}","request-path":"/0/members/aab6040abcf11/attributes","cluster-id":"2bae7d3d8857c059","publish-timeout":"7s"} {"level":"info","ts":"2026-04-08T06:10:57.635028Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-08T06:10:57.63513Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T06:10:57.635268Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-08T06:10:57.636315Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-08T06:10:57.636339Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-08T06:10:57.636572Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-08T06:10:57.637045Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.235:2379"} {"level":"info","ts":"2026-04-08T06:10:57.64072Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"2bae7d3d8857c059","local-member-id":"aab6040abcf11","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T06:10:57.640792Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T06:10:57.640836Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T06:11:04.119277Z","caller":"traceutil/trace.go:171","msg":"trace[34751819] transaction","detail":"{read_only:false; response_revision:273; number_of_response:1; }","duration":"106.62657ms","start":"2026-04-08T06:11:04.012624Z","end":"2026-04-08T06:11:04.119251Z","steps":["trace[34751819] 'process raft request' (duration: 71.677133ms)","trace[34751819] 'compare' (duration: 34.350549ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T06:11:04.302016Z","caller":"traceutil/trace.go:171","msg":"trace[1353388874] transaction","detail":"{read_only:false; response_revision:277; number_of_response:1; }","duration":"106.911194ms","start":"2026-04-08T06:11:04.195066Z","end":"2026-04-08T06:11:04.301977Z","steps":["trace[1353388874] 'process raft request' (duration: 54.564413ms)","trace[1353388874] 'compare' (duration: 52.131008ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T06:11:26.675589Z","caller":"traceutil/trace.go:171","msg":"trace[476150766] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"133.445719ms","start":"2026-04-08T06:11:26.542126Z","end":"2026-04-08T06:11:26.675572Z","steps":["trace[476150766] 'process raft request' (duration: 133.323688ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:11:29.574882Z","caller":"traceutil/trace.go:171","msg":"trace[142779600] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"156.143165ms","start":"2026-04-08T06:11:29.418709Z","end":"2026-04-08T06:11:29.574852Z","steps":["trace[142779600] 'process raft request' (duration: 156.012174ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:11:34.054435Z","caller":"traceutil/trace.go:171","msg":"trace[1248816060] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"370.394963ms","start":"2026-04-08T06:11:33.683994Z","end":"2026-04-08T06:11:34.054389Z","steps":["trace[1248816060] 'process raft request' (duration: 338.669592ms)","trace[1248816060] 'compare' (duration: 31.115515ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T06:11:34.055409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:11:33.683976Z","time spent":"370.676626ms","remote":"127.0.0.1:55002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036313,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T06:11:34.357755Z","caller":"traceutil/trace.go:171","msg":"trace[104162824] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"284.28485ms","start":"2026-04-08T06:11:34.073432Z","end":"2026-04-08T06:11:34.357717Z","steps":["trace[104162824] 'process raft request' (duration: 272.473383ms)","trace[104162824] 'compare' (duration: 11.462173ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T06:11:34.369622Z","caller":"traceutil/trace.go:171","msg":"trace[1539602588] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"258.260842ms","start":"2026-04-08T06:11:34.111334Z","end":"2026-04-08T06:11:34.369595Z","steps":["trace[1539602588] 'process raft request' (duration: 258.175151ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:11:35.099112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:11:34.645363Z","time spent":"453.741919ms","remote":"127.0.0.1:55478","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-08T06:11:35.099213Z","caller":"traceutil/trace.go:171","msg":"trace[858913202] linearizableReadLoop","detail":"{readStateIndex:608; appliedIndex:607; }","duration":"331.606923ms","start":"2026-04-08T06:11:34.767578Z","end":"2026-04-08T06:11:35.099185Z","steps":["trace[858913202] 'read index received' (duration: 244.256039ms)","trace[858913202] 'applied index is now lower than readState.Index' (duration: 87.350004ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T06:11:35.099454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.829545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-08T06:11:35.099516Z","caller":"traceutil/trace.go:171","msg":"trace[777775897] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:586; }","duration":"332.000907ms","start":"2026-04-08T06:11:34.767502Z","end":"2026-04-08T06:11:35.099503Z","steps":["trace[777775897] 'agreement among raft nodes before linearized reading' (duration: 331.770345ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:11:35.099553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:11:34.767476Z","time spent":"332.067128ms","remote":"127.0.0.1:55164","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":"info","ts":"2026-04-08T06:11:35.099799Z","caller":"traceutil/trace.go:171","msg":"trace[356159332] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"712.862588ms","start":"2026-04-08T06:11:34.386923Z","end":"2026-04-08T06:11:35.099786Z","steps":["trace[356159332] 'process raft request' (duration: 624.984539ms)","trace[356159332] 'compare' (duration: 86.98094ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T06:11:35.099876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:11:34.386896Z","time spent":"712.936919ms","remote":"127.0.0.1:55082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-08T06:11:50.574737Z","caller":"traceutil/trace.go:171","msg":"trace[1667466024] transaction","detail":"{read_only:false; response_revision:744; number_of_response:1; }","duration":"466.145479ms","start":"2026-04-08T06:11:50.10856Z","end":"2026-04-08T06:11:50.574706Z","steps":["trace[1667466024] 'process raft request' (duration: 465.812706ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:11:50.57495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:11:50.108531Z","time spent":"466.323811ms","remote":"127.0.0.1:55082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4028,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T06:11:50.629296Z","caller":"traceutil/trace.go:171","msg":"trace[1681124355] transaction","detail":"{read_only:false; response_revision:745; number_of_response:1; }","duration":"214.254275ms","start":"2026-04-08T06:11:50.41501Z","end":"2026-04-08T06:11:50.629264Z","steps":["trace[1681124355] 'process raft request' (duration: 214.032563ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:12:28.172387Z","caller":"traceutil/trace.go:171","msg":"trace[1177789918] transaction","detail":"{read_only:false; response_revision:1176; number_of_response:1; }","duration":"199.490587ms","start":"2026-04-08T06:12:27.972861Z","end":"2026-04-08T06:12:28.172352Z","steps":["trace[1177789918] 'process raft request' (duration: 187.730662ms)","trace[1177789918] 'compare' (duration: 11.430587ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T06:12:41.448503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.616289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/ingress-nginx/ingress-nginx-admission-create-7c67l\" ","response":"range_response_count:1 size:4197"} {"level":"info","ts":"2026-04-08T06:12:41.448592Z","caller":"traceutil/trace.go:171","msg":"trace[1506145224] range","detail":"{range_begin:/registry/pods/ingress-nginx/ingress-nginx-admission-create-7c67l; range_end:; response_count:1; response_revision:1299; }","duration":"122.760742ms","start":"2026-04-08T06:12:41.325812Z","end":"2026-04-08T06:12:41.448572Z","steps":["trace[1506145224] 'range keys from in-memory index tree' (duration: 122.416466ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:13:11.680701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.007836ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T06:13:11.680798Z","caller":"traceutil/trace.go:171","msg":"trace[532657346] transaction","detail":"{read_only:false; response_revision:1820; number_of_response:1; }","duration":"600.455327ms","start":"2026-04-08T06:13:11.080329Z","end":"2026-04-08T06:13:11.680784Z","steps":["trace[532657346] 'process raft request' (duration: 130.200567ms)","trace[532657346] 'compare' (duration: 469.814763ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T06:13:11.680841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:13:11.080295Z","time spent":"600.526239ms","remote":"127.0.0.1:55164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T06:13:11.729183Z","caller":"traceutil/trace.go:171","msg":"trace[799103565] transaction","detail":"{read_only:false; response_revision:1821; number_of_response:1; }","duration":"452.726987ms","start":"2026-04-08T06:13:11.276434Z","end":"2026-04-08T06:13:11.729161Z","steps":["trace[799103565] 'process raft request' (duration: 452.613145ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:13:11.729222Z","caller":"traceutil/trace.go:171","msg":"trace[2143677208] linearizableReadLoop","detail":"{readStateIndex:1890; appliedIndex:1890; }","duration":"387.046303ms","start":"2026-04-08T06:13:11.342146Z","end":"2026-04-08T06:13:11.729192Z","steps":["trace[2143677208] 'read index received' (duration: 387.037273ms)","trace[2143677208] 'applied index is now lower than readState.Index' (duration: 7.2µs)"],"step_count":2} {"level":"warn","ts":"2026-04-08T06:13:11.729343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.186505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T06:13:11.729413Z","caller":"traceutil/trace.go:171","msg":"trace[1939502447] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1821; }","duration":"387.286276ms","start":"2026-04-08T06:13:11.342111Z","end":"2026-04-08T06:13:11.729397Z","steps":["trace[1939502447] 'agreement among raft nodes before linearized reading' (duration: 387.174605ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:13:11.729451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:13:11.34209Z","time spent":"387.353668ms","remote":"127.0.0.1:54928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-08T06:13:11.729404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:13:11.276404Z","time spent":"452.851628ms","remote":"127.0.0.1:55164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T06:13:11.729704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.154769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-14a50a2d-b7ab-41cf-8dfa-723cfde6d61b\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-04-08T06:13:11.729759Z","caller":"traceutil/trace.go:171","msg":"trace[755793419] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-14a50a2d-b7ab-41cf-8dfa-723cfde6d61b; range_end:; response_count:1; response_revision:1821; }","duration":"273.241451ms","start":"2026-04-08T06:13:11.456504Z","end":"2026-04-08T06:13:11.729746Z","steps":["trace[755793419] 'agreement among raft nodes before linearized reading' (duration: 272.823634ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:13:11.729832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.106218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-08T06:13:11.729865Z","caller":"traceutil/trace.go:171","msg":"trace[77165420] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1821; }","duration":"145.17861ms","start":"2026-04-08T06:13:11.584676Z","end":"2026-04-08T06:13:11.729854Z","steps":["trace[77165420] 'agreement among raft nodes before linearized reading' (duration: 145.063928ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:13:11.73001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.504998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T06:13:11.730083Z","caller":"traceutil/trace.go:171","msg":"trace[1762400805] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:1821; }","duration":"157.62054ms","start":"2026-04-08T06:13:11.572443Z","end":"2026-04-08T06:13:11.730064Z","steps":["trace[1762400805] 'agreement among raft nodes before linearized reading' (duration: 157.491958ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:13:12.418488Z","caller":"traceutil/trace.go:171","msg":"trace[2032911279] linearizableReadLoop","detail":"{readStateIndex:1895; appliedIndex:1894; }","duration":"287.710941ms","start":"2026-04-08T06:13:12.130752Z","end":"2026-04-08T06:13:12.418463Z","steps":["trace[2032911279] 'read index received' (duration: 266.052262ms)","trace[2032911279] 'applied index is now lower than readState.Index' (duration: 21.657549ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T06:13:12.418624Z","caller":"traceutil/trace.go:171","msg":"trace[1156461571] transaction","detail":"{read_only:false; response_revision:1826; number_of_response:1; }","duration":"293.032326ms","start":"2026-04-08T06:13:12.125579Z","end":"2026-04-08T06:13:12.418612Z","steps":["trace[1156461571] 'process raft request' (duration: 271.234664ms)","trace[1156461571] 'compare' (duration: 21.490747ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T06:13:12.419191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.348911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-08T06:13:12.419237Z","caller":"traceutil/trace.go:171","msg":"trace[229302305] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1826; }","duration":"288.504743ms","start":"2026-04-08T06:13:12.130721Z","end":"2026-04-08T06:13:12.419226Z","steps":["trace[229302305] 'agreement among raft nodes before linearized reading' (duration: 288.26901ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:13:19.923208Z","caller":"traceutil/trace.go:171","msg":"trace[2086836202] linearizableReadLoop","detail":"{readStateIndex:1973; appliedIndex:1972; }","duration":"121.606407ms","start":"2026-04-08T06:13:19.801582Z","end":"2026-04-08T06:13:19.923188Z","steps":["trace[2086836202] 'read index received' (duration: 99.8982ms)","trace[2086836202] 'applied index is now lower than readState.Index' (duration: 21.707197ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T06:13:19.923332Z","caller":"traceutil/trace.go:171","msg":"trace[526574415] transaction","detail":"{read_only:false; response_revision:1902; number_of_response:1; }","duration":"170.452286ms","start":"2026-04-08T06:13:19.752859Z","end":"2026-04-08T06:13:19.923311Z","steps":["trace[526574415] 'process raft request' (duration: 148.686018ms)","trace[526574415] 'compare' (duration: 21.555244ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T06:13:19.923421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.82373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-08T06:13:19.923461Z","caller":"traceutil/trace.go:171","msg":"trace[1047953031] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1902; }","duration":"121.920322ms","start":"2026-04-08T06:13:19.801529Z","end":"2026-04-08T06:13:19.92345Z","steps":["trace[1047953031] 'agreement among raft nodes before linearized reading' (duration: 121.780819ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:13:25.448941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.914492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T06:13:25.449077Z","caller":"traceutil/trace.go:171","msg":"trace[1832349771] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1937; }","duration":"109.087705ms","start":"2026-04-08T06:13:25.339957Z","end":"2026-04-08T06:13:25.449044Z","steps":["trace[1832349771] 'range keys from in-memory index tree' (duration: 108.82673ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:13:46.007409Z","caller":"traceutil/trace.go:171","msg":"trace[2059393150] transaction","detail":"{read_only:false; response_revision:2064; number_of_response:1; }","duration":"147.914799ms","start":"2026-04-08T06:13:45.859472Z","end":"2026-04-08T06:13:46.007387Z","steps":["trace[2059393150] 'process raft request' (duration: 147.793378ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:13:46.174341Z","caller":"traceutil/trace.go:171","msg":"trace[721706113] transaction","detail":"{read_only:false; response_revision:2067; number_of_response:1; }","duration":"110.76818ms","start":"2026-04-08T06:13:46.063542Z","end":"2026-04-08T06:13:46.17431Z","steps":["trace[721706113] 'process raft request' (duration: 101.231849ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:13:46.784329Z","caller":"traceutil/trace.go:171","msg":"trace[458416859] transaction","detail":"{read_only:false; response_revision:2069; number_of_response:1; }","duration":"208.950795ms","start":"2026-04-08T06:13:46.575362Z","end":"2026-04-08T06:13:46.784312Z","steps":["trace[458416859] 'process raft request' (duration: 208.60802ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:13:46.784214Z","caller":"traceutil/trace.go:171","msg":"trace[1743237182] linearizableReadLoop","detail":"{readStateIndex:2146; appliedIndex:2145; }","duration":"173.791079ms","start":"2026-04-08T06:13:46.610386Z","end":"2026-04-08T06:13:46.784177Z","steps":["trace[1743237182] 'read index received' (duration: 173.409644ms)","trace[1743237182] 'applied index is now lower than readState.Index' (duration: 380.285µs)"],"step_count":2} {"level":"warn","ts":"2026-04-08T06:13:46.784734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.331516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-08T06:13:46.785306Z","caller":"traceutil/trace.go:171","msg":"trace[332844228] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2069; }","duration":"174.966965ms","start":"2026-04-08T06:13:46.610323Z","end":"2026-04-08T06:13:46.78529Z","steps":["trace[332844228] 'agreement among raft nodes before linearized reading' (duration: 174.265686ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:13:46.784882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.392356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-08T06:13:46.785737Z","caller":"traceutil/trace.go:171","msg":"trace[1853619678] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2069; }","duration":"175.302869ms","start":"2026-04-08T06:13:46.610421Z","end":"2026-04-08T06:13:46.785724Z","steps":["trace[1853619678] 'agreement among raft nodes before linearized reading' (duration: 174.376107ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:13:47.26945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.909855ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T06:13:47.269564Z","caller":"traceutil/trace.go:171","msg":"trace[1041382553] transaction","detail":"{read_only:false; response_revision:2070; number_of_response:1; }","duration":"478.800497ms","start":"2026-04-08T06:13:46.790743Z","end":"2026-04-08T06:13:47.269544Z","steps":["trace[1041382553] 'process raft request' (duration: 306.466996ms)","trace[1041382553] 'compare' (duration: 171.674142ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T06:13:47.269625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:13:46.790731Z","time spent":"478.864619ms","remote":"127.0.0.1:55164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T06:13:47.423205Z","caller":"traceutil/trace.go:171","msg":"trace[2030625209] transaction","detail":"{read_only:false; response_revision:2073; number_of_response:1; }","duration":"413.130074ms","start":"2026-04-08T06:13:47.010063Z","end":"2026-04-08T06:13:47.423193Z","steps":["trace[2030625209] 'process raft request' (duration: 412.88946ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:13:47.423283Z","caller":"traceutil/trace.go:171","msg":"trace[2020413023] transaction","detail":"{read_only:false; response_revision:2072; number_of_response:1; }","duration":"629.710344ms","start":"2026-04-08T06:13:46.793539Z","end":"2026-04-08T06:13:47.423249Z","steps":["trace[2020413023] 'process raft request' (duration: 629.251108ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:13:47.423364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:13:47.010038Z","time spent":"413.248446ms","remote":"127.0.0.1:54984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":819,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-08T06:13:47.423459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:13:46.793516Z","time spent":"629.845276ms","remote":"127.0.0.1:55164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T06:13:47.422905Z","caller":"traceutil/trace.go:171","msg":"trace[49596666] transaction","detail":"{read_only:false; response_revision:2071; number_of_response:1; }","duration":"631.926315ms","start":"2026-04-08T06:13:46.79091Z","end":"2026-04-08T06:13:47.422837Z","steps":["trace[49596666] 'process raft request' (duration: 631.690702ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:13:47.42364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:13:46.790896Z","time spent":"632.703995ms","remote":"127.0.0.1:55164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T06:13:47.46948Z","caller":"traceutil/trace.go:171","msg":"trace[921496846] linearizableReadLoop","detail":"{readStateIndex:2151; appliedIndex:2147; }","duration":"286.828735ms","start":"2026-04-08T06:13:47.182631Z","end":"2026-04-08T06:13:47.46946Z","steps":["trace[921496846] 'read index received' (duration: 239.893251ms)","trace[921496846] 'applied index is now lower than readState.Index' (duration: 46.934234ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T06:13:47.469686Z","caller":"traceutil/trace.go:171","msg":"trace[2014860733] transaction","detail":"{read_only:false; response_revision:2074; number_of_response:1; }","duration":"336.485868ms","start":"2026-04-08T06:13:47.133081Z","end":"2026-04-08T06:13:47.469567Z","steps":["trace[2014860733] 'process raft request' (duration: 336.265815ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:13:47.46975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.868634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-08T06:13:47.469775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.132849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-04-08T06:13:47.469835Z","caller":"traceutil/trace.go:171","msg":"trace[558701660] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2074; }","duration":"287.246401ms","start":"2026-04-08T06:13:47.182572Z","end":"2026-04-08T06:13:47.469818Z","steps":["trace[558701660] 'agreement among raft nodes before linearized reading' (duration: 287.020798ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:13:47.469834Z","caller":"traceutil/trace.go:171","msg":"trace[176475852] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2074; }","duration":"124.016456ms","start":"2026-04-08T06:13:47.345797Z","end":"2026-04-08T06:13:47.469813Z","steps":["trace[176475852] 'agreement among raft nodes before linearized reading' (duration: 123.853193ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:13:47.469995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:13:47.133053Z","time spent":"336.778252ms","remote":"127.0.0.1:55164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T06:13:47.914808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.334682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T06:13:47.914905Z","caller":"traceutil/trace.go:171","msg":"trace[799879533] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:2074; }","duration":"236.484453ms","start":"2026-04-08T06:13:47.678394Z","end":"2026-04-08T06:13:47.914878Z","steps":["trace[799879533] 'count revisions from in-memory index tree' (duration: 236.216569ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:14:46.589149Z","caller":"traceutil/trace.go:171","msg":"trace[402529794] linearizableReadLoop","detail":"{readStateIndex:2538; appliedIndex:2536; }","duration":"106.319238ms","start":"2026-04-08T06:14:46.482806Z","end":"2026-04-08T06:14:46.589126Z","steps":["trace[402529794] 'read index received' (duration: 65.563507ms)","trace[402529794] 'applied index is now lower than readState.Index' (duration: 40.754641ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T06:14:46.589425Z","caller":"traceutil/trace.go:171","msg":"trace[659838990] transaction","detail":"{read_only:false; response_revision:2447; number_of_response:1; }","duration":"127.935873ms","start":"2026-04-08T06:14:46.461475Z","end":"2026-04-08T06:14:46.589411Z","steps":["trace[659838990] 'process raft request' (duration: 106.876324ms)","trace[659838990] 'compare' (duration: 20.642894ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T06:14:46.589694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.876035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-08T06:14:46.589737Z","caller":"traceutil/trace.go:171","msg":"trace[375365494] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2447; }","duration":"106.961796ms","start":"2026-04-08T06:14:46.482764Z","end":"2026-04-08T06:14:46.589726Z","steps":["trace[375365494] 'agreement among raft nodes before linearized reading' (duration: 106.823784ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:15:02.82058Z","caller":"traceutil/trace.go:171","msg":"trace[1517231284] transaction","detail":"{read_only:false; response_revision:2588; number_of_response:1; }","duration":"115.611125ms","start":"2026-04-08T06:15:02.704927Z","end":"2026-04-08T06:15:02.820538Z","steps":["trace[1517231284] 'process raft request' (duration: 68.528472ms)","trace[1517231284] 'compare' (duration: 46.958872ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T06:15:24.453005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.247156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-08T06:15:24.453042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.739079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T06:15:24.453099Z","caller":"traceutil/trace.go:171","msg":"trace[240551289] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:2698; }","duration":"373.411628ms","start":"2026-04-08T06:15:24.079668Z","end":"2026-04-08T06:15:24.45308Z","steps":["trace[240551289] 'count revisions from in-memory index tree' (duration: 373.154516ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:15:24.453112Z","caller":"traceutil/trace.go:171","msg":"trace[1375280370] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2698; }","duration":"111.87644ms","start":"2026-04-08T06:15:24.34122Z","end":"2026-04-08T06:15:24.453096Z","steps":["trace[1375280370] 'range keys from in-memory index tree' (duration: 111.652338ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:15:24.453142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:15:24.079645Z","time spent":"373.485799ms","remote":"127.0.0.1:55712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":26,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"warn","ts":"2026-04-08T06:15:24.453126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.803919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-04-08T06:15:24.453221Z","caller":"traceutil/trace.go:171","msg":"trace[2076395274] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2698; }","duration":"489.95022ms","start":"2026-04-08T06:15:23.963242Z","end":"2026-04-08T06:15:24.453193Z","steps":["trace[2076395274] 'range keys from in-memory index tree' (duration: 489.607437ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:15:24.453286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:15:23.963216Z","time spent":"490.054412ms","remote":"127.0.0.1:55164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":577,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-04-08T06:15:29.793098Z","caller":"traceutil/trace.go:171","msg":"trace[1539481800] transaction","detail":"{read_only:false; response_revision:2728; number_of_response:1; }","duration":"331.646549ms","start":"2026-04-08T06:15:29.461424Z","end":"2026-04-08T06:15:29.79307Z","steps":["trace[1539481800] 'process raft request' (duration: 331.510548ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:15:29.793307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:15:29.461407Z","time spent":"331.790742ms","remote":"127.0.0.1:55164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T06:16:31.546371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.857343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/auth-system/keycloak\" ","response":"range_response_count:1 size:7341"} {"level":"info","ts":"2026-04-08T06:16:31.546554Z","caller":"traceutil/trace.go:171","msg":"trace[613628449] range","detail":"{range_begin:/registry/statefulsets/auth-system/keycloak; range_end:; response_count:1; response_revision:3055; }","duration":"124.06185ms","start":"2026-04-08T06:16:31.422471Z","end":"2026-04-08T06:16:31.546533Z","steps":["trace[613628449] 'range keys from in-memory index tree' (duration: 123.722837ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:16:39.662571Z","caller":"traceutil/trace.go:171","msg":"trace[1136695585] linearizableReadLoop","detail":"{readStateIndex:3209; appliedIndex:3208; }","duration":"236.89446ms","start":"2026-04-08T06:16:39.425652Z","end":"2026-04-08T06:16:39.662547Z","steps":["trace[1136695585] 'read index received' (duration: 236.765615ms)","trace[1136695585] 'applied index is now lower than readState.Index' (duration: 127.745µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T06:16:39.662593Z","caller":"traceutil/trace.go:171","msg":"trace[2082842859] transaction","detail":"{read_only:false; response_revision:3094; number_of_response:1; }","duration":"238.560792ms","start":"2026-04-08T06:16:39.424008Z","end":"2026-04-08T06:16:39.662569Z","steps":["trace[2082842859] 'process raft request' (duration: 238.386175ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:16:39.662831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.155989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/auth-system/keycloak\" ","response":"range_response_count:1 size:7341"} {"level":"info","ts":"2026-04-08T06:16:39.662908Z","caller":"traceutil/trace.go:171","msg":"trace[1725518306] range","detail":"{range_begin:/registry/statefulsets/auth-system/keycloak; range_end:; response_count:1; response_revision:3094; }","duration":"237.253182ms","start":"2026-04-08T06:16:39.425636Z","end":"2026-04-08T06:16:39.662889Z","steps":["trace[1725518306] 'agreement among raft nodes before linearized reading' (duration: 237.029114ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:16:40.068267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.844563ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T06:16:40.068386Z","caller":"traceutil/trace.go:171","msg":"trace[210217982] transaction","detail":"{read_only:false; response_revision:3095; number_of_response:1; }","duration":"495.22698ms","start":"2026-04-08T06:16:39.573143Z","end":"2026-04-08T06:16:40.06837Z","steps":["trace[210217982] 'process raft request' (duration: 276.208621ms)","trace[210217982] 'compare' (duration: 218.749849ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T06:16:40.068459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:16:39.573122Z","time spent":"495.316674ms","remote":"127.0.0.1:55164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T06:16:40.068567Z","caller":"traceutil/trace.go:171","msg":"trace[1751355435] linearizableReadLoop","detail":"{readStateIndex:3211; appliedIndex:3209; }","duration":"405.911595ms","start":"2026-04-08T06:16:39.66264Z","end":"2026-04-08T06:16:40.068552Z","steps":["trace[1751355435] 'read index received' (duration: 186.625156ms)","trace[1751355435] 'applied index is now lower than readState.Index' (duration: 219.285659ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T06:16:40.068738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.447224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18a44d14c5bb2e6b\" ","response":"range_response_count:1 size:781"} {"level":"info","ts":"2026-04-08T06:16:40.068757Z","caller":"traceutil/trace.go:171","msg":"trace[2069115148] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18a44d14c5bb2e6b; range_end:; response_count:1; response_revision:3096; }","duration":"364.493796ms","start":"2026-04-08T06:16:39.704258Z","end":"2026-04-08T06:16:40.068752Z","steps":["trace[2069115148] 'agreement among raft nodes before linearized reading' (duration: 364.415353ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:16:40.068775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:16:39.704243Z","time spent":"364.527838ms","remote":"127.0.0.1:54984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":802,"request content":"key:\"/registry/events/auth-system/keycloak-0.18a44d14c5bb2e6b\" "} {"level":"warn","ts":"2026-04-08T06:16:40.068777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"643.007291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-04-08T06:16:40.068831Z","caller":"traceutil/trace.go:171","msg":"trace[1487360087] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:3096; }","duration":"643.099924ms","start":"2026-04-08T06:16:39.425717Z","end":"2026-04-08T06:16:40.068817Z","steps":["trace[1487360087] 'agreement among raft nodes before linearized reading' (duration: 642.929868ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:16:40.06886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:16:39.425701Z","time spent":"643.153227ms","remote":"127.0.0.1:55082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12234,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-04-08T06:16:40.068858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.627822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"warn","ts":"2026-04-08T06:16:40.068888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.478227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T06:16:40.06891Z","caller":"traceutil/trace.go:171","msg":"trace[1155615559] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:3096; }","duration":"312.531718ms","start":"2026-04-08T06:16:39.756372Z","end":"2026-04-08T06:16:40.068904Z","steps":["trace[1155615559] 'agreement among raft nodes before linearized reading' (duration: 312.494787ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:16:40.068905Z","caller":"traceutil/trace.go:171","msg":"trace[703544579] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:3096; }","duration":"193.708145ms","start":"2026-04-08T06:16:39.875185Z","end":"2026-04-08T06:16:40.068893Z","steps":["trace[703544579] 'agreement among raft nodes before linearized reading' (duration: 193.56937ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:16:40.068929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:16:39.756355Z","time spent":"312.56992ms","remote":"127.0.0.1:55518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":26,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true "} {"level":"info","ts":"2026-04-08T06:16:40.068919Z","caller":"traceutil/trace.go:171","msg":"trace[126252977] transaction","detail":"{read_only:false; response_revision:3096; number_of_response:1; }","duration":"486.440945ms","start":"2026-04-08T06:16:39.582457Z","end":"2026-04-08T06:16:40.068898Z","steps":["trace[126252977] 'process raft request' (duration: 485.880925ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:16:40.06903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"643.184228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-08T06:16:40.069049Z","caller":"traceutil/trace.go:171","msg":"trace[189856946] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:3096; }","duration":"643.205549ms","start":"2026-04-08T06:16:39.425838Z","end":"2026-04-08T06:16:40.069043Z","steps":["trace[189856946] 'agreement among raft nodes before linearized reading' (duration: 643.169387ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:16:40.069041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:16:39.582431Z","time spent":"486.54081ms","remote":"127.0.0.1:55164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T06:16:40.069068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:16:39.425829Z","time spent":"643.233949ms","remote":"127.0.0.1:55048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":28,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"warn","ts":"2026-04-08T06:16:40.561727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.662946ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T06:16:40.561899Z","caller":"traceutil/trace.go:171","msg":"trace[1224789772] linearizableReadLoop","detail":"{readStateIndex:3214; appliedIndex:3213; }","duration":"360.902851ms","start":"2026-04-08T06:16:40.200976Z","end":"2026-04-08T06:16:40.561879Z","steps":["trace[1224789772] 'read index received' (duration: 58.888942ms)","trace[1224789772] 'applied index is now lower than readState.Index' (duration: 301.958597ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T06:16:40.562156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.15902ms","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-04-08T06:16:40.562285Z","caller":"traceutil/trace.go:171","msg":"trace[1851882123] transaction","detail":"{read_only:false; response_revision:3099; number_of_response:1; }","duration":"390.369172ms","start":"2026-04-08T06:16:40.171845Z","end":"2026-04-08T06:16:40.562214Z","steps":["trace[1851882123] 'process raft request' (duration: 88.107745ms)","trace[1851882123] 'compare' (duration: 301.50099ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T06:16:40.562362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.40398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-08T06:16:40.562447Z","caller":"traceutil/trace.go:171","msg":"trace[1205643317] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3099; }","duration":"312.528934ms","start":"2026-04-08T06:16:40.249898Z","end":"2026-04-08T06:16:40.562427Z","steps":["trace[1205643317] 'agreement among raft nodes before linearized reading' (duration: 312.301196ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:16:40.562529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:16:40.249879Z","time spent":"312.640499ms","remote":"127.0.0.1:55164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-08T06:16:40.562347Z","caller":"traceutil/trace.go:171","msg":"trace[281291179] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:3099; }","duration":"361.284815ms","start":"2026-04-08T06:16:40.200934Z","end":"2026-04-08T06:16:40.562219Z","steps":["trace[281291179] 'agreement among raft nodes before linearized reading' (duration: 361.14396ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:16:40.562636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:16:40.200914Z","time spent":"361.7053ms","remote":"127.0.0.1:55354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":26,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-04-08T06:16:40.562619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:16:40.17182Z","time spent":"390.57599ms","remote":"127.0.0.1:55164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T06:16:40.562845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.956543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-08T06:16:40.562929Z","caller":"traceutil/trace.go:171","msg":"trace[2060649203] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3099; }","duration":"300.170461ms","start":"2026-04-08T06:16:40.262717Z","end":"2026-04-08T06:16:40.562887Z","steps":["trace[2060649203] 'agreement among raft nodes before linearized reading' (duration: 299.537368ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:16:40.562977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:16:40.262695Z","time spent":"300.270496ms","remote":"127.0.0.1:55164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-08T06:16:41.361243Z","caller":"traceutil/trace.go:171","msg":"trace[31236526] linearizableReadLoop","detail":"{readStateIndex:3217; appliedIndex:3216; }","duration":"425.678076ms","start":"2026-04-08T06:16:40.935536Z","end":"2026-04-08T06:16:41.361214Z","steps":["trace[31236526] 'read index received' (duration: 425.613883ms)","trace[31236526] 'applied index is now lower than readState.Index' (duration: 62.853µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T06:16:41.36128Z","caller":"traceutil/trace.go:171","msg":"trace[1647627761] transaction","detail":"{read_only:false; response_revision:3102; number_of_response:1; }","duration":"454.177831ms","start":"2026-04-08T06:16:40.907058Z","end":"2026-04-08T06:16:41.361235Z","steps":["trace[1647627761] 'process raft request' (duration: 453.981334ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:16:41.361428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:16:40.907017Z","time spent":"454.326997ms","remote":"127.0.0.1:55164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T06:16:41.361512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.956296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.235\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-08T06:16:41.361548Z","caller":"traceutil/trace.go:171","msg":"trace[755448644] range","detail":"{range_begin:/registry/masterleases/199.204.45.235; range_end:; response_count:1; response_revision:3102; }","duration":"426.04774ms","start":"2026-04-08T06:16:40.935489Z","end":"2026-04-08T06:16:41.361537Z","steps":["trace[755448644] 'agreement among raft nodes before linearized reading' (duration: 425.850372ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:16:41.361585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:16:40.935467Z","time spent":"426.110412ms","remote":"127.0.0.1:54942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.235\" "} {"level":"info","ts":"2026-04-08T06:16:41.400454Z","caller":"traceutil/trace.go:171","msg":"trace[1022041167] transaction","detail":"{read_only:false; response_revision:3103; number_of_response:1; }","duration":"362.531815ms","start":"2026-04-08T06:16:41.037895Z","end":"2026-04-08T06:16:41.400427Z","steps":["trace[1022041167] 'process raft request' (duration: 362.360169ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:16:41.400639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:16:41.037866Z","time spent":"362.675211ms","remote":"127.0.0.1:55164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T06:16:41.837162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.294799ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:38"} {"level":"info","ts":"2026-04-08T06:16:41.837263Z","caller":"traceutil/trace.go:171","msg":"trace[730118247] linearizableReadLoop","detail":"{readStateIndex:3219; appliedIndex:3218; }","duration":"436.882563ms","start":"2026-04-08T06:16:41.400361Z","end":"2026-04-08T06:16:41.837243Z","steps":["trace[730118247] 'read index received' (duration: 190.49653ms)","trace[730118247] 'applied index is now lower than readState.Index' (duration: 246.384103ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T06:16:41.837342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:16:41.362546Z","time spent":"474.792838ms","remote":"127.0.0.1:54942","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-08T06:16:41.83756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.099543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T06:16:41.837599Z","caller":"traceutil/trace.go:171","msg":"trace[549879005] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:3103; }","duration":"442.147815ms","start":"2026-04-08T06:16:41.39544Z","end":"2026-04-08T06:16:41.837588Z","steps":["trace[549879005] 'agreement among raft nodes before linearized reading' (duration: 442.072322ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:16:41.837635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:16:41.395398Z","time spent":"442.228769ms","remote":"127.0.0.1:55622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":26,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true "} {"level":"warn","ts":"2026-04-08T06:16:41.856092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.177967ms","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-04-08T06:16:41.856137Z","caller":"traceutil/trace.go:171","msg":"trace[1259773025] transaction","detail":"{read_only:false; response_revision:3104; number_of_response:1; }","duration":"450.802652ms","start":"2026-04-08T06:16:41.405315Z","end":"2026-04-08T06:16:41.856118Z","steps":["trace[1259773025] 'process raft request' (duration: 450.456419ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:16:41.856174Z","caller":"traceutil/trace.go:171","msg":"trace[904186260] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:3104; }","duration":"153.307811ms","start":"2026-04-08T06:16:41.702848Z","end":"2026-04-08T06:16:41.856156Z","steps":["trace[904186260] 'agreement among raft nodes before linearized reading' (duration: 153.161616ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:16:41.856249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:16:41.405297Z","time spent":"450.884054ms","remote":"127.0.0.1:55164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T06:16:41.856364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.194825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-08T06:16:41.856364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.129781ms","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-04-08T06:16:41.856458Z","caller":"traceutil/trace.go:171","msg":"trace[1734544249] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3104; }","duration":"434.327899ms","start":"2026-04-08T06:16:41.422109Z","end":"2026-04-08T06:16:41.856437Z","steps":["trace[1734544249] 'agreement among raft nodes before linearized reading' (duration: 434.083311ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:16:41.856458Z","caller":"traceutil/trace.go:171","msg":"trace[362348013] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:3104; }","duration":"443.263356ms","start":"2026-04-08T06:16:41.41318Z","end":"2026-04-08T06:16:41.856443Z","steps":["trace[362348013] 'agreement among raft nodes before linearized reading' (duration: 443.071479ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:16:41.856518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:16:41.422088Z","time spent":"434.418193ms","remote":"127.0.0.1:55164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-08T06:16:41.856609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.971651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-04-08T06:16:41.856518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:16:41.413167Z","time spent":"443.339109ms","remote":"127.0.0.1:55090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1390,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"info","ts":"2026-04-08T06:16:41.856656Z","caller":"traceutil/trace.go:171","msg":"trace[680241199] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3104; }","duration":"190.063925ms","start":"2026-04-08T06:16:41.666581Z","end":"2026-04-08T06:16:41.856645Z","steps":["trace[680241199] 'agreement among raft nodes before linearized reading' (duration: 189.92223ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:16:41.856686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.761846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T06:16:41.856756Z","caller":"traceutil/trace.go:171","msg":"trace[1786534963] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3104; }","duration":"454.865169ms","start":"2026-04-08T06:16:41.401877Z","end":"2026-04-08T06:16:41.856743Z","steps":["trace[1786534963] 'agreement among raft nodes before linearized reading' (duration: 454.093112ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:16:41.85679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:16:41.401859Z","time spent":"454.922602ms","remote":"127.0.0.1:54926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-08T06:16:50.146416Z","caller":"traceutil/trace.go:171","msg":"trace[1043213372] transaction","detail":"{read_only:false; response_revision:3146; number_of_response:1; }","duration":"148.593992ms","start":"2026-04-08T06:16:49.997786Z","end":"2026-04-08T06:16:50.14638Z","steps":["trace[1043213372] 'process raft request' (duration: 78.113975ms)","trace[1043213372] 'compare' (duration: 69.806993ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T06:17:32.387661Z","caller":"traceutil/trace.go:171","msg":"trace[1953311620] transaction","detail":"{read_only:false; response_revision:3453; number_of_response:1; }","duration":"121.347033ms","start":"2026-04-08T06:17:32.266297Z","end":"2026-04-08T06:17:32.387644Z","steps":["trace[1953311620] 'process raft request' (duration: 120.89225ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:17:32.387513Z","caller":"traceutil/trace.go:171","msg":"trace[428136960] transaction","detail":"{read_only:false; response_revision:3452; number_of_response:1; }","duration":"124.214206ms","start":"2026-04-08T06:17:32.263258Z","end":"2026-04-08T06:17:32.387473Z","steps":["trace[428136960] 'process raft request' (duration: 103.785571ms)","trace[428136960] 'compare' (duration: 19.960332ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T06:17:58.975857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.014932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-08T06:17:58.975965Z","caller":"traceutil/trace.go:171","msg":"trace[192643167] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3597; }","duration":"144.162406ms","start":"2026-04-08T06:17:58.831763Z","end":"2026-04-08T06:17:58.975925Z","steps":["trace[192643167] 'range keys from in-memory index tree' (duration: 143.817707ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:17:58.976269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.674455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-08T06:17:58.976325Z","caller":"traceutil/trace.go:171","msg":"trace[760599276] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3597; }","duration":"159.781587ms","start":"2026-04-08T06:17:58.81653Z","end":"2026-04-08T06:17:58.976312Z","steps":["trace[760599276] 'range keys from in-memory index tree' (duration: 159.460139ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:17:58.976591Z","caller":"traceutil/trace.go:171","msg":"trace[664846139] transaction","detail":"{read_only:false; response_revision:3598; number_of_response:1; }","duration":"101.529143ms","start":"2026-04-08T06:17:58.875012Z","end":"2026-04-08T06:17:58.976541Z","steps":["trace[664846139] 'process raft request' (duration: 80.042948ms)","trace[664846139] 'compare' (duration: 20.817498ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T06:17:58.976169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.505245ms","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-04-08T06:17:58.976924Z","caller":"traceutil/trace.go:171","msg":"trace[2083288305] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:3597; }","duration":"235.182672ms","start":"2026-04-08T06:17:58.741573Z","end":"2026-04-08T06:17:58.976756Z","steps":["trace[2083288305] 'count revisions from in-memory index tree' (duration: 234.300919ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:18:13.33527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.824019ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T06:18:13.335545Z","caller":"traceutil/trace.go:171","msg":"trace[1406716604] linearizableReadLoop","detail":"{readStateIndex:3816; appliedIndex:3814; }","duration":"192.098316ms","start":"2026-04-08T06:18:13.143424Z","end":"2026-04-08T06:18:13.335522Z","steps":["trace[1406716604] 'read index received' (duration: 64.11342ms)","trace[1406716604] 'applied index is now lower than readState.Index' (duration: 127.983465ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T06:18:13.335661Z","caller":"traceutil/trace.go:171","msg":"trace[2052566092] transaction","detail":"{read_only:false; response_revision:3677; number_of_response:1; }","duration":"253.464269ms","start":"2026-04-08T06:18:13.082183Z","end":"2026-04-08T06:18:13.335648Z","steps":["trace[2052566092] 'process raft request' (duration: 35.132247ms)","trace[2052566092] 'compare' (duration: 217.47536ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T06:18:13.335816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.398944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secrettemplates/monitoring/kube-prometheus-stack-alertmanager-oauth2-proxy\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-08T06:18:13.335927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.798781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-08T06:18:13.335961Z","caller":"traceutil/trace.go:171","msg":"trace[1138345100] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3678; }","duration":"115.865983ms","start":"2026-04-08T06:18:13.220084Z","end":"2026-04-08T06:18:13.33595Z","steps":["trace[1138345100] 'agreement among raft nodes before linearized reading' (duration: 115.7308ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:18:13.335974Z","caller":"traceutil/trace.go:171","msg":"trace[1243056243] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secrettemplates/monitoring/kube-prometheus-stack-alertmanager-oauth2-proxy; range_end:; response_count:0; response_revision:3678; }","duration":"192.566379ms","start":"2026-04-08T06:18:13.143368Z","end":"2026-04-08T06:18:13.335934Z","steps":["trace[1243056243] 'agreement among raft nodes before linearized reading' (duration: 192.388964ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:18:13.336147Z","caller":"traceutil/trace.go:171","msg":"trace[414947339] transaction","detail":"{read_only:false; response_revision:3678; number_of_response:1; }","duration":"253.738075ms","start":"2026-04-08T06:18:13.082383Z","end":"2026-04-08T06:18:13.336121Z","steps":["trace[414947339] 'process raft request' (duration: 253.024228ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:19:12.507425Z","caller":"traceutil/trace.go:171","msg":"trace[1996440356] transaction","detail":"{read_only:false; response_revision:4383; number_of_response:1; }","duration":"143.869479ms","start":"2026-04-08T06:19:12.363518Z","end":"2026-04-08T06:19:12.507387Z","steps":["trace[1996440356] 'process raft request' (duration: 143.548512ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:19:12.507622Z","caller":"traceutil/trace.go:171","msg":"trace[461660432] linearizableReadLoop","detail":"{readStateIndex:4551; appliedIndex:4550; }","duration":"155.331189ms","start":"2026-04-08T06:19:12.35228Z","end":"2026-04-08T06:19:12.507612Z","steps":["trace[461660432] 'read index received' (duration: 116.868224ms)","trace[461660432] 'applied index is now lower than readState.Index' (duration: 38.462105ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T06:19:12.507671Z","caller":"traceutil/trace.go:171","msg":"trace[420546581] transaction","detail":"{read_only:false; response_revision:4382; number_of_response:1; }","duration":"157.499154ms","start":"2026-04-08T06:19:12.35013Z","end":"2026-04-08T06:19:12.507629Z","steps":["trace[420546581] 'process raft request' (duration: 119.011019ms)","trace[420546581] 'compare' (duration: 37.77866ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T06:19:12.507767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.469172ms","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-04-08T06:19:12.507805Z","caller":"traceutil/trace.go:171","msg":"trace[1751477323] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:4384; }","duration":"155.542174ms","start":"2026-04-08T06:19:12.35225Z","end":"2026-04-08T06:19:12.507793Z","steps":["trace[1751477323] 'agreement among raft nodes before linearized reading' (duration: 155.408671ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:19:12.533298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.302851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/grafana-tls\" ","response":"range_response_count:1 size:2900"} {"level":"info","ts":"2026-04-08T06:19:12.53338Z","caller":"traceutil/trace.go:171","msg":"trace[827327918] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/grafana-tls; range_end:; response_count:1; response_revision:4384; }","duration":"168.417483ms","start":"2026-04-08T06:19:12.364945Z","end":"2026-04-08T06:19:12.533363Z","steps":["trace[827327918] 'agreement among raft nodes before linearized reading' (duration: 168.158777ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:19:13.534925Z","caller":"traceutil/trace.go:171","msg":"trace[1245427722] transaction","detail":"{read_only:false; response_revision:4414; number_of_response:1; }","duration":"151.017841ms","start":"2026-04-08T06:19:13.38387Z","end":"2026-04-08T06:19:13.534888Z","steps":["trace[1245427722] 'process raft request' (duration: 101.722353ms)","trace[1245427722] 'compare' (duration: 49.002212ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T06:19:13.919713Z","caller":"traceutil/trace.go:171","msg":"trace[1417712180] transaction","detail":"{read_only:false; response_revision:4416; number_of_response:1; }","duration":"446.124668ms","start":"2026-04-08T06:19:13.473552Z","end":"2026-04-08T06:19:13.919677Z","steps":["trace[1417712180] 'process raft request' (duration: 445.812251ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:19:13.919771Z","caller":"traceutil/trace.go:171","msg":"trace[1900579662] transaction","detail":"{read_only:false; response_revision:4415; number_of_response:1; }","duration":"498.584232ms","start":"2026-04-08T06:19:13.421144Z","end":"2026-04-08T06:19:13.919728Z","steps":["trace[1900579662] 'process raft request' (duration: 496.940997ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:19:13.919956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:19:13.473523Z","time spent":"446.289791ms","remote":"127.0.0.1:57412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2747,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T06:19:13.920075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:19:13.421113Z","time spent":"498.775765ms","remote":"127.0.0.1:57412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2716,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T06:19:13.981153Z","caller":"traceutil/trace.go:171","msg":"trace[736444916] linearizableReadLoop","detail":"{readStateIndex:4589; appliedIndex:4584; }","duration":"380.362226ms","start":"2026-04-08T06:19:13.600768Z","end":"2026-04-08T06:19:13.981131Z","steps":["trace[736444916] 'read index received' (duration: 317.380371ms)","trace[736444916] 'applied index is now lower than readState.Index' (duration: 62.980795ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T06:19:13.981153Z","caller":"traceutil/trace.go:171","msg":"trace[414014049] transaction","detail":"{read_only:false; response_revision:4417; number_of_response:1; }","duration":"484.346784ms","start":"2026-04-08T06:19:13.496752Z","end":"2026-04-08T06:19:13.981099Z","steps":["trace[414014049] 'process raft request' (duration: 484.13451ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:19:13.98135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:19:13.496714Z","time spent":"484.554468ms","remote":"127.0.0.1:54984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":817,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-08T06:19:13.981388Z","caller":"traceutil/trace.go:171","msg":"trace[662300912] transaction","detail":"{read_only:false; response_revision:4419; number_of_response:1; }","duration":"411.083117ms","start":"2026-04-08T06:19:13.570292Z","end":"2026-04-08T06:19:13.981375Z","steps":["trace[662300912] 'process raft request' (duration: 410.78394ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:19:13.981181Z","caller":"traceutil/trace.go:171","msg":"trace[645945447] transaction","detail":"{read_only:false; response_revision:4418; number_of_response:1; }","duration":"457.877143ms","start":"2026-04-08T06:19:13.523275Z","end":"2026-04-08T06:19:13.981152Z","steps":["trace[645945447] 'process raft request' (duration: 457.72514ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:19:13.98149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:19:13.570256Z","time spent":"411.168108ms","remote":"127.0.0.1:55744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3459,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-08T06:19:13.981579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:19:13.523218Z","time spent":"458.271601ms","remote":"127.0.0.1:57412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2702,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T06:19:13.981788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.658674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T06:19:13.98183Z","caller":"traceutil/trace.go:171","msg":"trace[11133688] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:4419; }","duration":"325.746526ms","start":"2026-04-08T06:19:13.656074Z","end":"2026-04-08T06:19:13.98182Z","steps":["trace[11133688] 'agreement among raft nodes before linearized reading' (duration: 325.663524ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:19:13.981859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:19:13.656051Z","time spent":"325.801296ms","remote":"127.0.0.1:55118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":26,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-04-08T06:19:13.981965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.588041ms","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-04-08T06:19:13.982011Z","caller":"traceutil/trace.go:171","msg":"trace[879610133] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4419; }","duration":"149.659353ms","start":"2026-04-08T06:19:13.83234Z","end":"2026-04-08T06:19:13.981999Z","steps":["trace[879610133] 'agreement among raft nodes before linearized reading' (duration: 149.51567ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:19:13.982062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.58716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-08T06:19:13.982108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.932619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-08T06:19:13.982107Z","caller":"traceutil/trace.go:171","msg":"trace[1806045900] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:4419; }","duration":"170.710671ms","start":"2026-04-08T06:19:13.811383Z","end":"2026-04-08T06:19:13.982094Z","steps":["trace[1806045900] 'agreement among raft nodes before linearized reading' (duration: 170.65359ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:19:13.982139Z","caller":"traceutil/trace.go:171","msg":"trace[1647280619] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4419; }","duration":"256.163364ms","start":"2026-04-08T06:19:13.725968Z","end":"2026-04-08T06:19:13.982132Z","steps":["trace[1647280619] 'agreement among raft nodes before linearized reading' (duration: 256.046232ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:19:13.981793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.028679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-04-08T06:19:13.982255Z","caller":"traceutil/trace.go:171","msg":"trace[1928905553] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4419; }","duration":"381.545609ms","start":"2026-04-08T06:19:13.600702Z","end":"2026-04-08T06:19:13.982247Z","steps":["trace[1928905553] 'agreement among raft nodes before linearized reading' (duration: 380.967378ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:19:13.982329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.007504ms","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-04-08T06:19:13.982378Z","caller":"traceutil/trace.go:171","msg":"trace[1650904962] range","detail":"{range_begin:/registry/rabbitmq.com/superstreams/; range_end:/registry/rabbitmq.com/superstreams0; response_count:0; response_revision:4419; }","duration":"132.116076ms","start":"2026-04-08T06:19:13.850246Z","end":"2026-04-08T06:19:13.982362Z","steps":["trace[1650904962] 'agreement among raft nodes before linearized reading' (duration: 132.054205ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:19:13.982316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:19:13.600671Z","time spent":"381.605791ms","remote":"127.0.0.1:55082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12234,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-04-08T06:19:13.982015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.453253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-08T06:19:13.982656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.028966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-08T06:19:13.982713Z","caller":"traceutil/trace.go:171","msg":"trace[437979440] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4419; }","duration":"145.096838ms","start":"2026-04-08T06:19:13.837592Z","end":"2026-04-08T06:19:13.982689Z","steps":["trace[437979440] 'agreement among raft nodes before linearized reading' (duration: 144.966915ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:19:13.982697Z","caller":"traceutil/trace.go:171","msg":"trace[606163114] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4419; }","duration":"202.129267ms","start":"2026-04-08T06:19:13.780529Z","end":"2026-04-08T06:19:13.982658Z","steps":["trace[606163114] 'agreement among raft nodes before linearized reading' (duration: 201.419422ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:19:16.49791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.366724ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T06:19:16.499053Z","caller":"traceutil/trace.go:171","msg":"trace[1522734899] transaction","detail":"{read_only:false; response_revision:4457; number_of_response:1; }","duration":"233.742589ms","start":"2026-04-08T06:19:16.265159Z","end":"2026-04-08T06:19:16.498901Z","steps":["trace[1522734899] 'process raft request' (duration: 109.068098ms)","trace[1522734899] 'compare' (duration: 123.136779ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T06:19:16.499684Z","caller":"traceutil/trace.go:171","msg":"trace[1503793539] linearizableReadLoop","detail":"{readStateIndex:4628; appliedIndex:4627; }","duration":"199.619003ms","start":"2026-04-08T06:19:16.300031Z","end":"2026-04-08T06:19:16.49965Z","steps":["trace[1503793539] 'read index received' (duration: 74.207056ms)","trace[1503793539] 'applied index is now lower than readState.Index' (duration: 125.410247ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T06:19:16.499894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.975641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T06:19:16.499936Z","caller":"traceutil/trace.go:171","msg":"trace[743651647] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:4457; }","duration":"200.048853ms","start":"2026-04-08T06:19:16.299875Z","end":"2026-04-08T06:19:16.499924Z","steps":["trace[743651647] 'agreement among raft nodes before linearized reading' (duration: 199.9315ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:19:16.500949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.308603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T06:19:16.501013Z","caller":"traceutil/trace.go:171","msg":"trace[1585753126] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:4459; }","duration":"194.379464ms","start":"2026-04-08T06:19:16.306618Z","end":"2026-04-08T06:19:16.500997Z","steps":["trace[1585753126] 'agreement among raft nodes before linearized reading' (duration: 194.264862ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:19:16.501302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.451462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" ","response":"range_response_count:1 size:9425"} {"level":"info","ts":"2026-04-08T06:19:16.501332Z","caller":"traceutil/trace.go:171","msg":"trace[1393012044] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:1; response_revision:4459; }","duration":"132.532334ms","start":"2026-04-08T06:19:16.368791Z","end":"2026-04-08T06:19:16.501324Z","steps":["trace[1393012044] 'agreement among raft nodes before linearized reading' (duration: 132.410501ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:19:16.50155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.695286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T06:19:16.50161Z","caller":"traceutil/trace.go:171","msg":"trace[1108051754] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4459; }","duration":"160.803958ms","start":"2026-04-08T06:19:16.340788Z","end":"2026-04-08T06:19:16.501592Z","steps":["trace[1108051754] 'agreement among raft nodes before linearized reading' (duration: 160.711576ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:19:20.187591Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920880126414704891,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-08T06:19:20.347064Z","caller":"traceutil/trace.go:171","msg":"trace[532014275] linearizableReadLoop","detail":"{readStateIndex:4730; appliedIndex:4729; }","duration":"659.963363ms","start":"2026-04-08T06:19:19.687077Z","end":"2026-04-08T06:19:20.34704Z","steps":["trace[532014275] 'read index received' (duration: 659.723048ms)","trace[532014275] 'applied index is now lower than readState.Index' (duration: 239.425µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T06:19:20.347185Z","caller":"traceutil/trace.go:171","msg":"trace[1784208566] transaction","detail":"{read_only:false; response_revision:4558; number_of_response:1; }","duration":"753.531685ms","start":"2026-04-08T06:19:19.593609Z","end":"2026-04-08T06:19:20.347141Z","steps":["trace[1784208566] 'process raft request' (duration: 753.240379ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:19:20.34733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.573807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-08T06:19:20.347323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:19:19.593585Z","time spent":"753.656497ms","remote":"127.0.0.1:55164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T06:19:20.34737Z","caller":"traceutil/trace.go:171","msg":"trace[1254908601] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4558; }","duration":"314.631668ms","start":"2026-04-08T06:19:20.032728Z","end":"2026-04-08T06:19:20.34736Z","steps":["trace[1254908601] 'agreement among raft nodes before linearized reading' (duration: 314.476075ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:19:20.347396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:19:20.032722Z","time spent":"314.669139ms","remote":"127.0.0.1:55164","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-04-08T06:19:20.347411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.743331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7f3d4c21-370b-4273-af74-daecfdf55300\" ","response":"range_response_count:1 size:4050"} {"level":"info","ts":"2026-04-08T06:19:20.347442Z","caller":"traceutil/trace.go:171","msg":"trace[1396621202] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-7f3d4c21-370b-4273-af74-daecfdf55300; range_end:; response_count:1; response_revision:4558; }","duration":"294.818703ms","start":"2026-04-08T06:19:20.052616Z","end":"2026-04-08T06:19:20.347434Z","steps":["trace[1396621202] 'agreement among raft nodes before linearized reading' (duration: 294.729611ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:19:20.347556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.24192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-08T06:19:20.347538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.144458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-08T06:19:20.347678Z","caller":"traceutil/trace.go:171","msg":"trace[981029359] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4558; }","duration":"315.338192ms","start":"2026-04-08T06:19:20.032319Z","end":"2026-04-08T06:19:20.347657Z","steps":["trace[981029359] 'agreement among raft nodes before linearized reading' (duration: 314.963375ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:19:20.347678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.985799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-08T06:19:20.347612Z","caller":"traceutil/trace.go:171","msg":"trace[439023538] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4558; }","duration":"315.319512ms","start":"2026-04-08T06:19:20.032278Z","end":"2026-04-08T06:19:20.347598Z","steps":["trace[439023538] 'agreement among raft nodes before linearized reading' (duration: 315.180409ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:19:20.347774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:19:20.032294Z","time spent":"315.468235ms","remote":"127.0.0.1:55164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-08T06:19:20.347832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:19:20.032262Z","time spent":"315.550627ms","remote":"127.0.0.1:55164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-08T06:19:20.34795Z","caller":"traceutil/trace.go:171","msg":"trace[318667563] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4558; }","duration":"323.113482ms","start":"2026-04-08T06:19:20.024602Z","end":"2026-04-08T06:19:20.347716Z","steps":["trace[318667563] 'agreement among raft nodes before linearized reading' (duration: 322.587221ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:19:20.348125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:19:20.024521Z","time spent":"323.584351ms","remote":"127.0.0.1:55164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-08T06:19:20.348571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"661.478084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1695fa02-0d8c-4161-a41f-9c8290ce33e5\" ","response":"range_response_count:1 size:4064"} {"level":"info","ts":"2026-04-08T06:19:20.348621Z","caller":"traceutil/trace.go:171","msg":"trace[2036425515] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1695fa02-0d8c-4161-a41f-9c8290ce33e5; range_end:; response_count:1; response_revision:4558; }","duration":"661.541336ms","start":"2026-04-08T06:19:19.687068Z","end":"2026-04-08T06:19:20.348609Z","steps":["trace[2036425515] 'agreement among raft nodes before linearized reading' (duration: 660.102966ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:19:20.348702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:19:19.686973Z","time spent":"661.677648ms","remote":"127.0.0.1:55082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":4085,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1695fa02-0d8c-4161-a41f-9c8290ce33e5\" "} {"level":"info","ts":"2026-04-08T06:19:22.720643Z","caller":"traceutil/trace.go:171","msg":"trace[820442] transaction","detail":"{read_only:false; response_revision:4640; number_of_response:1; }","duration":"133.897721ms","start":"2026-04-08T06:19:22.586719Z","end":"2026-04-08T06:19:22.720616Z","steps":["trace[820442] 'process raft request' (duration: 131.181655ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:19:22.720995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.442791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1817"} {"level":"info","ts":"2026-04-08T06:19:22.721088Z","caller":"traceutil/trace.go:171","msg":"trace[1141432239] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4640; }","duration":"131.602083ms","start":"2026-04-08T06:19:22.589469Z","end":"2026-04-08T06:19:22.721071Z","steps":["trace[1141432239] 'agreement among raft nodes before linearized reading' (duration: 131.232606ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:19:22.720584Z","caller":"traceutil/trace.go:171","msg":"trace[1517140879] linearizableReadLoop","detail":"{readStateIndex:4817; appliedIndex:4816; }","duration":"131.014581ms","start":"2026-04-08T06:19:22.589517Z","end":"2026-04-08T06:19:22.720532Z","steps":["trace[1517140879] 'read index received' (duration: 128.405368ms)","trace[1517140879] 'applied index is now lower than readState.Index' (duration: 2.607753ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T06:19:23.176979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.540411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-08T06:19:23.17708Z","caller":"traceutil/trace.go:171","msg":"trace[950983930] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4642; }","duration":"146.683804ms","start":"2026-04-08T06:19:23.030375Z","end":"2026-04-08T06:19:23.177059Z","steps":["trace[950983930] 'range keys from in-memory index tree' (duration: 146.387527ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:19:23.177199Z","caller":"traceutil/trace.go:171","msg":"trace[805602880] transaction","detail":"{read_only:false; response_revision:4643; number_of_response:1; }","duration":"126.584435ms","start":"2026-04-08T06:19:23.050575Z","end":"2026-04-08T06:19:23.17716Z","steps":["trace[805602880] 'process raft request' (duration: 126.222098ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:19:23.177707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.387541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2193165"} {"level":"info","ts":"2026-04-08T06:19:23.177749Z","caller":"traceutil/trace.go:171","msg":"trace[1198744340] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4642; }","duration":"154.464984ms","start":"2026-04-08T06:19:23.023275Z","end":"2026-04-08T06:19:23.17774Z","steps":["trace[1198744340] 'range keys from in-memory index tree' (duration: 153.569335ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:19:30.258909Z","caller":"traceutil/trace.go:171","msg":"trace[1469557364] linearizableReadLoop","detail":"{readStateIndex:4926; appliedIndex:4925; }","duration":"310.135097ms","start":"2026-04-08T06:19:29.948734Z","end":"2026-04-08T06:19:30.25887Z","steps":["trace[1469557364] 'read index received' (duration: 310.049085ms)","trace[1469557364] 'applied index is now lower than readState.Index' (duration: 84.502µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T06:19:30.25894Z","caller":"traceutil/trace.go:171","msg":"trace[1670319576] transaction","detail":"{read_only:false; response_revision:4747; number_of_response:1; }","duration":"567.394246ms","start":"2026-04-08T06:19:29.691501Z","end":"2026-04-08T06:19:30.258895Z","steps":["trace[1670319576] 'process raft request' (duration: 567.142591ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:19:30.259158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:19:29.691475Z","time spent":"567.58215ms","remote":"127.0.0.1:54984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":922,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-08T06:19:30.259357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.586325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-95f69e37-232b-4741-aa94-470d597ddc13\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-04-08T06:19:30.259454Z","caller":"traceutil/trace.go:171","msg":"trace[664525995] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-95f69e37-232b-4741-aa94-470d597ddc13; range_end:; response_count:1; response_revision:4747; }","duration":"310.75787ms","start":"2026-04-08T06:19:29.948675Z","end":"2026-04-08T06:19:30.259433Z","steps":["trace[664525995] 'agreement among raft nodes before linearized reading' (duration: 310.307731ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:19:30.259703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:19:29.948632Z","time spent":"311.055316ms","remote":"127.0.0.1:55082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3667,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-95f69e37-232b-4741-aa94-470d597ddc13\" "} {"level":"info","ts":"2026-04-08T06:19:30.817058Z","caller":"traceutil/trace.go:171","msg":"trace[1099211183] transaction","detail":"{read_only:false; response_revision:4748; number_of_response:1; }","duration":"591.23798ms","start":"2026-04-08T06:19:30.225784Z","end":"2026-04-08T06:19:30.817022Z","steps":["trace[1099211183] 'process raft request' (duration: 512.2459ms)","trace[1099211183] 'compare' (duration: 77.890658ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T06:19:30.817282Z","caller":"traceutil/trace.go:171","msg":"trace[373619548] transaction","detail":"{read_only:false; response_revision:4749; number_of_response:1; }","duration":"553.558467ms","start":"2026-04-08T06:19:30.263706Z","end":"2026-04-08T06:19:30.817265Z","steps":["trace[373619548] 'process raft request' (duration: 552.658548ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:19:30.817342Z","caller":"traceutil/trace.go:171","msg":"trace[1812240683] linearizableReadLoop","detail":"{readStateIndex:4928; appliedIndex:4926; }","duration":"476.75029ms","start":"2026-04-08T06:19:30.340564Z","end":"2026-04-08T06:19:30.817314Z","steps":["trace[1812240683] 'read index received' (duration: 397.484183ms)","trace[1812240683] 'applied index is now lower than readState.Index' (duration: 79.265077ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T06:19:30.817355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:19:30.263691Z","time spent":"553.621938ms","remote":"127.0.0.1:54984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":814,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-08T06:19:30.817707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.108447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T06:19:30.817759Z","caller":"traceutil/trace.go:171","msg":"trace[836156519] transaction","detail":"{read_only:false; response_revision:4750; number_of_response:1; }","duration":"413.32113ms","start":"2026-04-08T06:19:30.404425Z","end":"2026-04-08T06:19:30.817746Z","steps":["trace[836156519] 'process raft request' (duration: 412.628787ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:19:30.817791Z","caller":"traceutil/trace.go:171","msg":"trace[2119474747] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4751; }","duration":"477.24179ms","start":"2026-04-08T06:19:30.340529Z","end":"2026-04-08T06:19:30.817771Z","steps":["trace[2119474747] 'agreement among raft nodes before linearized reading' (duration: 477.091817ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:19:30.817801Z","caller":"traceutil/trace.go:171","msg":"trace[1726278096] transaction","detail":"{read_only:false; response_revision:4751; number_of_response:1; }","duration":"257.567264ms","start":"2026-04-08T06:19:30.560223Z","end":"2026-04-08T06:19:30.817791Z","steps":["trace[1726278096] 'process raft request' (duration: 257.043754ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:19:30.817836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:19:30.34052Z","time spent":"477.307581ms","remote":"127.0.0.1:54928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-08T06:19:30.81788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:19:30.404409Z","time spent":"413.382552ms","remote":"127.0.0.1:55164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T06:19:30.818097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.799429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-08T06:19:30.818139Z","caller":"traceutil/trace.go:171","msg":"trace[163457239] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4751; }","duration":"302.88711ms","start":"2026-04-08T06:19:30.515243Z","end":"2026-04-08T06:19:30.81813Z","steps":["trace[163457239] 'agreement among raft nodes before linearized reading' (duration: 302.727307ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:19:30.818162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:19:30.515224Z","time spent":"302.933351ms","remote":"127.0.0.1:55164","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-04-08T06:19:30.818411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.791619ms","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-04-08T06:19:30.818432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.398381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-08T06:19:30.818474Z","caller":"traceutil/trace.go:171","msg":"trace[2047227965] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4751; }","duration":"354.907131ms","start":"2026-04-08T06:19:30.463554Z","end":"2026-04-08T06:19:30.818462Z","steps":["trace[2047227965] 'agreement among raft nodes before linearized reading' (duration: 354.743898ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:19:30.818542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:19:30.463528Z","time spent":"355.005064ms","remote":"127.0.0.1:55164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-08T06:19:30.818543Z","caller":"traceutil/trace.go:171","msg":"trace[1241193986] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4751; }","duration":"369.524954ms","start":"2026-04-08T06:19:30.448965Z","end":"2026-04-08T06:19:30.81849Z","steps":["trace[1241193986] 'agreement among raft nodes before linearized reading' (duration: 369.297449ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:19:30.818586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:19:30.44894Z","time spent":"369.636296ms","remote":"127.0.0.1:55164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-08T06:19:30.818456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.506332ms","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-04-08T06:19:30.81869Z","caller":"traceutil/trace.go:171","msg":"trace[1584404898] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:4751; }","duration":"174.777507ms","start":"2026-04-08T06:19:30.643899Z","end":"2026-04-08T06:19:30.818677Z","steps":["trace[1584404898] 'agreement among raft nodes before linearized reading' (duration: 174.522372ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:19:30.818842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:19:30.225754Z","time spent":"591.390283ms","remote":"127.0.0.1:55164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T06:19:30.818665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.474813ms","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-04-08T06:19:30.81943Z","caller":"traceutil/trace.go:171","msg":"trace[1392503576] 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:4751; }","duration":"419.402231ms","start":"2026-04-08T06:19:30.400007Z","end":"2026-04-08T06:19:30.819409Z","steps":["trace[1392503576] 'agreement among raft nodes before linearized reading' (duration: 418.34231ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:19:30.819474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:19:30.399975Z","time spent":"419.482013ms","remote":"127.0.0.1:55058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":147,"response count":1,"response size":1956,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" "} {"level":"warn","ts":"2026-04-08T06:19:30.820246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.525895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:12975"} {"level":"info","ts":"2026-04-08T06:19:30.820302Z","caller":"traceutil/trace.go:171","msg":"trace[464015863] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4751; }","duration":"477.584197ms","start":"2026-04-08T06:19:30.342692Z","end":"2026-04-08T06:19:30.820276Z","steps":["trace[464015863] 'agreement among raft nodes before linearized reading' (duration: 475.085437ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:19:30.820331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:19:30.342686Z","time spent":"477.638638ms","remote":"127.0.0.1:55082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":77,"response count":1,"response size":12996,"request content":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" "} {"level":"warn","ts":"2026-04-08T06:19:30.818474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.162925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-08T06:19:30.821449Z","caller":"traceutil/trace.go:171","msg":"trace[1673019826] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4751; }","duration":"306.160945ms","start":"2026-04-08T06:19:30.515275Z","end":"2026-04-08T06:19:30.821436Z","steps":["trace[1673019826] 'agreement among raft nodes before linearized reading' (duration: 303.046454ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:19:30.821534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:19:30.51526Z","time spent":"306.229797ms","remote":"127.0.0.1:55164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-08T06:19:30.956012Z","caller":"traceutil/trace.go:171","msg":"trace[529817834] linearizableReadLoop","detail":"{readStateIndex:4934; appliedIndex:4931; }","duration":"130.302307ms","start":"2026-04-08T06:19:30.825683Z","end":"2026-04-08T06:19:30.955985Z","steps":["trace[529817834] 'read index received' (duration: 16.719825ms)","trace[529817834] 'applied index is now lower than readState.Index' (duration: 113.581012ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T06:19:30.956434Z","caller":"traceutil/trace.go:171","msg":"trace[430940819] transaction","detail":"{read_only:false; response_revision:4757; number_of_response:1; }","duration":"124.741846ms","start":"2026-04-08T06:19:30.831648Z","end":"2026-04-08T06:19:30.95639Z","steps":["trace[430940819] 'process raft request' (duration: 124.267906ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:19:30.956711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.026241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-559647fc99-vvdd4.18a44d5262cafe61\" ","response":"range_response_count:1 size:896"} {"level":"info","ts":"2026-04-08T06:19:30.956706Z","caller":"traceutil/trace.go:171","msg":"trace[873552341] transaction","detail":"{read_only:false; response_revision:4753; number_of_response:1; }","duration":"130.705424ms","start":"2026-04-08T06:19:30.825971Z","end":"2026-04-08T06:19:30.956677Z","steps":["trace[873552341] 'process raft request' (duration: 118.508101ms)","trace[873552341] 'compare' (duration: 11.115052ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T06:19:30.956748Z","caller":"traceutil/trace.go:171","msg":"trace[186633267] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-grafana-559647fc99-vvdd4.18a44d5262cafe61; range_end:; response_count:1; response_revision:4757; }","duration":"131.122712ms","start":"2026-04-08T06:19:30.825614Z","end":"2026-04-08T06:19:30.956737Z","steps":["trace[186633267] 'agreement among raft nodes before linearized reading' (duration: 130.956349ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:19:30.956742Z","caller":"traceutil/trace.go:171","msg":"trace[729438135] transaction","detail":"{read_only:false; response_revision:4754; number_of_response:1; }","duration":"130.640193ms","start":"2026-04-08T06:19:30.826038Z","end":"2026-04-08T06:19:30.956678Z","steps":["trace[729438135] 'process raft request' (duration: 129.689895ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:19:30.956809Z","caller":"traceutil/trace.go:171","msg":"trace[845111539] transaction","detail":"{read_only:false; response_revision:4755; number_of_response:1; }","duration":"130.732715ms","start":"2026-04-08T06:19:30.82605Z","end":"2026-04-08T06:19:30.956782Z","steps":["trace[845111539] 'process raft request' (duration: 129.722106ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:19:30.956979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.629164ms","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-04-08T06:19:30.956946Z","caller":"traceutil/trace.go:171","msg":"trace[548325254] transaction","detail":"{read_only:false; response_revision:4756; number_of_response:1; }","duration":"129.597543ms","start":"2026-04-08T06:19:30.82731Z","end":"2026-04-08T06:19:30.956908Z","steps":["trace[548325254] 'process raft request' (duration: 128.507831ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:19:30.95701Z","caller":"traceutil/trace.go:171","msg":"trace[295290680] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretimports/; range_end:/registry/secretgen.carvel.dev/secretimports0; response_count:0; response_revision:4757; }","duration":"129.698055ms","start":"2026-04-08T06:19:30.827301Z","end":"2026-04-08T06:19:30.956999Z","steps":["trace[295290680] 'agreement among raft nodes before linearized reading' (duration: 129.616853ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:19:30.957243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.96641ms","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-04-08T06:19:30.95727Z","caller":"traceutil/trace.go:171","msg":"trace[1464290101] 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:4757; }","duration":"128.992711ms","start":"2026-04-08T06:19:30.828269Z","end":"2026-04-08T06:19:30.957262Z","steps":["trace[1464290101] 'agreement among raft nodes before linearized reading' (duration: 128.855368ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:19:31.199775Z","caller":"traceutil/trace.go:171","msg":"trace[2075061171] transaction","detail":"{read_only:false; response_revision:4758; number_of_response:1; }","duration":"239.998614ms","start":"2026-04-08T06:19:30.959742Z","end":"2026-04-08T06:19:31.19974Z","steps":["trace[2075061171] 'process raft request' (duration: 239.729929ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:19:31.20002Z","caller":"traceutil/trace.go:171","msg":"trace[524442410] transaction","detail":"{read_only:false; response_revision:4760; number_of_response:1; }","duration":"235.024054ms","start":"2026-04-08T06:19:30.964928Z","end":"2026-04-08T06:19:31.199952Z","steps":["trace[524442410] 'process raft request' (duration: 234.772819ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:19:31.200123Z","caller":"traceutil/trace.go:171","msg":"trace[912759898] linearizableReadLoop","detail":"{readStateIndex:4938; appliedIndex:4936; }","duration":"236.833129ms","start":"2026-04-08T06:19:30.963033Z","end":"2026-04-08T06:19:31.199866Z","steps":["trace[912759898] 'read index received' (duration: 144.933175ms)","trace[912759898] 'applied index is now lower than readState.Index' (duration: 91.899024ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T06:19:31.200351Z","caller":"traceutil/trace.go:171","msg":"trace[492550202] transaction","detail":"{read_only:false; response_revision:4761; number_of_response:1; }","duration":"234.202566ms","start":"2026-04-08T06:19:30.966111Z","end":"2026-04-08T06:19:31.200314Z","steps":["trace[492550202] 'process raft request' (duration: 233.651165ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:19:31.200366Z","caller":"traceutil/trace.go:171","msg":"trace[625520423] transaction","detail":"{read_only:false; response_revision:4762; number_of_response:1; }","duration":"233.35592ms","start":"2026-04-08T06:19:30.966985Z","end":"2026-04-08T06:19:31.200341Z","steps":["trace[625520423] 'process raft request' (duration: 232.839089ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:19:31.200728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.489512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-7f3d4c21-370b-4273-af74-daecfdf55300\" ","response":"range_response_count:1 size:1435"} {"level":"warn","ts":"2026-04-08T06:19:31.200738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.696556ms","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-04-08T06:19:31.200804Z","caller":"traceutil/trace.go:171","msg":"trace[518035660] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4762; }","duration":"237.797958ms","start":"2026-04-08T06:19:30.962991Z","end":"2026-04-08T06:19:31.200789Z","steps":["trace[518035660] 'agreement among raft nodes before linearized reading' (duration: 237.539083ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:19:31.200875Z","caller":"traceutil/trace.go:171","msg":"trace[1759713813] transaction","detail":"{read_only:false; response_revision:4759; number_of_response:1; }","duration":"236.154687ms","start":"2026-04-08T06:19:30.964682Z","end":"2026-04-08T06:19:31.200837Z","steps":["trace[1759713813] 'process raft request' (duration: 234.961603ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:19:31.2008Z","caller":"traceutil/trace.go:171","msg":"trace[1446468028] range","detail":"{range_begin:/registry/persistentvolumes/pvc-7f3d4c21-370b-4273-af74-daecfdf55300; range_end:; response_count:1; response_revision:4762; }","duration":"237.598774ms","start":"2026-04-08T06:19:30.963183Z","end":"2026-04-08T06:19:31.200782Z","steps":["trace[1446468028] 'agreement among raft nodes before linearized reading' (duration: 237.38557ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:19:32.277313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"621.603436ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T06:19:32.277628Z","caller":"traceutil/trace.go:171","msg":"trace[1721425676] linearizableReadLoop","detail":"{readStateIndex:4951; appliedIndex:4946; }","duration":"988.246804ms","start":"2026-04-08T06:19:31.289365Z","end":"2026-04-08T06:19:32.277612Z","steps":["trace[1721425676] 'read index received' (duration: 366.20095ms)","trace[1721425676] 'applied index is now lower than readState.Index' (duration: 622.045004ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T06:19:32.277721Z","caller":"traceutil/trace.go:171","msg":"trace[742779315] transaction","detail":"{read_only:false; response_revision:4767; number_of_response:1; }","duration":"992.879347ms","start":"2026-04-08T06:19:31.284833Z","end":"2026-04-08T06:19:32.277712Z","steps":["trace[742779315] 'process raft request' (duration: 370.801662ms)","trace[742779315] 'compare' (duration: 620.714327ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T06:19:32.277774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:19:31.284802Z","time spent":"992.944809ms","remote":"127.0.0.1:55164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T06:19:32.277836Z","caller":"traceutil/trace.go:171","msg":"trace[553768042] transaction","detail":"{read_only:false; response_revision:4770; number_of_response:1; }","duration":"991.115742ms","start":"2026-04-08T06:19:31.286688Z","end":"2026-04-08T06:19:32.277804Z","steps":["trace[553768042] 'process raft request' (duration: 990.846917ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:19:32.277968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"937.4358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T06:19:32.278002Z","caller":"traceutil/trace.go:171","msg":"trace[1091462743] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4771; }","duration":"937.503312ms","start":"2026-04-08T06:19:31.340489Z","end":"2026-04-08T06:19:32.277992Z","steps":["trace[1091462743] 'agreement among raft nodes before linearized reading' (duration: 937.456231ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:19:32.278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:19:31.286661Z","time spent":"991.250654ms","remote":"127.0.0.1:55064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":850,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T06:19:32.278023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:19:31.340469Z","time spent":"937.549542ms","remote":"127.0.0.1:54928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-08T06:19:32.278053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"988.658852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-08T06:19:32.278152Z","caller":"traceutil/trace.go:171","msg":"trace[1339600071] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:4771; }","duration":"988.803345ms","start":"2026-04-08T06:19:31.289328Z","end":"2026-04-08T06:19:32.278132Z","steps":["trace[1339600071] 'agreement among raft nodes before linearized reading' (duration: 988.477579ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:19:32.278208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:19:31.289319Z","time spent":"988.878647ms","remote":"127.0.0.1:54942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" "} {"level":"warn","ts":"2026-04-08T06:19:32.278239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.214421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-cpbtb.18a44d50f97d7a06\" ","response":"range_response_count:1 size:962"} {"level":"info","ts":"2026-04-08T06:19:32.278262Z","caller":"traceutil/trace.go:171","msg":"trace[486747317] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-cpbtb.18a44d50f97d7a06; range_end:; response_count:1; response_revision:4771; }","duration":"527.288793ms","start":"2026-04-08T06:19:31.750967Z","end":"2026-04-08T06:19:32.278256Z","steps":["trace[486747317] 'agreement among raft nodes before linearized reading' (duration: 527.180721ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:19:32.278284Z","caller":"traceutil/trace.go:171","msg":"trace[18585380] transaction","detail":"{read_only:false; response_revision:4768; number_of_response:1; }","duration":"993.287004ms","start":"2026-04-08T06:19:31.284988Z","end":"2026-04-08T06:19:32.278275Z","steps":["trace[18585380] 'process raft request' (duration: 992.408207ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:19:32.278329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:19:31.284983Z","time spent":"993.323245ms","remote":"127.0.0.1:55176","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1853,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T06:19:32.278423Z","caller":"traceutil/trace.go:171","msg":"trace[188779795] transaction","detail":"{read_only:false; response_revision:4771; number_of_response:1; }","duration":"991.333556ms","start":"2026-04-08T06:19:31.287076Z","end":"2026-04-08T06:19:32.27841Z","steps":["trace[188779795] 'process raft request' (duration: 990.50486ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:19:32.278288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:19:31.75094Z","time spent":"527.344383ms","remote":"127.0.0.1:54984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":1,"response size":983,"request content":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-cpbtb.18a44d50f97d7a06\" "} {"level":"warn","ts":"2026-04-08T06:19:32.27855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:19:31.287068Z","time spent":"991.391947ms","remote":"127.0.0.1:55064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1273,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T06:19:32.278428Z","caller":"traceutil/trace.go:171","msg":"trace[53708554] transaction","detail":"{read_only:false; response_revision:4769; number_of_response:1; }","duration":"992.021719ms","start":"2026-04-08T06:19:31.2864Z","end":"2026-04-08T06:19:32.278422Z","steps":["trace[53708554] 'process raft request' (duration: 991.097881ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:19:32.277054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.998274ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-08T06:19:32.278738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:19:31.286372Z","time spent":"992.332916ms","remote":"127.0.0.1:55176","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1393,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T06:19:32.278761Z","caller":"traceutil/trace.go:171","msg":"trace[994255147] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:4766; }","duration":"368.702047ms","start":"2026-04-08T06:19:31.910026Z","end":"2026-04-08T06:19:32.278728Z","steps":["trace[994255147] 'range keys from in-memory index tree' (duration: 366.977913ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:19:32.722246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.094153ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T06:19:32.722403Z","caller":"traceutil/trace.go:171","msg":"trace[1417561463] linearizableReadLoop","detail":"{readStateIndex:4953; appliedIndex:4952; }","duration":"405.884333ms","start":"2026-04-08T06:19:32.316494Z","end":"2026-04-08T06:19:32.722378Z","steps":["trace[1417561463] 'read index received' (duration: 183.251359ms)","trace[1417561463] 'applied index is now lower than readState.Index' (duration: 222.629684ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T06:19:32.722595Z","caller":"traceutil/trace.go:171","msg":"trace[1436137964] transaction","detail":"{read_only:false; response_revision:4773; number_of_response:1; }","duration":"420.304021ms","start":"2026-04-08T06:19:32.302271Z","end":"2026-04-08T06:19:32.722575Z","steps":["trace[1436137964] 'process raft request' (duration: 197.560994ms)","trace[1436137964] 'compare' (duration: 221.886199ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T06:19:32.722689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.06397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T06:19:32.722794Z","caller":"traceutil/trace.go:171","msg":"trace[861023225] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4773; }","duration":"382.215123ms","start":"2026-04-08T06:19:32.340562Z","end":"2026-04-08T06:19:32.722778Z","steps":["trace[861023225] 'agreement among raft nodes before linearized reading' (duration: 382.033809ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:19:32.722854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:19:32.340541Z","time spent":"382.299974ms","remote":"127.0.0.1:54928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-08T06:19:32.722704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:19:32.30225Z","time spent":"420.399313ms","remote":"127.0.0.1:54984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":881,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T06:19:32.723322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.797051ms","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-04-08T06:19:32.723673Z","caller":"traceutil/trace.go:171","msg":"trace[1615822798] 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:4773; }","duration":"407.021426ms","start":"2026-04-08T06:19:32.316463Z","end":"2026-04-08T06:19:32.723484Z","steps":["trace[1615822798] 'agreement among raft nodes before linearized reading' (duration: 406.21077ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:19:32.723799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:19:32.316449Z","time spent":"407.328602ms","remote":"127.0.0.1:55058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":147,"response count":1,"response size":1956,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" "} {"level":"warn","ts":"2026-04-08T06:19:44.100211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"659.941137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-04-08T06:19:44.100324Z","caller":"traceutil/trace.go:171","msg":"trace[529650395] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4911; }","duration":"660.129031ms","start":"2026-04-08T06:19:43.440168Z","end":"2026-04-08T06:19:44.100297Z","steps":["trace[529650395] 'range keys from in-memory index tree' (duration: 659.688252ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:19:44.100373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:19:43.440143Z","time spent":"660.218692ms","remote":"127.0.0.1:55058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1886,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-04-08T06:19:44.100717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.392715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3244"} {"level":"info","ts":"2026-04-08T06:19:44.100801Z","caller":"traceutil/trace.go:171","msg":"trace[350713631] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4911; }","duration":"539.541388ms","start":"2026-04-08T06:19:43.561242Z","end":"2026-04-08T06:19:44.100784Z","steps":["trace[350713631] 'range keys from in-memory index tree' (duration: 539.205411ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:19:44.100836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:19:43.561213Z","time spent":"539.61796ms","remote":"127.0.0.1:47026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":3265,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" "} {"level":"info","ts":"2026-04-08T06:19:44.101009Z","caller":"traceutil/trace.go:171","msg":"trace[1268797391] linearizableReadLoop","detail":"{readStateIndex:5096; appliedIndex:5095; }","duration":"199.784878ms","start":"2026-04-08T06:19:43.901192Z","end":"2026-04-08T06:19:44.100977Z","steps":["trace[1268797391] 'read index received' (duration: 105.257246ms)","trace[1268797391] 'applied index is now lower than readState.Index' (duration: 94.525772ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T06:19:44.101227Z","caller":"traceutil/trace.go:171","msg":"trace[1773751204] transaction","detail":"{read_only:false; response_revision:4912; number_of_response:1; }","duration":"327.488118ms","start":"2026-04-08T06:19:43.773723Z","end":"2026-04-08T06:19:44.101211Z","steps":["trace[1773751204] 'process raft request' (duration: 232.733391ms)","trace[1773751204] 'compare' (duration: 93.835069ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T06:19:44.101358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:19:43.773688Z","time spent":"327.57268ms","remote":"127.0.0.1:55164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T06:19:44.101537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.779061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T06:19:44.101713Z","caller":"traceutil/trace.go:171","msg":"trace[511812611] range","detail":"{range_begin:/registry/rabbitmq.com/topicpermissions/; range_end:/registry/rabbitmq.com/topicpermissions0; response_count:0; response_revision:4911; }","duration":"422.058216ms","start":"2026-04-08T06:19:43.67962Z","end":"2026-04-08T06:19:44.101679Z","steps":["trace[511812611] 'count revisions from in-memory index tree' (duration: 420.348583ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:19:44.10181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.614704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3244"} {"level":"warn","ts":"2026-04-08T06:19:44.101819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:19:43.679548Z","time spent":"422.249649ms","remote":"127.0.0.1:47082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":26,"request content":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" count_only:true "} {"level":"info","ts":"2026-04-08T06:19:44.101877Z","caller":"traceutil/trace.go:171","msg":"trace[1863146419] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4912; }","duration":"200.741066ms","start":"2026-04-08T06:19:43.901115Z","end":"2026-04-08T06:19:44.101856Z","steps":["trace[1863146419] 'agreement among raft nodes before linearized reading' (duration: 200.559523ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:19:44.101854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.382279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-cpbtb.18a44d50bd4c189b\" ","response":"range_response_count:1 size:906"} {"level":"info","ts":"2026-04-08T06:19:44.101939Z","caller":"traceutil/trace.go:171","msg":"trace[196091072] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-cpbtb.18a44d50bd4c189b; range_end:; response_count:1; response_revision:4912; }","duration":"184.492071ms","start":"2026-04-08T06:19:43.917426Z","end":"2026-04-08T06:19:44.101918Z","steps":["trace[196091072] 'agreement among raft nodes before linearized reading' (duration: 184.235886ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:19:44.607984Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920880126414705737,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-08T06:19:44.891541Z","caller":"traceutil/trace.go:171","msg":"trace[1239710696] linearizableReadLoop","detail":"{readStateIndex:5097; appliedIndex:5096; }","duration":"783.722143ms","start":"2026-04-08T06:19:44.107793Z","end":"2026-04-08T06:19:44.891516Z","steps":["trace[1239710696] 'read index received' (duration: 783.265494ms)","trace[1239710696] 'applied index is now lower than readState.Index' (duration: 454.649µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T06:19:44.891571Z","caller":"traceutil/trace.go:171","msg":"trace[1380879660] transaction","detail":"{read_only:false; response_revision:4913; number_of_response:1; }","duration":"785.303485ms","start":"2026-04-08T06:19:44.106227Z","end":"2026-04-08T06:19:44.89153Z","steps":["trace[1380879660] 'process raft request' (duration: 784.917497ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:19:44.891764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:19:44.106204Z","time spent":"785.457398ms","remote":"127.0.0.1:54984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":891,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T06:19:44.891879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"784.00742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rabbitmq-keystone-default-user\" ","response":"range_response_count:1 size:1162"} {"level":"info","ts":"2026-04-08T06:19:44.891909Z","caller":"traceutil/trace.go:171","msg":"trace[841913235] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-keystone-default-user; range_end:; response_count:1; response_revision:4913; }","duration":"784.119592ms","start":"2026-04-08T06:19:44.107783Z","end":"2026-04-08T06:19:44.891903Z","steps":["trace[841913235] 'agreement among raft nodes before linearized reading' (duration: 783.882917ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:19:44.891934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:19:44.107778Z","time spent":"784.151212ms","remote":"127.0.0.1:55002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":1183,"request content":"key:\"/registry/secrets/openstack/rabbitmq-keystone-default-user\" "} {"level":"warn","ts":"2026-04-08T06:19:44.911294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.987446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"warn","ts":"2026-04-08T06:19:44.911328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"652.221325ms","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":"warn","ts":"2026-04-08T06:19:44.911448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.257283ms","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-04-08T06:19:44.911804Z","caller":"traceutil/trace.go:171","msg":"trace[1618718843] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:4915; }","duration":"652.584061ms","start":"2026-04-08T06:19:44.259066Z","end":"2026-04-08T06:19:44.911651Z","steps":["trace[1618718843] 'agreement among raft nodes before linearized reading' (duration: 652.170684ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:19:44.911848Z","caller":"traceutil/trace.go:171","msg":"trace[28425093] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4915; }","duration":"493.697881ms","start":"2026-04-08T06:19:44.418133Z","end":"2026-04-08T06:19:44.911831Z","steps":["trace[28425093] 'agreement among raft nodes before linearized reading' (duration: 493.270773ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:19:44.911878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:19:44.418107Z","time spent":"493.762892ms","remote":"127.0.0.1:55164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-08T06:19:44.9119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:19:44.258973Z","time spent":"652.880887ms","remote":"127.0.0.1:43908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":28,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-04-08T06:19:44.911356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"499.775669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-04-08T06:19:44.912033Z","caller":"traceutil/trace.go:171","msg":"trace[1145335470] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4915; }","duration":"500.546474ms","start":"2026-04-08T06:19:44.411476Z","end":"2026-04-08T06:19:44.912023Z","steps":["trace[1145335470] 'agreement among raft nodes before linearized reading' (duration: 499.748269ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:19:44.912055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:19:44.411424Z","time spent":"500.625146ms","remote":"127.0.0.1:55058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1886,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"info","ts":"2026-04-08T06:19:44.911374Z","caller":"traceutil/trace.go:171","msg":"trace[1150849320] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4915; }","duration":"233.12613ms","start":"2026-04-08T06:19:44.678225Z","end":"2026-04-08T06:19:44.911351Z","steps":["trace[1150849320] 'agreement among raft nodes before linearized reading' (duration: 232.919066ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:19:45.091867Z","caller":"traceutil/trace.go:171","msg":"trace[1783852653] transaction","detail":"{read_only:false; response_revision:4919; number_of_response:1; }","duration":"105.193755ms","start":"2026-04-08T06:19:44.986631Z","end":"2026-04-08T06:19:45.091825Z","steps":["trace[1783852653] 'process raft request' (duration: 93.109442ms)","trace[1783852653] 'compare' (duration: 11.809728ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T06:19:45.268105Z","caller":"traceutil/trace.go:171","msg":"trace[1926923448] transaction","detail":"{read_only:false; response_revision:4921; number_of_response:1; }","duration":"170.832804ms","start":"2026-04-08T06:19:45.097245Z","end":"2026-04-08T06:19:45.268078Z","steps":["trace[1926923448] 'process raft request' (duration: 158.881153ms)","trace[1926923448] 'compare' (duration: 11.757617ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T06:19:45.268653Z","caller":"traceutil/trace.go:171","msg":"trace[1379447715] transaction","detail":"{read_only:false; response_revision:4923; number_of_response:1; }","duration":"171.299844ms","start":"2026-04-08T06:19:45.097343Z","end":"2026-04-08T06:19:45.268643Z","steps":["trace[1379447715] 'process raft request' (duration: 170.931727ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:19:45.268627Z","caller":"traceutil/trace.go:171","msg":"trace[1165827141] linearizableReadLoop","detail":"{readStateIndex:5107; appliedIndex:5103; }","duration":"171.09512ms","start":"2026-04-08T06:19:45.097502Z","end":"2026-04-08T06:19:45.268597Z","steps":["trace[1165827141] 'read index received' (duration: 27.486552ms)","trace[1165827141] 'applied index is now lower than readState.Index' (duration: 143.607178ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T06:19:45.268779Z","caller":"traceutil/trace.go:171","msg":"trace[1540808575] transaction","detail":"{read_only:false; response_revision:4922; number_of_response:1; }","duration":"171.455546ms","start":"2026-04-08T06:19:45.097311Z","end":"2026-04-08T06:19:45.268767Z","steps":["trace[1540808575] 'process raft request' (duration: 170.721552ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:19:45.268807Z","caller":"traceutil/trace.go:171","msg":"trace[148702607] transaction","detail":"{read_only:false; response_revision:4924; number_of_response:1; }","duration":"170.401066ms","start":"2026-04-08T06:19:45.098368Z","end":"2026-04-08T06:19:45.268769Z","steps":["trace[148702607] 'process raft request' (duration: 170.119171ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:19:45.268916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.346964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T06:19:45.268997Z","caller":"traceutil/trace.go:171","msg":"trace[164298258] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:4924; }","duration":"171.540698ms","start":"2026-04-08T06:19:45.097441Z","end":"2026-04-08T06:19:45.268982Z","steps":["trace[164298258] 'agreement among raft nodes before linearized reading' (duration: 171.314964ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:19:45.269105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.199916ms","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-04-08T06:19:45.269146Z","caller":"traceutil/trace.go:171","msg":"trace[1442477820] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:4924; }","duration":"134.326228ms","start":"2026-04-08T06:19:45.134809Z","end":"2026-04-08T06:19:45.269135Z","steps":["trace[1442477820] 'agreement among raft nodes before linearized reading' (duration: 134.151765ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:19:45.580769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.54675ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T06:19:45.580901Z","caller":"traceutil/trace.go:171","msg":"trace[1082177971] transaction","detail":"{read_only:false; response_revision:4925; number_of_response:1; }","duration":"212.900278ms","start":"2026-04-08T06:19:45.367977Z","end":"2026-04-08T06:19:45.580877Z","steps":["trace[1082177971] 'process raft request' (duration: 12.111724ms)","trace[1082177971] 'compare' (duration: 200.313205ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T06:19:57.169851Z","caller":"traceutil/trace.go:171","msg":"trace[1712014565] linearizableReadLoop","detail":"{readStateIndex:5218; appliedIndex:5217; }","duration":"143.904755ms","start":"2026-04-08T06:19:57.02592Z","end":"2026-04-08T06:19:57.169825Z","steps":["trace[1712014565] 'read index received' (duration: 111.600015ms)","trace[1712014565] 'applied index is now lower than readState.Index' (duration: 32.30382ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T06:19:57.170189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.208532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-08T06:19:57.170259Z","caller":"traceutil/trace.go:171","msg":"trace[1573560321] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5031; }","duration":"144.341674ms","start":"2026-04-08T06:19:57.02588Z","end":"2026-04-08T06:19:57.170222Z","steps":["trace[1573560321] 'agreement among raft nodes before linearized reading' (duration: 144.060328ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:19:57.170285Z","caller":"traceutil/trace.go:171","msg":"trace[1519236805] transaction","detail":"{read_only:false; response_revision:5031; number_of_response:1; }","duration":"153.970805ms","start":"2026-04-08T06:19:57.016257Z","end":"2026-04-08T06:19:57.170228Z","steps":["trace[1519236805] 'process raft request' (duration: 121.270188ms)","trace[1519236805] 'compare' (duration: 32.176667ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T06:19:57.174361Z","caller":"traceutil/trace.go:171","msg":"trace[1607115622] transaction","detail":"{read_only:false; response_revision:5032; number_of_response:1; }","duration":"125.13018ms","start":"2026-04-08T06:19:57.049212Z","end":"2026-04-08T06:19:57.174342Z","steps":["trace[1607115622] 'process raft request' (duration: 125.022058ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:20:10.855321Z","caller":"traceutil/trace.go:171","msg":"trace[10337036] linearizableReadLoop","detail":"{readStateIndex:5389; appliedIndex:5388; }","duration":"152.243811ms","start":"2026-04-08T06:20:10.703043Z","end":"2026-04-08T06:20:10.855287Z","steps":["trace[10337036] 'read index received' (duration: 145.373884ms)","trace[10337036] 'applied index is now lower than readState.Index' (duration: 6.866077ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T06:20:10.855532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.411703ms","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-04-08T06:20:10.855614Z","caller":"traceutil/trace.go:171","msg":"trace[1898217432] transaction","detail":"{read_only:false; response_revision:5199; number_of_response:1; }","duration":"209.722138ms","start":"2026-04-08T06:20:10.64566Z","end":"2026-04-08T06:20:10.855383Z","steps":["trace[1898217432] 'process raft request' (duration: 202.837891ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:20:10.855718Z","caller":"traceutil/trace.go:171","msg":"trace[254531283] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5199; }","duration":"152.713019ms","start":"2026-04-08T06:20:10.702987Z","end":"2026-04-08T06:20:10.8557Z","steps":["trace[254531283] 'agreement among raft nodes before linearized reading' (duration: 152.422564ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:20:13.591413Z","caller":"traceutil/trace.go:171","msg":"trace[375639953] transaction","detail":"{read_only:false; response_revision:5220; number_of_response:1; }","duration":"114.446104ms","start":"2026-04-08T06:20:13.476934Z","end":"2026-04-08T06:20:13.59138Z","steps":["trace[375639953] 'process raft request' (duration: 113.339873ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:20:13.594336Z","caller":"traceutil/trace.go:171","msg":"trace[1405488007] transaction","detail":"{read_only:false; response_revision:5221; number_of_response:1; }","duration":"116.96885ms","start":"2026-04-08T06:20:13.477331Z","end":"2026-04-08T06:20:13.5943Z","steps":["trace[1405488007] 'process raft request' (duration: 116.788247ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:20:21.778908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.832232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-08T06:20:21.779005Z","caller":"traceutil/trace.go:171","msg":"trace[1114978491] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5265; }","duration":"145.031085ms","start":"2026-04-08T06:20:21.633949Z","end":"2026-04-08T06:20:21.778981Z","steps":["trace[1114978491] 'range keys from in-memory index tree' (duration: 144.629938ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:20:21.779131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.232138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-04-08T06:20:21.779253Z","caller":"traceutil/trace.go:171","msg":"trace[2073207689] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5265; }","duration":"151.39514ms","start":"2026-04-08T06:20:21.627827Z","end":"2026-04-08T06:20:21.779222Z","steps":["trace[2073207689] 'range keys from in-memory index tree' (duration: 151.052605ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:20:21.779575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.337112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-08T06:20:21.779634Z","caller":"traceutil/trace.go:171","msg":"trace[1358550033] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5265; }","duration":"150.406433ms","start":"2026-04-08T06:20:21.629212Z","end":"2026-04-08T06:20:21.779619Z","steps":["trace[1358550033] 'range keys from in-memory index tree' (duration: 150.23065ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:20:21.780106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.895039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2193165"} {"level":"info","ts":"2026-04-08T06:20:21.780183Z","caller":"traceutil/trace.go:171","msg":"trace[978970711] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:5265; }","duration":"142.033222ms","start":"2026-04-08T06:20:21.638134Z","end":"2026-04-08T06:20:21.780167Z","steps":["trace[978970711] 'range keys from in-memory index tree' (duration: 140.658157ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:20:35.649743Z","caller":"traceutil/trace.go:171","msg":"trace[1203809241] linearizableReadLoop","detail":"{readStateIndex:5559; appliedIndex:5558; }","duration":"127.924014ms","start":"2026-04-08T06:20:35.521782Z","end":"2026-04-08T06:20:35.649706Z","steps":["trace[1203809241] 'read index received' (duration: 127.557488ms)","trace[1203809241] 'applied index is now lower than readState.Index' (duration: 364.806µs)"],"step_count":2} {"level":"warn","ts":"2026-04-08T06:20:35.650128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.313231ms","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-04-08T06:20:35.650197Z","caller":"traceutil/trace.go:171","msg":"trace[356443000] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5363; }","duration":"128.410252ms","start":"2026-04-08T06:20:35.521755Z","end":"2026-04-08T06:20:35.650165Z","steps":["trace[356443000] 'agreement among raft nodes before linearized reading' (duration: 128.143388ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:20:35.65057Z","caller":"traceutil/trace.go:171","msg":"trace[577115895] transaction","detail":"{read_only:false; response_revision:5363; number_of_response:1; }","duration":"134.068182ms","start":"2026-04-08T06:20:35.515737Z","end":"2026-04-08T06:20:35.649805Z","steps":["trace[577115895] 'process raft request' (duration: 133.708926ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:20:43.412402Z","caller":"traceutil/trace.go:171","msg":"trace[532837888] transaction","detail":"{read_only:false; response_revision:5430; number_of_response:1; }","duration":"108.258445ms","start":"2026-04-08T06:20:43.303816Z","end":"2026-04-08T06:20:43.412075Z","steps":["trace[532837888] 'process raft request' (duration: 72.97576ms)","trace[532837888] 'compare' (duration: 35.060171ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T06:20:43.826886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.804742ms","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-04-08T06:20:43.826983Z","caller":"traceutil/trace.go:171","msg":"trace[1972522101] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5431; }","duration":"120.950564ms","start":"2026-04-08T06:20:43.706009Z","end":"2026-04-08T06:20:43.826959Z","steps":["trace[1972522101] 'range keys from in-memory index tree' (duration: 120.631279ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:20:43.827096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.374749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-08T06:20:43.827208Z","caller":"traceutil/trace.go:171","msg":"trace[2053115834] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5431; }","duration":"130.594023ms","start":"2026-04-08T06:20:43.696592Z","end":"2026-04-08T06:20:43.827186Z","steps":["trace[2053115834] 'range keys from in-memory index tree' (duration: 130.199016ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:20:43.827518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.881112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T06:20:43.827632Z","caller":"traceutil/trace.go:171","msg":"trace[2108704819] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1; range_end:; response_count:0; response_revision:5431; }","duration":"164.071206ms","start":"2026-04-08T06:20:43.663531Z","end":"2026-04-08T06:20:43.827603Z","steps":["trace[2108704819] 'range keys from in-memory index tree' (duration: 163.787551ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:20:43.827496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.396406ms","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-04-08T06:20:43.828257Z","caller":"traceutil/trace.go:171","msg":"trace[952597049] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5431; }","duration":"140.23283ms","start":"2026-04-08T06:20:43.687987Z","end":"2026-04-08T06:20:43.82822Z","steps":["trace[952597049] 'range keys from in-memory index tree' (duration: 138.676864ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:20:45.437378Z","caller":"traceutil/trace.go:171","msg":"trace[2067527153] transaction","detail":"{read_only:false; response_revision:5443; number_of_response:1; }","duration":"102.018561ms","start":"2026-04-08T06:20:45.33531Z","end":"2026-04-08T06:20:45.437328Z","steps":["trace[2067527153] 'process raft request' (duration: 35.040599ms)","trace[2067527153] 'compare' (duration: 66.710197ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T06:20:57.869714Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2876} {"level":"info","ts":"2026-04-08T06:20:57.955309Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2876,"took":"83.601386ms","hash":2002568173,"current-db-size-bytes":52994048,"current-db-size":"53 MB","current-db-size-in-use-bytes":28975104,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-04-08T06:20:57.955379Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2002568173,"revision":2876,"compact-revision":-1} {"level":"info","ts":"2026-04-08T06:25:57.875598Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5532} {"level":"info","ts":"2026-04-08T06:25:57.955654Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5532,"took":"78.873238ms","hash":458103522,"current-db-size-bytes":52994048,"current-db-size":"53 MB","current-db-size-in-use-bytes":18255872,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-04-08T06:25:57.955713Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":458103522,"revision":5532,"compact-revision":2876}