{"level":"warn","ts":"2026-08-05T11:15:26.506026Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-05T11:15:26.506155Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.225:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.225:2380","--initial-cluster=instance=https://199.19.213.225:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.225:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.225: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-08-05T11:15:26.50651Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-05T11:15:26.506538Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.225:2380"]} {"level":"info","ts":"2026-08-05T11:15:26.5066Z","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-08-05T11:15:26.507742Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.225:2379"]} {"level":"info","ts":"2026-08-05T11:15:26.507958Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.225:2380"],"listen-peer-urls":["https://199.19.213.225:2380"],"advertise-client-urls":["https://199.19.213.225:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.225:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.225: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-08-05T11:15:26.512476Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.25511ms"} {"level":"info","ts":"2026-08-05T11:15:26.522897Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b7b89c171c5fe95c","cluster-id":"5746414c29a21eae"} {"level":"info","ts":"2026-08-05T11:15:26.522989Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b7b89c171c5fe95c switched to configuration voters=()"} {"level":"info","ts":"2026-08-05T11:15:26.523011Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b7b89c171c5fe95c became follower at term 0"} {"level":"info","ts":"2026-08-05T11:15:26.523065Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b7b89c171c5fe95c [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-05T11:15:26.523079Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b7b89c171c5fe95c became follower at term 1"} {"level":"info","ts":"2026-08-05T11:15:26.523108Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b7b89c171c5fe95c switched to configuration voters=(13238502727729801564)"} {"level":"warn","ts":"2026-08-05T11:15:26.531439Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-05T11:15:26.532718Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-05T11:15:26.533878Z","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-08-05T11:15:26.536674Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b7b89c171c5fe95c","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-05T11:15:26.537125Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b7b89c171c5fe95c","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-05T11:15:26.539398Z","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-08-05T11:15:26.539441Z","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-08-05T11:15:26.539452Z","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-08-05T11:15:26.541866Z","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-08-05T11:15:26.542071Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.225:2380"} {"level":"info","ts":"2026-08-05T11:15:26.542094Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.225:2380"} {"level":"info","ts":"2026-08-05T11:15:26.542558Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b7b89c171c5fe95c","initial-advertise-peer-urls":["https://199.19.213.225:2380"],"listen-peer-urls":["https://199.19.213.225:2380"],"advertise-client-urls":["https://199.19.213.225:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.225:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-05T11:15:26.542592Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-05T11:15:26.543221Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b7b89c171c5fe95c switched to configuration voters=(13238502727729801564)"} {"level":"info","ts":"2026-08-05T11:15:26.543367Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"5746414c29a21eae","local-member-id":"b7b89c171c5fe95c","added-peer-id":"b7b89c171c5fe95c","added-peer-peer-urls":["https://199.19.213.225:2380"]} {"level":"info","ts":"2026-08-05T11:15:26.624288Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b7b89c171c5fe95c is starting a new election at term 1"} {"level":"info","ts":"2026-08-05T11:15:26.624338Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b7b89c171c5fe95c became pre-candidate at term 1"} {"level":"info","ts":"2026-08-05T11:15:26.624363Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b7b89c171c5fe95c received MsgPreVoteResp from b7b89c171c5fe95c at term 1"} {"level":"info","ts":"2026-08-05T11:15:26.624376Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b7b89c171c5fe95c became candidate at term 2"} {"level":"info","ts":"2026-08-05T11:15:26.624383Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b7b89c171c5fe95c received MsgVoteResp from b7b89c171c5fe95c at term 2"} {"level":"info","ts":"2026-08-05T11:15:26.624393Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b7b89c171c5fe95c became leader at term 2"} {"level":"info","ts":"2026-08-05T11:15:26.624403Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b7b89c171c5fe95c elected leader b7b89c171c5fe95c at term 2"} {"level":"info","ts":"2026-08-05T11:15:26.626713Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-05T11:15:26.627451Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b7b89c171c5fe95c","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.225:2379]}","request-path":"/0/members/b7b89c171c5fe95c/attributes","cluster-id":"5746414c29a21eae","publish-timeout":"7s"} {"level":"info","ts":"2026-08-05T11:15:26.627451Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-05T11:15:26.627965Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"5746414c29a21eae","local-member-id":"b7b89c171c5fe95c","cluster-version":"3.5"} {"level":"info","ts":"2026-08-05T11:15:26.628057Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-05T11:15:26.628085Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-05T11:15:26.628099Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-05T11:15:26.630348Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.225:2379"} {"level":"info","ts":"2026-08-05T11:15:26.630421Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-05T11:15:26.630489Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-05T11:15:26.630769Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-08-05T11:15:48.967665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.01997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T11:15:48.96779Z","caller":"traceutil/trace.go:171","msg":"trace[603814885] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:383; }","duration":"234.192666ms","start":"2026-08-05T11:15:48.733578Z","end":"2026-08-05T11:15:48.967771Z","steps":["trace[603814885] 'range keys from in-memory index tree' (duration: 233.941547ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:16:11.665821Z","caller":"traceutil/trace.go:171","msg":"trace[1170119977] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"384.243385ms","start":"2026-08-05T11:16:11.28155Z","end":"2026-08-05T11:16:11.665794Z","steps":["trace[1170119977] 'process raft request' (duration: 384.062429ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:16:11.66662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:16:11.281531Z","time spent":"384.389381ms","remote":"127.0.0.1:56722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31111,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T11:16:11.669798Z","caller":"traceutil/trace.go:171","msg":"trace[456330856] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"386.083193ms","start":"2026-08-05T11:16:11.283689Z","end":"2026-08-05T11:16:11.669772Z","steps":["trace[456330856] 'process raft request' (duration: 385.839554ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:16:11.670004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:16:11.283667Z","time spent":"386.198946ms","remote":"127.0.0.1:56722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31121,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T11:16:11.669966Z","caller":"traceutil/trace.go:171","msg":"trace[2059372997] linearizableReadLoop","detail":"{readStateIndex:556; appliedIndex:553; }","duration":"262.403515ms","start":"2026-08-05T11:16:11.407532Z","end":"2026-08-05T11:16:11.669935Z","steps":["trace[2059372997] 'read index received' (duration: 258.102567ms)","trace[2059372997] 'applied index is now lower than readState.Index' (duration: 4.299078ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:16:11.670141Z","caller":"traceutil/trace.go:171","msg":"trace[1799354786] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"363.255707ms","start":"2026-08-05T11:16:11.306875Z","end":"2026-08-05T11:16:11.67013Z","steps":["trace[1799354786] 'process raft request' (duration: 362.836462ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:16:11.670191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:16:11.30685Z","time spent":"363.321289ms","remote":"127.0.0.1:56722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-05T11:16:11.670274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.691645ms","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-08-05T11:16:11.670379Z","caller":"traceutil/trace.go:171","msg":"trace[1840672754] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:535; }","duration":"262.81861ms","start":"2026-08-05T11:16:11.407492Z","end":"2026-08-05T11:16:11.670311Z","steps":["trace[1840672754] 'agreement among raft nodes before linearized reading' (duration: 262.582391ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:16:18.011576Z","caller":"traceutil/trace.go:171","msg":"trace[1600852609] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"284.949926ms","start":"2026-08-05T11:16:17.726594Z","end":"2026-08-05T11:16:18.011544Z","steps":["trace[1600852609] 'process raft request' (duration: 284.712397ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:16:18.384257Z","caller":"traceutil/trace.go:171","msg":"trace[494481434] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"646.882915ms","start":"2026-08-05T11:16:17.73732Z","end":"2026-08-05T11:16:18.384203Z","steps":["trace[494481434] 'process raft request' (duration: 646.539952ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:16:18.384505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:16:17.737296Z","time spent":"647.080632ms","remote":"127.0.0.1:56870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T11:16:18.384967Z","caller":"traceutil/trace.go:171","msg":"trace[353485962] linearizableReadLoop","detail":"{readStateIndex:631; appliedIndex:628; }","duration":"532.171801ms","start":"2026-08-05T11:16:17.852765Z","end":"2026-08-05T11:16:18.384937Z","steps":["trace[353485962] 'read index received' (duration: 158.55014ms)","trace[353485962] 'applied index is now lower than readState.Index' (duration: 373.620641ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T11:16:18.385217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"532.424411ms","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-08-05T11:16:18.38526Z","caller":"traceutil/trace.go:171","msg":"trace[234782690] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:609; }","duration":"532.555255ms","start":"2026-08-05T11:16:17.852691Z","end":"2026-08-05T11:16:18.385246Z","steps":["trace[234782690] 'agreement among raft nodes before linearized reading' (duration: 532.353888ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:16:18.385268Z","caller":"traceutil/trace.go:171","msg":"trace[2139135477] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"626.283789ms","start":"2026-08-05T11:16:17.758951Z","end":"2026-08-05T11:16:18.385235Z","steps":["trace[2139135477] 'process raft request' (duration: 625.319464ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:16:18.385297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:16:17.852662Z","time spent":"532.625698ms","remote":"127.0.0.1:56960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-05T11:16:18.385416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:16:17.758908Z","time spent":"626.437684ms","remote":"127.0.0.1:56770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T11:16:19.230031Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16815490831420669996,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-05T11:16:19.518511Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.066224365s","expected-duration":"1s"} {"level":"info","ts":"2026-08-05T11:16:19.564144Z","caller":"traceutil/trace.go:171","msg":"trace[1465869511] linearizableReadLoop","detail":"{readStateIndex:633; appliedIndex:632; }","duration":"834.363935ms","start":"2026-08-05T11:16:18.729751Z","end":"2026-08-05T11:16:19.564115Z","steps":["trace[1465869511] 'read index received' (duration: 789.178995ms)","trace[1465869511] 'applied index is now lower than readState.Index' (duration: 45.18393ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:16:19.564261Z","caller":"traceutil/trace.go:171","msg":"trace[1161805580] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"1.11208884s","start":"2026-08-05T11:16:18.452097Z","end":"2026-08-05T11:16:19.564186Z","steps":["trace[1161805580] 'process raft request' (duration: 1.066943942s)","trace[1161805580] 'compare' (duration: 44.928371ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T11:16:19.564415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"834.660616ms","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-08-05T11:16:19.564411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"833.917178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T11:16:19.564462Z","caller":"traceutil/trace.go:171","msg":"trace[597854498] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:611; }","duration":"834.77635ms","start":"2026-08-05T11:16:18.729672Z","end":"2026-08-05T11:16:19.564449Z","steps":["trace[597854498] 'agreement among raft nodes before linearized reading' (duration: 834.593073ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:16:19.564457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:16:18.452068Z","time spent":"1.112300658s","remote":"127.0.0.1:56928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-05T11:16:19.564508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:16:18.729638Z","time spent":"834.861573ms","remote":"127.0.0.1:56960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-05T11:16:19.564517Z","caller":"traceutil/trace.go:171","msg":"trace[1217798203] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:611; }","duration":"834.048883ms","start":"2026-08-05T11:16:18.730442Z","end":"2026-08-05T11:16:19.564491Z","steps":["trace[1217798203] 'agreement among raft nodes before linearized reading' (duration: 833.886918ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:16:19.564576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:16:18.730433Z","time spent":"834.129936ms","remote":"127.0.0.1:56678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-05T11:16:19.565664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"805.128888ms","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-08-05T11:16:19.565752Z","caller":"traceutil/trace.go:171","msg":"trace[1518187555] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:611; }","duration":"805.263163ms","start":"2026-08-05T11:16:18.76047Z","end":"2026-08-05T11:16:19.565733Z","steps":["trace[1518187555] 'agreement among raft nodes before linearized reading' (duration: 803.862533ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:16:19.565817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:16:18.760444Z","time spent":"805.363727ms","remote":"127.0.0.1:56960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-05T11:16:21.127149Z","caller":"traceutil/trace.go:171","msg":"trace[34260701] linearizableReadLoop","detail":"{readStateIndex:660; appliedIndex:659; }","duration":"231.562909ms","start":"2026-08-05T11:16:20.895543Z","end":"2026-08-05T11:16:21.127106Z","steps":["trace[34260701] 'read index received' (duration: 229.316599ms)","trace[34260701] 'applied index is now lower than readState.Index' (duration: 2.24439ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:16:21.127516Z","caller":"traceutil/trace.go:171","msg":"trace[1875221490] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"232.921318ms","start":"2026-08-05T11:16:20.894545Z","end":"2026-08-05T11:16:21.127466Z","steps":["trace[1875221490] 'process raft request' (duration: 230.302914ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:16:21.127526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.925652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T11:16:21.127667Z","caller":"traceutil/trace.go:171","msg":"trace[1135750533] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:0; response_revision:637; }","duration":"232.13461ms","start":"2026-08-05T11:16:20.895486Z","end":"2026-08-05T11:16:21.127621Z","steps":["trace[1135750533] 'agreement among raft nodes before linearized reading' (duration: 231.840179ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:16:21.135386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.352925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T11:16:21.135383Z","caller":"traceutil/trace.go:171","msg":"trace[231131990] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"234.520966ms","start":"2026-08-05T11:16:20.900832Z","end":"2026-08-05T11:16:21.135353Z","steps":["trace[231131990] 'process raft request' (duration: 234.35951ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:16:21.135461Z","caller":"traceutil/trace.go:171","msg":"trace[1721294551] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:641; }","duration":"235.48138ms","start":"2026-08-05T11:16:20.89996Z","end":"2026-08-05T11:16:21.135442Z","steps":["trace[1721294551] 'agreement among raft nodes before linearized reading' (duration: 235.312764ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:16:21.135737Z","caller":"traceutil/trace.go:171","msg":"trace[1400681151] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"235.684937ms","start":"2026-08-05T11:16:20.900032Z","end":"2026-08-05T11:16:21.135717Z","steps":["trace[1400681151] 'process raft request' (duration: 234.967901ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:16:21.135332Z","caller":"traceutil/trace.go:171","msg":"trace[559277181] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"234.755484ms","start":"2026-08-05T11:16:20.900549Z","end":"2026-08-05T11:16:21.135305Z","steps":["trace[559277181] 'process raft request' (duration: 234.604889ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:16:34.900089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.987103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T11:16:34.900219Z","caller":"traceutil/trace.go:171","msg":"trace[28721870] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:776; }","duration":"167.131758ms","start":"2026-08-05T11:16:34.733061Z","end":"2026-08-05T11:16:34.900193Z","steps":["trace[28721870] 'range keys from in-memory index tree' (duration: 166.886869ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:16:34.90162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"662.924776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-05T11:16:34.901721Z","caller":"traceutil/trace.go:171","msg":"trace[1103025361] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:776; }","duration":"663.083122ms","start":"2026-08-05T11:16:34.238613Z","end":"2026-08-05T11:16:34.901696Z","steps":["trace[1103025361] 'count revisions from in-memory index tree' (duration: 661.596449ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:16:34.901783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:16:34.238589Z","time spent":"663.180054ms","remote":"127.0.0.1:57084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":52,"response size":30,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"warn","ts":"2026-08-05T11:16:34.902228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.903359ms","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-08-05T11:16:34.902332Z","caller":"traceutil/trace.go:171","msg":"trace[614943573] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:776; }","duration":"427.021533ms","start":"2026-08-05T11:16:34.475242Z","end":"2026-08-05T11:16:34.902264Z","steps":["trace[614943573] 'range keys from in-memory index tree' (duration: 424.571728ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:16:34.902427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:16:34.47522Z","time spent":"427.195059ms","remote":"127.0.0.1:56960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-05T11:17:05.700714Z","caller":"traceutil/trace.go:171","msg":"trace[1306797109] linearizableReadLoop","detail":"{readStateIndex:1217; appliedIndex:1216; }","duration":"345.925198ms","start":"2026-08-05T11:17:05.354768Z","end":"2026-08-05T11:17:05.700693Z","steps":["trace[1306797109] 'read index received' (duration: 341.117458ms)","trace[1306797109] 'applied index is now lower than readState.Index' (duration: 4.80649ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T11:17:05.700876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.100043ms","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-08-05T11:17:05.700902Z","caller":"traceutil/trace.go:171","msg":"trace[1692672368] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1183; }","duration":"346.181317ms","start":"2026-08-05T11:17:05.354713Z","end":"2026-08-05T11:17:05.700894Z","steps":["trace[1692672368] 'agreement among raft nodes before linearized reading' (duration: 346.055473ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:17:05.700925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:17:05.354691Z","time spent":"346.228359ms","remote":"127.0.0.1:56960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-05T11:17:29.344431Z","caller":"traceutil/trace.go:171","msg":"trace[1709801385] transaction","detail":"{read_only:false; response_revision:1449; number_of_response:1; }","duration":"154.115477ms","start":"2026-08-05T11:17:29.190294Z","end":"2026-08-05T11:17:29.344409Z","steps":["trace[1709801385] 'process raft request' (duration: 153.996663ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:17:31.477827Z","caller":"traceutil/trace.go:171","msg":"trace[171594481] transaction","detail":"{read_only:false; response_revision:1472; number_of_response:1; }","duration":"104.583849ms","start":"2026-08-05T11:17:31.373217Z","end":"2026-08-05T11:17:31.477801Z","steps":["trace[171594481] 'process raft request' (duration: 103.091102ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:17:33.052267Z","caller":"traceutil/trace.go:171","msg":"trace[963570583] transaction","detail":"{read_only:false; response_revision:1487; number_of_response:1; }","duration":"121.043548ms","start":"2026-08-05T11:17:32.931161Z","end":"2026-08-05T11:17:33.052204Z","steps":["trace[963570583] 'process raft request' (duration: 102.710914ms)","trace[963570583] 'compare' (duration: 18.087586ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:17:33.052841Z","caller":"traceutil/trace.go:171","msg":"trace[967365939] transaction","detail":"{read_only:false; response_revision:1488; number_of_response:1; }","duration":"119.5546ms","start":"2026-08-05T11:17:32.933258Z","end":"2026-08-05T11:17:33.052813Z","steps":["trace[967365939] 'process raft request' (duration: 119.168898ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:17:53.538007Z","caller":"traceutil/trace.go:171","msg":"trace[1587962621] transaction","detail":"{read_only:false; response_revision:1844; number_of_response:1; }","duration":"329.638681ms","start":"2026-08-05T11:17:53.208321Z","end":"2026-08-05T11:17:53.53796Z","steps":["trace[1587962621] 'process raft request' (duration: 329.310211ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:17:53.53818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:17:53.208302Z","time spent":"329.782516ms","remote":"127.0.0.1:56870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3729,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T11:17:53.689506Z","caller":"traceutil/trace.go:171","msg":"trace[472686166] linearizableReadLoop","detail":"{readStateIndex:1914; appliedIndex:1913; }","duration":"139.833169ms","start":"2026-08-05T11:17:53.549648Z","end":"2026-08-05T11:17:53.689482Z","steps":["trace[472686166] 'read index received' (duration: 91.437155ms)","trace[472686166] 'applied index is now lower than readState.Index' (duration: 48.394854ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:17:53.689576Z","caller":"traceutil/trace.go:171","msg":"trace[954696608] transaction","detail":"{read_only:false; response_revision:1845; number_of_response:1; }","duration":"145.938817ms","start":"2026-08-05T11:17:53.543612Z","end":"2026-08-05T11:17:53.689551Z","steps":["trace[954696608] 'process raft request' (duration: 97.680277ms)","trace[954696608] 'compare' (duration: 48.079835ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T11:17:53.68973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.062906ms","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-08-05T11:17:53.689776Z","caller":"traceutil/trace.go:171","msg":"trace[206547161] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1845; }","duration":"140.136699ms","start":"2026-08-05T11:17:53.549625Z","end":"2026-08-05T11:17:53.689761Z","steps":["trace[206547161] 'agreement among raft nodes before linearized reading' (duration: 139.971804ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:17:54.297341Z","caller":"traceutil/trace.go:171","msg":"trace[1116896891] transaction","detail":"{read_only:false; response_revision:1849; number_of_response:1; }","duration":"135.901141ms","start":"2026-08-05T11:17:54.161404Z","end":"2026-08-05T11:17:54.297305Z","steps":["trace[1116896891] 'process raft request' (duration: 135.739456ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:18:07.042326Z","caller":"traceutil/trace.go:171","msg":"trace[2069943066] transaction","detail":"{read_only:false; response_revision:1961; number_of_response:1; }","duration":"289.276416ms","start":"2026-08-05T11:18:06.753028Z","end":"2026-08-05T11:18:07.042304Z","steps":["trace[2069943066] 'process raft request' (duration: 289.147272ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:18:07.042392Z","caller":"traceutil/trace.go:171","msg":"trace[371935959] linearizableReadLoop","detail":"{readStateIndex:2033; appliedIndex:2033; }","duration":"269.183867ms","start":"2026-08-05T11:18:06.77318Z","end":"2026-08-05T11:18:07.042364Z","steps":["trace[371935959] 'read index received' (duration: 269.174076ms)","trace[371935959] 'applied index is now lower than readState.Index' (duration: 8.211µs)"],"step_count":2} {"level":"warn","ts":"2026-08-05T11:18:07.042792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.542617ms","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-08-05T11:18:07.042938Z","caller":"traceutil/trace.go:171","msg":"trace[803777369] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1961; }","duration":"269.722213ms","start":"2026-08-05T11:18:06.773148Z","end":"2026-08-05T11:18:07.042871Z","steps":["trace[803777369] 'agreement among raft nodes before linearized reading' (duration: 269.28851ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:18:07.045431Z","caller":"traceutil/trace.go:171","msg":"trace[889965569] transaction","detail":"{read_only:false; response_revision:1962; number_of_response:1; }","duration":"222.11106ms","start":"2026-08-05T11:18:06.823301Z","end":"2026-08-05T11:18:07.045412Z","steps":["trace[889965569] 'process raft request' (duration: 222.005886ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:18:07.045475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.758948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-05T11:18:07.045525Z","caller":"traceutil/trace.go:171","msg":"trace[609833523] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:1962; }","duration":"119.883582ms","start":"2026-08-05T11:18:06.925629Z","end":"2026-08-05T11:18:07.045513Z","steps":["trace[609833523] 'agreement among raft nodes before linearized reading' (duration: 119.763688ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:18:28.120636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.959467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-08-05T11:18:28.121005Z","caller":"traceutil/trace.go:171","msg":"trace[2032605798] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2089; }","duration":"191.364229ms","start":"2026-08-05T11:18:27.92961Z","end":"2026-08-05T11:18:28.120974Z","steps":["trace[2032605798] 'range keys from in-memory index tree' (duration: 190.75024ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:18:28.120833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.131714ms","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-08-05T11:18:28.12132Z","caller":"traceutil/trace.go:171","msg":"trace[958869101] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2089; }","duration":"185.616119ms","start":"2026-08-05T11:18:27.935648Z","end":"2026-08-05T11:18:28.121264Z","steps":["trace[958869101] 'range keys from in-memory index tree' (duration: 184.937029ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:18:28.305298Z","caller":"traceutil/trace.go:171","msg":"trace[558998276] transaction","detail":"{read_only:false; response_revision:2092; number_of_response:1; }","duration":"106.414758ms","start":"2026-08-05T11:18:28.198856Z","end":"2026-08-05T11:18:28.305271Z","steps":["trace[558998276] 'process raft request' (duration: 49.133364ms)","trace[558998276] 'compare' (duration: 57.1587ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:18:28.755401Z","caller":"traceutil/trace.go:171","msg":"trace[98259093] transaction","detail":"{read_only:false; response_revision:2094; number_of_response:1; }","duration":"130.699652ms","start":"2026-08-05T11:18:28.624672Z","end":"2026-08-05T11:18:28.755371Z","steps":["trace[98259093] 'process raft request' (duration: 32.197026ms)","trace[98259093] 'compare' (duration: 98.402373ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:18:28.761291Z","caller":"traceutil/trace.go:171","msg":"trace[1047002337] transaction","detail":"{read_only:false; response_revision:2095; number_of_response:1; }","duration":"136.027349ms","start":"2026-08-05T11:18:28.62525Z","end":"2026-08-05T11:18:28.761277Z","steps":["trace[1047002337] 'process raft request' (duration: 135.892245ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:18:29.50213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.053147ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-05T11:18:29.502218Z","caller":"traceutil/trace.go:171","msg":"trace[1454915775] linearizableReadLoop","detail":"{readStateIndex:2172; appliedIndex:2171; }","duration":"320.374325ms","start":"2026-08-05T11:18:29.181829Z","end":"2026-08-05T11:18:29.502203Z","steps":["trace[1454915775] 'read index received' (duration: 65.038439ms)","trace[1454915775] 'applied index is now lower than readState.Index' (duration: 255.334665ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:18:29.5023Z","caller":"traceutil/trace.go:171","msg":"trace[1259586413] transaction","detail":"{read_only:false; response_revision:2096; number_of_response:1; }","duration":"361.512662ms","start":"2026-08-05T11:18:29.140759Z","end":"2026-08-05T11:18:29.502272Z","steps":["trace[1259586413] 'process raft request' (duration: 106.096844ms)","trace[1259586413] 'compare' (duration: 254.923213ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T11:18:29.502371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.547869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-05T11:18:29.502397Z","caller":"traceutil/trace.go:171","msg":"trace[2076741801] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2096; }","duration":"320.57429ms","start":"2026-08-05T11:18:29.181814Z","end":"2026-08-05T11:18:29.502388Z","steps":["trace[2076741801] 'agreement among raft nodes before linearized reading' (duration: 320.449106ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:18:29.502414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:18:29.140732Z","time spent":"361.623155ms","remote":"127.0.0.1:56960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T11:18:29.502425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:18:29.181798Z","time spent":"320.619652ms","remote":"127.0.0.1:56960","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-08-05T11:18:29.517438Z","caller":"traceutil/trace.go:171","msg":"trace[1488776106] transaction","detail":"{read_only:false; response_revision:2097; number_of_response:1; }","duration":"316.717367ms","start":"2026-08-05T11:18:29.200691Z","end":"2026-08-05T11:18:29.517408Z","steps":["trace[1488776106] 'process raft request' (duration: 316.607684ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:18:29.517538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.586844ms","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-08-05T11:18:29.5176Z","caller":"traceutil/trace.go:171","msg":"trace[1037372948] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2097; }","duration":"315.659106ms","start":"2026-08-05T11:18:29.201929Z","end":"2026-08-05T11:18:29.517588Z","steps":["trace[1037372948] 'agreement among raft nodes before linearized reading' (duration: 315.473801ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:18:29.517591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:18:29.200663Z","time spent":"316.862761ms","remote":"127.0.0.1:56762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":819,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-05T11:18:29.517626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:18:29.201911Z","time spent":"315.707307ms","remote":"127.0.0.1:34602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5467,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-08-05T11:18:29.728944Z","caller":"traceutil/trace.go:171","msg":"trace[1770943636] transaction","detail":"{read_only:false; response_revision:2098; number_of_response:1; }","duration":"222.536693ms","start":"2026-08-05T11:18:29.506384Z","end":"2026-08-05T11:18:29.728921Z","steps":["trace[1770943636] 'process raft request' (duration: 218.430422ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:18:29.968914Z","caller":"traceutil/trace.go:171","msg":"trace[1108641124] transaction","detail":"{read_only:false; response_revision:2099; number_of_response:1; }","duration":"131.286284ms","start":"2026-08-05T11:18:29.837603Z","end":"2026-08-05T11:18:29.96889Z","steps":["trace[1108641124] 'process raft request' (duration: 124.659869ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:18:30.477144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.855054ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-08-05T11:18:30.47725Z","caller":"traceutil/trace.go:171","msg":"trace[1208701973] linearizableReadLoop","detail":"{readStateIndex:2176; appliedIndex:2175; }","duration":"342.51625ms","start":"2026-08-05T11:18:30.134717Z","end":"2026-08-05T11:18:30.477234Z","steps":["trace[1208701973] 'read index received' (duration: 193.461141ms)","trace[1208701973] 'applied index is now lower than readState.Index' (duration: 149.053489ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T11:18:30.477531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:18:30.016525Z","time spent":"460.998654ms","remote":"127.0.0.1:56706","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-08-05T11:18:30.47758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.85529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"warn","ts":"2026-08-05T11:18:30.477786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.826193ms","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-08-05T11:18:30.477838Z","caller":"traceutil/trace.go:171","msg":"trace[1833919538] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2099; }","duration":"278.908106ms","start":"2026-08-05T11:18:30.198917Z","end":"2026-08-05T11:18:30.477825Z","steps":["trace[1833919538] 'agreement among raft nodes before linearized reading' (duration: 278.820063ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:18:30.477779Z","caller":"traceutil/trace.go:171","msg":"trace[246404093] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2099; }","duration":"343.054516ms","start":"2026-08-05T11:18:30.134669Z","end":"2026-08-05T11:18:30.477723Z","steps":["trace[246404093] 'agreement among raft nodes before linearized reading' (duration: 342.769887ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:18:30.477962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.160068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-05T11:18:30.47802Z","caller":"traceutil/trace.go:171","msg":"trace[1053611143] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2099; }","duration":"169.248571ms","start":"2026-08-05T11:18:30.308757Z","end":"2026-08-05T11:18:30.478006Z","steps":["trace[1053611143] 'agreement among raft nodes before linearized reading' (duration: 169.075426ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:18:30.478357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:18:30.134651Z","time spent":"343.242182ms","remote":"127.0.0.1:56960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-08-05T11:19:34.30014Z","caller":"traceutil/trace.go:171","msg":"trace[294379827] linearizableReadLoop","detail":"{readStateIndex:2638; appliedIndex:2637; }","duration":"262.681873ms","start":"2026-08-05T11:19:34.037415Z","end":"2026-08-05T11:19:34.300097Z","steps":["trace[294379827] 'read index received' (duration: 262.456167ms)","trace[294379827] 'applied index is now lower than readState.Index' (duration: 224.166µs)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:19:34.300312Z","caller":"traceutil/trace.go:171","msg":"trace[1681653245] transaction","detail":"{read_only:false; response_revision:2547; number_of_response:1; }","duration":"468.126202ms","start":"2026-08-05T11:19:33.83217Z","end":"2026-08-05T11:19:34.300297Z","steps":["trace[1681653245] 'process raft request' (duration: 467.70532ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:19:34.300465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:19:33.83214Z","time spent":"468.207114ms","remote":"127.0.0.1:56960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T11:19:34.301322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.844584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-05T11:19:34.301484Z","caller":"traceutil/trace.go:171","msg":"trace[162010198] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2547; }","duration":"264.110052ms","start":"2026-08-05T11:19:34.037347Z","end":"2026-08-05T11:19:34.301457Z","steps":["trace[162010198] 'agreement among raft nodes before linearized reading' (duration: 263.469445ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:19:34.334057Z","caller":"traceutil/trace.go:171","msg":"trace[307477692] transaction","detail":"{read_only:false; response_revision:2548; number_of_response:1; }","duration":"137.687378ms","start":"2026-08-05T11:19:34.196346Z","end":"2026-08-05T11:19:34.334033Z","steps":["trace[307477692] 'process raft request' (duration: 137.410971ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:19:34.337164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.777659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-08-05T11:19:34.337237Z","caller":"traceutil/trace.go:171","msg":"trace[546032839] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2548; }","duration":"131.888921ms","start":"2026-08-05T11:19:34.205331Z","end":"2026-08-05T11:19:34.33722Z","steps":["trace[546032839] 'agreement among raft nodes before linearized reading' (duration: 128.614963ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:20:06.035232Z","caller":"traceutil/trace.go:171","msg":"trace[1506147686] transaction","detail":"{read_only:false; response_revision:2723; number_of_response:1; }","duration":"170.617236ms","start":"2026-08-05T11:20:05.864585Z","end":"2026-08-05T11:20:06.035202Z","steps":["trace[1506147686] 'process raft request' (duration: 170.439301ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:20:12.124128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.419421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-05T11:20:12.124173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.015758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18c8e4c4fb98a6b7\" ","response":"range_response_count:1 size:781"} {"level":"info","ts":"2026-08-05T11:20:12.124266Z","caller":"traceutil/trace.go:171","msg":"trace[525941897] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18c8e4c4fb98a6b7; range_end:; response_count:1; response_revision:2755; }","duration":"181.152591ms","start":"2026-08-05T11:20:11.94309Z","end":"2026-08-05T11:20:12.124243Z","steps":["trace[525941897] 'range keys from in-memory index tree' (duration: 180.868264ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:20:12.124262Z","caller":"traceutil/trace.go:171","msg":"trace[972701124] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:2755; }","duration":"175.591336ms","start":"2026-08-05T11:20:11.94864Z","end":"2026-08-05T11:20:12.124232Z","steps":["trace[972701124] 'count revisions from in-memory index tree' (duration: 175.35334ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:23:26.52528Z","caller":"traceutil/trace.go:171","msg":"trace[1675267981] transaction","detail":"{read_only:false; response_revision:3937; number_of_response:1; }","duration":"117.961142ms","start":"2026-08-05T11:23:26.407287Z","end":"2026-08-05T11:23:26.525248Z","steps":["trace[1675267981] 'process raft request' (duration: 51.975234ms)","trace[1675267981] 'compare' (duration: 65.595819ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:24:12.875285Z","caller":"traceutil/trace.go:171","msg":"trace[735189240] transaction","detail":"{read_only:false; response_revision:4732; number_of_response:1; }","duration":"331.427849ms","start":"2026-08-05T11:24:12.543823Z","end":"2026-08-05T11:24:12.875251Z","steps":["trace[735189240] 'process raft request' (duration: 331.246905ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:24:12.875487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:24:12.543795Z","time spent":"331.586983ms","remote":"127.0.0.1:56960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T11:24:12.875848Z","caller":"traceutil/trace.go:171","msg":"trace[1984591534] linearizableReadLoop","detail":"{readStateIndex:4909; appliedIndex:4909; }","duration":"278.839699ms","start":"2026-08-05T11:24:12.596973Z","end":"2026-08-05T11:24:12.875812Z","steps":["trace[1984591534] 'read index received' (duration: 278.825829ms)","trace[1984591534] 'applied index is now lower than readState.Index' (duration: 11.12µs)"],"step_count":2} {"level":"warn","ts":"2026-08-05T11:24:12.876132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.533448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T11:24:12.876242Z","caller":"traceutil/trace.go:171","msg":"trace[357433934] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4732; }","duration":"142.690861ms","start":"2026-08-05T11:24:12.733522Z","end":"2026-08-05T11:24:12.876213Z","steps":["trace[357433934] 'agreement among raft nodes before linearized reading' (duration: 142.526857ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:24:12.876239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.235488ms","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-08-05T11:24:12.876356Z","caller":"traceutil/trace.go:171","msg":"trace[1805962967] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4732; }","duration":"279.394121ms","start":"2026-08-05T11:24:12.596933Z","end":"2026-08-05T11:24:12.876328Z","steps":["trace[1805962967] 'agreement among raft nodes before linearized reading' (duration: 278.993482ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:24:12.876603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.339183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-kube-state-metrics-5c97764fc9-znwmq\" ","response":"range_response_count:1 size:5148"} {"level":"info","ts":"2026-08-05T11:24:12.876652Z","caller":"traceutil/trace.go:171","msg":"trace[1311799855] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-kube-state-metrics-5c97764fc9-znwmq; range_end:; response_count:1; response_revision:4732; }","duration":"142.494476ms","start":"2026-08-05T11:24:12.734142Z","end":"2026-08-05T11:24:12.876636Z","steps":["trace[1311799855] 'agreement among raft nodes before linearized reading' (duration: 142.337763ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:24:12.87698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.923157ms","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-08-05T11:24:12.877038Z","caller":"traceutil/trace.go:171","msg":"trace[1694875762] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4732; }","duration":"234.009138ms","start":"2026-08-05T11:24:12.643015Z","end":"2026-08-05T11:24:12.877024Z","steps":["trace[1694875762] 'agreement among raft nodes before linearized reading' (duration: 233.797253ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:24:12.87754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.637669ms","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-08-05T11:24:12.877659Z","caller":"traceutil/trace.go:171","msg":"trace[770309058] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4732; }","duration":"238.829954ms","start":"2026-08-05T11:24:12.638798Z","end":"2026-08-05T11:24:12.877628Z","steps":["trace[770309058] 'agreement among raft nodes before linearized reading' (duration: 238.467826ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:24:13.014944Z","caller":"traceutil/trace.go:171","msg":"trace[63537367] transaction","detail":"{read_only:false; response_revision:4737; number_of_response:1; }","duration":"129.548814ms","start":"2026-08-05T11:24:12.885372Z","end":"2026-08-05T11:24:13.014921Z","steps":["trace[63537367] 'process raft request' (duration: 129.489632ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:24:13.015424Z","caller":"traceutil/trace.go:171","msg":"trace[1831649581] transaction","detail":"{read_only:false; response_revision:4734; number_of_response:1; }","duration":"132.769523ms","start":"2026-08-05T11:24:12.882645Z","end":"2026-08-05T11:24:13.015415Z","steps":["trace[1831649581] 'process raft request' (duration: 131.977406ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:24:13.015536Z","caller":"traceutil/trace.go:171","msg":"trace[766744308] transaction","detail":"{read_only:false; response_revision:4735; number_of_response:1; }","duration":"132.861336ms","start":"2026-08-05T11:24:12.882668Z","end":"2026-08-05T11:24:13.01553Z","steps":["trace[766744308] 'process raft request' (duration: 132.12269ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:24:13.015621Z","caller":"traceutil/trace.go:171","msg":"trace[737607443] transaction","detail":"{read_only:false; response_revision:4736; number_of_response:1; }","duration":"132.493828ms","start":"2026-08-05T11:24:12.88312Z","end":"2026-08-05T11:24:13.015614Z","steps":["trace[737607443] 'process raft request' (duration: 131.707451ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:24:13.218328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.142049ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-05T11:24:13.218494Z","caller":"traceutil/trace.go:171","msg":"trace[1820914460] transaction","detail":"{read_only:false; response_revision:4741; number_of_response:1; }","duration":"121.949046ms","start":"2026-08-05T11:24:13.096522Z","end":"2026-08-05T11:24:13.218471Z","steps":["trace[1820914460] 'process raft request' (duration: 10.566601ms)","trace[1820914460] 'compare' (duration: 111.006697ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:24:13.227673Z","caller":"traceutil/trace.go:171","msg":"trace[1391706506] transaction","detail":"{read_only:false; response_revision:4743; number_of_response:1; }","duration":"128.55759ms","start":"2026-08-05T11:24:13.099098Z","end":"2026-08-05T11:24:13.227656Z","steps":["trace[1391706506] 'process raft request' (duration: 128.437008ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:24:13.22799Z","caller":"traceutil/trace.go:171","msg":"trace[91948985] transaction","detail":"{read_only:false; response_revision:4742; number_of_response:1; }","duration":"129.91119ms","start":"2026-08-05T11:24:13.098005Z","end":"2026-08-05T11:24:13.227916Z","steps":["trace[91948985] 'process raft request' (duration: 129.4233ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:24:19.347679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"670.871099ms","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-08-05T11:24:19.3478Z","caller":"traceutil/trace.go:171","msg":"trace[1789184214] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:4835; }","duration":"671.031212ms","start":"2026-08-05T11:24:18.676741Z","end":"2026-08-05T11:24:19.347772Z","steps":["trace[1789184214] 'count revisions from in-memory index tree' (duration: 670.784697ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:24:19.347811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.201273ms","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-08-05T11:24:19.347914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-bfb36df2-87ee-4992-9e6c-8dc3ed500f03\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-08-05T11:24:19.347965Z","caller":"traceutil/trace.go:171","msg":"trace[102982076] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4835; }","duration":"299.402617ms","start":"2026-08-05T11:24:19.048528Z","end":"2026-08-05T11:24:19.34793Z","steps":["trace[102982076] 'range keys from in-memory index tree' (duration: 298.976778ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:24:19.347975Z","caller":"traceutil/trace.go:171","msg":"trace[418011909] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-bfb36df2-87ee-4992-9e6c-8dc3ed500f03; range_end:; response_count:1; response_revision:4835; }","duration":"478.379721ms","start":"2026-08-05T11:24:18.869575Z","end":"2026-08-05T11:24:19.347955Z","steps":["trace[418011909] 'range keys from in-memory index tree' (duration: 478.092514ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:24:19.347849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:24:18.676707Z","time spent":"671.130424ms","remote":"127.0.0.1:57024","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"warn","ts":"2026-08-05T11:24:19.348119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:24:18.869484Z","time spent":"478.586255ms","remote":"127.0.0.1:56870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3669,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-bfb36df2-87ee-4992-9e6c-8dc3ed500f03\" "} {"level":"warn","ts":"2026-08-05T11:24:19.347868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"616.410381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T11:24:19.348267Z","caller":"traceutil/trace.go:171","msg":"trace[443782443] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4835; }","duration":"616.800589ms","start":"2026-08-05T11:24:18.731441Z","end":"2026-08-05T11:24:19.348242Z","steps":["trace[443782443] 'range keys from in-memory index tree' (duration: 616.338459ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:24:19.348353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:24:18.731373Z","time spent":"616.960133ms","remote":"127.0.0.1:56678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-05T11:24:19.34872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.509502ms","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-08-05T11:24:19.348785Z","caller":"traceutil/trace.go:171","msg":"trace[63526347] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4835; }","duration":"412.135186ms","start":"2026-08-05T11:24:18.936634Z","end":"2026-08-05T11:24:19.348769Z","steps":["trace[63526347] 'range keys from in-memory index tree' (duration: 410.853387ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:24:19.348829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:24:18.93661Z","time spent":"412.206317ms","remote":"127.0.0.1:56960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-05T11:24:19.349127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.390431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-08-05T11:24:19.349188Z","caller":"traceutil/trace.go:171","msg":"trace[1015364779] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4835; }","duration":"307.551975ms","start":"2026-08-05T11:24:19.041618Z","end":"2026-08-05T11:24:19.349169Z","steps":["trace[1015364779] 'range keys from in-memory index tree' (duration: 306.046602ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:24:19.34924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:24:19.041591Z","time spent":"307.633776ms","remote":"127.0.0.1:56960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-08-05T11:24:19.349419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.326256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-05T11:24:19.34948Z","caller":"traceutil/trace.go:171","msg":"trace[930830716] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4835; }","duration":"443.417158ms","start":"2026-08-05T11:24:18.906045Z","end":"2026-08-05T11:24:19.349462Z","steps":["trace[930830716] 'range keys from in-memory index tree' (duration: 441.182819ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:24:19.349515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:24:18.90603Z","time spent":"443.47628ms","remote":"127.0.0.1:56960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-05T11:24:19.349501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.005404ms","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-08-05T11:24:19.349585Z","caller":"traceutil/trace.go:171","msg":"trace[228742666] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4835; }","duration":"302.148457ms","start":"2026-08-05T11:24:19.047417Z","end":"2026-08-05T11:24:19.349566Z","steps":["trace[228742666] 'range keys from in-memory index tree' (duration: 299.768045ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:24:19.349625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:24:19.047397Z","time spent":"302.219779ms","remote":"127.0.0.1:56960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-05T11:24:19.457065Z","caller":"traceutil/trace.go:171","msg":"trace[54700062] transaction","detail":"{read_only:false; response_revision:4836; number_of_response:1; }","duration":"326.045028ms","start":"2026-08-05T11:24:19.130982Z","end":"2026-08-05T11:24:19.457027Z","steps":["trace[54700062] 'process raft request' (duration: 325.624059ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:24:19.457323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:24:19.130951Z","time spent":"326.230692ms","remote":"127.0.0.1:56960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T11:24:19.492088Z","caller":"traceutil/trace.go:171","msg":"trace[443991353] transaction","detail":"{read_only:false; response_revision:4838; number_of_response:1; }","duration":"187.834735ms","start":"2026-08-05T11:24:19.304221Z","end":"2026-08-05T11:24:19.492056Z","steps":["trace[443991353] 'process raft request' (duration: 187.723813ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:24:19.49212Z","caller":"traceutil/trace.go:171","msg":"trace[335039468] transaction","detail":"{read_only:false; response_revision:4837; number_of_response:1; }","duration":"239.073892ms","start":"2026-08-05T11:24:19.253016Z","end":"2026-08-05T11:24:19.49209Z","steps":["trace[335039468] 'process raft request' (duration: 238.750815ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:24:19.492472Z","caller":"traceutil/trace.go:171","msg":"trace[1003223550] transaction","detail":"{read_only:false; response_revision:4839; number_of_response:1; }","duration":"138.482579ms","start":"2026-08-05T11:24:19.353922Z","end":"2026-08-05T11:24:19.492405Z","steps":["trace[1003223550] 'process raft request' (duration: 138.08303ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:24:19.492843Z","caller":"traceutil/trace.go:171","msg":"trace[486656739] transaction","detail":"{read_only:false; response_revision:4840; number_of_response:1; }","duration":"137.650521ms","start":"2026-08-05T11:24:19.355174Z","end":"2026-08-05T11:24:19.492824Z","steps":["trace[486656739] 'process raft request' (duration: 137.1488ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:24:19.493366Z","caller":"traceutil/trace.go:171","msg":"trace[2123918066] transaction","detail":"{read_only:false; response_revision:4841; number_of_response:1; }","duration":"138.114851ms","start":"2026-08-05T11:24:19.35524Z","end":"2026-08-05T11:24:19.493354Z","steps":["trace[2123918066] 'process raft request' (duration: 137.525028ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:24:19.493657Z","caller":"traceutil/trace.go:171","msg":"trace[510421009] linearizableReadLoop","detail":"{readStateIndex:5016; appliedIndex:5014; }","duration":"194.447799ms","start":"2026-08-05T11:24:19.299168Z","end":"2026-08-05T11:24:19.493615Z","steps":["trace[510421009] 'read index received' (duration: 157.694468ms)","trace[510421009] 'applied index is now lower than readState.Index' (duration: 36.752181ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:24:19.493714Z","caller":"traceutil/trace.go:171","msg":"trace[1916390177] transaction","detail":"{read_only:false; response_revision:4842; number_of_response:1; }","duration":"138.410837ms","start":"2026-08-05T11:24:19.355287Z","end":"2026-08-05T11:24:19.493697Z","steps":["trace[1916390177] 'process raft request' (duration: 138.014749ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:24:19.494159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.075889ms","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-08-05T11:24:19.494188Z","caller":"traceutil/trace.go:171","msg":"trace[1114153271] 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:4843; }","duration":"176.10178ms","start":"2026-08-05T11:24:19.318074Z","end":"2026-08-05T11:24:19.494175Z","steps":["trace[1114153271] 'agreement among raft nodes before linearized reading' (duration: 176.025558ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:24:19.493761Z","caller":"traceutil/trace.go:171","msg":"trace[1716628534] transaction","detail":"{read_only:false; response_revision:4843; number_of_response:1; }","duration":"138.410097ms","start":"2026-08-05T11:24:19.355342Z","end":"2026-08-05T11:24:19.493752Z","steps":["trace[1716628534] 'process raft request' (duration: 138.210283ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:24:19.4938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.615673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-bfb36df2-87ee-4992-9e6c-8dc3ed500f03\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-08-05T11:24:19.494497Z","caller":"traceutil/trace.go:171","msg":"trace[319403015] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-bfb36df2-87ee-4992-9e6c-8dc3ed500f03; range_end:; response_count:1; response_revision:4843; }","duration":"195.357029ms","start":"2026-08-05T11:24:19.299118Z","end":"2026-08-05T11:24:19.494475Z","steps":["trace[319403015] 'agreement among raft nodes before linearized reading' (duration: 194.564462ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:24:19.494665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.413129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T11:24:19.494722Z","caller":"traceutil/trace.go:171","msg":"trace[1380245911] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4843; }","duration":"144.524261ms","start":"2026-08-05T11:24:19.350183Z","end":"2026-08-05T11:24:19.494708Z","steps":["trace[1380245911] 'agreement among raft nodes before linearized reading' (duration: 144.422779ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:24:19.495058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.217197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-05T11:24:19.49512Z","caller":"traceutil/trace.go:171","msg":"trace[1862932770] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:4843; }","duration":"140.282479ms","start":"2026-08-05T11:24:19.354808Z","end":"2026-08-05T11:24:19.495091Z","steps":["trace[1862932770] 'agreement among raft nodes before linearized reading' (duration: 139.548163ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:24:20.176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.534709ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-05T11:24:20.176454Z","caller":"traceutil/trace.go:171","msg":"trace[1671219423] transaction","detail":"{read_only:false; response_revision:4846; number_of_response:1; }","duration":"524.139225ms","start":"2026-08-05T11:24:19.652285Z","end":"2026-08-05T11:24:20.176424Z","steps":["trace[1671219423] 'process raft request' (duration: 146.060815ms)","trace[1671219423] 'compare' (duration: 377.223372ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T11:24:20.176713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:24:19.652249Z","time spent":"524.255607ms","remote":"127.0.0.1:56976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1869,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T11:24:20.176793Z","caller":"traceutil/trace.go:171","msg":"trace[1017206048] linearizableReadLoop","detail":"{readStateIndex:5028; appliedIndex:5024; }","duration":"512.945441ms","start":"2026-08-05T11:24:19.663793Z","end":"2026-08-05T11:24:20.176738Z","steps":["trace[1017206048] 'read index received' (duration: 134.414801ms)","trace[1017206048] 'applied index is now lower than readState.Index' (duration: 378.52947ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:24:20.176708Z","caller":"traceutil/trace.go:171","msg":"trace[384214983] transaction","detail":"{read_only:false; response_revision:4847; number_of_response:1; }","duration":"523.640494ms","start":"2026-08-05T11:24:19.653028Z","end":"2026-08-05T11:24:20.176668Z","steps":["trace[384214983] 'process raft request' (duration: 523.319017ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:24:20.176986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:24:19.653009Z","time spent":"523.876869ms","remote":"127.0.0.1:56976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1455,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T11:24:20.177069Z","caller":"traceutil/trace.go:171","msg":"trace[481882201] transaction","detail":"{read_only:false; response_revision:4848; number_of_response:1; }","duration":"523.505281ms","start":"2026-08-05T11:24:19.653552Z","end":"2026-08-05T11:24:20.177057Z","steps":["trace[481882201] 'process raft request' (duration: 522.871807ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:24:20.177183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:24:19.653544Z","time spent":"523.596223ms","remote":"127.0.0.1:56838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1283,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T11:24:20.177207Z","caller":"traceutil/trace.go:171","msg":"trace[2076087001] transaction","detail":"{read_only:false; response_revision:4850; number_of_response:1; }","duration":"504.801194ms","start":"2026-08-05T11:24:19.672384Z","end":"2026-08-05T11:24:20.177185Z","steps":["trace[2076087001] 'process raft request' (duration: 504.17556ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:24:20.177272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.476282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-05T11:24:20.177301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:24:19.672374Z","time spent":"504.876855ms","remote":"127.0.0.1:56960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T11:24:20.177309Z","caller":"traceutil/trace.go:171","msg":"trace[1453893805] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:4851; }","duration":"513.549304ms","start":"2026-08-05T11:24:19.66375Z","end":"2026-08-05T11:24:20.177299Z","steps":["trace[1453893805] 'agreement among raft nodes before linearized reading' (duration: 513.476403ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:24:20.177338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:24:19.663739Z","time spent":"513.592695ms","remote":"127.0.0.1:57232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":6,"response size":30,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"info","ts":"2026-08-05T11:24:20.177494Z","caller":"traceutil/trace.go:171","msg":"trace[1334449741] transaction","detail":"{read_only:false; response_revision:4851; number_of_response:1; }","duration":"465.031236ms","start":"2026-08-05T11:24:19.712449Z","end":"2026-08-05T11:24:20.17748Z","steps":["trace[1334449741] 'process raft request' (duration: 464.237559ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:24:20.177494Z","caller":"traceutil/trace.go:171","msg":"trace[1589396028] transaction","detail":"{read_only:false; response_revision:4849; number_of_response:1; }","duration":"523.819548ms","start":"2026-08-05T11:24:19.653662Z","end":"2026-08-05T11:24:20.177481Z","steps":["trace[1589396028] 'process raft request' (duration: 522.833007ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:24:20.177542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:24:19.712439Z","time spent":"465.074937ms","remote":"127.0.0.1:56960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T11:24:20.177552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:24:19.653638Z","time spent":"523.880299ms","remote":"127.0.0.1:56838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":906,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T11:24:20.188389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.505916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-05T11:24:20.188435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.724832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.225\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-05T11:24:20.188464Z","caller":"traceutil/trace.go:171","msg":"trace[211883625] range","detail":"{range_begin:/registry/rabbitmq.com/schemareplications/; range_end:/registry/rabbitmq.com/schemareplications0; response_count:0; response_revision:4852; }","duration":"198.630558ms","start":"2026-08-05T11:24:19.989816Z","end":"2026-08-05T11:24:20.188446Z","steps":["trace[211883625] 'agreement among raft nodes before linearized reading' (duration: 198.458034ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:24:20.188477Z","caller":"traceutil/trace.go:171","msg":"trace[2086251011] range","detail":"{range_begin:/registry/masterleases/199.19.213.225; range_end:; response_count:1; response_revision:4852; }","duration":"154.782522ms","start":"2026-08-05T11:24:20.033677Z","end":"2026-08-05T11:24:20.18846Z","steps":["trace[2086251011] 'agreement among raft nodes before linearized reading' (duration: 154.586788ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:24:20.188457Z","caller":"traceutil/trace.go:171","msg":"trace[733125694] transaction","detail":"{read_only:false; response_revision:4852; number_of_response:1; }","duration":"169.147145ms","start":"2026-08-05T11:24:20.019262Z","end":"2026-08-05T11:24:20.18841Z","steps":["trace[733125694] 'process raft request' (duration: 168.867819ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:24:20.188533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.194992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T11:24:20.188604Z","caller":"traceutil/trace.go:171","msg":"trace[706793136] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4852; }","duration":"455.311214ms","start":"2026-08-05T11:24:19.733277Z","end":"2026-08-05T11:24:20.188589Z","steps":["trace[706793136] 'agreement among raft nodes before linearized reading' (duration: 455.167951ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:24:20.188624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:24:19.733266Z","time spent":"455.353965ms","remote":"127.0.0.1:56678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-05T11:24:20.188652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.708595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-08-05T11:24:20.188734Z","caller":"traceutil/trace.go:171","msg":"trace[1685804865] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4852; }","duration":"217.857148ms","start":"2026-08-05T11:24:19.970861Z","end":"2026-08-05T11:24:20.188718Z","steps":["trace[1685804865] 'agreement among raft nodes before linearized reading' (duration: 217.643283ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:24:20.44651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.834558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-08-05T11:24:20.446612Z","caller":"traceutil/trace.go:171","msg":"trace[1661261889] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:4854; }","duration":"167.95038ms","start":"2026-08-05T11:24:20.27864Z","end":"2026-08-05T11:24:20.446591Z","steps":["trace[1661261889] 'range keys from in-memory index tree' (duration: 167.737636ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:24:20.446696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.026326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-767b44f8b8-ksrc2.18c8e50465c03ce3\" ","response":"range_response_count:1 size:898"} {"level":"info","ts":"2026-08-05T11:24:20.446805Z","caller":"traceutil/trace.go:171","msg":"trace[375085531] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-grafana-767b44f8b8-ksrc2.18c8e50465c03ce3; range_end:; response_count:1; response_revision:4854; }","duration":"137.184869ms","start":"2026-08-05T11:24:20.309596Z","end":"2026-08-05T11:24:20.446781Z","steps":["trace[375085531] 'range keys from in-memory index tree' (duration: 136.902733ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:24:20.446897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.922077ms","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-08-05T11:24:20.447043Z","caller":"traceutil/trace.go:171","msg":"trace[2050877874] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:4854; }","duration":"105.12142ms","start":"2026-08-05T11:24:20.341892Z","end":"2026-08-05T11:24:20.447014Z","steps":["trace[2050877874] 'range keys from in-memory index tree' (duration: 104.829624ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:24:20.447229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.645956ms","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-08-05T11:24:20.447282Z","caller":"traceutil/trace.go:171","msg":"trace[681493531] 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:4854; }","duration":"135.738958ms","start":"2026-08-05T11:24:20.31153Z","end":"2026-08-05T11:24:20.447269Z","steps":["trace[681493531] 'range keys from in-memory index tree' (duration: 135.505553ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:24:22.466423Z","caller":"traceutil/trace.go:171","msg":"trace[1846237897] transaction","detail":"{read_only:false; response_revision:4876; number_of_response:1; }","duration":"282.813367ms","start":"2026-08-05T11:24:22.183584Z","end":"2026-08-05T11:24:22.466398Z","steps":["trace[1846237897] 'process raft request' (duration: 282.629803ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:24:22.471658Z","caller":"traceutil/trace.go:171","msg":"trace[100227054] linearizableReadLoop","detail":"{readStateIndex:5057; appliedIndex:5055; }","duration":"205.886342ms","start":"2026-08-05T11:24:22.26576Z","end":"2026-08-05T11:24:22.471646Z","steps":["trace[100227054] 'read index received' (duration: 200.450514ms)","trace[100227054] 'applied index is now lower than readState.Index' (duration: 5.435108ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:24:22.471732Z","caller":"traceutil/trace.go:171","msg":"trace[218869730] transaction","detail":"{read_only:false; response_revision:4877; number_of_response:1; }","duration":"273.850682ms","start":"2026-08-05T11:24:22.197874Z","end":"2026-08-05T11:24:22.471724Z","steps":["trace[218869730] 'process raft request' (duration: 273.710848ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:24:22.471875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.105567ms","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-08-05T11:24:22.471909Z","caller":"traceutil/trace.go:171","msg":"trace[1607400828] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4877; }","duration":"206.206139ms","start":"2026-08-05T11:24:22.265694Z","end":"2026-08-05T11:24:22.4719Z","steps":["trace[1607400828] 'agreement among raft nodes before linearized reading' (duration: 206.094667ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:24:27.655867Z","caller":"traceutil/trace.go:171","msg":"trace[1627790678] linearizableReadLoop","detail":"{readStateIndex:5149; appliedIndex:5148; }","duration":"122.254765ms","start":"2026-08-05T11:24:27.533583Z","end":"2026-08-05T11:24:27.655838Z","steps":["trace[1627790678] 'read index received' (duration: 122.051701ms)","trace[1627790678] 'applied index is now lower than readState.Index' (duration: 201.874µs)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:24:27.65598Z","caller":"traceutil/trace.go:171","msg":"trace[1221830621] transaction","detail":"{read_only:false; response_revision:4965; number_of_response:1; }","duration":"137.70172ms","start":"2026-08-05T11:24:27.518238Z","end":"2026-08-05T11:24:27.65594Z","steps":["trace[1221830621] 'process raft request' (duration: 137.459595ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:24:27.65627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.628123ms","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-08-05T11:24:27.656402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.696113ms","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-08-05T11:24:27.656464Z","caller":"traceutil/trace.go:171","msg":"trace[161813925] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4965; }","duration":"121.815646ms","start":"2026-08-05T11:24:27.534633Z","end":"2026-08-05T11:24:27.656448Z","steps":["trace[161813925] 'agreement among raft nodes before linearized reading' (duration: 121.56207ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:24:27.656484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.436437ms","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-08-05T11:24:27.656525Z","caller":"traceutil/trace.go:171","msg":"trace[938978069] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4965; }","duration":"121.475298ms","start":"2026-08-05T11:24:27.535036Z","end":"2026-08-05T11:24:27.656512Z","steps":["trace[938978069] 'agreement among raft nodes before linearized reading' (duration: 121.309814ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:24:27.65654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.514995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-08-05T11:24:27.656586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.710627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3243"} {"level":"info","ts":"2026-08-05T11:24:27.656639Z","caller":"traceutil/trace.go:171","msg":"trace[1815596634] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4965; }","duration":"118.688988ms","start":"2026-08-05T11:24:27.537929Z","end":"2026-08-05T11:24:27.656618Z","steps":["trace[1815596634] 'agreement among raft nodes before linearized reading' (duration: 118.343861ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:24:27.656725Z","caller":"traceutil/trace.go:171","msg":"trace[1918743043] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4965; }","duration":"100.808989ms","start":"2026-08-05T11:24:27.555859Z","end":"2026-08-05T11:24:27.656668Z","steps":["trace[1918743043] 'agreement among raft nodes before linearized reading' (duration: 100.535673ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:24:27.656777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.792236ms","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-08-05T11:24:27.65664Z","caller":"traceutil/trace.go:171","msg":"trace[384784318] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4965; }","duration":"122.866589ms","start":"2026-08-05T11:24:27.533447Z","end":"2026-08-05T11:24:27.656314Z","steps":["trace[384784318] 'agreement among raft nodes before linearized reading' (duration: 122.512091ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:24:27.656886Z","caller":"traceutil/trace.go:171","msg":"trace[1260907546] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4965; }","duration":"121.945779ms","start":"2026-08-05T11:24:27.534916Z","end":"2026-08-05T11:24:27.656862Z","steps":["trace[1260907546] 'agreement among raft nodes before linearized reading' (duration: 121.596041ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:24:32.503742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.005159249s","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-08-05T11:24:32.503836Z","caller":"traceutil/trace.go:171","msg":"trace[2006746005] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4994; }","duration":"1.005297742s","start":"2026-08-05T11:24:31.498516Z","end":"2026-08-05T11:24:32.503813Z","steps":["trace[2006746005] 'range keys from in-memory index tree' (duration: 1.004892514s)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:24:32.503883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:24:31.498504Z","time spent":"1.005368974s","remote":"127.0.0.1:56830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1888,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-08-05T11:24:32.50494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"697.509929ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-05T11:24:32.505215Z","caller":"traceutil/trace.go:171","msg":"trace[1416427993] transaction","detail":"{read_only:false; response_revision:4996; number_of_response:1; }","duration":"674.742615ms","start":"2026-08-05T11:24:31.830451Z","end":"2026-08-05T11:24:32.505194Z","steps":["trace[1416427993] 'process raft request' (duration: 674.607902ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:24:32.50529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:24:31.830418Z","time spent":"674.840087ms","remote":"127.0.0.1:56960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T11:24:32.505738Z","caller":"traceutil/trace.go:171","msg":"trace[5435621] transaction","detail":"{read_only:false; response_revision:4995; number_of_response:1; }","duration":"835.884469ms","start":"2026-08-05T11:24:31.669837Z","end":"2026-08-05T11:24:32.505722Z","steps":["trace[5435621] 'process raft request' (duration: 137.496061ms)","trace[5435621] 'compare' (duration: 697.211822ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T11:24:32.505821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:24:31.6698Z","time spent":"835.984441ms","remote":"127.0.0.1:56960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T11:24:32.505964Z","caller":"traceutil/trace.go:171","msg":"trace[1051627404] linearizableReadLoop","detail":"{readStateIndex:5180; appliedIndex:5179; }","duration":"817.193124ms","start":"2026-08-05T11:24:31.688759Z","end":"2026-08-05T11:24:32.505952Z","steps":["trace[1051627404] 'read index received' (duration: 118.50812ms)","trace[1051627404] 'applied index is now lower than readState.Index' (duration: 698.683894ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T11:24:32.506261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"817.50917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-05T11:24:32.506302Z","caller":"traceutil/trace.go:171","msg":"trace[1315288722] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4996; }","duration":"817.584962ms","start":"2026-08-05T11:24:31.688705Z","end":"2026-08-05T11:24:32.50629Z","steps":["trace[1315288722] 'agreement among raft nodes before linearized reading' (duration: 817.443809ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:24:32.50635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"814.416754ms","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-08-05T11:24:32.506494Z","caller":"traceutil/trace.go:171","msg":"trace[1732458679] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4996; }","duration":"814.552127ms","start":"2026-08-05T11:24:31.69186Z","end":"2026-08-05T11:24:32.506412Z","steps":["trace[1732458679] 'agreement among raft nodes before linearized reading' (duration: 814.382293ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:24:32.506547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:24:31.691833Z","time spent":"814.70237ms","remote":"127.0.0.1:56960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-05T11:24:32.506712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"812.847649ms","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-08-05T11:24:32.506753Z","caller":"traceutil/trace.go:171","msg":"trace[1822524037] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4996; }","duration":"812.939071ms","start":"2026-08-05T11:24:31.693806Z","end":"2026-08-05T11:24:32.506745Z","steps":["trace[1822524037] 'agreement among raft nodes before linearized reading' (duration: 812.836969ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:24:32.506786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:24:31.693778Z","time spent":"813.002593ms","remote":"127.0.0.1:56960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-05T11:24:32.506739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"774.189941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-05T11:24:32.506899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"814.800212ms","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-08-05T11:24:32.506895Z","caller":"traceutil/trace.go:171","msg":"trace[527809602] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4996; }","duration":"774.395206ms","start":"2026-08-05T11:24:31.732477Z","end":"2026-08-05T11:24:32.506872Z","steps":["trace[527809602] 'agreement among raft nodes before linearized reading' (duration: 773.931196ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:24:32.506924Z","caller":"traceutil/trace.go:171","msg":"trace[299099171] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4996; }","duration":"814.866954ms","start":"2026-08-05T11:24:31.692049Z","end":"2026-08-05T11:24:32.506916Z","steps":["trace[299099171] 'agreement among raft nodes before linearized reading' (duration: 814.801972ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:24:32.506955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:24:31.692039Z","time spent":"814.908664ms","remote":"127.0.0.1:56960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-08-05T11:24:32.506334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:24:31.688691Z","time spent":"817.636243ms","remote":"127.0.0.1:56960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-05T11:24:32.507005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:24:31.73246Z","time spent":"774.532038ms","remote":"127.0.0.1:56678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-05T11:24:32.506992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"814.525646ms","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-08-05T11:24:32.507088Z","caller":"traceutil/trace.go:171","msg":"trace[1765635732] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4996; }","duration":"814.658349ms","start":"2026-08-05T11:24:31.692406Z","end":"2026-08-05T11:24:32.507064Z","steps":["trace[1765635732] 'agreement among raft nodes before linearized reading' (duration: 814.356932ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:24:32.507161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:24:31.692395Z","time spent":"814.750141ms","remote":"127.0.0.1:56960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-05T11:24:33.11927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.177269ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-05T11:24:33.119367Z","caller":"traceutil/trace.go:171","msg":"trace[632652375] transaction","detail":"{read_only:false; response_revision:5008; number_of_response:1; }","duration":"345.547458ms","start":"2026-08-05T11:24:32.773804Z","end":"2026-08-05T11:24:33.119351Z","steps":["trace[632652375] 'process raft request' (duration: 192.214146ms)","trace[632652375] 'compare' (duration: 152.863542ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T11:24:33.119403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:24:32.773772Z","time spent":"345.61419ms","remote":"127.0.0.1:56976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1563,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T11:24:33.14295Z","caller":"traceutil/trace.go:171","msg":"trace[1957958506] transaction","detail":"{read_only:false; response_revision:5009; number_of_response:1; }","duration":"357.64147ms","start":"2026-08-05T11:24:32.785289Z","end":"2026-08-05T11:24:33.14293Z","steps":["trace[1957958506] 'process raft request' (duration: 357.414575ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:24:33.143043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:24:32.785271Z","time spent":"357.729561ms","remote":"127.0.0.1:57242","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10497,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T11:24:33.143096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.240317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3243"} {"level":"info","ts":"2026-08-05T11:24:33.143132Z","caller":"traceutil/trace.go:171","msg":"trace[780551104] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5009; }","duration":"217.353959ms","start":"2026-08-05T11:24:32.92577Z","end":"2026-08-05T11:24:33.143124Z","steps":["trace[780551104] 'agreement among raft nodes before linearized reading' (duration: 217.213076ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:24:33.142932Z","caller":"traceutil/trace.go:171","msg":"trace[1149819783] linearizableReadLoop","detail":"{readStateIndex:5194; appliedIndex:5192; }","duration":"217.068013ms","start":"2026-08-05T11:24:32.925834Z","end":"2026-08-05T11:24:33.142902Z","steps":["trace[1149819783] 'read index received' (duration: 40.245052ms)","trace[1149819783] 'applied index is now lower than readState.Index' (duration: 176.822061ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:24:33.353724Z","caller":"traceutil/trace.go:171","msg":"trace[1057306669] linearizableReadLoop","detail":"{readStateIndex:5195; appliedIndex:5194; }","duration":"196.291692ms","start":"2026-08-05T11:24:33.157391Z","end":"2026-08-05T11:24:33.353683Z","steps":["trace[1057306669] 'read index received' (duration: 141.399043ms)","trace[1057306669] 'applied index is now lower than readState.Index' (duration: 54.890869ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:24:33.353834Z","caller":"traceutil/trace.go:171","msg":"trace[688666253] transaction","detail":"{read_only:false; response_revision:5010; number_of_response:1; }","duration":"198.00751ms","start":"2026-08-05T11:24:33.15578Z","end":"2026-08-05T11:24:33.353788Z","steps":["trace[688666253] 'process raft request' (duration: 143.130261ms)","trace[688666253] 'compare' (duration: 54.531371ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T11:24:33.35421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.334296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-05T11:24:33.35427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.790843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/rabbitmq-keystone.18c8e504180e22a7\" ","response":"range_response_count:1 size:746"} {"level":"warn","ts":"2026-08-05T11:24:33.354338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.18624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:1455"} {"level":"info","ts":"2026-08-05T11:24:33.354343Z","caller":"traceutil/trace.go:171","msg":"trace[1641355087] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:5010; }","duration":"155.509499ms","start":"2026-08-05T11:24:33.198817Z","end":"2026-08-05T11:24:33.354327Z","steps":["trace[1641355087] 'agreement among raft nodes before linearized reading' (duration: 155.271174ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:24:33.354375Z","caller":"traceutil/trace.go:171","msg":"trace[191880731] range","detail":"{range_begin:/registry/events/openstack/rabbitmq-keystone.18c8e504180e22a7; range_end:; response_count:1; response_revision:5010; }","duration":"196.982818ms","start":"2026-08-05T11:24:33.157366Z","end":"2026-08-05T11:24:33.354348Z","steps":["trace[191880731] 'agreement among raft nodes before linearized reading' (duration: 196.483927ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:24:33.354405Z","caller":"traceutil/trace.go:171","msg":"trace[791937798] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5010; }","duration":"196.290473ms","start":"2026-08-05T11:24:33.158101Z","end":"2026-08-05T11:24:33.354391Z","steps":["trace[791937798] 'agreement among raft nodes before linearized reading' (duration: 195.953106ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:24:40.175111Z","caller":"traceutil/trace.go:171","msg":"trace[2092240911] transaction","detail":"{read_only:false; response_revision:5070; number_of_response:1; }","duration":"121.77337ms","start":"2026-08-05T11:24:40.053301Z","end":"2026-08-05T11:24:40.175074Z","steps":["trace[2092240911] 'process raft request' (duration: 60.385924ms)","trace[2092240911] 'compare' (duration: 61.222672ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:25:00.754154Z","caller":"traceutil/trace.go:171","msg":"trace[1225800518] transaction","detail":"{read_only:false; response_revision:5295; number_of_response:1; }","duration":"124.563498ms","start":"2026-08-05T11:25:00.629565Z","end":"2026-08-05T11:25:00.754128Z","steps":["trace[1225800518] 'process raft request' (duration: 119.873737ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:25:11.12699Z","caller":"traceutil/trace.go:171","msg":"trace[1843299138] transaction","detail":"{read_only:false; response_revision:5359; number_of_response:1; }","duration":"261.085489ms","start":"2026-08-05T11:25:10.865892Z","end":"2026-08-05T11:25:11.126978Z","steps":["trace[1843299138] 'process raft request' (duration: 260.928106ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:25:11.127332Z","caller":"traceutil/trace.go:171","msg":"trace[6178701] transaction","detail":"{read_only:false; response_revision:5358; number_of_response:1; }","duration":"261.968638ms","start":"2026-08-05T11:25:10.864895Z","end":"2026-08-05T11:25:11.126863Z","steps":["trace[6178701] 'process raft request' (duration: 176.361452ms)","trace[6178701] 'compare' (duration: 85.265068ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:25:27.185668Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2837} {"level":"warn","ts":"2026-08-05T11:25:27.355335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.769237ms","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-08-05T11:25:27.355421Z","caller":"traceutil/trace.go:171","msg":"trace[1512783974] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5470; }","duration":"109.900459ms","start":"2026-08-05T11:25:27.245503Z","end":"2026-08-05T11:25:27.355404Z","steps":["trace[1512783974] 'range keys from in-memory index tree' (duration: 109.613404ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:25:27.355409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.184103ms","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-08-05T11:25:27.355481Z","caller":"traceutil/trace.go:171","msg":"trace[511759067] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5470; }","duration":"149.302356ms","start":"2026-08-05T11:25:27.206161Z","end":"2026-08-05T11:25:27.355463Z","steps":["trace[511759067] 'range keys from in-memory index tree' (duration: 149.01816ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:25:27.468164Z","caller":"traceutil/trace.go:171","msg":"trace[1619119627] transaction","detail":"{read_only:false; response_revision:5472; number_of_response:1; }","duration":"107.732354ms","start":"2026-08-05T11:25:27.360309Z","end":"2026-08-05T11:25:27.468042Z","steps":["trace[1619119627] 'process raft request' (duration: 41.262585ms)","trace[1619119627] 'compare' (duration: 42.832998ms)","trace[1619119627] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/kube-system/plndr-svcs-lock; req_size:418; } (duration: 23.538049ms)"],"step_count":3} {"level":"info","ts":"2026-08-05T11:25:27.497782Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2837,"took":"310.062573ms","hash":1047244010,"current-db-size-bytes":52346880,"current-db-size":"52 MB","current-db-size-in-use-bytes":28606464,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-08-05T11:25:27.497915Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1047244010,"revision":2837,"compact-revision":-1} {"level":"info","ts":"2026-08-05T11:28:26.409038Z","caller":"traceutil/trace.go:171","msg":"trace[2063746594] linearizableReadLoop","detail":"{readStateIndex:6984; appliedIndex:6983; }","duration":"101.821507ms","start":"2026-08-05T11:28:26.307198Z","end":"2026-08-05T11:28:26.40902Z","steps":["trace[2063746594] 'read index received' (duration: 85.692571ms)","trace[2063746594] 'applied index is now lower than readState.Index' (duration: 16.128146ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:28:26.409179Z","caller":"traceutil/trace.go:171","msg":"trace[1304091354] transaction","detail":"{read_only:false; response_revision:6740; number_of_response:1; }","duration":"103.597943ms","start":"2026-08-05T11:28:26.305573Z","end":"2026-08-05T11:28:26.409171Z","steps":["trace[1304091354] 'process raft request' (duration: 87.342865ms)","trace[1304091354] 'compare' (duration: 15.981543ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T11:28:26.409279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.066822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-05T11:28:26.409304Z","caller":"traceutil/trace.go:171","msg":"trace[1736396756] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/; range_end:/registry/rabbitmq.com/rabbitmqclusters0; response_count:0; response_revision:6740; }","duration":"102.105893ms","start":"2026-08-05T11:28:26.307191Z","end":"2026-08-05T11:28:26.409297Z","steps":["trace[1736396756] 'agreement among raft nodes before linearized reading' (duration: 102.051472ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:30:27.193262Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5470} {"level":"info","ts":"2026-08-05T11:30:27.26757Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5470,"took":"72.461257ms","hash":1545418237,"current-db-size-bytes":52346880,"current-db-size":"52 MB","current-db-size-in-use-bytes":18448384,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-08-05T11:30:27.267688Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1545418237,"revision":5470,"compact-revision":2837}