{"level":"warn","ts":"2026-05-08T10:17:19.095012Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-08T10:17:19.095158Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.22:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.22:2380","--initial-cluster=instance=https://199.204.45.22:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.22:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.22:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-08T10:17:19.095266Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-08T10:17:19.095284Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.22:2380"]} {"level":"info","ts":"2026-05-08T10:17:19.095316Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-08T10:17:19.096466Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.22:2379"]} {"level":"info","ts":"2026-05-08T10:17:19.096603Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.22:2380"],"listen-peer-urls":["https://199.204.45.22:2380"],"advertise-client-urls":["https://199.204.45.22:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.22:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.22:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-08T10:17:19.108755Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"11.804387ms"} {"level":"info","ts":"2026-05-08T10:17:19.116153Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"fed19d9291630e09","cluster-id":"6ebf1e01320cc017"} {"level":"info","ts":"2026-05-08T10:17:19.116245Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 switched to configuration voters=()"} {"level":"info","ts":"2026-05-08T10:17:19.116267Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became follower at term 0"} {"level":"info","ts":"2026-05-08T10:17:19.116283Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft fed19d9291630e09 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-08T10:17:19.116294Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became follower at term 1"} {"level":"info","ts":"2026-05-08T10:17:19.116337Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 switched to configuration voters=(18361630408596196873)"} {"level":"warn","ts":"2026-05-08T10:17:19.124213Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-08T10:17:19.125271Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-08T10:17:19.126209Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-08T10:17:19.128011Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"fed19d9291630e09","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-08T10:17:19.130263Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-08T10:17:19.130327Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-08T10:17:19.130348Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-08T10:17:19.129261Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"fed19d9291630e09","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-08T10:17:19.130671Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 switched to configuration voters=(18361630408596196873)"} {"level":"info","ts":"2026-05-08T10:17:19.131234Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"6ebf1e01320cc017","local-member-id":"fed19d9291630e09","added-peer-id":"fed19d9291630e09","added-peer-peer-urls":["https://199.204.45.22:2380"]} {"level":"info","ts":"2026-05-08T10:17:19.132176Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-08T10:17:19.132301Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.22:2380"} {"level":"info","ts":"2026-05-08T10:17:19.132346Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.22:2380"} {"level":"info","ts":"2026-05-08T10:17:19.132464Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"fed19d9291630e09","initial-advertise-peer-urls":["https://199.204.45.22:2380"],"listen-peer-urls":["https://199.204.45.22:2380"],"advertise-client-urls":["https://199.204.45.22:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.22:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-08T10:17:19.132498Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-08T10:17:19.821339Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 is starting a new election at term 1"} {"level":"info","ts":"2026-05-08T10:17:19.821406Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-08T10:17:19.821454Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 received MsgPreVoteResp from fed19d9291630e09 at term 1"} {"level":"info","ts":"2026-05-08T10:17:19.821472Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became candidate at term 2"} {"level":"info","ts":"2026-05-08T10:17:19.821483Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 received MsgVoteResp from fed19d9291630e09 at term 2"} {"level":"info","ts":"2026-05-08T10:17:19.821496Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became leader at term 2"} {"level":"info","ts":"2026-05-08T10:17:19.821508Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: fed19d9291630e09 elected leader fed19d9291630e09 at term 2"} {"level":"info","ts":"2026-05-08T10:17:19.822412Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-08T10:17:19.825696Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-08T10:17:19.825667Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"fed19d9291630e09","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.22:2379]}","request-path":"/0/members/fed19d9291630e09/attributes","cluster-id":"6ebf1e01320cc017","publish-timeout":"7s"} {"level":"info","ts":"2026-05-08T10:17:19.825744Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-08T10:17:19.826962Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-08T10:17:19.827015Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-08T10:17:19.827016Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"6ebf1e01320cc017","local-member-id":"fed19d9291630e09","cluster-version":"3.5"} {"level":"info","ts":"2026-05-08T10:17:19.827107Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-08T10:17:19.827149Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-08T10:17:19.828676Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-08T10:17:19.829133Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.22:2379"} {"level":"info","ts":"2026-05-08T10:17:26.323645Z","caller":"traceutil/trace.go:171","msg":"trace[860405585] transaction","detail":"{read_only:false; response_revision:319; number_of_response:1; }","duration":"283.244231ms","start":"2026-05-08T10:17:26.040376Z","end":"2026-05-08T10:17:26.32362Z","steps":["trace[860405585] 'process raft request' (duration: 283.08852ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:17:26.838481Z","caller":"traceutil/trace.go:171","msg":"trace[232942790] linearizableReadLoop","detail":"{readStateIndex:332; appliedIndex:331; }","duration":"457.572136ms","start":"2026-05-08T10:17:26.380889Z","end":"2026-05-08T10:17:26.838461Z","steps":["trace[232942790] 'read index received' (duration: 457.033195ms)","trace[232942790] 'applied index is now lower than readState.Index' (duration: 538.231µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:17:26.838702Z","caller":"traceutil/trace.go:171","msg":"trace[952429729] transaction","detail":"{read_only:false; response_revision:320; number_of_response:1; }","duration":"505.122955ms","start":"2026-05-08T10:17:26.333572Z","end":"2026-05-08T10:17:26.838695Z","steps":["trace[952429729] 'process raft request' (duration: 504.292632ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:17:26.838993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:17:26.333553Z","time spent":"505.168599ms","remote":"127.0.0.1:33436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":194,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-08T10:17:26.839151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.259097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-08T10:17:26.839177Z","caller":"traceutil/trace.go:171","msg":"trace[548799310] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:320; }","duration":"458.339833ms","start":"2026-05-08T10:17:26.38083Z","end":"2026-05-08T10:17:26.83917Z","steps":["trace[548799310] 'agreement among raft nodes before linearized reading' (duration: 458.263037ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:17:26.839195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:17:26.380803Z","time spent":"458.388347ms","remote":"127.0.0.1:33492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-08T10:17:26.839286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.513398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-08T10:17:26.839301Z","caller":"traceutil/trace.go:171","msg":"trace[76873828] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:320; }","duration":"289.573992ms","start":"2026-05-08T10:17:26.549722Z","end":"2026-05-08T10:17:26.839296Z","steps":["trace[76873828] 'agreement among raft nodes before linearized reading' (duration: 289.541419ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:17:27.303802Z","caller":"traceutil/trace.go:171","msg":"trace[989478406] transaction","detail":"{read_only:false; response_revision:322; number_of_response:1; }","duration":"459.209ms","start":"2026-05-08T10:17:26.844569Z","end":"2026-05-08T10:17:27.303778Z","steps":["trace[989478406] 'process raft request' (duration: 459.130944ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:17:27.303786Z","caller":"traceutil/trace.go:171","msg":"trace[96213037] linearizableReadLoop","detail":"{readStateIndex:334; appliedIndex:332; }","duration":"459.046147ms","start":"2026-05-08T10:17:26.84471Z","end":"2026-05-08T10:17:27.303756Z","steps":["trace[96213037] 'read index received' (duration: 380.731288ms)","trace[96213037] 'applied index is now lower than readState.Index' (duration: 78.313749ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T10:17:27.303992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.220541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-05-08T10:17:27.304114Z","caller":"traceutil/trace.go:171","msg":"trace[1254913459] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:322; }","duration":"459.462959ms","start":"2026-05-08T10:17:26.844614Z","end":"2026-05-08T10:17:27.304077Z","steps":["trace[1254913459] 'agreement among raft nodes before linearized reading' (duration: 459.231041ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:17:27.304143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:17:26.844562Z","time spent":"459.304077ms","remote":"127.0.0.1:33492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T10:17:27.304184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:17:26.844597Z","time spent":"459.556426ms","remote":"127.0.0.1:33322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-05-08T10:17:27.304354Z","caller":"traceutil/trace.go:171","msg":"trace[1300620654] transaction","detail":"{read_only:false; response_revision:321; number_of_response:1; }","duration":"461.121635ms","start":"2026-05-08T10:17:26.843217Z","end":"2026-05-08T10:17:27.304339Z","steps":["trace[1300620654] 'process raft request' (duration: 382.220081ms)","trace[1300620654] 'compare' (duration: 78.107103ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T10:17:27.304458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:17:26.843207Z","time spent":"461.210351ms","remote":"127.0.0.1:33492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T10:17:29.06813Z","caller":"traceutil/trace.go:171","msg":"trace[1661364040] linearizableReadLoop","detail":"{readStateIndex:353; appliedIndex:352; }","duration":"128.376152ms","start":"2026-05-08T10:17:28.939729Z","end":"2026-05-08T10:17:29.068105Z","steps":["trace[1661364040] 'read index received' (duration: 127.232144ms)","trace[1661364040] 'applied index is now lower than readState.Index' (duration: 1.140908ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:17:29.068278Z","caller":"traceutil/trace.go:171","msg":"trace[1276662879] transaction","detail":"{read_only:false; response_revision:341; number_of_response:1; }","duration":"162.381228ms","start":"2026-05-08T10:17:28.905844Z","end":"2026-05-08T10:17:29.068225Z","steps":["trace[1276662879] 'process raft request' (duration: 161.299014ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:17:29.068319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.574418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-05-08T10:17:29.068366Z","caller":"traceutil/trace.go:171","msg":"trace[2075327269] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:341; }","duration":"128.672755ms","start":"2026-05-08T10:17:28.939683Z","end":"2026-05-08T10:17:29.068356Z","steps":["trace[2075327269] 'agreement among raft nodes before linearized reading' (duration: 128.547716ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:17:29.199255Z","caller":"traceutil/trace.go:171","msg":"trace[2090283966] linearizableReadLoop","detail":"{readStateIndex:355; appliedIndex:353; }","duration":"109.445823ms","start":"2026-05-08T10:17:29.089784Z","end":"2026-05-08T10:17:29.19923Z","steps":["trace[2090283966] 'read index received' (duration: 13.549901ms)","trace[2090283966] 'applied index is now lower than readState.Index' (duration: 95.894992ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T10:17:29.199603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.793299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-05-08T10:17:29.199674Z","caller":"traceutil/trace.go:171","msg":"trace[666381300] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:343; }","duration":"109.908358ms","start":"2026-05-08T10:17:29.089745Z","end":"2026-05-08T10:17:29.199653Z","steps":["trace[666381300] 'agreement among raft nodes before linearized reading' (duration: 109.790099ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:17:29.199857Z","caller":"traceutil/trace.go:171","msg":"trace[1891443578] transaction","detail":"{read_only:false; response_revision:343; number_of_response:1; }","duration":"121.977214ms","start":"2026-05-08T10:17:29.077851Z","end":"2026-05-08T10:17:29.199828Z","steps":["trace[1891443578] 'process raft request' (duration: 102.374194ms)","trace[1891443578] 'compare' (duration: 18.911876ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:17:29.862794Z","caller":"traceutil/trace.go:171","msg":"trace[297476084] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"292.720697ms","start":"2026-05-08T10:17:29.570042Z","end":"2026-05-08T10:17:29.862763Z","steps":["trace[297476084] 'process raft request' (duration: 214.0453ms)","trace[297476084] 'compare' (duration: 78.391516ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T10:17:29.862991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.875762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-08T10:17:29.862978Z","caller":"traceutil/trace.go:171","msg":"trace[1554434848] linearizableReadLoop","detail":"{readStateIndex:365; appliedIndex:363; }","duration":"275.542785ms","start":"2026-05-08T10:17:29.587104Z","end":"2026-05-08T10:17:29.862647Z","steps":["trace[1554434848] 'read index received' (duration: 1.358916ms)","trace[1554434848] 'applied index is now lower than readState.Index' (duration: 274.183009ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:17:29.863046Z","caller":"traceutil/trace.go:171","msg":"trace[167756978] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:353; }","duration":"275.976119ms","start":"2026-05-08T10:17:29.587056Z","end":"2026-05-08T10:17:29.863032Z","steps":["trace[167756978] 'agreement among raft nodes before linearized reading' (duration: 275.816547ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:17:29.863186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.87937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-05-08T10:17:29.863222Z","caller":"traceutil/trace.go:171","msg":"trace[562929829] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:353; }","duration":"272.956365ms","start":"2026-05-08T10:17:29.590255Z","end":"2026-05-08T10:17:29.863211Z","steps":["trace[562929829] 'agreement among raft nodes before linearized reading' (duration: 272.863958ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:17:29.863243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.148957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-05-08T10:17:29.863285Z","caller":"traceutil/trace.go:171","msg":"trace[564160454] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:353; }","duration":"122.235543ms","start":"2026-05-08T10:17:29.741036Z","end":"2026-05-08T10:17:29.863271Z","steps":["trace[564160454] 'agreement among raft nodes before linearized reading' (duration: 122.148336ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:17:29.863387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.662039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-08T10:17:29.863355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.672468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-05-08T10:17:29.863426Z","caller":"traceutil/trace.go:171","msg":"trace[602688521] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:353; }","duration":"257.745806ms","start":"2026-05-08T10:17:29.605671Z","end":"2026-05-08T10:17:29.863417Z","steps":["trace[602688521] 'agreement among raft nodes before linearized reading' (duration: 257.683231ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:17:29.863443Z","caller":"traceutil/trace.go:171","msg":"trace[1004041780] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:353; }","duration":"222.787966ms","start":"2026-05-08T10:17:29.640646Z","end":"2026-05-08T10:17:29.863433Z","steps":["trace[1004041780] 'agreement among raft nodes before linearized reading' (duration: 222.676468ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:17:29.863551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.705965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-05-08T10:17:29.863592Z","caller":"traceutil/trace.go:171","msg":"trace[831637688] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:353; }","duration":"172.778402ms","start":"2026-05-08T10:17:29.690804Z","end":"2026-05-08T10:17:29.863582Z","steps":["trace[831637688] 'agreement among raft nodes before linearized reading' (duration: 172.721356ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:17:30.027198Z","caller":"traceutil/trace.go:171","msg":"trace[908283393] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"157.194634ms","start":"2026-05-08T10:17:29.869972Z","end":"2026-05-08T10:17:30.027167Z","steps":["trace[908283393] 'process raft request' (duration: 139.779285ms)","trace[908283393] 'compare' (duration: 17.261007ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:17:30.027633Z","caller":"traceutil/trace.go:171","msg":"trace[1982685216] transaction","detail":"{read_only:false; response_revision:358; number_of_response:1; }","duration":"154.777238ms","start":"2026-05-08T10:17:29.87284Z","end":"2026-05-08T10:17:30.027617Z","steps":["trace[1982685216] 'process raft request' (duration: 154.573142ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:17:30.027665Z","caller":"traceutil/trace.go:171","msg":"trace[934164017] linearizableReadLoop","detail":"{readStateIndex:376; appliedIndex:366; }","duration":"127.811258ms","start":"2026-05-08T10:17:29.899836Z","end":"2026-05-08T10:17:30.027647Z","steps":["trace[934164017] 'read index received' (duration: 109.855156ms)","trace[934164017] 'applied index is now lower than readState.Index' (duration: 17.955502ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T10:17:30.027766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.908746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-05-08T10:17:30.027809Z","caller":"traceutil/trace.go:171","msg":"trace[1315675270] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:364; }","duration":"127.995252ms","start":"2026-05-08T10:17:29.899804Z","end":"2026-05-08T10:17:30.027799Z","steps":["trace[1315675270] 'agreement among raft nodes before linearized reading' (duration: 127.900915ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:17:30.02785Z","caller":"traceutil/trace.go:171","msg":"trace[1525930403] transaction","detail":"{read_only:false; response_revision:356; number_of_response:1; }","duration":"156.763832ms","start":"2026-05-08T10:17:29.871071Z","end":"2026-05-08T10:17:30.027834Z","steps":["trace[1525930403] 'process raft request' (duration: 156.060527ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:17:30.028064Z","caller":"traceutil/trace.go:171","msg":"trace[1695019316] transaction","detail":"{read_only:false; response_revision:359; number_of_response:1; }","duration":"154.840383ms","start":"2026-05-08T10:17:29.873212Z","end":"2026-05-08T10:17:30.028053Z","steps":["trace[1695019316] 'process raft request' (duration: 154.235047ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:17:30.028121Z","caller":"traceutil/trace.go:171","msg":"trace[126059958] transaction","detail":"{read_only:false; response_revision:357; number_of_response:1; }","duration":"156.567387ms","start":"2026-05-08T10:17:29.871539Z","end":"2026-05-08T10:17:30.028107Z","steps":["trace[126059958] 'process raft request' (duration: 155.831389ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:17:30.028201Z","caller":"traceutil/trace.go:171","msg":"trace[1266202425] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"153.272902ms","start":"2026-05-08T10:17:29.874918Z","end":"2026-05-08T10:17:30.028191Z","steps":["trace[1266202425] 'process raft request' (duration: 152.567906ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:17:30.028464Z","caller":"traceutil/trace.go:171","msg":"trace[91652470] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"152.285234ms","start":"2026-05-08T10:17:29.876166Z","end":"2026-05-08T10:17:30.028451Z","steps":["trace[91652470] 'process raft request' (duration: 151.357122ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:17:30.028631Z","caller":"traceutil/trace.go:171","msg":"trace[432554398] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"152.373391ms","start":"2026-05-08T10:17:29.876246Z","end":"2026-05-08T10:17:30.028619Z","steps":["trace[432554398] 'process raft request' (duration: 151.308949ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:17:30.029101Z","caller":"traceutil/trace.go:171","msg":"trace[877417282] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"151.754505ms","start":"2026-05-08T10:17:29.877337Z","end":"2026-05-08T10:17:30.029091Z","steps":["trace[877417282] 'process raft request' (duration: 150.28071ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:17:30.029311Z","caller":"traceutil/trace.go:171","msg":"trace[1243611904] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"152.727249ms","start":"2026-05-08T10:17:29.876516Z","end":"2026-05-08T10:17:30.029243Z","steps":["trace[1243611904] 'process raft request' (duration: 151.06848ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:17:30.140264Z","caller":"traceutil/trace.go:171","msg":"trace[742585512] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"102.192126ms","start":"2026-05-08T10:17:30.038049Z","end":"2026-05-08T10:17:30.140241Z","steps":["trace[742585512] 'process raft request' (duration: 101.999321ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:17:30.140449Z","caller":"traceutil/trace.go:171","msg":"trace[677082071] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"102.916263ms","start":"2026-05-08T10:17:30.037523Z","end":"2026-05-08T10:17:30.140439Z","steps":["trace[677082071] 'process raft request' (duration: 93.069515ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:17:30.14045Z","caller":"traceutil/trace.go:171","msg":"trace[766556925] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"100.904886ms","start":"2026-05-08T10:17:30.039528Z","end":"2026-05-08T10:17:30.140433Z","steps":["trace[766556925] 'process raft request' (duration: 100.586892ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:17:31.077072Z","caller":"traceutil/trace.go:171","msg":"trace[1117823336] transaction","detail":"{read_only:false; response_revision:389; number_of_response:1; }","duration":"130.722702ms","start":"2026-05-08T10:17:30.946327Z","end":"2026-05-08T10:17:31.077049Z","steps":["trace[1117823336] 'process raft request' (duration: 86.559252ms)","trace[1117823336] 'compare' (duration: 43.931571ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:17:31.567273Z","caller":"traceutil/trace.go:171","msg":"trace[1949927077] transaction","detail":"{read_only:false; response_revision:391; number_of_response:1; }","duration":"167.784527ms","start":"2026-05-08T10:17:31.39946Z","end":"2026-05-08T10:17:31.567245Z","steps":["trace[1949927077] 'process raft request' (duration: 144.451543ms)","trace[1949927077] 'compare' (duration: 23.199554ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:17:31.849715Z","caller":"traceutil/trace.go:171","msg":"trace[1453184769] transaction","detail":"{read_only:false; response_revision:393; number_of_response:1; }","duration":"193.15596ms","start":"2026-05-08T10:17:31.656535Z","end":"2026-05-08T10:17:31.849691Z","steps":["trace[1453184769] 'process raft request' (duration: 192.975676ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:17:32.195026Z","caller":"traceutil/trace.go:171","msg":"trace[633365823] linearizableReadLoop","detail":"{readStateIndex:407; appliedIndex:406; }","duration":"294.513965ms","start":"2026-05-08T10:17:31.900483Z","end":"2026-05-08T10:17:32.194997Z","steps":["trace[633365823] 'read index received' (duration: 294.190308ms)","trace[633365823] 'applied index is now lower than readState.Index' (duration: 322.306µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:17:32.195055Z","caller":"traceutil/trace.go:171","msg":"trace[1282349272] transaction","detail":"{read_only:false; response_revision:394; number_of_response:1; }","duration":"335.413178ms","start":"2026-05-08T10:17:31.859617Z","end":"2026-05-08T10:17:32.19503Z","steps":["trace[1282349272] 'process raft request' (duration: 335.173018ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:17:32.195394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:17:31.859595Z","time spent":"335.751816ms","remote":"127.0.0.1:33690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2903,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T10:17:32.195463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.809703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-08T10:17:32.195173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.678427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-08T10:17:32.195558Z","caller":"traceutil/trace.go:171","msg":"trace[662793971] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:394; }","duration":"295.092379ms","start":"2026-05-08T10:17:31.900431Z","end":"2026-05-08T10:17:32.195523Z","steps":["trace[662793971] 'agreement among raft nodes before linearized reading' (duration: 294.672107ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:17:32.19556Z","caller":"traceutil/trace.go:171","msg":"trace[790753630] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:394; }","duration":"159.90361ms","start":"2026-05-08T10:17:32.035598Z","end":"2026-05-08T10:17:32.195502Z","steps":["trace[790753630] 'agreement among raft nodes before linearized reading' (duration: 159.742787ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:17:38.888675Z","caller":"traceutil/trace.go:171","msg":"trace[925282134] transaction","detail":"{read_only:false; response_revision:448; number_of_response:1; }","duration":"254.925127ms","start":"2026-05-08T10:17:38.633721Z","end":"2026-05-08T10:17:38.888646Z","steps":["trace[925282134] 'process raft request' (duration: 189.204365ms)","trace[925282134] 'compare' (duration: 65.596172ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T10:17:39.767246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.628294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-08T10:17:39.767335Z","caller":"traceutil/trace.go:171","msg":"trace[525048716] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:450; }","duration":"131.794959ms","start":"2026-05-08T10:17:39.635519Z","end":"2026-05-08T10:17:39.767314Z","steps":["trace[525048716] 'range keys from in-memory index tree' (duration: 131.485613ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:17:51.566048Z","caller":"traceutil/trace.go:171","msg":"trace[2115578206] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"120.15551ms","start":"2026-05-08T10:17:51.445861Z","end":"2026-05-08T10:17:51.566017Z","steps":["trace[2115578206] 'process raft request' (duration: 108.038164ms)","trace[2115578206] 'compare' (duration: 11.813561ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:17:51.566131Z","caller":"traceutil/trace.go:171","msg":"trace[121854715] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"114.519972ms","start":"2026-05-08T10:17:51.451577Z","end":"2026-05-08T10:17:51.566097Z","steps":["trace[121854715] 'process raft request' (duration: 114.345757ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:17:51.566068Z","caller":"traceutil/trace.go:171","msg":"trace[379296558] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"120.146859ms","start":"2026-05-08T10:17:51.445903Z","end":"2026-05-08T10:17:51.566049Z","steps":["trace[379296558] 'process raft request' (duration: 119.963104ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:17:51.566369Z","caller":"traceutil/trace.go:171","msg":"trace[1787796842] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"104.510939ms","start":"2026-05-08T10:17:51.461844Z","end":"2026-05-08T10:17:51.566355Z","steps":["trace[1787796842] 'process raft request' (duration: 104.204362ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:17:51.566488Z","caller":"traceutil/trace.go:171","msg":"trace[1343091104] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"104.353705ms","start":"2026-05-08T10:17:51.462126Z","end":"2026-05-08T10:17:51.56648Z","steps":["trace[1343091104] 'process raft request' (duration: 104.066799ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:17:51.566483Z","caller":"traceutil/trace.go:171","msg":"trace[733117792] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"113.646668ms","start":"2026-05-08T10:17:51.452722Z","end":"2026-05-08T10:17:51.566369Z","steps":["trace[733117792] 'process raft request' (duration: 113.248193ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:17:51.566558Z","caller":"traceutil/trace.go:171","msg":"trace[1384963460] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"104.460974ms","start":"2026-05-08T10:17:51.46209Z","end":"2026-05-08T10:17:51.566551Z","steps":["trace[1384963460] 'process raft request' (duration: 104.001084ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:17:51.620369Z","caller":"traceutil/trace.go:171","msg":"trace[906211088] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"101.992492ms","start":"2026-05-08T10:17:51.518358Z","end":"2026-05-08T10:17:51.620351Z","steps":["trace[906211088] 'process raft request' (duration: 101.711187ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:17:51.62053Z","caller":"traceutil/trace.go:171","msg":"trace[832709285] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"100.739503ms","start":"2026-05-08T10:17:51.519748Z","end":"2026-05-08T10:17:51.620487Z","steps":["trace[832709285] 'process raft request' (duration: 100.421025ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:17:51.826025Z","caller":"traceutil/trace.go:171","msg":"trace[113131699] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"205.314247ms","start":"2026-05-08T10:17:51.620675Z","end":"2026-05-08T10:17:51.825989Z","steps":["trace[113131699] 'process raft request' (duration: 201.835026ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:17:51.826248Z","caller":"traceutil/trace.go:171","msg":"trace[400507507] transaction","detail":"{read_only:false; number_of_response:0; response_revision:532; }","duration":"202.472741ms","start":"2026-05-08T10:17:51.623767Z","end":"2026-05-08T10:17:51.826239Z","steps":["trace[400507507] 'process raft request' (duration: 202.127351ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:17:51.826423Z","caller":"traceutil/trace.go:171","msg":"trace[1252409945] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"184.63034ms","start":"2026-05-08T10:17:51.64175Z","end":"2026-05-08T10:17:51.82638Z","steps":["trace[1252409945] 'process raft request' (duration: 184.522461ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:17:51.826504Z","caller":"traceutil/trace.go:171","msg":"trace[1581269444] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"196.581733ms","start":"2026-05-08T10:17:51.629901Z","end":"2026-05-08T10:17:51.826483Z","steps":["trace[1581269444] 'process raft request' (duration: 196.179039ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:17:51.826515Z","caller":"traceutil/trace.go:171","msg":"trace[342444634] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"200.958831ms","start":"2026-05-08T10:17:51.625511Z","end":"2026-05-08T10:17:51.82647Z","steps":["trace[342444634] 'process raft request' (duration: 200.460317ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:17:51.836734Z","caller":"traceutil/trace.go:171","msg":"trace[1244987635] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"162.001685ms","start":"2026-05-08T10:17:51.674717Z","end":"2026-05-08T10:17:51.836718Z","steps":["trace[1244987635] 'process raft request' (duration: 161.858993ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:17:51.836835Z","caller":"traceutil/trace.go:171","msg":"trace[2055172120] transaction","detail":"{read_only:false; number_of_response:0; response_revision:535; }","duration":"173.805035ms","start":"2026-05-08T10:17:51.663011Z","end":"2026-05-08T10:17:51.836816Z","steps":["trace[2055172120] 'process raft request' (duration: 173.354926ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:17:51.837113Z","caller":"traceutil/trace.go:171","msg":"trace[943179410] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"173.903303ms","start":"2026-05-08T10:17:51.663203Z","end":"2026-05-08T10:17:51.837106Z","steps":["trace[943179410] 'process raft request' (duration: 173.252417ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:17:51.837208Z","caller":"traceutil/trace.go:171","msg":"trace[691098316] transaction","detail":"{read_only:false; number_of_response:0; response_revision:535; }","duration":"174.09698ms","start":"2026-05-08T10:17:51.663105Z","end":"2026-05-08T10:17:51.837202Z","steps":["trace[691098316] 'process raft request' (duration: 173.309372ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:17:51.8372Z","caller":"traceutil/trace.go:171","msg":"trace[1728710151] transaction","detail":"{read_only:false; number_of_response:1; response_revision:536; }","duration":"170.220055ms","start":"2026-05-08T10:17:51.666975Z","end":"2026-05-08T10:17:51.837195Z","steps":["trace[1728710151] 'process raft request' (duration: 169.536606ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:17:51.837344Z","caller":"traceutil/trace.go:171","msg":"trace[141540697] transaction","detail":"{read_only:false; number_of_response:0; response_revision:535; }","duration":"174.148805ms","start":"2026-05-08T10:17:51.663175Z","end":"2026-05-08T10:17:51.837324Z","steps":["trace[141540697] 'process raft request' (duration: 173.258487ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:17:51.837456Z","caller":"traceutil/trace.go:171","msg":"trace[1729254631] transaction","detail":"{read_only:false; number_of_response:0; response_revision:536; }","duration":"168.980008ms","start":"2026-05-08T10:17:51.668468Z","end":"2026-05-08T10:17:51.837448Z","steps":["trace[1729254631] 'process raft request' (duration: 168.070489ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:17:51.837674Z","caller":"traceutil/trace.go:171","msg":"trace[356749908] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"161.812109ms","start":"2026-05-08T10:17:51.675829Z","end":"2026-05-08T10:17:51.837641Z","steps":["trace[356749908] 'process raft request' (duration: 160.812143ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:17:53.471297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.083398ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-05-08T10:17:53.471396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:17:52.73074Z","time spent":"740.650953ms","remote":"127.0.0.1:33242","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-08T10:17:53.474908Z","caller":"traceutil/trace.go:171","msg":"trace[576114153] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"734.118798ms","start":"2026-05-08T10:17:52.740754Z","end":"2026-05-08T10:17:53.474873Z","steps":["trace[576114153] 'process raft request' (duration: 733.729904ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:17:53.475081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:17:52.740733Z","time spent":"734.25133ms","remote":"127.0.0.1:33266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":14661,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-08T10:17:53.477009Z","caller":"traceutil/trace.go:171","msg":"trace[950352081] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"599.401383ms","start":"2026-05-08T10:17:52.877586Z","end":"2026-05-08T10:17:53.476988Z","steps":["trace[950352081] 'process raft request' (duration: 598.405706ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:17:53.477188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:17:52.877558Z","time spent":"599.499202ms","remote":"127.0.0.1:33266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570081,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T10:17:53.477355Z","caller":"traceutil/trace.go:171","msg":"trace[1219930521] linearizableReadLoop","detail":"{readStateIndex:610; appliedIndex:607; }","duration":"330.189493ms","start":"2026-05-08T10:17:53.147153Z","end":"2026-05-08T10:17:53.477343Z","steps":["trace[1219930521] 'read index received' (duration: 2.06933ms)","trace[1219930521] 'applied index is now lower than readState.Index' (duration: 328.118533ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T10:17:53.477428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.272781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T10:17:53.477452Z","caller":"traceutil/trace.go:171","msg":"trace[363681153] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:585; }","duration":"330.345227ms","start":"2026-05-08T10:17:53.1471Z","end":"2026-05-08T10:17:53.477445Z","steps":["trace[363681153] 'agreement among raft nodes before linearized reading' (duration: 330.280212ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:17:53.477474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:17:53.147078Z","time spent":"330.392041ms","remote":"127.0.0.1:33200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-08T10:17:53.77795Z","caller":"traceutil/trace.go:171","msg":"trace[1063121371] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"123.454881ms","start":"2026-05-08T10:17:53.654463Z","end":"2026-05-08T10:17:53.777918Z","steps":["trace[1063121371] 'process raft request' (duration: 123.150425ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:17:53.897886Z","caller":"traceutil/trace.go:171","msg":"trace[1816135786] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"219.960102ms","start":"2026-05-08T10:17:53.677894Z","end":"2026-05-08T10:17:53.897854Z","steps":["trace[1816135786] 'process raft request' (duration: 196.662676ms)","trace[1816135786] 'compare' (duration: 22.985338ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T10:17:54.122232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.537877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/httproutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:151236"} {"level":"info","ts":"2026-05-08T10:17:54.122291Z","caller":"traceutil/trace.go:171","msg":"trace[472285342] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/httproutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:593; }","duration":"114.638676ms","start":"2026-05-08T10:17:54.007637Z","end":"2026-05-08T10:17:54.122276Z","steps":["trace[472285342] 'range keys from in-memory index tree' (duration: 114.396613ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:17:55.273244Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011513344617091317,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-08T10:17:55.768513Z","caller":"traceutil/trace.go:171","msg":"trace[250940877] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"1.004596069s","start":"2026-05-08T10:17:54.763891Z","end":"2026-05-08T10:17:55.768487Z","steps":["trace[250940877] 'process raft request' (duration: 1.004449226s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:17:55.768662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:17:54.763869Z","time spent":"1.00471488s","remote":"127.0.0.1:33266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9296,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T10:17:55.785256Z","caller":"traceutil/trace.go:171","msg":"trace[291034803] linearizableReadLoop","detail":"{readStateIndex:640; appliedIndex:636; }","duration":"1.012061375s","start":"2026-05-08T10:17:54.773181Z","end":"2026-05-08T10:17:55.785243Z","steps":["trace[291034803] 'read index received' (duration: 995.218092ms)","trace[291034803] 'applied index is now lower than readState.Index' (duration: 16.842353ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:17:55.785339Z","caller":"traceutil/trace.go:171","msg":"trace[432557830] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"370.354733ms","start":"2026-05-08T10:17:55.414978Z","end":"2026-05-08T10:17:55.785332Z","steps":["trace[432557830] 'process raft request' (duration: 370.131662ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:17:55.78539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:17:55.414952Z","time spent":"370.401857ms","remote":"127.0.0.1:33404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4558,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T10:17:55.785553Z","caller":"traceutil/trace.go:171","msg":"trace[1766505625] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"304.694801ms","start":"2026-05-08T10:17:55.480851Z","end":"2026-05-08T10:17:55.785546Z","steps":["trace[1766505625] 'process raft request' (duration: 304.337709ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:17:55.785591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:17:55.480832Z","time spent":"304.740635ms","remote":"127.0.0.1:33266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12244,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-08T10:17:55.785543Z","caller":"traceutil/trace.go:171","msg":"trace[1775543002] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"559.208436ms","start":"2026-05-08T10:17:55.226309Z","end":"2026-05-08T10:17:55.785517Z","steps":["trace[1775543002] 'process raft request' (duration: 558.741905ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:17:55.785673Z","caller":"traceutil/trace.go:171","msg":"trace[2106103641] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"103.956369ms","start":"2026-05-08T10:17:55.681712Z","end":"2026-05-08T10:17:55.785668Z","steps":["trace[2106103641] 'process raft request' (duration: 103.50749ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:17:55.785805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.012621394s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-bwvx7\" ","response":"range_response_count:1 size:4884"} {"level":"info","ts":"2026-05-08T10:17:55.78583Z","caller":"traceutil/trace.go:171","msg":"trace[1547606332] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-869df985b8-bwvx7; range_end:; response_count:1; response_revision:617; }","duration":"1.012676519s","start":"2026-05-08T10:17:54.773148Z","end":"2026-05-08T10:17:55.785824Z","steps":["trace[1547606332] 'agreement among raft nodes before linearized reading' (duration: 1.012588801s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:17:55.785849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:17:54.773132Z","time spent":"1.012712692s","remote":"127.0.0.1:33418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":4907,"request content":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-bwvx7\" "} {"level":"info","ts":"2026-05-08T10:17:55.785909Z","caller":"traceutil/trace.go:171","msg":"trace[1671570634] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"508.770495ms","start":"2026-05-08T10:17:55.277126Z","end":"2026-05-08T10:17:55.785896Z","steps":["trace[1671570634] 'process raft request' (duration: 507.964265ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:17:55.785907Z","caller":"traceutil/trace.go:171","msg":"trace[813307805] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"906.71796ms","start":"2026-05-08T10:17:54.879135Z","end":"2026-05-08T10:17:55.785853Z","steps":["trace[813307805] 'process raft request' (duration: 905.830193ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:17:55.785986Z","caller":"traceutil/trace.go:171","msg":"trace[1094327504] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"708.978221ms","start":"2026-05-08T10:17:55.076995Z","end":"2026-05-08T10:17:55.785974Z","steps":["trace[1094327504] 'process raft request' (duration: 708.020217ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:17:55.786027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:17:55.076978Z","time spent":"709.030306ms","remote":"127.0.0.1:33266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4580,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-08T10:17:55.786026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:17:55.277099Z","time spent":"508.868433ms","remote":"127.0.0.1:33266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3353,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-08T10:17:55.785797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:17:55.226283Z","time spent":"559.388392ms","remote":"127.0.0.1:33492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T10:17:55.78609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:17:54.879119Z","time spent":"906.879135ms","remote":"127.0.0.1:33266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3104,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-08T10:17:55.856905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"714.358992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T10:17:55.856951Z","caller":"traceutil/trace.go:171","msg":"trace[92530150] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:618; }","duration":"714.44716ms","start":"2026-05-08T10:17:55.142495Z","end":"2026-05-08T10:17:55.856942Z","steps":["trace[92530150] 'agreement among raft nodes before linearized reading' (duration: 714.368623ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:17:55.856977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:17:55.142476Z","time spent":"714.490204ms","remote":"127.0.0.1:33188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-08T10:17:55.971857Z","caller":"traceutil/trace.go:171","msg":"trace[718307593] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"101.381155ms","start":"2026-05-08T10:17:55.870455Z","end":"2026-05-08T10:17:55.971836Z","steps":["trace[718307593] 'process raft request' (duration: 101.199159ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:17:55.9719Z","caller":"traceutil/trace.go:171","msg":"trace[1042432670] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"100.490328ms","start":"2026-05-08T10:17:55.871389Z","end":"2026-05-08T10:17:55.971879Z","steps":["trace[1042432670] 'process raft request' (duration: 100.370677ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:17:56.270698Z","caller":"traceutil/trace.go:171","msg":"trace[1962345516] linearizableReadLoop","detail":"{readStateIndex:659; appliedIndex:658; }","duration":"255.869426ms","start":"2026-05-08T10:17:56.014802Z","end":"2026-05-08T10:17:56.270672Z","steps":["trace[1962345516] 'read index received' (duration: 255.674768ms)","trace[1962345516] 'applied index is now lower than readState.Index' (duration: 193.867µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:17:56.270813Z","caller":"traceutil/trace.go:171","msg":"trace[162893054] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"257.87544ms","start":"2026-05-08T10:17:56.012916Z","end":"2026-05-08T10:17:56.270792Z","steps":["trace[162893054] 'process raft request' (duration: 257.556521ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:17:56.270834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.009857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-leader-election-rolebinding\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T10:17:56.270872Z","caller":"traceutil/trace.go:171","msg":"trace[1499200234] range","detail":"{range_begin:/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-leader-election-rolebinding; range_end:; response_count:0; response_revision:632; }","duration":"256.084374ms","start":"2026-05-08T10:17:56.014774Z","end":"2026-05-08T10:17:56.270859Z","steps":["trace[1499200234] 'agreement among raft nodes before linearized reading' (duration: 256.010627ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:17:56.583066Z","caller":"traceutil/trace.go:171","msg":"trace[716789794] linearizableReadLoop","detail":"{readStateIndex:660; appliedIndex:659; }","duration":"312.28155ms","start":"2026-05-08T10:17:56.270765Z","end":"2026-05-08T10:17:56.583047Z","steps":["trace[716789794] 'read index received' (duration: 245.910731ms)","trace[716789794] 'applied index is now lower than readState.Index' (duration: 66.370159ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:17:56.58311Z","caller":"traceutil/trace.go:171","msg":"trace[1416297276] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"562.37911ms","start":"2026-05-08T10:17:56.020693Z","end":"2026-05-08T10:17:56.583072Z","steps":["trace[1416297276] 'process raft request' (duration: 495.88809ms)","trace[1416297276] 'compare' (duration: 66.390941ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T10:17:56.583215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.487694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-08T10:17:56.583251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:17:56.020676Z","time spent":"562.5025ms","remote":"127.0.0.1:33266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13214,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T10:17:56.58324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.219602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T10:17:56.583346Z","caller":"traceutil/trace.go:171","msg":"trace[317220664] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:633; }","duration":"507.356074ms","start":"2026-05-08T10:17:56.075963Z","end":"2026-05-08T10:17:56.583319Z","steps":["trace[317220664] 'agreement among raft nodes before linearized reading' (duration: 507.174388ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:17:56.583419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:17:56.075948Z","time spent":"507.455533ms","remote":"127.0.0.1:33266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"info","ts":"2026-05-08T10:17:56.58328Z","caller":"traceutil/trace.go:171","msg":"trace[1058111250] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:633; }","duration":"439.576922ms","start":"2026-05-08T10:17:56.14369Z","end":"2026-05-08T10:17:56.583267Z","steps":["trace[1058111250] 'agreement among raft nodes before linearized reading' (duration: 439.443191ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:17:56.583585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:17:56.143678Z","time spent":"439.867028ms","remote":"127.0.0.1:33200","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-05-08T10:17:56.588176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.636113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-08T10:17:56.588236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.567841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:1 size:5177"} {"level":"info","ts":"2026-05-08T10:17:56.588239Z","caller":"traceutil/trace.go:171","msg":"trace[1190943749] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:634; }","duration":"225.699929ms","start":"2026-05-08T10:17:56.362521Z","end":"2026-05-08T10:17:56.588221Z","steps":["trace[1190943749] 'agreement among raft nodes before linearized reading' (duration: 225.576198ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:17:56.588275Z","caller":"traceutil/trace.go:171","msg":"trace[263090358] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:634; }","duration":"107.661949ms","start":"2026-05-08T10:17:56.4806Z","end":"2026-05-08T10:17:56.588262Z","steps":["trace[263090358] 'agreement among raft nodes before linearized reading' (duration: 107.533728ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:17:56.58843Z","caller":"traceutil/trace.go:171","msg":"trace[1861691600] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"310.158085ms","start":"2026-05-08T10:17:56.278252Z","end":"2026-05-08T10:17:56.588411Z","steps":["trace[1861691600] 'process raft request' (duration: 309.703845ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:17:56.588452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.758403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-08T10:17:56.588483Z","caller":"traceutil/trace.go:171","msg":"trace[64524519] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:634; }","duration":"217.83625ms","start":"2026-05-08T10:17:56.370636Z","end":"2026-05-08T10:17:56.588473Z","steps":["trace[64524519] 'agreement among raft nodes before linearized reading' (duration: 217.783696ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:17:56.588496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:17:56.278236Z","time spent":"310.21727ms","remote":"127.0.0.1:33266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9343,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-08T10:17:56.588733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.171284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/envoy-gateway-system/envoy-gateway\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T10:17:56.58879Z","caller":"traceutil/trace.go:171","msg":"trace[785885123] range","detail":"{range_begin:/registry/services/specs/envoy-gateway-system/envoy-gateway; range_end:; response_count:0; response_revision:634; }","duration":"315.278323ms","start":"2026-05-08T10:17:56.273498Z","end":"2026-05-08T10:17:56.588776Z","steps":["trace[785885123] 'agreement among raft nodes before linearized reading' (duration: 315.134551ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:17:56.58879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.494439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-08T10:17:56.588829Z","caller":"traceutil/trace.go:171","msg":"trace[966369129] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:634; }","duration":"282.567955ms","start":"2026-05-08T10:17:56.306251Z","end":"2026-05-08T10:17:56.588819Z","steps":["trace[966369129] 'agreement among raft nodes before linearized reading' (duration: 282.471767ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:17:56.588837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:17:56.273481Z","time spent":"315.348459ms","remote":"127.0.0.1:33424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":0,"response size":28,"request content":"key:\"/registry/services/specs/envoy-gateway-system/envoy-gateway\" "} {"level":"info","ts":"2026-05-08T10:17:56.799631Z","caller":"traceutil/trace.go:171","msg":"trace[860717573] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"199.930639ms","start":"2026-05-08T10:17:56.599679Z","end":"2026-05-08T10:17:56.79961Z","steps":["trace[860717573] 'process raft request' (duration: 199.522704ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:17:56.807198Z","caller":"traceutil/trace.go:171","msg":"trace[1958234840] linearizableReadLoop","detail":"{readStateIndex:666; appliedIndex:664; }","duration":"200.399881ms","start":"2026-05-08T10:17:56.60678Z","end":"2026-05-08T10:17:56.807179Z","steps":["trace[1958234840] 'read index received' (duration: 192.438553ms)","trace[1958234840] 'applied index is now lower than readState.Index' (duration: 7.960518ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T10:17:56.807403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.582887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/envoy-gateway-system/\" range_end:\"/registry/resourcequotas/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T10:17:56.807442Z","caller":"traceutil/trace.go:171","msg":"trace[879191709] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"206.234342ms","start":"2026-05-08T10:17:56.60119Z","end":"2026-05-08T10:17:56.807425Z","steps":["trace[879191709] 'process raft request' (duration: 205.826777ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:17:56.807447Z","caller":"traceutil/trace.go:171","msg":"trace[174456706] range","detail":"{range_begin:/registry/resourcequotas/envoy-gateway-system/; range_end:/registry/resourcequotas/envoy-gateway-system0; response_count:0; response_revision:639; }","duration":"200.708187ms","start":"2026-05-08T10:17:56.606729Z","end":"2026-05-08T10:17:56.807437Z","steps":["trace[174456706] 'agreement among raft nodes before linearized reading' (duration: 200.580406ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:17:56.807669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.170492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:1 size:4180"} {"level":"info","ts":"2026-05-08T10:17:56.807735Z","caller":"traceutil/trace.go:171","msg":"trace[1049249094] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:639; }","duration":"131.285562ms","start":"2026-05-08T10:17:56.676414Z","end":"2026-05-08T10:17:56.807699Z","steps":["trace[1049249094] 'agreement among raft nodes before linearized reading' (duration: 130.984635ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:18:03.47206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"742.189962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.22\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-05-08T10:18:03.472143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.915144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T10:18:03.472164Z","caller":"traceutil/trace.go:171","msg":"trace[2070547386] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:690; }","duration":"324.96476ms","start":"2026-05-08T10:18:03.147194Z","end":"2026-05-08T10:18:03.472158Z","steps":["trace[2070547386] 'range keys from in-memory index tree' (duration: 324.849007ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:18:03.472196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:18:03.147169Z","time spent":"325.022385ms","remote":"127.0.0.1:33188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-08T10:18:03.472134Z","caller":"traceutil/trace.go:171","msg":"trace[181539245] range","detail":"{range_begin:/registry/masterleases/199.204.45.22; range_end:; response_count:1; response_revision:690; }","duration":"742.306702ms","start":"2026-05-08T10:18:02.729811Z","end":"2026-05-08T10:18:03.472118Z","steps":["trace[181539245] 'range keys from in-memory index tree' (duration: 742.051131ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:18:03.472271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:18:02.729785Z","time spent":"742.478669ms","remote":"127.0.0.1:33242","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.22\" "} {"level":"info","ts":"2026-05-08T10:18:04.30709Z","caller":"traceutil/trace.go:171","msg":"trace[679424546] linearizableReadLoop","detail":"{readStateIndex:722; appliedIndex:721; }","duration":"161.549787ms","start":"2026-05-08T10:18:04.145497Z","end":"2026-05-08T10:18:04.307047Z","steps":["trace[679424546] 'read index received' (duration: 113.980456ms)","trace[679424546] 'applied index is now lower than readState.Index' (duration: 47.568041ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T10:18:04.307239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.717772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T10:18:04.30725Z","caller":"traceutil/trace.go:171","msg":"trace[545205278] transaction","detail":"{read_only:false; response_revision:693; number_of_response:1; }","duration":"264.376315ms","start":"2026-05-08T10:18:04.042851Z","end":"2026-05-08T10:18:04.307227Z","steps":["trace[545205278] 'process raft request' (duration: 216.739368ms)","trace[545205278] 'compare' (duration: 47.236372ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:18:04.307284Z","caller":"traceutil/trace.go:171","msg":"trace[1637623298] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:693; }","duration":"161.795449ms","start":"2026-05-08T10:18:04.145474Z","end":"2026-05-08T10:18:04.30727Z","steps":["trace[1637623298] 'agreement among raft nodes before linearized reading' (duration: 161.703881ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:18:05.0109Z","caller":"traceutil/trace.go:171","msg":"trace[677499688] transaction","detail":"{read_only:false; response_revision:694; number_of_response:1; }","duration":"383.315665ms","start":"2026-05-08T10:18:04.627558Z","end":"2026-05-08T10:18:05.010873Z","steps":["trace[677499688] 'process raft request' (duration: 383.116428ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:18:05.011044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:18:04.62754Z","time spent":"383.432946ms","remote":"127.0.0.1:33492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T10:18:05.375761Z","caller":"traceutil/trace.go:171","msg":"trace[430200512] linearizableReadLoop","detail":"{readStateIndex:724; appliedIndex:722; }","duration":"742.768883ms","start":"2026-05-08T10:18:04.632973Z","end":"2026-05-08T10:18:05.375742Z","steps":["trace[430200512] 'read index received' (duration: 377.814354ms)","trace[430200512] 'applied index is now lower than readState.Index' (duration: 364.953759ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:18:05.375997Z","caller":"traceutil/trace.go:171","msg":"trace[1185042471] transaction","detail":"{read_only:false; response_revision:695; number_of_response:1; }","duration":"744.62454ms","start":"2026-05-08T10:18:04.631364Z","end":"2026-05-08T10:18:05.375988Z","steps":["trace[1185042471] 'process raft request' (duration: 744.255696ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:18:05.376092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:18:04.631337Z","time spent":"744.686546ms","remote":"127.0.0.1:33492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T10:18:05.376102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"743.119585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-08T10:18:05.376103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.159049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T10:18:05.376202Z","caller":"traceutil/trace.go:171","msg":"trace[651082387] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:695; }","duration":"230.313322ms","start":"2026-05-08T10:18:05.145877Z","end":"2026-05-08T10:18:05.376191Z","steps":["trace[651082387] 'agreement among raft nodes before linearized reading' (duration: 230.127546ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:18:05.376158Z","caller":"traceutil/trace.go:171","msg":"trace[1974854340] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:695; }","duration":"743.225334ms","start":"2026-05-08T10:18:04.63292Z","end":"2026-05-08T10:18:05.376145Z","steps":["trace[1974854340] 'agreement among raft nodes before linearized reading' (duration: 743.026647ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:18:05.376291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:18:04.632901Z","time spent":"743.381889ms","remote":"127.0.0.1:33492","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-05-08T10:18:05.511284Z","caller":"traceutil/trace.go:171","msg":"trace[27905435] transaction","detail":"{read_only:false; response_revision:696; number_of_response:1; }","duration":"131.214849ms","start":"2026-05-08T10:18:05.38004Z","end":"2026-05-08T10:18:05.511255Z","steps":["trace[27905435] 'process raft request' (duration: 96.394101ms)","trace[27905435] 'compare' (duration: 34.697967ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:18:09.668933Z","caller":"traceutil/trace.go:171","msg":"trace[1312949036] transaction","detail":"{read_only:false; response_revision:723; number_of_response:1; }","duration":"139.365723ms","start":"2026-05-08T10:18:09.529544Z","end":"2026-05-08T10:18:09.668909Z","steps":["trace[1312949036] 'process raft request' (duration: 139.209389ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:18:09.895728Z","caller":"traceutil/trace.go:171","msg":"trace[859415112] transaction","detail":"{read_only:false; response_revision:725; number_of_response:1; }","duration":"150.497508ms","start":"2026-05-08T10:18:09.7452Z","end":"2026-05-08T10:18:09.895697Z","steps":["trace[859415112] 'process raft request' (duration: 149.889023ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:18:10.23411Z","caller":"traceutil/trace.go:171","msg":"trace[571127056] transaction","detail":"{read_only:false; response_revision:726; number_of_response:1; }","duration":"122.575317ms","start":"2026-05-08T10:18:10.111483Z","end":"2026-05-08T10:18:10.234059Z","steps":["trace[571127056] 'process raft request' (duration: 122.292902ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:18:10.837164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.533786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-j86wk\" ","response":"range_response_count:1 size:18333"} {"level":"info","ts":"2026-05-08T10:18:10.83725Z","caller":"traceutil/trace.go:171","msg":"trace[254686735] range","detail":"{range_begin:/registry/pods/kube-system/cilium-j86wk; range_end:; response_count:1; response_revision:729; }","duration":"101.662977ms","start":"2026-05-08T10:18:10.735565Z","end":"2026-05-08T10:18:10.837228Z","steps":["trace[254686735] 'range keys from in-memory index tree' (duration: 101.251492ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:18:10.966123Z","caller":"traceutil/trace.go:171","msg":"trace[774534287] transaction","detail":"{read_only:false; response_revision:730; number_of_response:1; }","duration":"112.653ms","start":"2026-05-08T10:18:10.85345Z","end":"2026-05-08T10:18:10.966103Z","steps":["trace[774534287] 'process raft request' (duration: 112.504577ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:18:21.646773Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011513344617091793,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-08T10:18:21.682454Z","caller":"traceutil/trace.go:171","msg":"trace[672247257] linearizableReadLoop","detail":"{readStateIndex:841; appliedIndex:840; }","duration":"536.6876ms","start":"2026-05-08T10:18:21.145737Z","end":"2026-05-08T10:18:21.682424Z","steps":["trace[672247257] 'read index received' (duration: 536.465334ms)","trace[672247257] 'applied index is now lower than readState.Index' (duration: 221.187µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:18:21.682499Z","caller":"traceutil/trace.go:171","msg":"trace[603926683] transaction","detail":"{read_only:false; response_revision:808; number_of_response:1; }","duration":"539.577109ms","start":"2026-05-08T10:18:21.142902Z","end":"2026-05-08T10:18:21.682479Z","steps":["trace[603926683] 'process raft request' (duration: 539.350713ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:18:21.682612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"536.87123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T10:18:21.682658Z","caller":"traceutil/trace.go:171","msg":"trace[658698177] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:808; }","duration":"536.945265ms","start":"2026-05-08T10:18:21.145699Z","end":"2026-05-08T10:18:21.682644Z","steps":["trace[658698177] 'agreement among raft nodes before linearized reading' (duration: 536.8703ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:18:21.682654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:18:21.14288Z","time spent":"539.681863ms","remote":"127.0.0.1:33492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T10:18:21.682706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:18:21.145685Z","time spent":"537.009923ms","remote":"127.0.0.1:33200","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-05-08T10:18:21.682889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.739386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-08T10:18:21.682987Z","caller":"traceutil/trace.go:171","msg":"trace[689554270] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:808; }","duration":"208.83815ms","start":"2026-05-08T10:18:21.4741Z","end":"2026-05-08T10:18:21.682938Z","steps":["trace[689554270] 'agreement among raft nodes before linearized reading' (duration: 208.564517ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:18:31.50671Z","caller":"traceutil/trace.go:171","msg":"trace[1340401301] transaction","detail":"{read_only:false; response_revision:952; number_of_response:1; }","duration":"103.349178ms","start":"2026-05-08T10:18:31.403336Z","end":"2026-05-08T10:18:31.506685Z","steps":["trace[1340401301] 'process raft request' (duration: 103.197544ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:18:32.692754Z","caller":"traceutil/trace.go:171","msg":"trace[177883546] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"124.957136ms","start":"2026-05-08T10:18:32.567756Z","end":"2026-05-08T10:18:32.692713Z","steps":["trace[177883546] 'process raft request' (duration: 124.816002ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:18:33.28229Z","caller":"traceutil/trace.go:171","msg":"trace[427055529] linearizableReadLoop","detail":"{readStateIndex:997; appliedIndex:996; }","duration":"136.033956ms","start":"2026-05-08T10:18:33.146222Z","end":"2026-05-08T10:18:33.282256Z","steps":["trace[427055529] 'read index received' (duration: 129.748171ms)","trace[427055529] 'applied index is now lower than readState.Index' (duration: 6.284605ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T10:18:33.282402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.165543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T10:18:33.28244Z","caller":"traceutil/trace.go:171","msg":"trace[794357652] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:960; }","duration":"136.24345ms","start":"2026-05-08T10:18:33.146186Z","end":"2026-05-08T10:18:33.282429Z","steps":["trace[794357652] 'agreement among raft nodes before linearized reading' (duration: 136.166582ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:18:33.282762Z","caller":"traceutil/trace.go:171","msg":"trace[1515984048] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"179.097051ms","start":"2026-05-08T10:18:33.10346Z","end":"2026-05-08T10:18:33.282558Z","steps":["trace[1515984048] 'process raft request' (duration: 172.485737ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:18:33.928843Z","caller":"traceutil/trace.go:171","msg":"trace[1190067168] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"143.2415ms","start":"2026-05-08T10:18:33.785578Z","end":"2026-05-08T10:18:33.92882Z","steps":["trace[1190067168] 'process raft request' (duration: 143.072906ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:18:34.156994Z","caller":"traceutil/trace.go:171","msg":"trace[1430892980] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"217.372236ms","start":"2026-05-08T10:18:33.939596Z","end":"2026-05-08T10:18:34.156968Z","steps":["trace[1430892980] 'process raft request' (duration: 217.157804ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:18:34.607514Z","caller":"traceutil/trace.go:171","msg":"trace[1407451689] transaction","detail":"{read_only:false; response_revision:972; number_of_response:1; }","duration":"174.742087ms","start":"2026-05-08T10:18:34.432747Z","end":"2026-05-08T10:18:34.607489Z","steps":["trace[1407451689] 'process raft request' (duration: 174.525854ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:18:48.02773Z","caller":"traceutil/trace.go:171","msg":"trace[1699737103] linearizableReadLoop","detail":"{readStateIndex:1233; appliedIndex:1232; }","duration":"195.21549ms","start":"2026-05-08T10:18:47.832503Z","end":"2026-05-08T10:18:48.027719Z","steps":["trace[1699737103] 'read index received' (duration: 195.113461ms)","trace[1699737103] 'applied index is now lower than readState.Index' (duration: 101.479µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:18:48.027918Z","caller":"traceutil/trace.go:171","msg":"trace[838398047] transaction","detail":"{read_only:false; response_revision:1193; number_of_response:1; }","duration":"205.218609ms","start":"2026-05-08T10:18:47.822694Z","end":"2026-05-08T10:18:48.027912Z","steps":["trace[838398047] 'process raft request' (duration: 204.951922ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:18:48.027963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.357489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-08T10:18:48.02802Z","caller":"traceutil/trace.go:171","msg":"trace[215016037] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1193; }","duration":"189.445528ms","start":"2026-05-08T10:18:47.838561Z","end":"2026-05-08T10:18:48.028006Z","steps":["trace[215016037] 'agreement among raft nodes before linearized reading' (duration: 189.28408ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:18:48.028473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.943492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-08T10:18:48.029267Z","caller":"traceutil/trace.go:171","msg":"trace[1082606666] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1193; }","duration":"196.073251ms","start":"2026-05-08T10:18:47.832472Z","end":"2026-05-08T10:18:48.028545Z","steps":["trace[1082606666] 'agreement among raft nodes before linearized reading' (duration: 195.34252ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:18:50.660657Z","caller":"traceutil/trace.go:171","msg":"trace[706630605] transaction","detail":"{read_only:false; response_revision:1207; number_of_response:1; }","duration":"107.910982ms","start":"2026-05-08T10:18:50.552728Z","end":"2026-05-08T10:18:50.660639Z","steps":["trace[706630605] 'process raft request' (duration: 107.799944ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:18:52.979101Z","caller":"traceutil/trace.go:171","msg":"trace[949925669] transaction","detail":"{read_only:false; response_revision:1215; number_of_response:1; }","duration":"180.801286ms","start":"2026-05-08T10:18:52.798272Z","end":"2026-05-08T10:18:52.979073Z","steps":["trace[949925669] 'process raft request' (duration: 180.634397ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:18:53.645799Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011513344617092543,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-08T10:18:53.854823Z","caller":"traceutil/trace.go:171","msg":"trace[896116064] transaction","detail":"{read_only:false; response_revision:1216; number_of_response:1; }","duration":"840.912344ms","start":"2026-05-08T10:18:53.01388Z","end":"2026-05-08T10:18:53.854792Z","steps":["trace[896116064] 'process raft request' (duration: 840.762305ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:18:53.855178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:18:53.013859Z","time spent":"841.133793ms","remote":"127.0.0.1:33464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2914,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T10:18:53.857325Z","caller":"traceutil/trace.go:171","msg":"trace[1282559722] transaction","detail":"{read_only:false; response_revision:1217; number_of_response:1; }","duration":"769.919587ms","start":"2026-05-08T10:18:53.087392Z","end":"2026-05-08T10:18:53.857311Z","steps":["trace[1282559722] 'process raft request' (duration: 769.581058ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:18:53.857402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:18:53.087376Z","time spent":"769.983747ms","remote":"127.0.0.1:33492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T10:18:53.857868Z","caller":"traceutil/trace.go:171","msg":"trace[87977012] linearizableReadLoop","detail":"{readStateIndex:1259; appliedIndex:1257; }","duration":"712.621061ms","start":"2026-05-08T10:18:53.145234Z","end":"2026-05-08T10:18:53.857816Z","steps":["trace[87977012] 'read index received' (duration: 709.446147ms)","trace[87977012] 'applied index is now lower than readState.Index' (duration: 3.135894ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T10:18:53.857962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"712.728221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T10:18:53.858002Z","caller":"traceutil/trace.go:171","msg":"trace[280536566] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1218; }","duration":"712.79462ms","start":"2026-05-08T10:18:53.145197Z","end":"2026-05-08T10:18:53.857992Z","steps":["trace[280536566] 'agreement among raft nodes before linearized reading' (duration: 712.73569ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:18:53.858029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:18:53.145184Z","time spent":"712.83855ms","remote":"127.0.0.1:33200","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-05-08T10:18:53.85813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.046314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-08T10:18:53.858169Z","caller":"traceutil/trace.go:171","msg":"trace[837392081] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:1218; }","duration":"185.117093ms","start":"2026-05-08T10:18:53.673039Z","end":"2026-05-08T10:18:53.858156Z","steps":["trace[837392081] 'agreement among raft nodes before linearized reading' (duration: 185.061343ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:19:10.113702Z","caller":"traceutil/trace.go:171","msg":"trace[2100876898] transaction","detail":"{read_only:false; response_revision:1357; number_of_response:1; }","duration":"105.29242ms","start":"2026-05-08T10:19:10.008382Z","end":"2026-05-08T10:19:10.113675Z","steps":["trace[2100876898] 'process raft request' (duration: 105.113368ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:19:17.227607Z","caller":"traceutil/trace.go:171","msg":"trace[996411385] transaction","detail":"{read_only:false; response_revision:1482; number_of_response:1; }","duration":"140.33285ms","start":"2026-05-08T10:19:17.087242Z","end":"2026-05-08T10:19:17.227575Z","steps":["trace[996411385] 'process raft request' (duration: 140.135406ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:19:40.422636Z","caller":"traceutil/trace.go:171","msg":"trace[41246053] linearizableReadLoop","detail":"{readStateIndex:1959; appliedIndex:1958; }","duration":"107.671509ms","start":"2026-05-08T10:19:40.314935Z","end":"2026-05-08T10:19:40.422606Z","steps":["trace[41246053] 'read index received' (duration: 62.17143ms)","trace[41246053] 'applied index is now lower than readState.Index' (duration: 45.498049ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:19:40.423027Z","caller":"traceutil/trace.go:171","msg":"trace[1930816260] transaction","detail":"{read_only:false; response_revision:1879; number_of_response:1; }","duration":"199.51544ms","start":"2026-05-08T10:19:40.223484Z","end":"2026-05-08T10:19:40.422999Z","steps":["trace[1930816260] 'process raft request' (duration: 153.674117ms)","trace[1930816260] 'compare' (duration: 45.068692ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T10:19:40.423106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.165809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-08T10:19:40.423161Z","caller":"traceutil/trace.go:171","msg":"trace[2079163029] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1879; }","duration":"108.283022ms","start":"2026-05-08T10:19:40.314865Z","end":"2026-05-08T10:19:40.423149Z","steps":["trace[2079163029] 'agreement among raft nodes before linearized reading' (duration: 108.114225ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:19:41.224443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"699.857647ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-08T10:19:41.224606Z","caller":"traceutil/trace.go:171","msg":"trace[1079099187] transaction","detail":"{read_only:false; response_revision:1880; number_of_response:1; }","duration":"797.410797ms","start":"2026-05-08T10:19:40.427176Z","end":"2026-05-08T10:19:41.224587Z","steps":["trace[1079099187] 'process raft request' (duration: 97.334603ms)","trace[1079099187] 'compare' (duration: 698.666729ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T10:19:41.224666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:19:40.427159Z","time spent":"797.47838ms","remote":"127.0.0.1:33492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T10:19:41.224844Z","caller":"traceutil/trace.go:171","msg":"trace[191789332] linearizableReadLoop","detail":"{readStateIndex:1962; appliedIndex:1959; }","duration":"403.900442ms","start":"2026-05-08T10:19:40.820928Z","end":"2026-05-08T10:19:41.224828Z","steps":["trace[191789332] 'read index received' (duration: 36.641µs)","trace[191789332] 'applied index is now lower than readState.Index' (duration: 403.86327ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:19:41.224946Z","caller":"traceutil/trace.go:171","msg":"trace[204810268] transaction","detail":"{read_only:false; response_revision:1882; number_of_response:1; }","duration":"797.413628ms","start":"2026-05-08T10:19:40.427498Z","end":"2026-05-08T10:19:41.224912Z","steps":["trace[204810268] 'process raft request' (duration: 797.250092ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:19:41.224947Z","caller":"traceutil/trace.go:171","msg":"trace[177919252] transaction","detail":"{read_only:false; response_revision:1881; number_of_response:1; }","duration":"797.409548ms","start":"2026-05-08T10:19:40.4275Z","end":"2026-05-08T10:19:41.22491Z","steps":["trace[177919252] 'process raft request' (duration: 797.036583ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:19:41.22503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.052967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f605d74b-71de-4a8c-9947-0ae2ea7be3d8\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-05-08T10:19:41.225056Z","caller":"traceutil/trace.go:171","msg":"trace[2125430219] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f605d74b-71de-4a8c-9947-0ae2ea7be3d8; range_end:; response_count:1; response_revision:1882; }","duration":"404.161302ms","start":"2026-05-08T10:19:40.820887Z","end":"2026-05-08T10:19:41.225048Z","steps":["trace[2125430219] 'agreement among raft nodes before linearized reading' (duration: 404.014336ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:19:41.22508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:19:40.820864Z","time spent":"404.208383ms","remote":"127.0.0.1:33418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3649,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f605d74b-71de-4a8c-9947-0ae2ea7be3d8\" "} {"level":"warn","ts":"2026-05-08T10:19:41.225117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:19:40.427472Z","time spent":"797.554823ms","remote":"127.0.0.1:33492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T10:19:41.225158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:19:40.427473Z","time spent":"797.544713ms","remote":"127.0.0.1:33492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T10:19:41.298502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.791488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-08T10:19:41.298567Z","caller":"traceutil/trace.go:171","msg":"trace[191591443] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1883; }","duration":"367.911923ms","start":"2026-05-08T10:19:40.93064Z","end":"2026-05-08T10:19:41.298552Z","steps":["trace[191591443] 'agreement among raft nodes before linearized reading' (duration: 367.733976ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:19:41.298606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:19:40.930611Z","time spent":"367.986835ms","remote":"127.0.0.1:33492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-08T10:19:41.298884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.013617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T10:19:41.298989Z","caller":"traceutil/trace.go:171","msg":"trace[1335092026] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1883; }","duration":"153.175713ms","start":"2026-05-08T10:19:41.145794Z","end":"2026-05-08T10:19:41.29897Z","steps":["trace[1335092026] 'agreement among raft nodes before linearized reading' (duration: 153.038608ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:19:41.299132Z","caller":"traceutil/trace.go:171","msg":"trace[65033759] transaction","detail":"{read_only:false; response_revision:1883; number_of_response:1; }","duration":"152.969255ms","start":"2026-05-08T10:19:41.14613Z","end":"2026-05-08T10:19:41.299099Z","steps":["trace[65033759] 'process raft request' (duration: 152.06911ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:19:41.29924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.292775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-08T10:19:41.299163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.482667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f605d74b-71de-4a8c-9947-0ae2ea7be3d8\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-05-08T10:19:41.299324Z","caller":"traceutil/trace.go:171","msg":"trace[276622584] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:1883; }","duration":"113.4122ms","start":"2026-05-08T10:19:41.185897Z","end":"2026-05-08T10:19:41.29931Z","steps":["trace[276622584] 'agreement among raft nodes before linearized reading' (duration: 113.262024ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:19:41.299348Z","caller":"traceutil/trace.go:171","msg":"trace[308882080] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f605d74b-71de-4a8c-9947-0ae2ea7be3d8; range_end:; response_count:1; response_revision:1883; }","duration":"151.774208ms","start":"2026-05-08T10:19:41.147551Z","end":"2026-05-08T10:19:41.299325Z","steps":["trace[308882080] 'agreement among raft nodes before linearized reading' (duration: 151.250317ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:19:41.751928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.289527ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-08T10:19:41.752054Z","caller":"traceutil/trace.go:171","msg":"trace[1491413235] transaction","detail":"{read_only:false; response_revision:1884; number_of_response:1; }","duration":"447.485488ms","start":"2026-05-08T10:19:41.304544Z","end":"2026-05-08T10:19:41.752029Z","steps":["trace[1491413235] 'process raft request' (duration: 292.998794ms)","trace[1491413235] 'compare' (duration: 154.162031ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T10:19:41.752146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:19:41.304517Z","time spent":"447.581772ms","remote":"127.0.0.1:33492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T10:19:41.805633Z","caller":"traceutil/trace.go:171","msg":"trace[1942522957] linearizableReadLoop","detail":"{readStateIndex:1965; appliedIndex:1963; }","duration":"479.653244ms","start":"2026-05-08T10:19:41.325955Z","end":"2026-05-08T10:19:41.805608Z","steps":["trace[1942522957] 'read index received' (duration: 271.709432ms)","trace[1942522957] 'applied index is now lower than readState.Index' (duration: 207.942452ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:19:41.805755Z","caller":"traceutil/trace.go:171","msg":"trace[1009168054] transaction","detail":"{read_only:false; response_revision:1885; number_of_response:1; }","duration":"498.410098ms","start":"2026-05-08T10:19:41.307312Z","end":"2026-05-08T10:19:41.805722Z","steps":["trace[1009168054] 'process raft request' (duration: 498.154367ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:19:41.805917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:19:41.3073Z","time spent":"498.523211ms","remote":"127.0.0.1:33418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3731,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T10:19:41.806373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.404374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-08T10:19:41.806614Z","caller":"traceutil/trace.go:171","msg":"trace[1402956050] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1885; }","duration":"480.528909ms","start":"2026-05-08T10:19:41.325908Z","end":"2026-05-08T10:19:41.806437Z","steps":["trace[1402956050] 'agreement among raft nodes before linearized reading' (duration: 479.859823ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:19:41.806376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.625568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"warn","ts":"2026-05-08T10:19:41.806913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:19:41.325889Z","time spent":"481.002608ms","remote":"127.0.0.1:33492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-08T10:19:41.806741Z","caller":"traceutil/trace.go:171","msg":"trace[1191442952] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:1885; }","duration":"103.043875ms","start":"2026-05-08T10:19:41.703675Z","end":"2026-05-08T10:19:41.806718Z","steps":["trace[1191442952] 'agreement among raft nodes before linearized reading' (duration: 102.496153ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:19:41.80788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.718112ms","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-05-08T10:19:41.807943Z","caller":"traceutil/trace.go:171","msg":"trace[1735610444] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1885; }","duration":"357.826756ms","start":"2026-05-08T10:19:41.450096Z","end":"2026-05-08T10:19:41.807923Z","steps":["trace[1735610444] 'agreement among raft nodes before linearized reading' (duration: 355.918902ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:19:41.80798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:19:41.450075Z","time spent":"357.895869ms","remote":"127.0.0.1:33492","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-05-08T10:19:42.006813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.847545ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-08T10:19:42.007018Z","caller":"traceutil/trace.go:171","msg":"trace[1356947621] transaction","detail":"{read_only:false; response_revision:1886; number_of_response:1; }","duration":"194.686415ms","start":"2026-05-08T10:19:41.812311Z","end":"2026-05-08T10:19:42.006997Z","steps":["trace[1356947621] 'process raft request' (duration: 55.56901ms)","trace[1356947621] 'compare' (duration: 138.7411ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:19:42.009629Z","caller":"traceutil/trace.go:171","msg":"trace[1975165406] transaction","detail":"{read_only:false; response_revision:1888; number_of_response:1; }","duration":"197.115682ms","start":"2026-05-08T10:19:41.812489Z","end":"2026-05-08T10:19:42.009605Z","steps":["trace[1975165406] 'process raft request' (duration: 197.077941ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:19:42.009646Z","caller":"traceutil/trace.go:171","msg":"trace[1724080214] transaction","detail":"{read_only:false; response_revision:1887; number_of_response:1; }","duration":"197.240007ms","start":"2026-05-08T10:19:41.812385Z","end":"2026-05-08T10:19:42.009625Z","steps":["trace[1724080214] 'process raft request' (duration: 197.099681ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:19:42.558647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.371284ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-08T10:19:42.558907Z","caller":"traceutil/trace.go:171","msg":"trace[1780225916] transaction","detail":"{read_only:false; response_revision:1889; number_of_response:1; }","duration":"513.820822ms","start":"2026-05-08T10:19:42.04505Z","end":"2026-05-08T10:19:42.55887Z","steps":["trace[1780225916] 'process raft request' (duration: 391.102064ms)","trace[1780225916] 'compare' (duration: 121.715488ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T10:19:42.559106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:19:42.045025Z","time spent":"513.968138ms","remote":"127.0.0.1:33492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T10:19:42.559188Z","caller":"traceutil/trace.go:171","msg":"trace[592631201] transaction","detail":"{read_only:false; response_revision:1890; number_of_response:1; }","duration":"130.133222ms","start":"2026-05-08T10:19:42.429031Z","end":"2026-05-08T10:19:42.559164Z","steps":["trace[592631201] 'process raft request' (duration: 130.047469ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:19:42.559285Z","caller":"traceutil/trace.go:171","msg":"trace[954025003] linearizableReadLoop","detail":"{readStateIndex:1969; appliedIndex:1968; }","duration":"392.191496ms","start":"2026-05-08T10:19:42.167056Z","end":"2026-05-08T10:19:42.559247Z","steps":["trace[954025003] 'read index received' (duration: 269.091613ms)","trace[954025003] 'applied index is now lower than readState.Index' (duration: 123.095803ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T10:19:42.55947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.418035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T10:19:42.559557Z","caller":"traceutil/trace.go:171","msg":"trace[1511456925] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1890; }","duration":"392.54219ms","start":"2026-05-08T10:19:42.166999Z","end":"2026-05-08T10:19:42.559541Z","steps":["trace[1511456925] 'agreement among raft nodes before linearized reading' (duration: 392.422205ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:19:42.559558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.321088ms","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":"warn","ts":"2026-05-08T10:19:42.559593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:19:42.166974Z","time spent":"392.609222ms","remote":"127.0.0.1:33200","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-05-08T10:19:42.559609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.273061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f605d74b-71de-4a8c-9947-0ae2ea7be3d8\" ","response":"range_response_count:1 size:3746"} {"level":"info","ts":"2026-05-08T10:19:42.559667Z","caller":"traceutil/trace.go:171","msg":"trace[2059661906] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f605d74b-71de-4a8c-9947-0ae2ea7be3d8; range_end:; response_count:1; response_revision:1890; }","duration":"329.357955ms","start":"2026-05-08T10:19:42.230292Z","end":"2026-05-08T10:19:42.559649Z","steps":["trace[2059661906] 'agreement among raft nodes before linearized reading' (duration: 329.150027ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:19:42.559676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.760795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-08T10:19:42.559734Z","caller":"traceutil/trace.go:171","msg":"trace[1686378380] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:1890; }","duration":"313.84409ms","start":"2026-05-08T10:19:42.245882Z","end":"2026-05-08T10:19:42.559726Z","steps":["trace[1686378380] 'agreement among raft nodes before linearized reading' (duration: 313.759465ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:19:42.559726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:19:42.230277Z","time spent":"329.436058ms","remote":"127.0.0.1:33418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3769,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f605d74b-71de-4a8c-9947-0ae2ea7be3d8\" "} {"level":"warn","ts":"2026-05-08T10:19:42.559771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:19:42.245867Z","time spent":"313.895832ms","remote":"127.0.0.1:33424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":15,"response size":30,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true "} {"level":"info","ts":"2026-05-08T10:19:42.559602Z","caller":"traceutil/trace.go:171","msg":"trace[351827045] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1890; }","duration":"252.410591ms","start":"2026-05-08T10:19:42.307178Z","end":"2026-05-08T10:19:42.559588Z","steps":["trace[351827045] 'agreement among raft nodes before linearized reading' (duration: 252.267146ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:19:54.764729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.081944ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T10:19:54.764885Z","caller":"traceutil/trace.go:171","msg":"trace[237725227] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1995; }","duration":"437.274383ms","start":"2026-05-08T10:19:54.327592Z","end":"2026-05-08T10:19:54.764866Z","steps":["trace[237725227] 'range keys from in-memory index tree' (duration: 437.060432ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:19:54.764907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.560982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T10:19:54.765005Z","caller":"traceutil/trace.go:171","msg":"trace[1416070757] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1995; }","duration":"516.719479ms","start":"2026-05-08T10:19:54.24826Z","end":"2026-05-08T10:19:54.764979Z","steps":["trace[1416070757] 'range keys from in-memory index tree' (duration: 516.491809ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:19:54.765066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:19:54.248246Z","time spent":"516.803293ms","remote":"127.0.0.1:33200","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-05-08T10:19:54.765084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"559.095729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11988"} {"level":"info","ts":"2026-05-08T10:19:54.765126Z","caller":"traceutil/trace.go:171","msg":"trace[654102365] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:1995; }","duration":"559.182064ms","start":"2026-05-08T10:19:54.205932Z","end":"2026-05-08T10:19:54.765114Z","steps":["trace[654102365] 'range keys from in-memory index tree' (duration: 558.936222ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:19:54.765168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:19:54.205908Z","time spent":"559.238716ms","remote":"127.0.0.1:33418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12011,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-05-08T10:19:54.766417Z","caller":"traceutil/trace.go:171","msg":"trace[1000489344] transaction","detail":"{read_only:false; response_revision:1996; number_of_response:1; }","duration":"168.226593ms","start":"2026-05-08T10:19:54.598177Z","end":"2026-05-08T10:19:54.766403Z","steps":["trace[1000489344] 'process raft request' (duration: 168.115738ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:19:54.767314Z","caller":"traceutil/trace.go:171","msg":"trace[1177787590] transaction","detail":"{read_only:false; response_revision:1997; number_of_response:1; }","duration":"168.040315ms","start":"2026-05-08T10:19:54.599259Z","end":"2026-05-08T10:19:54.767299Z","steps":["trace[1177787590] 'process raft request' (duration: 167.771193ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:19:54.767326Z","caller":"traceutil/trace.go:171","msg":"trace[547311108] linearizableReadLoop","detail":"{readStateIndex:2080; appliedIndex:2078; }","duration":"132.048589ms","start":"2026-05-08T10:19:54.635223Z","end":"2026-05-08T10:19:54.767272Z","steps":["trace[547311108] 'read index received' (duration: 131.133947ms)","trace[547311108] 'applied index is now lower than readState.Index' (duration: 913.622µs)"],"step_count":2} {"level":"warn","ts":"2026-05-08T10:19:54.768281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.047736ms","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-05-08T10:19:54.768539Z","caller":"traceutil/trace.go:171","msg":"trace[636583875] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1997; }","duration":"133.172841ms","start":"2026-05-08T10:19:54.635148Z","end":"2026-05-08T10:19:54.76832Z","steps":["trace[636583875] 'agreement among raft nodes before linearized reading' (duration: 132.266269ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:20:09.421676Z","caller":"traceutil/trace.go:171","msg":"trace[2118220400] transaction","detail":"{read_only:false; response_revision:2092; number_of_response:1; }","duration":"214.290346ms","start":"2026-05-08T10:20:09.207359Z","end":"2026-05-08T10:20:09.42165Z","steps":["trace[2118220400] 'process raft request' (duration: 214.003381ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:20:14.926201Z","caller":"traceutil/trace.go:171","msg":"trace[1907208507] transaction","detail":"{read_only:false; response_revision:2122; number_of_response:1; }","duration":"235.780643ms","start":"2026-05-08T10:20:14.690388Z","end":"2026-05-08T10:20:14.926169Z","steps":["trace[1907208507] 'process raft request' (duration: 235.614234ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:20:15.135782Z","caller":"traceutil/trace.go:171","msg":"trace[579241206] linearizableReadLoop","detail":"{readStateIndex:2211; appliedIndex:2208; }","duration":"260.834364ms","start":"2026-05-08T10:20:14.874904Z","end":"2026-05-08T10:20:15.135739Z","steps":["trace[579241206] 'read index received' (duration: 51.122034ms)","trace[579241206] 'applied index is now lower than readState.Index' (duration: 209.71135ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:20:15.135815Z","caller":"traceutil/trace.go:171","msg":"trace[1298208333] transaction","detail":"{read_only:false; response_revision:2123; number_of_response:1; }","duration":"299.998912ms","start":"2026-05-08T10:20:14.835793Z","end":"2026-05-08T10:20:15.135792Z","steps":["trace[1298208333] 'process raft request' (duration: 289.119842ms)","trace[1298208333] 'compare' (duration: 10.672169ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:20:15.135831Z","caller":"traceutil/trace.go:171","msg":"trace[781711449] transaction","detail":"{read_only:false; response_revision:2124; number_of_response:1; }","duration":"299.981912ms","start":"2026-05-08T10:20:14.835833Z","end":"2026-05-08T10:20:15.135815Z","steps":["trace[781711449] 'process raft request' (duration: 299.859415ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:20:15.135965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:20:14.835774Z","time spent":"300.1227ms","remote":"127.0.0.1:33492","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-05-08T10:20:15.136022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:20:14.835811Z","time spent":"300.13542ms","remote":"127.0.0.1:33492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T10:20:15.136129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.214835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12385"} {"level":"info","ts":"2026-05-08T10:20:15.13619Z","caller":"traceutil/trace.go:171","msg":"trace[1813933950] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2124; }","duration":"261.300601ms","start":"2026-05-08T10:20:14.874877Z","end":"2026-05-08T10:20:15.136178Z","steps":["trace[1813933950] 'agreement among raft nodes before linearized reading' (duration: 261.006015ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:20:15.14547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.52548ms","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-05-08T10:20:15.145631Z","caller":"traceutil/trace.go:171","msg":"trace[2051440412] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2125; }","duration":"237.795434ms","start":"2026-05-08T10:20:14.907718Z","end":"2026-05-08T10:20:15.145513Z","steps":["trace[2051440412] 'agreement among raft nodes before linearized reading' (duration: 237.047723ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:20:16.693935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.568394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-08T10:20:16.694032Z","caller":"traceutil/trace.go:171","msg":"trace[1975700322] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2130; }","duration":"534.688891ms","start":"2026-05-08T10:20:16.159319Z","end":"2026-05-08T10:20:16.694008Z","steps":["trace[1975700322] 'range keys from in-memory index tree' (duration: 534.383364ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:20:16.69408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:20:16.159312Z","time spent":"534.756934ms","remote":"127.0.0.1:33492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-08T10:20:16.694349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"784.789475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-08T10:20:16.694403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"530.019852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-05-08T10:20:16.694424Z","caller":"traceutil/trace.go:171","msg":"trace[2118485796] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2130; }","duration":"784.914413ms","start":"2026-05-08T10:20:15.909491Z","end":"2026-05-08T10:20:16.694405Z","steps":["trace[2118485796] 'range keys from in-memory index tree' (duration: 784.623855ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:20:16.694451Z","caller":"traceutil/trace.go:171","msg":"trace[1474595730] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2130; }","duration":"530.100006ms","start":"2026-05-08T10:20:16.164332Z","end":"2026-05-08T10:20:16.694432Z","steps":["trace[1474595730] 'range keys from in-memory index tree' (duration: 529.830761ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:20:16.694463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:20:15.909471Z","time spent":"784.984036ms","remote":"127.0.0.1:33492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-08T10:20:16.694519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:20:16.164312Z","time spent":"530.164609ms","remote":"127.0.0.1:33492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":577,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-05-08T10:20:16.694979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.479724ms","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":"warn","ts":"2026-05-08T10:20:16.695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.776627ms","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":"info","ts":"2026-05-08T10:20:16.695026Z","caller":"traceutil/trace.go:171","msg":"trace[428013347] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2130; }","duration":"542.542388ms","start":"2026-05-08T10:20:16.152472Z","end":"2026-05-08T10:20:16.695015Z","steps":["trace[428013347] 'range keys from in-memory index tree' (duration: 542.386048ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:20:16.695048Z","caller":"traceutil/trace.go:171","msg":"trace[2065111083] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:2130; }","duration":"367.853801ms","start":"2026-05-08T10:20:16.327181Z","end":"2026-05-08T10:20:16.695035Z","steps":["trace[2065111083] 'count revisions from in-memory index tree' (duration: 367.702542ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:20:16.695058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:20:16.152463Z","time spent":"542.587689ms","remote":"127.0.0.1:41406","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":"warn","ts":"2026-05-08T10:20:16.695116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:20:16.327169Z","time spent":"367.910324ms","remote":"127.0.0.1:33684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":2,"response size":30,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"warn","ts":"2026-05-08T10:20:16.694869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"683.086835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T10:20:16.695221Z","caller":"traceutil/trace.go:171","msg":"trace[1915476174] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:2130; }","duration":"683.442696ms","start":"2026-05-08T10:20:16.011737Z","end":"2026-05-08T10:20:16.69518Z","steps":["trace[1915476174] 'count revisions from in-memory index tree' (duration: 683.033103ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:20:16.695313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:20:16.011725Z","time spent":"683.546891ms","remote":"127.0.0.1:42908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true "} {"level":"warn","ts":"2026-05-08T10:20:16.695319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"564.895389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-08T10:20:16.695405Z","caller":"traceutil/trace.go:171","msg":"trace[953233477] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:2130; }","duration":"565.019886ms","start":"2026-05-08T10:20:16.130366Z","end":"2026-05-08T10:20:16.695385Z","steps":["trace[953233477] 'count revisions from in-memory index tree' (duration: 564.841066ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:20:16.69545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:20:16.130353Z","time spent":"565.082899ms","remote":"127.0.0.1:33560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":23,"response size":30,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"info","ts":"2026-05-08T10:20:16.82892Z","caller":"traceutil/trace.go:171","msg":"trace[1398413865] transaction","detail":"{read_only:false; response_revision:2132; number_of_response:1; }","duration":"128.197154ms","start":"2026-05-08T10:20:16.700676Z","end":"2026-05-08T10:20:16.828874Z","steps":["trace[1398413865] 'process raft request' (duration: 128.018794ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:20:16.829069Z","caller":"traceutil/trace.go:171","msg":"trace[814185766] transaction","detail":"{read_only:false; response_revision:2133; number_of_response:1; }","duration":"127.957211ms","start":"2026-05-08T10:20:16.701088Z","end":"2026-05-08T10:20:16.829046Z","steps":["trace[814185766] 'process raft request' (duration: 127.716318ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:21:09.710441Z","caller":"traceutil/trace.go:171","msg":"trace[1136834629] transaction","detail":"{read_only:false; response_revision:2465; number_of_response:1; }","duration":"120.644477ms","start":"2026-05-08T10:21:09.58977Z","end":"2026-05-08T10:21:09.710414Z","steps":["trace[1136834629] 'process raft request' (duration: 120.457234ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:21:18.023448Z","caller":"traceutil/trace.go:171","msg":"trace[691611276] linearizableReadLoop","detail":"{readStateIndex:2668; appliedIndex:2667; }","duration":"176.446782ms","start":"2026-05-08T10:21:17.84698Z","end":"2026-05-08T10:21:18.023427Z","steps":["trace[691611276] 'read index received' (duration: 176.26416ms)","trace[691611276] 'applied index is now lower than readState.Index' (duration: 181.752µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:21:18.023726Z","caller":"traceutil/trace.go:171","msg":"trace[1060492523] transaction","detail":"{read_only:false; response_revision:2567; number_of_response:1; }","duration":"284.605243ms","start":"2026-05-08T10:21:17.739108Z","end":"2026-05-08T10:21:18.023713Z","steps":["trace[1060492523] 'process raft request' (duration: 284.201334ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:21:18.023989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.994001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-08T10:21:18.024037Z","caller":"traceutil/trace.go:171","msg":"trace[75599402] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2567; }","duration":"177.074916ms","start":"2026-05-08T10:21:17.846936Z","end":"2026-05-08T10:21:18.024011Z","steps":["trace[75599402] 'agreement among raft nodes before linearized reading' (duration: 176.953698ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:21:18.024222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.848894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-08T10:21:18.024248Z","caller":"traceutil/trace.go:171","msg":"trace[289778452] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2567; }","duration":"153.907238ms","start":"2026-05-08T10:21:17.870333Z","end":"2026-05-08T10:21:18.02424Z","steps":["trace[289778452] 'agreement among raft nodes before linearized reading' (duration: 153.833363ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:21:18.024472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.9261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-08T10:21:18.024499Z","caller":"traceutil/trace.go:171","msg":"trace[927823569] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2567; }","duration":"152.973894ms","start":"2026-05-08T10:21:17.871517Z","end":"2026-05-08T10:21:18.024491Z","steps":["trace[927823569] 'agreement among raft nodes before linearized reading' (duration: 152.897568ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:21:18.645671Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011513344617095477,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-08T10:21:18.880151Z","caller":"traceutil/trace.go:171","msg":"trace[1792856657] transaction","detail":"{read_only:false; response_revision:2569; number_of_response:1; }","duration":"847.919261ms","start":"2026-05-08T10:21:18.032215Z","end":"2026-05-08T10:21:18.880134Z","steps":["trace[1792856657] 'process raft request' (duration: 847.869828ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:21:18.88024Z","caller":"traceutil/trace.go:171","msg":"trace[124895232] transaction","detail":"{read_only:false; response_revision:2570; number_of_response:1; }","duration":"847.073623ms","start":"2026-05-08T10:21:18.033158Z","end":"2026-05-08T10:21:18.880232Z","steps":["trace[124895232] 'process raft request' (duration: 846.953434ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:21:18.880276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:21:18.032202Z","time spent":"848.014277ms","remote":"127.0.0.1:33492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T10:21:18.88016Z","caller":"traceutil/trace.go:171","msg":"trace[312123405] transaction","detail":"{read_only:false; response_revision:2568; number_of_response:1; }","duration":"851.241883ms","start":"2026-05-08T10:21:18.028903Z","end":"2026-05-08T10:21:18.880145Z","steps":["trace[312123405] 'process raft request' (duration: 847.541365ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:21:18.880347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:21:18.03315Z","time spent":"847.163178ms","remote":"127.0.0.1:33492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T10:21:18.880419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:21:18.028883Z","time spent":"851.487089ms","remote":"127.0.0.1:33492","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-05-08T10:21:18.884468Z","caller":"traceutil/trace.go:171","msg":"trace[280288803] linearizableReadLoop","detail":"{readStateIndex:2672; appliedIndex:2668; }","duration":"739.109711ms","start":"2026-05-08T10:21:18.145332Z","end":"2026-05-08T10:21:18.884442Z","steps":["trace[280288803] 'read index received' (duration: 731.119346ms)","trace[280288803] 'applied index is now lower than readState.Index' (duration: 7.989015ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T10:21:18.884543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"739.216548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T10:21:18.884581Z","caller":"traceutil/trace.go:171","msg":"trace[899617701] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2570; }","duration":"739.322207ms","start":"2026-05-08T10:21:18.145251Z","end":"2026-05-08T10:21:18.884573Z","steps":["trace[899617701] 'agreement among raft nodes before linearized reading' (duration: 739.260403ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:21:18.884612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:21:18.145221Z","time spent":"739.384082ms","remote":"127.0.0.1:33200","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-05-08T10:21:18.88483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"720.890675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-05-08T10:21:18.88496Z","caller":"traceutil/trace.go:171","msg":"trace[764709586] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2570; }","duration":"720.997222ms","start":"2026-05-08T10:21:18.163871Z","end":"2026-05-08T10:21:18.884868Z","steps":["trace[764709586] 'agreement among raft nodes before linearized reading' (duration: 720.828211ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:21:18.885063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:21:18.163849Z","time spent":"721.198316ms","remote":"127.0.0.1:33424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1392,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"info","ts":"2026-05-08T10:21:51.199364Z","caller":"traceutil/trace.go:171","msg":"trace[2138272772] linearizableReadLoop","detail":"{readStateIndex:2854; appliedIndex:2853; }","duration":"160.934206ms","start":"2026-05-08T10:21:51.038402Z","end":"2026-05-08T10:21:51.199336Z","steps":["trace[2138272772] 'read index received' (duration: 160.691478ms)","trace[2138272772] 'applied index is now lower than readState.Index' (duration: 241.778µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:21:51.199728Z","caller":"traceutil/trace.go:171","msg":"trace[683183036] transaction","detail":"{read_only:false; response_revision:2745; number_of_response:1; }","duration":"161.966442ms","start":"2026-05-08T10:21:51.037732Z","end":"2026-05-08T10:21:51.199699Z","steps":["trace[683183036] 'process raft request' (duration: 161.457514ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:21:51.200068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.907377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-08T10:21:51.200149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.711243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-05-08T10:21:51.200234Z","caller":"traceutil/trace.go:171","msg":"trace[343272200] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2745; }","duration":"161.839862ms","start":"2026-05-08T10:21:51.038377Z","end":"2026-05-08T10:21:51.200217Z","steps":["trace[343272200] 'agreement among raft nodes before linearized reading' (duration: 161.336486ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:21:51.200388Z","caller":"traceutil/trace.go:171","msg":"trace[495411143] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2745; }","duration":"152.078169ms","start":"2026-05-08T10:21:51.048106Z","end":"2026-05-08T10:21:51.200184Z","steps":["trace[495411143] 'agreement among raft nodes before linearized reading' (duration: 151.66473ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:21:51.716033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.653375ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-08T10:21:51.716487Z","caller":"traceutil/trace.go:171","msg":"trace[1295098331] transaction","detail":"{read_only:false; response_revision:2751; number_of_response:1; }","duration":"185.37083ms","start":"2026-05-08T10:21:51.5311Z","end":"2026-05-08T10:21:51.716471Z","steps":["trace[1295098331] 'process raft request' (duration: 185.338478ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:21:51.716686Z","caller":"traceutil/trace.go:171","msg":"trace[1864164213] transaction","detail":"{read_only:false; response_revision:2750; number_of_response:1; }","duration":"187.605943ms","start":"2026-05-08T10:21:51.529043Z","end":"2026-05-08T10:21:51.716648Z","steps":["trace[1864164213] 'process raft request' (duration: 187.338134ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:21:51.716529Z","caller":"traceutil/trace.go:171","msg":"trace[1108244566] linearizableReadLoop","detail":"{readStateIndex:2858; appliedIndex:2857; }","duration":"389.619368ms","start":"2026-05-08T10:21:51.326891Z","end":"2026-05-08T10:21:51.71651Z","steps":["trace[1108244566] 'read index received' (duration: 275.445675ms)","trace[1108244566] 'applied index is now lower than readState.Index' (duration: 114.172153ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:21:51.716717Z","caller":"traceutil/trace.go:171","msg":"trace[854001887] transaction","detail":"{read_only:false; response_revision:2749; number_of_response:1; }","duration":"419.716533ms","start":"2026-05-08T10:21:51.296991Z","end":"2026-05-08T10:21:51.716708Z","steps":["trace[854001887] 'process raft request' (duration: 305.328805ms)","trace[854001887] 'compare' (duration: 113.132306ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T10:21:51.716689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.789921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-08T10:21:51.717018Z","caller":"traceutil/trace.go:171","msg":"trace[1660648476] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2751; }","duration":"390.155686ms","start":"2026-05-08T10:21:51.326856Z","end":"2026-05-08T10:21:51.717012Z","steps":["trace[1660648476] 'agreement among raft nodes before linearized reading' (duration: 389.727826ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:21:51.717043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:21:51.326845Z","time spent":"390.19081ms","remote":"127.0.0.1:33492","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-05-08T10:21:51.717089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.624309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18ad8ff09c54186c\" ","response":"range_response_count:1 size:781"} {"level":"info","ts":"2026-05-08T10:21:51.717204Z","caller":"traceutil/trace.go:171","msg":"trace[399796456] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18ad8ff09c54186c; range_end:; response_count:1; response_revision:2751; }","duration":"255.809181ms","start":"2026-05-08T10:21:51.461384Z","end":"2026-05-08T10:21:51.717193Z","steps":["trace[399796456] 'agreement among raft nodes before linearized reading' (duration: 255.538111ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:21:51.717076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:21:51.296974Z","time spent":"419.969861ms","remote":"127.0.0.1:33492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T10:22:03.879265Z","caller":"traceutil/trace.go:171","msg":"trace[1110604287] linearizableReadLoop","detail":"{readStateIndex:2927; appliedIndex:2925; }","duration":"111.199443ms","start":"2026-05-08T10:22:03.768045Z","end":"2026-05-08T10:22:03.879244Z","steps":["trace[1110604287] 'read index received' (duration: 47.650829ms)","trace[1110604287] 'applied index is now lower than readState.Index' (duration: 63.547644ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T10:22:03.879703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.0687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-05-08T10:22:03.879683Z","caller":"traceutil/trace.go:171","msg":"trace[770091070] transaction","detail":"{read_only:false; response_revision:2815; number_of_response:1; }","duration":"117.459225ms","start":"2026-05-08T10:22:03.762189Z","end":"2026-05-08T10:22:03.879648Z","steps":["trace[770091070] 'process raft request' (duration: 116.953287ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:22:03.879749Z","caller":"traceutil/trace.go:171","msg":"trace[1698227971] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2815; }","duration":"110.150165ms","start":"2026-05-08T10:22:03.769587Z","end":"2026-05-08T10:22:03.879737Z","steps":["trace[1698227971] 'agreement among raft nodes before linearized reading' (duration: 109.95245ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:22:03.880875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.627968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-08T10:22:03.880973Z","caller":"traceutil/trace.go:171","msg":"trace[133680540] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2815; }","duration":"112.944232ms","start":"2026-05-08T10:22:03.768008Z","end":"2026-05-08T10:22:03.880953Z","steps":["trace[133680540] 'agreement among raft nodes before linearized reading' (duration: 111.633656ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:22:22.123432Z","caller":"traceutil/trace.go:171","msg":"trace[149133945] transaction","detail":"{read_only:false; response_revision:2912; number_of_response:1; }","duration":"121.70738ms","start":"2026-05-08T10:22:22.001697Z","end":"2026-05-08T10:22:22.123404Z","steps":["trace[149133945] 'process raft request' (duration: 47.118171ms)","trace[149133945] 'compare' (duration: 74.47735ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:22:46.061802Z","caller":"traceutil/trace.go:171","msg":"trace[1212000064] transaction","detail":"{read_only:false; response_revision:3036; number_of_response:1; }","duration":"107.938726ms","start":"2026-05-08T10:22:45.953839Z","end":"2026-05-08T10:22:46.061778Z","steps":["trace[1212000064] 'process raft request' (duration: 107.767462ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:22:46.692805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.443147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18ad8ff09c54186c\" ","response":"range_response_count:1 size:781"} {"level":"info","ts":"2026-05-08T10:22:46.692905Z","caller":"traceutil/trace.go:171","msg":"trace[766961325] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18ad8ff09c54186c; range_end:; response_count:1; response_revision:3040; }","duration":"231.623622ms","start":"2026-05-08T10:22:46.461263Z","end":"2026-05-08T10:22:46.692887Z","steps":["trace[766961325] 'range keys from in-memory index tree' (duration: 231.180186ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:22:55.927753Z","caller":"traceutil/trace.go:171","msg":"trace[195662913] linearizableReadLoop","detail":"{readStateIndex:3210; appliedIndex:3209; }","duration":"128.337821ms","start":"2026-05-08T10:22:55.799383Z","end":"2026-05-08T10:22:55.92772Z","steps":["trace[195662913] 'read index received' (duration: 19.571752ms)","trace[195662913] 'applied index is now lower than readState.Index' (duration: 108.764749ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:22:55.927861Z","caller":"traceutil/trace.go:171","msg":"trace[1386068567] transaction","detail":"{read_only:false; response_revision:3087; number_of_response:1; }","duration":"144.689586ms","start":"2026-05-08T10:22:55.783123Z","end":"2026-05-08T10:22:55.927812Z","steps":["trace[1386068567] 'process raft request' (duration: 98.049946ms)","trace[1386068567] 'compare' (duration: 46.374377ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T10:22:55.928043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.624884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T10:22:55.928141Z","caller":"traceutil/trace.go:171","msg":"trace[1374726802] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:3087; }","duration":"128.78033ms","start":"2026-05-08T10:22:55.79934Z","end":"2026-05-08T10:22:55.92812Z","steps":["trace[1374726802] 'agreement among raft nodes before linearized reading' (duration: 128.584794ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:22:56.267089Z","caller":"traceutil/trace.go:171","msg":"trace[522132009] transaction","detail":"{read_only:false; response_revision:3089; number_of_response:1; }","duration":"111.81216ms","start":"2026-05-08T10:22:56.155244Z","end":"2026-05-08T10:22:56.267056Z","steps":["trace[522132009] 'process raft request' (duration: 111.720392ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:22:56.267201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.112694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T10:22:56.267236Z","caller":"traceutil/trace.go:171","msg":"trace[1956600297] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3089; }","duration":"121.205942ms","start":"2026-05-08T10:22:56.14602Z","end":"2026-05-08T10:22:56.267226Z","steps":["trace[1956600297] 'agreement among raft nodes before linearized reading' (duration: 121.120994ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:22:56.267401Z","caller":"traceutil/trace.go:171","msg":"trace[1461066672] transaction","detail":"{read_only:false; response_revision:3088; number_of_response:1; }","duration":"131.879223ms","start":"2026-05-08T10:22:56.135509Z","end":"2026-05-08T10:22:56.267388Z","steps":["trace[1461066672] 'process raft request' (duration: 57.403262ms)","trace[1461066672] 'compare' (duration: 73.72343ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:22:56.267098Z","caller":"traceutil/trace.go:171","msg":"trace[1540495851] linearizableReadLoop","detail":"{readStateIndex:3211; appliedIndex:3210; }","duration":"121.005147ms","start":"2026-05-08T10:22:56.146075Z","end":"2026-05-08T10:22:56.26708Z","steps":["trace[1540495851] 'read index received' (duration: 46.821049ms)","trace[1540495851] 'applied index is now lower than readState.Index' (duration: 74.182798ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T10:22:56.267772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.200831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-05-08T10:22:56.267849Z","caller":"traceutil/trace.go:171","msg":"trace[1698887676] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:3089; }","duration":"104.305619ms","start":"2026-05-08T10:22:56.163525Z","end":"2026-05-08T10:22:56.26783Z","steps":["trace[1698887676] 'agreement among raft nodes before linearized reading' (duration: 104.081865ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:22:56.438423Z","caller":"traceutil/trace.go:171","msg":"trace[1052218954] linearizableReadLoop","detail":"{readStateIndex:3213; appliedIndex:3212; }","duration":"164.797273ms","start":"2026-05-08T10:22:56.273594Z","end":"2026-05-08T10:22:56.438392Z","steps":["trace[1052218954] 'read index received' (duration: 98.752669ms)","trace[1052218954] 'applied index is now lower than readState.Index' (duration: 66.043574ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:22:56.438509Z","caller":"traceutil/trace.go:171","msg":"trace[1244737041] transaction","detail":"{read_only:false; response_revision:3090; number_of_response:1; }","duration":"166.399252ms","start":"2026-05-08T10:22:56.272053Z","end":"2026-05-08T10:22:56.438452Z","steps":["trace[1244737041] 'process raft request' (duration: 100.389647ms)","trace[1244737041] 'compare' (duration: 65.834199ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T10:22:56.438724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.099774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak\" ","response":"range_response_count:1 size:1319"} {"level":"info","ts":"2026-05-08T10:22:56.438792Z","caller":"traceutil/trace.go:171","msg":"trace[1388148642] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak; range_end:; response_count:1; response_revision:3090; }","duration":"165.200502ms","start":"2026-05-08T10:22:56.273575Z","end":"2026-05-08T10:22:56.438775Z","steps":["trace[1388148642] 'agreement among raft nodes before linearized reading' (duration: 164.985218ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:22:56.563288Z","caller":"traceutil/trace.go:171","msg":"trace[236325447] linearizableReadLoop","detail":"{readStateIndex:3215; appliedIndex:3214; }","duration":"102.112704ms","start":"2026-05-08T10:22:56.461125Z","end":"2026-05-08T10:22:56.563237Z","steps":["trace[236325447] 'read index received' (duration: 97.205228ms)","trace[236325447] 'applied index is now lower than readState.Index' (duration: 4.903156ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:22:56.563427Z","caller":"traceutil/trace.go:171","msg":"trace[1048068487] transaction","detail":"{read_only:false; response_revision:3092; number_of_response:1; }","duration":"114.1531ms","start":"2026-05-08T10:22:56.449237Z","end":"2026-05-08T10:22:56.56339Z","steps":["trace[1048068487] 'process raft request' (duration: 109.071758ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:22:56.563844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.69132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18ad8ff09c54186c\" ","response":"range_response_count:1 size:781"} {"level":"info","ts":"2026-05-08T10:22:56.563937Z","caller":"traceutil/trace.go:171","msg":"trace[1270015446] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18ad8ff09c54186c; range_end:; response_count:1; response_revision:3092; }","duration":"102.807446ms","start":"2026-05-08T10:22:56.461111Z","end":"2026-05-08T10:22:56.563918Z","steps":["trace[1270015446] 'agreement among raft nodes before linearized reading' (duration: 102.517764ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:23:02.083507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.959516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-08T10:23:02.083647Z","caller":"traceutil/trace.go:171","msg":"trace[1529061065] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3119; }","duration":"131.169782ms","start":"2026-05-08T10:23:01.952447Z","end":"2026-05-08T10:23:02.083616Z","steps":["trace[1529061065] 'range keys from in-memory index tree' (duration: 130.74236ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:23:10.236557Z","caller":"traceutil/trace.go:171","msg":"trace[1639701606] linearizableReadLoop","detail":"{readStateIndex:3285; appliedIndex:3284; }","duration":"198.821689ms","start":"2026-05-08T10:23:10.037715Z","end":"2026-05-08T10:23:10.236537Z","steps":["trace[1639701606] 'read index received' (duration: 198.69432ms)","trace[1639701606] 'applied index is now lower than readState.Index' (duration: 126.339µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:23:10.236576Z","caller":"traceutil/trace.go:171","msg":"trace[251646465] transaction","detail":"{read_only:false; response_revision:3159; number_of_response:1; }","duration":"223.832292ms","start":"2026-05-08T10:23:10.012723Z","end":"2026-05-08T10:23:10.236556Z","steps":["trace[251646465] 'process raft request' (duration: 223.681364ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:23:10.236849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.271049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-08T10:23:10.236852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.103444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-05-08T10:23:10.236922Z","caller":"traceutil/trace.go:171","msg":"trace[1588595988] range","detail":"{range_begin:/registry/rabbitmq.com/topicpermissions/; range_end:/registry/rabbitmq.com/topicpermissions0; response_count:0; response_revision:3159; }","duration":"172.438437ms","start":"2026-05-08T10:23:10.064444Z","end":"2026-05-08T10:23:10.236883Z","steps":["trace[1588595988] 'agreement among raft nodes before linearized reading' (duration: 172.212821ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:23:10.236933Z","caller":"traceutil/trace.go:171","msg":"trace[1586051511] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:3159; }","duration":"199.222482ms","start":"2026-05-08T10:23:10.037686Z","end":"2026-05-08T10:23:10.236909Z","steps":["trace[1586051511] 'agreement among raft nodes before linearized reading' (duration: 198.954567ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:23:10.237523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.273555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-08T10:23:10.237577Z","caller":"traceutil/trace.go:171","msg":"trace[600081381] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3159; }","duration":"108.358313ms","start":"2026-05-08T10:23:10.129205Z","end":"2026-05-08T10:23:10.237563Z","steps":["trace[600081381] 'agreement among raft nodes before linearized reading' (duration: 108.205466ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:23:10.372652Z","caller":"traceutil/trace.go:171","msg":"trace[1536881892] linearizableReadLoop","detail":"{readStateIndex:3287; appliedIndex:3285; }","duration":"128.724759ms","start":"2026-05-08T10:23:10.243883Z","end":"2026-05-08T10:23:10.372608Z","steps":["trace[1536881892] 'read index received' (duration: 62.064996ms)","trace[1536881892] 'applied index is now lower than readState.Index' (duration: 66.658663ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:23:10.372695Z","caller":"traceutil/trace.go:171","msg":"trace[408569527] transaction","detail":"{read_only:false; response_revision:3161; number_of_response:1; }","duration":"129.651484ms","start":"2026-05-08T10:23:10.24301Z","end":"2026-05-08T10:23:10.372661Z","steps":["trace[408569527] 'process raft request' (duration: 129.466657ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:23:10.372836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.929475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak\" ","response":"range_response_count:1 size:1319"} {"level":"info","ts":"2026-05-08T10:23:10.372878Z","caller":"traceutil/trace.go:171","msg":"trace[1510480313] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak; range_end:; response_count:1; response_revision:3161; }","duration":"128.987104ms","start":"2026-05-08T10:23:10.24388Z","end":"2026-05-08T10:23:10.372867Z","steps":["trace[1510480313] 'agreement among raft nodes before linearized reading' (duration: 128.835867ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:23:50.188973Z","caller":"traceutil/trace.go:171","msg":"trace[1716856579] transaction","detail":"{read_only:false; response_revision:3450; number_of_response:1; }","duration":"106.215896ms","start":"2026-05-08T10:23:50.082738Z","end":"2026-05-08T10:23:50.188954Z","steps":["trace[1716856579] 'process raft request' (duration: 106.109856ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:23:50.239844Z","caller":"traceutil/trace.go:171","msg":"trace[1015441836] transaction","detail":"{read_only:false; response_revision:3451; number_of_response:1; }","duration":"156.246442ms","start":"2026-05-08T10:23:50.083572Z","end":"2026-05-08T10:23:50.239819Z","steps":["trace[1015441836] 'process raft request' (duration: 128.76689ms)","trace[1015441836] 'compare' (duration: 27.032399ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:23:50.903662Z","caller":"traceutil/trace.go:171","msg":"trace[1230867831] transaction","detail":"{read_only:false; response_revision:3457; number_of_response:1; }","duration":"118.415759ms","start":"2026-05-08T10:23:50.785219Z","end":"2026-05-08T10:23:50.903635Z","steps":["trace[1230867831] 'process raft request' (duration: 117.521334ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:23:54.726346Z","caller":"traceutil/trace.go:171","msg":"trace[1975653536] transaction","detail":"{read_only:false; response_revision:3474; number_of_response:1; }","duration":"111.003248ms","start":"2026-05-08T10:23:54.615323Z","end":"2026-05-08T10:23:54.726326Z","steps":["trace[1975653536] 'process raft request' (duration: 110.395393ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:23:54.767617Z","caller":"traceutil/trace.go:171","msg":"trace[388520005] transaction","detail":"{read_only:false; response_revision:3476; number_of_response:1; }","duration":"113.235366ms","start":"2026-05-08T10:23:54.654353Z","end":"2026-05-08T10:23:54.767589Z","steps":["trace[388520005] 'process raft request' (duration: 113.176185ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:23:54.767699Z","caller":"traceutil/trace.go:171","msg":"trace[1221503686] transaction","detail":"{read_only:false; response_revision:3475; number_of_response:1; }","duration":"130.328367ms","start":"2026-05-08T10:23:54.637338Z","end":"2026-05-08T10:23:54.767667Z","steps":["trace[1221503686] 'process raft request' (duration: 130.039025ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:24:13.162332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.432993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T10:24:13.162496Z","caller":"traceutil/trace.go:171","msg":"trace[405346356] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:3583; }","duration":"116.640645ms","start":"2026-05-08T10:24:13.045837Z","end":"2026-05-08T10:24:13.162478Z","steps":["trace[405346356] 'range keys from in-memory index tree' (duration: 116.29287ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:24:13.163013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.900068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-05-08T10:24:13.163108Z","caller":"traceutil/trace.go:171","msg":"trace[1194634183] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3583; }","duration":"122.04171ms","start":"2026-05-08T10:24:13.04104Z","end":"2026-05-08T10:24:13.163082Z","steps":["trace[1194634183] 'range keys from in-memory index tree' (duration: 121.637543ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:24:13.162447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.188076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-08T10:24:13.163277Z","caller":"traceutil/trace.go:171","msg":"trace[542218239] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3583; }","duration":"122.07846ms","start":"2026-05-08T10:24:13.041174Z","end":"2026-05-08T10:24:13.163252Z","steps":["trace[542218239] 'range keys from in-memory index tree' (duration: 120.870851ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:24:14.975712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.728778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-08T10:24:14.97585Z","caller":"traceutil/trace.go:171","msg":"trace[44154596] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3589; }","duration":"132.920672ms","start":"2026-05-08T10:24:14.842889Z","end":"2026-05-08T10:24:14.975809Z","steps":["trace[44154596] 'range keys from in-memory index tree' (duration: 132.487095ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:24:14.976227Z","caller":"traceutil/trace.go:171","msg":"trace[1413568500] transaction","detail":"{read_only:false; response_revision:3590; number_of_response:1; }","duration":"131.324326ms","start":"2026-05-08T10:24:14.844871Z","end":"2026-05-08T10:24:14.976195Z","steps":["trace[1413568500] 'process raft request' (duration: 131.122982ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:24:14.981781Z","caller":"traceutil/trace.go:171","msg":"trace[841969786] transaction","detail":"{read_only:false; response_revision:3591; number_of_response:1; }","duration":"136.700671ms","start":"2026-05-08T10:24:14.845036Z","end":"2026-05-08T10:24:14.981737Z","steps":["trace[841969786] 'process raft request' (duration: 136.405147ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:24:17.101592Z","caller":"traceutil/trace.go:171","msg":"trace[715816706] transaction","detail":"{read_only:false; response_revision:3602; number_of_response:1; }","duration":"113.303ms","start":"2026-05-08T10:24:16.988266Z","end":"2026-05-08T10:24:17.101569Z","steps":["trace[715816706] 'process raft request' (duration: 113.25381ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:24:17.101913Z","caller":"traceutil/trace.go:171","msg":"trace[1134878589] transaction","detail":"{read_only:false; response_revision:3601; number_of_response:1; }","duration":"114.999439ms","start":"2026-05-08T10:24:16.986883Z","end":"2026-05-08T10:24:17.101883Z","steps":["trace[1134878589] 'process raft request' (duration: 114.355828ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:24:21.409487Z","caller":"traceutil/trace.go:171","msg":"trace[1599227310] transaction","detail":"{read_only:false; response_revision:3623; number_of_response:1; }","duration":"111.915837ms","start":"2026-05-08T10:24:21.297526Z","end":"2026-05-08T10:24:21.409442Z","steps":["trace[1599227310] 'process raft request' (duration: 111.6052ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:24:31.329161Z","caller":"traceutil/trace.go:171","msg":"trace[1103875110] transaction","detail":"{read_only:false; response_revision:3679; number_of_response:1; }","duration":"119.099627ms","start":"2026-05-08T10:24:31.210028Z","end":"2026-05-08T10:24:31.329128Z","steps":["trace[1103875110] 'process raft request' (duration: 77.65525ms)","trace[1103875110] 'compare' (duration: 40.653309ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:24:47.957532Z","caller":"traceutil/trace.go:171","msg":"trace[621267582] transaction","detail":"{read_only:false; response_revision:3771; number_of_response:1; }","duration":"246.626316ms","start":"2026-05-08T10:24:47.710877Z","end":"2026-05-08T10:24:47.957503Z","steps":["trace[621267582] 'process raft request' (duration: 246.061141ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:24:48.735858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.130173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-08T10:24:48.73595Z","caller":"traceutil/trace.go:171","msg":"trace[614610519] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:3772; }","duration":"131.281047ms","start":"2026-05-08T10:24:48.604649Z","end":"2026-05-08T10:24:48.73593Z","steps":["trace[614610519] 'count revisions from in-memory index tree' (duration: 130.99839ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:24:49.216758Z","caller":"traceutil/trace.go:171","msg":"trace[605930689] linearizableReadLoop","detail":"{readStateIndex:3923; appliedIndex:3922; }","duration":"146.186771ms","start":"2026-05-08T10:24:49.07054Z","end":"2026-05-08T10:24:49.216726Z","steps":["trace[605930689] 'read index received' (duration: 145.969295ms)","trace[605930689] 'applied index is now lower than readState.Index' (duration: 216.116µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:24:49.216874Z","caller":"traceutil/trace.go:171","msg":"trace[1802611131] transaction","detail":"{read_only:false; response_revision:3774; number_of_response:1; }","duration":"148.248677ms","start":"2026-05-08T10:24:49.068597Z","end":"2026-05-08T10:24:49.216846Z","steps":["trace[1802611131] 'process raft request' (duration: 147.943229ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:24:49.217085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.53166ms","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-05-08T10:24:49.217143Z","caller":"traceutil/trace.go:171","msg":"trace[264355039] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:3774; }","duration":"146.641993ms","start":"2026-05-08T10:24:49.070474Z","end":"2026-05-08T10:24:49.217116Z","steps":["trace[264355039] 'agreement among raft nodes before linearized reading' (duration: 146.368215ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:24:49.446846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.473719ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-08T10:24:49.446993Z","caller":"traceutil/trace.go:171","msg":"trace[1171806535] linearizableReadLoop","detail":"{readStateIndex:3924; appliedIndex:3923; }","duration":"224.914456ms","start":"2026-05-08T10:24:49.222017Z","end":"2026-05-08T10:24:49.446931Z","steps":["trace[1171806535] 'read index received' (duration: 42.187581ms)","trace[1171806535] 'applied index is now lower than readState.Index' (duration: 182.725385ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:24:49.44734Z","caller":"traceutil/trace.go:171","msg":"trace[180949251] transaction","detail":"{read_only:false; response_revision:3775; number_of_response:1; }","duration":"225.323518ms","start":"2026-05-08T10:24:49.221986Z","end":"2026-05-08T10:24:49.44731Z","steps":["trace[180949251] 'process raft request' (duration: 42.295135ms)","trace[180949251] 'compare' (duration: 182.347384ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T10:24:49.447348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.306938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/kube-prometheus-stack\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T10:24:49.447558Z","caller":"traceutil/trace.go:171","msg":"trace[688782096] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/kube-prometheus-stack; range_end:; response_count:0; response_revision:3775; }","duration":"225.436691ms","start":"2026-05-08T10:24:49.221979Z","end":"2026-05-08T10:24:49.447415Z","steps":["trace[688782096] 'agreement among raft nodes before linearized reading' (duration: 225.291197ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:24:49.453608Z","caller":"traceutil/trace.go:171","msg":"trace[1013716962] transaction","detail":"{read_only:false; response_revision:3776; number_of_response:1; }","duration":"227.929271ms","start":"2026-05-08T10:24:49.225652Z","end":"2026-05-08T10:24:49.453581Z","steps":["trace[1013716962] 'process raft request' (duration: 227.739685ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:24:49.453728Z","caller":"traceutil/trace.go:171","msg":"trace[837812025] transaction","detail":"{read_only:false; number_of_response:1; response_revision:3776; }","duration":"227.88555ms","start":"2026-05-08T10:24:49.225816Z","end":"2026-05-08T10:24:49.453701Z","steps":["trace[837812025] 'process raft request' (duration: 227.718984ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:24:49.517978Z","caller":"traceutil/trace.go:171","msg":"trace[954623521] transaction","detail":"{read_only:false; response_revision:3777; number_of_response:1; }","duration":"243.145562ms","start":"2026-05-08T10:24:49.274805Z","end":"2026-05-08T10:24:49.517951Z","steps":["trace[954623521] 'process raft request' (duration: 242.961957ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:24:49.518158Z","caller":"traceutil/trace.go:171","msg":"trace[2009696158] transaction","detail":"{read_only:false; response_revision:3778; number_of_response:1; }","duration":"193.627898ms","start":"2026-05-08T10:24:49.32451Z","end":"2026-05-08T10:24:49.518138Z","steps":["trace[2009696158] 'process raft request' (duration: 193.383771ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:24:49.518409Z","caller":"traceutil/trace.go:171","msg":"trace[1558681757] transaction","detail":"{read_only:false; response_revision:3779; number_of_response:1; }","duration":"184.474944ms","start":"2026-05-08T10:24:49.333825Z","end":"2026-05-08T10:24:49.5183Z","steps":["trace[1558681757] 'process raft request' (duration: 184.238417ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:24:49.634668Z","caller":"traceutil/trace.go:171","msg":"trace[2109442615] transaction","detail":"{read_only:false; response_revision:3783; number_of_response:1; }","duration":"100.435849ms","start":"2026-05-08T10:24:49.534193Z","end":"2026-05-08T10:24:49.634629Z","steps":["trace[2109442615] 'process raft request' (duration: 100.064869ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:25:01.754213Z","caller":"traceutil/trace.go:171","msg":"trace[2129903070] transaction","detail":"{read_only:false; response_revision:3905; number_of_response:1; }","duration":"200.88104ms","start":"2026-05-08T10:25:01.553313Z","end":"2026-05-08T10:25:01.754194Z","steps":["trace[2129903070] 'process raft request' (duration: 186.437681ms)","trace[2129903070] 'compare' (duration: 14.294274ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:25:01.754466Z","caller":"traceutil/trace.go:171","msg":"trace[183158860] transaction","detail":"{read_only:false; response_revision:3906; number_of_response:1; }","duration":"199.640861ms","start":"2026-05-08T10:25:01.554796Z","end":"2026-05-08T10:25:01.754436Z","steps":["trace[183158860] 'process raft request' (duration: 199.363902ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:25:01.754584Z","caller":"traceutil/trace.go:171","msg":"trace[1010810024] transaction","detail":"{read_only:false; response_revision:3907; number_of_response:1; }","duration":"199.379443ms","start":"2026-05-08T10:25:01.555163Z","end":"2026-05-08T10:25:01.754543Z","steps":["trace[1010810024] 'process raft request' (duration: 199.143666ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:25:01.75485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.233554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-08T10:25:01.754913Z","caller":"traceutil/trace.go:171","msg":"trace[618165739] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3907; }","duration":"177.341768ms","start":"2026-05-08T10:25:01.577553Z","end":"2026-05-08T10:25:01.754895Z","steps":["trace[618165739] 'agreement among raft nodes before linearized reading' (duration: 177.07476ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:25:01.754408Z","caller":"traceutil/trace.go:171","msg":"trace[1226485985] linearizableReadLoop","detail":"{readStateIndex:4061; appliedIndex:4058; }","duration":"176.78357ms","start":"2026-05-08T10:25:01.577604Z","end":"2026-05-08T10:25:01.754388Z","steps":["trace[1226485985] 'read index received' (duration: 162.157925ms)","trace[1226485985] 'applied index is now lower than readState.Index' (duration: 14.624605ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:25:01.908903Z","caller":"traceutil/trace.go:171","msg":"trace[1083049524] transaction","detail":"{read_only:false; response_revision:3908; number_of_response:1; }","duration":"150.278164ms","start":"2026-05-08T10:25:01.758579Z","end":"2026-05-08T10:25:01.908857Z","steps":["trace[1083049524] 'process raft request' (duration: 112.404396ms)","trace[1083049524] 'compare' (duration: 37.483947ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:25:02.112824Z","caller":"traceutil/trace.go:171","msg":"trace[1739258445] transaction","detail":"{read_only:false; response_revision:3910; number_of_response:1; }","duration":"180.913587ms","start":"2026-05-08T10:25:01.931873Z","end":"2026-05-08T10:25:02.112786Z","steps":["trace[1739258445] 'process raft request' (duration: 180.282967ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:25:13.995838Z","caller":"traceutil/trace.go:171","msg":"trace[663210816] transaction","detail":"{read_only:false; response_revision:3978; number_of_response:1; }","duration":"200.757537ms","start":"2026-05-08T10:25:13.795043Z","end":"2026-05-08T10:25:13.9958Z","steps":["trace[663210816] 'process raft request' (duration: 122.715381ms)","trace[663210816] 'compare' (duration: 77.61036ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:25:13.995876Z","caller":"traceutil/trace.go:171","msg":"trace[902179946] transaction","detail":"{read_only:false; number_of_response:0; response_revision:3978; }","duration":"111.083864ms","start":"2026-05-08T10:25:13.884773Z","end":"2026-05-08T10:25:13.995857Z","steps":["trace[902179946] 'process raft request' (duration: 110.95201ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:25:14.201608Z","caller":"traceutil/trace.go:171","msg":"trace[1562772640] transaction","detail":"{read_only:false; number_of_response:0; response_revision:3980; }","duration":"101.718387ms","start":"2026-05-08T10:25:14.099873Z","end":"2026-05-08T10:25:14.201591Z","steps":["trace[1562772640] 'process raft request' (duration: 101.601013ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:25:38.107966Z","caller":"traceutil/trace.go:171","msg":"trace[471298101] transaction","detail":"{read_only:false; response_revision:4463; number_of_response:1; }","duration":"168.797816ms","start":"2026-05-08T10:25:37.939147Z","end":"2026-05-08T10:25:38.107945Z","steps":["trace[471298101] 'process raft request' (duration: 168.660281ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:25:38.110596Z","caller":"traceutil/trace.go:171","msg":"trace[1158988909] transaction","detail":"{read_only:false; response_revision:4464; number_of_response:1; }","duration":"162.918616ms","start":"2026-05-08T10:25:37.947627Z","end":"2026-05-08T10:25:38.110545Z","steps":["trace[1158988909] 'process raft request' (duration: 162.769009ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:25:38.588688Z","caller":"traceutil/trace.go:171","msg":"trace[483770081] transaction","detail":"{read_only:false; response_revision:4469; number_of_response:1; }","duration":"145.931653ms","start":"2026-05-08T10:25:38.442733Z","end":"2026-05-08T10:25:38.588664Z","steps":["trace[483770081] 'process raft request' (duration: 89.053809ms)","trace[483770081] 'compare' (duration: 56.76492ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:25:48.374058Z","caller":"traceutil/trace.go:171","msg":"trace[1062207470] transaction","detail":"{read_only:false; response_revision:4697; number_of_response:1; }","duration":"101.662803ms","start":"2026-05-08T10:25:48.272377Z","end":"2026-05-08T10:25:48.37404Z","steps":["trace[1062207470] 'process raft request' (duration: 101.538678ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:25:49.643369Z","caller":"traceutil/trace.go:171","msg":"trace[2094254241] transaction","detail":"{read_only:false; response_revision:4710; number_of_response:1; }","duration":"138.407964ms","start":"2026-05-08T10:25:49.504923Z","end":"2026-05-08T10:25:49.643331Z","steps":["trace[2094254241] 'process raft request' (duration: 138.209736ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:25:51.191175Z","caller":"traceutil/trace.go:171","msg":"trace[415807884] linearizableReadLoop","detail":"{readStateIndex:4959; appliedIndex:4958; }","duration":"276.613945ms","start":"2026-05-08T10:25:50.914535Z","end":"2026-05-08T10:25:51.191149Z","steps":["trace[415807884] 'read index received' (duration: 276.435678ms)","trace[415807884] 'applied index is now lower than readState.Index' (duration: 176.727µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:25:51.191284Z","caller":"traceutil/trace.go:171","msg":"trace[1528498674] transaction","detail":"{read_only:false; response_revision:4771; number_of_response:1; }","duration":"287.602595ms","start":"2026-05-08T10:25:50.903657Z","end":"2026-05-08T10:25:51.191259Z","steps":["trace[1528498674] 'process raft request' (duration: 287.336874ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:25:51.191443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.883037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b83131e0-b252-4e66-8bb2-e7644ac7d996\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-05-08T10:25:51.191492Z","caller":"traceutil/trace.go:171","msg":"trace[1405298058] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b83131e0-b252-4e66-8bb2-e7644ac7d996; range_end:; response_count:1; response_revision:4771; }","duration":"276.97843ms","start":"2026-05-08T10:25:50.914499Z","end":"2026-05-08T10:25:51.191478Z","steps":["trace[1405298058] 'agreement among raft nodes before linearized reading' (duration: 276.784483ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:25:51.191635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.855835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-08T10:25:51.191703Z","caller":"traceutil/trace.go:171","msg":"trace[277478110] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4771; }","duration":"110.970699ms","start":"2026-05-08T10:25:51.080718Z","end":"2026-05-08T10:25:51.191689Z","steps":["trace[277478110] 'agreement among raft nodes before linearized reading' (duration: 110.784742ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:25:51.191732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.242073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b83131e0-b252-4e66-8bb2-e7644ac7d996\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-05-08T10:25:51.191902Z","caller":"traceutil/trace.go:171","msg":"trace[1852841780] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b83131e0-b252-4e66-8bb2-e7644ac7d996; range_end:; response_count:1; response_revision:4771; }","duration":"252.508165ms","start":"2026-05-08T10:25:50.939364Z","end":"2026-05-08T10:25:51.191872Z","steps":["trace[1852841780] 'agreement among raft nodes before linearized reading' (duration: 252.010914ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:25:51.192159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.958771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-08T10:25:51.192217Z","caller":"traceutil/trace.go:171","msg":"trace[499147476] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:4771; }","duration":"174.058325ms","start":"2026-05-08T10:25:51.018144Z","end":"2026-05-08T10:25:51.192202Z","steps":["trace[499147476] 'agreement among raft nodes before linearized reading' (duration: 173.624916ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:25:51.593431Z","caller":"traceutil/trace.go:171","msg":"trace[1086938747] transaction","detail":"{read_only:false; response_revision:4772; number_of_response:1; }","duration":"395.788616ms","start":"2026-05-08T10:25:51.197616Z","end":"2026-05-08T10:25:51.593404Z","steps":["trace[1086938747] 'process raft request' (duration: 351.524694ms)","trace[1086938747] 'compare' (duration: 43.999211ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T10:25:51.593559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:25:51.197528Z","time spent":"395.962975ms","remote":"127.0.0.1:33492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T10:25:51.60939Z","caller":"traceutil/trace.go:171","msg":"trace[312893083] linearizableReadLoop","detail":"{readStateIndex:4961; appliedIndex:4959; }","duration":"326.174407ms","start":"2026-05-08T10:25:51.283158Z","end":"2026-05-08T10:25:51.609333Z","steps":["trace[312893083] 'read index received' (duration: 265.913141ms)","trace[312893083] 'applied index is now lower than readState.Index' (duration: 60.259536ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:25:51.609608Z","caller":"traceutil/trace.go:171","msg":"trace[293867904] transaction","detail":"{read_only:false; response_revision:4773; number_of_response:1; }","duration":"409.106856ms","start":"2026-05-08T10:25:51.200474Z","end":"2026-05-08T10:25:51.609581Z","steps":["trace[293867904] 'process raft request' (duration: 408.528072ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:25:51.609736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:25:51.200455Z","time spent":"409.209961ms","remote":"127.0.0.1:33418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3747,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T10:25:51.609804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.625855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-ldxpf-tls\" ","response":"range_response_count:1 size:1966"} {"level":"info","ts":"2026-05-08T10:25:51.609945Z","caller":"traceutil/trace.go:171","msg":"trace[738314736] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-ldxpf-tls; range_end:; response_count:1; response_revision:4773; }","duration":"326.841084ms","start":"2026-05-08T10:25:51.283054Z","end":"2026-05-08T10:25:51.609896Z","steps":["trace[738314736] 'agreement among raft nodes before linearized reading' (duration: 326.476479ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:25:51.610032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:25:51.283027Z","time spent":"326.96145ms","remote":"127.0.0.1:42884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":1989,"request content":"key:\"/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-ldxpf-tls\" "} {"level":"info","ts":"2026-05-08T10:25:55.247386Z","caller":"traceutil/trace.go:171","msg":"trace[2103326061] transaction","detail":"{read_only:false; response_revision:4833; number_of_response:1; }","duration":"120.039925ms","start":"2026-05-08T10:25:55.127322Z","end":"2026-05-08T10:25:55.247362Z","steps":["trace[2103326061] 'process raft request' (duration: 77.767297ms)","trace[2103326061] 'compare' (duration: 42.108902ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:25:57.691461Z","caller":"traceutil/trace.go:171","msg":"trace[1838600800] transaction","detail":"{read_only:false; response_revision:4859; number_of_response:1; }","duration":"278.978523ms","start":"2026-05-08T10:25:57.412454Z","end":"2026-05-08T10:25:57.691432Z","steps":["trace[1838600800] 'process raft request' (duration: 278.684771ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:26:02.063478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.65455ms","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-05-08T10:26:02.06355Z","caller":"traceutil/trace.go:171","msg":"trace[1275828450] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4921; }","duration":"100.768834ms","start":"2026-05-08T10:26:01.962765Z","end":"2026-05-08T10:26:02.063534Z","steps":["trace[1275828450] 'range keys from in-memory index tree' (duration: 100.463901ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:26:06.484571Z","caller":"traceutil/trace.go:171","msg":"trace[1745975145] linearizableReadLoop","detail":"{readStateIndex:5143; appliedIndex:5142; }","duration":"407.588627ms","start":"2026-05-08T10:26:06.076945Z","end":"2026-05-08T10:26:06.484533Z","steps":["trace[1745975145] 'read index received' (duration: 407.547305ms)","trace[1745975145] 'applied index is now lower than readState.Index' (duration: 38.051µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:26:06.484751Z","caller":"traceutil/trace.go:171","msg":"trace[2143350277] transaction","detail":"{read_only:false; response_revision:4949; number_of_response:1; }","duration":"748.399033ms","start":"2026-05-08T10:26:05.736319Z","end":"2026-05-08T10:26:06.484718Z","steps":["trace[2143350277] 'process raft request' (duration: 748.081108ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:26:06.484915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:26:05.736299Z","time spent":"748.524067ms","remote":"127.0.0.1:33492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T10:26:06.485201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.222126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10391"} {"level":"info","ts":"2026-05-08T10:26:06.485366Z","caller":"traceutil/trace.go:171","msg":"trace[945202459] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4949; }","duration":"408.442266ms","start":"2026-05-08T10:26:06.0769Z","end":"2026-05-08T10:26:06.485343Z","steps":["trace[945202459] 'agreement among raft nodes before linearized reading' (duration: 407.851889ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:26:06.485512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:26:06.076881Z","time spent":"408.614683ms","remote":"127.0.0.1:33418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":10414,"request content":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" "} {"level":"info","ts":"2026-05-08T10:26:06.68559Z","caller":"traceutil/trace.go:171","msg":"trace[1951255910] transaction","detail":"{read_only:false; response_revision:4950; number_of_response:1; }","duration":"460.98847ms","start":"2026-05-08T10:26:06.224561Z","end":"2026-05-08T10:26:06.685549Z","steps":["trace[1951255910] 'process raft request' (duration: 380.424175ms)","trace[1951255910] 'compare' (duration: 80.173427ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:26:06.685619Z","caller":"traceutil/trace.go:171","msg":"trace[1908907714] transaction","detail":"{read_only:false; response_revision:4951; number_of_response:1; }","duration":"457.544447ms","start":"2026-05-08T10:26:06.228046Z","end":"2026-05-08T10:26:06.68559Z","steps":["trace[1908907714] 'process raft request' (duration: 457.38962ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:26:06.685753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:26:06.224544Z","time spent":"461.124376ms","remote":"127.0.0.1:33492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T10:26:06.68581Z","caller":"traceutil/trace.go:171","msg":"trace[605687191] linearizableReadLoop","detail":"{readStateIndex:5146; appliedIndex:5143; }","duration":"201.139665ms","start":"2026-05-08T10:26:06.484639Z","end":"2026-05-08T10:26:06.685779Z","steps":["trace[605687191] 'read index received' (duration: 120.494877ms)","trace[605687191] 'applied index is now lower than readState.Index' (duration: 80.643498ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T10:26:06.685857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:26:06.228033Z","time spent":"457.648801ms","remote":"127.0.0.1:33492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T10:26:06.686027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.932253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-08T10:26:06.686078Z","caller":"traceutil/trace.go:171","msg":"trace[317353317] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4952; }","duration":"233.011077ms","start":"2026-05-08T10:26:06.453048Z","end":"2026-05-08T10:26:06.686059Z","steps":["trace[317353317] 'agreement among raft nodes before linearized reading' (duration: 232.86989ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:26:06.686077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"540.699017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T10:26:06.686111Z","caller":"traceutil/trace.go:171","msg":"trace[524591651] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4952; }","duration":"540.75378ms","start":"2026-05-08T10:26:06.145347Z","end":"2026-05-08T10:26:06.686101Z","steps":["trace[524591651] 'agreement among raft nodes before linearized reading' (duration: 540.699387ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:26:06.686148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:26:06.145336Z","time spent":"540.805922ms","remote":"127.0.0.1:33200","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-05-08T10:26:06.686141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.030494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-08T10:26:06.686176Z","caller":"traceutil/trace.go:171","msg":"trace[1769074302] transaction","detail":"{read_only:false; response_revision:4952; number_of_response:1; }","duration":"447.75478ms","start":"2026-05-08T10:26:06.238415Z","end":"2026-05-08T10:26:06.686169Z","steps":["trace[1769074302] 'process raft request' (duration: 447.137172ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:26:06.68622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:26:06.238384Z","time spent":"447.808832ms","remote":"127.0.0.1:33492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T10:26:06.686202Z","caller":"traceutil/trace.go:171","msg":"trace[110181095] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4952; }","duration":"427.134149ms","start":"2026-05-08T10:26:06.259049Z","end":"2026-05-08T10:26:06.686184Z","steps":["trace[110181095] 'agreement among raft nodes before linearized reading' (duration: 426.916159ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:26:06.6863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:26:06.259037Z","time spent":"427.245574ms","remote":"127.0.0.1:33492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-08T10:26:06.686354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.217071ms","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-05-08T10:26:06.686388Z","caller":"traceutil/trace.go:171","msg":"trace[404018350] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4952; }","duration":"522.268724ms","start":"2026-05-08T10:26:06.164107Z","end":"2026-05-08T10:26:06.686376Z","steps":["trace[404018350] 'agreement among raft nodes before linearized reading' (duration: 522.17278ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:26:06.686417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:26:06.164097Z","time spent":"522.313286ms","remote":"127.0.0.1:33384","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-05-08T10:26:06.686455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.395538ms","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-05-08T10:26:06.686479Z","caller":"traceutil/trace.go:171","msg":"trace[421106418] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4952; }","duration":"565.454621ms","start":"2026-05-08T10:26:06.121017Z","end":"2026-05-08T10:26:06.686472Z","steps":["trace[421106418] 'agreement among raft nodes before linearized reading' (duration: 565.394168ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:26:06.686505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:26:06.120997Z","time spent":"565.503323ms","remote":"127.0.0.1:33492","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-05-08T10:26:06.719644Z","caller":"traceutil/trace.go:171","msg":"trace[251072812] transaction","detail":"{read_only:false; response_revision:4953; number_of_response:1; }","duration":"222.601463ms","start":"2026-05-08T10:26:06.496987Z","end":"2026-05-08T10:26:06.719588Z","steps":["trace[251072812] 'process raft request' (duration: 222.433656ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:27:19.850091Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2897} {"level":"info","ts":"2026-05-08T10:27:19.926344Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2897,"took":"75.006971ms","hash":4048058614,"current-db-size-bytes":53329920,"current-db-size":"53 MB","current-db-size-in-use-bytes":28897280,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-05-08T10:27:19.926437Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4048058614,"revision":2897,"compact-revision":-1} {"level":"info","ts":"2026-05-08T10:27:33.094611Z","caller":"traceutil/trace.go:171","msg":"trace[1035620138] transaction","detail":"{read_only:false; response_revision:5640; number_of_response:1; }","duration":"116.93304ms","start":"2026-05-08T10:27:32.97766Z","end":"2026-05-08T10:27:33.094593Z","steps":["trace[1035620138] 'process raft request' (duration: 116.833745ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:27:33.130251Z","caller":"traceutil/trace.go:171","msg":"trace[675427114] transaction","detail":"{read_only:false; response_revision:5642; number_of_response:1; }","duration":"148.262014ms","start":"2026-05-08T10:27:32.981966Z","end":"2026-05-08T10:27:33.130228Z","steps":["trace[675427114] 'process raft request' (duration: 148.19681ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:27:33.130327Z","caller":"traceutil/trace.go:171","msg":"trace[2091027866] transaction","detail":"{read_only:false; response_revision:5641; number_of_response:1; }","duration":"150.775562ms","start":"2026-05-08T10:27:32.97952Z","end":"2026-05-08T10:27:33.130296Z","steps":["trace[2091027866] 'process raft request' (duration: 150.521737ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:32:19.856106Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5565} {"level":"info","ts":"2026-05-08T10:32:20.106227Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5565,"took":"248.411851ms","hash":3546448857,"current-db-size-bytes":53329920,"current-db-size":"53 MB","current-db-size-in-use-bytes":18423808,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-05-08T10:32:20.106287Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3546448857,"revision":5565,"compact-revision":2897}